Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow.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.001364/0.001364, allocations: 103.5 kB / 16.45 MB, free: 6.449 MB / 14.72 MB
"
[Timeout remaining time 180]
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.001475/0.001475, allocations: 206.4 kB / 19.42 MB, free: 3.641 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.446/1.446, allocations: 230.6 MB / 252.8 MB, free: 8.949 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo): time 0.8363/0.8363, allocations: 134 MB / 442.8 MB, free: 11.27 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/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 Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow,fileNamePrefix="IDEAS_IDEAS_Fluid_MixingVolumes_Validation_MixingVolumeHeatReverseFlow",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow,fileNamePrefix="IDEAS_IDEAS_Fluid_MixingVolumes_Validation_MixingVolumeHeatReverseFlow",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: IDEAS 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.001878/0.001878, allocations: 68.09 kB / 0.6134 GB, free: 8.727 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1679/0.1698, allocations: 85.85 MB / 0.6973 GB, free: 2.953 MB / 0.5137 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow): time 0.5757/0.7455, allocations: 194.2 MB / 0.8869 GB, free: 6.195 MB / 0.6544 GB
Notification: Performance of NFInst.instExpressions: time 0.009825/0.7554, allocations: 5.726 MB / 0.8925 GB, free: 464 kB / 0.6544 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00129/0.7566, allocations: 59.5 kB / 0.8925 GB, free: 404 kB / 0.6544 GB
Notification: Performance of NFTyping.typeComponents: time 0.001633/0.7583, allocations: 0.6059 MB / 0.8931 GB, free: 15.79 MB / 0.67 GB
Notification: Performance of NFTyping.typeBindings: time 0.004753/0.763, allocations: 1.902 MB / 0.895 GB, free: 13.88 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003121/0.7661, allocations: 1.202 MB / 0.8962 GB, free: 12.68 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.003992/0.7701, allocations: 2.95 MB / 0.8991 GB, free: 9.723 MB / 0.67 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001353/0.7715, allocations: 0.8133 MB / 0.8998 GB, free: 8.895 MB / 0.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002112/0.7736, allocations: 1.132 MB / 0.901 GB, free: 7.758 MB / 0.67 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002112/0.7757, allocations: 0.9806 MB / 0.9019 GB, free: 6.785 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002289/0.7759, allocations: 108 kB / 0.902 GB, free: 6.68 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003308/0.7793, allocations: 1.524 MB / 0.9035 GB, free: 5.152 MB / 0.67 GB
Notification: Performance of NFScalarize.scalarize: time 0.000604/0.7799, allocations: 470.6 kB / 0.9039 GB, free: 4.691 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.0009352/0.7808, allocations: 0.6534 MB / 0.9046 GB, free: 4.035 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.003977/0.7848, allocations: 2.157 MB / 0.9067 GB, free: 1.871 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 5.511e-06/0.7848, allocations: 0 / 0.9067 GB, free: 1.871 MB / 0.67 GB
Notification: Performance of FrontEnd: time 1.694e-06/0.7848, allocations: 0 / 0.9067 GB, free: 1.871 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 3.336e-05/0.7848, allocations: 0 / 0.9067 GB, free: 1.871 MB / 0.67 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 213
 * Number of variables: 213
Notification: Performance of Generate backend data structure: time 0.00301/0.7878, allocations: 1.549 MB / 0.9082 GB, free: 276 kB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 4.529e-05/0.7879, allocations: 9.109 kB / 0.9082 GB, free: 268 kB / 0.67 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001859/0.7881, allocations: 135.2 kB / 0.9083 GB, free: 124 kB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00206/0.7901, allocations: 319 kB / 0.9086 GB, free: 15.81 MB / 0.6856 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001587/0.7917, allocations: 0.8715 MB / 0.9095 GB, free: 14.89 MB / 0.6856 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 6.117e-05/0.7918, allocations: 69.94 kB / 0.9096 GB, free: 14.82 MB / 0.6856 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001394/0.7919, allocations: 80 kB / 0.9096 GB, free: 14.75 MB / 0.6856 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001705/0.7936, allocations: 0.9053 MB / 0.9105 GB, free: 13.84 MB / 0.6856 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.229e-05/0.7936, allocations: 4 kB / 0.9105 GB, free: 13.83 MB / 0.6856 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.13e-05/0.7937, allocations: 43.94 kB / 0.9106 GB, free: 13.79 MB / 0.6856 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.12e-05/0.7938, allocations: 30 kB / 0.9106 GB, free: 13.76 MB / 0.6856 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001507/0.7953, allocations: 0.8237 MB / 0.9114 GB, free: 12.93 MB / 0.6856 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.005874/0.8012, allocations: 3.507 MB / 0.9148 GB, free: 9.211 MB / 0.6856 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0008115/0.802, allocations: 480.5 kB / 0.9153 GB, free: 8.734 MB / 0.6856 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004859/0.8024, allocations: 274 kB / 0.9155 GB, free: 8.465 MB / 0.6856 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001933/0.8026, allocations: 101.5 kB / 0.9156 GB, free: 8.348 MB / 0.6856 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.439e-05/0.8027, allocations: 43 kB / 0.9157 GB, free: 8.293 MB / 0.6856 GB
Notification: Performance of pre-optimization done (n=47): time 2.094e-06/0.8027, allocations: 4 kB / 0.9157 GB, free: 8.289 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=49): time 0.004199/0.8069, allocations: 1.936 MB / 0.9176 GB, free: 6.262 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.125e-05/0.8069, allocations: 59.78 kB / 0.9176 GB, free: 6.191 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001057/0.808, allocations: 0.8058 MB / 0.9184 GB, free: 5.379 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 6.478e-05/0.8081, allocations: 37.7 kB / 0.9185 GB, free: 5.336 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003824/0.8084, allocations: 484.3 kB / 0.9189 GB, free: 4.855 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00018/0.8086, allocations: 178.4 kB / 0.9191 GB, free: 4.68 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002019/0.8088, allocations: 114.3 kB / 0.9192 GB, free: 4.562 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 0.000143/0.809, allocations: 330 kB / 0.9195 GB, free: 4.234 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004488/0.8094, allocations: 245.2 kB / 0.9198 GB, free: 3.996 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005822/0.81, allocations: 370.9 kB / 0.9201 GB, free: 3.594 MB / 0.6856 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: volDyn.dynBal.medium.p = volDyn.dynBal.p_start (bou.p = volDyn.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.003875/0.8139, allocations: 1.518 MB / 0.9216 GB, free: 2.02 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.985e-06/0.8139, allocations: 0 / 0.9216 GB, free: 2.02 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=72) (initialization): time 0.00145/0.8153, allocations: 0.785 MB / 0.9224 GB, free: 1.223 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 2.011e-05/0.8153, allocations: 7.984 kB / 0.9224 GB, free: 1.215 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.975e-06/0.8154, allocations: 8.281 kB / 0.9224 GB, free: 1.207 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003052/0.8157, allocations: 93.66 kB / 0.9225 GB, free: 1.109 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006719/0.8163, allocations: 143.8 kB / 0.9226 GB, free: 0.9688 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003273/0.8196, allocations: 3.302 MB / 0.9258 GB, free: 13.38 MB / 0.7012 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008983/0.8205, allocations: 35.86 kB / 0.9259 GB, free: 13.35 MB / 0.7012 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.492e-05/0.8206, allocations: 20 kB / 0.9259 GB, free: 13.33 MB / 0.7012 GB
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization system:
         volDyn.dynBal.medium.p = volDyn.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 12
 * 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 (67):
 * Single equations (assignments): 65
 * 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), (1,1)}
Notification: Performance of prepare postOptimizeDAE: time 0.000509/0.8211, allocations: 307.5 kB / 0.9262 GB, free: 13.02 MB / 0.7012 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001875/0.8213, allocations: 95.44 kB / 0.9263 GB, free: 12.93 MB / 0.7012 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00021/0.8215, allocations: 100.7 kB / 0.9264 GB, free: 12.83 MB / 0.7012 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.933e-06/0.8215, allocations: 4 kB / 0.9264 GB, free: 12.83 MB / 0.7012 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.271e-06/0.8215, allocations: 3.938 kB / 0.9264 GB, free: 12.82 MB / 0.7012 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001183/0.8227, allocations: 0.8427 MB / 0.9272 GB, free: 11.89 MB / 0.7012 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00308/0.8257, allocations: 1.787 MB / 0.9289 GB, free: 10 MB / 0.7012 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.277e-06/0.8258, allocations: 8 kB / 0.9289 GB, free: 9.996 MB / 0.7012 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006333/0.8264, allocations: 123.9 kB / 0.9291 GB, free: 9.875 MB / 0.7012 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0002396/0.8266, allocations: 68.8 kB / 0.9291 GB, free: 9.805 MB / 0.7012 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.082e-05/0.8267, allocations: 16 kB / 0.9291 GB, free: 9.789 MB / 0.7012 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001833/0.8285, allocations: 1.793 MB / 0.9309 GB, free: 7.859 MB / 0.7012 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.635e-06/0.8285, allocations: 0 / 0.9309 GB, free: 7.859 MB / 0.7012 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001794/0.8303, allocations: 1.11 MB / 0.932 GB, free: 6.691 MB / 0.7012 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004299/0.8307, allocations: 240.4 kB / 0.9322 GB, free: 6.438 MB / 0.7012 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001133/0.8308, allocations: 16.17 kB / 0.9322 GB, free: 6.422 MB / 0.7012 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006936/0.8315, allocations: 31.98 kB / 0.9322 GB, free: 6.391 MB / 0.7012 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001124/0.8316, allocations: 43.89 kB / 0.9323 GB, free: 6.348 MB / 0.7012 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.853e-05/0.8317, allocations: 31.98 kB / 0.9323 GB, free: 6.316 MB / 0.7012 GB
Notification: Performance of sorting global known variables: time 0.0007826/0.8325, allocations: 0.5654 MB / 0.9329 GB, free: 5.754 MB / 0.7012 GB
Notification: Performance of sort global known variables: time 8e-08/0.8325, allocations: 0 / 0.9329 GB, free: 5.754 MB / 0.7012 GB
Notification: Performance of remove unused functions: time 0.0009566/0.8335, allocations: 283.9 kB / 0.9331 GB, free: 5.477 MB / 0.7012 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * Number of states: 2 (volDyn.dynBal.U,volDyn.dynBal.mXi[1])
 * 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 (45):
 * Single equations (assignments): 44
 * Array equations: 0
 * Algorithm blocks: 0
 * 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
   {(1,4)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.002308/0.8358, allocations: 1.696 MB / 0.9348 GB, free: 3.711 MB / 0.7012 GB
Notification: Performance of simCode: created initialization part: time 0.001458/0.8372, allocations: 0.7924 MB / 0.9356 GB, free: 2.891 MB / 0.7012 GB
Notification: Performance of simCode: created event and clocks part: time 6.482e-06/0.8372, allocations: 4.125 kB / 0.9356 GB, free: 2.887 MB / 0.7012 GB
Notification: Performance of simCode: created simulation system equations: time 0.0006418/0.8379, allocations: 394.3 kB / 0.936 GB, free: 2.488 MB / 0.7012 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002774/0.8406, allocations: 0.6181 MB / 0.9366 GB, free: 1.906 MB / 0.7012 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01057/0.8512, allocations: 6.988 MB / 0.9434 GB, free: 10.63 MB / 0.7169 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007743/0.852, allocations: 1.095 MB / 0.9444 GB, free: 9.504 MB / 0.7169 GB
Notification: Performance of simCode: alias equations: time 0.0008011/0.8528, allocations: 215.7 kB / 0.9447 GB, free: 9.289 MB / 0.7169 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004931/0.8533, allocations: 347.3 kB / 0.945 GB, free: 8.949 MB / 0.7169 GB
Notification: Performance of SimCode: time 1.272e-06/0.8533, allocations: 0 / 0.945 GB, free: 8.949 MB / 0.7169 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07393/0.9272, allocations: 28.26 MB / 0.9726 GB, free: 13.07 MB / 0.7481 GB
Notification: Performance of buildModelFMU: Generate platform static: time 13.09/14.02, allocations: 12 kB / 0.9726 GB, free: 13.05 MB / 0.7481 GB
"
[Timeout remaining time 646]
(rm -f IDEAS_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow.pipe ; mkfifo IDEAS_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow.pipe >> ../files/IDEAS_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow.sim & python3 -m fmpy simulate  --output-file IDEAS_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow_res.csv --start-time 0 --stop-time 10 --timeout 50 --relative-tolerance 1e-08 --interface-type ModelExchange --solver CVode --output-interval 0.004 IDEAS_IDEAS_Fluid_MixingVolumes_Validation_MixingVolumeHeatReverseFlow.fmu > IDEAS_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeHeatReverseFlow.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 17.7469655249879]
Failed to read output from testmodel.py, exit status != 0:
14.12118900999485 14.137138311 13.349213061
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f8fd7757340>