Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_reduced_N_1_M_1.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.00144/0.00144, allocations: 114.6 kB / 21.13 MB, free: 0.6758 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001732/0.001732, allocations: 197 kB / 24.42 MB, free: 3.945 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.751/1.751, allocations: 207.6 MB / 235.1 MB, free: 10.95 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo): time 0.06891/0.06891, allocations: 14.4 MB / 301.7 MB, free: 7.684 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo): time 3.912/3.912, allocations: 468.9 MB / 0.8072 GB, free: 59.25 MB / 0.6233 GB
"
[Timeout remaining time 176]
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.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/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_reduced_N_1_M_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_reduced_N_1_M_1")
translateModel(ScalableTestGrids.Models.Type1.Type1_reduced_N_1_M_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_reduced_N_1_M_1") [Timeout 600]
"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: ScalableTestGrids requested package PowerGrids of version 1.0.2. PowerGrids 1.0.3 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002961/0.002961, allocations: 92.67 kB / 1.183 GB, free: 11.25 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.349/0.352, allocations: 182.5 MB / 1.361 GB, free: 12.89 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_reduced_N_1_M_1): time 0.05732/0.4093, allocations: 55.76 MB / 1.416 GB, free: 4.898 MB / 1.077 GB
Notification: Performance of NFInst.instExpressions: time 0.04331/0.4526, allocations: 20.57 MB / 1.436 GB, free: 268 kB / 1.092 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01299/0.4656, allocations: 211 kB / 1.436 GB, free: 56 kB / 1.092 GB
Notification: Performance of NFTyping.typeComponents: time 0.0113/0.4769, allocations: 2.898 MB / 1.439 GB, free: 13.14 MB / 1.108 GB
Notification: Performance of NFTyping.typeBindings: time 0.03987/0.5168, allocations: 8.183 MB / 1.447 GB, free: 4.918 MB / 1.108 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01037/0.5272, allocations: 3.155 MB / 1.45 GB, free: 1.758 MB / 1.108 GB
Notification: Performance of NFFlatten.flatten: time 0.03314/0.5603, allocations: 18.58 MB / 1.468 GB, free: 15.11 MB / 1.139 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004593/0.5649, allocations: 1.401 MB / 1.469 GB, free: 13.63 MB / 1.139 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01368/0.5786, allocations: 6.262 MB / 1.475 GB, free: 7.348 MB / 1.139 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01435/0.5929, allocations: 6.622 MB / 1.482 GB, free: 0.6992 MB / 1.139 GB
Notification: Performance of NFPackage.collectConstants: time 0.004583/0.5975, allocations: 0.9571 MB / 1.483 GB, free: 15.74 MB / 1.155 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.009165/0.6067, allocations: 1.84 MB / 1.485 GB, free: 13.9 MB / 1.155 GB
Notification: Performance of NFScalarize.scalarize: time 0.004491/0.6112, allocations: 2.085 MB / 1.487 GB, free: 11.81 MB / 1.155 GB
Notification: Performance of NFVerifyModel.verify: time 0.01474/0.6259, allocations: 5.921 MB / 1.492 GB, free: 5.859 MB / 1.155 GB
Notification: Performance of NFConvertDAE.convert: time 0.03104/0.6569, allocations: 14.93 MB / 1.507 GB, free: 6.879 MB / 1.17 GB
Notification: Performance of FrontEnd - DAE generated: time 9.247e-06/0.657, allocations: 3.984 kB / 1.507 GB, free: 6.875 MB / 1.17 GB
Notification: Performance of FrontEnd: time 2.615e-06/0.657, allocations: 0 / 1.507 GB, free: 6.875 MB / 1.17 GB
Notification: Performance of Transformations before backend: time 0.0005304/0.6575, allocations: 0 / 1.507 GB, free: 6.875 MB / 1.17 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1921
 * Number of variables: 1921
Notification: Performance of Generate backend data structure: time 0.06003/0.7175, allocations: 14.55 MB / 1.521 GB, free: 8.207 MB / 1.186 GB
Notification: Performance of prepare preOptimizeDAE: time 6.334e-05/0.7176, allocations: 11.98 kB / 1.521 GB, free: 8.195 MB / 1.186 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009079/0.7267, allocations: 1.49 MB / 1.523 GB, free: 6.699 MB / 1.186 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01935/0.746, allocations: 6.384 MB / 1.529 GB, free: 260 kB / 1.186 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007221/0.7467, allocations: 0.585 MB / 1.529 GB, free: 15.66 MB / 1.202 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002925/0.7497, allocations: 0.7655 MB / 1.53 GB, free: 14.9 MB / 1.202 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02169/0.7713, allocations: 7.887 MB / 1.538 GB, free: 6.695 MB / 1.202 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001774/0.7715, allocations: 15.88 kB / 1.538 GB, free: 6.68 MB / 1.202 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0009047/0.7724, allocations: 312 kB / 1.538 GB, free: 6.375 MB / 1.202 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001691/0.7726, allocations: 188 kB / 1.538 GB, free: 6.191 MB / 1.202 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 1.433/2.205, allocations: 7.495 MB / 1.546 GB, free: 155.5 MB / 1.202 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.1011/2.307, allocations: 48.93 MB / 1.593 GB, free: 117.7 MB / 1.202 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02294/2.329, allocations: 8.776 MB / 1.602 GB, free: 112.2 MB / 1.202 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008573/2.338, allocations: 3.169 MB / 1.605 GB, free: 110.6 MB / 1.202 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0009595/2.339, allocations: 92.42 kB / 1.605 GB, free: 110.6 MB / 1.202 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001649/2.339, allocations: 124.7 kB / 1.605 GB, free: 110.5 MB / 1.202 GB
Notification: Performance of pre-optimization done (n=787): time 2.663e-05/2.339, allocations: 0 / 1.605 GB, free: 110.5 MB / 1.202 GB
Notification: Performance of matching and sorting (n=787): time 0.03169/2.371, allocations: 10.34 MB / 1.615 GB, free: 105.3 MB / 1.202 GB
Notification: Performance of evaluateOutputsOnly (n=787 -> n=337): time 0.04094/2.412, allocations: 22.99 MB / 1.638 GB, free: 95.25 MB / 1.202 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001095/2.412, allocations: 295.3 kB / 1.638 GB, free: 94.93 MB / 1.202 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01802/2.43, allocations: 7.485 MB / 1.645 GB, free: 90.99 MB / 1.202 GB
Notification: Performance of collectPreVariables (initialization): time 0.001668/2.432, allocations: 169.5 kB / 1.646 GB, free: 90.85 MB / 1.202 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003081/2.435, allocations: 2.846 MB / 1.648 GB, free: 88.52 MB / 1.202 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001893/2.437, allocations: 1.267 MB / 1.65 GB, free: 87.58 MB / 1.202 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002694/2.439, allocations: 1.843 MB / 1.651 GB, free: 86.5 MB / 1.202 GB
Notification: Performance of setup shared object (initialization): time 6.205e-05/2.439, allocations: 305.1 kB / 1.652 GB, free: 86.2 MB / 1.202 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004879/2.444, allocations: 2.951 MB / 1.655 GB, free: 84.67 MB / 1.202 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006053/2.45, allocations: 4.75 MB / 1.659 GB, free: 81.02 MB / 1.202 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009067/2.459, allocations: 6.553 MB / 1.666 GB, free: 76.49 MB / 1.202 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.076e-05/2.459, allocations: 18.22 kB / 1.666 GB, free: 76.48 MB / 1.202 GB
Notification: Performance of matching and sorting (n=1195) (initialization): time 0.02461/2.484, allocations: 12.38 MB / 1.678 GB, free: 64.98 MB / 1.202 GB
Notification: Performance of prepare postOptimizeDAE: time 4.887e-05/2.484, allocations: 20 kB / 1.678 GB, free: 64.96 MB / 1.202 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.348e-05/2.484, allocations: 44 kB / 1.678 GB, free: 64.91 MB / 1.202 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.024/2.508, allocations: 5.423 MB / 1.683 GB, free: 59.43 MB / 1.202 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007372/2.515, allocations: 1.736 MB / 1.685 GB, free: 57.7 MB / 1.202 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06599/2.581, allocations: 54.08 MB / 1.738 GB, free: 0.793 MB / 1.202 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01274/2.594, allocations: 0.8992 MB / 1.738 GB, free: 84 kB / 1.202 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001871/2.596, allocations: 333.6 kB / 1.739 GB, free: 15.76 MB / 1.218 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.009201/2.605, allocations: 3.564 MB / 1.742 GB, free: 12.18 MB / 1.218 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.007242/2.612, allocations: 4.514 MB / 1.747 GB, free: 7.008 MB / 1.218 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01161/2.624, allocations: 6.229 MB / 1.753 GB, free: 180 kB / 1.218 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.21e-05/2.624, allocations: 12 kB / 1.753 GB, free: 168 kB / 1.218 GB
Notification: Performance of matching and sorting (n=1195) (initialization_lambda0): time 0.02499/2.649, allocations: 10.7 MB / 1.763 GB, free: 5.398 MB / 1.233 GB
Notification: Performance of prepare postOptimizeDAE: time 4.822e-05/2.649, allocations: 23.94 kB / 1.763 GB, free: 5.375 MB / 1.233 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.218e-05/2.649, allocations: 44 kB / 1.763 GB, free: 5.332 MB / 1.233 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02256/2.672, allocations: 4.355 MB / 1.768 GB, free: 0.9258 MB / 1.233 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009983/2.682, allocations: 1.892 MB / 1.769 GB, free: 15.03 MB / 1.249 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06143/2.743, allocations: 43.52 MB / 1.812 GB, free: 0.9414 MB / 1.28 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01212/2.755, allocations: 0.6041 MB / 1.813 GB, free: 460 kB / 1.28 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001651/2.757, allocations: 336 kB / 1.813 GB, free: 124 kB / 1.28 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 (949):
 * Single equations (assignments): 929
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 20
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 8 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%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 12 systems
   {(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.001682/2.759, allocations: 0.601 MB / 1.813 GB, free: 15.54 MB / 1.296 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003256/2.762, allocations: 0.7761 MB / 1.814 GB, free: 14.76 MB / 1.296 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01238/2.774, allocations: 5.343 MB / 1.819 GB, free: 9.359 MB / 1.296 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.952e-05/2.774, allocations: 15.94 kB / 1.819 GB, free: 9.344 MB / 1.296 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.428e-06/2.774, allocations: 3.984 kB / 1.819 GB, free: 9.34 MB / 1.296 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.005687/2.78, allocations: 1.382 MB / 1.821 GB, free: 7.957 MB / 1.296 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003022/2.783, allocations: 64 kB / 1.821 GB, free: 7.895 MB / 1.296 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005899/2.789, allocations: 158.5 kB / 1.821 GB, free: 7.742 MB / 1.296 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009243/2.79, allocations: 409.9 kB / 1.821 GB, free: 7.34 MB / 1.296 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01595/2.806, allocations: 5.375 MB / 1.827 GB, free: 1.941 MB / 1.296 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.1073/2.913, allocations: 38.87 MB / 1.865 GB, free: 10.72 MB / 1.343 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003277/2.916, allocations: 1.372 MB / 1.866 GB, free: 9.34 MB / 1.343 GB
Notification: Performance of sorting global known variables: time 0.01326/2.93, allocations: 4.63 MB / 1.87 GB, free: 4.738 MB / 1.343 GB
Notification: Performance of Backend: time 6.41e-07/2.93, allocations: 0 / 1.87 GB, free: 4.738 MB / 1.343 GB
Notification: Performance of simCode: created initialization part: time 0.05983/2.989, allocations: 20.99 MB / 1.891 GB, free: 15.25 MB / 1.374 GB
Notification: Performance of SimCode: time 0.04931/3.039, allocations: 22.24 MB / 1.913 GB, free: 8.82 MB / 1.39 GB
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 1: GEN_2_1.TGOV.firstOrderLim.G.y >= 0.0 and GEN_2_1.TGOV.firstOrderLim.I.y > GEN_2_1.TGOV.firstOrderLim.lim.uMax or GEN_2_1.TGOV.firstOrderLim.G.y <= 0.0 and GEN_2_1.TGOV.firstOrderLim.I.y < GEN_2_1.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 2: GEN_1_1.TGOV.firstOrderLim.G.y >= 0.0 and GEN_1_1.TGOV.firstOrderLim.I.y > GEN_1_1.TGOV.firstOrderLim.lim.uMax or GEN_1_1.TGOV.firstOrderLim.G.y <= 0.0 and GEN_1_1.TGOV.firstOrderLim.I.y < GEN_1_1.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 3: GEN_1_1.AVR.firstOrderLim.G.y >= 0.0 and GEN_1_1.AVR.firstOrderLim.I.y > GEN_1_1.AVR.firstOrderLim.lim.uMax or GEN_1_1.AVR.firstOrderLim.G.y <= 0.0 and GEN_1_1.AVR.firstOrderLim.I.y < GEN_1_1.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 4: GEN_2_1.AVR.firstOrderLim.G.y >= 0.0 and GEN_2_1.AVR.firstOrderLim.I.y > GEN_2_1.AVR.firstOrderLim.lim.uMax or GEN_2_1.AVR.firstOrderLim.G.y <= 0.0 and GEN_2_1.AVR.firstOrderLim.I.y < GEN_2_1.AVR.firstOrderLim.lim.uMin.
Error: Internal error SimCode DAEmode: The model ScalableTestGrids.Models.Type1.Type1_reduced_N_1_M_1 could not be translated
"
[Timeout remaining time 596]
[Calling sys.exit(0), Time elapsed: 12.334917276999477]
Failed to read output from testmodel.py, exit status != 0:
4.278044120999766 4.287511491 3.627864528
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4199422050>