Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_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.002577/0.002577, allocations: 91.56 kB / 21.73 MB, free: 2.691 MB / 18.57 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.003222/0.003222, allocations: 162.8 kB / 25.04 MB, free: 8.441 MB / 18.57 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.339/1.339, allocations: 158.6 MB / 186.8 MB, free: 2.918 MB / 170.7 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.07241/0.07241, allocations: 10.64 MB / 249.8 MB, free: 8.449 MB / 234.7 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.837/2.837, allocations: 339.2 MB / 0.6299 GB, free: 5.812 MB / 0.5575 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_4_M_4,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4")
translateModel(ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4") [Timeout 600]
"Notification: Performance of FrontEnd - loaded program: time 1.322e-06/1.322e-06, allocations: 0 / 0.8282 GB, free: 480 kB / 0.6984 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.274e-05/2.406e-05, allocations: 2.312 kB / 0.8282 GB, free: 476 kB / 0.6984 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4): time 2.835/2.835, allocations: 1.325 GB / 2.153 GB, free: 4.121 MB / 1.465 GB
Notification: Performance of NFInst.instExpressions: time 1.968/4.803, allocations: 488.5 MB / 2.63 GB, free: 15.89 MB / 1.652 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.3714/5.174, allocations: 6.315 MB / 2.636 GB, free: 13.18 MB / 1.652 GB
Notification: Performance of NFTyping.typeComponents: time 0.32/5.494, allocations: 73.14 MB / 2.708 GB, free: 5.098 MB / 1.699 GB
Notification: Performance of NFTyping.typeBindings: time 0.7775/6.272, allocations: 213.1 MB / 2.916 GB, free: 12.3 MB / 1.871 GB
Notification: Performance of NFTyping.typeClassSections: time 0.2132/6.485, allocations: 53.48 MB / 2.968 GB, free: 10.63 MB / 1.918 GB
Notification: Performance of NFFlatten.flatten: time 2.061/8.547, allocations: 0.5138 GB / 3.482 GB, free: 10.59 MB / 2.011 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.2224/8.769, allocations: 31.44 MB / 3.513 GB, free: 7.59 MB / 2.027 GB
Notification: Performance of NFEvalConstants.evaluate: time 2.506/11.28, allocations: 255.7 MB / 3.762 GB, free: 36.02 MB / 2.105 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.3364/11.61, allocations: 141.2 MB / 3.9 GB, free: 36.02 MB / 2.105 GB
Notification: Performance of NFPackage.collectConstants: time 0.1329/11.74, allocations: 21.87 MB / 3.922 GB, free: 36.02 MB / 2.105 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.2761/12.02, allocations: 41.01 MB / 3.962 GB, free: 36.02 MB / 2.105 GB
Notification: Performance of NFScalarize.scalarize: time 0.1009/12.12, allocations: 50.82 MB / 4.011 GB, free: 36.02 MB / 2.105 GB
Notification: Performance of NFVerifyModel.verify: time 0.4123/12.53, allocations: 107.5 MB / 4.116 GB, free: 14.7 MB / 2.105 GB
Notification: Performance of NFConvertDAE.convert: time 1.009/13.54, allocations: 381.9 MB / 4.489 GB, free: 6.602 MB / 2.386 GB
Notification: Performance of FrontEnd - DAE generated: time 7.514e-06/13.54, allocations: 0 / 4.489 GB, free: 6.602 MB / 2.386 GB
Notification: Performance of FrontEnd: time 1.813e-06/13.54, allocations: 0 / 4.489 GB, free: 6.602 MB / 2.386 GB
Notification: Performance of Transformations before backend: time 0.01552/13.56, allocations: 0 / 4.489 GB, free: 6.602 MB / 2.386 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 51078
 * Number of variables: 51078
Notification: Performance of Generate backend data structure: time 2.523/16.08, allocations: 378.8 MB / 4.859 GB, free: 1.037 GB / 2.387 GB
Notification: Performance of prepare preOptimizeDAE: time 6.727e-05/16.08, allocations: 8.031 kB / 4.859 GB, free: 1.037 GB / 2.387 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.2695/16.35, allocations: 58.24 MB / 4.916 GB, free: 0.98 GB / 2.387 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.4555/16.81, allocations: 161.9 MB / 5.074 GB, free: 0.8246 GB / 2.387 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.01742/16.82, allocations: 14.93 MB / 5.089 GB, free: 0.8101 GB / 2.387 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0709/16.9, allocations: 19.77 MB / 5.108 GB, free: 0.7907 GB / 2.387 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.5575/17.45, allocations: 216.4 MB / 5.319 GB, free: 0.5668 GB / 2.387 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.01125/17.46, allocations: 420 kB / 5.32 GB, free: 0.5664 GB / 2.387 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.06083/17.52, allocations: 8.07 MB / 5.328 GB, free: 0.5585 GB / 2.387 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002876/17.53, allocations: 4.844 MB / 5.332 GB, free: 0.5538 GB / 2.387 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.4593/17.99, allocations: 176.7 MB / 5.505 GB, free: 390.2 MB / 2.387 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.246/21.23, allocations: 0.9406 GB / 6.446 GB, free: 356.2 MB / 2.403 GB
Notification: Performance of preOpt comSubExp (simulation): time 1.572/22.8, allocations: 0.8085 GB / 7.254 GB, free: 0.6012 GB / 2.403 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.2262/23.03, allocations: 83.03 MB / 7.335 GB, free: 0.599 GB / 2.403 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0284/23.06, allocations: 3.585 MB / 7.339 GB, free: 0.5989 GB / 2.403 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004546/23.06, allocations: 2.187 MB / 7.341 GB, free: 0.5987 GB / 2.403 GB
Notification: Performance of pre-optimization done (n=21307): time 0.001684/23.07, allocations: 0 / 7.341 GB, free: 0.5987 GB / 2.403 GB
Notification: Performance of matching and sorting (n=21307): time 0.9417/24.01, allocations: 276.6 MB / 7.611 GB, free: 454.3 MB / 2.403 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.002021/24.01, allocations: 5.426 MB / 7.616 GB, free: 448.1 MB / 2.403 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 1.225/25.23, allocations: 141.7 MB / 7.754 GB, free: 0.9008 GB / 2.403 GB
Notification: Performance of collectPreVariables (initialization): time 0.03506/25.27, allocations: 3.624 MB / 7.758 GB, free: 0.9008 GB / 2.403 GB
Notification: Performance of collectInitialEqns (initialization): time 0.09928/25.37, allocations: 102.9 MB / 7.858 GB, free: 0.8601 GB / 2.403 GB
Notification: Performance of collectInitialBindings (initialization): time 0.1302/25.5, allocations: 81.2 MB / 7.938 GB, free: 0.8088 GB / 2.403 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.1863/25.69, allocations: 40.04 MB / 7.977 GB, free: 0.7892 GB / 2.403 GB
Notification: Performance of setup shared object (initialization): time 7.245e-05/25.69, allocations: 482.3 kB / 7.977 GB, free: 0.7887 GB / 2.403 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.1672/25.85, allocations: 68.89 MB / 8.045 GB, free: 0.7657 GB / 2.403 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.2336/26.09, allocations: 138.9 MB / 8.18 GB, free: 0.664 GB / 2.403 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4234/26.51, allocations: 220.9 MB / 8.396 GB, free: 461.1 MB / 2.403 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.003315/26.51, allocations: 312 kB / 8.396 GB, free: 460.8 MB / 2.403 GB
Notification: Performance of matching and sorting (n=30427) (initialization): time 1.992/28.51, allocations: 309.3 MB / 8.698 GB, free: 0.7629 GB / 2.403 GB
Notification: Performance of prepare postOptimizeDAE: time 0.003625/28.51, allocations: 475.8 kB / 8.699 GB, free: 0.7629 GB / 2.403 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.003524/28.51, allocations: 1.064 MB / 8.7 GB, free: 0.7629 GB / 2.403 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.045/29.56, allocations: 166.2 MB / 8.862 GB, free: 0.7028 GB / 2.403 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1777/29.74, allocations: 42.48 MB / 8.904 GB, free: 0.6626 GB / 2.403 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.765/33.5, allocations: 1.201 GB / 10.1 GB, free: 450.9 MB / 2.403 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.2452/33.75, allocations: 17.56 MB / 10.12 GB, free: 442.8 MB / 2.403 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.04635/33.79, allocations: 8.197 MB / 10.13 GB, free: 440.9 MB / 2.403 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.2615/34.05, allocations: 90.89 MB / 10.22 GB, free: 360.5 MB / 2.403 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 1.35/35.4, allocations: 136.5 MB / 10.35 GB, free: 0.5209 GB / 2.403 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.4168/35.82, allocations: 217.1 MB / 10.56 GB, free: 467.8 MB / 2.403 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.002231/35.82, allocations: 309.3 kB / 10.56 GB, free: 467.8 MB / 2.403 GB
Notification: Performance of matching and sorting (n=30427) (initialization_lambda0): time 0.8553/36.68, allocations: 286.1 MB / 10.84 GB, free: 402.4 MB / 2.403 GB
Notification: Performance of prepare postOptimizeDAE: time 0.003224/36.68, allocations: 472 kB / 10.84 GB, free: 401.9 MB / 2.403 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.004092/36.68, allocations: 1.065 MB / 10.84 GB, free: 400.9 MB / 2.403 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.9093/37.59, allocations: 144.4 MB / 10.99 GB, free: 293.7 MB / 2.403 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.1759/37.77, allocations: 44.89 MB / 11.03 GB, free: 248.8 MB / 2.403 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.788/42.56, allocations: 1.041 GB / 12.07 GB, free: 408.3 MB / 2.403 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.2371/42.8, allocations: 14.34 MB / 12.08 GB, free: 408.3 MB / 2.403 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.04193/42.84, allocations: 8.142 MB / 12.09 GB, free: 408.3 MB / 2.403 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 4975
 * Number of states: 0 ()
 * Number of discrete variables: 160 (GEN_8_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_8_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_8_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_8_4.AVR.firstOrderLim.I.local_reset,GEN_8_4.TGOV.firstOrderLim.I.local_reset,GEN_8_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_8_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_8_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_8_3.AVR.firstOrderLim.I.local_reset,GEN_8_3.TGOV.firstOrderLim.I.local_reset,GEN_8_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_8_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_8_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_8_2.AVR.firstOrderLim.I.local_reset,GEN_8_2.TGOV.firstOrderLim.I.local_reset,GEN_8_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_8_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_8_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_8_1.AVR.firstOrderLim.I.local_reset,GEN_8_1.TGOV.firstOrderLim.I.local_reset,GEN_7_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_7_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_7_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_7_4.AVR.firstOrderLim.I.local_reset,GEN_7_4.TGOV.firstOrderLim.I.local_reset,GEN_7_3.PSS.firstWashoutVsi2.integrator.local_reset,GEN_7_3.PSS.secondWashoutVsi1.integrator.local_reset,GEN_7_3.PSS.firstWashoutVsi1.integrator.local_reset,GEN_7_3.AVR.firstOrderLim.I.local_reset,GEN_7_3.TGOV.firstOrderLim.I.local_reset,GEN_7_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_7_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_7_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_7_2.AVR.firstOrderLim.I.local_reset,GEN_7_2.TGOV.firstOrderLim.I.local_reset,GEN_7_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_7_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_7_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_7_1.AVR.firstOrderLim.I.local_reset,GEN_7_1.TGOV.firstOrderLim.I.local_reset,GEN_6_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_6_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_6_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_6_4.AVR.firstOrderLim.I.local_reset,GEN_6_4.TGOV.firstOrderLim.I.local_reset,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_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_5_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_5_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_5_4.AVR.firstOrderLim.I.local_reset,GEN_5_4.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_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_4_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_4_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_4_4.AVR.firstOrderLim.I.local_reset,GEN_4_4.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_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_3_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_3_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_3_4.AVR.firstOrderLim.I.local_reset,GEN_3_4.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_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_2_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_2_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_2_4.AVR.firstOrderLim.I.local_reset,GEN_2_4.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_4.PSS.firstWashoutVsi2.integrator.local_reset,GEN_1_4.PSS.secondWashoutVsi1.integrator.local_reset,GEN_1_4.PSS.firstWashoutVsi1.integrator.local_reset,GEN_1_4.AVR.firstOrderLim.I.local_reset,GEN_1_4.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 (23227):
 * Single equations (assignments): 22937
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 290
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 128 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%), (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): 162 systems
   {(38,0), (6556,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), (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.09509/42.93, allocations: 12.88 MB / 12.1 GB, free: 407.9 MB / 2.403 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.1364/43.07, allocations: 27.55 MB / 12.13 GB, free: 407.9 MB / 2.403 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.671/43.74, allocations: 225.7 MB / 12.35 GB, free: 397.5 MB / 2.403 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.006438/43.75, allocations: 0.9815 MB / 12.35 GB, free: 397.5 MB / 2.403 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0008029/43.75, allocations: 0 / 12.35 GB, free: 397.5 MB / 2.403 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.1783/43.93, allocations: 41.48 MB / 12.39 GB, free: 389.1 MB / 2.403 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.08642/44.01, allocations: 3.451 MB / 12.4 GB, free: 386.4 MB / 2.403 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.1878/44.2, allocations: 5.43 MB / 12.4 GB, free: 383.6 MB / 2.403 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.06009/44.26, allocations: 18.13 MB / 12.42 GB, free: 381.9 MB / 2.403 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.8022/45.06, allocations: 214.8 MB / 12.63 GB, free: 259.9 MB / 2.403 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 4.531/49.59, allocations: 0.9779 GB / 13.61 GB, free: 275.1 MB / 2.481 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.1474/49.74, allocations: 69.7 MB / 13.68 GB, free: 205.4 MB / 2.481 GB
Notification: Performance of sorting global known variables: time 0.1915/49.93, allocations: 92.43 MB / 13.77 GB, free: 113.9 MB / 2.481 GB
Notification: Performance of Backend: time 1.122e-06/49.93, allocations: 2.125 kB / 13.77 GB, free: 113.9 MB / 2.481 GB
Notification: Performance of simCode: created initialization part: time 2.662/52.59, allocations: 0.5671 GB / 14.33 GB, free: 323.8 MB / 2.481 GB
Notification: Performance of SimCode: time 2.991/55.59, allocations: 0.7096 GB / 15.04 GB, free: 303 MB / 2.481 GB
Notification: Performance of Templates: time 15.81/71.39, allocations: 5.253 GB / 20.3 GB, free: 489.3 MB / 2.872 GB
"
[Timeout remaining time 529]
make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4.makefile [Timeout 600]
(rm -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=240  -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.Models.Type2.Type2_noTap___N_4_M_4.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 184.5991150890477]