Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-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.07893/0.07893, allocations: 12.77 MB / 29.12 MB, free: 220 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.001235/0.001235, allocations: 119.5 kB / 34.91 MB, free: 1.199 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.358/1.358, allocations: 207.6 MB / 248.2 MB, free: 10.23 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.00158/0.00158, allocations: 184.5 kB / 303.1 MB, free: 5.578 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.4276/0.4276, allocations: 79.72 MB / 437.6 MB, free: 3.953 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.TwoArea.FifthOrder_AVRI,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoArea_FifthOrder_AVRI",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoArea_FifthOrder_AVRI",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.003949/0.003949, allocations: 143.8 kB / 0.5829 GB, free: 7.453 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1323/0.1363, allocations: 72 MB / 0.6532 GB, free: 15.35 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI): time 0.0226/0.1589, allocations: 21.87 MB / 0.6746 GB, free: 9.383 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01218/0.171, allocations: 4.865 MB / 0.6793 GB, free: 4.496 MB / 478.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004032/0.1751, allocations: 44 kB / 0.6794 GB, free: 4.453 MB / 478.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003607/0.1787, allocations: 0.8828 MB / 0.6802 GB, free: 3.562 MB / 478.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr16.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr67.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr78.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr89.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr49.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr528.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr2829.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr2930.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr3031.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr231.m <> 1.0, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeBindings: time 0.008675/0.1874, allocations: 2.297 MB / 0.6825 GB, free: 1.258 MB / 478.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006203/0.1936, allocations: 2.091 MB / 0.6845 GB, free: 15.2 MB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0101/0.2037, allocations: 5.717 MB / 0.6901 GB, free: 9.457 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002878/0.2065, allocations: 0.9151 MB / 0.691 GB, free: 8.488 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004157/0.2107, allocations: 2.322 MB / 0.6933 GB, free: 6.156 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004828/0.2155, allocations: 2.975 MB / 0.6962 GB, free: 3.168 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001829/0.2174, allocations: 440 kB / 0.6966 GB, free: 2.738 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002385/0.2197, allocations: 0.578 MB / 0.6971 GB, free: 2.16 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002017/0.2218, allocations: 1.167 MB / 0.6983 GB, free: 0.9883 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.2771/0.4988, allocations: 2.609 MB / 0.7008 GB, free: 12.04 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.008398/0.5072, allocations: 6.266 MB / 0.707 GB, free: 11.6 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.881e-06/0.5072, allocations: 1.438 kB / 0.707 GB, free: 11.6 MB / 494.1 MB
Notification: Performance of FrontEnd: time 2.034e-06/0.5072, allocations: 0 / 0.707 GB, free: 11.6 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 0.0002511/0.5075, allocations: 4.406 kB / 0.707 GB, free: 11.6 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: 891
 * Number of variables: 891
Notification: Performance of Generate backend data structure: time 0.03345/0.5409, allocations: 9.321 MB / 0.7161 GB, free: 8.895 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.303e-05/0.541, allocations: 17.44 kB / 0.7161 GB, free: 8.891 MB / 494.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0006456/0.5416, allocations: 490.8 kB / 0.7165 GB, free: 8.832 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02541/0.567, allocations: 4.037 MB / 0.7205 GB, free: 8.43 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006079/0.5731, allocations: 2.717 MB / 0.7231 GB, free: 7.559 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000783/0.5739, allocations: 0.9307 MB / 0.7241 GB, free: 6.895 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001162/0.5751, allocations: 344 kB / 0.7244 GB, free: 6.895 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.008984/0.584, allocations: 4.749 MB / 0.729 GB, free: 5.496 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.444e-05/0.5841, allocations: 0 / 0.729 GB, free: 5.496 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003682/0.5845, allocations: 141.5 kB / 0.7292 GB, free: 5.387 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.303e-05/0.5845, allocations: 83.62 kB / 0.7292 GB, free: 5.387 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007395/0.5919, allocations: 4.27 MB / 0.7334 GB, free: 3.359 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.03295/0.6249, allocations: 18.33 MB / 0.7513 GB, free: 4.699 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01236/0.6372, allocations: 4.625 MB / 0.7558 GB, free: 0.6758 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006065/0.6433, allocations: 2.49 MB / 0.7582 GB, free: 14.18 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006162/0.6439, allocations: 73 kB / 0.7583 GB, free: 14.12 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.861e-05/0.644, allocations: 84.95 kB / 0.7584 GB, free: 14.03 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=381): time 7.665e-06/0.644, allocations: 2.625 kB / 0.7584 GB, free: 14.03 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=381): time 0.305/0.949, allocations: 66.18 MB / 0.823 GB, free: 11.89 MB / 0.5762 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001323/0.9492, allocations: 167.5 kB / 0.8232 GB, free: 11.69 MB / 0.5762 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005489/0.9547, allocations: 3.246 MB / 0.8264 GB, free: 8.426 MB / 0.5762 GB
Notification: Performance of collectPreVariables (initialization): time 0.001094/0.9558, allocations: 89.7 kB / 0.8264 GB, free: 8.332 MB / 0.5762 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001952/0.9577, allocations: 2.016 MB / 0.8284 GB, free: 6.316 MB / 0.5762 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002147/0.9599, allocations: 1.366 MB / 0.8297 GB, free: 4.965 MB / 0.5762 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001563/0.9614, allocations: 0.6686 MB / 0.8304 GB, free: 4.285 MB / 0.5762 GB
Notification: Performance of setup shared object (initialization): time 0.0001442/0.9616, allocations: 301.1 kB / 0.8307 GB, free: 3.988 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005762/0.9673, allocations: 2.614 MB / 0.8332 GB, free: 1.352 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006424/0.9737, allocations: 3.228 MB / 0.8364 GB, free: 13.9 MB / 0.5919 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0722/1.046, allocations: 18.98 MB / 0.8549 GB, free: 10.72 MB / 0.6075 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.177e-05/1.046, allocations: 8 kB / 0.8549 GB, free: 10.71 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=532) (initialization): time 0.05953/1.105, allocations: 17.15 MB / 0.8717 GB, free: 9.711 MB / 0.6231 GB
Notification: Performance of prepare postOptimizeDAE: time 8.604e-05/1.106, allocations: 67.39 kB / 0.8718 GB, free: 9.633 MB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.456e-05/1.106, allocations: 20 kB / 0.8718 GB, free: 9.613 MB / 0.6231 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02644/1.132, allocations: 7.153 MB / 0.8788 GB, free: 2.457 MB / 0.6231 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003685/1.136, allocations: 0.6667 MB / 0.8794 GB, free: 1.832 MB / 0.6231 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08848/1.224, allocations: 33.32 MB / 0.9119 GB, free: 372 kB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005903/1.23, allocations: 0.6026 MB / 0.9125 GB, free: 15.76 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006582/1.231, allocations: 148 kB / 0.9127 GB, free: 15.61 MB / 0.67 GB
Warning: Assuming fixed start value for the following 5 variables:
         order5.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.delta:VARIABLE(flow=false start = order3.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order3.e1q:VARIABLE(start = order3.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.007406/1.238, allocations: 3.077 MB / 0.9157 GB, free: 12.53 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006372/1.245, allocations: 3.216 MB / 0.9188 GB, free: 9.098 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.07052/1.315, allocations: 18.94 MB / 0.9373 GB, free: 5.953 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.212e-05/1.315, allocations: 11.94 kB / 0.9373 GB, free: 5.941 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=532) (initialization_lambda0): time 0.05749/1.373, allocations: 17.14 MB / 0.9541 GB, free: 4.957 MB / 0.7012 GB
Notification: Performance of prepare postOptimizeDAE: time 7.412e-05/1.373, allocations: 64.44 kB / 0.9541 GB, free: 4.883 MB / 0.7012 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.364e-05/1.373, allocations: 16 kB / 0.9541 GB, free: 4.867 MB / 0.7012 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0258/1.399, allocations: 7.146 MB / 0.9611 GB, free: 13.71 MB / 0.7169 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003659/1.402, allocations: 0.6673 MB / 0.9618 GB, free: 13.09 MB / 0.7169 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3651/1.767, allocations: 33.33 MB / 0.9943 GB, free: 246.1 MB / 0.7169 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00648/1.774, allocations: 0.5664 MB / 0.9949 GB, free: 246 MB / 0.7169 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001215/1.775, allocations: 155 kB / 0.995 GB, free: 246 MB / 0.7169 GB
Warning: Assuming fixed start value for the following 5 variables:
         order5.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order3.delta:VARIABLE(flow=false start = order3.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order3.e1q:VARIABLE(start = order3.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 65
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (397):
 * Single equations (assignments): 394
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(92,44,5.8%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.001213/1.776, allocations: 424.7 kB / 0.9954 GB, free: 245.6 MB / 0.7169 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00165/1.778, allocations: 480.5 kB / 0.9959 GB, free: 245.5 MB / 0.7169 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04946/1.827, allocations: 16.63 MB / 1.012 GB, free: 242.2 MB / 0.7169 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.02e-05/1.827, allocations: 35.47 kB / 1.012 GB, free: 242.2 MB / 0.7169 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.744e-05/1.827, allocations: 35.31 kB / 1.012 GB, free: 242.2 MB / 0.7169 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.362e-05/1.827, allocations: 20.3 kB / 1.012 GB, free: 242.2 MB / 0.7169 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.0562/1.884, allocations: 21.94 MB / 1.034 GB, free: 233.9 MB / 0.7169 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.157e-05/1.884, allocations: 0 / 1.034 GB, free: 233.9 MB / 0.7169 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002281/1.886, allocations: 403.5 kB / 1.034 GB, free: 233.6 MB / 0.7169 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02166/1.908, allocations: 7.24 MB / 1.041 GB, free: 226.3 MB / 0.7169 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001012/1.909, allocations: 67.98 kB / 1.041 GB, free: 226.2 MB / 0.7169 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0657/1.974, allocations: 29.67 MB / 1.07 GB, free: 196.4 MB / 0.7169 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.116e-06/1.974, allocations: 0 / 1.07 GB, free: 196.4 MB / 0.7169 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0549/2.029, allocations: 20.04 MB / 1.09 GB, free: 176.3 MB / 0.7169 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001647/2.031, allocations: 0.5731 MB / 1.09 GB, free: 175.7 MB / 0.7169 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001424/2.032, allocations: 68 kB / 1.09 GB, free: 175.6 MB / 0.7169 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004335/2.037, allocations: 359.6 kB / 1.091 GB, free: 175.3 MB / 0.7169 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002732/2.039, allocations: 0.7688 MB / 1.091 GB, free: 174.5 MB / 0.7169 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003123/2.04, allocations: 91.98 kB / 1.091 GB, free: 174.4 MB / 0.7169 GB
Notification: Performance of sorting global known variables: time 0.002439/2.042, allocations: 2.129 MB / 1.094 GB, free: 172.3 MB / 0.7169 GB
Notification: Performance of sort global known variables: time 1.1e-07/2.042, allocations: 0 / 1.094 GB, free: 172.3 MB / 0.7169 GB
Notification: Performance of remove unused functions: time 0.00812/2.05, allocations: 2.985 MB / 1.096 GB, free: 169.3 MB / 0.7169 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 12 (order3.delta,order3.w,order3.e1q,order5.delta,order5.w,order5.e1q,order5.e2q,order5.e2d,aVRTypeI.firstOrder.y,aVRTypeI.firstOrder2.y,aVRTypeI.transferFunction.x_scaled[1],aVRTypeI.transferFunction.x_scaled[2])
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (251):
 * Single equations (assignments): 249
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(96,40,5.5%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.05451/2.105, allocations: 20.71 MB / 1.117 GB, free: 148.5 MB / 0.7169 GB
Notification: Performance of simCode: created initialization part: time 0.04054/2.145, allocations: 14.9 MB / 1.131 GB, free: 133.5 MB / 0.7169 GB
Notification: Performance of simCode: created event and clocks part: time 7.845e-06/2.145, allocations: 0 / 1.131 GB, free: 133.5 MB / 0.7169 GB
Notification: Performance of simCode: created simulation system equations: time 0.01702/2.162, allocations: 5.716 MB / 1.137 GB, free: 127.8 MB / 0.7169 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00532/2.168, allocations: 386.3 kB / 1.137 GB, free: 127.4 MB / 0.7169 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1377/2.305, allocations: 58.9 MB / 1.195 GB, free: 67.76 MB / 0.7169 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001574/2.307, allocations: 2.503 MB / 1.197 GB, free: 65.22 MB / 0.7169 GB
Notification: Performance of simCode: alias equations: time 0.002159/2.309, allocations: 451 kB / 1.198 GB, free: 64.77 MB / 0.7169 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001974/2.311, allocations: 2.014 MB / 1.2 GB, free: 62.76 MB / 0.7169 GB
Notification: Performance of SimCode: time 1.353e-06/2.311, allocations: 0 / 1.2 GB, free: 62.76 MB / 0.7169 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.4864/2.797, allocations: 156.1 MB / 1.352 GB, free: 189.2 MB / 0.7169 GB
Notification: Performance of buildModelFMU: Generate platform static: time 21.53/24.32, allocations: 11.05 kB / 1.352 GB, free: 189.2 MB / 0.7169 GB
"
[Timeout remaining time 636]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoArea_FifthOrder_AVRI_fmu --startTime=0 --stopTime=20 --stepSize=0.008 --timeout=50 --tolerance=1e-06 OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoArea_FifthOrder_AVRI.fmu > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 28.380623183213174]
Failed to read output from testmodel.py, exit status != 0:
24.48587843310088 24.502069843 23.991434813
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f6d4b1fe020>