Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermoSysPro_ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/package.mo): time 0.8921/0.8921, allocations: 153.8 MB / 172.7 MB, free: 4.887 MB / 142.1 MB
"
[Timeout remaining time 179]
Using package ThermoSysPro with version 4.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/package.mo)
Running command: "" <> buildModelFMU(ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh,fileNamePrefix="ThermoSysPro_ThermoSysPro_Fluid_Examples_Book_SimpleExamples_Volume_TestVolumeATh",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh,fileNamePrefix="ThermoSysPro_ThermoSysPro_Fluid_Examples_Book_SimpleExamples_Volume_TestVolumeATh",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.0006925/0.0006925, allocations: 15.97 kB / 249.8 MB, free: 4.418 MB / 206.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1899/0.1906, allocations: 33.35 MB / 283.1 MB, free: 1.75 MB / 222.1 MB
Notification: Automatically loaded package Complex 4.1.0 due to uses annotation from Modelica.
Notification: Automatically loaded package ModelicaServices 4.1.0 due to uses annotation from Modelica.
Notification: Automatically loaded package Modelica 4.1.0 due to usage.
Notification: Performance of NFInst.instantiate(ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh): time 1.876/2.067, allocations: 309.7 MB / 0.5789 GB, free: 17.02 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.2178/2.285, allocations: 172.6 MB / 0.7474 GB, free: 6.137 MB / 0.6075 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001219/2.286, allocations: 31.75 kB / 0.7475 GB, free: 6.105 MB / 0.6075 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/Volumes/VolumeATh.mo:104:3-105:67:writable] Warning: Connector Ce1 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/Volumes/VolumeATh.mo:106:3-108:57:writable] Warning: Connector Ce2 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/Volumes/VolumeATh.mo:109:3-110:73:writable] Warning: Connector Cs1 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/Volumes/VolumeATh.mo:111:3-112:75:writable] Warning: Connector Cs2 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/BoundaryConditions/SinkP.mo:47:3-48:67:writable] Warning: Connector C is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/PressureLosses/ControlValve.mo:44:3-45:68:writable] Warning: Connector C1 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/PressureLosses/ControlValve.mo:46:3-47:66:writable] Warning: Connector C2 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/PressureLosses/ControlValve.mo:44:3-45:68:writable] Warning: Connector C1 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/PressureLosses/ControlValve.mo:46:3-47:66:writable] Warning: Connector C2 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/BoundaryConditions/SourceQ.mo:63:3-64:65:writable] Warning: Connector C is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/BoundaryConditions/SourceP.mo:59:3-60:65:writable] Warning: Connector C is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/PressureLosses/LumpedStraightPipe.mo:54:3-55:67:writable] Warning: Connector C1 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Fluid/PressureLosses/LumpedStraightPipe.mo:56:3-57:65:writable] Warning: Connector C2 is not balanced: The number of potential variables (9) is not equal to the number of flow variables (0).
Notification: Performance of NFTyping.typeComponents: time 0.0008287/2.287, allocations: 184.9 kB / 0.7477 GB, free: 5.926 MB / 0.6075 GB
Notification: Performance of NFTyping.typeBindings: time 0.001394/2.288, allocations: 404.8 kB / 0.748 GB, free: 5.527 MB / 0.6075 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/WaterSteam/IF97_packages.mo:783:9-783:27:writable] Warning: cv was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/WaterSteam/IF97_packages.mo:850:9-850:27:writable] Warning: cv was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/WaterSteam/IF97_packages.mo:1088:9-1088:27:writable] Warning: cv was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/WaterSteamSimple/prop4_Ph.mo:69:3-69:60:writable] Warning: dh1satp was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/WaterSteamSimple/prop4_Ph.mo:69:3-69:60:writable] Warning: dh2satp was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/WaterSteamSimple/prop4_Ph_der.mo:167:3-170:49:writable] Warning: du1satp_der was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/WaterSteamSimple/prop4_Ph_der.mo:167:3-170:49:writable] Warning: du2satp_der was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro master/Properties/Fluid/derDensity_derP_derh.mo:63:5-63:84:writable] Warning: T was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
Notification: Performance of NFTyping.typeClassSections: time 0.03733/2.325, allocations: 14.19 MB / 0.7619 GB, free: 7.324 MB / 0.6231 GB
Notification: Performance of NFFlatten.flatten: time 0.002291/2.328, allocations: 1.388 MB / 0.7632 GB, free: 5.934 MB / 0.6231 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009025/2.329, allocations: 0.5845 MB / 0.7638 GB, free: 5.328 MB / 0.6231 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008869/2.329, allocations: 0.5762 MB / 0.7644 GB, free: 4.75 MB / 0.6231 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006539/2.33, allocations: 0.5404 MB / 0.7649 GB, free: 4.207 MB / 0.6231 GB
Notification: Performance of NFPackage.collectConstants: time 0.000161/2.33, allocations: 124 kB / 0.765 GB, free: 4.086 MB / 0.6231 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03637/2.367, allocations: 17.92 MB / 0.7825 GB, free: 2.145 MB / 0.6387 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005322/2.367, allocations: 326.9 kB / 0.7828 GB, free: 1.824 MB / 0.6387 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006697/2.368, allocations: 0.5209 MB / 0.7833 GB, free: 1.301 MB / 0.6387 GB
Notification: Performance of NFConvertDAE.convert: time 0.02526/2.393, allocations: 10.98 MB / 0.7941 GB, free: 6.293 MB / 0.6544 GB
Notification: Performance of FrontEnd - DAE generated: time 7.434e-06/2.393, allocations: 3.938 kB / 0.7941 GB, free: 6.289 MB / 0.6544 GB
Notification: Performance of FrontEnd: time 2.335e-06/2.393, allocations: 0 / 0.7941 GB, free: 6.289 MB / 0.6544 GB
Notification: Performance of Transformations before backend: time 2.279e-05/2.393, allocations: 0 / 0.7941 GB, free: 6.289 MB / 0.6544 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 286
 * Number of variables: 286
Notification: Performance of Generate backend data structure: time 0.007032/2.4, allocations: 2.755 MB / 0.7968 GB, free: 3.484 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 5.665e-05/2.4, allocations: 12.03 kB / 0.7968 GB, free: 3.473 MB / 0.6544 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0002081/2.4, allocations: 169.1 kB / 0.7969 GB, free: 3.297 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0002585/2.401, allocations: 155.1 kB / 0.7971 GB, free: 3.145 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001092/2.402, allocations: 0.6086 MB / 0.7977 GB, free: 2.504 MB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.702e-05/2.402, allocations: 86.25 kB / 0.7978 GB, free: 2.418 MB / 0.6544 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002055/2.402, allocations: 115.9 kB / 0.7979 GB, free: 2.305 MB / 0.6544 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001994/2.404, allocations: 1.251 MB / 0.7991 GB, free: 0.9297 MB / 0.6544 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.629e-05/2.404, allocations: 0 / 0.7991 GB, free: 0.9297 MB / 0.6544 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.87e-05/2.404, allocations: 48.88 kB / 0.7991 GB, free: 0.8828 MB / 0.6544 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.206e-05/2.404, allocations: 36 kB / 0.7992 GB, free: 0.8477 MB / 0.6544 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001749/2.406, allocations: 0.9172 MB / 0.8001 GB, free: 15.93 MB / 0.67 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.3388/2.745, allocations: 46.69 MB / 0.8457 GB, free: 296.6 MB / 0.6856 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0007232/2.745, allocations: 344.7 kB / 0.846 GB, free: 296.6 MB / 0.6856 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002917/2.746, allocations: 143.4 kB / 0.8461 GB, free: 296.6 MB / 0.6856 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.567/3.313, allocations: 191.9 MB / 1.034 GB, free: 118.5 MB / 0.6856 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.067e-05/3.313, allocations: 34.56 kB / 1.034 GB, free: 118.5 MB / 0.6856 GB
Notification: Performance of pre-optimization done (n=35): time 2.295e-06/3.313, allocations: 0 / 1.034 GB, free: 118.5 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=35): time 0.002745/3.316, allocations: 0.8478 MB / 1.034 GB, free: 117.7 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.284e-05/3.316, allocations: 55.41 kB / 1.034 GB, free: 117.6 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008963/3.317, allocations: 0.9186 MB / 1.035 GB, free: 116.7 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 2.685e-05/3.317, allocations: 37.7 kB / 1.035 GB, free: 116.6 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002606/3.317, allocations: 0.6146 MB / 1.036 GB, free: 116.1 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 7.045e-05/3.317, allocations: 145.5 kB / 1.036 GB, free: 116 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001831/3.317, allocations: 214.4 kB / 1.036 GB, free: 115.8 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 7.915e-05/3.317, allocations: 386.4 kB / 1.037 GB, free: 115.4 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003536/3.318, allocations: 201.9 kB / 1.037 GB, free: 115.2 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004958/3.318, allocations: 0.611 MB / 1.037 GB, free: 114.4 MB / 0.6856 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008319/3.319, allocations: 0.8708 MB / 1.038 GB, free: 113.3 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.843e-06/3.319, allocations: 4 kB / 1.038 GB, free: 113.3 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=109) (initialization): time 0.001688/3.321, allocations: 1.021 MB / 1.039 GB, free: 112.3 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 2.072e-05/3.321, allocations: 8.938 kB / 1.039 GB, free: 112.2 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.574e-05/3.321, allocations: 19.94 kB / 1.039 GB, free: 112.2 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001992/3.323, allocations: 424.7 kB / 1.04 GB, free: 111.8 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004059/3.323, allocations: 159.7 kB / 1.04 GB, free: 111.7 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0009095/3.324, allocations: 1.344 MB / 1.041 GB, free: 110.3 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006382/3.325, allocations: 125.1 kB / 1.041 GB, free: 110.2 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.735e-05/3.325, allocations: 52 kB / 1.041 GB, free: 110.1 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 68
 * Number of states: 0 ()
 * Number of discrete variables: 21 (sourceQ.C.ftype,sourceP2.C.ftype,volumeATh.Cs1.ftype,volumeATh.Cs2.ftype,volumeATh.fluids[1],controlValve1.fluid,controlValve2.fluid,lumpedStraightPipe1.fluid,sink.fluid,sourceP2.fluid1,sourceQ.fluid1,volumeATh.fluid1,volumeATh.Ce1.diff_on_2,controlValve1.C2.diff_on_1,volumeATh.Cs1.diff_on_2,volumeATh.Cs1.diff_on_1,controlValve2.C2.diff_on_1,volumeATh.Ce2.diff_on_2,sourceP2.C.diff_on_2,volumeATh.Cs2.diff_on_1,volumeATh.Cs2.diff_on_2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (91):
 * Single equations (assignments): 89
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * 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): 2 systems
   {(1,4), (3,12)}
Notification: Performance of prepare postOptimizeDAE: time 0.0003657/3.325, allocations: 290.9 kB / 1.042 GB, free: 109.8 MB / 0.6856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 9.889e-05/3.325, allocations: 95.39 kB / 1.042 GB, free: 109.8 MB / 0.6856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001135/3.326, allocations: 0.5938 MB / 1.042 GB, free: 109.2 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.709e-06/3.326, allocations: 8 kB / 1.042 GB, free: 109.1 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.839e-06/3.326, allocations: 0 / 1.042 GB, free: 109.1 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.18e-06/3.326, allocations: 3.984 kB / 1.042 GB, free: 109.1 MB / 0.6856 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00202/3.328, allocations: 1.321 MB / 1.044 GB, free: 107.8 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.176e-06/3.328, allocations: 0 / 1.044 GB, free: 107.8 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000187/3.328, allocations: 47.91 kB / 1.044 GB, free: 107.7 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001171/3.33, allocations: 268.5 kB / 1.044 GB, free: 107.5 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.624e-05/3.33, allocations: 11.98 kB / 1.044 GB, free: 107.5 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0006811/3.33, allocations: 1.238 MB / 1.045 GB, free: 106.2 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.984e-06/3.33, allocations: 8.062 kB / 1.045 GB, free: 106.2 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001466/3.332, allocations: 0.8613 MB / 1.046 GB, free: 105.3 MB / 0.6856 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003099/3.332, allocations: 156.2 kB / 1.046 GB, free: 105.1 MB / 0.6856 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 8.06e-05/3.332, allocations: 8 kB / 1.046 GB, free: 105.1 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002807/3.332, allocations: 15.98 kB / 1.046 GB, free: 105.1 MB / 0.6856 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.278e-05/3.332, allocations: 38.31 kB / 1.046 GB, free: 105.1 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.84e-05/3.332, allocations: 12 kB / 1.046 GB, free: 105.1 MB / 0.6856 GB
Notification: Performance of sorting global known variables: time 0.0005732/3.333, allocations: 0.5746 MB / 1.047 GB, free: 104.5 MB / 0.6856 GB
Notification: Performance of sort global known variables: time 1e-07/3.333, allocations: 0 / 1.047 GB, free: 104.5 MB / 0.6856 GB
Notification: Performance of remove unused functions: time 0.003736/3.337, allocations: 1.824 MB / 1.049 GB, free: 102.8 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 1 (volumeATh.h)
 * 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 (22):
 * Single equations (assignments): 20
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * 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): 2 systems
   {(1,4), (2,11)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.007764/3.345, allocations: 3.775 MB / 1.052 GB, free: 99.29 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.00139/3.346, allocations: 0.5612 MB / 1.053 GB, free: 98.73 MB / 0.6856 GB
Notification: Performance of simCode: created event and clocks part: time 4.218e-06/3.346, allocations: 2 kB / 1.053 GB, free: 98.73 MB / 0.6856 GB
Notification: Performance of simCode: created simulation system equations: time 0.0004642/3.346, allocations: 211.5 kB / 1.053 GB, free: 98.53 MB / 0.6856 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.000924/3.347, allocations: 202.6 kB / 1.053 GB, free: 98.36 MB / 0.6856 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01002/3.357, allocations: 7.632 MB / 1.061 GB, free: 90.2 MB / 0.6856 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0003544/3.358, allocations: 463.3 kB / 1.061 GB, free: 89.71 MB / 0.6856 GB
Notification: Performance of simCode: alias equations: time 0.0004171/3.358, allocations: 132.8 kB / 1.061 GB, free: 89.59 MB / 0.6856 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003183/3.358, allocations: 379.3 kB / 1.062 GB, free: 89.22 MB / 0.6856 GB
Notification: Performance of SimCode: time 9.32e-07/3.358, allocations: 0 / 1.062 GB, free: 89.22 MB / 0.6856 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.09594/3.454, allocations: 66.6 MB / 1.127 GB, free: 23.73 MB / 0.6856 GB
Notification: Performance of buildModelFMU: Generate platform static: time 15.88/19.34, allocations: 4.766 kB / 1.127 GB, free: 23.73 MB / 0.6856 GB
"
[Timeout remaining time 640]
(rm -f ThermoSysPro_ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh.pipe ; mkfifo ThermoSysPro_ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh.pipe ; head -c 1048576 < ThermoSysPro_ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh.pipe >> ../files/ThermoSysPro_ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh.sim & python3 -m fmpy simulate  --output-file ThermoSysPro_ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh_res.csv --start-time 0 --stop-time 10 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.004 ThermoSysPro_ThermoSysPro_Fluid_Examples_Book_SimpleExamples_Volume_TestVolumeATh.fmu > ThermoSysPro_ThermoSysPro.Fluid.Examples.Book.SimpleExamples.Volume.TestVolumeATh.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 22.070668749976903]
Failed to read output from testmodel.py, exit status != 0:
19.821179015096277 19.830694945 17.435114088
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4705bcefb0>