Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.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.09543/0.09543, allocations: 9.526 MB / 28.63 MB, free: 1.578 MB / 19.63 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.001281/0.001281, allocations: 91.28 kB / 34.67 MB, free: 2.777 MB / 26.18 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.314/1.314, allocations: 158.6 MB / 199.2 MB, free: 10.55 MB / 190.1 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.00159/0.00159, allocations: 160.5 kB / 254.4 MB, free: 6.762 MB / 238.1 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.4655/0.4655, allocations: 59.7 MB / 369.2 MB, free: 50.02 MB / 334.1 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 179]
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.TwoAreaOld.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.893e-06/1.893e-06, allocations: 0 / 0.518 GB, free: 33.43 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 3.232e-05/3.421e-05, allocations: 4.766 kB / 0.518 GB, free: 33.43 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI): time 0.005477/0.005511, allocations: 5.793 MB / 0.5237 GB, free: 29.5 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.003611/0.009122, allocations: 1.906 MB / 0.5255 GB, free: 28.18 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.000699/0.009821, allocations: 10.09 kB / 0.5255 GB, free: 28.18 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006644/0.01049, allocations: 253.4 kB / 0.5258 GB, free: 28.04 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001922/0.01241, allocations: 0.8678 MB / 0.5266 GB, free: 27.48 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001312/0.01372, allocations: 0.6746 MB / 0.5273 GB, free: 27.14 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001522/0.01524, allocations: 1.607 MB / 0.5288 GB, free: 26.32 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003647/0.01561, allocations: 217.8 kB / 0.529 GB, free: 26.21 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009817/0.01659, allocations: 0.8921 MB / 0.5299 GB, free: 25.8 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007359/0.01732, allocations: 0.688 MB / 0.5306 GB, free: 25.58 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001837/0.01751, allocations: 125.1 kB / 0.5307 GB, free: 25.58 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0005917/0.0181, allocations: 260.2 kB / 0.531 GB, free: 25.54 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003266/0.01843, allocations: 349.4 kB / 0.5313 GB, free: 25.31 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0005002/0.01893, allocations: 466.2 kB / 0.5317 GB, free: 25.08 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00186/0.02079, allocations: 1.741 MB / 0.5334 GB, free: 24.34 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.909e-06/0.02079, allocations: 0 / 0.5334 GB, free: 24.34 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.744e-06/0.02079, allocations: 0 / 0.5334 GB, free: 24.34 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 2.107e-05/0.02081, allocations: 0 / 0.5334 GB, free: 24.34 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 188
 * Number of variables: 188
Notification: Performance of Generate backend data structure: time 0.008734/0.02955, allocations: 2.687 MB / 0.5361 GB, free: 23.1 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.104e-05/0.02959, allocations: 10.91 kB / 0.5361 GB, free: 23.1 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005161/0.03475, allocations: 0.775 MB / 0.5368 GB, free: 22.94 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001499/0.03625, allocations: 0.8552 MB / 0.5377 GB, free: 22.39 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002187/0.03647, allocations: 285.9 kB / 0.5379 GB, free: 22.13 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001603/0.03663, allocations: 87.05 kB / 0.538 GB, free: 22.1 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002063/0.03869, allocations: 1.155 MB / 0.5391 GB, free: 21.61 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.754e-05/0.03872, allocations: 0 / 0.5391 GB, free: 21.61 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001011/0.03882, allocations: 32.31 kB / 0.5392 GB, free: 21.59 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.928e-05/0.03884, allocations: 24.34 kB / 0.5392 GB, free: 21.58 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001997/0.04084, allocations: 1.033 MB / 0.5402 GB, free: 21.22 MB / 430.1 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.00611/0.04695, allocations: 2.892 MB / 0.543 GB, free: 19.71 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.007436/0.05438, allocations: 2.004 MB / 0.545 GB, free: 19.27 MB / 430.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001361/0.05574, allocations: 0.6699 MB / 0.5456 GB, free: 19 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 9.525e-05/0.05584, allocations: 14.64 kB / 0.5457 GB, free: 19 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.504e-05/0.05586, allocations: 45.94 kB / 0.5457 GB, free: 18.95 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=94): time 3.276e-06/0.05587, allocations: 0 / 0.5457 GB, free: 18.95 MB / 430.1 MB
Notification: Performance of matching and sorting (n=94): time 0.02837/0.08424, allocations: 6.667 MB / 0.5522 GB, free: 17.73 MB / 430.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.014e-05/0.0843, allocations: 139.7 kB / 0.5523 GB, free: 17.57 MB / 430.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00181/0.08611, allocations: 1.099 MB / 0.5534 GB, free: 17.12 MB / 430.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002121/0.08632, allocations: 45.92 kB / 0.5535 GB, free: 17.07 MB / 430.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003426/0.08666, allocations: 0.5065 MB / 0.554 GB, free: 16.66 MB / 430.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003737/0.08704, allocations: 360.6 kB / 0.5543 GB, free: 16.38 MB / 430.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005306/0.08757, allocations: 265 kB / 0.5546 GB, free: 16.26 MB / 430.1 MB
Notification: Performance of setup shared object (initialization): time 7.108e-05/0.08764, allocations: 307.5 kB / 0.5548 GB, free: 15.96 MB / 430.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00183/0.08947, allocations: 0.8279 MB / 0.5557 GB, free: 15.59 MB / 430.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001872/0.09134, allocations: 1.016 MB / 0.5566 GB, free: 15.06 MB / 430.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02594/0.1173, allocations: 5.739 MB / 0.5623 GB, free: 12.76 MB / 430.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.139e-05/0.1173, allocations: 0 / 0.5623 GB, free: 12.76 MB / 430.1 MB
Notification: Performance of matching and sorting (n=183) (initialization): time 0.01431/0.1316, allocations: 4.423 MB / 0.5666 GB, free: 10.5 MB / 430.1 MB
Notification: Performance of prepare postOptimizeDAE: time 4.995e-05/0.1316, allocations: 68.09 kB / 0.5666 GB, free: 10.43 MB / 430.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.417e-05/0.1317, allocations: 2.75 kB / 0.5666 GB, free: 10.43 MB / 430.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0065/0.1382, allocations: 1.636 MB / 0.5682 GB, free: 9.707 MB / 430.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001497/0.1397, allocations: 260.5 kB / 0.5685 GB, free: 9.625 MB / 430.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02024/0.1599, allocations: 8.295 MB / 0.5766 GB, free: 1.219 MB / 430.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002205/0.1621, allocations: 191.3 kB / 0.5768 GB, free: 1.031 MB / 430.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002744/0.1624, allocations: 47.98 kB / 0.5768 GB, free: 0.9844 MB / 430.1 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
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.e1q:VARIABLE(start = order5.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00229/0.1647, allocations: 1.004 MB / 0.5778 GB, free: 15.97 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002135/0.1668, allocations: 1.01 MB / 0.5788 GB, free: 14.89 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02671/0.1935, allocations: 5.707 MB / 0.5844 GB, free: 9.102 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.746e-06/0.1935, allocations: 0 / 0.5844 GB, free: 9.102 MB / 446.1 MB
Notification: Performance of matching and sorting (n=183) (initialization_lambda0): time 0.01545/0.209, allocations: 4.402 MB / 0.5887 GB, free: 4.66 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 5.684e-05/0.209, allocations: 67.33 kB / 0.5887 GB, free: 4.582 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.16e-05/0.209, allocations: 4 kB / 0.5887 GB, free: 4.578 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006569/0.2156, allocations: 1.628 MB / 0.5903 GB, free: 2.945 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001402/0.217, allocations: 259.6 kB / 0.5906 GB, free: 2.691 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02274/0.2397, allocations: 8.303 MB / 0.5987 GB, free: 10.27 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002034/0.2418, allocations: 175.4 kB / 0.5988 GB, free: 10.09 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002422/0.242, allocations: 51.97 kB / 0.5989 GB, free: 10.04 MB / 462.1 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
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.e1q:VARIABLE(start = order5.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 13
 * 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 (150):
 * Single equations (assignments): 147
 * 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
   {(20,14,23.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0005429/0.2426, allocations: 294.9 kB / 0.5992 GB, free: 9.742 MB / 462.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004125/0.243, allocations: 131.1 kB / 0.5993 GB, free: 9.613 MB / 462.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01382/0.2568, allocations: 3.791 MB / 0.603 GB, free: 5.793 MB / 462.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.455e-05/0.2568, allocations: 11.98 kB / 0.603 GB, free: 5.781 MB / 462.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.711e-05/0.2568, allocations: 8 kB / 0.603 GB, free: 5.773 MB / 462.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.088e-05/0.2568, allocations: 7.984 kB / 0.603 GB, free: 5.766 MB / 462.1 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 postOpt removeSimpleEquations (simulation): time 0.01598/0.2728, allocations: 4.88 MB / 0.6078 GB, free: 0.8242 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.139e-06/0.2728, allocations: 0 / 0.6078 GB, free: 0.8242 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007369/0.2736, allocations: 119.8 kB / 0.6079 GB, free: 0.707 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.005904/0.2795, allocations: 1.512 MB / 0.6094 GB, free: 15.19 MB / 478.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001272/0.2796, allocations: 16 kB / 0.6094 GB, free: 15.17 MB / 478.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01936/0.299, allocations: 7.101 MB / 0.6163 GB, free: 7.969 MB / 478.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.044e-06/0.299, allocations: 0 / 0.6163 GB, free: 7.969 MB / 478.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01612/0.3151, allocations: 4.687 MB / 0.6209 GB, free: 3.238 MB / 478.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004702/0.3155, allocations: 154.9 kB / 0.621 GB, free: 3.086 MB / 478.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000271/0.3158, allocations: 20 kB / 0.6211 GB, free: 3.066 MB / 478.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001353/0.3172, allocations: 99.88 kB / 0.6212 GB, free: 2.969 MB / 478.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005887/0.3178, allocations: 192.6 kB / 0.6213 GB, free: 2.781 MB / 478.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 9.564e-05/0.3179, allocations: 27.98 kB / 0.6214 GB, free: 2.754 MB / 478.1 MB
Notification: Performance of sorting global known variables: time 0.001277/0.3191, allocations: 0.7428 MB / 0.6221 GB, free: 2.004 MB / 478.1 MB
Notification: Performance of sort global known variables: time 9.1e-08/0.3191, allocations: 0 / 0.6221 GB, free: 2.004 MB / 478.1 MB
Notification: Performance of remove unused functions: time 0.002231/0.3214, allocations: 0.7727 MB / 0.6229 GB, free: 1.23 MB / 478.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 11 (order2.delta,order2.w,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 (68):
 * Single equations (assignments): 66
 * 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
   {(19,13,24.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003223/0.3217, allocations: 127.2 kB / 0.623 GB, free: 1.098 MB / 478.1 MB
Notification: Performance of simCode: created initialization part: time 0.01287/0.3346, allocations: 4.317 MB / 0.6272 GB, free: 12.68 MB / 494.1 MB
Notification: Performance of simCode: created event and clocks part: time 3.777e-06/0.3346, allocations: 0 / 0.6272 GB, free: 12.68 MB / 494.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.00458/0.3391, allocations: 1.42 MB / 0.6286 GB, free: 11.23 MB / 494.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001001/0.3401, allocations: 128.1 kB / 0.6287 GB, free: 11.11 MB / 494.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004605/0.3447, allocations: 2.301 MB / 0.6309 GB, free: 8.773 MB / 494.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006315/0.3454, allocations: 0.5569 MB / 0.6315 GB, free: 8.184 MB / 494.1 MB
Notification: Performance of simCode: alias equations: time 0.0006334/0.346, allocations: 159.7 kB / 0.6316 GB, free: 8.023 MB / 494.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003239/0.3463, allocations: 84.47 kB / 0.6317 GB, free: 7.941 MB / 494.1 MB
Notification: Performance of SimCode: time 6.61e-07/0.3463, allocations: 0 / 0.6317 GB, free: 7.941 MB / 494.1 MB
Notification: Performance of Templates: time 0.05291/0.3992, allocations: 32.92 MB / 0.6639 GB, free: 7.043 MB / 0.5137 GB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.564456067979336]
Failed to read output from testmodel.py, exit status != 0:
0.40851300954818726 0.42359214700000003 0.39962382100000005
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fbcadf2d1e0>