Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1.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.001318/0.001318, allocations: 110.8 kB / 21.19 MB, free: 0.6641 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.001442/0.001442, allocations: 205.2 kB / 24.49 MB, free: 3.965 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.451/1.451, allocations: 230.6 MB / 258.3 MB, free: 7.793 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.2243/0.2243, allocations: 46.97 MB / 361.6 MB, free: 4.043 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.TestComponents.Sensors.TestTemperature1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5004,variableFilter="time|Tmix1.T|Tmix2.T|Tdiff|volume1.medium.h|volume2.medium.h",fileNamePrefix="ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1")
translateModel(ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5004,variableFilter="time|Tmix1.T|Tmix2.T|Tdiff|volume1.medium.h|volume2.medium.h",fileNamePrefix="ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002049/0.002049, allocations: 67.83 kB / 0.4973 GB, free: 7.227 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3597/0.3618, allocations: 66.04 MB / 0.5618 GB, free: 4.168 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1): time 0.07629/0.4381, allocations: 65.95 MB / 0.6262 GB, free: 1.992 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03703/0.4751, allocations: 25.75 MB / 0.6514 GB, free: 8.16 MB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001959/0.4771, allocations: 47.56 kB / 0.6514 GB, free: 8.113 MB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002806/0.4799, allocations: 0.7724 MB / 0.6522 GB, free: 7.336 MB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0172/0.4971, allocations: 5.889 MB / 0.6579 GB, free: 1.441 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01375/0.5108, allocations: 4.434 MB / 0.6623 GB, free: 13.01 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.006229/0.5171, allocations: 3.836 MB / 0.666 GB, free: 9.164 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001619/0.5187, allocations: 0.8663 MB / 0.6668 GB, free: 8.27 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006953/0.5256, allocations: 3.189 MB / 0.67 GB, free: 5.051 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00234/0.528, allocations: 1.226 MB / 0.6712 GB, free: 3.82 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 0.000337/0.5283, allocations: 144 kB / 0.6713 GB, free: 3.68 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01407/0.5424, allocations: 7.044 MB / 0.6782 GB, free: 12.63 MB / 0.5137 GB
Notification: Performance of NFScalarize.scalarize: time 0.0006866/0.5431, allocations: 494.4 kB / 0.6786 GB, free: 12.14 MB / 0.5137 GB
Notification: Performance of NFVerifyModel.verify: time 0.001055/0.5441, allocations: 0.7042 MB / 0.6793 GB, free: 11.44 MB / 0.5137 GB
Notification: Performance of NFConvertDAE.convert: time 0.01278/0.5569, allocations: 5.906 MB / 0.6851 GB, free: 5.516 MB / 0.5137 GB
Notification: Performance of FrontEnd - DAE generated: time 6.903e-06/0.5569, allocations: 7.984 kB / 0.6851 GB, free: 5.508 MB / 0.5137 GB
Notification: Performance of FrontEnd: time 1.492e-06/0.5569, allocations: 0 / 0.6851 GB, free: 5.508 MB / 0.5137 GB
Notification: Performance of Transformations before backend: time 5.487e-05/0.557, allocations: 0 / 0.6851 GB, free: 5.508 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: 321
 * Number of variables: 321
Notification: Performance of Generate backend data structure: time 0.006054/0.563, allocations: 2.387 MB / 0.6874 GB, free: 3.078 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 4.617e-05/0.5631, allocations: 15.97 kB / 0.6875 GB, free: 3.062 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005612/0.5687, allocations: 0.6985 MB / 0.6881 GB, free: 2.363 MB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001728/0.5704, allocations: 0.829 MB / 0.6889 GB, free: 1.508 MB / 0.5137 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002885/0.5707, allocations: 340.7 kB / 0.6893 GB, free: 1.125 MB / 0.5137 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002081/0.5709, allocations: 123.9 kB / 0.6894 GB, free: 1.004 MB / 0.5137 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005418/0.5763, allocations: 2.708 MB / 0.692 GB, free: 14 MB / 0.5294 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.858e-05/0.5763, allocations: 15.81 kB / 0.692 GB, free: 13.98 MB / 0.5294 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00015/0.5765, allocations: 56 kB / 0.6921 GB, free: 13.93 MB / 0.5294 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.181e-05/0.5766, allocations: 58.09 kB / 0.6922 GB, free: 13.87 MB / 0.5294 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005267/0.5818, allocations: 1.967 MB / 0.6941 GB, free: 11.89 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.01214/0.594, allocations: 8.089 MB / 0.702 GB, free: 3.551 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003764/0.5977, allocations: 1.185 MB / 0.7031 GB, free: 2.359 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001084/0.5988, allocations: 484.1 kB / 0.7036 GB, free: 1.883 MB / 0.5294 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0009536/0.5998, allocations: 240.7 kB / 0.7038 GB, free: 1.648 MB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.85e-05/0.5998, allocations: 51.55 kB / 0.7039 GB, free: 1.59 MB / 0.5294 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.000536/0.6004, allocations: 95.23 kB / 0.704 GB, free: 1.496 MB / 0.5294 GB
Notification: Performance of pre-optimization done (n=88): time 3.277e-06/0.6004, allocations: 0 / 0.704 GB, free: 1.496 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=96): time 0.01253/0.6129, allocations: 3.911 MB / 0.7078 GB, free: 13.55 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.592e-05/0.613, allocations: 167.2 kB / 0.7079 GB, free: 13.35 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001092/0.6141, allocations: 0.8685 MB / 0.7088 GB, free: 12.45 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 8.809e-05/0.6142, allocations: 45.7 kB / 0.7088 GB, free: 12.4 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005053/0.6147, allocations: 0.6907 MB / 0.7095 GB, free: 11.7 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002909/0.615, allocations: 353 kB / 0.7098 GB, free: 11.36 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004287/0.6154, allocations: 315.4 kB / 0.7101 GB, free: 11.04 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001664/0.6156, allocations: 353.9 kB / 0.7105 GB, free: 10.69 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001184/0.6168, allocations: 0.5005 MB / 0.711 GB, free: 10.18 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001762/0.6185, allocations: 1.237 MB / 0.7122 GB, free: 8.625 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002925/0.6214, allocations: 1.709 MB / 0.7139 GB, free: 6.629 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.188e-06/0.6214, allocations: 8 kB / 0.7139 GB, free: 6.621 MB / 0.545 GB
Notification: Performance of matching and sorting (n=192) (initialization): time 0.003696/0.6251, allocations: 1.78 MB / 0.7156 GB, free: 4.828 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 3.301e-05/0.6252, allocations: 18.5 kB / 0.7156 GB, free: 4.812 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.588e-05/0.6252, allocations: 20 kB / 0.7156 GB, free: 4.793 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005262/0.6257, allocations: 125.8 kB / 0.7158 GB, free: 4.664 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00147/0.6272, allocations: 411.4 kB / 0.7161 GB, free: 4.262 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0009269/0.6281, allocations: 1.214 MB / 0.7173 GB, free: 2.996 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001065/0.6292, allocations: 87.94 kB / 0.7174 GB, free: 2.91 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001265/0.6293, allocations: 83.98 kB / 0.7175 GB, free: 2.828 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001564/0.6309, allocations: 0.7343 MB / 0.7182 GB, free: 2.09 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001836/0.6327, allocations: 1.241 MB / 0.7194 GB, free: 0.5312 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.003119/0.6358, allocations: 1.674 MB / 0.7211 GB, free: 14.57 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.112e-05/0.6358, allocations: 11.94 kB / 0.7211 GB, free: 14.55 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=192) (initialization_lambda0): time 0.003747/0.6396, allocations: 1.76 MB / 0.7228 GB, free: 12.78 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 2.138e-05/0.6396, allocations: 12 kB / 0.7228 GB, free: 12.77 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.163e-05/0.6396, allocations: 20.09 kB / 0.7228 GB, free: 12.75 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0004636/0.6401, allocations: 129.6 kB / 0.7229 GB, free: 12.62 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001481/0.6416, allocations: 411.2 kB / 0.7233 GB, free: 12.21 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0008825/0.6425, allocations: 1.202 MB / 0.7245 GB, free: 10.96 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001035/0.6435, allocations: 91.88 kB / 0.7246 GB, free: 10.87 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000137/0.6436, allocations: 91.92 kB / 0.7247 GB, free: 10.78 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 93
 * Number of states: 0 ()
 * Number of discrete variables: 18 (sink2.medium.phase,sink1.medium.phase,source2.medium.phase,volume2.heatTransfer.states[1].phase,source1.medium.phase,volume1.heatTransfer.states[1].phase,volume1.regularFlow[1],volume1.regularFlow[2],volume2.regularFlow[1],volume2.regularFlow[2],volume2.inFlow[2],volume2.inFlow[1],volume1.inFlow[2],volume1.inFlow[1],orifice2.state_b.phase,orifice2.state_a.phase,orifice1.state_b.phase,orifice1.state_a.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (190):
 * Single equations (assignments): 188
 * 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,1), (1,1)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005062/0.6441, allocations: 297.6 kB / 0.725 GB, free: 10.48 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002373/0.6444, allocations: 123.3 kB / 0.7251 GB, free: 10.36 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006919/0.6513, allocations: 5.117 MB / 0.7301 GB, free: 5.203 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.422e-05/0.6513, allocations: 3.984 kB / 0.7301 GB, free: 5.199 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.347e-06/0.6513, allocations: 7.938 kB / 0.7301 GB, free: 5.191 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006032/0.6519, allocations: 313.9 kB / 0.7304 GB, free: 4.883 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001828/0.6521, allocations: 55.62 kB / 0.7304 GB, free: 4.828 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008226/0.6529, allocations: 107.9 kB / 0.7306 GB, free: 4.723 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001822/0.6531, allocations: 96.81 kB / 0.7306 GB, free: 4.629 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004493/0.6576, allocations: 2.715 MB / 0.7333 GB, free: 1.887 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.2989/0.9565, allocations: 3.984 MB / 0.7372 GB, free: 162.9 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001447/0.958, allocations: 0.9874 MB / 0.7381 GB, free: 162.5 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.0009362/0.9589, allocations: 0.7442 MB / 0.7389 GB, free: 162.2 MB / 0.5606 GB
Notification: Performance of Backend: time 5.81e-07/0.9589, allocations: 7.281 kB / 0.7389 GB, free: 162.2 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.009472/0.9684, allocations: 3.255 MB / 0.7421 GB, free: 161.6 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.004598/0.973, allocations: 3.976 MB / 0.7459 GB, free: 159.6 MB / 0.5606 GB
Notification: Performance of Templates: time 0.06896/1.042, allocations: 67.89 MB / 0.8122 GB, free: 94.06 MB / 0.5606 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1.pipe ; mkfifo ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1.pipe ; head -c 1048576 < ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1.pipe >> ../files/ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1.sim & ./ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Sensors.TestTemperature1.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 8.611828428998706]
Failed to read output from testmodel.py, exit status != 0:
['time', 'Tmix1.T', 'Tmix2.T', 'Tdiff', 'volume1.medium.h', 'volume2.medium.h']
1.0520144969996181 1.060785787 0.501509981
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa05c232f50>