Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo", uses=false)
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.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/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)
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)
Running command: translateModel(ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4,tolerance=1e-05,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_AnalyticJacobian_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=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0009121/0.0009122, allocations: 189.8 kB / 17.33 MB, free: 6.156 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 0.3109/0.3109, allocations: 205.5 MB / 223.6 MB, free: 0.8867 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009093/0.0009093, allocations: 114.8 kB / 271.1 MB, free: 8.648 MB / 222.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.0944/0.0944, allocations: 14.44 MB / 332.8 MB, free: 44.9 MB / 270.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo): time 1.288/1.288, allocations: 438.4 MB / 0.8028 GB, free: 14.53 MB / 0.6233 GB
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: Performance of FrontEnd - loaded program: time 0.001153/0.001153, allocations: 75.91 kB / 0.9788 GB, free: 50.81 MB / 0.7017 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1737/0.1748, allocations: 166.1 MB / 1.141 GB, free: 12.42 MB / 0.8267 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4): time 1.598/1.772, allocations: 0.7991 GB / 1.94 GB, free: 15.34 MB / 1.327 GB
Notification: Performance of NFInst.instExpressions: time 0.3351/2.108, allocations: 280.4 MB / 2.214 GB, free: 5.883 MB / 1.452 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.1219/2.23, allocations: 2.905 MB / 2.217 GB, free: 2.965 MB / 1.452 GB
Notification: Performance of NFTyping.typeComponents: time 0.1042/2.334, allocations: 35.71 MB / 2.252 GB, free: 15.07 MB / 1.499 GB
Notification: Performance of NFTyping.typeBindings: time 0.2563/2.59, allocations: 98.02 MB / 2.347 GB, free: 12.61 MB / 1.593 GB
Notification: Performance of NFTyping.typeClassSections: time 0.6223/3.213, allocations: 27.6 MB / 2.374 GB, free: 39.96 MB / 1.593 GB
Notification: Performance of NFFlatten.flatten: time 0.2523/3.465, allocations: 283.1 MB / 2.651 GB, free: 6.082 MB / 1.593 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.05198/3.517, allocations: 28.1 MB / 2.678 GB, free: 1.18 MB / 1.608 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1281/3.645, allocations: 81.37 MB / 2.758 GB, free: 11.74 MB / 1.687 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1082/3.753, allocations: 86.62 MB / 2.842 GB, free: 4.957 MB / 1.749 GB
Notification: Performance of NFPackage.collectConstants: time 0.03313/3.786, allocations: 10.1 MB / 2.852 GB, free: 10.85 MB / 1.765 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.07615/3.863, allocations: 20.64 MB / 2.872 GB, free: 6.613 MB / 1.78 GB
Notification: Performance of NFScalarize.scalarize: time 0.03026/3.893, allocations: 24.37 MB / 2.896 GB, free: 14.95 MB / 1.812 GB
Notification: Performance of NFVerifyModel.verify: time 0.1198/4.013, allocations: 81.26 MB / 2.975 GB, free: 15.45 MB / 1.89 GB
Notification: Performance of NFConvertDAE.convert: time 0.8953/4.908, allocations: 185 MB / 3.156 GB, free: 139.2 MB / 1.905 GB
Notification: Performance of FrontEnd - DAE generated: time 6.212e-06/4.908, allocations: 3.812 kB / 3.156 GB, free: 139.2 MB / 1.905 GB
Notification: Performance of FrontEnd: time 1.473e-06/4.908, allocations: 0 / 3.156 GB, free: 139.2 MB / 1.905 GB
Notification: Performance of Transformations before backend: time 0.00539/4.913, allocations: 2.875 kB / 3.156 GB, free: 139.2 MB / 1.905 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.5664/5.48, allocations: 196.7 MB / 3.348 GB, free: 56.14 MB / 1.905 GB
Notification: Performance of prepare preOptimizeDAE: time 4.086e-05/5.48, allocations: 11.08 kB / 3.348 GB, free: 56.14 MB / 1.905 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.09107/5.571, allocations: 21.14 MB / 3.369 GB, free: 46.73 MB / 1.905 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1599/5.731, allocations: 80.12 MB / 3.447 GB, free: 1.328 MB / 1.905 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.007489/5.738, allocations: 8.273 MB / 3.455 GB, free: 11.63 MB / 1.921 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.02862/5.767, allocations: 10.92 MB / 3.466 GB, free: 0.7109 MB / 1.921 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.2252/5.992, allocations: 114.6 MB / 3.578 GB, free: 9.832 MB / 2.046 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.003766/5.996, allocations: 233.4 kB / 3.578 GB, free: 9.738 MB / 2.046 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01995/6.016, allocations: 4.473 MB / 3.582 GB, free: 5.266 MB / 2.046 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001555/6.017, allocations: 2.68 MB / 3.585 GB, free: 2.586 MB / 2.046 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1964/6.214, allocations: 108.7 MB / 3.691 GB, free: 7.055 MB / 2.155 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.482/7.696, allocations: 0.6955 GB / 4.387 GB, free: 3.273 MB / 2.265 GB
Notification: Performance of preOpt comSubExp (simulation): time 1.015/8.711, allocations: 254.5 MB / 4.635 GB, free: 1.087 GB / 2.39 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.08457/8.796, allocations: 51.58 MB / 4.685 GB, free: 1.086 GB / 2.39 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.008282/8.804, allocations: 1.323 MB / 4.687 GB, free: 1.086 GB / 2.39 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0005499/8.805, allocations: 1.225 MB / 4.688 GB, free: 1.086 GB / 2.39 GB
Notification: Performance of pre-optimization done (n=11801): time 0.0001182/8.805, allocations: 0 / 4.688 GB, free: 1.086 GB / 2.39 GB
Notification: Performance of matching and sorting (n=11819): time 0.4945/9.299, allocations: 262 MB / 4.944 GB, free: 0.9601 GB / 2.39 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0007778/9.3, allocations: 3.063 MB / 4.947 GB, free: 0.9566 GB / 2.39 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.08951/9.39, allocations: 68.8 MB / 5.014 GB, free: 0.9016 GB / 2.39 GB
Notification: Performance of collectPreVariables (initialization): time 0.01387/9.404, allocations: 2.025 MB / 5.016 GB, free: 0.8997 GB / 2.39 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03038/9.434, allocations: 54.77 MB / 5.069 GB, free: 0.8518 GB / 2.39 GB
Notification: Performance of collectInitialBindings (initialization): time 0.03706/9.471, allocations: 41.02 MB / 5.109 GB, free: 0.8152 GB / 2.39 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.04836/9.52, allocations: 21.32 MB / 5.13 GB, free: 0.7962 GB / 2.39 GB
Notification: Performance of setup shared object (initialization): time 3.416e-05/9.52, allocations: 312.1 kB / 5.131 GB, free: 0.7959 GB / 2.39 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.06251/9.582, allocations: 39.61 MB / 5.169 GB, free: 0.7612 GB / 2.39 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.07434/9.656, allocations: 64.4 MB / 5.232 GB, free: 0.6958 GB / 2.39 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.2795/9.936, allocations: 107.1 MB / 5.337 GB, free: 0.5854 GB / 2.39 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0004935/9.937, allocations: 172 kB / 5.337 GB, free: 0.5853 GB / 2.39 GB
Notification: Performance of matching and sorting (n=16993) (initialization): time 0.3237/10.26, allocations: 169.6 MB / 5.503 GB, free: 437.7 MB / 2.39 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0006106/10.26, allocations: 268 kB / 5.503 GB, free: 437.5 MB / 2.39 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0008129/10.26, allocations: 0.5898 MB / 5.503 GB, free: 436.9 MB / 2.39 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.9457/11.21, allocations: 76.3 MB / 5.578 GB, free: 1.041 GB / 2.39 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.08099/11.29, allocations: 23.83 MB / 5.601 GB, free: 1.041 GB / 2.39 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.7996/12.09, allocations: 0.615 GB / 6.216 GB, free: 0.6273 GB / 2.39 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.09603/12.18, allocations: 6.442 MB / 6.222 GB, free: 0.6217 GB / 2.39 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01613/12.2, allocations: 4.566 MB / 6.227 GB, free: 0.6172 GB / 2.39 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.09248/12.29, allocations: 51.59 MB / 6.277 GB, free: 0.5668 GB / 2.39 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.06922/12.36, allocations: 62.25 MB / 6.338 GB, free: 0.4974 GB / 2.39 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.2647/12.63, allocations: 104.2 MB / 6.44 GB, free: 396.6 MB / 2.39 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0005266/12.63, allocations: 172 kB / 6.44 GB, free: 396.4 MB / 2.39 GB
Notification: Performance of matching and sorting (n=16993) (initialization_lambda0): time 0.9835/13.61, allocations: 154.4 MB / 6.591 GB, free: 0.9142 GB / 2.39 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003994/13.61, allocations: 264.1 kB / 6.591 GB, free: 0.9142 GB / 2.39 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000749/13.61, allocations: 0.5883 MB / 6.592 GB, free: 0.9142 GB / 2.39 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.2826/13.89, allocations: 66.51 MB / 6.656 GB, free: 0.9128 GB / 2.39 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.08574/13.98, allocations: 25.15 MB / 6.681 GB, free: 0.9128 GB / 2.39 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.638/14.62, allocations: 0.523 GB / 7.204 GB, free: 0.5984 GB / 2.39 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.09665/14.72, allocations: 5.694 MB / 7.21 GB, free: 0.5934 GB / 2.39 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01616/14.73, allocations: 4.535 MB / 7.214 GB, free: 0.589 GB / 2.39 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 (13011):
 * Single equations (assignments): 12847
 * 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.02966/14.76, allocations: 7.204 MB / 7.221 GB, free: 0.5821 GB / 2.39 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02785/14.79, allocations: 11.21 MB / 7.232 GB, free: 0.571 GB / 2.39 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.2384/15.03, allocations: 128.5 MB / 7.357 GB, free: 456.1 MB / 2.39 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001691/15.03, allocations: 0.5447 MB / 7.358 GB, free: 455.5 MB / 2.39 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001411/15.03, allocations: 0 / 7.358 GB, free: 455.5 MB / 2.39 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.05142/15.08, allocations: 18.83 MB / 7.376 GB, free: 436.5 MB / 2.39 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.02723/15.11, allocations: 1.922 MB / 7.378 GB, free: 434.6 MB / 2.39 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.06576/15.17, allocations: 4.407 MB / 7.383 GB, free: 430.2 MB / 2.39 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01714/15.19, allocations: 9.43 MB / 7.392 GB, free: 420.7 MB / 2.39 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.2782/15.47, allocations: 119 MB / 7.508 GB, free: 304.2 MB / 2.39 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 1.749/17.22, allocations: 0.5409 GB / 8.049 GB, free: 0.7214 GB / 2.39 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.05975/17.28, allocations: 37.42 MB / 8.085 GB, free: 0.6856 GB / 2.39 GB
Notification: Performance of sorting global known variables: time 0.06065/17.34, allocations: 44.08 MB / 8.128 GB, free: 0.6431 GB / 2.39 GB
Notification: Performance of Backend: time 6.21e-07/17.34, allocations: 2.188 kB / 8.128 GB, free: 0.6431 GB / 2.39 GB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed.
Notification: Performance of simCode: created initialization part: time 1.225/18.56, allocations: 305.3 MB / 8.427 GB, free: 0.8055 GB / 2.39 GB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed.
Notification: Performance of SimCode: time 0.5016/19.06, allocations: 427.4 MB / 8.844 GB, free: 0.6419 GB / 2.39 GB
Notification: Performance of Templates: time 4.506/23.57, allocations: 2.441 GB / 11.28 GB, free: 264.8 MB / 2.391 GB
make -j1 -f ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.makefile
(rm -f ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe ; mkfifo ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe ; head -c 1048576 < ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe >> ../files/ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.sim & ./ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=300 -lv LOG_STATS > ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type2.Type2_noTap___N_3_M_4.pipe 2>&1)