Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI.conf.json
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.001451/0.001451, allocations: 101.5 kB / 19.73 MB, free: 2.23 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.577/1.577, allocations: 230.6 MB / 253.4 MB, free: 14.41 MB / 206.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.001827/0.001827, allocations: 208.5 kB / 310 MB, free: 3.07 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.5221/0.5221, allocations: 79.73 MB / 446.2 MB, free: 38.89 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+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)
Running command: "" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI,fileNamePrefix="OpenIPSL_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRI",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI,fileNamePrefix="OpenIPSL_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRI",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: OpenIPSL requested package Complex of version 4.0.0. Complex 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: OpenIPSL requested package Modelica of version 4.0.0. Modelica 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.002466/0.002466, allocations: 83.44 kB / 0.5938 GB, free: 38.6 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1601/0.1626, allocations: 73.3 MB / 0.6654 GB, free: 0.4922 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI): time 0.02361/0.1862, allocations: 21.9 MB / 0.6868 GB, free: 11.97 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01476/0.2009, allocations: 4.975 MB / 0.6916 GB, free: 6.973 MB / 478.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.006087/0.207, allocations: 43.94 kB / 0.6917 GB, free: 6.93 MB / 478.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004237/0.2113, allocations: 0.8867 MB / 0.6925 GB, free: 6.035 MB / 478.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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 3.0.1/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 3.0.1/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 3.0.1/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 3.0.1/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 3.0.1/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 3.0.1/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 3.0.1/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 3.0.1/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 3.0.1/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.01203/0.2233, allocations: 2.323 MB / 0.6948 GB, free: 3.703 MB / 478.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00807/0.2314, allocations: 2.1 MB / 0.6969 GB, free: 1.637 MB / 478.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01333/0.2447, allocations: 5.795 MB / 0.7025 GB, free: 11.82 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003568/0.2483, allocations: 0.9117 MB / 0.7034 GB, free: 10.85 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005992/0.2543, allocations: 2.396 MB / 0.7058 GB, free: 8.445 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006402/0.2607, allocations: 2.986 MB / 0.7087 GB, free: 5.445 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002873/0.2635, allocations: 451.9 kB / 0.7091 GB, free: 5.004 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003577/0.2671, allocations: 0.6093 MB / 0.7097 GB, free: 4.395 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002916/0.27, allocations: 1.167 MB / 0.7108 GB, free: 3.223 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.006154/0.2762, allocations: 2.625 MB / 0.7134 GB, free: 0.582 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01457/0.2908, allocations: 6.313 MB / 0.7196 GB, free: 10.24 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 8.335e-06/0.2908, allocations: 0 / 0.7196 GB, free: 10.24 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 2.675e-06/0.2908, allocations: 0 / 0.7196 GB, free: 10.24 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 0.000383/0.2911, allocations: 0 / 0.7196 GB, free: 10.24 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 892
 * Number of variables: 892
Notification: Performance of Generate backend data structure: time 0.03916/0.3303, allocations: 9.309 MB / 0.7287 GB, free: 0.8516 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 6.672e-05/0.3304, allocations: 8.031 kB / 0.7287 GB, free: 0.8438 MB / 0.4981 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0001611/0.3305, allocations: 86.52 kB / 0.7287 GB, free: 0.7578 MB / 0.4981 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002033/0.3326, allocations: 488.8 kB / 0.7292 GB, free: 276 kB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02856/0.3611, allocations: 4.047 MB / 0.7332 GB, free: 12.21 MB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009146/0.3703, allocations: 2.602 MB / 0.7357 GB, free: 9.523 MB / 0.5137 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001694/0.372, allocations: 0.9823 MB / 0.7367 GB, free: 8.398 MB / 0.5137 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002243/0.3742, allocations: 352 kB / 0.737 GB, free: 8.055 MB / 0.5137 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01803/0.3922, allocations: 4.755 MB / 0.7416 GB, free: 3.266 MB / 0.5137 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001694/0.3924, allocations: 0 / 0.7416 GB, free: 3.266 MB / 0.5137 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001432/0.3938, allocations: 140 kB / 0.7418 GB, free: 3.129 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001071/0.3939, allocations: 88 kB / 0.7419 GB, free: 3.043 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0135/0.4075, allocations: 4.279 MB / 0.746 GB, free: 14.76 MB / 0.5294 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.04909/0.4565, allocations: 18.17 MB / 0.7638 GB, free: 11.39 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01442/0.471, allocations: 4.348 MB / 0.768 GB, free: 7.035 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007386/0.4784, allocations: 2.501 MB / 0.7705 GB, free: 4.523 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0007734/0.4791, allocations: 70.92 kB / 0.7705 GB, free: 4.453 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.015e-05/0.4792, allocations: 77.5 kB / 0.7706 GB, free: 4.367 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=382): time 7.384e-06/0.4792, allocations: 0 / 0.7706 GB, free: 4.367 MB / 0.545 GB
Notification: Performance of matching and sorting (n=382): time 0.6819/1.161, allocations: 67.03 MB / 0.8361 GB, free: 113.3 MB / 0.5762 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.915e-05/1.161, allocations: 170.4 kB / 0.8362 GB, free: 113.1 MB / 0.5762 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004143/1.165, allocations: 3.258 MB / 0.8394 GB, free: 112.1 MB / 0.5762 GB
Notification: Performance of collectPreVariables (initialization): time 0.001078/1.166, allocations: 99.97 kB / 0.8395 GB, free: 112 MB / 0.5762 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00116/1.168, allocations: 2.005 MB / 0.8415 GB, free: 110.6 MB / 0.5762 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001731/1.169, allocations: 1.38 MB / 0.8428 GB, free: 109.6 MB / 0.5762 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001358/1.171, allocations: 0.6708 MB / 0.8435 GB, free: 109.2 MB / 0.5762 GB
Notification: Performance of setup shared object (initialization): time 4.935e-05/1.171, allocations: 301.1 kB / 0.8438 GB, free: 108.9 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005265/1.176, allocations: 2.628 MB / 0.8463 GB, free: 107.1 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005809/1.182, allocations: 3.245 MB / 0.8495 GB, free: 103.7 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.06973/1.251, allocations: 19.34 MB / 0.8684 GB, free: 84.07 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.536e-05/1.252, allocations: 4 kB / 0.8684 GB, free: 84.06 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=536) (initialization): time 0.05769/1.309, allocations: 17.08 MB / 0.8851 GB, free: 66.94 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 7.287e-05/1.309, allocations: 63.41 kB / 0.8851 GB, free: 66.87 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.897e-05/1.309, allocations: 16 kB / 0.8851 GB, free: 66.85 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02616/1.335, allocations: 7.295 MB / 0.8923 GB, free: 59.55 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003532/1.339, allocations: 0.6603 MB / 0.8929 GB, free: 58.89 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.09058/1.43, allocations: 33.83 MB / 0.9259 GB, free: 24.98 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00646/1.436, allocations: 0.6072 MB / 0.9265 GB, free: 24.46 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001146/1.437, allocations: 148 kB / 0.9267 GB, free: 24.31 MB / 0.5762 GB
Warning: Assuming fixed start value for the following 4 variables:
         order6.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
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008511/1.446, allocations: 3.088 MB / 0.9297 GB, free: 21.21 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006363/1.452, allocations: 3.226 MB / 0.9328 GB, free: 17.77 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.07093/1.523, allocations: 19.3 MB / 0.9517 GB, free: 14.21 MB / 0.5919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.918e-05/1.523, allocations: 4 kB / 0.9517 GB, free: 14.21 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=536) (initialization_lambda0): time 0.06691/1.59, allocations: 17.05 MB / 0.9683 GB, free: 13.12 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001033/1.59, allocations: 71.34 kB / 0.9684 GB, free: 13.04 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.16e-05/1.59, allocations: 16 kB / 0.9684 GB, free: 13.02 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.03034/1.62, allocations: 7.303 MB / 0.9756 GB, free: 5.707 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003834/1.624, allocations: 0.6514 MB / 0.9762 GB, free: 5.055 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.104/1.728, allocations: 33.81 MB / 1.009 GB, free: 3.16 MB / 0.6387 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006433/1.735, allocations: 0.567 MB / 1.01 GB, free: 2.66 MB / 0.6387 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008886/1.736, allocations: 159.9 kB / 1.01 GB, free: 2.504 MB / 0.6387 GB
Warning: Assuming fixed start value for the following 4 variables:
         order6.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
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 (399):
 * Single equations (assignments): 396
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(92,46)}
Notification: Performance of prepare postOptimizeDAE: time 0.001212/1.737, allocations: 430.8 kB / 1.01 GB, free: 2.082 MB / 0.6387 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001469/1.738, allocations: 480.9 kB / 1.011 GB, free: 1.609 MB / 0.6387 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05784/1.796, allocations: 16.65 MB / 1.027 GB, free: 0.9062 MB / 0.6544 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.345e-05/1.796, allocations: 36 kB / 1.027 GB, free: 0.8711 MB / 0.6544 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001156/1.796, allocations: 32 kB / 1.027 GB, free: 0.8398 MB / 0.6544 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001306/1.796, allocations: 20.95 kB / 1.027 GB, free: 0.8203 MB / 0.6544 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.07553/1.872, allocations: 21.94 MB / 1.049 GB, free: 10.86 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.567e-05/1.872, allocations: 10.11 kB / 1.049 GB, free: 10.86 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002825/1.875, allocations: 407.4 kB / 1.049 GB, free: 10.46 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02825/1.903, allocations: 7.288 MB / 1.056 GB, free: 3.156 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001211/1.904, allocations: 71.98 kB / 1.056 GB, free: 3.086 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.4804/2.385, allocations: 29.47 MB / 1.085 GB, free: 230.2 MB / 0.7012 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.571e-06/2.385, allocations: 4.031 kB / 1.085 GB, free: 230.2 MB / 0.7012 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.06156/2.446, allocations: 20.08 MB / 1.105 GB, free: 226.9 MB / 0.7012 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001455/2.448, allocations: 0.5005 MB / 1.105 GB, free: 226.6 MB / 0.7012 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001172/2.449, allocations: 70.89 kB / 1.105 GB, free: 226.6 MB / 0.7012 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004198/2.453, allocations: 351.8 kB / 1.105 GB, free: 226.5 MB / 0.7012 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002889/2.456, allocations: 0.8033 MB / 1.106 GB, free: 226.4 MB / 0.7012 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003338/2.456, allocations: 92.03 kB / 1.106 GB, free: 226.3 MB / 0.7012 GB
Notification: Performance of sorting global known variables: time 0.00273/2.459, allocations: 2.138 MB / 1.108 GB, free: 225.6 MB / 0.7012 GB
Notification: Performance of sort global known variables: time 8e-08/2.459, allocations: 0 / 1.108 GB, free: 225.6 MB / 0.7012 GB
Notification: Performance of remove unused functions: time 0.008437/2.468, allocations: 3.008 MB / 1.111 GB, free: 225.2 MB / 0.7012 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 13 (order3.delta,order3.w,order3.e1q,order6.delta,order6.w,order6.e1q,order6.e1d,order6.e2q,order6.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 (252):
 * Single equations (assignments): 250
 * 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
   {(100,36,5.5%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.05874/2.526, allocations: 20.74 MB / 1.132 GB, free: 210.5 MB / 0.7012 GB
Notification: Performance of simCode: created initialization part: time 0.04441/2.571, allocations: 15.26 MB / 1.146 GB, free: 195.2 MB / 0.7012 GB
Notification: Performance of simCode: created event and clocks part: time 8.776e-06/2.571, allocations: 0 / 1.146 GB, free: 195.2 MB / 0.7012 GB
Notification: Performance of simCode: created simulation system equations: time 0.01866/2.589, allocations: 5.756 MB / 1.152 GB, free: 189.4 MB / 0.7012 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003684/2.593, allocations: 388.8 kB / 1.152 GB, free: 189 MB / 0.7012 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1492/2.742, allocations: 58.96 MB / 1.21 GB, free: 129.4 MB / 0.7012 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00189/2.744, allocations: 2.504 MB / 1.212 GB, free: 126.8 MB / 0.7012 GB
Notification: Performance of simCode: alias equations: time 0.002098/2.746, allocations: 451 kB / 1.213 GB, free: 126.4 MB / 0.7012 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002051/2.748, allocations: 1.995 MB / 1.215 GB, free: 124.4 MB / 0.7012 GB
Notification: Performance of SimCode: time 1.313e-06/2.748, allocations: 0 / 1.215 GB, free: 124.4 MB / 0.7012 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.6462/3.394, allocations: 167.6 MB / 1.379 GB, free: 234.9 MB / 0.7169 GB
Notification: Performance of buildModelFMU: Generate platform static: time 27.06/30.45, allocations: 13.36 kB / 1.379 GB, free: 234.9 MB / 0.7169 GB
"
[Timeout remaining time 629]
(rm -f OpenIPSL_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI.pipe ; mkfifo OpenIPSL_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI.pipe >> ../files/OpenIPSL_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_OpenIPSL_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRI_fmu --startTime=0 --stopTime=20 --stepSize=0.008 --timeout=50 --tolerance=1e-06 OpenIPSL_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRI.fmu > OpenIPSL_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRI.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 35.514714973047376]
Failed to read output from testmodel.py, exit status != 0:
30.703848389093764 30.717375051 30.423324126
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7efca5e933a0>