Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.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.001694/0.001694, allocations: 99.59 kB / 21.24 MB, free: 0.5742 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.002086/0.002086, allocations: 213.1 kB / 24.54 MB, free: 3.832 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.553/1.553, allocations: 230.6 MB / 258.3 MB, free: 7.734 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.0+maint.om/package.mo): time 0.2273/0.2273, allocations: 44.94 MB / 359.7 MB, free: 6.023 MB / 286.1 MB
"
[Timeout remaining time 180]
Using package ModelicaTest with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.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.Pipes.IdealMixing1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|pipeFriction1.port_b.m_flow|pipeFriction1.port_b.p|pipeFriction1.port_b.h_outflow|pipeFriction2.port_b.m_flow|pipeFriction2.port_b.p|pipeFriction2.port_b.h_outflow|pipeFriction3.port_b.m_flow|pipeFriction3.port_b.p|pipeFriction3.port_b.h_outflow|boundary1.ports.1..m_flow|boundary1.ports.1..p|boundary1.ports.1..h_outflow|boundary1.ports.1..Xi_outflow.1.|boundary1.ports.1..Xi_outflow.2.|boundary1.ports.1..Xi_outflow.3.|boundary1.ports.1..Xi_outflow.4.|boundary1.ports.1..Xi_outflow.5.|boundary1.ports.1..Xi_outflow.6.|boundary2.ports.1..m_flow|boundary2.ports.1..p|boundary2.ports.1..h_outflow|boundary2.ports.1..Xi_outflow.1.|boundary2.ports.1..Xi_outflow.2.|boundary2.ports.1..Xi_outflow.3.|boundary2.ports.1..Xi_outflow.4.|boundary2.ports.1..Xi_outflow.5.|boundary2.ports.1..Xi_outflow.6.|boundary3.ports.1..m_flow|boundary3.ports.1..p|boundary3.ports.1..h_outflow|boundary3.ports.1..Xi_outflow.1.|boundary3.ports.1..Xi_outflow.2.|boundary3.ports.1..Xi_outflow.3.|boundary3.ports.1..Xi_outflow.4.|boundary3.ports.1..Xi_outflow.5.|boundary3.ports.1..Xi_outflow.6.|sine1.y|sine2.y|sine3.y",fileNamePrefix="ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1")
translateModel(ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|pipeFriction1.port_b.m_flow|pipeFriction1.port_b.p|pipeFriction1.port_b.h_outflow|pipeFriction2.port_b.m_flow|pipeFriction2.port_b.p|pipeFriction2.port_b.h_outflow|pipeFriction3.port_b.m_flow|pipeFriction3.port_b.p|pipeFriction3.port_b.h_outflow|boundary1.ports.1..m_flow|boundary1.ports.1..p|boundary1.ports.1..h_outflow|boundary1.ports.1..Xi_outflow.1.|boundary1.ports.1..Xi_outflow.2.|boundary1.ports.1..Xi_outflow.3.|boundary1.ports.1..Xi_outflow.4.|boundary1.ports.1..Xi_outflow.5.|boundary1.ports.1..Xi_outflow.6.|boundary2.ports.1..m_flow|boundary2.ports.1..p|boundary2.ports.1..h_outflow|boundary2.ports.1..Xi_outflow.1.|boundary2.ports.1..Xi_outflow.2.|boundary2.ports.1..Xi_outflow.3.|boundary2.ports.1..Xi_outflow.4.|boundary2.ports.1..Xi_outflow.5.|boundary2.ports.1..Xi_outflow.6.|boundary3.ports.1..m_flow|boundary3.ports.1..p|boundary3.ports.1..h_outflow|boundary3.ports.1..Xi_outflow.1.|boundary3.ports.1..Xi_outflow.2.|boundary3.ports.1..Xi_outflow.3.|boundary3.ports.1..Xi_outflow.4.|boundary3.ports.1..Xi_outflow.5.|boundary3.ports.1..Xi_outflow.6.|sine1.y|sine2.y|sine3.y",fileNamePrefix="ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 4.975e-05/4.975e-05, allocations: 2.281 kB / 0.4943 GB, free: 10.05 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1): time 0.4758/0.4758, allocations: 174.9 MB / 0.6651 GB, free: 5.898 MB / 494.1 MB
Notification: Performance of NFInst.instExpressions: time 0.02176/0.4976, allocations: 13.16 MB / 0.6779 GB, free: 8.703 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001334/0.4989, allocations: 51.5 kB / 0.678 GB, free: 8.652 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.001625/0.5005, allocations: 0.5202 MB / 0.6785 GB, free: 8.129 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.009897/0.5104, allocations: 3.377 MB / 0.6818 GB, free: 4.746 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01063/0.5211, allocations: 3.891 MB / 0.6856 GB, free: 0.8633 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.006962/0.528, allocations: 4.856 MB / 0.6903 GB, free: 12 MB / 0.5137 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003257/0.5313, allocations: 1.737 MB / 0.692 GB, free: 10.24 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005414/0.5367, allocations: 2.583 MB / 0.6945 GB, free: 7.652 MB / 0.5137 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006754/0.5434, allocations: 3.648 MB / 0.6981 GB, free: 3.996 MB / 0.5137 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005244/0.544, allocations: 184 kB / 0.6983 GB, free: 3.816 MB / 0.5137 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01016/0.5541, allocations: 3.883 MB / 0.7021 GB, free: 15.93 MB / 0.5294 GB
Notification: Performance of combineBinaries: time 0.006303/0.5604, allocations: 4.6 MB / 0.7066 GB, free: 11.3 MB / 0.5294 GB
Notification: Performance of replaceArrayConstructors: time 0.002311/0.5627, allocations: 1.841 MB / 0.7084 GB, free: 9.445 MB / 0.5294 GB
Notification: Performance of NFVerifyModel.verify: time 0.0008905/0.5636, allocations: 104 kB / 0.7085 GB, free: 9.344 MB / 0.5294 GB
Notification: Performance of FrontEnd: time 0.0002776/0.5639, allocations: 47.73 kB / 0.7085 GB, free: 9.297 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: 334 (161)
 * Number of variables: 334 (167)
Notification: Performance of [SIM] Bindings: time 0.01066/0.5746, allocations: 8.432 MB / 0.7167 GB, free: 0.6719 MB / 0.5294 GB
Notification: Performance of [SIM] FunctionAlias: time 0.002723/0.5773, allocations: 2.007 MB / 0.7187 GB, free: 14.66 MB / 0.545 GB
Notification: Performance of [SIM] Early Inline: time 0.01452/0.5918, allocations: 11.88 MB / 0.7303 GB, free: 2.684 MB / 0.545 GB
Notification: Performance of [SIM] Simplify 1: time 0.002623/0.5944, allocations: 1.236 MB / 0.7315 GB, free: 1.375 MB / 0.545 GB
Notification: Performance of [SIM] Alias: time 0.3051/0.8995, allocations: 5.071 MB / 0.7365 GB, free: 33.64 MB / 0.545 GB
Notification: Performance of [SIM] Simplify 2: time 0.002515/0.902, allocations: 1.161 MB / 0.7376 GB, free: 33.36 MB / 0.545 GB
Notification: Performance of [SIM] Remove Stream: time 0.002892/0.9049, allocations: 1.762 MB / 0.7393 GB, free: 33.07 MB / 0.545 GB
Notification: Performance of [SIM] Detect States: time 0.00184/0.9068, allocations: 1.518 MB / 0.7408 GB, free: 32.94 MB / 0.545 GB
Notification: Performance of [SIM] Events: time 0.0009043/0.9077, allocations: 499.1 kB / 0.7413 GB, free: 32.88 MB / 0.545 GB
Notification: Performance of [SIM] Partitioning: time 0.003663/0.9113, allocations: 2.87 MB / 0.7441 GB, free: 32.66 MB / 0.545 GB
Notification: Performance of [SIM] Causalize: time 0.01115/0.9225, allocations: 6.686 MB / 0.7506 GB, free: 32.26 MB / 0.545 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.006426/0.9289, allocations: 6.042 MB / 0.7565 GB, free: 32.26 MB / 0.545 GB
Notification: Performance of [INI] Simplify: time 0.004319/0.9332, allocations: 2.398 MB / 0.7589 GB, free: 31.67 MB / 0.545 GB
Notification: Performance of [INI] Inline: time 0.01063/0.9439, allocations: 10.52 MB / 0.7691 GB, free: 31.63 MB / 0.545 GB
Notification: Performance of [INI] Partitioning: time 0.0007994/0.9447, allocations: 88.38 kB / 0.7692 GB, free: 31.57 MB / 0.545 GB
Notification: Performance of [INI] Cleanup: time 0.004023/0.9487, allocations: 2.008 MB / 0.7712 GB, free: 31.53 MB / 0.545 GB
Notification: Performance of [INI] Causalize: time 0.02252/0.9712, allocations: 16.11 MB / 0.7869 GB, free: 30.36 MB / 0.545 GB
Notification: Performance of [INI] Tearing: time 0.001527/0.9727, allocations: 1.31 MB / 0.7882 GB, free: 29.98 MB / 0.545 GB
Notification: Performance of [SIM] Initialization: time 2.334e-06/0.9727, allocations: 0 / 0.7882 GB, free: 29.98 MB / 0.545 GB
Notification: Performance of [SIM] Remove Dummies: time 3.338e-05/0.9728, allocations: 2 kB / 0.7882 GB, free: 29.98 MB / 0.545 GB
Notification: Performance of [SIM] Tearing: time 0.001047/0.9738, allocations: 0.9147 MB / 0.7891 GB, free: 29.69 MB / 0.545 GB
Notification: Performance of [SIM] Categorize: time 5.647e-05/0.9739, allocations: 14.78 kB / 0.7891 GB, free: 29.69 MB / 0.545 GB
Notification: Performance of [SIM] Solve: time 0.009807/0.9837, allocations: 8.011 MB / 0.7969 GB, free: 28.76 MB / 0.545 GB
Notification: Performance of [SIM] Jacobian: time 0.01862/1.002, allocations: 15.95 MB / 0.8125 GB, free: 17.26 MB / 0.545 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 4
 * Number of ODE event partitions: ............... 0
 * Number of algebraic event partitions: ......... 7
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 0 (0) {}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 30 (15) {$SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_2, $TEV_1, $TEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 196 (scalar:178, array:18, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 3 (resizable: 3, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 1, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 250 (scalar:229, array:21, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 3 (resizable: 3, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 1, nonlinear:0)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 262 (scalar:241, array:21, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 12 (resizable: 12, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 1, nonlinear:0)
Notification: Performance of backend: time 0.000211/1.002, allocations: 120.7 kB / 0.8126 GB, free: 17.14 MB / 0.545 GB
Notification: Performance of SimCode: time 0.04066/1.043, allocations: 22.74 MB / 0.8348 GB, free: 13.64 MB / 0.5606 GB
Notification: Performance of Templates: time 0.1316/1.175, allocations: 75.47 MB / 0.9085 GB, free: 2.215 MB / 0.6231 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.makefile [Timeout 660]
(rm -f ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe ; mkfifo ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe ; head -c 1048576 < ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe >> ../files/ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.sim & ./ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.0.0_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 9.18649456743151]
Failed to read output from testmodel.py, exit status != 0:
['time', 'pipeFriction1.port_b.m_flow', 'pipeFriction1.port_b.p', 'pipeFriction1.port_b.h_outflow', 'pipeFriction2.port_b.m_flow', 'pipeFriction2.port_b.p', 'pipeFriction2.port_b.h_outflow', 'pipeFriction3.port_b.m_flow', 'pipeFriction3.port_b.p', 'pipeFriction3.port_b.h_outflow', 'boundary1.ports[1].m_flow', 'boundary1.ports[1].p', 'boundary1.ports[1].h_outflow', 'boundary1.ports[1].Xi_outflow[1]', 'boundary1.ports[1].Xi_outflow[2]', 'boundary1.ports[1].Xi_outflow[3]', 'boundary1.ports[1].Xi_outflow[4]', 'boundary1.ports[1].Xi_outflow[5]', 'boundary1.ports[1].Xi_outflow[6]', 'boundary2.ports[1].m_flow', 'boundary2.ports[1].p', 'boundary2.ports[1].h_outflow', 'boundary2.ports[1].Xi_outflow[1]', 'boundary2.ports[1].Xi_outflow[2]', 'boundary2.ports[1].Xi_outflow[3]', 'boundary2.ports[1].Xi_outflow[4]', 'boundary2.ports[1].Xi_outflow[5]', 'boundary2.ports[1].Xi_outflow[6]', 'boundary3.ports[1].m_flow', 'boundary3.ports[1].p', 'boundary3.ports[1].h_outflow', 'boundary3.ports[1].Xi_outflow[1]', 'boundary3.ports[1].Xi_outflow[2]', 'boundary3.ports[1].Xi_outflow[3]', 'boundary3.ports[1].Xi_outflow[4]', 'boundary3.ports[1].Xi_outflow[5]', 'boundary3.ports[1].Xi_outflow[6]', 'sine1.y', 'sine2.y', 'sine3.y']
1.1842134529724717 1.199702774 0.6323819629999999
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f15ce80ef20>