Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.x/package.mo", uses=false)
Using package Buildings with version 9.1.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.x/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|disMinCon.VOutMinSet_flow_normalized|disMinCon.VOut_flow_normalized|disMinCon.u1SupFan|disMinCon.uOpeMod|disMinCon.yMinOutDam|disMinCon.yOutDam_min|disMinCon.yOutDam_max|disMinCon.yRetDam_min|disMinCon.yRetDam_max|disMinCon1.VOutMinSet_flow_normalized|disMinCon1.VOut_flow_normalized|disMinCon1.u1SupFan|disMinCon1.uOpeMod|disMinCon1.yMinOutDam|disMinCon1.yOutDam_min|disMinCon1.yOutDam_max|disMinCon1.yRetDam_min|disMinCon1.yRetDam_max|minCon.VOutMinSet_flow_normalized|minCon.VOut_flow_normalized|minCon.u1SupFan|minCon.uOpeMod|minCon.yMinOutDam|minCon.yOutDam_min|minCon.yOutDam_max|minCon.yRetDam_min|minCon.yRetDam_max",fileNamePrefix="Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS")
translateModel(Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|disMinCon.VOutMinSet_flow_normalized|disMinCon.VOut_flow_normalized|disMinCon.u1SupFan|disMinCon.uOpeMod|disMinCon.yMinOutDam|disMinCon.yOutDam_min|disMinCon.yOutDam_max|disMinCon.yRetDam_min|disMinCon.yRetDam_max|disMinCon1.VOutMinSet_flow_normalized|disMinCon1.VOut_flow_normalized|disMinCon1.u1SupFan|disMinCon1.uOpeMod|disMinCon1.yMinOutDam|disMinCon1.yOutDam_min|disMinCon1.yOutDam_max|disMinCon1.yRetDam_min|disMinCon1.yRetDam_max|minCon.VOutMinSet_flow_normalized|minCon.VOut_flow_normalized|minCon.u1SupFan|minCon.uOpeMod|minCon.yMinOutDam|minCon.yOutDam_min|minCon.yOutDam_max|minCon.yRetDam_min|minCon.yRetDam_max",fileNamePrefix="Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001176/0.001176, allocations: 106.1 kB / 16.42 MB, free: 6.48 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001079/0.001079, allocations: 190 kB / 17.36 MB, free: 5.703 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.169/1.169, allocations: 222.9 MB / 241 MB, free: 15.13 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.x/package.mo): time 1.936/1.936, allocations: 390.2 MB / 0.6655 GB, free: 0.6602 MB / 0.5294 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.632e-05/1.635e-05, allocations: 2.281 kB / 0.9516 GB, free: 7.57 MB / 0.7325 GB
Notification: Performance of NFInst.instantiate(Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS): time 0.006183/0.00621, allocations: 5.818 MB / 0.9573 GB, free: 1.723 MB / 0.7325 GB
Notification: Performance of NFInst.instExpressions: time 0.003772/0.009996, allocations: 2.858 MB / 0.9601 GB, free: 14.84 MB / 0.7481 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0005239/0.01053, allocations: 47.81 kB / 0.9601 GB, free: 14.8 MB / 0.7481 GB
Notification: Performance of NFTyping.typeComponents: time 0.0005281/0.01107, allocations: 280.7 kB / 0.9604 GB, free: 14.52 MB / 0.7481 GB
Notification: Performance of NFTyping.typeBindings: time 0.0006022/0.01168, allocations: 317.1 kB / 0.9607 GB, free: 14.21 MB / 0.7481 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001344/0.01303, allocations: 0.8817 MB / 0.9615 GB, free: 13.32 MB / 0.7481 GB
Notification: Performance of NFFlatten.flatten: time 0.002218/0.01529, allocations: 2.728 MB / 0.9642 GB, free: 10.59 MB / 0.7481 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001382/0.01668, allocations: 1.296 MB / 0.9655 GB, free: 9.25 MB / 0.7481 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007068/0.0174, allocations: 0.856 MB / 0.9663 GB, free: 8.391 MB / 0.7481 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006523/0.01805, allocations: 0.9445 MB / 0.9672 GB, free: 7.441 MB / 0.7481 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002547/0.01832, allocations: 276 kB / 0.9675 GB, free: 7.172 MB / 0.7481 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002299/0.01855, allocations: 276 kB / 0.9677 GB, free: 6.902 MB / 0.7481 GB
Notification: Performance of combineBinaries: time 0.001685/0.02024, allocations: 3.239 MB / 0.9709 GB, free: 3.625 MB / 0.7481 GB
Notification: Performance of replaceArrayConstructors: time 0.0009311/0.02118, allocations: 2.05 MB / 0.9729 GB, free: 1.543 MB / 0.7481 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002862/0.02147, allocations: 346.8 kB / 0.9732 GB, free: 1.203 MB / 0.7481 GB
Notification: Performance of FrontEnd: time 0.0002867/0.02177, allocations: 142.6 kB / 0.9734 GB, free: 1.062 MB / 0.7481 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 450 (450)
 * Number of variables: 450 (450)
Notification: Performance of Bindings: time 0.007158/0.02893, allocations: 9.818 MB / 0.983 GB, free: 6.984 MB / 0.7637 GB
Notification: Performance of FunctionAlias: time 0.0002844/0.02922, allocations: 439.6 kB / 0.9834 GB, free: 6.555 MB / 0.7637 GB
Notification: Performance of Early Inline: time 0.004268/0.0335, allocations: 5.2 MB / 0.9885 GB, free: 1.301 MB / 0.7637 GB
Notification: Performance of simplify1: time 0.0003277/0.03383, allocations: 367.4 kB / 0.9888 GB, free: 0.9414 MB / 0.7637 GB
Notification: Performance of Alias: time 0.01215/0.04599, allocations: 12.53 MB / 1.001 GB, free: 3.152 MB / 0.7794 GB
Notification: Performance of simplify2: time 0.0002563/0.04628, allocations: 207.7 kB / 1.001 GB, free: 2.949 MB / 0.7794 GB
Notification: Performance of Detect States: time 0.0008931/0.04718, allocations: 1.079 MB / 1.002 GB, free: 1.844 MB / 0.7794 GB
Notification: Performance of Events: time 0.001134/0.04832, allocations: 0.898 MB / 1.003 GB, free: 0.9492 MB / 0.7794 GB
Notification: Performance of Partitioning: time 0.00122/0.04955, allocations: 1.301 MB / 1.004 GB, free: 15.51 MB / 0.795 GB
Notification: Performance of Causalize: time 0.004684/0.05425, allocations: 4.521 MB / 1.009 GB, free: 10.85 MB / 0.795 GB
Notification: Performance of After Index Reduction Inline: time 0.002942/0.0572, allocations: 3.388 MB / 1.012 GB, free: 7.41 MB / 0.795 GB
Notification: Performance of Inline: time 0.007101/0.06431, allocations: 7.63 MB / 1.02 GB, free: 15.64 MB / 0.8106 GB
Notification: Performance of Partitioning: time 0.0004686/0.0648, allocations: 454.1 kB / 1.02 GB, free: 15.18 MB / 0.8106 GB
Notification: Performance of Cleanup: time 0.0005833/0.06539, allocations: 0.7406 MB / 1.021 GB, free: 14.42 MB / 0.8106 GB
Notification: Performance of Causalize: time 0.02287/0.08827, allocations: 21.99 MB / 1.042 GB, free: 8.156 MB / 0.8262 GB
Notification: Performance of Tearing: time 4.498e-05/0.08834, allocations: 0 / 1.042 GB, free: 8.156 MB / 0.8262 GB
Notification: Performance of Initialization: time 9.81e-07/0.08835, allocations: 4 kB / 1.042 GB, free: 8.152 MB / 0.8262 GB
Notification: Performance of Tearing: time 2.096e-05/0.08838, allocations: 7.938 kB / 1.042 GB, free: 8.145 MB / 0.8262 GB
Notification: Performance of Categorize: time 4.085e-05/0.08842, allocations: 11.94 kB / 1.042 GB, free: 8.133 MB / 0.8262 GB
Notification: Performance of Solve: time 0.002061/0.09049, allocations: 1.228 MB / 1.043 GB, free: 6.887 MB / 0.8262 GB
Notification: Performance of Jacobian: time 0.001528/0.09202, allocations: 1.781 MB / 1.045 GB, free: 4.992 MB / 0.8262 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 3
 * Number of algebraic event partitions: ......... 15
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 3 (3) {disMinCon.minOACon.addPID.u2, disMinCon1.minOACon.addPID.u2, minCon.minOACon.addPID.u2}
 * Number of discrete states: .................... 3 (3) {minCon.retDamPosMaxSwi.u2, disMinCon1.retDamPosMaxSwi.u2, disMinCon.retDamPosMaxSwi.u2}
 * Number of clocked states: ..................... 0 (0) {NOT YET AVAILABLE}
 * Number of discrete variables: ................. 101 (101) {disMinCon.intEqu.y, disMinCon.minOACon.trigger, disMinCon.enaRetDamMin.u1, disMinCon.enaRetDamMin.u2, disMinCon.enaRetDamMin.y, disMinCon.disRetDamMin.u2, disMinCon.disRetDamMin.y, disMinCon1.intEqu.y, disMinCon1.minOACon.trigger, disMinCon1.enaRetDamMin.u1, disMinCon1.enaRetDamMin.u2, disMinCon1.enaRetDamMin.y, disMinCon1.disRetDamMin.u2, disMinCon1.disRetDamMin.y, minCon.intEqu.y, minCon.minOACon.trigger, minCon.enaRetDamMin.u1, minCon.enaRetDamMin.u2, minCon.enaRetDamMin.y, minCon.disRetDamMin.u2, minCon.disRetDamMin.y, $SEV_47, $SEV_46, $SEV_45, $SEV_44, $SEV_43, $SEV_42, $SEV_41, $SEV_40, $SEV_39, $SEV_38, $SEV_37, $SEV_36, $SEV_35, $SEV_34, $SEV_33, $SEV_32, $SEV_31, $SEV_30, $SEV_29, $SEV_28, $SEV_27, $SEV_26, $SEV_25, $SEV_24, $SEV_23, $SEV_22, $SEV_21, $SEV_20, $SEV_19, $SEV_18, $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_31, $TEV_30, $TEV_29, $TEV_28, $TEV_27, $TEV_26, $TEV_25, $TEV_24, $TEV_23, $TEV_22, $TEV_21, $TEV_20, $TEV_19, $TEV_18, $TEV_17, $TEV_16, $TEV_15, $TEV_14, $TEV_13, $TEV_12, $TEV_11, $TEV_10, $TEV_9, $TEV_8, $TEV_7, $TEV_6, $TEV_5, $TEV_4, $TEV_3, $TEV_2, $TEV_1, $TEV_0}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 195 (scalar:195, 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 (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: ........... 463 (scalar:463, array:0, record:0)
 * Number of multi strong components: ............ 3 (algorithm:3, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 463 (scalar:463, array:0, record:0)
 * Number of multi strong components: ............ 3 (algorithm:3, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 0.0001607/0.09219, allocations: 171 kB / 1.045 GB, free: 4.828 MB / 0.8262 GB
Notification: Performance of SimCode: time 0.006935/0.09913, allocations: 9.188 MB / 1.054 GB, free: 11.47 MB / 0.8419 GB
Notification: Performance of Templates: time 0.02423/0.1234, allocations: 16.23 MB / 1.07 GB, free: 11.3 MB / 0.8575 GB
make -j1 -f Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS.makefile
(rm -f Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS.pipe ; mkfifo Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS.pipe ; head -c 1048576 < Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS.pipe >> ../files/Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS.sim & ./Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Controls.OBC.ASHRAE.G36.AHUs.MultiZone.VAV.Economizers.Subsequences.Limits.Validation.SeparateWithAFMS.pipe 2>&1)