Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.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.1231/0.1231, allocations: 12.77 MB / 29.12 MB, free: 248 kB / 19.63 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.001638/0.001638, allocations: 111.5 kB / 34.91 MB, free: 1.23 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.415/1.415, allocations: 207.6 MB / 248.2 MB, free: 10.34 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.001688/0.001688, allocations: 187.9 kB / 303.1 MB, free: 5.59 MB / 238.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.4534/0.4534, allocations: 79.72 MB / 437.6 MB, free: 4.086 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: "" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRIII",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRIII",fmuType="me",version="2.0",platforms={"static"}) [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.004194/0.004194, allocations: 145 kB / 0.5828 GB, free: 7.516 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1448/0.149, allocations: 72 MB / 0.6532 GB, free: 15.41 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII): time 0.01652/0.1655, allocations: 12.99 MB / 0.6658 GB, free: 2.359 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008227/0.1738, allocations: 3.243 MB / 0.669 GB, free: 15.11 MB / 478.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002327/0.1761, allocations: 31.94 kB / 0.669 GB, free: 15.08 MB / 478.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001759/0.1779, allocations: 0.4995 MB / 0.6695 GB, free: 14.57 MB / 478.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006157/0.184, allocations: 1.47 MB / 0.671 GB, free: 13.1 MB / 478.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Machines/PSAT/Order4.mo:25:3-30:9:writable] Warning: In relation order4_1.xq <> order4_1.x1q, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.004557/0.1886, allocations: 1.458 MB / 0.6724 GB, free: 11.66 MB / 478.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006344/0.1949, allocations: 3.297 MB / 0.6756 GB, free: 8.352 MB / 478.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001707/0.1966, allocations: 0.5244 MB / 0.6761 GB, free: 7.789 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002731/0.1994, allocations: 1.369 MB / 0.6775 GB, free: 6.414 MB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003122/0.2025, allocations: 1.812 MB / 0.6792 GB, free: 4.594 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001246/0.2037, allocations: 256 kB / 0.6795 GB, free: 4.344 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001706/0.2054, allocations: 395.9 kB / 0.6798 GB, free: 3.957 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001252/0.2067, allocations: 0.6767 MB / 0.6805 GB, free: 3.277 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002768/0.2094, allocations: 1.499 MB / 0.682 GB, free: 1.77 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00731/0.2168, allocations: 3.621 MB / 0.6855 GB, free: 14.13 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 9.418e-06/0.2168, allocations: 4 kB / 0.6855 GB, free: 14.12 MB / 494.1 MB
Notification: Performance of FrontEnd: time 2.435e-06/0.2168, allocations: 0 / 0.6855 GB, free: 14.12 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 0.0001665/0.2169, allocations: 0 / 0.6855 GB, free: 14.12 MB / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 487
 * Number of variables: 487
Notification: Performance of Generate backend data structure: time 0.02491/0.2418, allocations: 6.202 MB / 0.6916 GB, free: 7.852 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.207e-05/0.2419, allocations: 15.98 kB / 0.6916 GB, free: 7.836 MB / 494.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0007692/0.2427, allocations: 286.3 kB / 0.6919 GB, free: 7.547 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01593/0.2586, allocations: 2.261 MB / 0.6941 GB, free: 5.281 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004281/0.2629, allocations: 1.667 MB / 0.6957 GB, free: 3.547 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0009847/0.2639, allocations: 0.6591 MB / 0.6963 GB, free: 2.789 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0009024/0.2648, allocations: 211.9 kB / 0.6965 GB, free: 2.582 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.2661/0.5309, allocations: 2.885 MB / 0.6994 GB, free: 38.36 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.067e-05/0.5309, allocations: 0 / 0.6994 GB, free: 38.36 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002931/0.5312, allocations: 81.84 kB / 0.6994 GB, free: 38.36 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.238e-05/0.5313, allocations: 47.62 kB / 0.6995 GB, free: 38.36 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007402/0.5387, allocations: 2.666 MB / 0.7021 GB, free: 38.34 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.02132/0.56, allocations: 9.511 MB / 0.7114 GB, free: 33.57 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01185/0.5718, allocations: 3.83 MB / 0.7151 GB, free: 32.23 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004515/0.5764, allocations: 1.689 MB / 0.7168 GB, free: 31.62 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.000394/0.5768, allocations: 40.41 kB / 0.7168 GB, free: 31.61 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.144e-05/0.5768, allocations: 64.58 kB / 0.7169 GB, free: 31.55 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=228): time 5.009e-06/0.5768, allocations: 0 / 0.7169 GB, free: 31.55 MB / 494.1 MB
Notification: Performance of matching and sorting (n=228): time 0.1183/0.6951, allocations: 28.76 MB / 0.7449 GB, free: 15.07 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.371e-05/0.6951, allocations: 128.8 kB / 0.7451 GB, free: 14.92 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002288/0.6974, allocations: 1.984 MB / 0.747 GB, free: 12.93 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0006315/0.698, allocations: 73.64 kB / 0.7471 GB, free: 12.85 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005542/0.6986, allocations: 1.087 MB / 0.7481 GB, free: 11.84 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0008058/0.6994, allocations: 0.8388 MB / 0.749 GB, free: 11.12 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0008535/0.7003, allocations: 0.5102 MB / 0.7495 GB, free: 10.61 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 3.749e-05/0.7003, allocations: 301.1 kB / 0.7497 GB, free: 10.31 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003574/0.7039, allocations: 1.877 MB / 0.7516 GB, free: 8.422 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003879/0.7077, allocations: 2.23 MB / 0.7538 GB, free: 6.094 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.05795/0.7657, allocations: 13.69 MB / 0.7671 GB, free: 8.27 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.757e-05/0.7657, allocations: 3.938 kB / 0.7671 GB, free: 8.266 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=377) (initialization): time 0.04527/0.811, allocations: 12.08 MB / 0.7789 GB, free: 12.14 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 3.596e-05/0.811, allocations: 8 kB / 0.7789 GB, free: 12.13 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.78e-05/0.811, allocations: 8 kB / 0.7789 GB, free: 12.12 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01889/0.8299, allocations: 5.209 MB / 0.784 GB, free: 6.918 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002716/0.8327, allocations: 491.3 kB / 0.7845 GB, free: 6.438 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06835/0.901, allocations: 23.21 MB / 0.8072 GB, free: 15.07 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004082/0.9051, allocations: 444.2 kB / 0.8076 GB, free: 14.7 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007897/0.9059, allocations: 104 kB / 0.8077 GB, free: 14.59 MB / 0.545 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.006946/0.9128, allocations: 2.172 MB / 0.8098 GB, free: 12.43 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005426/0.9182, allocations: 2.206 MB / 0.812 GB, free: 10.12 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05955/0.9778, allocations: 13.63 MB / 0.8253 GB, free: 12.36 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.625e-05/0.9778, allocations: 4 kB / 0.8253 GB, free: 12.36 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=377) (initialization_lambda0): time 0.04467/1.022, allocations: 12.04 MB / 0.837 GB, free: 280 kB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 2.97e-05/1.023, allocations: 8 kB / 0.837 GB, free: 272 kB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.096e-05/1.023, allocations: 8 kB / 0.8371 GB, free: 264 kB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01884/1.041, allocations: 5.213 MB / 0.8421 GB, free: 11.04 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002629/1.044, allocations: 475.3 kB / 0.8426 GB, free: 10.57 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06851/1.113, allocations: 23.21 MB / 0.8653 GB, free: 3.227 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004327/1.117, allocations: 408.1 kB / 0.8657 GB, free: 2.867 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008616/1.118, allocations: 104 kB / 0.8658 GB, free: 2.766 MB / 0.5919 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 (290):
 * Single equations (assignments): 289
 * Array equations: 0
 * Algorithm blocks: 0
 * 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
   {(62,26,8.3%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.001552/1.119, allocations: 340.9 kB / 0.8661 GB, free: 2.426 MB / 0.5919 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001204/1.12, allocations: 286.1 kB / 0.8663 GB, free: 2.145 MB / 0.5919 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0429/1.163, allocations: 11.36 MB / 0.8774 GB, free: 6.754 MB / 0.6075 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.773e-05/1.163, allocations: 29.25 kB / 0.8775 GB, free: 6.727 MB / 0.6075 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.986e-05/1.163, allocations: 24 kB / 0.8775 GB, free: 6.703 MB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.549e-05/1.164, allocations: 11.95 kB / 0.8775 GB, free: 6.691 MB / 0.6075 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.05018/1.214, allocations: 14.87 MB / 0.892 GB, free: 7.668 MB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.667e-05/1.214, allocations: 0 / 0.892 GB, free: 7.668 MB / 0.6231 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001685/1.215, allocations: 247.6 kB / 0.8923 GB, free: 7.426 MB / 0.6231 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01856/1.234, allocations: 5.016 MB / 0.8972 GB, free: 2.402 MB / 0.6231 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006675/1.235, allocations: 40 kB / 0.8972 GB, free: 2.363 MB / 0.6231 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.06046/1.295, allocations: 20.52 MB / 0.9172 GB, free: 13.69 MB / 0.6544 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.469e-06/1.295, allocations: 4 kB / 0.9172 GB, free: 13.68 MB / 0.6544 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.04739/1.343, allocations: 13.64 MB / 0.9306 GB, free: 0 / 0.6544 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009531/1.343, allocations: 386.8 kB / 0.9309 GB, free: 15.6 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009607/1.344, allocations: 44 kB / 0.931 GB, free: 15.56 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003195/1.348, allocations: 251.7 kB / 0.9312 GB, free: 15.31 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00245/1.35, allocations: 0.5535 MB / 0.9318 GB, free: 14.76 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0005299/1.351, allocations: 59.98 kB / 0.9318 GB, free: 14.7 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.002384/1.353, allocations: 1.291 MB / 0.9331 GB, free: 13.4 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1.1e-07/1.353, allocations: 0 / 0.9331 GB, free: 13.4 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.005754/1.359, allocations: 1.83 MB / 0.9349 GB, free: 11.57 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 15 (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.vm,Exc1.vr,Exc1.vf1,Exc2.vm,Exc2.vr,Exc2.vf1)
 * 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 (147):
 * Single equations (assignments): 146
 * Array equations: 0
 * Algorithm blocks: 0
 * 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
   {(62,26,8.3%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.04748/1.406, allocations: 14.28 MB / 0.9488 GB, free: 13.23 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.03385/1.44, allocations: 10.05 MB / 0.9586 GB, free: 3.156 MB / 0.6856 GB
Notification: Performance of simCode: created event and clocks part: time 8.055e-06/1.44, allocations: 0 / 0.9586 GB, free: 3.156 MB / 0.6856 GB
Notification: Performance of simCode: created simulation system equations: time 0.2769/1.717, allocations: 3.703 MB / 0.9622 GB, free: 233.4 MB / 0.6856 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002091/1.719, allocations: 223.9 kB / 0.9625 GB, free: 233.4 MB / 0.6856 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.11/1.829, allocations: 39.75 MB / 1.001 GB, free: 224.1 MB / 0.6856 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001272/1.83, allocations: 1.734 MB / 1.003 GB, free: 223 MB / 0.6856 GB
Notification: Performance of simCode: alias equations: time 0.001425/1.832, allocations: 272.5 kB / 1.003 GB, free: 222.8 MB / 0.6856 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001155/1.833, allocations: 1.104 MB / 1.004 GB, free: 221.8 MB / 0.6856 GB
Notification: Performance of SimCode: time 1.372e-06/1.833, allocations: 4 kB / 1.004 GB, free: 221.8 MB / 0.6856 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1386/1.971, allocations: 101.6 MB / 1.104 GB, free: 120.5 MB / 0.6856 GB
Notification: Performance of buildModelFMU: Generate platform static: time 16.41/18.38, allocations: 0 / 1.104 GB, free: 120.5 MB / 0.6856 GB
"
[Timeout remaining time 641]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRIII_fmu --startTime=0 --stopTime=20 --stepSize=0.008 --timeout=50 --tolerance=1e-06 OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_ThreeArea_FourthOrder_AVRIII.fmu > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 22.239396593999118]
Failed to read output from testmodel.py, exit status != 0:
18.51304404484108 18.528908042 18.308695636
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f574a20a020>