Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo): time 0.08207/0.08207, allocations: 9.525 MB / 28.88 MB, free: 300 kB / 18.57 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
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.001387/0.001387, allocations: 95.52 kB / 34.96 MB, free: 0.8086 MB / 24.77 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[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.459/1.459, allocations: 158.6 MB / 199.6 MB, free: 7.141 MB / 186.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 178]
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.001663/0.001663, allocations: 152.7 kB / 254.8 MB, free: 15.8 MB / 250.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.3096/0.3096, allocations: 59.71 MB / 369.7 MB, free: 12.57 MB / 298.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
Using package OpenIPSL with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/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 Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo)
Running command: translateModel(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.764e-06/1.764e-06, allocations: 0 / 459.3 MB, free: 37.98 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.76e-05/2.937e-05, allocations: 5.375 kB / 459.3 MB, free: 37.97 MB / 362.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI): time 0.01356/0.01359, allocations: 18.42 MB / 477.7 MB, free: 25.54 MB / 362.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01186/0.02546, allocations: 4.783 MB / 482.5 MB, free: 22.11 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005275/0.03073, allocations: 44.62 kB / 482.5 MB, free: 22.11 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.003724/0.03446, allocations: 0.8848 MB / 483.4 MB, free: 21.63 MB / 362.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr16.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr67.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr78.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr89.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr49.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr528.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr2829.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr2930.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr3031.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr231.m <> 1.0, <> on Real operands is deprecated in non-function contexts.
Notification: Performance of NFTyping.typeBindings: time 0.01066/0.04511, allocations: 2.338 MB / 485.7 MB, free: 19.96 MB / 362.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006516/0.05163, allocations: 2.077 MB / 487.8 MB, free: 18.89 MB / 362.7 MB
Notification: Performance of NFFlatten.flatten: time 0.009611/0.06124, allocations: 5.874 MB / 493.7 MB, free: 15.43 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002363/0.0636, allocations: 0.8605 MB / 494.6 MB, free: 14.72 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.006555/0.07016, allocations: 3.304 MB / 497.9 MB, free: 11.41 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003443/0.0736, allocations: 2.274 MB / 0.4884 GB, free: 9.129 MB / 362.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.001329/0.07493, allocations: 440 kB / 0.4888 GB, free: 8.699 MB / 362.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002205/0.07713, allocations: 0.5817 MB / 0.4894 GB, free: 8.117 MB / 362.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.00127/0.0784, allocations: 1.182 MB / 0.4906 GB, free: 6.93 MB / 362.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.002992/0.0814, allocations: 1.721 MB / 0.4922 GB, free: 5.203 MB / 362.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.007659/0.08906, allocations: 6.333 MB / 0.4984 GB, free: 14.85 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.591e-06/0.08906, allocations: 0 / 0.4984 GB, free: 14.85 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.322e-06/0.08906, allocations: 0 / 0.4984 GB, free: 14.85 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 0.0002441/0.08931, allocations: 0 / 0.4984 GB, free: 14.85 MB / 378.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 891
 * Number of variables: 891
Notification: Performance of Generate backend data structure: time 0.03516/0.1245, allocations: 9.199 MB / 0.5074 GB, free: 5.57 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.68e-05/0.1245, allocations: 12.03 kB / 0.5074 GB, free: 5.559 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02947/0.154, allocations: 4.718 MB / 0.512 GB, free: 0.832 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008496/0.1625, allocations: 2.668 MB / 0.5146 GB, free: 14.07 MB / 394.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001496/0.164, allocations: 0.9824 MB / 0.5156 GB, free: 12.95 MB / 394.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001949/0.1659, allocations: 359.9 kB / 0.5159 GB, free: 12.6 MB / 394.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01509/0.181, allocations: 4.793 MB / 0.5206 GB, free: 7.758 MB / 394.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 8.217e-05/0.1811, allocations: 0 / 0.5206 GB, free: 7.758 MB / 394.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007342/0.1818, allocations: 140 kB / 0.5207 GB, free: 7.621 MB / 394.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.802e-05/0.1819, allocations: 87.94 kB / 0.5208 GB, free: 7.535 MB / 394.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01064/0.1926, allocations: 4.269 MB / 0.525 GB, free: 3.254 MB / 394.7 MB
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.03717/0.2297, allocations: 13.18 MB / 0.5379 GB, free: 5.66 MB / 410.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01411/0.2438, allocations: 4.337 MB / 0.5421 GB, free: 1.309 MB / 410.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007843/0.2517, allocations: 2.523 MB / 0.5446 GB, free: 14.77 MB / 426.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005599/0.2522, allocations: 82.95 kB / 0.5446 GB, free: 14.68 MB / 426.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.721e-05/0.2523, allocations: 69.55 kB / 0.5447 GB, free: 14.61 MB / 426.7 MB
Notification: Performance of pre-optimization done (n=381): time 6.833e-06/0.2523, allocations: 0 / 0.5447 GB, free: 14.61 MB / 426.7 MB
Notification: Performance of matching and sorting (n=381): time 0.33/0.5823, allocations: 66.3 MB / 0.6095 GB, free: 12.13 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.00017/0.5825, allocations: 171.5 kB / 0.6096 GB, free: 11.93 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006867/0.5894, allocations: 3.249 MB / 0.6128 GB, free: 8.66 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.001101/0.5905, allocations: 89.7 kB / 0.6129 GB, free: 8.566 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002264/0.5927, allocations: 2.013 MB / 0.6148 GB, free: 6.547 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002678/0.5954, allocations: 1.359 MB / 0.6162 GB, free: 5.176 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003113/0.5985, allocations: 0.6507 MB / 0.6168 GB, free: 4.512 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001666/0.5987, allocations: 305.1 kB / 0.6171 GB, free: 4.211 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007229/0.6059, allocations: 2.653 MB / 0.6197 GB, free: 1.523 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.1882/0.7941, allocations: 3.293 MB / 0.6229 GB, free: 144.9 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.07056/0.8646, allocations: 18.95 MB / 0.6414 GB, free: 141.7 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.307e-05/0.8647, allocations: 4.219 kB / 0.6414 GB, free: 141.7 MB / 490.7 MB
Notification: Performance of matching and sorting (n=525) (initialization): time 0.05563/0.9203, allocations: 17.35 MB / 0.6584 GB, free: 137.9 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 8.32e-05/0.9204, allocations: 63.28 kB / 0.6584 GB, free: 137.8 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.047e-05/0.9204, allocations: 19.72 kB / 0.6584 GB, free: 137.8 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.04719/0.9676, allocations: 10.59 MB / 0.6688 GB, free: 132 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003533/0.9711, allocations: 0.6597 MB / 0.6694 GB, free: 131.4 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0854/1.057, allocations: 32.45 MB / 0.7011 GB, free: 99.13 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006218/1.063, allocations: 0.5942 MB / 0.7017 GB, free: 98.61 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00116/1.064, allocations: 148 kB / 0.7018 GB, free: 98.47 MB / 490.7 MB
Warning: Assuming fixed start value for the following 5 variables:
         order5.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.delta:VARIABLE(flow=false start = order3.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order3.e1q:VARIABLE(start = order3.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.007945/1.072, allocations: 3.12 MB / 0.7049 GB, free: 95.33 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006124/1.078, allocations: 3.28 MB / 0.7081 GB, free: 91.81 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.07049/1.148, allocations: 18.91 MB / 0.7266 GB, free: 72.62 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.635e-05/1.149, allocations: 8 kB / 0.7266 GB, free: 72.62 MB / 490.7 MB
Notification: Performance of matching and sorting (n=525) (initialization_lambda0): time 0.05981/1.208, allocations: 17.32 MB / 0.7435 GB, free: 55.23 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 7.399e-05/1.208, allocations: 67.41 kB / 0.7435 GB, free: 55.15 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.911e-05/1.208, allocations: 12 kB / 0.7436 GB, free: 55.14 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.04897/1.257, allocations: 10.6 MB / 0.7539 GB, free: 44.55 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003527/1.261, allocations: 0.6553 MB / 0.7545 GB, free: 43.89 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08563/1.347, allocations: 32.45 MB / 0.7862 GB, free: 11.34 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006035/1.353, allocations: 0.5581 MB / 0.7868 GB, free: 10.85 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009665/1.354, allocations: 157.2 kB / 0.7869 GB, free: 10.7 MB / 490.7 MB
Warning: Assuming fixed start value for the following 5 variables:
         order5.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.delta:VARIABLE(flow=false start = order3.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order3.e1q:VARIABLE(start = order3.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 65
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (390):
 * Single equations (assignments): 387
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(92,44,5.8%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.001663/1.355, allocations: 434.2 kB / 0.7873 GB, free: 10.28 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001949/1.357, allocations: 0.583 MB / 0.7879 GB, free: 9.695 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05893/1.416, allocations: 16.66 MB / 0.8042 GB, free: 8.965 MB / 0.4948 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.353e-05/1.416, allocations: 40 kB / 0.8042 GB, free: 8.926 MB / 0.4948 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001081/1.416, allocations: 28 kB / 0.8043 GB, free: 8.898 MB / 0.4948 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001108/1.416, allocations: 19.22 kB / 0.8043 GB, free: 8.883 MB / 0.4948 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 postOpt removeSimpleEquations (simulation): time 0.2617/1.678, allocations: 20.24 MB / 0.824 GB, free: 143.7 MB / 0.4948 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.227e-05/1.678, allocations: 0.5156 kB / 0.824 GB, free: 143.7 MB / 0.4948 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002518/1.681, allocations: 403.6 kB / 0.8244 GB, free: 143.7 MB / 0.4948 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.05102/1.732, allocations: 10.68 MB / 0.8348 GB, free: 143.6 MB / 0.4948 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001177/1.733, allocations: 72.47 kB / 0.8349 GB, free: 143.6 MB / 0.4948 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.07914/1.812, allocations: 28.8 MB / 0.863 GB, free: 134 MB / 0.4948 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.092e-06/1.812, allocations: 0.7656 kB / 0.863 GB, free: 134 MB / 0.4948 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.06263/1.875, allocations: 20.2 MB / 0.8828 GB, free: 121.7 MB / 0.4948 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001769/1.876, allocations: 0.4963 MB / 0.8833 GB, free: 121.3 MB / 0.4948 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002041/1.878, allocations: 71.69 kB / 0.8833 GB, free: 121.2 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005708/1.884, allocations: 352.1 kB / 0.8837 GB, free: 121 MB / 0.4948 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.004056/1.888, allocations: 0.7975 MB / 0.8844 GB, free: 120.6 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001042/1.889, allocations: 99.34 kB / 0.8845 GB, free: 120.5 MB / 0.4948 GB
Notification: Performance of sorting global known variables: time 0.004006/1.893, allocations: 2.113 MB / 0.8866 GB, free: 118.4 MB / 0.4948 GB
Notification: Performance of sort global known variables: time 2e-07/1.893, allocations: 0 / 0.8866 GB, free: 118.4 MB / 0.4948 GB
Notification: Performance of remove unused functions: time 0.01268/1.906, allocations: 2.982 MB / 0.8895 GB, free: 115.5 MB / 0.4948 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 12 (order3.delta,order3.w,order3.e1q,order5.delta,order5.w,order5.e1q,order5.e2q,order5.e2d,aVRTypeI.firstOrder.y,aVRTypeI.firstOrder2.y,aVRTypeI.transferFunction.x_scaled[1],aVRTypeI.transferFunction.x_scaled[2])
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (251):
 * Single equations (assignments): 249
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(96,40,5.5%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001396/1.907, allocations: 198.5 kB / 0.8897 GB, free: 115.3 MB / 0.4948 GB
Notification: Performance of simCode: created initialization part: time 0.04555/1.953, allocations: 14.99 MB / 0.9043 GB, free: 100.2 MB / 0.4948 GB
Notification: Performance of simCode: created event and clocks part: time 9.758e-06/1.953, allocations: 0.5 kB / 0.9043 GB, free: 100.2 MB / 0.4948 GB
Notification: Performance of simCode: created simulation system equations: time 0.01997/1.973, allocations: 5.74 MB / 0.9099 GB, free: 94.41 MB / 0.4948 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006032/1.979, allocations: 479.8 kB / 0.9104 GB, free: 93.95 MB / 0.4948 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02006/1.999, allocations: 8.216 MB / 0.9184 GB, free: 85.73 MB / 0.4948 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002897/2.002, allocations: 1.987 MB / 0.9204 GB, free: 83.7 MB / 0.4948 GB
Notification: Performance of simCode: alias equations: time 0.002889/2.005, allocations: 459.9 kB / 0.9208 GB, free: 83.25 MB / 0.4948 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001231/2.006, allocations: 256.4 kB / 0.921 GB, free: 83 MB / 0.4948 GB
Notification: Performance of SimCode: time 1.703e-06/2.006, allocations: 0 / 0.921 GB, free: 83 MB / 0.4948 GB
Notification: Performance of Templates: time 0.3365/2.342, allocations: 129 MB / 1.047 GB, free: 135 MB / 0.4948 GB
"
[Timeout remaining time 658]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 12.045499654021114]