Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed.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.001451/0.001451, allocations: 108.3 kB / 19.72 MB, free: 2.137 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.001536/0.001536, allocations: 210.1 kB / 23 MB, free: 4.957 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.297/1.297, allocations: 230.6 MB / 256.8 MB, free: 7.812 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.817/2.817, allocations: 0.49 GB / 0.7958 GB, free: 35.38 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.Validation.DoorOpenClosed,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|doo.port_a1.m_flow|doo.port_b2.m_flow|dooOpeClo.port_a1.m_flow|dooOpeClo.port_b2.m_flow|lea.port_a.m_flow|mNetClo_flow.y",fileNamePrefix="Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed")
translateModel(Buildings.Airflow.Multizone.Validation.DoorOpenClosed,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|doo.port_a1.m_flow|doo.port_b2.m_flow|dooOpeClo.port_a1.m_flow|dooOpeClo.port_b2.m_flow|lea.port_a.m_flow|mNetClo_flow.y",fileNamePrefix="Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.616e-05/1.616e-05, allocations: 2.281 kB / 1.155 GB, free: 25.25 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Validation.DoorOpenClosed): time 0.1974/0.1974, allocations: 175.9 MB / 1.327 GB, free: 0.9102 MB / 1.014 GB
Notification: Performance of NFInst.instExpressions: time 0.01162/0.209, allocations: 8.689 MB / 1.335 GB, free: 8.195 MB / 1.029 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008249/0.2099, allocations: 23.81 kB / 1.335 GB, free: 8.172 MB / 1.029 GB
Notification: Performance of NFTyping.typeComponents: time 0.001493/0.2113, allocations: 0.5748 MB / 1.336 GB, free: 7.594 MB / 1.029 GB
Notification: Performance of NFTyping.typeBindings: time 0.004764/0.2161, allocations: 2.087 MB / 1.338 GB, free: 5.5 MB / 1.029 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001882/0.218, allocations: 0.7602 MB / 1.339 GB, free: 4.742 MB / 1.029 GB
Notification: Performance of NFFlatten.flatten: time 0.002496/0.2205, allocations: 2.132 MB / 1.341 GB, free: 2.605 MB / 1.029 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009814/0.2215, allocations: 0.6524 MB / 1.341 GB, free: 1.938 MB / 1.029 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002804/0.2243, allocations: 1.545 MB / 1.343 GB, free: 396 kB / 1.029 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.000952/0.2252, allocations: 0.6694 MB / 1.344 GB, free: 15.71 MB / 1.045 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001218/0.2253, allocations: 64 kB / 1.344 GB, free: 15.65 MB / 1.045 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002713/0.2281, allocations: 1.508 MB / 1.345 GB, free: 14.14 MB / 1.045 GB
Notification: Performance of combineBinaries: time 0.0018/0.2299, allocations: 1.964 MB / 1.347 GB, free: 12.16 MB / 1.045 GB
Notification: Performance of replaceArrayConstructors: time 0.0008679/0.2307, allocations: 1.194 MB / 1.348 GB, free: 10.95 MB / 1.045 GB
Notification: Performance of NFVerifyModel.verify: time 0.0001523/0.2309, allocations: 84 kB / 1.348 GB, free: 10.87 MB / 1.045 GB
Notification: Performance of FrontEnd: time 0.0001102/0.231, allocations: 19.94 kB / 1.348 GB, free: 10.85 MB / 1.045 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 185 (131)
 * Number of variables: 185 (141)
Notification: Performance of [SIM] Bindings: time 0.004793/0.2358, allocations: 4.838 MB / 1.353 GB, free: 5.816 MB / 1.045 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0005316/0.2363, allocations: 459.4 kB / 1.353 GB, free: 5.352 MB / 1.045 GB
Notification: Performance of [SIM] Early Inline: time 0.003198/0.2395, allocations: 3.129 MB / 1.356 GB, free: 2.18 MB / 1.045 GB
Notification: Performance of [SIM] Simplify 1: time 0.0006903/0.2402, allocations: 0.4931 MB / 1.357 GB, free: 1.613 MB / 1.045 GB
Notification: Performance of [SIM] Alias: time 0.003106/0.2433, allocations: 2.298 MB / 1.359 GB, free: 15.05 MB / 1.061 GB
Notification: Performance of [SIM] Simplify 2: time 0.0005979/0.2439, allocations: 465.4 kB / 1.36 GB, free: 14.54 MB / 1.061 GB
Notification: Performance of [SIM] Remove Stream: time 0.0002298/0.2441, allocations: 204 kB / 1.36 GB, free: 14.32 MB / 1.061 GB
Notification: Performance of [SIM] Detect States: time 0.0005114/0.2447, allocations: 460.4 kB / 1.36 GB, free: 13.8 MB / 1.061 GB
Notification: Performance of [SIM] Events: time 0.0001384/0.2448, allocations: 87.78 kB / 1.36 GB, free: 13.72 MB / 1.061 GB
Notification: Performance of [SIM] Partitioning: time 0.0008739/0.2457, allocations: 0.7804 MB / 1.361 GB, free: 12.92 MB / 1.061 GB
Notification: Performance of [SIM] Causalize: time 0.002735/0.2484, allocations: 2.077 MB / 1.363 GB, free: 10.82 MB / 1.061 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001406/0.2498, allocations: 1.506 MB / 1.365 GB, free: 9.309 MB / 1.061 GB
Notification: Performance of [INI] Simplify: time 0.002111/0.2519, allocations: 1.374 MB / 1.366 GB, free: 7.816 MB / 1.061 GB
Notification: Performance of [INI] Inline: time 0.002898/0.2548, allocations: 3.139 MB / 1.369 GB, free: 4.668 MB / 1.061 GB
Notification: Performance of [INI] Partitioning: time 0.0001047/0.2549, allocations: 56.05 kB / 1.369 GB, free: 4.594 MB / 1.061 GB
Notification: Performance of [INI] Cleanup: time 0.0003541/0.2553, allocations: 403.6 kB / 1.369 GB, free: 4.199 MB / 1.061 GB
Notification: Performance of [INI] Causalize: time 0.003858/0.2591, allocations: 2.955 MB / 1.372 GB, free: 1.195 MB / 1.061 GB
Notification: Performance of [INI] Tearing: time 5.285e-05/0.2592, allocations: 76 kB / 1.372 GB, free: 1.121 MB / 1.061 GB
Notification: Performance of [SIM] Initialization: time 5.41e-07/0.2592, allocations: 0 / 1.372 GB, free: 1.121 MB / 1.061 GB
Notification: Performance of [SIM] Remove Dummies: time 6.692e-06/0.2592, allocations: 1 kB / 1.372 GB, free: 1.121 MB / 1.061 GB
Notification: Performance of [SIM] Tearing: time 2.946e-05/0.2592, allocations: 48.66 kB / 1.372 GB, free: 1.074 MB / 1.061 GB
Notification: Performance of [SIM] Categorize: time 1.059e-05/0.2592, allocations: 4 kB / 1.372 GB, free: 1.07 MB / 1.061 GB
Notification: Performance of [SIM] Solve: time 0.003252/0.2625, allocations: 2.824 MB / 1.375 GB, free: 14.23 MB / 1.076 GB
Notification: Performance of [SIM] Jacobian: time 0.001159/0.2636, allocations: 0.9649 MB / 1.376 GB, free: 13.22 MB / 1.076 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 1
 * 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: ............................. 1 (1) {lea.mExc}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 2 (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: ........... 78 (scalar:78, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:9)
 * Number of for-loop strong components: ......... 8 (resizable: 8, 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: ........... 116 (scalar:112, array:4, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:9)
 * Number of for-loop strong components: ......... 11 (resizable: 11, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 6.021e-05/0.2637, allocations: 50.75 kB / 1.376 GB, free: 13.18 MB / 1.076 GB
Notification: Performance of SimCode: time 0.007507/0.2712, allocations: 5.656 MB / 1.382 GB, free: 7.441 MB / 1.076 GB
Notification: Performance of Templates: time 0.02118/0.2924, allocations: 13.93 MB / 1.395 GB, free: 9.562 MB / 1.092 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed.pipe ; mkfifo Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed.pipe ; head -c 1048576 < Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed.pipe >> ../files/Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed.sim & ./Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Airflow.Multizone.Validation.DoorOpenClosed.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 8.034343424718827]
Failed to read output from testmodel.py, exit status != 0:
0.29918475821614265 0.312578746 0.078770886
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff9a7336f20>