Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo): time 0.08911/0.08911, allocations: 12.36 MB / 32 MB, free: 6.43 MB / 26.18 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001365/0.001365, allocations: 105.1 kB / 38.03 MB, free: 1.109 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.481/1.481, allocations: 207.7 MB / 251.7 MB, free: 7.289 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001572/0.001572, allocations: 200.5 kB / 306.9 MB, free: 304 kB / 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.3139/0.3139, allocations: 79.73 MB / 441.7 MB, free: 12.28 MB / 318.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.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo)
Running command: "" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoAreaOld_FifthOrder_AVRI",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoAreaOld_FifthOrder_AVRI",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Modelica_Synchronous requested package ModelicaServices of version 3.2.3. ModelicaServices 4.1.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.1.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.1.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.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.004009/0.004009, allocations: 144.9 kB / 0.5883 GB, free: 9.359 MB / 398.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1481/0.1521, allocations: 71.96 MB / 0.6585 GB, free: 1.32 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI): time 0.2898/0.4419, allocations: 5.771 MB / 0.6642 GB, free: 8.016 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004255/0.4462, allocations: 1.907 MB / 0.666 GB, free: 7.297 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008429/0.447, allocations: 10.88 kB / 0.6661 GB, free: 7.297 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007624/0.4478, allocations: 249.5 kB / 0.6663 GB, free: 7.172 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00203/0.4498, allocations: 0.8418 MB / 0.6671 GB, free: 6.664 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001355/0.4512, allocations: 0.6626 MB / 0.6678 GB, free: 6.34 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001606/0.4528, allocations: 1.631 MB / 0.6694 GB, free: 5.547 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.000354/0.4531, allocations: 218.8 kB / 0.6696 GB, free: 5.422 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007962/0.4539, allocations: 0.686 MB / 0.6702 GB, free: 5.094 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0008585/0.4548, allocations: 0.8613 MB / 0.6711 GB, free: 4.695 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002183/0.455, allocations: 125.9 kB / 0.6712 GB, free: 4.695 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0005384/0.4556, allocations: 257.4 kB / 0.6714 GB, free: 4.656 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002994/0.4559, allocations: 347.3 kB / 0.6718 GB, free: 4.438 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0004631/0.4563, allocations: 0.5541 MB / 0.6723 GB, free: 4.102 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001765/0.4581, allocations: 1.714 MB / 0.674 GB, free: 3.344 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.621e-06/0.4581, allocations: 0.9844 kB / 0.674 GB, free: 3.344 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.533e-06/0.4581, allocations: 3.938 kB / 0.674 GB, free: 3.34 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 1.785e-05/0.4581, allocations: 4 kB / 0.674 GB, free: 3.336 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 188
 * Number of variables: 188
Notification: Performance of Generate backend data structure: time 0.00851/0.4666, allocations: 2.701 MB / 0.6766 GB, free: 2.105 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.411e-05/0.4667, allocations: 11.03 kB / 0.6766 GB, free: 2.105 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 3.45e-05/0.4667, allocations: 22 kB / 0.6767 GB, free: 2.102 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001293/0.4668, allocations: 129.4 kB / 0.6768 GB, free: 2.012 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004755/0.4716, allocations: 0.7776 MB / 0.6775 GB, free: 1.848 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001539/0.4731, allocations: 0.8623 MB / 0.6784 GB, free: 1.285 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002312/0.4734, allocations: 280.6 kB / 0.6787 GB, free: 1.039 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001698/0.4735, allocations: 86.05 kB / 0.6787 GB, free: 1.008 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002127/0.4756, allocations: 1.157 MB / 0.6799 GB, free: 0.5156 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.416e-05/0.4757, allocations: 0 / 0.6799 GB, free: 0.5156 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.737e-05/0.4758, allocations: 34.03 kB / 0.6799 GB, free: 0.4922 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.346e-05/0.4758, allocations: 22.31 kB / 0.6799 GB, free: 500 kB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001875/0.4777, allocations: 1.037 MB / 0.6809 GB, free: 132 kB / 462.1 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.00686/0.4845, allocations: 2.988 MB / 0.6839 GB, free: 14.57 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.007572/0.4921, allocations: 2.007 MB / 0.6858 GB, free: 14.12 MB / 478.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00158/0.4937, allocations: 0.6684 MB / 0.6865 GB, free: 13.84 MB / 478.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001043/0.4938, allocations: 19.7 kB / 0.6865 GB, free: 13.83 MB / 478.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.684e-05/0.4938, allocations: 39.94 kB / 0.6865 GB, free: 13.78 MB / 478.1 MB
Notification: Performance of pre-optimization done (n=94): time 4.729e-06/0.4938, allocations: 1.5 kB / 0.6865 GB, free: 13.78 MB / 478.1 MB
Notification: Performance of matching and sorting (n=94): time 0.02663/0.5205, allocations: 6.675 MB / 0.693 GB, free: 10.45 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001315/0.5206, allocations: 148.7 kB / 0.6932 GB, free: 10.28 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00266/0.5232, allocations: 1.123 MB / 0.6943 GB, free: 9.648 MB / 478.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002797/0.5235, allocations: 45.86 kB / 0.6943 GB, free: 9.602 MB / 478.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005652/0.5241, allocations: 0.5052 MB / 0.6948 GB, free: 9.148 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0005985/0.5247, allocations: 363 kB / 0.6952 GB, free: 8.836 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009272/0.5256, allocations: 277.9 kB / 0.6954 GB, free: 8.672 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001287/0.5257, allocations: 302.2 kB / 0.6957 GB, free: 8.375 MB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001933/0.5277, allocations: 0.8362 MB / 0.6965 GB, free: 7.906 MB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002088/0.5298, allocations: 1.029 MB / 0.6975 GB, free: 7.246 MB / 478.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02583/0.5556, allocations: 5.786 MB / 0.7032 GB, free: 4.234 MB / 478.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.764e-05/0.5556, allocations: 1.344 kB / 0.7032 GB, free: 4.234 MB / 478.1 MB
Notification: Performance of matching and sorting (n=190) (initialization): time 0.01528/0.5709, allocations: 4.461 MB / 0.7075 GB, free: 0.9453 MB / 478.1 MB
Notification: Performance of prepare postOptimizeDAE: time 8.49e-05/0.571, allocations: 63.39 kB / 0.7076 GB, free: 0.8711 MB / 478.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.665e-05/0.571, allocations: 0 / 0.7076 GB, free: 0.8711 MB / 478.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006116/0.5771, allocations: 1.624 MB / 0.7092 GB, free: 15.25 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001604/0.5787, allocations: 271.6 kB / 0.7095 GB, free: 14.98 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0214/0.6001, allocations: 8.345 MB / 0.7176 GB, free: 6.508 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002308/0.6024, allocations: 195.2 kB / 0.7178 GB, free: 6.316 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003765/0.6028, allocations: 57.16 kB / 0.7178 GB, free: 6.262 MB / 494.1 MB
Warning: Assuming fixed start value for the following 5 variables:
         order5.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.e1q:VARIABLE(start = order5.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.002759/0.6056, allocations: 1.022 MB / 0.7188 GB, free: 5.227 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002136/0.6077, allocations: 1.014 MB / 0.7198 GB, free: 4.156 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02541/0.6331, allocations: 5.743 MB / 0.7254 GB, free: 14.32 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.066e-05/0.6331, allocations: 4 kB / 0.7254 GB, free: 14.32 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=190) (initialization_lambda0): time 0.01484/0.648, allocations: 4.433 MB / 0.7298 GB, free: 9.848 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 7.48e-05/0.648, allocations: 64.03 kB / 0.7298 GB, free: 9.773 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.055e-05/0.6481, allocations: 4 kB / 0.7298 GB, free: 9.77 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006051/0.6541, allocations: 1.625 MB / 0.7314 GB, free: 8.141 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00153/0.6556, allocations: 271.6 kB / 0.7317 GB, free: 7.875 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02123/0.6769, allocations: 8.328 MB / 0.7398 GB, free: 15.41 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002278/0.6791, allocations: 183 kB / 0.74 GB, free: 15.23 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000396/0.6795, allocations: 55.97 kB / 0.74 GB, free: 15.18 MB / 0.5137 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
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true )  \"Rotor angle\" type: Real
         order5.e1q:VARIABLE(start = order5.e1q0 unit = \"1\" fixed = true )  \"q-axis transient voltage\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 13
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (157):
 * Single equations (assignments): 154
 * 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
   {(19,15,24.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0008311/0.6804, allocations: 302.3 kB / 0.7403 GB, free: 14.88 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005919/0.681, allocations: 131.2 kB / 0.7405 GB, free: 14.75 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01293/0.6939, allocations: 3.792 MB / 0.7442 GB, free: 10.92 MB / 0.5137 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.742e-05/0.6939, allocations: 15.94 kB / 0.7442 GB, free: 10.91 MB / 0.5137 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.27e-05/0.6939, allocations: 5.312 kB / 0.7442 GB, free: 10.9 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.844e-05/0.694, allocations: 7.922 kB / 0.7442 GB, free: 10.89 MB / 0.5137 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.01593/0.7099, allocations: 4.978 MB / 0.749 GB, free: 5.852 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.476e-05/0.7099, allocations: 4 kB / 0.7491 GB, free: 5.848 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000854/0.7108, allocations: 107.9 kB / 0.7492 GB, free: 5.742 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.005445/0.7162, allocations: 1.516 MB / 0.7506 GB, free: 4.223 MB / 0.5137 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002253/0.7164, allocations: 27.94 kB / 0.7507 GB, free: 4.195 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01798/0.7344, allocations: 7.114 MB / 0.7576 GB, free: 12.96 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.645e-06/0.7344, allocations: 0 / 0.7576 GB, free: 12.96 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01457/0.749, allocations: 4.695 MB / 0.7622 GB, free: 8.223 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006645/0.7497, allocations: 162.8 kB / 0.7624 GB, free: 8.062 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005075/0.7502, allocations: 16 kB / 0.7624 GB, free: 8.047 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001543/0.7517, allocations: 111.9 kB / 0.7625 GB, free: 7.938 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007062/0.7524, allocations: 188.6 kB / 0.7627 GB, free: 7.754 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001432/0.7526, allocations: 23.98 kB / 0.7627 GB, free: 7.73 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.001656/0.7542, allocations: 0.7553 MB / 0.7634 GB, free: 6.969 MB / 0.5294 GB
Notification: Performance of sort global known variables: time 1.21e-07/0.7542, allocations: 0 / 0.7634 GB, free: 6.969 MB / 0.5294 GB
Notification: Performance of remove unused functions: time 0.003238/0.7574, allocations: 0.7806 MB / 0.7642 GB, free: 6.188 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 11 (order2.delta,order2.w,order5.delta,order5.w,order5.e1q,order5.e2q,order5.e2d,aVRTypeI.firstOrder.y,aVRTypeI.firstOrder2.y,aVRTypeI.transferFunction.x_scaled[1],aVRTypeI.transferFunction.x_scaled[2])
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (68):
 * Single equations (assignments): 66
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(19,13,24.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.01466/0.7721, allocations: 5.287 MB / 0.7693 GB, free: 0.8516 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.01262/0.7847, allocations: 4.316 MB / 0.7736 GB, free: 12.44 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 4.989e-06/0.7847, allocations: 4 kB / 0.7736 GB, free: 12.43 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.004272/0.789, allocations: 1.417 MB / 0.7749 GB, free: 10.98 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001611/0.7906, allocations: 124 kB / 0.7751 GB, free: 10.87 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0448/0.8354, allocations: 16.66 MB / 0.7913 GB, free: 9.945 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008495/0.8363, allocations: 0.5454 MB / 0.7919 GB, free: 9.363 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.001043/0.8373, allocations: 157.7 kB / 0.792 GB, free: 9.207 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0009523/0.8383, allocations: 487.3 kB / 0.7925 GB, free: 8.73 MB / 0.5606 GB
Notification: Performance of SimCode: time 1.373e-06/0.8383, allocations: 0 / 0.7925 GB, free: 8.73 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.09621/0.9345, allocations: 44.07 MB / 0.8355 GB, free: 12.81 MB / 0.6075 GB
Notification: Performance of buildModelFMU: Generate platform static: time 14.21/15.15, allocations: 4.062 kB / 0.8355 GB, free: 12.81 MB / 0.6075 GB
"
[Timeout remaining time 645]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoAreaOld_FifthOrder_AVRI_fmu --startTime=0 --stopTime=20 --stepSize=0.008 --timeout=50 --tolerance=1e-06 OpenIPSL_2_0_0_OpenIPSL_Examples_PSATSystems_TwoAreaOld_FifthOrder_AVRI.fmu > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 18.645436023012735]
Failed to read output from testmodel.py, exit status != 0:
15.328921339008957 15.350313789 14.888144255
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f81d33fb3a0>