Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI.conf.json
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.001514/0.001514, allocations: 107.2 kB / 16.45 MB, free: 6.035 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.451/1.451, allocations: 225.6 MB / 244.8 MB, free: 14.16 MB / 206.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.001483/0.001483, allocations: 205.5 kB / 299.8 MB, free: 9.812 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5902/0.5902, allocations: 99.65 MB / 454.4 MB, free: 5.031 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/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 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 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: "" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRI",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRI",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002462/0.002462, allocations: 83.88 kB / 0.6074 GB, free: 9.426 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1738/0.1763, allocations: 76.94 MB / 0.6825 GB, free: 12.33 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI): time 0.3952/0.5715, allocations: 13.55 MB / 0.6958 GB, free: 8.031 MB / 494.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008601/0.5801, allocations: 3.509 MB / 0.6992 GB, free: 7.969 MB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003168/0.5833, allocations: 27.88 kB / 0.6992 GB, free: 7.969 MB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002014/0.5853, allocations: 0.5634 MB / 0.6998 GB, free: 7.738 MB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00593/0.5912, allocations: 1.541 MB / 0.7013 GB, free: 7.223 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004222/0.5955, allocations: 1.562 MB / 0.7028 GB, free: 6.781 MB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006889/0.6023, allocations: 3.663 MB / 0.7064 GB, free: 5.043 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001121/0.6035, allocations: 0.5809 MB / 0.7069 GB, free: 4.711 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002779/0.6062, allocations: 1.479 MB / 0.7084 GB, free: 4.02 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002946/0.6092, allocations: 1.979 MB / 0.7103 GB, free: 3.16 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007804/0.61, allocations: 275.8 kB / 0.7106 GB, free: 3.16 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001304/0.6113, allocations: 423.1 kB / 0.711 GB, free: 3.121 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.000722/0.612, allocations: 0.761 MB / 0.7117 GB, free: 2.66 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0023/0.6143, allocations: 1.612 MB / 0.7133 GB, free: 1.602 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00741/0.6217, allocations: 3.948 MB / 0.7172 GB, free: 0 / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 9.418e-06/0.6217, allocations: 0.9844 kB / 0.7172 GB, free: 0 / 494.1 MB
Notification: Performance of FrontEnd: time 2.845e-06/0.6217, allocations: 1.25 kB / 0.7172 GB, free: 0 / 494.1 MB
Notification: Performance of Transformations before backend: time 0.0001946/0.6219, allocations: 0.5156 kB / 0.7172 GB, free: 0 / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 517
 * Number of variables: 517
Notification: Performance of Generate backend data structure: time 0.0294/0.6513, allocations: 6.641 MB / 0.7236 GB, free: 13.17 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 5.529e-05/0.6514, allocations: 10.03 kB / 0.7237 GB, free: 13.17 MB / 0.4981 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001174/0.6525, allocations: 300.1 kB / 0.7239 GB, free: 12.94 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01684/0.6694, allocations: 2.295 MB / 0.7262 GB, free: 12.03 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006422/0.6758, allocations: 1.897 MB / 0.728 GB, free: 10.61 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001047/0.6768, allocations: 0.6718 MB / 0.7287 GB, free: 9.984 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001283/0.6781, allocations: 216.6 kB / 0.7289 GB, free: 9.91 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01022/0.6883, allocations: 3.04 MB / 0.7319 GB, free: 8.133 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 9.596e-05/0.6884, allocations: 10.84 kB / 0.7319 GB, free: 8.125 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007105/0.6892, allocations: 85.44 kB / 0.732 GB, free: 8.059 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.807e-05/0.6892, allocations: 48.28 kB / 0.732 GB, free: 8.059 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008167/0.6974, allocations: 2.804 MB / 0.7347 GB, free: 6.719 MB / 0.4981 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.02648/0.7239, allocations: 9.937 MB / 0.7444 GB, free: 15.05 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0114/0.7353, allocations: 3.875 MB / 0.7482 GB, free: 12.88 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00483/0.7401, allocations: 1.79 MB / 0.75 GB, free: 11.27 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004413/0.7405, allocations: 50.34 kB / 0.75 GB, free: 11.23 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.241e-05/0.7406, allocations: 61.16 kB / 0.7501 GB, free: 11.16 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=242): time 7.674e-06/0.7406, allocations: 0 / 0.7501 GB, free: 11.16 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=242): time 0.1316/0.8722, allocations: 29.39 MB / 0.7788 GB, free: 13.77 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002167/0.8725, allocations: 235.6 kB / 0.779 GB, free: 13.49 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00582/0.8783, allocations: 2.265 MB / 0.7812 GB, free: 11.21 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.000939/0.8792, allocations: 73.7 kB / 0.7813 GB, free: 11.14 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0015/0.8807, allocations: 1.169 MB / 0.7824 GB, free: 9.965 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002025/0.8827, allocations: 0.8904 MB / 0.7833 GB, free: 9.086 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00306/0.8858, allocations: 0.5457 MB / 0.7838 GB, free: 8.531 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001589/0.886, allocations: 301.1 kB / 0.7841 GB, free: 8.234 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005334/0.8913, allocations: 1.952 MB / 0.786 GB, free: 6.273 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005658/0.897, allocations: 2.31 MB / 0.7883 GB, free: 3.863 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.05887/0.9558, allocations: 13.98 MB / 0.8019 GB, free: 5.668 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.103e-05/0.9558, allocations: 4 kB / 0.8019 GB, free: 5.664 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=397) (initialization): time 0.04667/1.003, allocations: 12.38 MB / 0.814 GB, free: 9.215 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000149/1.003, allocations: 114.8 kB / 0.8141 GB, free: 9.078 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.565e-05/1.003, allocations: 12 kB / 0.8141 GB, free: 9.066 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01959/1.022, allocations: 5.27 MB / 0.8193 GB, free: 3.797 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003229/1.026, allocations: 0.5344 MB / 0.8198 GB, free: 3.262 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06984/1.095, allocations: 23.21 MB / 0.8425 GB, free: 11.9 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005562/1.101, allocations: 477.8 kB / 0.8429 GB, free: 11.43 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001055/1.102, allocations: 112 kB / 0.843 GB, free: 11.32 MB / 0.6075 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
         order4_1.delta:VARIABLE(flow=false start = order4_1.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order4_1.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.007579/1.11, allocations: 2.282 MB / 0.8453 GB, free: 9.031 MB / 0.6075 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005747/1.115, allocations: 2.283 MB / 0.8475 GB, free: 6.648 MB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05819/1.173, allocations: 13.92 MB / 0.8611 GB, free: 8.512 MB / 0.6231 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.64e-05/1.174, allocations: 4 kB / 0.8611 GB, free: 8.508 MB / 0.6231 GB
Notification: Performance of matching and sorting (n=397) (initialization_lambda0): time 0.04622/1.22, allocations: 12.33 MB / 0.8731 GB, free: 12.11 MB / 0.6387 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001291/1.22, allocations: 114.8 kB / 0.8732 GB, free: 11.97 MB / 0.6387 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.779e-05/1.22, allocations: 8 kB / 0.8733 GB, free: 11.96 MB / 0.6387 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0194/1.239, allocations: 5.279 MB / 0.8784 GB, free: 6.68 MB / 0.6387 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003072/1.242, allocations: 0.5188 MB / 0.8789 GB, free: 6.16 MB / 0.6387 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06799/1.31, allocations: 23.21 MB / 0.9016 GB, free: 14.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005031/1.315, allocations: 442.2 kB / 0.902 GB, free: 14.36 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008533/1.316, allocations: 116 kB / 0.9021 GB, free: 14.25 MB / 0.67 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
         order4_1.delta:VARIABLE(flow=false start = order4_1.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order4_1.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 (310):
 * Single equations (assignments): 305
 * 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.001575/1.318, allocations: 397.5 kB / 0.9025 GB, free: 13.84 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001466/1.319, allocations: 306 kB / 0.9028 GB, free: 13.54 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.042/1.361, allocations: 11.6 MB / 0.9141 GB, free: 1.891 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.585e-05/1.361, allocations: 24 kB / 0.9141 GB, free: 1.867 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.76e-05/1.361, allocations: 24 kB / 0.9142 GB, free: 1.844 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.316e-05/1.361, allocations: 11.95 kB / 0.9142 GB, free: 1.832 MB / 0.67 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.05195/1.413, allocations: 15.37 MB / 0.9292 GB, free: 2.383 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.984e-05/1.413, allocations: 8.062 kB / 0.9292 GB, free: 2.375 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001872/1.415, allocations: 271.6 kB / 0.9294 GB, free: 2.109 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01822/1.434, allocations: 5.048 MB / 0.9344 GB, free: 13.05 MB / 0.7012 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0008832/1.434, allocations: 51.98 kB / 0.9344 GB, free: 13 MB / 0.7012 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.05973/1.494, allocations: 20.5 MB / 0.9544 GB, free: 8.363 MB / 0.7169 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.763e-06/1.494, allocations: 4 kB / 0.9544 GB, free: 8.359 MB / 0.7169 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.04801/1.542, allocations: 13.97 MB / 0.9681 GB, free: 10.33 MB / 0.7325 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001481/1.544, allocations: 422.4 kB / 0.9685 GB, free: 9.895 MB / 0.7325 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001418/1.545, allocations: 44 kB / 0.9685 GB, free: 9.852 MB / 0.7325 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003966/1.549, allocations: 283.7 kB / 0.9688 GB, free: 9.574 MB / 0.7325 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002603/1.552, allocations: 0.5529 MB / 0.9693 GB, free: 9.02 MB / 0.7325 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0005766/1.552, allocations: 63.94 kB / 0.9694 GB, free: 8.961 MB / 0.7325 GB
Notification: Performance of sorting global known variables: time 0.003473/1.556, allocations: 1.503 MB / 0.9709 GB, free: 7.453 MB / 0.7325 GB
Notification: Performance of sort global known variables: time 1.2e-07/1.556, allocations: 0 / 0.9709 GB, free: 7.453 MB / 0.7325 GB
Notification: Performance of remove unused functions: time 0.008443/1.564, allocations: 1.9 MB / 0.9727 GB, free: 5.551 MB / 0.7325 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 17 (order3_2.delta,order3_2.w,order3_2.e1q,order2.delta,order2.w,order4_1.delta,order4_1.w,order4_1.e1q,order4_1.e1d,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 (163):
 * Single equations (assignments): 160
 * 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.4211/1.985, allocations: 14.6 MB / 0.987 GB, free: 248.2 MB / 0.7325 GB
Notification: Performance of simCode: created initialization part: time 0.03582/2.021, allocations: 10.73 MB / 0.9975 GB, free: 247 MB / 0.7325 GB
Notification: Performance of simCode: created event and clocks part: time 9.347e-06/2.021, allocations: 5.562 kB / 0.9975 GB, free: 247 MB / 0.7325 GB
Notification: Performance of simCode: created simulation system equations: time 0.01344/2.034, allocations: 3.902 MB / 1.001 GB, free: 246.2 MB / 0.7325 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004653/2.039, allocations: 275.8 kB / 1.002 GB, free: 246 MB / 0.7325 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.123/2.162, allocations: 41.47 MB / 1.042 GB, free: 223.5 MB / 0.7325 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00233/2.164, allocations: 1.84 MB / 1.044 GB, free: 221.7 MB / 0.7325 GB
Notification: Performance of simCode: alias equations: time 0.002504/2.167, allocations: 342 kB / 1.044 GB, free: 221.3 MB / 0.7325 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002029/2.169, allocations: 1.214 MB / 1.045 GB, free: 220.1 MB / 0.7325 GB
Notification: Performance of SimCode: time 1.503e-06/2.169, allocations: 0 / 1.045 GB, free: 220.1 MB / 0.7325 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1818/2.351, allocations: 106.1 MB / 1.149 GB, free: 114.3 MB / 0.7325 GB
Notification: Performance of buildModelFMU: Generate platform static: time 17.01/19.36, allocations: 2.234 kB / 1.149 GB, free: 114.3 MB / 0.7325 GB
"
[Timeout remaining time 641]
(rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRI_fmu --startTime=0 --stopTime=20 --stepSize=0.001 --timeout=50 --tolerance=1e-06 OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRI.fmu > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRI.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 25.94594227289781]
Failed to read output from testmodel.py, exit status != 0:
19.49254460912198 19.501270278 18.877228456
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fc97574e020>