Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2.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.00648/0.00648, allocations: 101 kB / 21.81 MB, free: 4.715 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.001377/0.001377, allocations: 192.1 kB / 25.08 MB, free: 3.125 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.417/1.417, allocations: 207.6 MB / 235.8 MB, free: 10.02 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.1096/0.1096, allocations: 14.38 MB / 302.4 MB, free: 7.594 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.342/3.342, allocations: 468.9 MB / 0.8079 GB, free: 59.03 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.GridModelBuilders.Type1Sample_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2")
translateModel(ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2") [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.00293/0.00293, allocations: 92.28 kB / 1.183 GB, free: 11.05 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3456/0.3486, allocations: 182.6 MB / 1.362 GB, free: 12.61 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2): time 0.06736/0.4159, allocations: 63.48 MB / 1.424 GB, free: 12.87 MB / 1.092 GB
Notification: Performance of NFInst.instExpressions: time 0.04979/0.4657, allocations: 23.25 MB / 1.446 GB, free: 5.547 MB / 1.108 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01562/0.4813, allocations: 305.8 kB / 1.447 GB, free: 5.246 MB / 1.108 GB
Notification: Performance of NFTyping.typeComponents: time 0.013/0.4943, allocations: 3.329 MB / 1.45 GB, free: 1.895 MB / 1.108 GB
Notification: Performance of NFTyping.typeBindings: time 0.03337/0.5277, allocations: 9.428 MB / 1.459 GB, free: 8.445 MB / 1.124 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01268/0.5404, allocations: 3.303 MB / 1.462 GB, free: 5.141 MB / 1.124 GB
Notification: Performance of NFFlatten.flatten: time 0.03879/0.5792, allocations: 21.25 MB / 1.483 GB, free: 15.81 MB / 1.155 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.006868/0.5861, allocations: 1.477 MB / 1.485 GB, free: 14.26 MB / 1.155 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0176/0.6037, allocations: 7.278 MB / 1.492 GB, free: 6.957 MB / 1.155 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01617/0.6198, allocations: 7.567 MB / 1.499 GB, free: 15.36 MB / 1.17 GB
Notification: Performance of NFPackage.collectConstants: time 0.005394/0.6252, allocations: 1.047 MB / 1.5 GB, free: 14.31 MB / 1.17 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01185/0.6371, allocations: 2.039 MB / 1.502 GB, free: 12.27 MB / 1.17 GB
Notification: Performance of NFScalarize.scalarize: time 0.006729/0.6438, allocations: 2.315 MB / 1.504 GB, free: 9.949 MB / 1.17 GB
Notification: Performance of NFVerifyModel.verify: time 0.01828/0.6621, allocations: 6.722 MB / 1.511 GB, free: 3.195 MB / 1.17 GB
Notification: Performance of NFConvertDAE.convert: time 0.0355/0.6976, allocations: 16.8 MB / 1.527 GB, free: 2.328 MB / 1.186 GB
Notification: Performance of FrontEnd - DAE generated: time 8.266e-06/0.6976, allocations: 0 / 1.527 GB, free: 2.328 MB / 1.186 GB
Notification: Performance of FrontEnd: time 2.154e-06/0.6976, allocations: 0 / 1.527 GB, free: 2.328 MB / 1.186 GB
Notification: Performance of Transformations before backend: time 0.0006818/0.6983, allocations: 4 kB / 1.527 GB, free: 2.324 MB / 1.186 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2196
 * Number of variables: 2196
Notification: Performance of Generate backend data structure: time 0.06802/0.7663, allocations: 16.47 MB / 1.543 GB, free: 1.746 MB / 1.202 GB
Notification: Performance of prepare preOptimizeDAE: time 6.442e-05/0.7664, allocations: 12.02 kB / 1.543 GB, free: 1.734 MB / 1.202 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009928/0.7763, allocations: 1.704 MB / 1.545 GB, free: 24 kB / 1.202 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 1.412/2.188, allocations: 6.953 MB / 1.552 GB, free: 154.5 MB / 1.202 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000835/2.189, allocations: 0.6622 MB / 1.553 GB, free: 154.1 MB / 1.202 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003098/2.192, allocations: 0.8685 MB / 1.553 GB, free: 153.9 MB / 1.202 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02974/2.221, allocations: 8.983 MB / 1.562 GB, free: 149.5 MB / 1.202 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0004745/2.222, allocations: 15.22 kB / 1.562 GB, free: 149.5 MB / 1.202 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002874/2.225, allocations: 356.5 kB / 1.563 GB, free: 149.2 MB / 1.202 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002736/2.225, allocations: 216.9 kB / 1.563 GB, free: 149.2 MB / 1.202 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02996/2.255, allocations: 8.518 MB / 1.571 GB, free: 146.2 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.1212/2.376, allocations: 56.67 MB / 1.626 GB, free: 95.98 MB / 1.202 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02572/2.402, allocations: 8.771 MB / 1.635 GB, free: 91.24 MB / 1.202 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.009641/2.412, allocations: 3.569 MB / 1.638 GB, free: 89.45 MB / 1.202 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001049/2.413, allocations: 112.5 kB / 1.639 GB, free: 89.43 MB / 1.202 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0002093/2.413, allocations: 133.9 kB / 1.639 GB, free: 89.32 MB / 1.202 GB
Notification: Performance of pre-optimization done (n=909): time 3.644e-05/2.413, allocations: 7.922 kB / 1.639 GB, free: 89.31 MB / 1.202 GB
Notification: Performance of matching and sorting (n=909): time 0.03296/2.446, allocations: 11.92 MB / 1.65 GB, free: 83.3 MB / 1.202 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001677/2.446, allocations: 355.2 kB / 1.651 GB, free: 82.98 MB / 1.202 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0139/2.46, allocations: 6.324 MB / 1.657 GB, free: 79.72 MB / 1.202 GB
Notification: Performance of collectPreVariables (initialization): time 0.002042/2.462, allocations: 193.4 kB / 1.657 GB, free: 79.56 MB / 1.202 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00311/2.465, allocations: 4.119 MB / 1.661 GB, free: 76.35 MB / 1.202 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0047/2.47, allocations: 3.3 MB / 1.664 GB, free: 73.57 MB / 1.202 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002753/2.473, allocations: 1.736 MB / 1.666 GB, free: 72.55 MB / 1.202 GB
Notification: Performance of setup shared object (initialization): time 6.957e-05/2.473, allocations: 309.1 kB / 1.666 GB, free: 72.25 MB / 1.202 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005594/2.478, allocations: 3.268 MB / 1.669 GB, free: 70.57 MB / 1.202 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00763/2.486, allocations: 5.274 MB / 1.675 GB, free: 66.54 MB / 1.202 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01447/2.5, allocations: 8.582 MB / 1.683 GB, free: 59.7 MB / 1.202 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.72e-05/2.5, allocations: 16 kB / 1.683 GB, free: 59.68 MB / 1.202 GB
Notification: Performance of matching and sorting (n=1351) (initialization): time 0.03313/2.533, allocations: 13.8 MB / 1.696 GB, free: 45.75 MB / 1.202 GB
Notification: Performance of prepare postOptimizeDAE: time 9.776e-05/2.534, allocations: 27.59 kB / 1.696 GB, free: 45.73 MB / 1.202 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000129/2.534, allocations: 48 kB / 1.697 GB, free: 45.68 MB / 1.202 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02903/2.563, allocations: 6.098 MB / 1.702 GB, free: 39.52 MB / 1.202 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008612/2.571, allocations: 1.95 MB / 1.704 GB, free: 37.57 MB / 1.202 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08404/2.655, allocations: 56.08 MB / 1.759 GB, free: 11.2 MB / 1.233 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01458/2.67, allocations: 0.8688 MB / 1.76 GB, free: 10.33 MB / 1.233 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002503/2.672, allocations: 379.9 kB / 1.76 GB, free: 9.957 MB / 1.233 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01384/2.686, allocations: 4.266 MB / 1.765 GB, free: 5.684 MB / 1.233 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.009921/2.696, allocations: 5.024 MB / 1.769 GB, free: 15.93 MB / 1.249 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01687/2.713, allocations: 8.256 MB / 1.777 GB, free: 6.961 MB / 1.249 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 5.076e-05/2.713, allocations: 16 kB / 1.778 GB, free: 6.945 MB / 1.249 GB
Notification: Performance of matching and sorting (n=1351) (initialization_lambda0): time 0.03071/2.744, allocations: 12.12 MB / 1.789 GB, free: 10.7 MB / 1.265 GB
Notification: Performance of prepare postOptimizeDAE: time 6e-05/2.744, allocations: 24.19 kB / 1.789 GB, free: 10.67 MB / 1.265 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.792e-05/2.744, allocations: 55.94 kB / 1.789 GB, free: 10.62 MB / 1.265 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02636/2.77, allocations: 5.015 MB / 1.794 GB, free: 5.551 MB / 1.265 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01045/2.781, allocations: 2.095 MB / 1.796 GB, free: 3.453 MB / 1.265 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06969/2.85, allocations: 45.82 MB / 1.841 GB, free: 3.555 MB / 1.312 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01313/2.864, allocations: 0.6657 MB / 1.842 GB, free: 2.887 MB / 1.312 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001887/2.865, allocations: 372 kB / 1.842 GB, free: 2.523 MB / 1.312 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 215
 * 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 (1061):
 * Single equations (assignments): 1041
 * 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), (216,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.00236/2.868, allocations: 0.7494 MB / 1.843 GB, free: 1.766 MB / 1.312 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004273/2.872, allocations: 0.9434 MB / 1.844 GB, free: 0.8164 MB / 1.312 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02206/2.894, allocations: 9.838 MB / 1.853 GB, free: 6.992 MB / 1.327 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001284/2.894, allocations: 47.77 kB / 1.853 GB, free: 6.945 MB / 1.327 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.55e-05/2.894, allocations: 0 / 1.853 GB, free: 6.945 MB / 1.327 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.008118/2.902, allocations: 1.821 MB / 1.855 GB, free: 5.109 MB / 1.327 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002942/2.905, allocations: 156 kB / 1.855 GB, free: 4.957 MB / 1.327 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.008667/2.914, allocations: 279.7 kB / 1.856 GB, free: 4.684 MB / 1.327 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001642/2.916, allocations: 0.7591 MB / 1.856 GB, free: 3.922 MB / 1.327 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02791/2.944, allocations: 9.324 MB / 1.865 GB, free: 10.57 MB / 1.343 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.1156/3.059, allocations: 47.65 MB / 1.912 GB, free: 10.63 MB / 1.39 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.007117/3.066, allocations: 2.82 MB / 1.915 GB, free: 7.805 MB / 1.39 GB
Notification: Performance of sorting global known variables: time 0.009337/3.076, allocations: 4.066 MB / 1.919 GB, free: 3.781 MB / 1.39 GB
Notification: Performance of Backend: time 1.262e-06/3.076, allocations: 0 / 1.919 GB, free: 3.781 MB / 1.39 GB
Notification: Performance of simCode: created initialization part: time 0.07331/3.149, allocations: 23.66 MB / 1.942 GB, free: 11.57 MB / 1.421 GB
Notification: Performance of SimCode: time 0.07944/3.228, allocations: 33.22 MB / 1.974 GB, free: 10.15 MB / 1.452 GB
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 0: 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 1: 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 2: 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 3: 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.GridModelBuilders.Type1Sample_N_1_M_2 could not be translated
"
[Timeout remaining time 595]
[Calling sys.exit(0), Time elapsed: 11.695263557994622]
Failed to read output from testmodel.py, exit status != 0:
4.781338043001597 4.790067602 4.089532251
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd4546017e0>