Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.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.001369/0.001369, allocations: 116.3 kB / 20.92 MB, free: 0.9297 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.00154/0.00154, allocations: 192 kB / 24.16 MB, free: 4.285 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.522/1.522, allocations: 207.6 MB / 234.8 MB, free: 11 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.06704/0.06704, allocations: 14.4 MB / 301.4 MB, free: 7.77 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.835/3.835, allocations: 468.9 MB / 0.8069 GB, free: 59.28 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_N_3_M_4,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_3_M_4")
translateModel(ScalableTestGrids.Models.Type1.Type1_N_3_M_4,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_N_3_M_4") [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.002834/0.002834, allocations: 88.66 kB / 1.183 GB, free: 10.98 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4352/0.438, allocations: 182.5 MB / 1.361 GB, free: 12.7 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_3_M_4): time 2.341/2.779, allocations: 0.7917 GB / 2.152 GB, free: 15.32 MB / 1.671 GB
Notification: Performance of NFInst.instExpressions: time 2.009/4.789, allocations: 279.4 MB / 2.425 GB, free: 12.08 MB / 1.733 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.2493/5.038, allocations: 2.771 MB / 2.428 GB, free: 12.08 MB / 1.733 GB
Notification: Performance of NFTyping.typeComponents: time 0.1888/5.227, allocations: 42.18 MB / 2.469 GB, free: 8.77 MB / 1.749 GB
Notification: Performance of NFTyping.typeBindings: time 0.5051/5.732, allocations: 115.6 MB / 2.582 GB, free: 5.18 MB / 1.827 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1431/5.875, allocations: 30.2 MB / 2.612 GB, free: 13.16 MB / 1.858 GB
Notification: Performance of NFFlatten.flatten: time 0.55/6.425, allocations: 271 MB / 2.876 GB, free: 3.539 MB / 2.093 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1094/6.534, allocations: 16.51 MB / 2.892 GB, free: 3.238 MB / 2.108 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.2277/6.762, allocations: 90.83 MB / 2.981 GB, free: 9.406 MB / 2.202 GB
Notification: Performance of NFSimplifyModel.simplify: time 1.789/8.551, allocations: 96.68 MB / 3.075 GB, free: 125.8 MB / 2.202 GB
Notification: Performance of NFPackage.collectConstants: time 0.07923/8.631, allocations: 12.16 MB / 3.087 GB, free: 125.8 MB / 2.202 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1752/8.806, allocations: 22.7 MB / 3.109 GB, free: 125.8 MB / 2.202 GB
Notification: Performance of NFScalarize.scalarize: time 0.06677/8.873, allocations: 27.76 MB / 3.137 GB, free: 125.4 MB / 2.202 GB
Notification: Performance of NFVerifyModel.verify: time 0.232/9.105, allocations: 84.44 MB / 3.219 GB, free: 104.2 MB / 2.202 GB
Notification: Performance of NFConvertDAE.convert: time 0.4424/9.547, allocations: 206.1 MB / 3.42 GB, free: 50.52 MB / 2.202 GB
Notification: Performance of FrontEnd - DAE generated: time 9.437e-06/9.547, allocations: 0 / 3.42 GB, free: 50.52 MB / 2.202 GB
Notification: Performance of FrontEnd: time 2.955e-06/9.547, allocations: 3.938 kB / 3.42 GB, free: 50.52 MB / 2.202 GB
Notification: Performance of Transformations before backend: time 0.01017/9.557, allocations: 0 / 3.42 GB, free: 50.52 MB / 2.202 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 28284
 * Number of variables: 28284
Notification: Performance of Generate backend data structure: time 0.9048/10.46, allocations: 209.1 MB / 3.625 GB, free: 8.16 MB / 2.296 GB
Notification: Performance of prepare preOptimizeDAE: time 6.208e-05/10.46, allocations: 15.97 kB / 3.625 GB, free: 8.145 MB / 2.296 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1392/10.6, allocations: 21.89 MB / 3.646 GB, free: 2.145 MB / 2.312 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.3199/10.92, allocations: 86.64 MB / 3.731 GB, free: 15.48 MB / 2.405 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.01767/10.94, allocations: 8.27 MB / 3.739 GB, free: 7.203 MB / 2.405 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.06213/11, allocations: 10.92 MB / 3.749 GB, free: 12.28 MB / 2.421 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 1.691/12.69, allocations: 114.6 MB / 3.861 GB, free: 0.9421 GB / 2.468 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.01051/12.7, allocations: 229.9 kB / 3.861 GB, free: 0.942 GB / 2.468 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0438/12.75, allocations: 4.468 MB / 3.866 GB, free: 0.942 GB / 2.468 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.007555/12.75, allocations: 2.68 MB / 3.868 GB, free: 0.942 GB / 2.468 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3464/13.1, allocations: 108.7 MB / 3.975 GB, free: 0.9262 GB / 2.468 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.419/14.52, allocations: 0.7423 GB / 4.717 GB, free: 308.7 MB / 2.468 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.5616/15.08, allocations: 246.6 MB / 4.958 GB, free: 125 MB / 2.468 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.142/15.22, allocations: 45.46 MB / 5.002 GB, free: 79.46 MB / 2.468 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01595/15.24, allocations: 1.322 MB / 5.003 GB, free: 78.19 MB / 2.468 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002542/15.24, allocations: 1.226 MB / 5.005 GB, free: 76.95 MB / 2.468 GB
Notification: Performance of pre-optimization done (n=11801): time 0.0005571/15.24, allocations: 0 / 5.005 GB, free: 76.95 MB / 2.468 GB
Notification: Performance of matching and sorting (n=11801): time 1.899/17.14, allocations: 152.3 MB / 5.153 GB, free: 0.9241 GB / 2.468 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.00138/17.14, allocations: 3.054 MB / 5.156 GB, free: 0.9217 GB / 2.468 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.2357/17.38, allocations: 76.14 MB / 5.231 GB, free: 0.9181 GB / 2.468 GB
Notification: Performance of collectPreVariables (initialization): time 0.02867/17.41, allocations: 2.022 MB / 5.233 GB, free: 0.918 GB / 2.468 GB
Notification: Performance of collectInitialEqns (initialization): time 0.06966/17.48, allocations: 55.44 MB / 5.287 GB, free: 0.8911 GB / 2.468 GB
Notification: Performance of collectInitialBindings (initialization): time 0.08262/17.56, allocations: 43.05 MB / 5.329 GB, free: 0.8577 GB / 2.468 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.1337/17.69, allocations: 21.4 MB / 5.35 GB, free: 0.8474 GB / 2.468 GB
Notification: Performance of setup shared object (initialization): time 0.0001078/17.69, allocations: 302.1 kB / 5.35 GB, free: 0.8471 GB / 2.468 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.1118/17.8, allocations: 39.66 MB / 5.389 GB, free: 0.8313 GB / 2.468 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.1555/17.96, allocations: 64.43 MB / 5.452 GB, free: 0.7893 GB / 2.468 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4636/18.42, allocations: 107.1 MB / 5.556 GB, free: 0.7233 GB / 2.468 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.002571/18.43, allocations: 175.5 kB / 5.556 GB, free: 0.7233 GB / 2.468 GB
Notification: Performance of matching and sorting (n=16975) (initialization): time 0.6183/19.04, allocations: 168.2 MB / 5.721 GB, free: 0.5995 GB / 2.468 GB
Notification: Performance of prepare postOptimizeDAE: time 0.002546/19.05, allocations: 264 kB / 5.721 GB, free: 0.5993 GB / 2.468 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.002788/19.05, allocations: 0.5937 MB / 5.722 GB, free: 0.5987 GB / 2.468 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.347/19.4, allocations: 74.75 MB / 5.795 GB, free: 0.546 GB / 2.468 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.101/19.5, allocations: 23.77 MB / 5.818 GB, free: 0.5257 GB / 2.468 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.833/22.33, allocations: 0.6171 GB / 6.435 GB, free: 0.607 GB / 2.468 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1732/22.5, allocations: 10.21 MB / 6.445 GB, free: 0.6024 GB / 2.468 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.03424/22.54, allocations: 4.564 MB / 6.449 GB, free: 0.6014 GB / 2.468 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1824/22.72, allocations: 51.64 MB / 6.5 GB, free: 0.5777 GB / 2.468 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.1318/22.85, allocations: 62.29 MB / 6.561 GB, free: 0.5306 GB / 2.468 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.4053/23.26, allocations: 104.2 MB / 6.662 GB, free: 432.4 MB / 2.468 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.001003/23.26, allocations: 176 kB / 6.662 GB, free: 432.2 MB / 2.468 GB
Notification: Performance of matching and sorting (n=16975) (initialization_lambda0): time 2.166/25.42, allocations: 153.2 MB / 6.812 GB, free: 0.7808 GB / 2.468 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0008866/25.43, allocations: 263.3 kB / 6.812 GB, free: 0.7808 GB / 2.468 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.00159/25.43, allocations: 0.5909 MB / 6.813 GB, free: 0.7808 GB / 2.468 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.3295/25.76, allocations: 65.06 MB / 6.876 GB, free: 0.7794 GB / 2.468 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.111/25.87, allocations: 25.08 MB / 6.901 GB, free: 0.7777 GB / 2.468 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.235/27.1, allocations: 0.5243 GB / 7.425 GB, free: 419.8 MB / 2.468 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1793/27.28, allocations: 8.034 MB / 7.433 GB, free: 413.4 MB / 2.468 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.02972/27.31, allocations: 4.535 MB / 7.437 GB, free: 408.9 MB / 2.468 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 2755
 * Number of states: 0 ()
 * Number of discrete variables: 90 (GEN_6_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_6_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_6_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_6_3.AVR.firstOrderLim.I.local_reset,GEN_6_3.TGOV.firstOrderLim.I.local_reset,GEN_6_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_6_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_6_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_6_2.AVR.firstOrderLim.I.local_reset,GEN_6_2.TGOV.firstOrderLim.I.local_reset,GEN_6_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_6_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_6_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_6_1.AVR.firstOrderLim.I.local_reset,GEN_6_1.TGOV.firstOrderLim.I.local_reset,GEN_5_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_5_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_5_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_5_3.AVR.firstOrderLim.I.local_reset,GEN_5_3.TGOV.firstOrderLim.I.local_reset,GEN_5_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_5_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_5_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_5_2.AVR.firstOrderLim.I.local_reset,GEN_5_2.TGOV.firstOrderLim.I.local_reset,GEN_5_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_5_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_5_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_5_1.AVR.firstOrderLim.I.local_reset,GEN_5_1.TGOV.firstOrderLim.I.local_reset,GEN_4_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_4_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_4_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_4_3.AVR.firstOrderLim.I.local_reset,GEN_4_3.TGOV.firstOrderLim.I.local_reset,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_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_3_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_3_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_3_3.AVR.firstOrderLim.I.local_reset,GEN_3_3.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_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_2_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_2_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_2_3.AVR.firstOrderLim.I.local_reset,GEN_2_3.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_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_1_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_1_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_1_3.AVR.firstOrderLim.I.local_reset,GEN_1_3.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 (12993):
 * Single equations (assignments): 12829
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 164
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 72 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%), (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%), (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): 92 systems
   {(38,0), (3604,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), (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), (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.07215/27.38, allocations: 7.201 MB / 7.444 GB, free: 402 MB / 2.468 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.06673/27.45, allocations: 11.76 MB / 7.456 GB, free: 390.2 MB / 2.468 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 1.988/29.44, allocations: 126.5 MB / 7.579 GB, free: 0.7461 GB / 2.468 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.00402/29.44, allocations: 0.547 MB / 7.58 GB, free: 0.7461 GB / 2.468 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003186/29.44, allocations: 0 / 7.58 GB, free: 0.7461 GB / 2.468 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.09737/29.54, allocations: 22.97 MB / 7.602 GB, free: 0.746 GB / 2.468 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.05485/29.6, allocations: 1.92 MB / 7.604 GB, free: 0.746 GB / 2.468 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.1311/29.73, allocations: 3.207 MB / 7.607 GB, free: 0.746 GB / 2.468 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.04732/29.77, allocations: 9.419 MB / 7.617 GB, free: 0.7459 GB / 2.468 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.4539/30.23, allocations: 118.7 MB / 7.732 GB, free: 0.7412 GB / 2.468 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 2.082/32.31, allocations: 0.556 GB / 8.288 GB, free: 357.1 MB / 2.468 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 1.726/34.04, allocations: 35.09 MB / 8.323 GB, free: 0.7018 GB / 2.468 GB
Notification: Performance of sorting global known variables: time 0.1962/34.23, allocations: 49.43 MB / 8.371 GB, free: 0.6993 GB / 2.468 GB
Notification: Performance of Backend: time 7.82e-07/34.23, allocations: 0 / 8.371 GB, free: 0.6993 GB / 2.468 GB
Notification: Performance of simCode: created initialization part: time 0.8992/35.13, allocations: 292.9 MB / 8.657 GB, free: 0.6338 GB / 2.468 GB
Notification: Performance of SimCode: time 1.103/36.23, allocations: 425 MB / 9.072 GB, free: 347.7 MB / 2.468 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_1_2.TGOV.firstOrderLim.G.y >= 0.0 and GEN_1_2.TGOV.firstOrderLim.I.y > GEN_1_2.TGOV.firstOrderLim.lim.uMax or GEN_1_2.TGOV.firstOrderLim.G.y <= 0.0 and GEN_1_2.TGOV.firstOrderLim.I.y < GEN_1_2.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 3: GEN_1_2.AVR.firstOrderLim.G.y >= 0.0 and GEN_1_2.AVR.firstOrderLim.I.y > GEN_1_2.AVR.firstOrderLim.lim.uMax or GEN_1_2.AVR.firstOrderLim.G.y <= 0.0 and GEN_1_2.AVR.firstOrderLim.I.y < GEN_1_2.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 4: GEN_1_3.TGOV.firstOrderLim.G.y >= 0.0 and GEN_1_3.TGOV.firstOrderLim.I.y > GEN_1_3.TGOV.firstOrderLim.lim.uMax or GEN_1_3.TGOV.firstOrderLim.G.y <= 0.0 and GEN_1_3.TGOV.firstOrderLim.I.y < GEN_1_3.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 5: GEN_1_3.AVR.firstOrderLim.G.y >= 0.0 and GEN_1_3.AVR.firstOrderLim.I.y > GEN_1_3.AVR.firstOrderLim.lim.uMax or GEN_1_3.AVR.firstOrderLim.G.y <= 0.0 and GEN_1_3.AVR.firstOrderLim.I.y < GEN_1_3.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 6: 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 7: 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.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 8: GEN_2_2.TGOV.firstOrderLim.G.y >= 0.0 and GEN_2_2.TGOV.firstOrderLim.I.y > GEN_2_2.TGOV.firstOrderLim.lim.uMax or GEN_2_2.TGOV.firstOrderLim.G.y <= 0.0 and GEN_2_2.TGOV.firstOrderLim.I.y < GEN_2_2.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 9: GEN_2_2.AVR.firstOrderLim.G.y >= 0.0 and GEN_2_2.AVR.firstOrderLim.I.y > GEN_2_2.AVR.firstOrderLim.lim.uMax or GEN_2_2.AVR.firstOrderLim.G.y <= 0.0 and GEN_2_2.AVR.firstOrderLim.I.y < GEN_2_2.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 10: GEN_2_3.TGOV.firstOrderLim.G.y >= 0.0 and GEN_2_3.TGOV.firstOrderLim.I.y > GEN_2_3.TGOV.firstOrderLim.lim.uMax or GEN_2_3.TGOV.firstOrderLim.G.y <= 0.0 and GEN_2_3.TGOV.firstOrderLim.I.y < GEN_2_3.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 11: GEN_2_3.AVR.firstOrderLim.G.y >= 0.0 and GEN_2_3.AVR.firstOrderLim.I.y > GEN_2_3.AVR.firstOrderLim.lim.uMax or GEN_2_3.AVR.firstOrderLim.G.y <= 0.0 and GEN_2_3.AVR.firstOrderLim.I.y < GEN_2_3.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 12: GEN_3_1.TGOV.firstOrderLim.G.y >= 0.0 and GEN_3_1.TGOV.firstOrderLim.I.y > GEN_3_1.TGOV.firstOrderLim.lim.uMax or GEN_3_1.TGOV.firstOrderLim.G.y <= 0.0 and GEN_3_1.TGOV.firstOrderLim.I.y < GEN_3_1.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 13: GEN_3_1.AVR.firstOrderLim.G.y >= 0.0 and GEN_3_1.AVR.firstOrderLim.I.y > GEN_3_1.AVR.firstOrderLim.lim.uMax or GEN_3_1.AVR.firstOrderLim.G.y <= 0.0 and GEN_3_1.AVR.firstOrderLim.I.y < GEN_3_1.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 14: GEN_3_2.TGOV.firstOrderLim.G.y >= 0.0 and GEN_3_2.TGOV.firstOrderLim.I.y > GEN_3_2.TGOV.firstOrderLim.lim.uMax or GEN_3_2.TGOV.firstOrderLim.G.y <= 0.0 and GEN_3_2.TGOV.firstOrderLim.I.y < GEN_3_2.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 15: GEN_3_2.AVR.firstOrderLim.G.y >= 0.0 and GEN_3_2.AVR.firstOrderLim.I.y > GEN_3_2.AVR.firstOrderLim.lim.uMax or GEN_3_2.AVR.firstOrderLim.G.y <= 0.0 and GEN_3_2.AVR.firstOrderLim.I.y < GEN_3_2.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 16: GEN_3_3.TGOV.firstOrderLim.G.y >= 0.0 and GEN_3_3.TGOV.firstOrderLim.I.y > GEN_3_3.TGOV.firstOrderLim.lim.uMax or GEN_3_3.TGOV.firstOrderLim.G.y <= 0.0 and GEN_3_3.TGOV.firstOrderLim.I.y < GEN_3_3.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 17: GEN_3_3.AVR.firstOrderLim.G.y >= 0.0 and GEN_3_3.AVR.firstOrderLim.I.y > GEN_3_3.AVR.firstOrderLim.lim.uMax or GEN_3_3.AVR.firstOrderLim.G.y <= 0.0 and GEN_3_3.AVR.firstOrderLim.I.y < GEN_3_3.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 18: GEN_4_1.TGOV.firstOrderLim.G.y >= 0.0 and GEN_4_1.TGOV.firstOrderLim.I.y > GEN_4_1.TGOV.firstOrderLim.lim.uMax or GEN_4_1.TGOV.firstOrderLim.G.y <= 0.0 and GEN_4_1.TGOV.firstOrderLim.I.y < GEN_4_1.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 19: GEN_4_1.AVR.firstOrderLim.G.y >= 0.0 and GEN_4_1.AVR.firstOrderLim.I.y > GEN_4_1.AVR.firstOrderLim.lim.uMax or GEN_4_1.AVR.firstOrderLim.G.y <= 0.0 and GEN_4_1.AVR.firstOrderLim.I.y < GEN_4_1.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 20: GEN_4_2.TGOV.firstOrderLim.G.y >= 0.0 and GEN_4_2.TGOV.firstOrderLim.I.y > GEN_4_2.TGOV.firstOrderLim.lim.uMax or GEN_4_2.TGOV.firstOrderLim.G.y <= 0.0 and GEN_4_2.TGOV.firstOrderLim.I.y < GEN_4_2.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 21: GEN_4_2.AVR.firstOrderLim.G.y >= 0.0 and GEN_4_2.AVR.firstOrderLim.I.y > GEN_4_2.AVR.firstOrderLim.lim.uMax or GEN_4_2.AVR.firstOrderLim.G.y <= 0.0 and GEN_4_2.AVR.firstOrderLim.I.y < GEN_4_2.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 22: GEN_4_3.TGOV.firstOrderLim.G.y >= 0.0 and GEN_4_3.TGOV.firstOrderLim.I.y > GEN_4_3.TGOV.firstOrderLim.lim.uMax or GEN_4_3.TGOV.firstOrderLim.G.y <= 0.0 and GEN_4_3.TGOV.firstOrderLim.I.y < GEN_4_3.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 23: GEN_4_3.AVR.firstOrderLim.G.y >= 0.0 and GEN_4_3.AVR.firstOrderLim.I.y > GEN_4_3.AVR.firstOrderLim.lim.uMax or GEN_4_3.AVR.firstOrderLim.G.y <= 0.0 and GEN_4_3.AVR.firstOrderLim.I.y < GEN_4_3.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 24: GEN_5_1.TGOV.firstOrderLim.G.y >= 0.0 and GEN_5_1.TGOV.firstOrderLim.I.y > GEN_5_1.TGOV.firstOrderLim.lim.uMax or GEN_5_1.TGOV.firstOrderLim.G.y <= 0.0 and GEN_5_1.TGOV.firstOrderLim.I.y < GEN_5_1.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 25: GEN_5_1.AVR.firstOrderLim.G.y >= 0.0 and GEN_5_1.AVR.firstOrderLim.I.y > GEN_5_1.AVR.firstOrderLim.lim.uMax or GEN_5_1.AVR.firstOrderLim.G.y <= 0.0 and GEN_5_1.AVR.firstOrderLim.I.y < GEN_5_1.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 26: GEN_5_2.TGOV.firstOrderLim.G.y >= 0.0 and GEN_5_2.TGOV.firstOrderLim.I.y > GEN_5_2.TGOV.firstOrderLim.lim.uMax or GEN_5_2.TGOV.firstOrderLim.G.y <= 0.0 and GEN_5_2.TGOV.firstOrderLim.I.y < GEN_5_2.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 27: GEN_5_2.AVR.firstOrderLim.G.y >= 0.0 and GEN_5_2.AVR.firstOrderLim.I.y > GEN_5_2.AVR.firstOrderLim.lim.uMax or GEN_5_2.AVR.firstOrderLim.G.y <= 0.0 and GEN_5_2.AVR.firstOrderLim.I.y < GEN_5_2.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 28: GEN_5_3.TGOV.firstOrderLim.G.y >= 0.0 and GEN_5_3.TGOV.firstOrderLim.I.y > GEN_5_3.TGOV.firstOrderLim.lim.uMax or GEN_5_3.TGOV.firstOrderLim.G.y <= 0.0 and GEN_5_3.TGOV.firstOrderLim.I.y < GEN_5_3.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 29: GEN_5_3.AVR.firstOrderLim.G.y >= 0.0 and GEN_5_3.AVR.firstOrderLim.I.y > GEN_5_3.AVR.firstOrderLim.lim.uMax or GEN_5_3.AVR.firstOrderLim.G.y <= 0.0 and GEN_5_3.AVR.firstOrderLim.I.y < GEN_5_3.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 30: GEN_6_1.TGOV.firstOrderLim.G.y >= 0.0 and GEN_6_1.TGOV.firstOrderLim.I.y > GEN_6_1.TGOV.firstOrderLim.lim.uMax or GEN_6_1.TGOV.firstOrderLim.G.y <= 0.0 and GEN_6_1.TGOV.firstOrderLim.I.y < GEN_6_1.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 31: GEN_6_1.AVR.firstOrderLim.G.y >= 0.0 and GEN_6_1.AVR.firstOrderLim.I.y > GEN_6_1.AVR.firstOrderLim.lim.uMax or GEN_6_1.AVR.firstOrderLim.G.y <= 0.0 and GEN_6_1.AVR.firstOrderLim.I.y < GEN_6_1.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 32: GEN_6_2.TGOV.firstOrderLim.G.y >= 0.0 and GEN_6_2.TGOV.firstOrderLim.I.y > GEN_6_2.TGOV.firstOrderLim.lim.uMax or GEN_6_2.TGOV.firstOrderLim.G.y <= 0.0 and GEN_6_2.TGOV.firstOrderLim.I.y < GEN_6_2.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 33: GEN_6_2.AVR.firstOrderLim.G.y >= 0.0 and GEN_6_2.AVR.firstOrderLim.I.y > GEN_6_2.AVR.firstOrderLim.lim.uMax or GEN_6_2.AVR.firstOrderLim.G.y <= 0.0 and GEN_6_2.AVR.firstOrderLim.I.y < GEN_6_2.AVR.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 34: GEN_6_3.TGOV.firstOrderLim.G.y >= 0.0 and GEN_6_3.TGOV.firstOrderLim.I.y > GEN_6_3.TGOV.firstOrderLim.lim.uMax or GEN_6_3.TGOV.firstOrderLim.G.y <= 0.0 and GEN_6_3.TGOV.firstOrderLim.I.y < GEN_6_3.TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12297:11-12297:11:writable] Error: Template error: Unsupported zero crossing at 35: GEN_6_3.AVR.firstOrderLim.G.y >= 0.0 and GEN_6_3.AVR.firstOrderLim.I.y > GEN_6_3.AVR.firstOrderLim.lim.uMax or GEN_6_3.AVR.firstOrderLim.G.y <= 0.0 and GEN_6_3.AVR.firstOrderLim.I.y < GEN_6_3.AVR.firstOrderLim.lim.uMin.
Error: Internal error SimCode DAEmode: The model ScalableTestGrids.Models.Type1.Type1_N_3_M_4 could not be translated
"
[Timeout remaining time 431]
[Calling sys.exit(0), Time elapsed: 176.82300114282407]
Failed to read output from testmodel.py, exit status != 0:
169.0088448410388 169.002900759 159.452752386
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f965c7a6050>