Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001395/0.001395, allocations: 103.8 kB / 21.97 MB, free: 5.168 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001477/0.001477, allocations: 212.7 kB / 25.28 MB, free: 3.035 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.441/1.441, allocations: 207.7 MB / 236.1 MB, free: 9.777 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.07031/0.07031, allocations: 14.4 MB / 302.8 MB, free: 7.223 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.2.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.2.0/package.mo): time 3.412/3.412, allocations: 468.9 MB / 0.8082 GB, free: 58.8 MB / 0.6233 GB
"
[Timeout remaining time 176]
Using package ScalableTestGrids with version 1.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.2.0/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.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(ScalableTestGrids.Models.Type1.Type1_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_2")
translateModel(ScalableTestGrids.Models.Type1.Type1_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_1_M_2") [Timeout 600]
"Notification: Modelica requested package Complex of version 3.2.3. Complex 4.1.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.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.00269/0.00269, allocations: 91.5 kB / 1.184 GB, free: 10.92 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3309/0.3335, allocations: 182.6 MB / 1.362 GB, free: 12.28 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_1_M_2): time 0.05538/0.3889, allocations: 54.44 MB / 1.415 GB, free: 5.617 MB / 1.077 GB
Notification: Performance of NFInst.instExpressions: time 0.0415/0.4304, allocations: 22.13 MB / 1.437 GB, free: 15.42 MB / 1.108 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01265/0.4431, allocations: 305.8 kB / 1.437 GB, free: 15.12 MB / 1.108 GB
Notification: Performance of NFTyping.typeComponents: time 0.01203/0.4551, allocations: 3.177 MB / 1.44 GB, free: 11.92 MB / 1.108 GB
Notification: Performance of NFTyping.typeBindings: time 0.03238/0.4875, allocations: 9.369 MB / 1.45 GB, free: 2.516 MB / 1.108 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0102/0.4977, allocations: 3.281 MB / 1.453 GB, free: 15.23 MB / 1.124 GB
Notification: Performance of NFFlatten.flatten: time 0.03293/0.5306, allocations: 21.2 MB / 1.473 GB, free: 9.961 MB / 1.139 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004701/0.5353, allocations: 1.407 MB / 1.475 GB, free: 8.477 MB / 1.139 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01553/0.5508, allocations: 7.278 MB / 1.482 GB, free: 1.172 MB / 1.139 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01434/0.5652, allocations: 6.868 MB / 1.489 GB, free: 10.27 MB / 1.155 GB
Notification: Performance of NFPackage.collectConstants: time 0.004769/0.57, allocations: 1.047 MB / 1.49 GB, free: 9.227 MB / 1.155 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01028/0.5802, allocations: 2.027 MB / 1.492 GB, free: 7.199 MB / 1.155 GB
Notification: Performance of NFScalarize.scalarize: time 0.005428/0.5857, allocations: 2.311 MB / 1.494 GB, free: 4.879 MB / 1.155 GB
Notification: Performance of NFVerifyModel.verify: time 0.01508/0.6007, allocations: 5.486 MB / 1.499 GB, free: 15.36 MB / 1.17 GB
Notification: Performance of NFConvertDAE.convert: time 0.03178/0.6325, allocations: 16.94 MB / 1.516 GB, free: 14.37 MB / 1.186 GB
Notification: Performance of FrontEnd - DAE generated: time 1.116e-05/0.6325, allocations: 4 kB / 1.516 GB, free: 14.37 MB / 1.186 GB
Notification: Performance of FrontEnd: time 2.775e-06/0.6325, allocations: 0 / 1.516 GB, free: 14.37 MB / 1.186 GB
Notification: Performance of Transformations before backend: time 0.0006527/0.6332, allocations: 0 / 1.516 GB, free: 14.37 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.06344/0.6966, allocations: 16.47 MB / 1.532 GB, free: 13.78 MB / 1.202 GB
Notification: Performance of prepare preOptimizeDAE: time 5.559e-05/0.6967, allocations: 15.97 kB / 1.532 GB, free: 13.77 MB / 1.202 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009326/0.706, allocations: 1.704 MB / 1.534 GB, free: 12.05 MB / 1.202 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01946/0.7255, allocations: 6.961 MB / 1.54 GB, free: 4.984 MB / 1.202 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007916/0.7263, allocations: 0.6496 MB / 1.541 GB, free: 4.332 MB / 1.202 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002673/0.7289, allocations: 0.8671 MB / 1.542 GB, free: 3.465 MB / 1.202 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 1.128/1.857, allocations: 9.234 MB / 1.551 GB, free: 158.4 MB / 1.202 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002554/1.857, allocations: 14.41 kB / 1.551 GB, free: 158.3 MB / 1.202 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001325/1.858, allocations: 352.3 kB / 1.551 GB, free: 158.1 MB / 1.202 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002615/1.858, allocations: 212.3 kB / 1.551 GB, free: 158.1 MB / 1.202 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02453/1.883, allocations: 8.409 MB / 1.56 GB, free: 155.1 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.0864/1.969, allocations: 34.59 MB / 1.593 GB, free: 133.7 MB / 1.202 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01908/1.988, allocations: 8.734 MB / 1.602 GB, free: 129 MB / 1.202 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008236/1.997, allocations: 3.567 MB / 1.605 GB, free: 127.2 MB / 1.202 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000831/1.997, allocations: 105.1 kB / 1.605 GB, free: 127.2 MB / 1.202 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001449/1.998, allocations: 131.7 kB / 1.606 GB, free: 127.1 MB / 1.202 GB
Notification: Performance of pre-optimization done (n=909): time 2.486e-05/1.998, allocations: 5.312 kB / 1.606 GB, free: 127.1 MB / 1.202 GB
Notification: Performance of matching and sorting (n=909): time 0.03137/2.029, allocations: 11.89 MB / 1.617 GB, free: 121.2 MB / 1.202 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001851/2.029, allocations: 363.1 kB / 1.618 GB, free: 120.9 MB / 1.202 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01423/2.043, allocations: 6.276 MB / 1.624 GB, free: 117.6 MB / 1.202 GB
Notification: Performance of collectPreVariables (initialization): time 0.001874/2.045, allocations: 193.5 kB / 1.624 GB, free: 117.5 MB / 1.202 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003631/2.049, allocations: 4.129 MB / 1.628 GB, free: 114.2 MB / 1.202 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00482/2.054, allocations: 3.291 MB / 1.631 GB, free: 111.9 MB / 1.202 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.005133/2.059, allocations: 1.711 MB / 1.633 GB, free: 110.9 MB / 1.202 GB
Notification: Performance of setup shared object (initialization): time 6.02e-05/2.059, allocations: 305.1 kB / 1.633 GB, free: 110.6 MB / 1.202 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005449/2.064, allocations: 3.242 MB / 1.636 GB, free: 108.9 MB / 1.202 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006939/2.071, allocations: 5.841 MB / 1.642 GB, free: 104.2 MB / 1.202 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0147/2.086, allocations: 8.538 MB / 1.65 GB, free: 95.85 MB / 1.202 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.987e-05/2.086, allocations: 16 kB / 1.65 GB, free: 95.84 MB / 1.202 GB
Notification: Performance of matching and sorting (n=1333) (initialization): time 0.03404/2.12, allocations: 13.7 MB / 1.664 GB, free: 82 MB / 1.202 GB
Notification: Performance of prepare postOptimizeDAE: time 6.17e-05/2.12, allocations: 27.28 kB / 1.664 GB, free: 81.98 MB / 1.202 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.507e-05/2.12, allocations: 48.88 kB / 1.664 GB, free: 81.93 MB / 1.202 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02719/2.147, allocations: 6.103 MB / 1.67 GB, free: 75.77 MB / 1.202 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007713/2.155, allocations: 1.915 MB / 1.672 GB, free: 73.85 MB / 1.202 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.07679/2.232, allocations: 54.75 MB / 1.725 GB, free: 17.1 MB / 1.202 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01228/2.244, allocations: 0.8411 MB / 1.726 GB, free: 16.41 MB / 1.202 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00206/2.246, allocations: 368 kB / 1.726 GB, free: 16.05 MB / 1.202 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0111/2.257, allocations: 4.23 MB / 1.73 GB, free: 11.82 MB / 1.202 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00754/2.265, allocations: 5.594 MB / 1.736 GB, free: 5.348 MB / 1.202 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01579/2.281, allocations: 8.214 MB / 1.744 GB, free: 12.42 MB / 1.218 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.271e-05/2.281, allocations: 15.94 kB / 1.744 GB, free: 12.4 MB / 1.218 GB
Notification: Performance of matching and sorting (n=1333) (initialization_lambda0): time 0.03465/2.315, allocations: 12.03 MB / 1.756 GB, free: 252 kB / 1.218 GB
Notification: Performance of prepare postOptimizeDAE: time 8.812e-05/2.316, allocations: 24 kB / 1.756 GB, free: 228 kB / 1.218 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.337e-05/2.316, allocations: 48 kB / 1.756 GB, free: 180 kB / 1.218 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02622/2.342, allocations: 5.024 MB / 1.761 GB, free: 11.1 MB / 1.233 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009481/2.351, allocations: 2.056 MB / 1.763 GB, free: 9.043 MB / 1.233 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.07181/2.423, allocations: 44.73 MB / 1.806 GB, free: 10.45 MB / 1.28 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01127/2.434, allocations: 0.6634 MB / 1.807 GB, free: 9.906 MB / 1.28 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001843/2.436, allocations: 364 kB / 1.807 GB, free: 9.551 MB / 1.28 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 (1043):
 * Single equations (assignments): 1023
 * 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.003562/2.44, allocations: 0.7329 MB / 1.808 GB, free: 8.836 MB / 1.28 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004096/2.444, allocations: 0.9395 MB / 1.809 GB, free: 7.891 MB / 1.28 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02756/2.471, allocations: 9.81 MB / 1.818 GB, free: 14.05 MB / 1.296 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0002094/2.472, allocations: 43.83 kB / 1.819 GB, free: 14 MB / 1.296 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.173e-05/2.472, allocations: 0 / 1.819 GB, free: 14 MB / 1.296 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.007822/2.48, allocations: 1.811 MB / 1.82 GB, free: 12.19 MB / 1.296 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00304/2.483, allocations: 152 kB / 1.82 GB, free: 12.04 MB / 1.296 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.008349/2.491, allocations: 283.6 kB / 1.821 GB, free: 11.76 MB / 1.296 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001891/2.493, allocations: 0.7592 MB / 1.821 GB, free: 11 MB / 1.296 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02945/2.522, allocations: 9.268 MB / 1.831 GB, free: 1.879 MB / 1.296 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.1319/2.654, allocations: 45.93 MB / 1.875 GB, free: 3.672 MB / 1.343 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.006617/2.661, allocations: 2.812 MB / 1.878 GB, free: 0.8516 MB / 1.343 GB
Notification: Performance of sorting global known variables: time 0.009416/2.67, allocations: 3.98 MB / 1.882 GB, free: 12.91 MB / 1.358 GB
Notification: Performance of Backend: time 1.062e-06/2.67, allocations: 0 / 1.882 GB, free: 12.91 MB / 1.358 GB
Notification: Performance of simCode: created initialization part: time 0.0672/2.737, allocations: 23.56 MB / 1.905 GB, free: 4.801 MB / 1.374 GB
Notification: Performance of SimCode: time 0.06949/2.807, allocations: 32.73 MB / 1.937 GB, free: 3.871 MB / 1.405 GB
[CodegenCpp.tpl:12296:11-12296: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:12296:11-12296: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:12296:11-12296: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:12296:11-12296: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.Models.Type1.Type1_N_1_M_2 could not be translated
"
[Timeout remaining time 596]
[Calling sys.exit(0), Time elapsed: 11.037708956981078]
Failed to read output from testmodel.py, exit status != 0:
4.037548612046521 4.048810696 3.412846847
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4a6848af50>