Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.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_2_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2")
translateModel(ScalableTestGrids.Models.Type1.Type1_N_2_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001203/0.001203, allocations: 92.95 kB / 16.95 MB, free: 6.441 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.0009726/0.0009727, allocations: 181.5 kB / 17.84 MB, free: 6.004 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.039/2.039, allocations: 205.4 MB / 224 MB, free: 13.17 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.06235/0.06235, allocations: 14.15 MB / 286.4 MB, free: 14.11 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo): time 5.261/5.261, 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.0006729/0.0006731, allocations: 12 kB / 0.9684 GB, free: 352 kB / 0.7327 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.673/1.674, allocations: 176 MB / 1.14 GB, free: 25.45 MB / 0.8735 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_2_M_2): time 0.2489/1.923, allocations: 233.7 MB / 1.368 GB, free: 8.457 MB / 1.045 GB
Notification: Performance of NFInst.instExpressions: time 0.2375/2.16, allocations: 94.71 MB / 1.461 GB, free: 9.508 MB / 1.139 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.1054/2.266, allocations: 0.9833 MB / 1.462 GB, free: 8.52 MB / 1.139 GB
Notification: Performance of NFTyping.typeComponents: time 0.07011/2.336, allocations: 9.862 MB / 1.472 GB, free: 14.6 MB / 1.155 GB
Notification: Performance of NFTyping.typeBindings: time 0.1853/2.521, allocations: 34.06 MB / 1.505 GB, free: 12.48 MB / 1.186 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05338/2.575, allocations: 8.317 MB / 1.513 GB, free: 4.156 MB / 1.186 GB
Notification: Performance of NFFlatten.flatten: time 0.2115/2.786, allocations: 65.54 MB / 1.577 GB, free: 2.496 MB / 1.249 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.04123/2.828, allocations: 8.766 MB / 1.586 GB, free: 9.641 MB / 1.264 GB
Notification: Performance of NFEvalConstants.evaluate: time 1.958/4.786, allocations: 24.53 MB / 1.609 GB, free: 25.19 MB / 1.265 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.08767/4.873, allocations: 25.39 MB / 1.634 GB, free: 25.19 MB / 1.265 GB
Notification: Performance of NFPackage.collectConstants: time 0.0277/4.901, allocations: 1.475 MB / 1.636 GB, free: 25.19 MB / 1.265 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.06591/4.967, allocations: 4.393 MB / 1.64 GB, free: 25.19 MB / 1.265 GB
Notification: Performance of NFScalarize.scalarize: time 0.0295/4.997, allocations: 6.153 MB / 1.646 GB, free: 25.19 MB / 1.265 GB
Notification: Performance of NFVerifyModel.verify: time 0.06054/5.057, allocations: 9.667 MB / 1.655 GB, free: 25.16 MB / 1.265 GB
Notification: Performance of NFConvertDAE.convert: time 0.1191/5.176, allocations: 50.57 MB / 1.705 GB, free: 14.69 MB / 1.265 GB
Notification: Performance of FrontEnd - DAE generated: time 6.352e-06/5.176, allocations: 2.594 kB / 1.705 GB, free: 14.69 MB / 1.265 GB
Notification: Performance of FrontEnd: time 3.286e-06/5.176, allocations: 0 / 1.705 GB, free: 14.69 MB / 1.265 GB
Notification: Performance of Transformations before backend: time 0.004567/5.181, allocations: 0 / 1.705 GB, free: 14.69 MB / 1.265 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 9966
 * Number of variables: 9966
Notification: Performance of Generate backend data structure: time 0.2788/5.46, allocations: 61.01 MB / 1.764 GB, free: 2.609 MB / 1.28 GB
Notification: Performance of prepare preOptimizeDAE: time 6.584e-05/5.46, allocations: 9.625 kB / 1.764 GB, free: 2.609 MB / 1.28 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05016/5.51, allocations: 7.441 MB / 1.772 GB, free: 15.29 MB / 1.296 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1168/5.627, allocations: 27.98 MB / 1.799 GB, free: 4.469 MB / 1.296 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.005454/5.632, allocations: 2.941 MB / 1.802 GB, free: 4.391 MB / 1.296 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01877/5.651, allocations: 3.902 MB / 1.806 GB, free: 2.426 MB / 1.296 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1483/5.8, allocations: 40.22 MB / 1.845 GB, free: 6.52 MB / 1.327 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.003168/5.803, allocations: 73.97 kB / 1.845 GB, free: 6.492 MB / 1.327 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01573/5.819, allocations: 1.575 MB / 1.847 GB, free: 6.137 MB / 1.327 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001142/5.82, allocations: 0.9414 MB / 1.848 GB, free: 5.195 MB / 1.327 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1211/5.941, allocations: 38.8 MB / 1.885 GB, free: 11.77 MB / 1.358 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.5256/6.467, allocations: 246.3 MB / 2.126 GB, free: 14.98 MB / 1.577 GB
Notification: Performance of preOpt comSubExp (simulation): time 1.5/7.967, allocations: 62.28 MB / 2.187 GB, free: 322.5 MB / 1.593 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.006339/7.973, allocations: 489.6 kB / 2.187 GB, free: 322.5 MB / 1.593 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0005829/7.974, allocations: 460.8 kB / 2.188 GB, free: 322.4 MB / 1.593 GB
Notification: Performance of pre-optimization done (n=4107): time 5.864e-05/7.974, allocations: 0 / 2.188 GB, free: 322.4 MB / 1.593 GB
Notification: Performance of matching and sorting (n=4115): time 0.437/8.411, allocations: 91.83 MB / 2.277 GB, free: 315 MB / 1.593 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.001124/8.412, allocations: 1.272 MB / 2.279 GB, free: 313.9 MB / 1.593 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.08026/8.492, allocations: 24.97 MB / 2.303 GB, free: 312.2 MB / 1.593 GB
Notification: Performance of collectPreVariables (initialization): time 0.01161/8.504, allocations: 0.7429 MB / 2.304 GB, free: 312.2 MB / 1.593 GB
Notification: Performance of collectInitialEqns (initialization): time 0.02252/8.527, allocations: 18.88 MB / 2.322 GB, free: 306.6 MB / 1.593 GB
Notification: Performance of collectInitialBindings (initialization): time 0.02614/8.553, allocations: 14.28 MB / 2.336 GB, free: 297.1 MB / 1.593 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0111/8.564, allocations: 0.9268 MB / 2.337 GB, free: 297.1 MB / 1.593 GB
Notification: Performance of setup shared object (initialization): time 0.004537/8.568, allocations: 2.075 MB / 2.339 GB, free: 296.7 MB / 1.593 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04001/8.608, allocations: 14.35 MB / 2.353 GB, free: 296.6 MB / 1.593 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.05314/8.662, allocations: 23.48 MB / 2.376 GB, free: 281 MB / 1.593 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.05781/8.72, allocations: 17.74 MB / 2.393 GB, free: 271.8 MB / 1.593 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.001158/8.721, allocations: 64 kB / 2.393 GB, free: 271.7 MB / 1.593 GB
Notification: Performance of matching and sorting (n=6081) (initialization): time 0.2279/8.949, allocations: 63.19 MB / 2.455 GB, free: 234.8 MB / 1.593 GB
Notification: Performance of prepare postOptimizeDAE: time 0.05059/8.999, allocations: 208.6 MB / 2.659 GB, free: 18.21 MB / 1.593 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001346/9.001, allocations: 220 kB / 2.659 GB, free: 18 MB / 1.593 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1239/9.125, allocations: 26.23 MB / 2.685 GB, free: 13.5 MB / 1.608 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04801/9.173, allocations: 8.683 MB / 2.693 GB, free: 5.617 MB / 1.608 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.08/11.25, allocations: 234 MB / 2.921 GB, free: 0.5912 GB / 1.702 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.06931/11.32, allocations: 2.512 MB / 2.924 GB, free: 0.5904 GB / 1.702 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0145/11.34, allocations: 1.65 MB / 2.926 GB, free: 0.5901 GB / 1.702 GB
Notification: Performance of prepare postOptimizeDAE: time 2.382e-05/11.34, allocations: 4.188 kB / 2.926 GB, free: 0.5901 GB / 1.702 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.2036/11.54, allocations: 51.1 MB / 2.975 GB, free: 0.57 GB / 1.702 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001814/11.54, allocations: 220.2 kB / 2.976 GB, free: 0.57 GB / 1.702 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1117/11.65, allocations: 22.53 MB / 2.998 GB, free: 0.5619 GB / 1.702 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04915/11.7, allocations: 9.253 MB / 3.007 GB, free: 0.5541 GB / 1.702 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.4052/12.11, allocations: 188.3 MB / 3.191 GB, free: 374.9 MB / 1.702 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.07156/12.18, allocations: 1.965 MB / 3.192 GB, free: 373.1 MB / 1.702 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.02013/12.2, allocations: 1.644 MB / 3.194 GB, free: 371.5 MB / 1.702 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1007
 * Number of states: 0 ()
 * Number of discrete variables: 40 (GEN_4_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_4_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_4_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_4_2.AVR.firstOrderLim.I.local_reset,GEN_4_2.TGOV.firstOrderLim.I.local_reset,GEN_4_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_4_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_4_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_4_1.AVR.firstOrderLim.I.local_reset,GEN_4_1.TGOV.firstOrderLim.I.local_reset,GEN_3_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_3_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_3_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_3_2.AVR.firstOrderLim.I.local_reset,GEN_3_2.TGOV.firstOrderLim.I.local_reset,GEN_3_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_3_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_3_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_3_1.AVR.firstOrderLim.I.local_reset,GEN_3_1.TGOV.firstOrderLim.I.local_reset,GEN_2_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_2_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_2_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_2_2.AVR.firstOrderLim.I.local_reset,GEN_2_2.TGOV.firstOrderLim.I.local_reset,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_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_1_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_1_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_1_2.AVR.firstOrderLim.I.local_reset,GEN_1_2.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 (4729):
 * Single equations (assignments): 4655
 * 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: 74
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 32 {(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,(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,(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,(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: 42 {38 0,1164 0,2 0,2 0,2 0,2 0,10 0,2 0,2 0,2 0,2 0,10 0,2 0,2 0,2 0,2 0,10 0,2 0,2 0,2 0,2 0,10 0,2 0,2 0,2 0,2 0,10 0,2 0,2 0,2 0,2 0,10 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.06392/12.26, allocations: 7.075 MB / 3.201 GB, free: 364.5 MB / 1.702 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0309/12.29, allocations: 4.003 MB / 3.205 GB, free: 360.5 MB / 1.702 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1848/12.48, allocations: 44.89 MB / 3.249 GB, free: 315.5 MB / 1.702 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001854/12.48, allocations: 195.2 kB / 3.249 GB, free: 315.3 MB / 1.702 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0002633/12.48, allocations: 0 / 3.249 GB, free: 315.3 MB / 1.702 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0476/12.53, allocations: 6.798 MB / 3.256 GB, free: 308.4 MB / 1.702 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.03169/12.56, allocations: 0.6797 MB / 3.256 GB, free: 307.7 MB / 1.702 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.06224/12.62, allocations: 1.683 MB / 3.258 GB, free: 306.2 MB / 1.702 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01834/12.64, allocations: 3.256 MB / 3.261 GB, free: 302.9 MB / 1.702 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.2036/12.85, allocations: 42.69 MB / 3.303 GB, free: 261 MB / 1.702 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.1746/13.02, allocations: 48.26 MB / 3.35 GB, free: 212.6 MB / 1.702 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.03912/13.06, allocations: 13.49 MB / 3.363 GB, free: 199.1 MB / 1.702 GB
Notification: Performance of sorting global known variables: time 1.521/14.58, allocations: 15.83 MB / 3.379 GB, free: 0.5766 GB / 1.702 GB
Notification: Performance of Backend: time 2.865e-06/14.58, allocations: 0.9062 kB / 3.379 GB, free: 0.5766 GB / 1.702 GB
Notification: Performance of simCode: created initialization part: time 0.4134/14.99, allocations: 106.9 MB / 3.483 GB, free: 0.5494 GB / 1.702 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 0.3338/15.33, allocations: 181.3 MB / 3.66 GB, free: 424.6 MB / 1.702 GB
Notification: Performance of Templates: time 2.966/18.29, allocations: 0.7098 GB / 4.37 GB, free: 283.5 MB / 1.702 GB
make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.makefile
Environment - environmentSimulation:
(rm -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=480 -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.pipe 2>&1)
Environment - environmentSimulation: