Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.conf.json
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/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/PowerGrids 1.0.3/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.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.Type1.Type1_N_3_M_4,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4")
translateModel(ScalableTestGrids.Models.Type1.Type1_N_3_M_4,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.002218/0.002218, allocations: 116.5 kB / 17.12 MB, free: 5.895 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002229/0.002229, allocations: 195.8 kB / 18.06 MB, free: 4.969 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.493/0.493, allocations: 208 MB / 226.9 MB, free: 6.621 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo): time 0.02394/0.02394, allocations: 14.75 MB / 291.5 MB, free: 9.789 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo): time 1.712/1.712, allocations: 469.1 MB / 0.7951 GB, free: 112 kB / 0.6077 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: 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.002212/0.002212, allocations: 95.86 kB / 1.167 GB, free: 18.25 MB / 0.8579 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2208/0.223, allocations: 182.5 MB / 1.345 GB, free: 11.2 MB / 1.03 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_3_M_4): time 2.04/2.263, allocations: 0.7916 GB / 2.137 GB, free: 15.96 MB / 1.687 GB
Notification: Performance of NFInst.instExpressions: time 0.3405/2.604, allocations: 279.4 MB / 2.41 GB, free: 10.68 MB / 1.733 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.1158/2.719, allocations: 2.771 MB / 2.413 GB, free: 10.57 MB / 1.733 GB
Notification: Performance of NFTyping.typeComponents: time 0.1108/2.83, allocations: 42.17 MB / 2.454 GB, free: 11.33 MB / 1.765 GB
Notification: Performance of NFTyping.typeBindings: time 0.2906/3.121, allocations: 115.6 MB / 2.567 GB, free: 7.727 MB / 1.843 GB
Notification: Performance of NFTyping.typeClassSections: time 0.07798/3.199, allocations: 30.18 MB / 2.596 GB, free: 15.73 MB / 1.874 GB
Notification: Performance of NFFlatten.flatten: time 0.3199/3.519, allocations: 271 MB / 2.861 GB, free: 9.055 MB / 2.124 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.04325/3.562, allocations: 16.51 MB / 2.877 GB, free: 8.742 MB / 2.14 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.8816/4.444, allocations: 90.81 MB / 2.966 GB, free: 69.99 MB / 2.155 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1247/4.568, allocations: 96.7 MB / 3.06 GB, free: 54.69 MB / 2.155 GB
Notification: Performance of NFPackage.collectConstants: time 0.03975/4.608, allocations: 12.16 MB / 3.072 GB, free: 54.69 MB / 2.155 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0797/4.688, allocations: 22.7 MB / 3.094 GB, free: 54.69 MB / 2.155 GB
Notification: Performance of NFScalarize.scalarize: time 0.02967/4.718, allocations: 27.76 MB / 3.121 GB, free: 49.39 MB / 2.155 GB
Notification: Performance of NFVerifyModel.verify: time 0.1175/4.835, allocations: 84.44 MB / 3.204 GB, free: 28.19 MB / 2.155 GB
Notification: Performance of NFConvertDAE.convert: time 0.2448/5.08, allocations: 206.2 MB / 3.405 GB, free: 6.125 MB / 2.187 GB
Notification: Performance of FrontEnd - DAE generated: time 5.891e-06/5.08, allocations: 2.406 kB / 3.405 GB, free: 6.125 MB / 2.187 GB
Notification: Performance of FrontEnd: time 1.253e-06/5.08, allocations: 0 / 3.405 GB, free: 6.125 MB / 2.187 GB
Notification: Performance of Transformations before backend: time 0.005281/5.085, allocations: 0 / 3.405 GB, free: 6.125 MB / 2.187 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.7035/5.789, allocations: 208 MB / 3.608 GB, free: 8.738 MB / 2.374 GB
Notification: Performance of prepare preOptimizeDAE: time 4.147e-05/5.789, allocations: 8.328 kB / 3.608 GB, free: 8.73 MB / 2.374 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1026/5.891, allocations: 21.89 MB / 3.629 GB, free: 2.734 MB / 2.39 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.2044/6.096, allocations: 86.65 MB / 3.714 GB, free: 76 kB / 2.468 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.007662/6.103, allocations: 8.274 MB / 3.722 GB, free: 7.797 MB / 2.483 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.659/6.762, allocations: 10.92 MB / 3.733 GB, free: 0.9668 GB / 2.483 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.2484/7.011, allocations: 114.6 MB / 3.845 GB, free: 0.9491 GB / 2.483 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.00409/7.015, allocations: 228.1 kB / 3.845 GB, free: 0.9489 GB / 2.483 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.02061/7.035, allocations: 4.468 MB / 3.849 GB, free: 0.9456 GB / 2.483 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001194/7.037, allocations: 2.682 MB / 3.852 GB, free: 0.9456 GB / 2.483 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1947/7.231, allocations: 108.7 MB / 3.958 GB, free: 0.9079 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 0.8615/8.093, allocations: 0.7423 GB / 4.7 GB, free: 235.4 MB / 2.483 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.3919/8.485, allocations: 246.6 MB / 4.941 GB, free: 31.92 MB / 2.483 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.7625/9.247, allocations: 45.44 MB / 4.986 GB, free: 0.8993 GB / 2.483 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.008748/9.256, allocations: 1.323 MB / 4.987 GB, free: 0.8992 GB / 2.483 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0008491/9.257, allocations: 1.229 MB / 4.988 GB, free: 0.8991 GB / 2.483 GB
Notification: Performance of pre-optimization done (n=11801): time 0.0001403/9.257, allocations: 3.719 kB / 4.988 GB, free: 0.8991 GB / 2.483 GB
Notification: Performance of matching and sorting (n=11801): time 0.3246/9.582, allocations: 152.3 MB / 5.137 GB, free: 0.891 GB / 2.483 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0007815/9.582, allocations: 3.055 MB / 5.14 GB, free: 0.8886 GB / 2.483 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1186/9.701, allocations: 76.14 MB / 5.214 GB, free: 0.8627 GB / 2.483 GB
Notification: Performance of collectPreVariables (initialization): time 0.01513/9.716, allocations: 2.022 MB / 5.216 GB, free: 0.8611 GB / 2.483 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03417/9.75, allocations: 55.44 MB / 5.27 GB, free: 0.8232 GB / 2.483 GB
Notification: Performance of collectInitialBindings (initialization): time 0.04462/9.795, allocations: 43.06 MB / 5.312 GB, free: 0.7958 GB / 2.483 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.05721/9.852, allocations: 21.4 MB / 5.333 GB, free: 0.7855 GB / 2.483 GB
Notification: Performance of setup shared object (initialization): time 3.395e-05/9.852, allocations: 301.1 kB / 5.333 GB, free: 0.7852 GB / 2.483 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0657/9.918, allocations: 39.66 MB / 5.372 GB, free: 0.7695 GB / 2.483 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.07994/9.998, allocations: 64.44 MB / 5.435 GB, free: 0.7275 GB / 2.483 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.2872/10.29, allocations: 107.1 MB / 5.54 GB, free: 0.6343 GB / 2.483 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0006253/10.29, allocations: 179.4 kB / 5.54 GB, free: 0.6341 GB / 2.483 GB
Notification: Performance of matching and sorting (n=16975) (initialization): time 0.356/10.64, allocations: 168.3 MB / 5.704 GB, free: 0.4884 GB / 2.483 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003327/10.64, allocations: 265 kB / 5.704 GB, free: 499.8 MB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0005847/10.64, allocations: 0.5859 MB / 5.705 GB, free: 499.2 MB / 2.483 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.05/11.69, allocations: 77.76 MB / 5.781 GB, free: 0.8502 GB / 2.483 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.08242/11.78, allocations: 23.76 MB / 5.804 GB, free: 0.8496 GB / 2.483 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.8246/12.6, allocations: 0.6171 GB / 6.421 GB, free: 488.3 MB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.1133/12.71, allocations: 10.21 MB / 6.431 GB, free: 480.4 MB / 2.483 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01611/12.73, allocations: 4.566 MB / 6.436 GB, free: 475.9 MB / 2.483 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1/12.83, allocations: 51.64 MB / 6.486 GB, free: 427.8 MB / 2.483 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.8363/13.67, allocations: 62.28 MB / 6.547 GB, free: 0.7542 GB / 2.483 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.3413/14.01, allocations: 104.2 MB / 6.649 GB, free: 0.7289 GB / 2.483 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.001111/14.01, allocations: 175.5 kB / 6.649 GB, free: 0.7289 GB / 2.483 GB
Notification: Performance of matching and sorting (n=16975) (initialization_lambda0): time 0.3206/14.33, allocations: 153.2 MB / 6.798 GB, free: 0.7106 GB / 2.483 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004537/14.33, allocations: 265.5 kB / 6.799 GB, free: 0.7106 GB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0006537/14.33, allocations: 0.5909 MB / 6.799 GB, free: 0.7106 GB / 2.483 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.2795/14.61, allocations: 66.06 MB / 6.864 GB, free: 0.7031 GB / 2.483 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.08708/14.7, allocations: 25.07 MB / 6.888 GB, free: 0.7006 GB / 2.483 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.447/16.14, allocations: 0.5257 GB / 7.414 GB, free: 0.6544 GB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.115/16.26, allocations: 8.026 MB / 7.422 GB, free: 0.6544 GB / 2.483 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01701/16.28, allocations: 4.541 MB / 7.426 GB, free: 0.6544 GB / 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.02481/16.3, allocations: 7.208 MB / 7.433 GB, free: 0.6541 GB / 2.483 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.03413/16.33, allocations: 11.77 MB / 7.445 GB, free: 0.6502 GB / 2.483 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.2409/16.58, allocations: 126.5 MB / 7.568 GB, free: 0.6445 GB / 2.483 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001777/16.58, allocations: 0.5433 MB / 7.569 GB, free: 0.6445 GB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001497/16.58, allocations: 0 / 7.569 GB, free: 0.6445 GB / 2.483 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.06267/16.64, allocations: 22.98 MB / 7.591 GB, free: 0.6384 GB / 2.483 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.03001/16.67, allocations: 1.921 MB / 7.593 GB, free: 0.6384 GB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.08108/16.75, allocations: 3.205 MB / 7.596 GB, free: 0.6383 GB / 2.483 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0181/16.77, allocations: 9.42 MB / 7.605 GB, free: 0.6382 GB / 2.483 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.2896/17.06, allocations: 118.7 MB / 7.721 GB, free: 0.6235 GB / 2.483 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.216/17.27, allocations: 134.8 MB / 7.853 GB, free: 0.5521 GB / 2.483 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.05768/17.33, allocations: 35.09 MB / 7.887 GB, free: 0.5222 GB / 2.483 GB
Notification: Performance of sorting global known variables: time 0.07868/17.41, allocations: 49.44 MB / 7.936 GB, free: 493.4 MB / 2.483 GB
Notification: Performance of Backend: time 6.72e-07/17.41, allocations: 0 / 7.936 GB, free: 493.4 MB / 2.483 GB
Notification: Performance of simCode: created initialization part: time 1.312/18.72, allocations: 292.9 MB / 8.222 GB, free: 0.6436 GB / 2.483 GB
Notification: Performance of SimCode: time 0.3598/19.08, allocations: 256.9 MB / 8.472 GB, free: 0.5532 GB / 2.483 GB
Notification: Performance of Templates: time 3.924/23.01, allocations: 2.561 GB / 11.03 GB, free: 0.5139 GB / 2.577 GB
make -j1 -f ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.makefile
(rm -f ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.pipe ; mkfifo ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.pipe ; head -c 1048576 < ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.pipe >> ../files/ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.sim & ./ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=300 -lv LOG_STATS > ScalableTestGrids_NumericalJacobian_ScalableTestGrids.Models.Type1.Type1_N_3_M_4.pipe 2>&1)