Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001806/0.001806, allocations: 113.8 kB / 20.38 MB, free: 1.426 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002165/0.002165, allocations: 197 kB / 23.67 MB, free: 4.723 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.506/1.506, allocations: 225.6 MB / 252.4 MB, free: 13.3 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/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.0.0_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.0.0_Modelica.Fluid.Examples.HeatingSystem") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001483/0.001483, allocations: 39.92 kB / 370.7 MB, free: 9.57 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2116/0.213, allocations: 52.14 MB / 422.9 MB, free: 15.03 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Fluid.Examples.HeatingSystem): time 0.1/0.3131, allocations: 91.29 MB / 0.5021 GB, free: 3.445 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03218/0.3453, allocations: 21.49 MB / 0.5231 GB, free: 13.89 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002099/0.3474, allocations: 87.44 kB / 0.5232 GB, free: 13.8 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002729/0.3501, allocations: 0.955 MB / 0.5241 GB, free: 12.84 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00939/0.3595, allocations: 3.783 MB / 0.5278 GB, free: 9.043 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01297/0.3724, allocations: 5.665 MB / 0.5333 GB, free: 3.383 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.2098/0.5823, allocations: 7.055 MB / 0.5402 GB, free: 5.672 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002305/0.5846, allocations: 1.604 MB / 0.5418 GB, free: 5.605 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.1464/0.731, allocations: 75.13 MB / 0.6152 GB, free: 14.95 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.06245/0.7934, allocations: 32.18 MB / 0.6466 GB, free: 2.094 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 0.0007349/0.7941, allocations: 256 kB / 0.6468 GB, free: 1.844 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01231/0.8065, allocations: 6.14 MB / 0.6528 GB, free: 11.96 MB / 0.5137 GB
Notification: Performance of NFScalarize.scalarize: time 0.001229/0.8077, allocations: 1.123 MB / 0.6539 GB, free: 10.88 MB / 0.5137 GB
Notification: Performance of NFVerifyModel.verify: time 0.001853/0.8095, allocations: 1.496 MB / 0.6554 GB, free: 9.398 MB / 0.5137 GB
Notification: Performance of NFConvertDAE.convert: time 0.006888/0.8164, allocations: 5.545 MB / 0.6608 GB, free: 4.133 MB / 0.5137 GB
Notification: Performance of FrontEnd - DAE generated: time 2.755e-06/0.8164, allocations: 0 / 0.6608 GB, free: 4.133 MB / 0.5137 GB
Notification: Performance of FrontEnd: time 1.322e-06/0.8164, allocations: 0 / 0.6608 GB, free: 4.133 MB / 0.5137 GB
Notification: Performance of Transformations before backend: time 2.453e-05/0.8164, allocations: 3.562 kB / 0.6608 GB, free: 4.133 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: 538
 * Number of variables: 538
Notification: Performance of Generate backend data structure: time 0.007977/0.8244, allocations: 3.598 MB / 0.6643 GB, free: 0.5273 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 3.794e-05/0.8245, allocations: 12.03 kB / 0.6643 GB, free: 0.5156 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0009061/0.8254, allocations: 314.2 kB / 0.6646 GB, free: 212 kB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003144/0.8285, allocations: 1.691 MB / 0.6663 GB, free: 14.55 MB / 0.5294 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002073/0.8287, allocations: 220.3 kB / 0.6665 GB, free: 14.34 MB / 0.5294 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003551/0.8291, allocations: 196.5 kB / 0.6667 GB, free: 14.16 MB / 0.5294 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005295/0.8344, allocations: 3.295 MB / 0.6699 GB, free: 10.79 MB / 0.5294 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.701e-05/0.8344, allocations: 7.75 kB / 0.6699 GB, free: 10.78 MB / 0.5294 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000244/0.8347, allocations: 85.14 kB / 0.67 GB, free: 10.71 MB / 0.5294 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003259/0.835, allocations: 246.8 kB / 0.6702 GB, free: 10.47 MB / 0.5294 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005329/0.8403, allocations: 3.106 MB / 0.6732 GB, free: 7.383 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.01947/0.8598, allocations: 13.66 MB / 0.6866 GB, free: 9.055 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004247/0.864, allocations: 2.117 MB / 0.6887 GB, free: 6.945 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002282/0.8663, allocations: 1.286 MB / 0.6899 GB, free: 5.66 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.008908/0.8752, allocations: 5.701 MB / 0.6955 GB, free: 15.52 MB / 0.5606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.335e-05/0.8753, allocations: 56.56 kB / 0.6955 GB, free: 15.46 MB / 0.5606 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0005349/0.8758, allocations: 196.8 kB / 0.6957 GB, free: 15.27 MB / 0.5606 GB
Notification: Performance of pre-optimization done (n=171): time 2.695e-06/0.8758, allocations: 0 / 0.6957 GB, free: 15.27 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=197): time 0.02221/0.898, allocations: 9.166 MB / 0.7047 GB, free: 6.047 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.892e-05/0.8981, allocations: 77.41 kB / 0.7047 GB, free: 5.957 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002411/0.9005, allocations: 1.841 MB / 0.7065 GB, free: 4.074 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001672/0.9007, allocations: 61.7 kB / 0.7066 GB, free: 4.008 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008798/0.9015, allocations: 1.085 MB / 0.7077 GB, free: 2.918 MB / 0.5606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006149/0.9022, allocations: 0.7081 MB / 0.7084 GB, free: 2.219 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007842/0.9029, allocations: 460.5 kB / 0.7088 GB, free: 1.762 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 0.0001225/0.9031, allocations: 305.1 kB / 0.7091 GB, free: 1.461 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002121/0.9052, allocations: 1.229 MB / 0.7103 GB, free: 228 kB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002667/0.9078, allocations: 1.806 MB / 0.712 GB, free: 14.18 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004831/0.9127, allocations: 3.159 MB / 0.7151 GB, free: 10.86 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.546e-06/0.9127, allocations: 4 kB / 0.7151 GB, free: 10.86 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=299) (initialization): time 0.00742/0.9201, allocations: 4.234 MB / 0.7193 GB, free: 6.715 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 2.749e-05/0.9201, allocations: 12 kB / 0.7193 GB, free: 6.703 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.258e-05/0.9202, allocations: 16 kB / 0.7193 GB, free: 6.688 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01435/0.9345, allocations: 3.641 MB / 0.7229 GB, free: 3.055 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001222/0.9357, allocations: 334.2 kB / 0.7232 GB, free: 2.75 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007506/0.9432, allocations: 3.947 MB / 0.727 GB, free: 14.81 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002927/0.9462, allocations: 95.94 kB / 0.7271 GB, free: 14.71 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003738/0.9465, allocations: 115.9 kB / 0.7272 GB, free: 14.6 MB / 0.5919 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004689/0.9512, allocations: 1.679 MB / 0.7289 GB, free: 12.91 MB / 0.5919 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00409/0.9553, allocations: 1.751 MB / 0.7306 GB, free: 10.92 MB / 0.5919 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.007497/0.9628, allocations: 3.048 MB / 0.7336 GB, free: 7.695 MB / 0.5919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.471e-05/0.9628, allocations: 4 kB / 0.7336 GB, free: 7.691 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=299) (initialization_lambda0): time 0.0113/0.9741, allocations: 3.975 MB / 0.7374 GB, free: 3.812 MB / 0.5919 GB
Notification: Performance of prepare postOptimizeDAE: time 5.586e-05/0.9742, allocations: 12 kB / 0.7374 GB, free: 3.801 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.396e-05/0.9742, allocations: 20 kB / 0.7375 GB, free: 3.781 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01185/0.9861, allocations: 2.746 MB / 0.7402 GB, free: 1.039 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001592/0.9877, allocations: 374 kB / 0.7405 GB, free: 0.6992 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007138/0.9948, allocations: 3.48 MB / 0.7439 GB, free: 13.23 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002824/0.9976, allocations: 87.95 kB / 0.744 GB, free: 13.14 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003536/0.998, allocations: 112 kB / 0.7441 GB, free: 13.04 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.0008321/0.9988, allocations: 320.8 kB / 0.7444 GB, free: 12.74 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007109/0.9995, allocations: 242.4 kB / 0.7446 GB, free: 12.5 MB / 0.6075 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01138/1.011, allocations: 3.927 MB / 0.7485 GB, free: 8.57 MB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.006374/1.017, allocations: 3.357 MB / 0.7517 GB, free: 5.133 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.53e-05/1.017, allocations: 4 kB / 0.7518 GB, free: 5.129 MB / 0.6075 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001476/1.019, allocations: 0.5968 MB / 0.7523 GB, free: 4.512 MB / 0.6075 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004179/1.019, allocations: 55.98 kB / 0.7524 GB, free: 4.457 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001378/1.021, allocations: 75.94 kB / 0.7525 GB, free: 4.383 MB / 0.6075 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003531/1.021, allocations: 172.9 kB / 0.7526 GB, free: 4.215 MB / 0.6075 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.008995/1.03, allocations: 3.771 MB / 0.7563 GB, free: 484 kB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.1779/1.208, allocations: 4.725 MB / 0.7609 GB, free: 281.9 MB / 0.6075 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001696/1.209, allocations: 1.316 MB / 0.7622 GB, free: 281.5 MB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.001515/1.211, allocations: 1.223 MB / 0.7634 GB, free: 281 MB / 0.6075 GB
Notification: Performance of Backend: time 3.4e-07/1.211, allocations: 3.906 kB / 0.7634 GB, free: 281 MB / 0.6075 GB
Notification: Performance of simCode: created initialization part: time 0.01091/1.222, allocations: 4.741 MB / 0.768 GB, free: 279.8 MB / 0.6075 GB
Notification: Performance of SimCode: time 0.007451/1.229, allocations: 6.133 MB / 0.774 GB, free: 277 MB / 0.6075 GB
Notification: Performance of Templates: time 0.08137/1.311, allocations: 77.14 MB / 0.8494 GB, free: 201.9 MB / 0.6075 GB
"
[Timeout remaining time 659]
make -j1 -f Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem.makefile [Timeout 660]
(rm -f Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem.pipe ; mkfifo Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem.pipe >> ../files/Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem.sim & ./Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.0.0_Modelica.Fluid.Examples.HeatingSystem.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 8.189839256927371]
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.318701195064932 1.327243562 0.50851808
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe64b01e050>