Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV.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.00157/0.00157, allocations: 104.3 kB / 19.76 MB, free: 1.906 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.00181/0.00181, allocations: 212.5 kB / 23.06 MB, free: 4.984 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.541/1.541, allocations: 230.7 MB / 256.9 MB, free: 7.727 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream main/package.mo): time 0.9312/0.9312, allocations: 100.9 MB / 414.2 MB, free: 2.832 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package ThermofluidStream with version 1.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream main/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(ThermofluidStream.FlowControl.Tests.MCV,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|mCV.dp_int|mCV1.dp_int|mCV2.dp_int|mCV3.dp_int|mCV4.dp_int|mCV5.dp_int|mCV6.dp_int|pulse1.T_start|pulse1.count|pulse3.T_start|pulse3.count|pulse5.T_start|pulse5.count|source.outlet.m_flow|source1.outlet.m_flow|source2.outlet.m_flow|source3.outlet.m_flow|source4.outlet.m_flow|source5.outlet.m_flow|source6.outlet.m_flow|trapezoid.T_start|trapezoid.count|trapezoid1.T_start|trapezoid1.count|trapezoid2.T_start|trapezoid2.count",fileNamePrefix="ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV")
translateModel(ThermofluidStream.FlowControl.Tests.MCV,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|mCV.dp_int|mCV1.dp_int|mCV2.dp_int|mCV3.dp_int|mCV4.dp_int|mCV5.dp_int|mCV6.dp_int|pulse1.T_start|pulse1.count|pulse3.T_start|pulse3.count|pulse5.T_start|pulse5.count|source.outlet.m_flow|source1.outlet.m_flow|source2.outlet.m_flow|source3.outlet.m_flow|source4.outlet.m_flow|source5.outlet.m_flow|source6.outlet.m_flow|trapezoid.T_start|trapezoid.count|trapezoid1.T_start|trapezoid1.count|trapezoid2.T_start|trapezoid2.count",fileNamePrefix="ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.315e-05/2.315e-05, allocations: 5.781 kB / 0.5654 GB, free: 35.32 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(ThermofluidStream.FlowControl.Tests.MCV): time 0.2367/0.2367, allocations: 173.3 MB / 0.7346 GB, free: 2.715 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.4808/0.7175, allocations: 22.27 MB / 0.7564 GB, free: 3.609 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003505/0.721, allocations: 152.1 kB / 0.7565 GB, free: 3.609 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.004332/0.7253, allocations: 1.044 MB / 0.7576 GB, free: 3.609 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.01053/0.7358, allocations: 2.627 MB / 0.7601 GB, free: 3.562 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007534/0.7434, allocations: 1.897 MB / 0.762 GB, free: 3.523 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.009318/0.7527, allocations: 4.489 MB / 0.7664 GB, free: 3.516 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001762/0.7545, allocations: 336.6 kB / 0.7667 GB, free: 19.43 MB / 0.5919 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006885/0.7613, allocations: 1.97 MB / 0.7686 GB, free: 19.43 MB / 0.5919 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006578/0.7679, allocations: 1.523 MB / 0.7701 GB, free: 19.43 MB / 0.5919 GB
Notification: Performance of NFPackage.collectConstants: time 0.001638/0.7696, allocations: 184.6 kB / 0.7703 GB, free: 19.43 MB / 0.5919 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01358/0.7831, allocations: 3.138 MB / 0.7733 GB, free: 19.37 MB / 0.5919 GB
Notification: Performance of combineBinaries: time 0.008879/0.792, allocations: 5.56 MB / 0.7788 GB, free: 16.77 MB / 0.5919 GB
Notification: Performance of replaceArrayConstructors: time 0.004811/0.7968, allocations: 3.672 MB / 0.7823 GB, free: 14.49 MB / 0.5919 GB
Notification: Performance of NFVerifyModel.verify: time 0.002189/0.799, allocations: 224.8 kB / 0.7826 GB, free: 14.49 MB / 0.5919 GB
Notification: Performance of FrontEnd: time 0.001338/0.8004, allocations: 257.5 kB / 0.7828 GB, free: 14.46 MB / 0.5919 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 395 (375)
 * Number of variables: 395 (395)
Notification: Performance of [SIM] Bindings: time 0.02479/0.8252, allocations: 11.85 MB / 0.7944 GB, free: 9.676 MB / 0.5919 GB
Notification: Performance of [SIM] FunctionAlias: time 0.003339/0.8285, allocations: 1.335 MB / 0.7957 GB, free: 9.434 MB / 0.5919 GB
Notification: Performance of [SIM] Early Inline: time 0.01132/0.8398, allocations: 5.877 MB / 0.8014 GB, free: 9.289 MB / 0.5919 GB
Notification: Performance of [SIM] Simplify 1: time 0.003316/0.8431, allocations: 1.029 MB / 0.8024 GB, free: 8.977 MB / 0.5919 GB
Notification: Performance of [SIM] Alias: time 0.01584/0.859, allocations: 7.328 MB / 0.8096 GB, free: 6.668 MB / 0.5919 GB
Notification: Performance of [SIM] Simplify 2: time 0.002551/0.8615, allocations: 0.8349 MB / 0.8104 GB, free: 6.43 MB / 0.5919 GB
Notification: Performance of [SIM] Remove Stream: time 0.001185/0.8627, allocations: 0.5036 MB / 0.8109 GB, free: 6.309 MB / 0.5919 GB
Notification: Performance of [SIM] Detect States: time 0.003567/0.8663, allocations: 1.291 MB / 0.8122 GB, free: 6.027 MB / 0.5919 GB
Notification: Performance of [SIM] Events: time 0.001626/0.8679, allocations: 457.5 kB / 0.8126 GB, free: 5.863 MB / 0.5919 GB
Notification: Performance of [SIM] Partitioning: time 0.004488/0.8724, allocations: 1.881 MB / 0.8144 GB, free: 5.664 MB / 0.5919 GB
Notification: Performance of [SIM] Causalize: time 0.007821/0.8802, allocations: 4.105 MB / 0.8184 GB, free: 5.344 MB / 0.5919 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.005398/0.8856, allocations: 3.6 MB / 0.822 GB, free: 5.34 MB / 0.5919 GB
Notification: Performance of [INI] Simplify: time 0.008208/0.8938, allocations: 3.027 MB / 0.8249 GB, free: 4.504 MB / 0.5919 GB
Notification: Performance of [INI] Inline: time 0.0101/0.9039, allocations: 6.524 MB / 0.8313 GB, free: 1.652 MB / 0.5919 GB
Notification: Performance of [INI] Partitioning: time 0.0009781/0.9049, allocations: 121.7 kB / 0.8314 GB, free: 1.582 MB / 0.5919 GB
Notification: Performance of [INI] Cleanup: time 0.002214/0.9071, allocations: 0.9684 MB / 0.8323 GB, free: 0.7656 MB / 0.5919 GB
Notification: Performance of [INI] Causalize: time 0.01368/0.9208, allocations: 6.464 MB / 0.8387 GB, free: 14.7 MB / 0.6075 GB
Notification: Performance of [INI] Tearing: time 0.0005444/0.9213, allocations: 380.5 kB / 0.839 GB, free: 14.48 MB / 0.6075 GB
Notification: Performance of [SIM] Initialization: time 2.785e-06/0.9213, allocations: 0 / 0.839 GB, free: 14.48 MB / 0.6075 GB
Notification: Performance of [SIM] DAE-Mode: time 0.002637/0.924, allocations: 1.43 MB / 0.8404 GB, free: 13.8 MB / 0.6075 GB
Notification: Performance of [SIM] Remove Dummies: time 3.374e-05/0.924, allocations: 4.734 kB / 0.8404 GB, free: 13.8 MB / 0.6075 GB
Notification: Performance of [SIM] Tearing: time 0.00044/0.9244, allocations: 448.3 kB / 0.8408 GB, free: 13.53 MB / 0.6075 GB
Notification: Performance of [SIM] Categorize: time 6.108e-05/0.9245, allocations: 12.8 kB / 0.8409 GB, free: 13.53 MB / 0.6075 GB
Notification: Performance of [SIM] Solve: time 0.01026/0.9348, allocations: 5.327 MB / 0.8461 GB, free: 9.852 MB / 0.6075 GB
Notification: Performance of [SIM] Jacobian: time 0.01025/0.945, allocations: 7.799 MB / 0.8537 GB, free: 1.176 MB / 0.6075 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 1
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 6
 * Number of algebraic event partitions: ......... 0
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 14 (14) {mCV.dp_int, mCV.m_flow, mCV1.dp_int, mCV1.m_flow, mCV2.dp_int, mCV2.m_flow, mCV3.dp_int, mCV3.m_flow, mCV4.dp_int, mCV4.m_flow, mCV5.dp_int, mCV5.m_flow, mCV6.dp_int, mCV6.m_flow}
 * Number of discrete states: .................... 12 (12) {trapezoid2.T_start, trapezoid2.count, pulse5.T_start, pulse5.count, trapezoid1.T_start, trapezoid1.count, pulse3.T_start, pulse3.count, trapezoid.T_start, trapezoid.count, pulse1.T_start, pulse1.count}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 24 (24) {$SEV_23, $SEV_22, $SEV_21, $SEV_20, $SEV_19, $SEV_18, $SEV_17, $SEV_16, $SEV_15, $SEV_14, $SEV_13, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_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: ........... 233 (scalar:233, array:0, record:0)
 * Number of multi strong components: ............ 12 (algorithm:0, when:12, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 7 (linear: 7, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 372 (scalar:372, array:0, record:0)
 * Number of multi strong components: ............ 6 (algorithm:6, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 7 (linear: 7, nonlinear:0)
Notification: Performance of backend: time 0.0002997/0.9453, allocations: 119.4 kB / 0.8538 GB, free: 1.07 MB / 0.6075 GB
Notification: Performance of SimCode: time 0.02708/0.9724, allocations: 11.59 MB / 0.8651 GB, free: 5.734 MB / 0.6231 GB
Notification: Performance of Templates: time 0.07066/1.043, allocations: 36.84 MB / 0.9011 GB, free: 1.047 MB / 0.6544 GB
"
[Timeout remaining time 659]
make -j1 -f ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV.makefile [Timeout 660]
(rm -f ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV.pipe ; mkfifo ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV.pipe ; head -c 1048576 < ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV.pipe >> ../files/ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV.sim & ./ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV  -abortSlowSimulation -alarm=1200  -emit_protected -lv LOG_STATS > ThermofluidStream_dev_ThermofluidStream.FlowControl.Tests.MCV.pipe 2>&1) [Timeout 1200]
[Calling os._exit(0), Time elapsed: 8.430038244987372]
Failed to read output from testmodel.py, exit status != 0:
1.0534088760032319 1.070909141 0.26635362700000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff951eb6fb0>