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/package.mo", uses=false) Using package ScalableTestGrids with version 1.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0/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.0008565/0.0008566, allocations: 96.62 kB / 17.31 MB, free: 6.539 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.0008186/0.000819, allocations: 183.5 kB / 18.22 MB, free: 5.68 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.503/1.503, allocations: 205.4 MB / 224.4 MB, free: 13.06 MB / 190.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.05968/0.05968, allocations: 14.17 MB / 286.9 MB, free: 13.89 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0/package.mo): time 2.801/2.801, allocations: 421.4 MB / 0.7423 GB, free: 4.16 MB / 0.5764 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.0006269/0.0006269, allocations: 18.91 kB / 0.917 GB, free: 9.727 MB / 0.6548 GB Notification: Performance of FrontEnd - Absyn->SCode: time 1.083/1.084, allocations: 164.7 MB / 1.078 GB, free: 14.66 MB / 0.7959 GB Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_2_M_2): time 0.2425/1.326, allocations: 278.6 MB / 1.35 GB, free: 14.68 MB / 1.046 GB Notification: Performance of NFInst.instExpressions: time 0.1997/1.526, allocations: 98.38 MB / 1.446 GB, free: 11.97 MB / 1.14 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.08323/1.609, allocations: 1.03 MB / 1.447 GB, free: 10.94 MB / 1.14 GB Notification: Performance of NFTyping.typeComponents: time 0.06215/1.672, allocations: 12.46 MB / 1.459 GB, free: 14.42 MB / 1.155 GB Notification: Performance of NFTyping.typeBindings: time 0.1482/1.82, allocations: 34.15 MB / 1.492 GB, free: 12.15 MB / 1.187 GB Notification: Performance of NFTyping.typeClassSections: time 0.04167/1.861, allocations: 11.36 MB / 1.504 GB, free: 0.7852 MB / 1.187 GB Notification: Performance of NFFlatten.flatten: time 0.8844/2.746, allocations: 94.19 MB / 1.596 GB, free: 36.23 MB / 1.187 GB Notification: Performance of NFFlatten.resolveConnections: time 0.03777/2.784, allocations: 10.58 MB / 1.606 GB, free: 49.3 MB / 1.202 GB Notification: Performance of NFEvalConstants.evaluate: time 0.06154/2.845, allocations: 23.54 MB / 1.629 GB, free: 46.9 MB / 1.202 GB Notification: Performance of NFSimplifyModel.simplify: time 0.06275/2.908, allocations: 27.11 MB / 1.655 GB, free: 36.21 MB / 1.202 GB Notification: Performance of NFPackage.collectConstants: time 0.02172/2.93, allocations: 1.477 MB / 1.657 GB, free: 34.73 MB / 1.202 GB Notification: Performance of NFFlatten.collectFunctions: time 0.04525/2.975, allocations: 4.079 MB / 1.661 GB, free: 31.17 MB / 1.202 GB Notification: Performance of NFScalarize.scalarize: time 0.01863/2.994, allocations: 6.477 MB / 1.667 GB, free: 27.78 MB / 1.202 GB Notification: Performance of NFVerifyModel.verify: time 0.07252/3.066, allocations: 25.18 MB / 1.692 GB, free: 10.63 MB / 1.202 GB Notification: Performance of NFConvertDAE.convert: time 0.1318/3.198, allocations: 62.6 MB / 1.753 GB, free: 5.312 MB / 1.249 GB Notification: Performance of FrontEnd - DAE generated: time 6.793e-06/3.198, allocations: 0 / 1.753 GB, free: 5.312 MB / 1.249 GB Notification: Performance of FrontEnd: time 3.637e-06/3.198, allocations: 0 / 1.753 GB, free: 5.312 MB / 1.249 GB Notification: Performance of Transformations before backend: time 0.002933/3.201, allocations: 0 / 1.753 GB, free: 5.312 MB / 1.249 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.2451/3.446, allocations: 60.59 MB / 1.812 GB, free: 9.555 MB / 1.312 GB Notification: Performance of prepare preOptimizeDAE: time 7.447e-05/3.446, allocations: 15.91 kB / 1.812 GB, free: 9.539 MB / 1.312 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04492/3.491, allocations: 7.441 MB / 1.819 GB, free: 2.031 MB / 1.312 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.1074/3.599, allocations: 29.07 MB / 1.848 GB, free: 5.352 MB / 1.343 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00464/3.604, allocations: 2.943 MB / 1.851 GB, free: 2.406 MB / 1.343 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.01636/3.62, allocations: 3.902 MB / 1.854 GB, free: 14.5 MB / 1.358 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.1399/3.76, allocations: 40.83 MB / 1.894 GB, free: 4 MB / 1.39 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.002465/3.762, allocations: 79.25 kB / 1.894 GB, free: 3.922 MB / 1.39 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0136/3.776, allocations: 1.574 MB / 1.896 GB, free: 2.348 MB / 1.39 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0009051/3.777, allocations: 0.9531 MB / 1.897 GB, free: 1.395 MB / 1.39 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1174/3.894, allocations: 39.09 MB / 1.935 GB, free: 10.27 MB / 1.437 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 1.081/4.975, allocations: 246.4 MB / 2.176 GB, free: 471.8 MB / 1.53 GB Notification: Performance of preOpt comSubExp (simulation): time 0.1232/5.099, allocations: 62.58 MB / 2.237 GB, free: 436.6 MB / 1.53 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.04054/5.139, allocations: 18.36 MB / 2.255 GB, free: 428.2 MB / 1.53 GB Notification: Performance of preOpt evalFunc (simulation): time 0.004962/5.144, allocations: 491.6 kB / 2.255 GB, free: 428.2 MB / 1.53 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001327/5.146, allocations: 456.5 kB / 2.255 GB, free: 427.9 MB / 1.53 GB Notification: Performance of pre-optimization done (n=4107): time 0.0001075/5.146, allocations: 0 / 2.255 GB, free: 427.9 MB / 1.53 GB Notification: Performance of matching and sorting (n=4115): time 0.2967/5.443, allocations: 92.51 MB / 2.346 GB, free: 374.4 MB / 1.53 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004854/5.443, allocations: 1.272 MB / 2.347 GB, free: 372.9 MB / 1.53 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.05658/5.5, allocations: 25.24 MB / 2.372 GB, free: 348.1 MB / 1.53 GB Notification: Performance of collectPreVariables (initialization): time 0.008893/5.509, allocations: 0.7399 MB / 2.372 GB, free: 347.4 MB / 1.53 GB Notification: Performance of collectInitialEqns (initialization): time 0.01813/5.527, allocations: 18.88 MB / 2.391 GB, free: 329.4 MB / 1.53 GB Notification: Performance of collectInitialBindings (initialization): time 0.01931/5.546, allocations: 14.28 MB / 2.405 GB, free: 315.3 MB / 1.53 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.01341/5.56, allocations: 3.682 MB / 2.408 GB, free: 311.6 MB / 1.53 GB Notification: Performance of setup shared object (initialization): time 5.884e-05/5.56, allocations: 305.1 kB / 2.409 GB, free: 311.3 MB / 1.53 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.03773/5.597, allocations: 14.56 MB / 2.423 GB, free: 296.7 MB / 1.53 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.04472/5.642, allocations: 23.87 MB / 2.446 GB, free: 269.5 MB / 1.53 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.08833/5.731, allocations: 39.17 MB / 2.484 GB, free: 227.2 MB / 1.53 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0003521/5.731, allocations: 68 kB / 2.485 GB, free: 227.2 MB / 1.53 GB Notification: Performance of matching and sorting (n=6081) (initialization): time 0.1772/5.908, allocations: 63.7 MB / 2.547 GB, free: 163.2 MB / 1.53 GB Notification: Performance of prepare postOptimizeDAE: time 0.003562/5.912, allocations: 6.95 MB / 2.554 GB, free: 153.2 MB / 1.53 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003721/5.912, allocations: 224 kB / 2.554 GB, free: 152.9 MB / 1.53 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.1243/6.036, allocations: 28.31 MB / 2.581 GB, free: 124.4 MB / 1.53 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03541/6.072, allocations: 8.687 MB / 2.59 GB, free: 115.7 MB / 1.53 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.247/7.318, allocations: 228.4 MB / 2.813 GB, free: 488.1 MB / 1.53 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04966/7.368, allocations: 2.515 MB / 2.815 GB, free: 487.4 MB / 1.53 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01052/7.379, allocations: 1.65 MB / 2.817 GB, free: 487 MB / 1.53 GB Notification: Performance of prepare postOptimizeDAE: time 1.817e-05/7.379, allocations: 7.688 kB / 2.817 GB, free: 487 MB / 1.53 GB Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.1697/7.548, allocations: 51.55 MB / 2.867 GB, free: 466.4 MB / 1.53 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0008561/7.549, allocations: 220.4 kB / 2.868 GB, free: 466.4 MB / 1.53 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.1064/7.656, allocations: 22.59 MB / 2.89 GB, free: 460.9 MB / 1.53 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04102/7.697, allocations: 9.263 MB / 2.899 GB, free: 456 MB / 1.53 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3368/8.034, allocations: 184.3 MB / 3.079 GB, free: 264.5 MB / 1.53 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05375/8.087, allocations: 1.971 MB / 3.081 GB, free: 262.7 MB / 1.53 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01445/8.102, allocations: 1.637 MB / 3.082 GB, free: 261 MB / 1.53 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 (not torn): 0 * Torn equation systems: 74 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 32 systems {(2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%)} * Non-linear torn systems (#iteration vars, #inner vars): 42 systems {(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.05005/8.152, allocations: 7.07 MB / 3.089 GB, free: 254.1 MB / 1.53 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02115/8.173, allocations: 4.003 MB / 3.093 GB, free: 250 MB / 1.53 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1561/8.329, allocations: 45.2 MB / 3.137 GB, free: 204.7 MB / 1.53 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.00135/8.331, allocations: 195.2 kB / 3.137 GB, free: 204.5 MB / 1.53 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.058e-05/8.331, allocations: 0 / 3.137 GB, free: 204.5 MB / 1.53 GB Notification: Performance of postOpt removeConstants (simulation): time 0.03133/8.362, allocations: 6.796 MB / 3.144 GB, free: 197.7 MB / 1.53 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01668/8.379, allocations: 0.6836 MB / 3.145 GB, free: 197 MB / 1.53 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.03711/8.416, allocations: 1.681 MB / 3.146 GB, free: 195.5 MB / 1.53 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01147/8.428, allocations: 3.248 MB / 3.149 GB, free: 192.2 MB / 1.53 GB Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.1405/8.568, allocations: 42.97 MB / 3.191 GB, free: 150 MB / 1.53 GB Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.1374/8.706, allocations: 48.63 MB / 3.239 GB, free: 101.2 MB / 1.53 GB Notification: Performance of postOpt setEvaluationStage (simulation): time 0.9466/9.652, allocations: 13.62 MB / 3.252 GB, free: 477.8 MB / 1.53 GB Notification: Performance of sorting global known variables: time 0.05044/9.703, allocations: 16.11 MB / 3.268 GB, free: 476.9 MB / 1.53 GB Notification: Performance of Backend: time 1.643e-06/9.703, allocations: 0 / 3.268 GB, free: 476.9 MB / 1.53 GB Notification: Performance of simCode: created initialization part: time 0.2997/10, allocations: 108.3 MB / 3.374 GB, free: 446.4 MB / 1.53 GB Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed. Notification: Performance of SimCode: time 0.2285/10.23, allocations: 104.1 MB / 3.475 GB, free: 388.3 MB / 1.53 GB Notification: Performance of Templates: time 2.17/12.4, allocations: 0.7295 GB / 4.205 GB, free: 161 MB / 1.53 GB make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_2_M_2.makefile (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)