Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial.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.001822/0.001822, allocations: 106.8 kB / 21.19 MB, free: 0.6406 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.002053/0.002053, allocations: 214.8 kB / 24.49 MB, free: 3.934 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.442/1.442, allocations: 230.6 MB / 258.3 MB, free: 7.797 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo): time 0.247/0.247, allocations: 46.97 MB / 361.6 MB, free: 4.145 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+maint.om/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: translateModel(ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial,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="ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial")
translateModel(ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial,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="ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002993/0.002993, allocations: 63.91 kB / 0.4974 GB, free: 7.238 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.357/0.3599, allocations: 66.03 MB / 0.5618 GB, free: 4.199 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial): time 0.09831/0.4583, allocations: 81.75 MB / 0.6417 GB, free: 2.168 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0331/0.4914, allocations: 19.63 MB / 0.6608 GB, free: 14.49 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002248/0.4936, allocations: 126.9 kB / 0.661 GB, free: 14.37 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.002452/0.4961, allocations: 0.9084 MB / 0.6619 GB, free: 13.45 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.009893/0.506, allocations: 3.892 MB / 0.6657 GB, free: 9.543 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0146/0.5206, allocations: 6.055 MB / 0.6716 GB, free: 3.488 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.0107/0.5313, allocations: 7.436 MB / 0.6788 GB, free: 12.03 MB / 0.5137 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003519/0.5348, allocations: 1.626 MB / 0.6804 GB, free: 10.38 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.4782/1.013, allocations: 74.5 MB / 0.7532 GB, free: 53.09 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0575/1.07, allocations: 31.9 MB / 0.7843 GB, free: 43.76 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 0.0009016/1.071, allocations: 251 kB / 0.7846 GB, free: 43.76 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01184/1.083, allocations: 5.509 MB / 0.7899 GB, free: 42.81 MB / 0.5606 GB
Notification: Performance of NFScalarize.scalarize: time 0.00197/1.085, allocations: 1.125 MB / 0.791 GB, free: 42.81 MB / 0.5606 GB
Notification: Performance of NFVerifyModel.verify: time 0.002085/1.087, allocations: 1.253 MB / 0.7923 GB, free: 42.81 MB / 0.5606 GB
Notification: Performance of NFConvertDAE.convert: time 0.00865/1.096, allocations: 5.415 MB / 0.7975 GB, free: 41.12 MB / 0.5606 GB
Notification: Performance of FrontEnd - DAE generated: time 5.751e-06/1.096, allocations: 8 kB / 0.7976 GB, free: 41.11 MB / 0.5606 GB
Notification: Performance of FrontEnd: time 1.573e-06/1.096, allocations: 0 / 0.7976 GB, free: 41.11 MB / 0.5606 GB
Notification: Performance of Transformations before backend: time 5.155e-05/1.096, allocations: 0 / 0.7976 GB, free: 41.11 MB / 0.5606 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.008754/1.105, allocations: 3.513 MB / 0.801 GB, free: 37.99 MB / 0.5606 GB
Notification: Performance of prepare preOptimizeDAE: time 4.754e-05/1.105, allocations: 12.03 kB / 0.801 GB, free: 37.98 MB / 0.5606 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0009519/1.106, allocations: 321.4 kB / 0.8013 GB, free: 37.66 MB / 0.5606 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002915/1.109, allocations: 1.591 MB / 0.8029 GB, free: 36.42 MB / 0.5606 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001699/1.109, allocations: 276.8 kB / 0.8031 GB, free: 36.19 MB / 0.5606 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003039/1.109, allocations: 191.2 kB / 0.8033 GB, free: 36.07 MB / 0.5606 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004536/1.114, allocations: 3.669 MB / 0.8069 GB, free: 33.18 MB / 0.5606 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.284e-05/1.114, allocations: 3.656 kB / 0.8069 GB, free: 33.18 MB / 0.5606 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002249/1.114, allocations: 84.33 kB / 0.807 GB, free: 33.16 MB / 0.5606 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000237/1.114, allocations: 250.5 kB / 0.8072 GB, free: 32.95 MB / 0.5606 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004297/1.118, allocations: 3.108 MB / 0.8102 GB, free: 30.88 MB / 0.5606 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.0161/1.135, allocations: 9.704 MB / 0.8197 GB, free: 23.38 MB / 0.5606 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003649/1.138, allocations: 2.094 MB / 0.8218 GB, free: 22.03 MB / 0.5606 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00172/1.14, allocations: 1.289 MB / 0.823 GB, free: 21.17 MB / 0.5606 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00675/1.147, allocations: 3.473 MB / 0.8264 GB, free: 17.72 MB / 0.5606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.83e-05/1.147, allocations: 57.44 kB / 0.8265 GB, free: 17.66 MB / 0.5606 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0006356/1.147, allocations: 150.9 kB / 0.8266 GB, free: 17.51 MB / 0.5606 GB
Notification: Performance of pre-optimization done (n=171): time 4.088e-06/1.147, allocations: 0 / 0.8266 GB, free: 17.51 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=197): time 0.02851/1.176, allocations: 10.35 MB / 0.8367 GB, free: 7.172 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.933e-05/1.176, allocations: 73.41 kB / 0.8368 GB, free: 7.09 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001674/1.178, allocations: 1.482 MB / 0.8382 GB, free: 5.602 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002021/1.178, allocations: 65.7 kB / 0.8383 GB, free: 5.531 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004552/1.178, allocations: 1.086 MB / 0.8394 GB, free: 4.445 MB / 0.5606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004137/1.179, allocations: 0.7074 MB / 0.8401 GB, free: 3.746 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0006404/1.179, allocations: 460.5 kB / 0.8405 GB, free: 3.289 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 3.311e-05/1.179, allocations: 301.1 kB / 0.8408 GB, free: 2.992 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001622/1.181, allocations: 1.239 MB / 0.842 GB, free: 1.746 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001994/1.183, allocations: 1.801 MB / 0.8438 GB, free: 15.71 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004926/1.188, allocations: 3.158 MB / 0.8468 GB, free: 12.38 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.275e-05/1.188, allocations: 8 kB / 0.8468 GB, free: 12.37 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=299) (initialization): time 0.008293/1.196, allocations: 3.984 MB / 0.8507 GB, free: 8.469 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 3.481e-05/1.196, allocations: 16 kB / 0.8507 GB, free: 8.453 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.728e-05/1.196, allocations: 16.91 kB / 0.8508 GB, free: 8.438 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005121/1.201, allocations: 1.239 MB / 0.852 GB, free: 7.18 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002382/1.204, allocations: 469.6 kB / 0.8524 GB, free: 6.75 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01168/1.215, allocations: 10.54 MB / 0.8627 GB, free: 11.7 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002499/1.218, allocations: 92.12 kB / 0.8628 GB, free: 11.61 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000343/1.218, allocations: 120.1 kB / 0.8629 GB, free: 11.5 MB / 0.5919 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003184/1.221, allocations: 1.676 MB / 0.8646 GB, free: 9.812 MB / 0.5919 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002654/1.224, allocations: 1.751 MB / 0.8663 GB, free: 7.82 MB / 0.5919 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.004892/1.229, allocations: 3.047 MB / 0.8692 GB, free: 4.605 MB / 0.5919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.546e-05/1.229, allocations: 4 kB / 0.8692 GB, free: 4.602 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=299) (initialization_lambda0): time 0.007608/1.237, allocations: 3.752 MB / 0.8729 GB, free: 0.9336 MB / 0.5919 GB
Notification: Performance of prepare postOptimizeDAE: time 2.853e-05/1.237, allocations: 14.5 kB / 0.8729 GB, free: 0.9219 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.247e-05/1.237, allocations: 20 kB / 0.8729 GB, free: 0.9023 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002652/1.239, allocations: 0.8345 MB / 0.8738 GB, free: 44 kB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002599/1.242, allocations: 0.4995 MB / 0.8742 GB, free: 15.57 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01052/1.252, allocations: 10.24 MB / 0.8842 GB, free: 4.812 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002286/1.255, allocations: 91.95 kB / 0.8843 GB, free: 4.723 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002779/1.255, allocations: 116 kB / 0.8844 GB, free: 4.609 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 (254):
 * Single equations (assignments): 246
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 8
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 5 systems
   {(1,2,100.0%), (1,6,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(5,14), (2,6), (1,6)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007178/1.256, allocations: 332.2 kB / 0.8848 GB, free: 4.312 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004912/1.256, allocations: 214.6 kB / 0.885 GB, free: 4.102 MB / 0.6075 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008164/1.264, allocations: 3.921 MB / 0.8888 GB, free: 208 kB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.006252/1.271, allocations: 3.361 MB / 0.8921 GB, free: 12.77 MB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.546e-06/1.271, allocations: 0 / 0.8921 GB, free: 12.77 MB / 0.6231 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001551/1.272, allocations: 0.5021 MB / 0.8926 GB, free: 12.26 MB / 0.6231 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003817/1.273, allocations: 51.98 kB / 0.8926 GB, free: 12.21 MB / 0.6231 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001283/1.274, allocations: 71.94 kB / 0.8927 GB, free: 12.14 MB / 0.6231 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003826/1.274, allocations: 177.6 kB / 0.8929 GB, free: 11.97 MB / 0.6231 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.008829/1.283, allocations: 3.748 MB / 0.8965 GB, free: 8.242 MB / 0.6231 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.008079/1.291, allocations: 4.722 MB / 0.9011 GB, free: 3.5 MB / 0.6231 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002217/1.293, allocations: 1.315 MB / 0.9024 GB, free: 2.184 MB / 0.6231 GB
Notification: Performance of sorting global known variables: time 0.001625/1.295, allocations: 0.9549 MB / 0.9033 GB, free: 1.234 MB / 0.6231 GB
Notification: Performance of Backend: time 3.91e-07/1.295, allocations: 0 / 0.9033 GB, free: 1.234 MB / 0.6231 GB
Notification: Performance of simCode: created initialization part: time 0.01452/1.309, allocations: 5.834 MB / 0.909 GB, free: 11.48 MB / 0.6387 GB
Notification: Performance of SimCode: time 0.01093/1.32, allocations: 6.034 MB / 0.9149 GB, free: 5.48 MB / 0.6387 GB
Notification: Performance of Templates: time 0.1169/1.437, allocations: 74.81 MB / 0.988 GB, free: 11.61 MB / 0.7169 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial.pipe ; mkfifo ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial.pipe ; head -c 1048576 < ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial.pipe >> ../files/ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial.sim & ./ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0_ModelicaTest.Fluid.TestExamplesVariants.HeatingSystem_FixedInitial.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 8.641872339001566]
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.4490595769966603 1.689941194 0.5900061320000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa9d198af50>