Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy.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.001421/0.001421, allocations: 104.3 kB / 19.72 MB, free: 2.145 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.001591/0.001591, allocations: 213.8 kB / 23.01 MB, free: 4.961 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.312/1.312, allocations: 230.6 MB / 256.8 MB, free: 7.816 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 2.832/2.832, allocations: 0.49 GB / 0.7958 GB, free: 35.5 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/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(Buildings.Airflow.Multizone.Examples.ReverseBuoyancy,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|oriWesTop.port_a.m_flow|oriEasTop.port_a.m_flow|oriOutTop.port_a.m_flow|oriOutBot.port_a.m_flow|dooOpeClo.port_a1.m_flow|dooOpeClo.port_a2.m_flow|volBotWes.T|volBotEas.T|volTopWes.T|volTopEas.T|volOut.T|oriWesTop.dp|oriEasTop.dp|oriOutTop.dp|oriOutBot.dp|dooOpeClo.dpAB.1.",fileNamePrefix="Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy")
translateModel(Buildings.Airflow.Multizone.Examples.ReverseBuoyancy,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|oriWesTop.port_a.m_flow|oriEasTop.port_a.m_flow|oriOutTop.port_a.m_flow|oriOutBot.port_a.m_flow|dooOpeClo.port_a1.m_flow|dooOpeClo.port_a2.m_flow|volBotWes.T|volBotEas.T|volTopWes.T|volTopEas.T|volOut.T|oriWesTop.dp|oriEasTop.dp|oriOutTop.dp|oriOutBot.dp|dooOpeClo.dpAB.1.",fileNamePrefix="Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.165e-05/1.165e-05, allocations: 2.281 kB / 1.155 GB, free: 25.19 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Examples.ReverseBuoyancy): time 0.3078/0.3078, allocations: 280.1 MB / 1.429 GB, free: 8.367 MB / 1.123 GB
Notification: Performance of NFInst.instExpressions: time 0.02647/0.3343, allocations: 17.3 MB / 1.445 GB, free: 7.035 MB / 1.139 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002387/0.3367, allocations: 119.1 kB / 1.446 GB, free: 6.918 MB / 1.139 GB
Notification: Performance of NFTyping.typeComponents: time 0.004552/0.3412, allocations: 2.028 MB / 1.448 GB, free: 4.879 MB / 1.139 GB
Notification: Performance of NFTyping.typeBindings: time 0.01647/0.3577, allocations: 7.65 MB / 1.455 GB, free: 13.2 MB / 1.154 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007554/0.3652, allocations: 3.314 MB / 1.458 GB, free: 9.887 MB / 1.154 GB
Notification: Performance of NFFlatten.flatten: time 0.01122/0.3765, allocations: 9.425 MB / 1.467 GB, free: 444 kB / 1.154 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003267/0.3797, allocations: 2.361 MB / 1.47 GB, free: 14.01 MB / 1.17 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005876/0.3856, allocations: 3.832 MB / 1.473 GB, free: 10.17 MB / 1.17 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00475/0.3904, allocations: 2.9 MB / 1.476 GB, free: 7.27 MB / 1.17 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005118/0.3909, allocations: 311.9 kB / 1.477 GB, free: 6.965 MB / 1.17 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01042/0.4013, allocations: 5.344 MB / 1.482 GB, free: 1.605 MB / 1.17 GB
Notification: Performance of combineBinaries: time 0.007266/0.4086, allocations: 7.637 MB / 1.489 GB, free: 9.906 MB / 1.186 GB
Notification: Performance of replaceArrayConstructors: time 0.003949/0.4125, allocations: 4.595 MB / 1.494 GB, free: 5.262 MB / 1.186 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007477/0.4132, allocations: 336 kB / 1.494 GB, free: 4.934 MB / 1.186 GB
Notification: Performance of FrontEnd: time 0.000453/0.4137, allocations: 87.66 kB / 1.494 GB, free: 4.848 MB / 1.186 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 885 (587)
 * Number of variables: 885 (572)
Notification: Performance of [SIM] Bindings: time 0.01872/0.4324, allocations: 18.81 MB / 1.513 GB, free: 1.566 MB / 1.201 GB
Notification: Performance of [SIM] FunctionAlias: time 0.002704/0.4351, allocations: 2.453 MB / 1.515 GB, free: 15.16 MB / 1.217 GB
Notification: Performance of [SIM] Early Inline: time 0.01387/0.449, allocations: 13.17 MB / 1.528 GB, free: 1.93 MB / 1.217 GB
Notification: Performance of [SIM] Simplify 1: time 0.003016/0.452, allocations: 2.006 MB / 1.53 GB, free: 15.81 MB / 1.232 GB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.01654/0.4685, allocations: 11.97 MB / 1.541 GB, free: 2.797 MB / 1.232 GB
Notification: Performance of [SIM] Simplify 2: time 0.002457/0.471, allocations: 1.676 MB / 1.543 GB, free: 1.062 MB / 1.232 GB
Notification: Performance of [SIM] Remove Stream: time 0.001044/0.472, allocations: 0.7902 MB / 1.544 GB, free: 220 kB / 1.232 GB
Notification: Performance of [SIM] Detect States: time 0.002199/0.4742, allocations: 1.976 MB / 1.546 GB, free: 14.15 MB / 1.248 GB
Notification: Performance of [SIM] Events: time 0.0007727/0.475, allocations: 0.5461 MB / 1.546 GB, free: 13.6 MB / 1.248 GB
Notification: Performance of [SIM] Partitioning: time 0.003824/0.4788, allocations: 3.246 MB / 1.55 GB, free: 10.28 MB / 1.248 GB
Notification: Performance of [SIM] Causalize: time 0.01383/0.4927, allocations: 10.28 MB / 1.56 GB, free: 15.88 MB / 1.264 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.006482/0.4992, allocations: 6.678 MB / 1.566 GB, free: 9.191 MB / 1.264 GB
Notification: Performance of [INI] Simplify: time 0.007414/0.5066, allocations: 4.546 MB / 1.571 GB, free: 4.371 MB / 1.264 GB
Notification: Performance of [INI] Inline: time 0.01338/0.52, allocations: 13.08 MB / 1.583 GB, free: 7.246 MB / 1.279 GB
Notification: Performance of [INI] Partitioning: time 0.0005191/0.5205, allocations: 193.1 kB / 1.583 GB, free: 7.004 MB / 1.279 GB
Notification: Performance of [INI] Cleanup: time 0.001954/0.5224, allocations: 1.639 MB / 1.585 GB, free: 5.363 MB / 1.279 GB
Notification: Performance of [INI] Causalize: time 0.01903/0.5415, allocations: 13.29 MB / 1.598 GB, free: 7.832 MB / 1.295 GB
Notification: Performance of [INI] Tearing: time 0.0001727/0.5416, allocations: 276.1 kB / 1.598 GB, free: 7.562 MB / 1.295 GB
Notification: Performance of [SIM] Initialization: time 1.142e-06/0.5416, allocations: 0 / 1.598 GB, free: 7.562 MB / 1.295 GB
Notification: Performance of [SIM] Remove Dummies: time 4.413e-05/0.5417, allocations: 7.25 kB / 1.598 GB, free: 7.555 MB / 1.295 GB
Notification: Performance of [SIM] Tearing: time 0.000131/0.5418, allocations: 216 kB / 1.599 GB, free: 7.344 MB / 1.295 GB
Notification: Performance of [SIM] Categorize: time 3.468e-05/0.5418, allocations: 12 kB / 1.599 GB, free: 7.332 MB / 1.295 GB
Notification: Performance of [SIM] Solve: time 0.5466/1.088, allocations: 10.33 MB / 1.609 GB, free: 113.4 MB / 1.295 GB
Notification: Performance of [SIM] Jacobian: time 0.00662/1.095, allocations: 5.002 MB / 1.614 GB, free: 113.1 MB / 1.295 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 4
 * Number of ODE event partitions: ............... 1
 * 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: ............................. 20 (20) {volBotEas.U, volBotEas.m, volBotEas.Xi, oriOutBot.mExc, oriOutTop.mExc, oriWesTop.mExc, dooOpeClo.mExcAB, dooOpeClo.mExcBA, volBotWes.U, volBotWes.m, volBotWes.Xi, oriEasTop.mExc, volTopEas.U, volTopEas.m, volTopEas.Xi, volTopWes.U, volTopWes.m, volTopWes.Xi, dooOpeCloTop.mExcAB, dooOpeCloTop.mExcBA}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 10 (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: ........... 365 (scalar:353, array:12, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:20)
 * Number of for-loop strong components: ......... 41 (resizable: 41, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 472 (scalar:450, array:22, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:24)
 * Number of for-loop strong components: ......... 41 (resizable: 41, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 0.0001605/1.095, allocations: 147.5 kB / 1.614 GB, free: 113.1 MB / 1.295 GB
Notification: Performance of SimCode: time 0.03663/1.132, allocations: 25.04 MB / 1.638 GB, free: 108.5 MB / 1.295 GB
Notification: Performance of Templates: time 0.07173/1.204, allocations: 53.02 MB / 1.69 GB, free: 98.08 MB / 1.295 GB
"
[Timeout remaining time 299]
make -j1 -f Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy.pipe ; mkfifo Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy.pipe ; head -c 1048576 < Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy.pipe >> ../files/Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy.sim & ./Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 10.38326937891543]
Failed to read output from testmodel.py, exit status != 0:
1.209926683921367 1.218695377 0.802810866
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f94b933af20>