Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___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.001359/0.001359, allocations: 109.3 kB / 21.82 MB, free: 108 kB / 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.001498/0.001498, allocations: 189.2 kB / 25.08 MB, free: 3.629 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.571/1.571, allocations: 207.6 MB / 235.8 MB, free: 10.01 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.06895/0.06895, allocations: 14.37 MB / 302.4 MB, free: 7.496 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.595/3.595, allocations: 468.9 MB / 0.8079 GB, free: 59.04 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.Type2.Type2_noTap___N_3_M_4,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4")
translateModel(ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___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.003176/0.003176, allocations: 89.61 kB / 1.184 GB, free: 10.77 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3877/0.3909, allocations: 182.6 MB / 1.362 GB, free: 12.27 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4): time 2.191/2.582, allocations: 0.7844 GB / 2.146 GB, free: 5.797 MB / 1.655 GB
Notification: Performance of NFInst.instExpressions: time 1.67/4.252, allocations: 278.5 MB / 2.418 GB, free: 11.95 MB / 1.733 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.2199/4.472, allocations: 3.51 MB / 2.422 GB, free: 11.95 MB / 1.733 GB
Notification: Performance of NFTyping.typeComponents: time 0.1801/4.652, allocations: 42.17 MB / 2.463 GB, free: 9.672 MB / 1.749 GB
Notification: Performance of NFTyping.typeBindings: time 0.473/5.125, allocations: 115.6 MB / 2.576 GB, free: 6.059 MB / 1.827 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1287/5.254, allocations: 30.25 MB / 2.606 GB, free: 13.98 MB / 1.858 GB
Notification: Performance of NFFlatten.flatten: time 0.4689/5.723, allocations: 271.1 MB / 2.87 GB, free: 6.113 MB / 2.093 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.09511/5.818, allocations: 16.52 MB / 2.886 GB, free: 5.82 MB / 2.108 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.2257/6.043, allocations: 92.38 MB / 2.977 GB, free: 10.44 MB / 2.202 GB
Notification: Performance of NFSimplifyModel.simplify: time 1.605/7.648, allocations: 96.72 MB / 3.071 GB, free: 127.6 MB / 2.218 GB
Notification: Performance of NFPackage.collectConstants: time 0.07779/7.726, allocations: 12.16 MB / 3.083 GB, free: 127.6 MB / 2.218 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1601/7.886, allocations: 22.69 MB / 3.105 GB, free: 127.6 MB / 2.218 GB
Notification: Performance of NFScalarize.scalarize: time 0.07233/7.958, allocations: 27.77 MB / 3.132 GB, free: 127.6 MB / 2.218 GB
Notification: Performance of NFVerifyModel.verify: time 0.2388/8.197, allocations: 84.45 MB / 3.215 GB, free: 112.6 MB / 2.218 GB
Notification: Performance of NFConvertDAE.convert: time 0.4379/8.635, allocations: 206.6 MB / 3.416 GB, free: 63.72 MB / 2.218 GB
Notification: Performance of FrontEnd - DAE generated: time 9.097e-06/8.635, allocations: 3.938 kB / 3.416 GB, free: 63.72 MB / 2.218 GB
Notification: Performance of FrontEnd: time 3.246e-06/8.635, allocations: 0 / 3.416 GB, free: 63.72 MB / 2.218 GB
Notification: Performance of Transformations before backend: time 0.008447/8.643, allocations: 2.531 kB / 3.416 GB, free: 63.72 MB / 2.218 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.8831/9.527, allocations: 208 MB / 3.619 GB, free: 15.33 MB / 2.296 GB
Notification: Performance of prepare preOptimizeDAE: time 6.286e-05/9.527, allocations: 8.031 kB / 3.619 GB, free: 15.32 MB / 2.296 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1536/9.68, allocations: 21.89 MB / 3.641 GB, free: 10.38 MB / 2.312 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.3105/9.991, allocations: 84.9 MB / 3.724 GB, free: 10.17 MB / 2.39 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.01444/10.01, allocations: 8.274 MB / 3.732 GB, free: 1.898 MB / 2.39 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.04858/10.05, allocations: 10.92 MB / 3.743 GB, free: 6.977 MB / 2.405 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 1.611/11.66, allocations: 114.6 MB / 3.854 GB, free: 0.9349 GB / 2.483 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.01113/11.68, allocations: 230.6 kB / 3.855 GB, free: 0.9347 GB / 2.483 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.04322/11.72, allocations: 4.467 MB / 3.859 GB, free: 0.9347 GB / 2.483 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.009774/11.73, allocations: 2.684 MB / 3.862 GB, free: 0.9347 GB / 2.483 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3414/12.07, allocations: 108.7 MB / 3.968 GB, free: 0.932 GB / 2.483 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.41/13.48, allocations: 0.7359 GB / 4.704 GB, free: 326.5 MB / 2.483 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.5611/14.04, allocations: 232.8 MB / 4.931 GB, free: 180 MB / 2.483 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.1322/14.17, allocations: 45.35 MB / 4.975 GB, free: 134.6 MB / 2.483 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01421/14.19, allocations: 1.321 MB / 4.977 GB, free: 133.3 MB / 2.483 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002761/14.19, allocations: 1.226 MB / 4.978 GB, free: 132.1 MB / 2.483 GB
Notification: Performance of pre-optimization done (n=11801): time 0.001196/14.19, allocations: 7.938 kB / 4.978 GB, free: 132 MB / 2.483 GB
Notification: Performance of matching and sorting (n=11801): time 1.75/15.94, allocations: 152.2 MB / 5.126 GB, free: 0.918 GB / 2.483 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.001759/15.94, allocations: 3.057 MB / 5.129 GB, free: 0.9156 GB / 2.483 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.2307/16.17, allocations: 76.14 MB / 5.204 GB, free: 0.9119 GB / 2.483 GB
Notification: Performance of collectPreVariables (initialization): time 0.03047/16.2, allocations: 2.025 MB / 5.206 GB, free: 0.9119 GB / 2.483 GB
Notification: Performance of collectInitialEqns (initialization): time 0.06828/16.27, allocations: 55.44 MB / 5.26 GB, free: 0.9031 GB / 2.483 GB
Notification: Performance of collectInitialBindings (initialization): time 0.07773/16.35, allocations: 43.06 MB / 5.302 GB, free: 0.8749 GB / 2.483 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.1192/16.47, allocations: 21.39 MB / 5.323 GB, free: 0.8694 GB / 2.483 GB
Notification: Performance of setup shared object (initialization): time 6.134e-05/16.47, allocations: 301.1 kB / 5.323 GB, free: 0.8692 GB / 2.483 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.1126/16.58, allocations: 39.62 MB / 5.362 GB, free: 0.8534 GB / 2.483 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.1469/16.73, allocations: 64.41 MB / 5.425 GB, free: 0.8115 GB / 2.483 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4578/17.19, allocations: 107 MB / 5.529 GB, free: 0.7455 GB / 2.483 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.001437/17.19, allocations: 177.6 kB / 5.529 GB, free: 0.7455 GB / 2.483 GB
Notification: Performance of matching and sorting (n=16975) (initialization): time 0.5483/17.74, allocations: 168.1 MB / 5.694 GB, free: 0.6645 GB / 2.483 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0007593/17.74, allocations: 271.3 kB / 5.694 GB, free: 0.6643 GB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001226/17.74, allocations: 0.5898 MB / 5.694 GB, free: 0.6637 GB / 2.483 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.3669/18.1, allocations: 74.64 MB / 5.767 GB, free: 0.6111 GB / 2.483 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1032/18.21, allocations: 23.77 MB / 5.791 GB, free: 0.5908 GB / 2.483 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.748/20.96, allocations: 0.6006 GB / 6.391 GB, free: 0.6306 GB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1732/21.13, allocations: 10.21 MB / 6.401 GB, free: 0.626 GB / 2.483 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.03177/21.16, allocations: 4.565 MB / 6.406 GB, free: 0.625 GB / 2.483 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1695/21.33, allocations: 51.6 MB / 6.456 GB, free: 0.6014 GB / 2.483 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.1307/21.46, allocations: 62.26 MB / 6.517 GB, free: 0.5462 GB / 2.483 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.3987/21.86, allocations: 104.1 MB / 6.618 GB, free: 448.5 MB / 2.483 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.001083/21.86, allocations: 172 kB / 6.619 GB, free: 448.3 MB / 2.483 GB
Notification: Performance of matching and sorting (n=16975) (initialization_lambda0): time 1.887/23.75, allocations: 153.1 MB / 6.768 GB, free: 0.8194 GB / 2.483 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001499/23.75, allocations: 267.4 kB / 6.768 GB, free: 0.8194 GB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.002069/23.75, allocations: 0.5891 MB / 6.769 GB, free: 0.8194 GB / 2.483 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.3281/24.08, allocations: 64.96 MB / 6.832 GB, free: 0.818 GB / 2.483 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1152/24.19, allocations: 25.08 MB / 6.857 GB, free: 0.8164 GB / 2.483 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.193/25.39, allocations: 0.5096 GB / 7.366 GB, free: 425.3 MB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1686/25.56, allocations: 8.036 MB / 7.374 GB, free: 418.7 MB / 2.483 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.03044/25.59, allocations: 4.535 MB / 7.379 GB, free: 414.2 MB / 2.483 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.06303/25.65, allocations: 7.197 MB / 7.386 GB, free: 407.3 MB / 2.483 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.06506/25.71, allocations: 11.77 MB / 7.397 GB, free: 395.5 MB / 2.483 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 1.742/27.46, allocations: 126.4 MB / 7.521 GB, free: 0.7728 GB / 2.483 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004615/27.46, allocations: 0.5454 MB / 7.521 GB, free: 0.7728 GB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003733/27.46, allocations: 0 / 7.521 GB, free: 0.7728 GB / 2.483 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.09791/27.56, allocations: 22.79 MB / 7.543 GB, free: 0.7726 GB / 2.483 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.05332/27.61, allocations: 1.915 MB / 7.545 GB, free: 0.7726 GB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.1243/27.74, allocations: 3.209 MB / 7.548 GB, free: 0.7726 GB / 2.483 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.03398/27.77, allocations: 9.946 MB / 7.558 GB, free: 0.7725 GB / 2.483 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.4989/28.27, allocations: 118 MB / 7.673 GB, free: 0.7615 GB / 2.483 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 1.86/30.13, allocations: 0.5538 GB / 8.227 GB, free: 346 MB / 2.483 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 1.454/31.58, allocations: 35.07 MB / 8.261 GB, free: 0.7156 GB / 2.483 GB
Notification: Performance of sorting global known variables: time 0.1473/31.73, allocations: 49.43 MB / 8.31 GB, free: 0.7131 GB / 2.483 GB
Notification: Performance of Backend: time 1.663e-06/31.73, allocations: 0 / 8.31 GB, free: 0.7131 GB / 2.483 GB
Notification: Performance of simCode: created initialization part: time 0.8476/32.58, allocations: 292.8 MB / 8.595 GB, free: 0.6467 GB / 2.483 GB
Notification: Performance of SimCode: time 0.9459/33.52, allocations: 425 MB / 9.011 GB, free: 333.6 MB / 2.483 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.Type2.Type2_noTap___N_3_M_4 could not be translated
"
[Timeout remaining time 447]
[Calling sys.exit(0), Time elapsed: 160.44718546100194]
Failed to read output from testmodel.py, exit status != 0:
152.9882524429995 152.98585495 144.347956104
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe1524917e0>