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.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.002106/0.002106, allocations: 87.28 kB / 21.43 MB, free: 292 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.001723/0.001723, allocations: 158.9 kB / 24.69 MB, free: 3.562 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.382/1.382, allocations: 158.6 MB / 186.5 MB, free: 6.406 MB / 174.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.06143/0.06143, allocations: 10.65 MB / 249.4 MB, free: 10.54 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 2.875/2.875, allocations: 339.2 MB / 0.6295 GB, free: 9.27 MB / 0.5607 GB
"
[Timeout remaining time 176]
Using package ScalableTestGrids with version 1.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.2.0/package.mo)
Using package PowerGrids with version 1.0.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(ScalableTestGrids.Models.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.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: 91.88 kB / 1.006 GB, free: 0.7422 MB / 0.9202 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.327/1.33, allocations: 182.5 MB / 1.184 GB, free: 11.39 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4): time 2.341/3.671, allocations: 0.6707 GB / 1.855 GB, free: 8.727 MB / 1.437 GB
Notification: Performance of NFInst.instExpressions: time 0.5801/4.251, allocations: 265.3 MB / 2.114 GB, free: 15.93 MB / 1.671 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.2109/4.462, allocations: 3.512 MB / 2.117 GB, free: 12.4 MB / 1.671 GB
Notification: Performance of NFTyping.typeComponents: time 0.1879/4.65, allocations: 40.36 MB / 2.157 GB, free: 3.754 MB / 1.702 GB
Notification: Performance of NFTyping.typeBindings: time 0.5287/5.178, allocations: 116.2 MB / 2.27 GB, free: 14.97 MB / 1.827 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1359/5.314, allocations: 30.19 MB / 2.3 GB, free: 0.75 MB / 1.843 GB
Notification: Performance of NFFlatten.flatten: time 1.621/6.936, allocations: 283.1 MB / 2.576 GB, free: 81.48 MB / 1.874 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1098/7.045, allocations: 15.72 MB / 2.591 GB, free: 94.2 MB / 1.89 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.4473/7.493, allocations: 137.8 MB / 2.726 GB, free: 37.67 MB / 1.89 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.2487/7.741, allocations: 87.75 MB / 2.812 GB, free: 1.633 MB / 1.921 GB
Notification: Performance of NFPackage.collectConstants: time 0.07959/7.821, allocations: 12.16 MB / 2.824 GB, free: 5.473 MB / 1.937 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1741/7.995, allocations: 22.73 MB / 2.846 GB, free: 15.2 MB / 1.968 GB
Notification: Performance of NFScalarize.scalarize: time 0.082/8.077, allocations: 27.77 MB / 2.873 GB, free: 5.164 MB / 1.983 GB
Notification: Performance of NFVerifyModel.verify: time 0.2709/8.348, allocations: 69.25 MB / 2.94 GB, free: 1.93 MB / 2.046 GB
Notification: Performance of NFConvertDAE.convert: time 2.232/10.58, allocations: 215 MB / 3.15 GB, free: 232.2 MB / 2.202 GB
Notification: Performance of FrontEnd - DAE generated: time 1.126e-05/10.58, allocations: 2.469 kB / 3.15 GB, free: 232.2 MB / 2.202 GB
Notification: Performance of FrontEnd: time 3.356e-06/10.58, allocations: 2.438 kB / 3.15 GB, free: 232.2 MB / 2.202 GB
Notification: Performance of Transformations before backend: time 0.01096/10.59, allocations: 0 / 3.15 GB, free: 232.2 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.9368/11.53, allocations: 208 MB / 3.354 GB, free: 176.8 MB / 2.202 GB
Notification: Performance of prepare preOptimizeDAE: time 7.795e-05/11.53, allocations: 15.56 kB / 3.354 GB, free: 176.8 MB / 2.202 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1534/11.68, allocations: 21.89 MB / 3.375 GB, free: 168.5 MB / 2.202 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.3631/12.04, allocations: 84.9 MB / 3.458 GB, free: 148.7 MB / 2.202 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.01764/12.06, allocations: 8.273 MB / 3.466 GB, free: 148.5 MB / 2.202 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.06996/12.13, allocations: 10.91 MB / 3.477 GB, free: 148.4 MB / 2.202 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.5206/12.65, allocations: 116.9 MB / 3.591 GB, free: 61.7 MB / 2.202 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.009672/12.66, allocations: 238.4 kB / 3.591 GB, free: 61.61 MB / 2.202 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.05899/12.72, allocations: 4.469 MB / 3.595 GB, free: 57.14 MB / 2.202 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003852/12.73, allocations: 2.68 MB / 3.598 GB, free: 54.46 MB / 2.202 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.4003/13.13, allocations: 107.2 MB / 3.703 GB, free: 12.39 MB / 2.265 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 2.794/15.92, allocations: 451.2 MB / 4.143 GB, free: 0.9208 GB / 2.546 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.3273/16.25, allocations: 232.2 MB / 4.37 GB, free: 0.8391 GB / 2.546 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.1408/16.39, allocations: 45.33 MB / 4.414 GB, free: 0.8207 GB / 2.546 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01614/16.4, allocations: 1.27 MB / 4.415 GB, free: 0.8206 GB / 2.546 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002264/16.41, allocations: 1.227 MB / 4.417 GB, free: 0.8197 GB / 2.546 GB
Notification: Performance of pre-optimization done (n=11801): time 0.0001826/16.41, allocations: 0 / 4.417 GB, free: 0.8197 GB / 2.546 GB
Notification: Performance of matching and sorting (n=11801): time 0.5339/16.94, allocations: 152.1 MB / 4.565 GB, free: 0.7543 GB / 2.546 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.001463/16.94, allocations: 3.057 MB / 4.568 GB, free: 0.7509 GB / 2.546 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.2215/17.16, allocations: 76.47 MB / 4.643 GB, free: 0.6906 GB / 2.546 GB
Notification: Performance of collectPreVariables (initialization): time 0.02686/17.19, allocations: 2.021 MB / 4.645 GB, free: 0.6886 GB / 2.546 GB
Notification: Performance of collectInitialEqns (initialization): time 0.06259/17.25, allocations: 54.86 MB / 4.698 GB, free: 0.6501 GB / 2.546 GB
Notification: Performance of collectInitialBindings (initialization): time 0.07492/17.33, allocations: 43.03 MB / 4.74 GB, free: 0.6117 GB / 2.546 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.1261/17.45, allocations: 21.17 MB / 4.761 GB, free: 0.5928 GB / 2.546 GB
Notification: Performance of setup shared object (initialization): time 6.722e-05/17.45, allocations: 301.1 kB / 4.761 GB, free: 0.5926 GB / 2.546 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.1161/17.57, allocations: 39.47 MB / 4.8 GB, free: 0.5579 GB / 2.546 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.167/17.74, allocations: 69.24 MB / 4.868 GB, free: 497.8 MB / 2.546 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4622/18.2, allocations: 106.8 MB / 4.972 GB, free: 385.6 MB / 2.546 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.001684/18.2, allocations: 176 kB / 4.972 GB, free: 385.4 MB / 2.546 GB
Notification: Performance of matching and sorting (n=16861) (initialization): time 0.6215/18.82, allocations: 167.6 MB / 5.136 GB, free: 219 MB / 2.546 GB
Notification: Performance of prepare postOptimizeDAE: time 0.002346/18.82, allocations: 272 kB / 5.136 GB, free: 218.7 MB / 2.546 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.00246/18.83, allocations: 0.5898 MB / 5.137 GB, free: 218.1 MB / 2.546 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.3503/19.18, allocations: 75.66 MB / 5.21 GB, free: 142.1 MB / 2.546 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0994/19.28, allocations: 23.6 MB / 5.233 GB, free: 118.4 MB / 2.546 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.872/22.15, allocations: 0.5854 GB / 5.819 GB, free: 0.7686 GB / 2.546 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1652/22.31, allocations: 10.03 MB / 5.829 GB, free: 0.7642 GB / 2.546 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.03658/22.35, allocations: 4.536 MB / 5.833 GB, free: 0.7631 GB / 2.546 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1908/22.54, allocations: 51.4 MB / 5.883 GB, free: 0.7215 GB / 2.546 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.1351/22.68, allocations: 67.07 MB / 5.949 GB, free: 0.6516 GB / 2.546 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.3875/23.06, allocations: 103.8 MB / 6.05 GB, free: 0.5436 GB / 2.546 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.001301/23.07, allocations: 172 kB / 6.05 GB, free: 0.5434 GB / 2.546 GB
Notification: Performance of matching and sorting (n=16861) (initialization_lambda0): time 0.5084/23.57, allocations: 152.4 MB / 6.199 GB, free: 418.6 MB / 2.546 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0006883/23.57, allocations: 264 kB / 6.199 GB, free: 418.4 MB / 2.546 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.00119/23.58, allocations: 0.5898 MB / 6.2 GB, free: 417.8 MB / 2.546 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.2988/23.87, allocations: 65.93 MB / 6.264 GB, free: 357.6 MB / 2.546 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1073/23.98, allocations: 24.91 MB / 6.289 GB, free: 332.6 MB / 2.546 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.794/26.78, allocations: 0.4965 GB / 6.785 GB, free: 0.7836 GB / 2.546 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.162/26.94, allocations: 7.973 MB / 6.793 GB, free: 0.7836 GB / 2.546 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.02816/26.97, allocations: 4.515 MB / 6.797 GB, free: 0.7836 GB / 2.546 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.08618/27.05, allocations: 7.167 MB / 6.804 GB, free: 0.7834 GB / 2.546 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.1003/27.15, allocations: 11.68 MB / 6.816 GB, free: 0.778 GB / 2.546 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.5616/27.71, allocations: 126.3 MB / 6.939 GB, free: 0.7332 GB / 2.546 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003958/27.72, allocations: 0.5436 MB / 6.94 GB, free: 0.7332 GB / 2.546 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003346/27.72, allocations: 0 / 6.94 GB, free: 0.7332 GB / 2.546 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.1114/27.83, allocations: 22.67 MB / 6.962 GB, free: 0.7186 GB / 2.546 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0665/27.9, allocations: 1.921 MB / 6.964 GB, free: 0.7172 GB / 2.546 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.1373/28.03, allocations: 3.21 MB / 6.967 GB, free: 0.7157 GB / 2.546 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.04394/28.08, allocations: 9.943 MB / 6.976 GB, free: 0.7148 GB / 2.546 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.4643/28.54, allocations: 117.3 MB / 7.091 GB, free: 0.6756 GB / 2.546 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 3.702/32.24, allocations: 0.5339 GB / 7.625 GB, free: 0.7878 GB / 2.546 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.1537/32.4, allocations: 35.07 MB / 7.659 GB, free: 0.7773 GB / 2.546 GB
Notification: Performance of sorting global known variables: time 0.2125/32.61, allocations: 48.74 MB / 7.707 GB, free: 0.7601 GB / 2.546 GB
Notification: Performance of Backend: time 1.683e-06/32.61, allocations: 0 / 7.707 GB, free: 0.7601 GB / 2.546 GB
Notification: Performance of simCode: created initialization part: time 0.8958/33.51, allocations: 292 MB / 7.992 GB, free: 0.5849 GB / 2.546 GB
Notification: Performance of SimCode: time 2.593/36.1, allocations: 394.9 MB / 8.378 GB, free: 0.7115 GB / 2.546 GB
Notification: Performance of Templates: time 10.15/46.24, allocations: 2.843 GB / 11.22 GB, free: 0.6286 GB / 2.546 GB
"
[Timeout remaining time 554]
make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.makefile [Timeout 600]
(rm -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=480 -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 106.21615845896304]
Failed to read output from testmodel.py, exit status != 0:
46.255791367962956 46.267797566 35.684183275
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f550176d1e0>