Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.Media.TestOnly.MoistAir.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001328/0.001328, allocations: 123.3 kB / 18.5 MB, free: 4.582 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001425/0.001425, allocations: 210.4 kB / 21.46 MB, free: 1.629 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.476/1.476, allocations: 230.5 MB / 254.7 MB, free: 15.04 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.228/0.228, allocations: 46.93 MB / 357.7 MB, free: 10.22 MB / 286.1 MB " [Timeout remaining time 180] Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo) Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo) Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo) Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo) Running command: "" <> buildModelFMU(ModelicaTest.Media.TestOnly.MoistAir,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_Media_TestOnly_MoistAir",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.Media.TestOnly.MoistAir,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_Media_TestOnly_MoistAir",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Warning: Requested package Modelica of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions. Notification: Performance of FrontEnd - loaded program: time 0.002269/0.002269, allocations: 79.88 kB / 0.4916 GB, free: 7.785 MB / 350.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1282/0.1305, allocations: 66.04 MB / 0.5561 GB, free: 5.613 MB / 414.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.Media.TestOnly.MoistAir): time 0.4475/0.578, allocations: 154.4 MB / 0.7069 GB, free: 6.762 MB / 0.5137 GB Notification: Performance of NFInst.instExpressions: time 0.001972/0.58, allocations: 1.463 MB / 0.7083 GB, free: 5.301 MB / 0.5137 GB Notification: Performance of NFInst.updateImplicitVariability: time 4.6e-05/0.58, allocations: 0 / 0.7083 GB, free: 5.301 MB / 0.5137 GB Notification: Performance of NFTyping.typeComponents: time 0.0003175/0.5803, allocations: 47.73 kB / 0.7084 GB, free: 5.254 MB / 0.5137 GB Notification: Performance of NFTyping.typeBindings: time 0.002826/0.5832, allocations: 1.077 MB / 0.7094 GB, free: 4.191 MB / 0.5137 GB Notification: Performance of NFTyping.typeClassSections: time 4.909e-06/0.5832, allocations: 0 / 0.7094 GB, free: 4.191 MB / 0.5137 GB Notification: Performance of NFFlatten.flatten: time 0.0001369/0.5833, allocations: 63.66 kB / 0.7095 GB, free: 4.129 MB / 0.5137 GB Notification: Performance of NFFlatten.resolveConnections: time 4.161e-05/0.5833, allocations: 3.984 kB / 0.7095 GB, free: 4.125 MB / 0.5137 GB Notification: Performance of NFEvalConstants.evaluate: time 4.134e-05/0.5834, allocations: 23.92 kB / 0.7095 GB, free: 4.102 MB / 0.5137 GB Notification: Performance of NFSimplifyModel.simplify: time 7.484e-05/0.5835, allocations: 35.84 kB / 0.7095 GB, free: 4.066 MB / 0.5137 GB Notification: Performance of NFPackage.collectConstants: time 9.228e-06/0.5835, allocations: 0 / 0.7095 GB, free: 4.066 MB / 0.5137 GB Notification: Performance of NFFlatten.collectFunctions: time 0.003098/0.5866, allocations: 1.93 MB / 0.7114 GB, free: 2.133 MB / 0.5137 GB Notification: Performance of NFScalarize.scalarize: time 5.033e-05/0.5866, allocations: 15.92 kB / 0.7114 GB, free: 2.117 MB / 0.5137 GB Notification: Performance of NFVerifyModel.verify: time 3.373e-05/0.5867, allocations: 15.94 kB / 0.7114 GB, free: 2.102 MB / 0.5137 GB Notification: Performance of NFConvertDAE.convert: time 0.001416/0.5881, allocations: 0.932 MB / 0.7124 GB, free: 1.168 MB / 0.5137 GB Notification: Performance of FrontEnd - DAE generated: time 3.827e-06/0.5881, allocations: 3.938 kB / 0.7124 GB, free: 1.164 MB / 0.5137 GB Notification: Performance of FrontEnd: time 1.413e-06/0.5881, allocations: 0 / 0.7124 GB, free: 1.164 MB / 0.5137 GB Notification: Performance of Transformations before backend: time 4.749e-06/0.5881, allocations: 0 / 0.7124 GB, free: 1.164 MB / 0.5137 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 12 * Number of variables: 12 Notification: Performance of Generate backend data structure: time 0.0007639/0.5888, allocations: 0.5153 MB / 0.7129 GB, free: 0.6016 MB / 0.5137 GB Notification: Performance of prepare preOptimizeDAE: time 3.738e-05/0.5889, allocations: 12.02 kB / 0.7129 GB, free: 0.5898 MB / 0.5137 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 3.67e-05/0.5889, allocations: 34.75 kB / 0.7129 GB, free: 0.5469 MB / 0.5137 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 3.165e-05/0.5889, allocations: 8.891 kB / 0.7129 GB, free: 0.5391 MB / 0.5137 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0001298/0.5891, allocations: 163.8 kB / 0.7131 GB, free: 348 kB / 0.5137 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.074e-06/0.5891, allocations: 4 kB / 0.7131 GB, free: 344 kB / 0.5137 GB Notification: Performance of preOpt expandDerOperator (simulation): time 1.395e-05/0.5891, allocations: 8 kB / 0.7131 GB, free: 336 kB / 0.5137 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0001525/0.5893, allocations: 81.59 kB / 0.7132 GB, free: 248 kB / 0.5137 GB Notification: Performance of preOpt findStateOrder (simulation): time 7.484e-06/0.5893, allocations: 4 kB / 0.7132 GB, free: 244 kB / 0.5137 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.246e-06/0.5893, allocations: 0 / 0.7132 GB, free: 244 kB / 0.5137 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.784e-05/0.5893, allocations: 11.98 kB / 0.7132 GB, free: 232 kB / 0.5137 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0001032/0.5894, allocations: 63.95 kB / 0.7132 GB, free: 168 kB / 0.5137 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0004863/0.5899, allocations: 324.6 kB / 0.7136 GB, free: 15.8 MB / 0.5294 GB Notification: Performance of preOpt comSubExp (simulation): time 7.821e-05/0.59, allocations: 34.56 kB / 0.7136 GB, free: 15.75 MB / 0.5294 GB Notification: Performance of preOpt resolveLoops (simulation): time 3.04e-05/0.59, allocations: 16.98 kB / 0.7136 GB, free: 15.73 MB / 0.5294 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0002323/0.5902, allocations: 255.3 kB / 0.7138 GB, free: 15.43 MB / 0.5294 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.936e-05/0.5903, allocations: 42.5 kB / 0.7139 GB, free: 15.38 MB / 0.5294 GB Notification: Performance of pre-optimization done (n=5): time 1.142e-06/0.5903, allocations: 0 / 0.7139 GB, free: 15.38 MB / 0.5294 GB Notification: Performance of matching and sorting (n=5): time 0.0002018/0.5905, allocations: 73.61 kB / 0.714 GB, free: 15.3 MB / 0.5294 GB Notification: Performance of inlineWhenForInitialization (initialization): time 3.468e-05/0.5905, allocations: 51.41 kB / 0.714 GB, free: 15.24 MB / 0.5294 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 8.594e-05/0.5906, allocations: 105.8 kB / 0.7141 GB, free: 15.13 MB / 0.5294 GB Notification: Performance of collectPreVariables (initialization): time 1.659e-05/0.5906, allocations: 29.64 kB / 0.7141 GB, free: 15.09 MB / 0.5294 GB Notification: Performance of collectInitialEqns (initialization): time 5.256e-05/0.5906, allocations: 92.03 kB / 0.7142 GB, free: 15 MB / 0.5294 GB Notification: Performance of collectInitialBindings (initialization): time 2.057e-05/0.5907, allocations: 23.83 kB / 0.7142 GB, free: 14.98 MB / 0.5294 GB Notification: Performance of simplifyInitialFunctions (initialization): time 4.553e-05/0.5907, allocations: 33.66 kB / 0.7143 GB, free: 14.94 MB / 0.5294 GB Notification: Performance of setup shared object (initialization): time 0.0001326/0.5908, allocations: 314.8 kB / 0.7146 GB, free: 14.62 MB / 0.5294 GB Notification: Performance of preBalanceInitialSystem (initialization): time 2.913e-05/0.5909, allocations: 11.98 kB / 0.7146 GB, free: 14.61 MB / 0.5294 GB Notification: Performance of partitionIndependentBlocks (initialization): time 4.917e-05/0.5909, allocations: 35.42 kB / 0.7146 GB, free: 14.56 MB / 0.5294 GB Notification: Performance of analyzeInitialSystem (initialization): time 8.394e-05/0.591, allocations: 56.75 kB / 0.7147 GB, free: 14.5 MB / 0.5294 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.724e-06/0.591, allocations: 0 / 0.7147 GB, free: 14.5 MB / 0.5294 GB Notification: Performance of matching and sorting (n=8) (initialization): time 0.0001383/0.5911, allocations: 81.61 kB / 0.7148 GB, free: 14.41 MB / 0.5294 GB Notification: Performance of prepare postOptimizeDAE: time 1.452e-05/0.5912, allocations: 9.969 kB / 0.7148 GB, free: 14.4 MB / 0.5294 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.359e-06/0.5912, allocations: 4 kB / 0.7148 GB, free: 14.4 MB / 0.5294 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.468e-05/0.5912, allocations: 4.812 kB / 0.7148 GB, free: 14.39 MB / 0.5294 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 7.673e-05/0.5913, allocations: 15.98 kB / 0.7148 GB, free: 14.38 MB / 0.5294 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 5.05e-06/0.5913, allocations: 0 / 0.7148 GB, free: 14.38 MB / 0.5294 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 3.821e-05/0.5913, allocations: 12.09 kB / 0.7148 GB, free: 14.37 MB / 0.5294 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 1.278e-05/0.5913, allocations: 4 kB / 0.7148 GB, free: 14.36 MB / 0.5294 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 4 * 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 (8): * Single equations (assignments): 7 * Array equations: 1 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of prepare postOptimizeDAE: time 8.13e-05/0.5914, allocations: 109.8 kB / 0.7149 GB, free: 14.25 MB / 0.5294 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 2.906e-05/0.5914, allocations: 7.891 kB / 0.7149 GB, free: 14.24 MB / 0.5294 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0001808/0.5916, allocations: 84.61 kB / 0.715 GB, free: 14.15 MB / 0.5294 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.734e-06/0.5916, allocations: 0 / 0.715 GB, free: 14.15 MB / 0.5294 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.779e-06/0.5916, allocations: 4 kB / 0.715 GB, free: 14.15 MB / 0.5294 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.696e-06/0.5916, allocations: 0 / 0.715 GB, free: 14.15 MB / 0.5294 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0001935/0.5918, allocations: 169.7 kB / 0.7152 GB, free: 13.93 MB / 0.5294 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.182e-06/0.5918, allocations: 0 / 0.7152 GB, free: 13.93 MB / 0.5294 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 3.34e-05/0.5918, allocations: 8 kB / 0.7152 GB, free: 13.93 MB / 0.5294 GB Notification: Performance of postOpt tearingSystem (simulation): time 3.537e-06/0.5919, allocations: 3.938 kB / 0.7152 GB, free: 13.92 MB / 0.5294 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 8.185e-06/0.5919, allocations: 3.984 kB / 0.7152 GB, free: 13.92 MB / 0.5294 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 3.016e-06/0.5919, allocations: 3.984 kB / 0.7152 GB, free: 13.91 MB / 0.5294 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.81e-07/0.5919, allocations: 0 / 0.7152 GB, free: 13.91 MB / 0.5294 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0001514/0.592, allocations: 167.2 kB / 0.7153 GB, free: 13.74 MB / 0.5294 GB Notification: Performance of postOpt removeConstants (simulation): time 5.032e-05/0.5921, allocations: 85.05 kB / 0.7154 GB, free: 13.64 MB / 0.5294 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 1.294e-05/0.5921, allocations: 0 / 0.7154 GB, free: 13.64 MB / 0.5294 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 4.79e-05/0.5921, allocations: 0 / 0.7154 GB, free: 13.64 MB / 0.5294 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 2.166e-05/0.5921, allocations: 7.984 kB / 0.7154 GB, free: 13.63 MB / 0.5294 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.025e-06/0.5922, allocations: 4 kB / 0.7154 GB, free: 13.62 MB / 0.5294 GB Notification: Performance of sorting global known variables: time 4.354e-05/0.5922, allocations: 87.22 kB / 0.7155 GB, free: 13.54 MB / 0.5294 GB Notification: Performance of sort global known variables: time 7e-08/0.5922, allocations: 0 / 0.7155 GB, free: 13.54 MB / 0.5294 GB Notification: Performance of remove unused functions: time 0.0005069/0.5927, allocations: 151.5 kB / 0.7157 GB, free: 13.39 MB / 0.5294 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * 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 simulation (5): * Single equations (assignments): 4 * Array equations: 1 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.001086/0.5938, allocations: 0.9148 MB / 0.7166 GB, free: 12.45 MB / 0.5294 GB Notification: Performance of simCode: created initialization part: time 0.0001259/0.5939, allocations: 39.92 kB / 0.7166 GB, free: 12.41 MB / 0.5294 GB Notification: Performance of simCode: created event and clocks part: time 2.154e-06/0.5939, allocations: 4.188 kB / 0.7166 GB, free: 12.41 MB / 0.5294 GB Notification: Performance of simCode: created simulation system equations: time 3.255e-05/0.594, allocations: 15.92 kB / 0.7166 GB, free: 12.39 MB / 0.5294 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0002788/0.5942, allocations: 10.34 kB / 0.7166 GB, free: 12.39 MB / 0.5294 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0006596/0.5949, allocations: 271.1 kB / 0.7169 GB, free: 12.08 MB / 0.5294 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0003056/0.5952, allocations: 0.6846 MB / 0.7175 GB, free: 11.37 MB / 0.5294 GB Notification: Performance of simCode: alias equations: time 6.348e-05/0.5953, allocations: 20.91 kB / 0.7176 GB, free: 11.35 MB / 0.5294 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 6.076e-05/0.5953, allocations: 15.44 kB / 0.7176 GB, free: 11.34 MB / 0.5294 GB Notification: Performance of SimCode: time 6.91e-07/0.5953, allocations: 4 kB / 0.7176 GB, free: 11.33 MB / 0.5294 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.02728/0.6226, allocations: 4.979 MB / 0.7224 GB, free: 6.445 MB / 0.5294 GB Notification: Performance of buildModelFMU: Generate platform static: time 7.988/8.611, allocations: 12 kB / 0.7225 GB, free: 6.434 MB / 0.5294 GB " [Timeout remaining time 651] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.Media.TestOnly.MoistAir.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.Media.TestOnly.MoistAir.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.Media.TestOnly.MoistAir.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.Media.TestOnly.MoistAir.sim & python3 -m fmpy simulate --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.Media.TestOnly.MoistAir_res.csv --start-time 0 --stop-time 1 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00019992 ModelicaTest_4_1_0-beta_om_ModelicaTest_Media_TestOnly_MoistAir.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.Media.TestOnly.MoistAir.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 11.559403793886304] Failed to read output from testmodel.py, exit status != 0: ['time', 'p', 's', 'u', 'Tsat'] 8.678894898388535 8.696066264 8.10479603 Calling exit ...