Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem.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.001628/0.001628, allocations: 112.3 kB / 21.18 MB, free: 0.6289 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.001976/0.001976, allocations: 211 kB / 24.48 MB, free: 4.035 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo): time 1.428/1.428, allocations: 230.4 MB / 258.1 MB, free: 8.148 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/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: translateModel(Modelica.Fluid.Examples.HeatingSystem,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5004,variableFilter="time|heater.mediums.1..h|heater.mediums.1..p|pipe.mediums.1..h|pipe.mediums.1..p|pipe.mediums.2..h|pipe.mediums.2..p|radiator.mediums.1..h|radiator.mediums.1..p|tank.level|tank.medium.h",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem")
translateModel(Modelica.Fluid.Examples.HeatingSystem,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5004,variableFilter="time|heater.mediums.1..h|heater.mediums.1..p|pipe.mediums.1..h|pipe.mediums.1..p|pipe.mediums.2..h|pipe.mediums.2..p|radiator.mediums.1..h|radiator.mediums.1..p|tank.level|tank.medium.h",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem") [Timeout 660]
"Warning: Requested package Modelica of version trunk, but this package was already loaded with version 4.1.0. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package Complex 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.
Warning: Requested package ModelicaServices 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.002007/0.002007, allocations: 71.83 kB / 378.7 MB, free: 2.934 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.22/0.222, allocations: 53.31 MB / 432 MB, free: 7.406 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Fluid.Examples.HeatingSystem): time 0.1047/0.3267, allocations: 91.6 MB / 0.5114 GB, free: 11.5 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03197/0.3587, allocations: 21.52 MB / 0.5324 GB, free: 5.926 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001933/0.3606, allocations: 87.5 kB / 0.5325 GB, free: 5.84 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002561/0.3632, allocations: 0.9628 MB / 0.5334 GB, free: 4.871 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.008785/0.372, allocations: 3.797 MB / 0.5371 GB, free: 1.059 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.2085/0.5805, allocations: 5.669 MB / 0.5426 GB, free: 4.07 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007076/0.5875, allocations: 7.078 MB / 0.5496 GB, free: 4.062 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001845/0.5894, allocations: 1.62 MB / 0.5511 GB, free: 3.984 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.1422/0.7315, allocations: 75.19 MB / 0.6246 GB, free: 6.719 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.06708/0.7986, allocations: 32.2 MB / 0.656 GB, free: 6.879 MB / 0.5137 GB
Notification: Performance of NFPackage.collectConstants: time 0.000774/0.7994, allocations: 248 kB / 0.6563 GB, free: 6.637 MB / 0.5137 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0134/0.8128, allocations: 6.178 MB / 0.6623 GB, free: 0.6758 MB / 0.5137 GB
Notification: Performance of NFScalarize.scalarize: time 0.001405/0.8142, allocations: 1.126 MB / 0.6634 GB, free: 15.59 MB / 0.5294 GB
Notification: Performance of NFVerifyModel.verify: time 0.002298/0.8165, allocations: 1.498 MB / 0.6648 GB, free: 14.11 MB / 0.5294 GB
Notification: Performance of NFConvertDAE.convert: time 0.007915/0.8244, allocations: 5.561 MB / 0.6703 GB, free: 8.816 MB / 0.5294 GB
Notification: Performance of FrontEnd - DAE generated: time 3.156e-06/0.8244, allocations: 0 / 0.6703 GB, free: 8.816 MB / 0.5294 GB
Notification: Performance of FrontEnd: time 1.142e-06/0.8244, allocations: 0 / 0.6703 GB, free: 8.816 MB / 0.5294 GB
Notification: Performance of Transformations before backend: time 3.115e-05/0.8245, allocations: 4 kB / 0.6703 GB, free: 8.812 MB / 0.5294 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 538
 * Number of variables: 538
Notification: Performance of Generate backend data structure: time 0.008488/0.8329, allocations: 3.597 MB / 0.6738 GB, free: 5.211 MB / 0.5294 GB
Notification: Performance of prepare preOptimizeDAE: time 4.082e-05/0.833, allocations: 8.031 kB / 0.6738 GB, free: 5.203 MB / 0.5294 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0009871/0.834, allocations: 322.1 kB / 0.6741 GB, free: 4.887 MB / 0.5294 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003316/0.8373, allocations: 1.693 MB / 0.6758 GB, free: 3.227 MB / 0.5294 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002093/0.8375, allocations: 214.3 kB / 0.676 GB, free: 3.016 MB / 0.5294 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003704/0.8379, allocations: 197.8 kB / 0.6762 GB, free: 2.836 MB / 0.5294 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005472/0.8433, allocations: 3.289 MB / 0.6794 GB, free: 15.47 MB / 0.545 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.875e-05/0.8434, allocations: 7.328 kB / 0.6794 GB, free: 15.46 MB / 0.545 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002431/0.8436, allocations: 84.42 kB / 0.6795 GB, free: 15.39 MB / 0.545 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003379/0.844, allocations: 258.4 kB / 0.6797 GB, free: 15.15 MB / 0.545 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00543/0.8494, allocations: 3.108 MB / 0.6827 GB, free: 12.06 MB / 0.545 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.02015/0.8695, allocations: 13.65 MB / 0.6961 GB, free: 13.72 MB / 0.5606 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004376/0.8739, allocations: 2.114 MB / 0.6981 GB, free: 11.61 MB / 0.5606 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002313/0.8762, allocations: 1.289 MB / 0.6994 GB, free: 10.33 MB / 0.5606 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.009198/0.8854, allocations: 5.702 MB / 0.705 GB, free: 4.191 MB / 0.5606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.187e-05/0.8855, allocations: 60.56 kB / 0.705 GB, free: 4.125 MB / 0.5606 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0005557/0.886, allocations: 182.2 kB / 0.7052 GB, free: 3.949 MB / 0.5606 GB
Notification: Performance of pre-optimization done (n=171): time 3.026e-06/0.886, allocations: 3.938 kB / 0.7052 GB, free: 3.945 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=197): time 0.0235/0.9095, allocations: 9.177 MB / 0.7142 GB, free: 10.7 MB / 0.5762 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.682e-05/0.9096, allocations: 73.41 kB / 0.7142 GB, free: 10.62 MB / 0.5762 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002543/0.9121, allocations: 1.845 MB / 0.716 GB, free: 8.73 MB / 0.5762 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001725/0.9123, allocations: 57.7 kB / 0.7161 GB, free: 8.668 MB / 0.5762 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009262/0.9132, allocations: 1.081 MB / 0.7171 GB, free: 7.582 MB / 0.5762 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006609/0.9139, allocations: 0.7218 MB / 0.7178 GB, free: 6.871 MB / 0.5762 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0008282/0.9147, allocations: 460.5 kB / 0.7183 GB, free: 6.414 MB / 0.5762 GB
Notification: Performance of setup shared object (initialization): time 0.000123/0.9149, allocations: 305.1 kB / 0.7186 GB, free: 6.113 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002221/0.9171, allocations: 1.236 MB / 0.7198 GB, free: 4.871 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002743/0.9198, allocations: 1.801 MB / 0.7215 GB, free: 2.832 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005047/0.9249, allocations: 3.159 MB / 0.7246 GB, free: 15.5 MB / 0.5919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.247e-06/0.9249, allocations: 4 kB / 0.7246 GB, free: 15.5 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=299) (initialization): time 0.007781/0.9327, allocations: 4.232 MB / 0.7288 GB, free: 11.36 MB / 0.5919 GB
Notification: Performance of prepare postOptimizeDAE: time 2.614e-05/0.9327, allocations: 15.28 kB / 0.7288 GB, free: 11.35 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.784e-05/0.9327, allocations: 20 kB / 0.7288 GB, free: 11.33 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01469/0.9474, allocations: 3.631 MB / 0.7323 GB, free: 7.703 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001226/0.9486, allocations: 334.2 kB / 0.7327 GB, free: 7.398 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006855/0.9555, allocations: 3.942 MB / 0.7365 GB, free: 3.461 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001743/0.9572, allocations: 99.94 kB / 0.7366 GB, free: 3.363 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002455/0.9575, allocations: 119.9 kB / 0.7367 GB, free: 3.246 MB / 0.5919 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.002968/0.9604, allocations: 1.672 MB / 0.7384 GB, free: 1.566 MB / 0.5919 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002651/0.9631, allocations: 1.752 MB / 0.7401 GB, free: 15.57 MB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.004794/0.9679, allocations: 3.054 MB / 0.743 GB, free: 12.35 MB / 0.6075 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.298e-06/0.9679, allocations: 4 kB / 0.743 GB, free: 12.35 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=299) (initialization_lambda0): time 0.007124/0.975, allocations: 3.976 MB / 0.7469 GB, free: 8.469 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 2.107e-05/0.975, allocations: 15.84 kB / 0.7469 GB, free: 8.457 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.275e-05/0.9751, allocations: 20 kB / 0.747 GB, free: 8.438 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01015/0.9852, allocations: 2.745 MB / 0.7496 GB, free: 5.699 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001509/0.9867, allocations: 383.3 kB / 0.75 GB, free: 5.352 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005817/0.9925, allocations: 3.466 MB / 0.7534 GB, free: 1.895 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001647/0.9942, allocations: 98.66 kB / 0.7535 GB, free: 1.801 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002268/0.9944, allocations: 108 kB / 0.7536 GB, free: 1.695 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 78
 * Number of states: 0 ()
 * Number of discrete variables: 4 (tank.inFlow[2],tank.inFlow[1],tank.regularFlow[2],tank.regularFlow[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (175):
 * Single equations (assignments): 174
 * 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
   {(18,107)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005432/0.9949, allocations: 316.2 kB / 0.7539 GB, free: 1.402 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004571/0.9954, allocations: 242.4 kB / 0.7541 GB, free: 1.164 MB / 0.6075 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1889/1.184, allocations: 3.916 MB / 0.758 GB, free: 276.1 MB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.005463/1.19, allocations: 3.347 MB / 0.7612 GB, free: 274.6 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.426e-06/1.19, allocations: 5.406 kB / 0.7612 GB, free: 274.6 MB / 0.6075 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001409/1.191, allocations: 0.603 MB / 0.7618 GB, free: 274.4 MB / 0.6075 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000365/1.192, allocations: 55.55 kB / 0.7619 GB, free: 274.4 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001303/1.193, allocations: 72.47 kB / 0.7619 GB, free: 274.4 MB / 0.6075 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002777/1.193, allocations: 170.1 kB / 0.7621 GB, free: 274.3 MB / 0.6075 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.007151/1.2, allocations: 3.77 MB / 0.7658 GB, free: 273.1 MB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.005655/1.206, allocations: 4.735 MB / 0.7704 GB, free: 271.4 MB / 0.6075 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001585/1.207, allocations: 1.313 MB / 0.7717 GB, free: 271 MB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.001295/1.209, allocations: 1.232 MB / 0.7729 GB, free: 270.4 MB / 0.6075 GB
Notification: Performance of Backend: time 2.61e-07/1.209, allocations: 0 / 0.7729 GB, free: 270.4 MB / 0.6075 GB
Notification: Performance of simCode: created initialization part: time 0.009813/1.219, allocations: 4.758 MB / 0.7775 GB, free: 267.4 MB / 0.6075 GB
Notification: Performance of SimCode: time 0.006871/1.225, allocations: 6.12 MB / 0.7835 GB, free: 261.6 MB / 0.6075 GB
Notification: Performance of Templates: time 0.08153/1.307, allocations: 77.06 MB / 0.8588 GB, free: 185 MB / 0.6075 GB
"
[Timeout remaining time 659]
make -j1 -f Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem.makefile [Timeout 660]
(rm -f Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem.sim & ./Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.1.0-beta.om_Modelica.Fluid.Examples.HeatingSystem.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 8.046315313782543]
Failed to read output from testmodel.py, exit status != 0:
['time', 'heater.mediums[1].h', 'heater.mediums[1].p', 'pipe.mediums[1].h', 'pipe.mediums[1].p', 'pipe.mediums[2].h', 'pipe.mediums[2].p', 'radiator.mediums[1].h', 'radiator.mediums[1].p', 'tank.level', 'tank.medium.h']
1.3148656580597162 1.32328608 0.49660697699999995
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd77b9ea050>