Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut.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.00188/0.00188, allocations: 80.88 kB / 21.56 MB, free: 2.934 MB / 18.57 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.002728/0.002728, allocations: 161.8 kB / 24.97 MB, free: 9.133 MB / 18.57 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.715/1.715, allocations: 177.1 MB / 205.4 MB, free: 5.504 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 3.295/3.295, allocations: 389.5 MB / 0.6361 GB, free: 12.32 MB / 0.6042 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.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.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut")
translateModel(Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.392e-05/2.392e-05, allocations: 6.453 kB / 0.776 GB, free: 5.262 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut): time 0.007524/0.007548, allocations: 2.313 MB / 0.7783 GB, free: 2.984 MB / 0.7292 GB
Notification: Performance of NFInst.instExpressions: time 0.00362/0.01117, allocations: 1.934 MB / 0.7802 GB, free: 1.035 MB / 0.7292 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0002704/0.01144, allocations: 15.94 kB / 0.7802 GB, free: 1.02 MB / 0.7292 GB
Notification: Performance of NFTyping.typeComponents: time 0.0002782/0.01172, allocations: 90.86 kB / 0.7803 GB, free: 0.9297 MB / 0.7292 GB
Notification: Performance of NFTyping.typeBindings: time 0.0003418/0.01206, allocations: 161.2 kB / 0.7804 GB, free: 0.7734 MB / 0.7292 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0007037/0.01276, allocations: 323.5 kB / 0.7807 GB, free: 468 kB / 0.7292 GB
Notification: Performance of NFFlatten.flatten: time 0.001215/0.01398, allocations: 1.069 MB / 0.7818 GB, free: 15.4 MB / 0.7448 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006371/0.01461, allocations: 354.8 kB / 0.7821 GB, free: 15.02 MB / 0.7448 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009685/0.01558, allocations: 0.5845 MB / 0.7827 GB, free: 14.43 MB / 0.7448 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0003491/0.01593, allocations: 284.6 kB / 0.783 GB, free: 14.16 MB / 0.7448 GB
Notification: Performance of NFPackage.collectConstants: time 0.00014/0.01607, allocations: 92 kB / 0.783 GB, free: 14.07 MB / 0.7448 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001843/0.01626, allocations: 92 kB / 0.7831 GB, free: 13.98 MB / 0.7448 GB
Notification: Performance of combineBinaries: time 0.001298/0.01755, allocations: 1.273 MB / 0.7844 GB, free: 12.7 MB / 0.7448 GB
Notification: Performance of replaceArrayConstructors: time 0.0007466/0.0183, allocations: 0.7358 MB / 0.7851 GB, free: 11.95 MB / 0.7448 GB
Notification: Performance of NFVerifyModel.verify: time 9.691e-05/0.0184, allocations: 57.25 kB / 0.7852 GB, free: 11.9 MB / 0.7448 GB
Notification: Performance of FrontEnd: time 0.0001223/0.01852, allocations: 59.38 kB / 0.7852 GB, free: 11.84 MB / 0.7448 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 193 (193)
 * Number of variables: 193 (193)
Notification: Performance of [SIM] Bindings: time 0.004542/0.02306, allocations: 3.636 MB / 0.7888 GB, free: 8.039 MB / 0.7448 GB
Notification: Performance of [SIM] FunctionAlias: time 0.000748/0.02381, allocations: 0.6059 MB / 0.7894 GB, free: 7.422 MB / 0.7448 GB
Notification: Performance of [SIM] Early Inline: time 0.004172/0.02798, allocations: 3.652 MB / 0.7929 GB, free: 3.73 MB / 0.7448 GB
Notification: Performance of [SIM] Simplify 1: time 0.0004759/0.02846, allocations: 279.8 kB / 0.7932 GB, free: 3.418 MB / 0.7448 GB
Warning: NBAlias.setStartFixed: Multiple variables are fixed and have identical start values. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.007057/0.03551, allocations: 4.692 MB / 0.7978 GB, free: 14.25 MB / 0.7605 GB
Notification: Performance of [SIM] Simplify 2: time 0.0002883/0.0358, allocations: 182 kB / 0.7979 GB, free: 14.05 MB / 0.7605 GB
Notification: Performance of [SIM] Remove Stream: time 0.0002435/0.03605, allocations: 194 kB / 0.7981 GB, free: 13.83 MB / 0.7605 GB
Notification: Performance of [SIM] Detect States: time 0.0009324/0.03698, allocations: 0.6848 MB / 0.7988 GB, free: 13.09 MB / 0.7605 GB
Notification: Performance of [SIM] Events: time 0.000578/0.03756, allocations: 395.1 kB / 0.7992 GB, free: 12.72 MB / 0.7605 GB
Notification: Performance of [SIM] Partitioning: time 0.001181/0.03874, allocations: 0.8063 MB / 0.8 GB, free: 11.88 MB / 0.7605 GB
Notification: Performance of [SIM] Causalize: time 0.001654/0.04039, allocations: 0.8658 MB / 0.8008 GB, free: 11.02 MB / 0.7605 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.002197/0.04259, allocations: 1.752 MB / 0.8025 GB, free: 9.262 MB / 0.7605 GB
Notification: Performance of [INI] Simplify: time 0.002204/0.04479, allocations: 1.248 MB / 0.8037 GB, free: 7.977 MB / 0.7605 GB
Notification: Performance of [INI] Inline: time 0.003249/0.04804, allocations: 2.761 MB / 0.8064 GB, free: 5.203 MB / 0.7605 GB
Notification: Performance of [INI] Partitioning: time 8.53e-05/0.04813, allocations: 43.92 kB / 0.8065 GB, free: 5.152 MB / 0.7605 GB
Notification: Performance of [INI] Cleanup: time 0.0004843/0.04861, allocations: 427.5 kB / 0.8069 GB, free: 4.734 MB / 0.7605 GB
Notification: Performance of [INI] Causalize: time 0.002074/0.05068, allocations: 1.357 MB / 0.8082 GB, free: 3.363 MB / 0.7605 GB
Notification: Performance of [INI] Tearing: time 4.48e-05/0.05073, allocations: 48 kB / 0.8083 GB, free: 3.316 MB / 0.7605 GB
Notification: Performance of [SIM] Initialization: time 6.61e-07/0.05073, allocations: 0 / 0.8083 GB, free: 3.316 MB / 0.7605 GB
Notification: Performance of [SIM] Remove Dummies: time 9.998e-06/0.05074, allocations: 4.656 kB / 0.8083 GB, free: 3.312 MB / 0.7605 GB
Notification: Performance of [SIM] Tearing: time 3.347e-05/0.05077, allocations: 40 kB / 0.8083 GB, free: 3.273 MB / 0.7605 GB
Notification: Performance of [SIM] Categorize: time 1.068e-05/0.05078, allocations: 4 kB / 0.8083 GB, free: 3.27 MB / 0.7605 GB
Notification: Performance of [SIM] Solve: time 0.0008044/0.05159, allocations: 0.5011 MB / 0.8088 GB, free: 2.77 MB / 0.7605 GB
Notification: Performance of [SIM] Jacobian: time 8.476e-06/0.0516, allocations: 0 / 0.8088 GB, free: 2.77 MB / 0.7605 GB
Notification: Performance of [SIM] Minimize Homotopy System: time 4.01e-07/0.0516, allocations: 7.938 kB / 0.8088 GB, free: 2.762 MB / 0.7605 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: .................... 9 (9) {enaDis2.truFalHol.entryTimeFalse, enaDis2.truFalHol.entryTimeTrue, enaDis2.truFalHol.y, enaDis1.truFalHol.entryTimeFalse, enaDis1.truFalHol.entryTimeTrue, enaDis1.truFalHol.y, enaDis.truFalHol.entryTimeFalse, enaDis.truFalHol.entryTimeTrue, enaDis.truFalHol.y}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 53 (53) {enaDis.andFrePro.u1, enaDis.andEnaDis.u2, enaDis.andEnaDis.y, enaDis.truFalHol.not_u, enaDis.truFalHol.not_y, enaDis.hysOutTem.y, enaDis.hysOutEnt.y, enaDis.and1.y, enaDis.intEqu1.y, enaDis1.andFrePro.u1, enaDis1.andEnaDis.u2, enaDis1.andEnaDis.y, enaDis1.truFalHol.not_u, enaDis1.truFalHol.not_y, enaDis1.hysOutTem.y, enaDis1.hysOutEnt.y, enaDis1.and1.y, enaDis1.intEqu1.y, enaDis2.andFrePro.u1, enaDis2.andEnaDis.u2, enaDis2.andEnaDis.y, enaDis2.truFalHol.not_u, enaDis2.truFalHol.not_y, enaDis2.hysOutTem.y, enaDis2.and1.y, enaDis2.intEqu1.y, $WC_17, $WC_16, $WC_15, $WC_14, $WC_13, $WC_12, $WC_11, $WC_10, $WC_9, $WC_8, $WC_7, $WC_6, $WC_5, $WC_4, $WC_3, $WC_2, $WC_1, $WC_0, $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: ........... 68 (scalar:68, array:0, record:0)
 * Number of multi strong components: ............ 9 (algorithm:0, when:9, 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: ........... 90 (scalar:90, array:0, record:0)
 * Number of multi strong components: ............ 9 (algorithm:9, 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 9.773e-05/0.0517, allocations: 68.61 kB / 0.8089 GB, free: 2.703 MB / 0.7605 GB
Notification: Performance of SimCode: time 0.002875/0.05457, allocations: 1.245 MB / 0.8101 GB, free: 1.375 MB / 0.7605 GB
Notification: Performance of Templates: time 0.01378/0.06835, allocations: 5.731 MB / 0.8157 GB, free: 11.76 MB / 0.7761 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut.pipe ; mkfifo Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut.pipe ; head -c 1048576 < Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut.pipe >> ../files/Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut.sim & ./Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Controls.OBC.ASHRAE.G36.AHUs.SingleZone.VAV.Economizers.Subsequences.Validation.Enable_TOut_hOut.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 8.432873675599694]