Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Controls.SetPoints.Validation.OccupancySchedulePositiveStartTime.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.001672/0.001672, 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.001783/0.001783, allocations: 212.8 kB / 23.01 MB, free: 4.965 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.232/1.232, allocations: 230.6 MB / 256.8 MB, free: 7.809 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.712/2.712, allocations: 0.49 GB / 0.7958 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.SetPoints.Validation.OccupancySchedulePositiveStartTime,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|occSchDay.occupied|occSchDay1.occupied|occSchDay2.occupied|occSchDay.tNexNonOcc|occSchDay.tNexOcc|occSchDay1.tNexNonOcc|occSchDay1.tNexOcc|occSchDay2.tNexNonOcc|occSchDay2.tNexOcc",fileNamePrefix="Buildings_12_Buildings.Controls.SetPoints.Validation.OccupancySchedulePositiveStartTime")
translateModel(Buildings.Controls.SetPoints.Validation.OccupancySchedulePositiveStartTime,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|occSchDay.occupied|occSchDay1.occupied|occSchDay2.occupied|occSchDay.tNexNonOcc|occSchDay.tNexOcc|occSchDay1.tNexNonOcc|occSchDay1.tNexOcc|occSchDay2.tNexNonOcc|occSchDay2.tNexOcc",fileNamePrefix="Buildings_12_Buildings.Controls.SetPoints.Validation.OccupancySchedulePositiveStartTime") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 9.538e-06/9.538e-06, allocations: 2.281 kB / 1.155 GB, free: 25.23 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Controls.SetPoints.Validation.OccupancySchedulePositiveStartTime): time 0.001656/0.001666, allocations: 1.482 MB / 1.156 GB, free: 24.22 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.0007717/0.002438, allocations: 0.6244 MB / 1.157 GB, free: 23.82 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 2.885e-05/0.002467, allocations: 0.7188 kB / 1.157 GB, free: 23.82 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 5.977e-05/0.002526, allocations: 12.75 kB / 1.157 GB, free: 23.81 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 5.118e-05/0.002578, allocations: 16.28 kB / 1.157 GB, free: 23.8 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0007/0.003277, allocations: 357.1 kB / 1.157 GB, free: 23.65 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.0001528/0.00343, allocations: 164.4 kB / 1.158 GB, free: 23.58 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 3.461e-05/0.003465, allocations: 5.484 kB / 1.158 GB, free: 23.58 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 9e-05/0.003555, allocations: 80.64 kB / 1.158 GB, free: 23.55 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 7.588e-05/0.003631, allocations: 53.42 kB / 1.158 GB, free: 23.53 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 2.967e-05/0.00366, allocations: 12.25 kB / 1.158 GB, free: 23.53 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002893/0.00395, allocations: 300.4 kB / 1.158 GB, free: 23.43 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0001122/0.004062, allocations: 200.2 kB / 1.158 GB, free: 23.3 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 3.297e-05/0.004095, allocations: 107.6 kB / 1.158 GB, free: 23.23 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 2.96e-05/0.004125, allocations: 6.969 kB / 1.158 GB, free: 23.23 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 1.15e-05/0.004136, allocations: 7.344 kB / 1.158 GB, free: 23.23 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: 18 (9)
 * Number of variables: 18 (18)
Notification: Performance of [SIM] Bindings: time 0.0006977/0.004834, allocations: 0.6961 MB / 1.159 GB, free: 22.69 MB / 0.9044 GB
Notification: Performance of [SIM] FunctionAlias: time 9.377e-05/0.004928, allocations: 93 kB / 1.159 GB, free: 22.62 MB / 0.9044 GB
Notification: Performance of [SIM] Early Inline: time 0.0003038/0.005231, allocations: 427.5 kB / 1.159 GB, free: 22.39 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 1: time 0.0001154/0.005347, allocations: 137.3 kB / 1.16 GB, free: 22.27 MB / 0.9044 GB
Notification: Performance of [SIM] Alias: time 0.0006299/0.005977, allocations: 0.5683 MB / 1.16 GB, free: 21.89 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 2: time 8.716e-05/0.006064, allocations: 114.1 kB / 1.16 GB, free: 21.79 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Stream: time 5.664e-05/0.00612, allocations: 90.72 kB / 1.16 GB, free: 21.7 MB / 0.9044 GB
Notification: Performance of [SIM] Detect States: time 0.0001707/0.006291, allocations: 217.2 kB / 1.161 GB, free: 21.51 MB / 0.9044 GB
Notification: Performance of [SIM] Events: time 3.796e-05/0.006329, allocations: 14.16 kB / 1.161 GB, free: 21.51 MB / 0.9044 GB
Notification: Performance of [SIM] Partitioning: time 0.0001021/0.006431, allocations: 111.9 kB / 1.161 GB, free: 21.43 MB / 0.9044 GB
Notification: Performance of [SIM] Causalize: time 0.0001982/0.006629, allocations: 171 kB / 1.161 GB, free: 21.35 MB / 0.9044 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.00023/0.006859, allocations: 357.4 kB / 1.161 GB, free: 21.19 MB / 0.9044 GB
Notification: Performance of [INI] Simplify: time 0.0001707/0.00703, allocations: 201.7 kB / 1.161 GB, free: 21 MB / 0.9044 GB
Notification: Performance of [INI] Inline: time 0.0001576/0.007188, allocations: 251.7 kB / 1.162 GB, free: 20.88 MB / 0.9044 GB
Notification: Performance of [INI] Partitioning: time 1.53e-05/0.007203, allocations: 13.84 kB / 1.162 GB, free: 20.87 MB / 0.9044 GB
Notification: Performance of [INI] Cleanup: time 2.817e-05/0.007231, allocations: 43.67 kB / 1.162 GB, free: 20.84 MB / 0.9044 GB
Notification: Performance of [INI] Causalize: time 0.0006105/0.007842, allocations: 0.5275 MB / 1.162 GB, free: 20.69 MB / 0.9044 GB
Notification: Performance of [INI] Tearing: time 1.026e-05/0.007852, allocations: 14.91 kB / 1.162 GB, free: 20.68 MB / 0.9044 GB
Notification: Performance of [SIM] Initialization: time 3.6e-07/0.007852, allocations: 0 / 1.162 GB, free: 20.68 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Dummies: time 4.028e-06/0.007856, allocations: 9.25 kB / 1.162 GB, free: 20.67 MB / 0.9044 GB
Notification: Performance of [SIM] Tearing: time 1.623e-06/0.007858, allocations: 1 kB / 1.162 GB, free: 20.67 MB / 0.9044 GB
Notification: Performance of [SIM] Categorize: time 6.311e-06/0.007864, allocations: 2.219 kB / 1.162 GB, free: 20.67 MB / 0.9044 GB
Notification: Performance of [SIM] Solve: time 0.0001456/0.00801, allocations: 113.4 kB / 1.162 GB, free: 20.63 MB / 0.9044 GB
Notification: Performance of [SIM] Jacobian: time 5.38e-06/0.008015, allocations: 0.8125 kB / 1.162 GB, free: 20.63 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: ......... 3
 * 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: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 9 (9) {occSchDay.occupied, occSchDay.tNext, occSchDay1.occupied, occSchDay1.tNext, occSchDay2.occupied, occSchDay2.tNext, $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: ........... 3 (scalar:3, array:0, record:0)
 * Number of multi strong components: ............ 3 (algorithm:0, when:3, 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: ........... 18 (scalar:18, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:3)
 * 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 3.887e-05/0.008054, allocations: 33.33 kB / 1.162 GB, free: 20.61 MB / 0.9044 GB
Notification: Performance of SimCode: time 0.0008595/0.008914, allocations: 0.7273 MB / 1.163 GB, free: 20.27 MB / 0.9044 GB
[CodegenCFunctions.tpl:3323:11-3323:11:writable] Error: Template error: tupleReturnVariableUpdates: Unhandled expression. -((-occSchDay2.tNexNonOcc) - time).
[CodegenCFunctions.tpl:3130:11-3130:11:writable] Error: Template error: algStmtAssignWithRhsExpStr: Unhandled lhs expression. -((-occSchDay2.tNexOcc) - time).
[CodegenCFunctions.tpl:3323:11-3323:11:writable] Error: Template error: tupleReturnVariableUpdates: Unhandled expression. -((-occSchDay1.tNexNonOcc) - time).
[CodegenCFunctions.tpl:3130:11-3130:11:writable] Error: Template error: algStmtAssignWithRhsExpStr: Unhandled lhs expression. -((-occSchDay1.tNexOcc) - time).
[CodegenCFunctions.tpl:3323:11-3323:11:writable] Error: Template error: tupleReturnVariableUpdates: Unhandled expression. -((-occSchDay.tNexNonOcc) - time).
[CodegenCFunctions.tpl:3130:11-3130:11:writable] Error: Template error: algStmtAssignWithRhsExpStr: Unhandled lhs expression. -((-occSchDay.tNexOcc) - time).
"
[Timeout remaining time 300]
[Calling sys.exit(0), Time elapsed: 5.367922640871257]
Failed to read output from testmodel.py, exit status != 0:
0.019037782680243254 0.028946703 0.022697262000000003
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f2b1eaaaf20>