Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.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.001696/0.001696, allocations: 104.3 kB / 19.77 MB, free: 2.051 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.001712/0.001712, allocations: 213.1 kB / 23.07 MB, free: 4.844 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.434/1.434, 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/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 3.18/3.18, allocations: 0.5108 GB / 0.8167 GB, free: 17.7 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|enaDis.yOutDam_max|enaDis.yRetDam_min|enaDis.yRetDam_max|enaDis.andEnaDis.y|enaDis.truFalHol.y|freProSta1.y",fileNamePrefix="Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta")
translateModel(Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|enaDis.yOutDam_max|enaDis.yRetDam_min|enaDis.yRetDam_max|enaDis.andEnaDis.y|enaDis.truFalHol.y|freProSta1.y",fileNamePrefix="Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.647e-05/1.647e-05, allocations: 3.828 kB / 1.187 GB, free: 13.51 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta): time 0.002318/0.002334, allocations: 1.429 MB / 1.189 GB, free: 12.6 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.002083/0.004418, allocations: 1.74 MB / 1.19 GB, free: 11.34 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001529/0.004571, allocations: 4.875 kB / 1.19 GB, free: 11.34 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 0.0001436/0.004714, allocations: 46.16 kB / 1.19 GB, free: 11.31 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 0.0001299/0.004844, allocations: 66.91 kB / 1.19 GB, free: 11.26 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0005919/0.005436, allocations: 282.7 kB / 1.191 GB, free: 11.1 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.0004993/0.005935, allocations: 0.5762 MB / 1.191 GB, free: 10.81 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002458/0.006181, allocations: 159.9 kB / 1.191 GB, free: 10.72 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0001979/0.006379, allocations: 210.6 kB / 1.192 GB, free: 10.61 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001949/0.006574, allocations: 194.9 kB / 1.192 GB, free: 10.52 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 7.741e-05/0.006651, allocations: 60.91 kB / 1.192 GB, free: 10.52 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001184/0.00677, allocations: 66.47 kB / 1.192 GB, free: 10.52 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0004371/0.007207, allocations: 0.8191 MB / 1.193 GB, free: 9.938 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 0.0001825/0.007389, allocations: 470.2 kB / 1.193 GB, free: 9.613 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 7.949e-05/0.007469, allocations: 34.03 kB / 1.193 GB, free: 9.613 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 6.247e-05/0.007531, allocations: 24.28 kB / 1.193 GB, free: 9.598 MB / 0.9044 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 88 (88)
 * Number of variables: 88 (88)
Notification: Performance of [SIM] Bindings: time 0.0017/0.009231, allocations: 1.982 MB / 1.195 GB, free: 8.199 MB / 0.9044 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0001824/0.009414, allocations: 240 kB / 1.195 GB, free: 8.043 MB / 0.9044 GB
Notification: Performance of [SIM] Early Inline: time 0.001456/0.01087, allocations: 1.616 MB / 1.197 GB, free: 7.082 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 1: time 0.0004352/0.01131, allocations: 313.1 kB / 1.197 GB, free: 6.91 MB / 0.9044 GB
Notification: Performance of [SIM] Alias: time 0.002777/0.01408, allocations: 2.279 MB / 1.199 GB, free: 5.562 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 2: time 0.0003012/0.01438, allocations: 238.7 kB / 1.2 GB, free: 5.43 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Stream: time 0.0001973/0.01458, allocations: 205.9 kB / 1.2 GB, free: 5.297 MB / 0.9044 GB
Notification: Performance of [SIM] Detect States: time 0.0005217/0.0151, allocations: 466.2 kB / 1.2 GB, free: 4.98 MB / 0.9044 GB
Notification: Performance of [SIM] Events: time 0.0002964/0.0154, allocations: 207 kB / 1.201 GB, free: 4.863 MB / 0.9044 GB
Notification: Performance of [SIM] Partitioning: time 0.0004312/0.01583, allocations: 410.8 kB / 1.201 GB, free: 4.68 MB / 0.9044 GB
Notification: Performance of [SIM] Causalize: time 0.0009077/0.01674, allocations: 0.6518 MB / 1.202 GB, free: 4.527 MB / 0.9044 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0008961/0.01763, allocations: 1.037 MB / 1.203 GB, free: 3.973 MB / 0.9044 GB
Notification: Performance of [INI] Simplify: time 0.001183/0.01882, allocations: 0.8686 MB / 1.203 GB, free: 3.48 MB / 0.9044 GB
Notification: Performance of [INI] Inline: time 0.001401/0.02022, allocations: 1.609 MB / 1.205 GB, free: 2.559 MB / 0.9044 GB
Notification: Performance of [INI] Partitioning: time 7.321e-05/0.02029, allocations: 38.48 kB / 1.205 GB, free: 2.535 MB / 0.9044 GB
Notification: Performance of [INI] Cleanup: time 0.0002291/0.02052, allocations: 274.6 kB / 1.205 GB, free: 2.383 MB / 0.9044 GB
Notification: Performance of [INI] Causalize: time 0.003011/0.02353, allocations: 2.292 MB / 1.208 GB, free: 1.832 MB / 0.9044 GB
Notification: Performance of [INI] Tearing: time 3.425e-05/0.02357, allocations: 55.41 kB / 1.208 GB, free: 1.793 MB / 0.9044 GB
Notification: Performance of [SIM] Initialization: time 8.11e-07/0.02357, allocations: 0 / 1.208 GB, free: 1.793 MB / 0.9044 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0001002/0.02367, allocations: 71.06 kB / 1.208 GB, free: 1.746 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Dummies: time 5.4e-06/0.02367, allocations: 1.781 kB / 1.208 GB, free: 1.746 MB / 0.9044 GB
Notification: Performance of [SIM] Tearing: time 1.108e-05/0.02368, allocations: 24.31 kB / 1.208 GB, free: 1.73 MB / 0.9044 GB
Notification: Performance of [SIM] Categorize: time 9.237e-06/0.02369, allocations: 5.438 kB / 1.208 GB, free: 1.727 MB / 0.9044 GB
Notification: Performance of [SIM] Solve: time 0.0006094/0.0243, allocations: 471.9 kB / 1.208 GB, free: 1.539 MB / 0.9044 GB
Notification: Performance of [SIM] Jacobian: time 0.0001146/0.02442, allocations: 190.6 kB / 1.208 GB, free: 1.344 MB / 0.9044 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 0
 * Number of algebraic event partitions: ......... 1
 * 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: ............................. 0 (0) {}
 * Number of discrete states: .................... 8 (8) {booPul1.y, enaDis.delRetDam.y, enaDis.delRetDam.t_next, enaDis.and3.u2, enaDis.delOutDamOsc.t_next, enaDis.truFalHol.entryTimeFalse, enaDis.truFalHol.entryTimeTrue, enaDis.truFalHol.y}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 33 (33) {enaDis.truFalHol.u, enaDis.truFalHol.not_u, enaDis.truFalHol.not_y, enaDis.andEnaDis.y, enaDis.hysOutTem.y, enaDis.hysOutEnt.y, enaDis.outDamSwitch.u2, enaDis.maxRetDamSwitch.u2, enaDis.and1.y, enaDis.intEqu.y, enaDis.delOutDamOsc.u, enaDis.delRetDam.u, freProSta1.y, $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, $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: ........... 39 (scalar:39, array:0, record:0)
 * Number of multi strong components: ............ 8 (algorithm:0, when:8, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, 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: ........... 102 (scalar:102, array:0, record:0)
 * Number of multi strong components: ............ 8 (algorithm:8, 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: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 0.0001042/0.02452, allocations: 54.64 kB / 1.208 GB, free: 1.305 MB / 0.9044 GB
Notification: Performance of SimCode: time 0.001535/0.02605, allocations: 1.406 MB / 1.21 GB, free: 0.5273 MB / 0.9044 GB
Notification: Performance of Templates: time 0.00773/0.03379, allocations: 3.787 MB / 1.213 GB, free: 14.62 MB / 0.92 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe ; mkfifo Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe ; head -c 1048576 < Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe >> ../files/Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.sim & ./Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe 2>&1) [Timeout 800]
[Calling os._exit(0), Time elapsed: 8.528973205015063]
Failed to read output from testmodel.py, exit status != 0:
0.04105337901273742 0.059367478 0.049008362
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f760996efb0>