Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermofluidStream_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.001439/0.001439, 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.001847/0.001847, allocations: 209.8 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.564/1.564, allocations: 230.7 MB / 256.9 MB, free: 7.762 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.2.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.2.0/package.mo): time 0.8787/0.8787, allocations: 94.62 MB / 407.9 MB, free: 8.805 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package ThermofluidStream with version 1.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.2.0/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_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_ThermofluidStream.FlowControl.Tests.MCV") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.169e-05/2.169e-05, allocations: 2.281 kB / 0.5562 GB, free: 37.96 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(ThermofluidStream.FlowControl.Tests.MCV): time 0.2533/0.2533, allocations: 173.3 MB / 0.7255 GB, free: 10.86 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.03489/0.2882, allocations: 22.29 MB / 0.7473 GB, free: 4.508 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003561/0.2918, allocations: 154.5 kB / 0.7474 GB, free: 4.355 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.004333/0.2961, allocations: 1.036 MB / 0.7484 GB, free: 3.312 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.01036/0.3064, allocations: 2.629 MB / 0.751 GB, free: 0.668 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.4379/0.7443, allocations: 1.891 MB / 0.7528 GB, free: 6.457 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.008577/0.7529, allocations: 4.488 MB / 0.7572 GB, free: 6.457 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001608/0.7545, allocations: 339 kB / 0.7575 GB, free: 6.371 MB / 0.5762 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005787/0.7603, allocations: 1.966 MB / 0.7595 GB, free: 6.371 MB / 0.5762 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003864/0.7642, allocations: 1.526 MB / 0.761 GB, free: 6.371 MB / 0.5762 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008582/0.765, allocations: 184.3 kB / 0.7611 GB, free: 6.371 MB / 0.5762 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01098/0.776, allocations: 3.136 MB / 0.7642 GB, free: 6.371 MB / 0.5762 GB
Notification: Performance of combineBinaries: time 0.009215/0.7852, allocations: 5.568 MB / 0.7696 GB, free: 3.918 MB / 0.5762 GB
Notification: Performance of replaceArrayConstructors: time 0.006494/0.7917, allocations: 3.671 MB / 0.7732 GB, free: 1.645 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 0.002668/0.7944, allocations: 224.2 kB / 0.7734 GB, free: 1.645 MB / 0.5762 GB
Notification: Performance of FrontEnd: time 0.0009583/0.7953, allocations: 254.5 kB / 0.7737 GB, free: 1.613 MB / 0.5762 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.02182/0.8171, allocations: 11.84 MB / 0.7852 GB, free: 12.84 MB / 0.5919 GB
Notification: Performance of [SIM] FunctionAlias: time 0.003515/0.8207, allocations: 1.333 MB / 0.7865 GB, free: 12.62 MB / 0.5919 GB
Notification: Performance of [SIM] Early Inline: time 0.009209/0.8299, allocations: 5.889 MB / 0.7923 GB, free: 12.48 MB / 0.5919 GB
Notification: Performance of [SIM] Simplify 1: time 0.002997/0.8329, allocations: 1.033 MB / 0.7933 GB, free: 12.17 MB / 0.5919 GB
Notification: Performance of [SIM] Alias: time 0.01248/0.8453, allocations: 7.322 MB / 0.8004 GB, free: 9.875 MB / 0.5919 GB
Notification: Performance of [SIM] Simplify 2: time 0.00172/0.8471, allocations: 0.8362 MB / 0.8013 GB, free: 9.633 MB / 0.5919 GB
Notification: Performance of [SIM] Remove Stream: time 0.0008068/0.8479, allocations: 0.5053 MB / 0.8018 GB, free: 9.52 MB / 0.5919 GB
Notification: Performance of [SIM] Detect States: time 0.001817/0.8497, allocations: 1.293 MB / 0.803 GB, free: 9.238 MB / 0.5919 GB
Notification: Performance of [SIM] Events: time 0.0009333/0.8506, allocations: 459.2 kB / 0.8035 GB, free: 9.07 MB / 0.5919 GB
Notification: Performance of [SIM] Partitioning: time 0.002414/0.853, allocations: 1.878 MB / 0.8053 GB, free: 8.871 MB / 0.5919 GB
Notification: Performance of [SIM] Causalize: time 0.006056/0.8591, allocations: 4.114 MB / 0.8093 GB, free: 8.551 MB / 0.5919 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.005109/0.8642, allocations: 3.594 MB / 0.8128 GB, free: 8.547 MB / 0.5919 GB
Notification: Performance of [INI] Simplify: time 0.007941/0.8721, allocations: 3.012 MB / 0.8158 GB, free: 7.734 MB / 0.5919 GB
Notification: Performance of [INI] Inline: time 0.008401/0.8805, allocations: 6.525 MB / 0.8221 GB, free: 7.66 MB / 0.5919 GB
Notification: Performance of [INI] Partitioning: time 0.000849/0.8814, allocations: 131.8 kB / 0.8223 GB, free: 7.586 MB / 0.5919 GB
Notification: Performance of [INI] Cleanup: time 0.002099/0.8835, allocations: 0.9685 MB / 0.8232 GB, free: 7.012 MB / 0.5919 GB
Notification: Performance of [INI] Causalize: time 0.01315/0.8966, allocations: 6.472 MB / 0.8295 GB, free: 5.742 MB / 0.5919 GB
Notification: Performance of [INI] Tearing: time 0.0004627/0.8971, allocations: 373.1 kB / 0.8299 GB, free: 5.582 MB / 0.5919 GB
Notification: Performance of [SIM] Initialization: time 2.013e-06/0.8971, allocations: 1.125 kB / 0.8299 GB, free: 5.582 MB / 0.5919 GB
Notification: Performance of [SIM] DAE-Mode: time 0.00239/0.8995, allocations: 1.426 MB / 0.8313 GB, free: 5.121 MB / 0.5919 GB
Notification: Performance of [SIM] Remove Dummies: time 3.253e-05/0.8995, allocations: 4.578 kB / 0.8313 GB, free: 5.121 MB / 0.5919 GB
Notification: Performance of [SIM] Tearing: time 0.0004391/0.9, allocations: 437.3 kB / 0.8317 GB, free: 4.902 MB / 0.5919 GB
Notification: Performance of [SIM] Categorize: time 6.34e-05/0.9, allocations: 11.56 kB / 0.8317 GB, free: 4.902 MB / 0.5919 GB
Notification: Performance of [SIM] Solve: time 0.009872/0.9099, allocations: 5.332 MB / 0.8369 GB, free: 3.035 MB / 0.5919 GB
Notification: Performance of [SIM] Jacobian: time 0.009337/0.9192, allocations: 7.799 MB / 0.8445 GB, free: 11.82 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.0003195/0.9196, allocations: 114.5 kB / 0.8446 GB, free: 11.71 MB / 0.6075 GB
Notification: Performance of SimCode: time 0.02705/0.9466, allocations: 11.59 MB / 0.856 GB, free: 384 kB / 0.6075 GB
Notification: Performance of Templates: time 0.0706/1.017, allocations: 36.84 MB / 0.8919 GB, free: 11.73 MB / 0.6544 GB
"
[Timeout remaining time 659]
make -j1 -f ThermofluidStream_ThermofluidStream.FlowControl.Tests.MCV.makefile [Timeout 660]
(rm -f ThermofluidStream_ThermofluidStream.FlowControl.Tests.MCV.pipe ; mkfifo ThermofluidStream_ThermofluidStream.FlowControl.Tests.MCV.pipe ; head -c 1048576 < ThermofluidStream_ThermofluidStream.FlowControl.Tests.MCV.pipe >> ../files/ThermofluidStream_ThermofluidStream.FlowControl.Tests.MCV.sim & ./ThermofluidStream_ThermofluidStream.FlowControl.Tests.MCV  -abortSlowSimulation -alarm=1200  -emit_protected -lv LOG_STATS > ThermofluidStream_ThermofluidStream.FlowControl.Tests.MCV.pipe 2>&1) [Timeout 1200]
[Calling os._exit(0), Time elapsed: 8.434170771040954]
Failed to read output from testmodel.py, exit status != 0:
1.0272795329801738 1.043371 0.24417874399999995
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff888d2efb0>