Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo", uses=false)
Using package ScalableTestGrids with version 1.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo)
Using package PowerGrids with version 1.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(ScalableTestGrids.Models.Type1.Type1_N_1_M_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1")
translateModel(ScalableTestGrids.Models.Type1.Type1_N_1_M_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001347/0.001347, allocations: 100.6 kB / 16.95 MB, free: 6.449 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001971/0.001971, allocations: 182.9 kB / 17.84 MB, free: 6 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 2.007/2.007, allocations: 205.4 MB / 224 MB, free: 13.18 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.05847/0.05847, allocations: 14.16 MB / 286.4 MB, free: 14.09 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo): time 5/5, allocations: 463.5 MB / 0.783 GB, free: 108.6 MB / 0.6545 GB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0007217/0.0007219, allocations: 23.94 kB / 0.9684 GB, free: 344 kB / 0.7327 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.745/1.746, allocations: 176 MB / 1.14 GB, free: 25.41 MB / 0.8735 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_1_M_1): time 0.0461/1.792, allocations: 46.1 MB / 1.185 GB, free: 7.613 MB / 0.8892 GB
Notification: Performance of NFInst.instExpressions: time 0.05093/1.843, allocations: 19.53 MB / 1.204 GB, free: 7.379 MB / 0.9048 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02018/1.863, allocations: 203 kB / 1.205 GB, free: 7.312 MB / 0.9048 GB
Notification: Performance of NFTyping.typeComponents: time 0.01429/1.877, allocations: 1.927 MB / 1.206 GB, free: 5.633 MB / 0.9048 GB
Notification: Performance of NFTyping.typeBindings: time 0.04248/1.92, allocations: 6.901 MB / 1.213 GB, free: 15.95 MB / 0.9204 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01142/1.931, allocations: 2.083 MB / 1.215 GB, free: 14.34 MB / 0.9204 GB
Notification: Performance of NFFlatten.flatten: time 0.04385/1.975, allocations: 12.55 MB / 1.227 GB, free: 6.371 MB / 0.9204 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01134/1.987, allocations: 1.771 MB / 1.229 GB, free: 5.488 MB / 0.9204 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02334/2.01, allocations: 4.741 MB / 1.234 GB, free: 2.574 MB / 0.9204 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01689/2.027, allocations: 4.92 MB / 1.239 GB, free: 15.45 MB / 0.936 GB
Notification: Performance of NFPackage.collectConstants: time 0.004948/2.032, allocations: 302.9 kB / 1.239 GB, free: 15.45 MB / 0.936 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01309/2.045, allocations: 0.9963 MB / 1.24 GB, free: 15.29 MB / 0.936 GB
Notification: Performance of NFScalarize.scalarize: time 0.005109/2.05, allocations: 1.233 MB / 1.241 GB, free: 14.29 MB / 0.936 GB
Notification: Performance of NFVerifyModel.verify: time 0.01051/2.061, allocations: 1.914 MB / 1.243 GB, free: 13.2 MB / 0.936 GB
Notification: Performance of NFConvertDAE.convert: time 0.0278/2.089, allocations: 10.25 MB / 1.253 GB, free: 5.621 MB / 0.936 GB
Notification: Performance of FrontEnd - DAE generated: time 9.648e-06/2.089, allocations: 0 / 1.253 GB, free: 5.621 MB / 0.936 GB
Notification: Performance of FrontEnd: time 7.815e-06/2.089, allocations: 0 / 1.253 GB, free: 5.621 MB / 0.936 GB
Notification: Performance of Transformations before backend: time 0.001246/2.09, allocations: 0 / 1.253 GB, free: 5.621 MB / 0.936 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1920
 * Number of variables: 1920
Notification: Performance of Generate backend data structure: time 0.06151/2.152, allocations: 12.08 MB / 1.265 GB, free: 14.29 MB / 0.9517 GB
Notification: Performance of prepare preOptimizeDAE: time 9.226e-05/2.152, allocations: 8.594 kB / 1.265 GB, free: 14.29 MB / 0.9517 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01157/2.163, allocations: 1.437 MB / 1.266 GB, free: 13.58 MB / 0.9517 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02706/2.19, allocations: 5.723 MB / 1.272 GB, free: 9.309 MB / 0.9517 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001774/2.192, allocations: 0.5743 MB / 1.272 GB, free: 9.043 MB / 0.9517 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.006219/2.199, allocations: 0.7638 MB / 1.273 GB, free: 8.785 MB / 0.9517 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04027/2.239, allocations: 7.838 MB / 1.281 GB, free: 2.047 MB / 0.9517 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0006824/2.24, allocations: 11.88 kB / 1.281 GB, free: 2.035 MB / 0.9517 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.004106/2.244, allocations: 315.9 kB / 1.281 GB, free: 1.727 MB / 0.9517 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003039/2.244, allocations: 184 kB / 1.281 GB, free: 1.547 MB / 0.9517 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02624/2.27, allocations: 7.559 MB / 1.289 GB, free: 9.973 MB / 0.9673 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.1085/2.379, allocations: 46.33 MB / 1.334 GB, free: 7.547 MB / 1.014 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02233/2.401, allocations: 9.399 MB / 1.343 GB, free: 14.03 MB / 1.03 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00107/2.403, allocations: 98.05 kB / 1.343 GB, free: 13.93 MB / 1.03 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001645/2.403, allocations: 116.7 kB / 1.343 GB, free: 13.81 MB / 1.03 GB
Notification: Performance of pre-optimization done (n=787): time 1.529e-05/2.403, allocations: 0 / 1.343 GB, free: 13.81 MB / 1.03 GB
Notification: Performance of matching and sorting (n=789): time 0.06545/2.468, allocations: 17.98 MB / 1.361 GB, free: 11.7 MB / 1.045 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002575/2.469, allocations: 347.2 kB / 1.361 GB, free: 11.29 MB / 1.045 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01419/2.483, allocations: 5.154 MB / 1.366 GB, free: 6.16 MB / 1.045 GB
Notification: Performance of collectPreVariables (initialization): time 0.001981/2.485, allocations: 173.7 kB / 1.366 GB, free: 5.984 MB / 1.045 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004471/2.489, allocations: 3.587 MB / 1.37 GB, free: 2.367 MB / 1.045 GB
Notification: Performance of collectInitialBindings (initialization): time 0.005293/2.495, allocations: 2.73 MB / 1.373 GB, free: 15.59 MB / 1.061 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002114/2.497, allocations: 184 kB / 1.373 GB, free: 15.41 MB / 1.061 GB
Notification: Performance of setup shared object (initialization): time 0.001055/2.498, allocations: 0.6552 MB / 1.373 GB, free: 14.75 MB / 1.061 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008754/2.507, allocations: 2.923 MB / 1.376 GB, free: 11.82 MB / 1.061 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01105/2.518, allocations: 4.733 MB / 1.381 GB, free: 6.434 MB / 1.061 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01324/2.531, allocations: 3.58 MB / 1.384 GB, free: 2.852 MB / 1.061 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001234/2.531, allocations: 12 kB / 1.384 GB, free: 2.84 MB / 1.061 GB
Notification: Performance of matching and sorting (n=1197) (initialization): time 0.04647/2.578, allocations: 12.82 MB / 1.397 GB, free: 5.938 MB / 1.077 GB
Notification: Performance of prepare postOptimizeDAE: time 0.009389/2.587, allocations: 20.8 MB / 1.417 GB, free: 15.96 MB / 1.108 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001552/2.587, allocations: 48 kB / 1.417 GB, free: 15.91 MB / 1.108 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02577/2.613, allocations: 5.23 MB / 1.422 GB, free: 10.61 MB / 1.108 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008877/2.622, allocations: 1.747 MB / 1.424 GB, free: 8.863 MB / 1.108 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08738/2.71, allocations: 52.11 MB / 1.475 GB, free: 2.035 MB / 1.155 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0121/2.722, allocations: 0.5098 MB / 1.475 GB, free: 1.523 MB / 1.155 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002253/2.724, allocations: 336 kB / 1.476 GB, free: 1.195 MB / 1.155 GB
Notification: Performance of prepare postOptimizeDAE: time 2.38e-05/2.724, allocations: 4 kB / 1.476 GB, free: 1.191 MB / 1.155 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.02754/2.752, allocations: 9.944 MB / 1.485 GB, free: 7.191 MB / 1.17 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001969/2.752, allocations: 40 kB / 1.485 GB, free: 7.152 MB / 1.17 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02148/2.773, allocations: 4.325 MB / 1.49 GB, free: 2.773 MB / 1.17 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00935/2.783, allocations: 1.892 MB / 1.492 GB, free: 0.8789 MB / 1.17 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05746/2.84, allocations: 40.74 MB / 1.531 GB, free: 5.711 MB / 1.217 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01039/2.851, allocations: 402.2 kB / 1.532 GB, free: 5.316 MB / 1.217 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002086/2.853, allocations: 336 kB / 1.532 GB, free: 4.988 MB / 1.217 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 193
 * Number of states: 0 ()
 * Number of discrete variables: 10 (GEN_2_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_2_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_2_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_2_1.AVR.firstOrderLim.I.local_reset,GEN_2_1.TGOV.firstOrderLim.I.local_reset,GEN_1_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_1_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_1_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_1_1.AVR.firstOrderLim.I.local_reset,GEN_1_1.TGOV.firstOrderLim.I.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (951):
 * Single equations (assignments): 931
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 20
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 8 {(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0}
 * Non-linear torn systems: 12 {38 0,172 0,2 0,2 0,2 0,2 0,10 0,2 0,2 0,2 0,2 0,10 0}
Notification: Performance of prepare postOptimizeDAE: time 0.009771/2.863, allocations: 1.556 MB / 1.534 GB, free: 3.422 MB / 1.217 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004245/2.867, allocations: 0.799 MB / 1.534 GB, free: 2.613 MB / 1.217 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01894/2.886, allocations: 8.698 MB / 1.543 GB, free: 9.848 MB / 1.233 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0002068/2.886, allocations: 39.81 kB / 1.543 GB, free: 9.809 MB / 1.233 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.294e-05/2.886, allocations: 0 / 1.543 GB, free: 9.809 MB / 1.233 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006911/2.893, allocations: 1.42 MB / 1.544 GB, free: 8.359 MB / 1.233 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003197/2.896, allocations: 140 kB / 1.544 GB, free: 8.223 MB / 1.233 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0082/2.904, allocations: 346.5 kB / 1.545 GB, free: 7.883 MB / 1.233 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001844/2.906, allocations: 0.6183 MB / 1.545 GB, free: 7.262 MB / 1.233 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0281/2.934, allocations: 8.405 MB / 1.554 GB, free: 14.81 MB / 1.249 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.02867/2.963, allocations: 9.703 MB / 1.563 GB, free: 5.012 MB / 1.249 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.006868/2.97, allocations: 2.696 MB / 1.566 GB, free: 2.301 MB / 1.249 GB
Notification: Performance of sorting global known variables: time 0.01059/2.981, allocations: 3.254 MB / 1.569 GB, free: 15.07 MB / 1.264 GB
Notification: Performance of Backend: time 2.244e-06/2.981, allocations: 3.938 kB / 1.569 GB, free: 15.07 MB / 1.264 GB
Notification: Performance of simCode: created initialization part: time 0.06192/3.043, allocations: 21.54 MB / 1.59 GB, free: 9.016 MB / 1.28 GB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is set to 'isProtected' (=false) by default.
Notification: Performance of SimCode: time 1.413/4.456, allocations: 21.39 MB / 1.611 GB, free: 379.1 MB / 1.28 GB
Notification: Performance of Templates: time 0.3323/4.789, allocations: 147.9 MB / 1.755 GB, free: 289 MB / 1.28 GB
make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.makefile
Environment - environmentSimulation:
(rm -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=480 -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe 2>&1)
Environment - environmentSimulation: