Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_reduced_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.001546/0.001546, allocations: 107.7 kB / 21.93 MB, free: 4.707 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.001616/0.001616, allocations: 220.7 kB / 25.23 MB, free: 2.879 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.588/1.588, allocations: 207.7 MB / 236.1 MB, free: 9.816 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.0701/0.0701, allocations: 14.38 MB / 302.7 MB, free: 7.262 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 4.356/4.356, allocations: 468.9 MB / 0.8082 GB, free: 58.83 MB / 0.6233 GB
"
[Timeout remaining time 175]
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_reduced_N_3_M_4,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_reduced_N_3_M_4")
translateModel(ScalableTestGrids.Models.Type1.Type1_reduced_N_3_M_4,tolerance=0.0001,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1.Type1_reduced_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: Performance of FrontEnd - loaded program: time 0.00279/0.00279, allocations: 87.28 kB / 1.184 GB, free: 10.43 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4328/0.4356, allocations: 182.6 MB / 1.363 GB, free: 11.75 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_reduced_N_3_M_4): time 2.554/2.99, allocations: 0.671 GB / 2.034 GB, free: 14.97 MB / 1.562 GB
Notification: Performance of NFInst.instExpressions: time 2.009/4.999, allocations: 265.2 MB / 2.293 GB, free: 14.93 MB / 1.733 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.2449/5.244, allocations: 3.512 MB / 2.296 GB, free: 14.93 MB / 1.733 GB
Notification: Performance of NFTyping.typeComponents: time 0.1907/5.434, allocations: 40.26 MB / 2.335 GB, free: 14.15 MB / 1.733 GB
Notification: Performance of NFTyping.typeBindings: time 0.5081/5.943, allocations: 116.2 MB / 2.449 GB, free: 7.652 MB / 1.749 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1451/6.088, allocations: 30.17 MB / 2.478 GB, free: 15.77 MB / 1.78 GB
Notification: Performance of NFFlatten.flatten: time 0.5443/6.632, allocations: 270.9 MB / 2.743 GB, free: 12.54 MB / 1.983 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.09235/6.724, allocations: 16.52 MB / 2.759 GB, free: 12.21 MB / 1.999 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.2618/6.986, allocations: 92.56 MB / 2.849 GB, free: 0.6406 MB / 2.077 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.235/7.221, allocations: 96.72 MB / 2.944 GB, free: 2.504 MB / 2.171 GB
Notification: Performance of NFPackage.collectConstants: time 0.07723/7.298, allocations: 12.16 MB / 2.956 GB, free: 6.344 MB / 2.187 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1563/7.455, allocations: 22.69 MB / 2.978 GB, free: 15.66 MB / 2.218 GB
Notification: Performance of NFScalarize.scalarize: time 0.08011/7.535, allocations: 27.77 MB / 3.005 GB, free: 4.66 MB / 2.233 GB
Notification: Performance of NFVerifyModel.verify: time 2.019/9.554, allocations: 69.24 MB / 3.073 GB, free: 205.8 MB / 2.249 GB
Notification: Performance of NFConvertDAE.convert: time 0.5078/10.06, allocations: 207.9 MB / 3.276 GB, free: 192.9 MB / 2.249 GB
Notification: Performance of FrontEnd - DAE generated: time 9.648e-06/10.06, allocations: 0 / 3.276 GB, free: 192.9 MB / 2.249 GB
Notification: Performance of FrontEnd: time 3.617e-06/10.06, allocations: 2.281 kB / 3.276 GB, free: 192.9 MB / 2.249 GB
Notification: Performance of Transformations before backend: time 0.01004/10.07, allocations: 6.938 kB / 3.276 GB, free: 192.9 MB / 2.249 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 28285
 * Number of variables: 28285
Notification: Performance of Generate backend data structure: time 0.9844/11.06, allocations: 208 MB / 3.479 GB, free: 104.5 MB / 2.249 GB
Notification: Performance of prepare preOptimizeDAE: time 7.32e-05/11.06, allocations: 8.406 kB / 3.479 GB, free: 104.5 MB / 2.249 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1539/11.21, allocations: 21.89 MB / 3.5 GB, free: 94.38 MB / 2.249 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.316/11.53, allocations: 84.9 MB / 3.583 GB, free: 44.79 MB / 2.249 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.01272/11.54, allocations: 8.275 MB / 3.591 GB, free: 40.09 MB / 2.249 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.05682/11.6, allocations: 10.91 MB / 3.602 GB, free: 32.8 MB / 2.249 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.4495/12.05, allocations: 116.9 MB / 3.716 GB, free: 9.953 MB / 2.327 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.008047/12.05, allocations: 233.4 kB / 3.716 GB, free: 9.859 MB / 2.327 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.04536/12.1, allocations: 4.47 MB / 3.721 GB, free: 5.391 MB / 2.327 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002964/12.1, allocations: 2.68 MB / 3.723 GB, free: 2.711 MB / 2.327 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3432/12.44, allocations: 107.2 MB / 3.828 GB, free: 8.672 MB / 2.437 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 3.177/15.62, allocations: 451.2 MB / 4.268 GB, free: 333.5 MB / 2.468 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.3067/15.93, allocations: 233.1 MB / 4.496 GB, free: 196.1 MB / 2.468 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.1239/16.05, allocations: 45.36 MB / 4.54 GB, free: 150.7 MB / 2.468 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01369/16.07, allocations: 1.273 MB / 4.542 GB, free: 149.4 MB / 2.468 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002053/16.07, allocations: 1.222 MB / 4.543 GB, free: 148.2 MB / 2.468 GB
Notification: Performance of pre-optimization done (n=11801): time 0.0001717/16.07, allocations: 0 / 4.543 GB, free: 148.2 MB / 2.468 GB
Notification: Performance of matching and sorting (n=11801): time 0.5327/16.6, allocations: 152.2 MB / 4.691 GB, free: 0.9219 MB / 2.468 GB
Notification: Performance of evaluateOutputsOnly (n=11801 -> n=4613): time 4.635/21.24, allocations: 0.8811 GB / 5.573 GB, free: 0.5969 GB / 2.593 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.001342/21.24, allocations: 2.353 MB / 5.575 GB, free: 0.5942 GB / 2.593 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.3614/21.6, allocations: 105.6 MB / 5.678 GB, free: 0.5112 GB / 2.593 GB
Notification: Performance of collectPreVariables (initialization): time 0.02958/21.63, allocations: 2.017 MB / 5.68 GB, free: 0.5092 GB / 2.593 GB
Notification: Performance of collectInitialEqns (initialization): time 0.09508/21.72, allocations: 44.05 MB / 5.723 GB, free: 483.4 MB / 2.593 GB
Notification: Performance of collectInitialBindings (initialization): time 0.04211/21.77, allocations: 17.3 MB / 5.74 GB, free: 468 MB / 2.593 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.1545/21.92, allocations: 25.82 MB / 5.765 GB, free: 444.8 MB / 2.593 GB
Notification: Performance of setup shared object (initialization): time 7.015e-05/21.92, allocations: 301.1 kB / 5.765 GB, free: 444.5 MB / 2.593 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.1237/22.04, allocations: 39.75 MB / 5.804 GB, free: 408.8 MB / 2.593 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.1616/22.21, allocations: 69.52 MB / 5.872 GB, free: 335 MB / 2.593 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.2904/22.5, allocations: 89.54 MB / 5.96 GB, free: 239.5 MB / 2.593 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.001595/22.5, allocations: 172 kB / 5.96 GB, free: 239.3 MB / 2.593 GB
Notification: Performance of matching and sorting (n=16861) (initialization): time 2.175/24.67, allocations: 168.2 MB / 6.124 GB, free: 1.019 GB / 2.593 GB
Notification: Performance of prepare postOptimizeDAE: time 0.00288/24.68, allocations: 263.7 kB / 6.124 GB, free: 1.019 GB / 2.593 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.003806/24.68, allocations: 0.592 MB / 6.125 GB, free: 1.019 GB / 2.593 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.4314/25.11, allocations: 74.73 MB / 6.198 GB, free: 1.018 GB / 2.593 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1172/25.23, allocations: 23.59 MB / 6.221 GB, free: 1.016 GB / 2.593 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.565/26.79, allocations: 0.5855 GB / 6.806 GB, free: 0.6146 GB / 2.593 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.2176/27.01, allocations: 11.64 MB / 6.818 GB, free: 0.6057 GB / 2.593 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.03384/27.04, allocations: 4.535 MB / 6.822 GB, free: 0.6013 GB / 2.593 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.173/27.22, allocations: 48.09 MB / 6.869 GB, free: 0.5543 GB / 2.593 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.1419/27.36, allocations: 67.37 MB / 6.935 GB, free: 490.2 MB / 2.593 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.25/27.61, allocations: 86.63 MB / 7.019 GB, free: 395 MB / 2.593 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.001438/27.61, allocations: 173.8 kB / 7.02 GB, free: 394.8 MB / 2.593 GB
Notification: Performance of matching and sorting (n=16861) (initialization_lambda0): time 0.5438/28.15, allocations: 153.1 MB / 7.169 GB, free: 241.3 MB / 2.593 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0006065/28.16, allocations: 264.9 kB / 7.169 GB, free: 241 MB / 2.593 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001066/28.16, allocations: 0.5898 MB / 7.17 GB, free: 240.4 MB / 2.593 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.3356/28.49, allocations: 65.07 MB / 7.233 GB, free: 174.9 MB / 2.593 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1171/28.61, allocations: 24.91 MB / 7.258 GB, free: 150 MB / 2.593 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.088/31.7, allocations: 0.4965 GB / 7.754 GB, free: 0.7766 GB / 2.593 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.2329/31.93, allocations: 7.971 MB / 7.762 GB, free: 0.7723 GB / 2.593 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.04092/31.97, allocations: 4.513 MB / 7.766 GB, free: 0.7713 GB / 2.593 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 (12879):
 * Single equations (assignments): 12715
 * 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.06657/32.04, allocations: 6.069 MB / 7.772 GB, free: 0.768 GB / 2.593 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.06707/32.1, allocations: 10.79 MB / 7.783 GB, free: 0.762 GB / 2.593 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.2999/32.4, allocations: 76.18 MB / 7.857 GB, free: 0.7325 GB / 2.593 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002205/32.41, allocations: 219.8 kB / 7.858 GB, free: 0.7325 GB / 2.593 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0002693/32.41, allocations: 0 / 7.858 GB, free: 0.7325 GB / 2.593 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.1041/32.51, allocations: 17.97 MB / 7.875 GB, free: 0.7202 GB / 2.593 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.07177/32.58, allocations: 0.82 MB / 7.876 GB, free: 0.7196 GB / 2.593 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.1234/32.71, allocations: 1.737 MB / 7.878 GB, free: 0.719 GB / 2.593 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.02284/32.73, allocations: 6.143 MB / 7.884 GB, free: 0.7186 GB / 2.593 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.2727/33, allocations: 72.74 MB / 7.955 GB, free: 0.6811 GB / 2.593 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 3.695/36.7, allocations: 484.4 MB / 8.428 GB, free: 0.8103 GB / 2.593 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.04871/36.75, allocations: 17.12 MB / 8.444 GB, free: 0.81 GB / 2.593 GB
Notification: Performance of sorting global known variables: time 0.199/36.94, allocations: 64.81 MB / 8.508 GB, free: 0.7919 GB / 2.593 GB
Notification: Performance of Backend: time 1.132e-06/36.94, allocations: 5.375 kB / 8.508 GB, free: 0.7919 GB / 2.593 GB
Notification: Performance of simCode: created initialization part: time 0.8505/37.8, allocations: 289.8 MB / 8.791 GB, free: 0.6338 GB / 2.593 GB
Notification: Performance of SimCode: time 2.36/40.16, allocations: 313 MB / 9.096 GB, free: 0.7543 GB / 2.593 GB
[CodegenCpp.tpl:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 1: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 2: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 3: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 4: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 5: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 6: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 7: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 8: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 9: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 10: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 11: 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:12296:11-12296: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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 13: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 14: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 15: 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 16: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 17: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 18: 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 19: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 20: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 21: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 22: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 23: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 24: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 25: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 26: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 27: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 28: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 29: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 30: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 31: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 32: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 33: 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:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 34: 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:12296:11-12296: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.
[CodegenCpp.tpl:12296:11-12296:11:writable] Error: Template error: Unsupported zero crossing at 36: 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.
Error: Internal error SimCode DAEmode: The model ScalableTestGrids.Models.Type1.Type1_reduced_N_3_M_4 could not be translated
"
[Timeout remaining time 464]
[Calling sys.exit(0), Time elapsed: 144.79262958298204]
Failed to read output from testmodel.py, exit status != 0:
136.21659157099202 136.224256344 126.159077817
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f7281172f50>