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.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.001599/0.001599, allocations: 109 kB / 21.82 MB, free: 112 kB / 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.00164/0.00164, allocations: 209.6 kB / 25.1 MB, free: 3.605 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.636/1.636, allocations: 207.6 MB / 235.8 MB, free: 10 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.06405/0.06405, allocations: 14.39 MB / 302.5 MB, free: 7.555 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.466/3.466, allocations: 468.9 MB / 0.8079 GB, free: 59.02 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.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_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.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: 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.002957/0.002957, allocations: 99.98 kB / 1.184 GB, free: 10.75 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.372/0.375, allocations: 182.6 MB / 1.362 GB, free: 12.23 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_3_M_4): time 2.18/2.555, allocations: 0.7821 GB / 2.144 GB, free: 8.371 MB / 1.655 GB
Notification: Performance of NFInst.instExpressions: time 1.613/4.168, allocations: 278.3 MB / 2.416 GB, free: 14.11 MB / 1.733 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.2013/4.369, allocations: 3.514 MB / 2.419 GB, free: 14.11 MB / 1.733 GB
Notification: Performance of NFTyping.typeComponents: time 0.1727/4.542, allocations: 42.18 MB / 2.461 GB, free: 12.52 MB / 1.749 GB
Notification: Performance of NFTyping.typeBindings: time 0.4808/5.022, allocations: 116.2 MB / 2.574 GB, free: 8.938 MB / 1.827 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1148/5.137, allocations: 30.2 MB / 2.604 GB, free: 1.055 MB / 1.843 GB
Notification: Performance of NFFlatten.flatten: time 0.4864/5.623, allocations: 271.1 MB / 2.868 GB, free: 9.062 MB / 2.093 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1015/5.725, allocations: 16.52 MB / 2.884 GB, free: 8.746 MB / 2.108 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.2265/5.951, allocations: 92.36 MB / 2.975 GB, free: 13.38 MB / 2.202 GB
Notification: Performance of NFSimplifyModel.simplify: time 1.448/7.4, allocations: 96.72 MB / 3.069 GB, free: 127.3 MB / 2.202 GB
Notification: Performance of NFPackage.collectConstants: time 0.07135/7.471, allocations: 12.16 MB / 3.081 GB, free: 127.3 MB / 2.202 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1508/7.622, allocations: 22.69 MB / 3.103 GB, free: 127.3 MB / 2.202 GB
Notification: Performance of NFScalarize.scalarize: time 0.06733/7.689, allocations: 27.77 MB / 3.13 GB, free: 127.3 MB / 2.202 GB
Notification: Performance of NFVerifyModel.verify: time 0.2225/7.912, allocations: 84.45 MB / 3.213 GB, free: 108.1 MB / 2.202 GB
Notification: Performance of NFConvertDAE.convert: time 0.4002/8.312, allocations: 207.9 MB / 3.416 GB, free: 55.18 MB / 2.202 GB
Notification: Performance of FrontEnd - DAE generated: time 7.574e-06/8.312, allocations: 2.188 kB / 3.416 GB, free: 55.18 MB / 2.202 GB
Notification: Performance of FrontEnd: time 2.275e-06/8.312, allocations: 0 / 3.416 GB, free: 55.18 MB / 2.202 GB
Notification: Performance of Transformations before backend: time 0.008809/8.321, allocations: 0 / 3.416 GB, free: 55.18 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.9323/9.253, allocations: 208 MB / 3.619 GB, free: 11.32 MB / 2.296 GB
Notification: Performance of prepare preOptimizeDAE: time 6.802e-05/9.253, allocations: 8.031 kB / 3.619 GB, free: 11.31 MB / 2.296 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1362/9.389, allocations: 21.89 MB / 3.64 GB, free: 5.316 MB / 2.312 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.3118/9.701, allocations: 84.91 MB / 3.723 GB, free: 4.316 MB / 2.39 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.01414/9.715, allocations: 8.273 MB / 3.731 GB, free: 12.04 MB / 2.405 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.04799/9.763, allocations: 10.91 MB / 3.742 GB, free: 1.133 MB / 2.405 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 1.577/11.34, allocations: 116.8 MB / 3.856 GB, free: 1.013 GB / 2.468 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.009251/11.35, allocations: 232 kB / 3.856 GB, free: 1.013 GB / 2.468 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.03833/11.39, allocations: 4.468 MB / 3.86 GB, free: 1.013 GB / 2.468 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.005351/11.39, allocations: 2.68 MB / 3.863 GB, free: 1.013 GB / 2.468 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3379/11.73, allocations: 108.7 MB / 3.969 GB, free: 0.9958 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.453/13.18, allocations: 0.736 GB / 4.705 GB, free: 336.2 MB / 2.468 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.5895/13.77, allocations: 233.6 MB / 4.933 GB, free: 148.9 MB / 2.468 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.1509/13.92, allocations: 45.38 MB / 4.978 GB, free: 103.4 MB / 2.468 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01611/13.94, allocations: 1.325 MB / 4.979 GB, free: 102.1 MB / 2.468 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002737/13.94, allocations: 1.223 MB / 4.98 GB, free: 100.9 MB / 2.468 GB
Notification: Performance of pre-optimization done (n=11801): time 0.001254/13.94, allocations: 0 / 4.98 GB, free: 100.9 MB / 2.468 GB
Notification: Performance of matching and sorting (n=11801): time 1.858/15.8, allocations: 152.3 MB / 5.129 GB, free: 0.9739 GB / 2.468 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.00232/15.81, allocations: 3.056 MB / 5.132 GB, free: 0.9715 GB / 2.468 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.2292/16.03, allocations: 76.14 MB / 5.206 GB, free: 0.9678 GB / 2.468 GB
Notification: Performance of collectPreVariables (initialization): time 0.02474/16.06, allocations: 2.028 MB / 5.208 GB, free: 0.9678 GB / 2.468 GB
Notification: Performance of collectInitialEqns (initialization): time 0.06756/16.13, allocations: 55.44 MB / 5.262 GB, free: 0.9477 GB / 2.468 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0774/16.2, allocations: 43.06 MB / 5.304 GB, free: 0.9142 GB / 2.468 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.1135/16.32, allocations: 21.39 MB / 5.325 GB, free: 0.9039 GB / 2.468 GB
Notification: Performance of setup shared object (initialization): time 5.626e-05/16.32, allocations: 303.2 kB / 5.326 GB, free: 0.9036 GB / 2.468 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.1127/16.43, allocations: 39.67 MB / 5.364 GB, free: 0.8879 GB / 2.468 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.1522/16.58, allocations: 69.49 MB / 5.432 GB, free: 0.8398 GB / 2.468 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4212/17, allocations: 107.1 MB / 5.537 GB, free: 0.7625 GB / 2.468 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.001185/17, allocations: 172 kB / 5.537 GB, free: 0.7623 GB / 2.468 GB
Notification: Performance of matching and sorting (n=16975) (initialization): time 0.5673/17.57, allocations: 168.2 MB / 5.701 GB, free: 0.6165 GB / 2.468 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001835/17.57, allocations: 270.3 kB / 5.702 GB, free: 0.6163 GB / 2.468 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.002278/17.58, allocations: 0.5859 MB / 5.702 GB, free: 0.6157 GB / 2.468 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.3669/17.94, allocations: 74.74 MB / 5.775 GB, free: 0.563 GB / 2.468 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1022/18.05, allocations: 23.77 MB / 5.798 GB, free: 0.5427 GB / 2.468 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.694/20.74, allocations: 0.6007 GB / 6.399 GB, free: 0.7741 GB / 2.468 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1667/20.91, allocations: 10.21 MB / 6.409 GB, free: 0.7696 GB / 2.468 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.03189/20.94, allocations: 4.561 MB / 6.413 GB, free: 0.7686 GB / 2.468 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1693/21.11, allocations: 51.64 MB / 6.464 GB, free: 0.7299 GB / 2.468 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.1261/21.23, allocations: 67.34 MB / 6.53 GB, free: 0.6599 GB / 2.468 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.3983/21.63, allocations: 104.2 MB / 6.631 GB, free: 0.5525 GB / 2.468 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.001227/21.63, allocations: 172 kB / 6.632 GB, free: 0.5523 GB / 2.468 GB
Notification: Performance of matching and sorting (n=16975) (initialization_lambda0): time 0.466/22.1, allocations: 153.2 MB / 6.781 GB, free: 427.5 MB / 2.468 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000579/22.1, allocations: 264 kB / 6.781 GB, free: 427.3 MB / 2.468 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001259/22.1, allocations: 0.5898 MB / 6.782 GB, free: 426.7 MB / 2.468 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.667/23.77, allocations: 65.07 MB / 6.846 GB, free: 0.8431 GB / 2.468 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1134/23.88, allocations: 25.08 MB / 6.87 GB, free: 0.8431 GB / 2.468 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.141/25.02, allocations: 0.5097 GB / 7.38 GB, free: 0.5361 GB / 2.468 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1653/25.19, allocations: 8.034 MB / 7.388 GB, free: 0.5297 GB / 2.468 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.02973/25.22, allocations: 4.536 MB / 7.392 GB, free: 0.5253 GB / 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.0582/25.27, allocations: 7.202 MB / 7.399 GB, free: 0.5186 GB / 2.468 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.06218/25.34, allocations: 11.76 MB / 7.41 GB, free: 0.5071 GB / 2.468 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.4111/25.75, allocations: 126.5 MB / 7.534 GB, free: 410.7 MB / 2.468 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003572/25.75, allocations: 0.5425 MB / 7.534 GB, free: 410.7 MB / 2.468 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.000263/25.75, allocations: 0 / 7.534 GB, free: 410.7 MB / 2.468 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.1003/25.85, allocations: 22.79 MB / 7.557 GB, free: 389.2 MB / 2.468 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.05403/25.91, allocations: 1.918 MB / 7.559 GB, free: 387.3 MB / 2.468 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.1275/26.03, allocations: 3.21 MB / 7.562 GB, free: 384.2 MB / 2.468 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.03326/26.07, allocations: 9.947 MB / 7.571 GB, free: 374.2 MB / 2.468 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 1.726/27.79, allocations: 118 MB / 7.687 GB, free: 0.75 GB / 2.468 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 1.942/29.73, allocations: 0.5558 GB / 8.242 GB, free: 0.4988 GB / 2.468 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.08968/29.82, allocations: 35.09 MB / 8.277 GB, free: 480.2 MB / 2.468 GB
Notification: Performance of sorting global known variables: time 0.112/29.94, allocations: 49.44 MB / 8.325 GB, free: 438.5 MB / 2.468 GB
Notification: Performance of Backend: time 1.313e-06/29.94, allocations: 0 / 8.325 GB, free: 438.5 MB / 2.468 GB
Notification: Performance of simCode: created initialization part: time 1.973/31.91, allocations: 292.9 MB / 8.611 GB, free: 0.673 GB / 2.468 GB
Notification: Performance of SimCode: time 0.9614/32.87, allocations: 424.6 MB / 9.026 GB, free: 0.5283 GB / 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 445]
[Calling sys.exit(0), Time elapsed: 162.27494668000145]
Failed to read output from testmodel.py, exit status != 0:
154.89882028498687 154.909096547 146.594120449
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f27c61a1840>