Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_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.001566/0.001566, allocations: 100.4 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.001682/0.001682, allocations: 208.8 kB / 23.06 MB, free: 4.988 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.392/1.392, allocations: 230.7 MB / 256.9 MB, free: 7.742 MB / 206.1 MB
"
[Timeout remaining time 178]
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 3.063/3.063, allocations: 0.4893 GB / 0.7952 GB, free: 35.45 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.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_12_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_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.3e-05/2.3e-05, allocations: 4.797 kB / 1.155 GB, free: 25.61 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta): time 0.002458/0.002481, allocations: 1.417 MB / 1.156 GB, free: 24.71 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.00219/0.00467, allocations: 1.75 MB / 1.158 GB, free: 23.46 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001543/0.004825, allocations: 6.906 kB / 1.158 GB, free: 23.46 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 0.0001614/0.004986, allocations: 43.41 kB / 1.158 GB, free: 23.42 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 0.0001385/0.005125, allocations: 62.89 kB / 1.158 GB, free: 23.38 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0006646/0.005789, allocations: 281.5 kB / 1.158 GB, free: 23.22 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.0005354/0.006325, allocations: 0.5773 MB / 1.159 GB, free: 22.93 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002733/0.006598, allocations: 152.9 kB / 1.159 GB, free: 22.84 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0002211/0.006819, allocations: 217.8 kB / 1.159 GB, free: 22.73 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0002289/0.007048, allocations: 192.6 kB / 1.159 GB, free: 22.64 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 8.95e-05/0.007137, allocations: 57.19 kB / 1.159 GB, free: 22.64 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001428/0.00728, allocations: 70.25 kB / 1.159 GB, free: 22.63 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0005002/0.00778, allocations: 0.8156 MB / 1.16 GB, free: 22.06 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 0.0002152/0.007995, allocations: 480.5 kB / 1.16 GB, free: 21.73 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 8.361e-05/0.008079, allocations: 32.75 kB / 1.16 GB, free: 21.73 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 6.475e-05/0.008144, allocations: 24.22 kB / 1.161 GB, free: 21.71 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.001766/0.009909, allocations: 1.978 MB / 1.162 GB, free: 20.33 MB / 0.9044 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0001974/0.01011, allocations: 239 kB / 1.163 GB, free: 20.18 MB / 0.9044 GB
Notification: Performance of [SIM] Early Inline: time 0.001449/0.01156, allocations: 1.613 MB / 1.164 GB, free: 19.22 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 1: time 0.00046/0.01202, allocations: 310.4 kB / 1.165 GB, free: 19.05 MB / 0.9044 GB
Notification: Performance of [SIM] Alias: time 0.003127/0.01514, allocations: 2.292 MB / 1.167 GB, free: 17.7 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 2: time 0.0003057/0.01545, allocations: 235.2 kB / 1.167 GB, free: 17.56 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Stream: time 0.0002159/0.01566, allocations: 203.8 kB / 1.167 GB, free: 17.43 MB / 0.9044 GB
Notification: Performance of [SIM] Detect States: time 0.0005908/0.01625, allocations: 469.7 kB / 1.168 GB, free: 17.1 MB / 0.9044 GB
Notification: Performance of [SIM] Events: time 0.0003271/0.01658, allocations: 205.2 kB / 1.168 GB, free: 16.99 MB / 0.9044 GB
Notification: Performance of [SIM] Partitioning: time 0.0004707/0.01705, allocations: 405.8 kB / 1.168 GB, free: 16.81 MB / 0.9044 GB
Notification: Performance of [SIM] Causalize: time 0.0009635/0.01802, allocations: 0.6481 MB / 1.169 GB, free: 16.66 MB / 0.9044 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0009677/0.01898, allocations: 1.034 MB / 1.17 GB, free: 16.11 MB / 0.9044 GB
Notification: Performance of [INI] Simplify: time 0.00124/0.02022, allocations: 0.8634 MB / 1.171 GB, free: 15.62 MB / 0.9044 GB
Notification: Performance of [INI] Inline: time 0.001513/0.02174, allocations: 1.617 MB / 1.172 GB, free: 14.7 MB / 0.9044 GB
Notification: Performance of [INI] Partitioning: time 7.776e-05/0.02182, allocations: 38.59 kB / 1.172 GB, free: 14.68 MB / 0.9044 GB
Notification: Performance of [INI] Cleanup: time 0.0002626/0.02208, allocations: 266.6 kB / 1.173 GB, free: 14.53 MB / 0.9044 GB
Notification: Performance of [INI] Causalize: time 0.003311/0.02539, allocations: 2.297 MB / 1.175 GB, free: 13.97 MB / 0.9044 GB
Notification: Performance of [INI] Tearing: time 3.953e-05/0.02543, allocations: 58.16 kB / 1.175 GB, free: 13.93 MB / 0.9044 GB
Notification: Performance of [SIM] Initialization: time 1.372e-06/0.02543, allocations: 3.984 kB / 1.175 GB, free: 13.92 MB / 0.9044 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0001176/0.02555, allocations: 60.58 kB / 1.175 GB, free: 13.89 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Dummies: time 7.333e-06/0.02555, allocations: 4.438 kB / 1.175 GB, free: 13.88 MB / 0.9044 GB
Notification: Performance of [SIM] Tearing: time 1.314e-05/0.02557, allocations: 27.88 kB / 1.175 GB, free: 13.86 MB / 0.9044 GB
Notification: Performance of [SIM] Categorize: time 1.57e-05/0.02558, allocations: 7.094 kB / 1.175 GB, free: 13.86 MB / 0.9044 GB
Notification: Performance of [SIM] Solve: time 0.000682/0.02626, allocations: 471.7 kB / 1.175 GB, free: 13.67 MB / 0.9044 GB
Notification: Performance of [SIM] Jacobian: time 0.0001401/0.02641, allocations: 194.1 kB / 1.176 GB, free: 13.47 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.0001468/0.02655, allocations: 54.34 kB / 1.176 GB, free: 13.43 MB / 0.9044 GB
Notification: Performance of SimCode: time 0.002373/0.02893, allocations: 1.416 MB / 1.177 GB, free: 12.82 MB / 0.9044 GB
Notification: Performance of Templates: time 0.009591/0.03852, allocations: 3.792 MB / 1.181 GB, free: 12.4 MB / 0.9044 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe ; mkfifo Buildings_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe ; head -c 1048576 < Buildings_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe >> ../files/Buildings_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.sim & ./Buildings_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Validation.Enable_FreProSta.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 8.232967867981642]
Failed to read output from testmodel.py, exit status != 0:
0.04544981295475736 0.058194659 0.047412801000000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f61654f6fb0>