Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.08839/0.08839, allocations: 12.77 MB / 32.26 MB, free: 6.16 MB / 26.18 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001369/0.001369, allocations: 102.3 kB / 38.36 MB, free: 0.7734 MB / 26.18 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.417/1.417, allocations: 207.6 MB / 252 MB, free: 2.746 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001577/0.001577, allocations: 188.4 kB / 307.3 MB, free: 11.29 MB / 254.1 MB
"
[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.4861/0.4861, allocations: 79.7 MB / 442.1 MB, free: 11.21 MB / 302.1 MB
"
[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.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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 ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.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-master/package.mo)
Running command: translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI") [Timeout 660]
"Notification: Modelica_Synchronous 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: 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: OpenIPSL 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: Performance of FrontEnd - loaded program: time 0.00436/0.00436, allocations: 144.3 kB / 0.5893 GB, free: 36 kB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2673/0.2717, allocations: 72.04 MB / 0.6597 GB, free: 11.63 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI): time 0.009879/0.2816, allocations: 13.62 MB / 0.673 GB, free: 13.96 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008034/0.2896, allocations: 3.501 MB / 0.6764 GB, free: 10.45 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002047/0.2917, allocations: 35.88 kB / 0.6764 GB, free: 10.41 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00159/0.2933, allocations: 0.5577 MB / 0.677 GB, free: 9.848 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004919/0.2982, allocations: 1.562 MB / 0.6785 GB, free: 8.285 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004123/0.3023, allocations: 1.578 MB / 0.68 GB, free: 6.727 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005844/0.3081, allocations: 3.773 MB / 0.6837 GB, free: 2.938 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001195/0.3093, allocations: 0.581 MB / 0.6843 GB, free: 2.32 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002427/0.3118, allocations: 1.564 MB / 0.6858 GB, free: 0.75 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002782/0.3145, allocations: 2.003 MB / 0.6878 GB, free: 14.74 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007188/0.3153, allocations: 284 kB / 0.688 GB, free: 14.46 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001061/0.3163, allocations: 427.9 kB / 0.6884 GB, free: 14.04 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0008094/0.3171, allocations: 0.7818 MB / 0.6892 GB, free: 13.26 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002027/0.3192, allocations: 1.639 MB / 0.6908 GB, free: 11.61 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004963/0.3241, allocations: 4.027 MB / 0.6947 GB, free: 7.57 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.92e-06/0.3241, allocations: 0 / 0.6947 GB, free: 7.57 MB / 478.1 MB
Notification: Performance of FrontEnd: time 5.43e-06/0.3241, allocations: 3.984 kB / 0.6947 GB, free: 7.566 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 0.0001016/0.3242, allocations: 4 kB / 0.6948 GB, free: 7.562 MB / 478.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 518
 * Number of variables: 518
Notification: Performance of Generate backend data structure: time 0.02428/0.3485, allocations: 6.637 MB / 0.7012 GB, free: 0.8438 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.985e-05/0.3485, allocations: 8.031 kB / 0.7012 GB, free: 0.8359 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01591/0.3645, allocations: 2.296 MB / 0.7035 GB, free: 14.54 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00474/0.3692, allocations: 1.828 MB / 0.7053 GB, free: 12.64 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008163/0.37, allocations: 0.6672 MB / 0.7059 GB, free: 11.88 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007209/0.3707, allocations: 223.9 kB / 0.7061 GB, free: 11.66 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007573/0.3783, allocations: 3.16 MB / 0.7092 GB, free: 8.387 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.237e-05/0.3784, allocations: 4 kB / 0.7092 GB, free: 8.383 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002711/0.3786, allocations: 83.94 kB / 0.7093 GB, free: 8.301 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.207e-05/0.3787, allocations: 48 kB / 0.7093 GB, free: 8.254 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006942/0.3856, allocations: 2.877 MB / 0.7122 GB, free: 5.348 MB / 494.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.02253/0.4081, allocations: 9.829 MB / 0.7218 GB, free: 11.07 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01181/0.42, allocations: 3.495 MB / 0.7252 GB, free: 7.543 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004707/0.4247, allocations: 1.853 MB / 0.727 GB, free: 5.672 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000362/0.425, allocations: 47.12 kB / 0.727 GB, free: 5.625 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.613e-05/0.4251, allocations: 64.48 kB / 0.7271 GB, free: 5.555 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=243): time 4.829e-06/0.4251, allocations: 0 / 0.7271 GB, free: 5.555 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=243): time 0.1269/0.552, allocations: 28.14 MB / 0.7546 GB, free: 9.332 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001687/0.5522, allocations: 235.6 kB / 0.7548 GB, free: 9.051 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003895/0.5561, allocations: 2.301 MB / 0.757 GB, free: 6.73 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005807/0.5567, allocations: 69.7 kB / 0.7571 GB, free: 6.656 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001126/0.5578, allocations: 1.201 MB / 0.7583 GB, free: 5.445 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001285/0.5591, allocations: 0.9023 MB / 0.7592 GB, free: 4.535 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001241/0.5603, allocations: 0.5382 MB / 0.7597 GB, free: 3.988 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001268/0.5605, allocations: 301.1 kB / 0.76 GB, free: 3.691 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004939/0.5654, allocations: 2.076 MB / 0.762 GB, free: 1.582 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005451/0.5709, allocations: 2.488 MB / 0.7644 GB, free: 14.96 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0609/0.6318, allocations: 14.21 MB / 0.7783 GB, free: 0.5195 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.245e-05/0.6318, allocations: 4 kB / 0.7783 GB, free: 0.5156 MB / 0.545 GB
Notification: Performance of matching and sorting (n=401) (initialization): time 0.04473/0.6765, allocations: 12.53 MB / 0.7905 GB, free: 3.891 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001127/0.6766, allocations: 117 kB / 0.7906 GB, free: 3.754 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.299e-05/0.6766, allocations: 8 kB / 0.7907 GB, free: 3.746 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01941/0.696, allocations: 5.245 MB / 0.7958 GB, free: 14.5 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003259/0.6993, allocations: 0.5499 MB / 0.7963 GB, free: 13.95 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3177/1.017, allocations: 23.09 MB / 0.8189 GB, free: 125.9 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004129/1.021, allocations: 476.8 kB / 0.8193 GB, free: 125.9 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003756/1.022, allocations: 116.4 kB / 0.8194 GB, free: 125.9 MB / 0.5762 GB
Warning: Assuming fixed start value for the following 7 variables:
         order3_2.delta:VARIABLE(flow=false start = order3_2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order3_2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3_2.e1q:VARIABLE(start = order3_2.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         Syn2.delta:VARIABLE(flow=false start = Syn2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         Syn2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004646/1.026, allocations: 2.4 MB / 0.8218 GB, free: 124.8 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00402/1.03, allocations: 2.464 MB / 0.8242 GB, free: 123.6 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05221/1.082, allocations: 14.14 MB / 0.838 GB, free: 119.2 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.097e-05/1.082, allocations: 4 kB / 0.838 GB, free: 119.2 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=401) (initialization_lambda0): time 0.03818/1.121, allocations: 12.48 MB / 0.8502 GB, free: 110 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 5.987e-05/1.121, allocations: 118.3 kB / 0.8503 GB, free: 109.8 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.253e-05/1.121, allocations: 4 kB / 0.8503 GB, free: 109.8 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01632/1.137, allocations: 5.257 MB / 0.8554 GB, free: 105.3 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002915/1.14, allocations: 0.5344 MB / 0.8559 GB, free: 104.8 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05526/1.195, allocations: 23.09 MB / 0.8785 GB, free: 81.62 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004161/1.199, allocations: 454.5 kB / 0.8789 GB, free: 81.22 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004717/1.2, allocations: 114.3 kB / 0.879 GB, free: 81.11 MB / 0.5762 GB
Warning: Assuming fixed start value for the following 7 variables:
         order3_2.delta:VARIABLE(flow=false start = order3_2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order3_2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3_2.e1q:VARIABLE(start = order3_2.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         Syn2.delta:VARIABLE(flow=false start = Syn2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         Syn2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 29
 * 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 (314):
 * Single equations (assignments): 309
 * Array equations: 0
 * Algorithm blocks: 4
 * 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
   {(63,25,8.1%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0007654/1.201, allocations: 413.1 kB / 0.8794 GB, free: 80.7 MB / 0.5762 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006788/1.201, allocations: 306 kB / 0.8797 GB, free: 80.4 MB / 0.5762 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0358/1.237, allocations: 11.68 MB / 0.8911 GB, free: 68.66 MB / 0.5762 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.244e-05/1.237, allocations: 24 kB / 0.8912 GB, free: 68.64 MB / 0.5762 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.519e-05/1.237, allocations: 20 kB / 0.8912 GB, free: 68.62 MB / 0.5762 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.149e-05/1.237, allocations: 11.95 kB / 0.8912 GB, free: 68.61 MB / 0.5762 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.04217/1.279, allocations: 15.44 MB / 0.9063 GB, free: 53.1 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.723e-05/1.279, allocations: 4 kB / 0.9063 GB, free: 53.1 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001669/1.281, allocations: 267.7 kB / 0.9065 GB, free: 52.84 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01569/1.297, allocations: 5.048 MB / 0.9115 GB, free: 47.78 MB / 0.5762 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005583/1.297, allocations: 47.98 kB / 0.9115 GB, free: 47.73 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04713/1.344, allocations: 20.4 MB / 0.9314 GB, free: 27.22 MB / 0.5762 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.178e-06/1.344, allocations: 7.984 kB / 0.9314 GB, free: 27.21 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.04081/1.385, allocations: 14.12 MB / 0.9452 GB, free: 13.02 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007887/1.386, allocations: 345.2 kB / 0.9455 GB, free: 12.72 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005645/1.387, allocations: 47.98 kB / 0.9456 GB, free: 12.67 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00298/1.39, allocations: 287.7 kB / 0.9459 GB, free: 12.39 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001917/1.391, allocations: 0.5722 MB / 0.9464 GB, free: 11.81 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002156/1.392, allocations: 73.98 kB / 0.9465 GB, free: 11.74 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.001984/1.394, allocations: 1.523 MB / 0.948 GB, free: 10.21 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 1.2e-07/1.394, allocations: 0 / 0.948 GB, free: 10.21 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.004385/1.398, allocations: 1.925 MB / 0.9499 GB, free: 8.355 MB / 0.5762 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 18 (order3_2.delta,order3_2.w,order3_2.e1q,order2.delta,order2.w,Syn2.delta,Syn2.w,Syn2.e1q,Syn2.e2q,Syn2.e2d,Exc1.firstOrder.y,Exc1.firstOrder2.y,Exc1.transferFunction.x_scaled[1],Exc1.transferFunction.x_scaled[2],Exc2.firstOrder.y,Exc2.firstOrder2.y,Exc2.transferFunction.x_scaled[1],Exc2.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 (164):
 * Single equations (assignments): 161
 * 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
   {(63,25,8.1%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003982/1.398, allocations: 177 kB / 0.95 GB, free: 8.176 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.0299/1.428, allocations: 11.05 MB / 0.9608 GB, free: 12.94 MB / 0.5919 GB
Notification: Performance of simCode: created event and clocks part: time 5.45e-06/1.428, allocations: 0 / 0.9608 GB, free: 12.94 MB / 0.5919 GB
Notification: Performance of simCode: created simulation system equations: time 0.01339/1.442, allocations: 3.964 MB / 0.9647 GB, free: 8.922 MB / 0.5919 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002102/1.444, allocations: 283.2 kB / 0.965 GB, free: 8.648 MB / 0.5919 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01106/1.455, allocations: 5.647 MB / 0.9705 GB, free: 2.98 MB / 0.5919 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001733/1.457, allocations: 1.845 MB / 0.9723 GB, free: 1.105 MB / 0.5919 GB
Notification: Performance of simCode: alias equations: time 0.00154/1.458, allocations: 342 kB / 0.9726 GB, free: 0.7695 MB / 0.5919 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000665/1.459, allocations: 175.4 kB / 0.9728 GB, free: 0.5977 MB / 0.5919 GB
Notification: Performance of SimCode: time 1.122e-06/1.459, allocations: 0 / 0.9728 GB, free: 0.5977 MB / 0.5919 GB
Notification: Performance of Templates: time 0.1399/1.599, allocations: 84.95 MB / 1.056 GB, free: 11.64 MB / 0.6856 GB
"
[Timeout remaining time 658]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI  -abortSlowSimulation -alarm=480 -s gbode -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 9.39570106798783]
Failed to read output from testmodel.py, exit status != 0:
1.6089389170520008 1.624203831 1.29661329
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fb6dfe29810>