Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.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.001949/0.001949, 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.002044/0.002044, allocations: 161 kB / 24.97 MB, free: 8.672 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.606/1.606, allocations: 177.1 MB / 205.4 MB, free: 5.461 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.201/3.201, allocations: 389.5 MB / 0.6361 GB, free: 12.31 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.Fluid.CHPs.Validation.ElectricalFollowing,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing")
translateModel(Buildings.Fluid.CHPs.Validation.ElectricalFollowing,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.327e-05/2.327e-05, allocations: 5.688 kB / 0.7759 GB, free: 5.391 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.CHPs.Validation.ElectricalFollowing): time 0.03334/0.03336, allocations: 19.23 MB / 0.7947 GB, free: 2.133 MB / 0.7448 GB
Notification: Performance of NFInst.instExpressions: time 0.02268/0.05604, allocations: 9.218 MB / 0.8037 GB, free: 8.93 MB / 0.7605 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003804/0.05985, allocations: 106.5 kB / 0.8038 GB, free: 8.828 MB / 0.7605 GB
Notification: Performance of NFTyping.typeComponents: time 0.003473/0.06332, allocations: 0.8478 MB / 0.8046 GB, free: 7.977 MB / 0.7605 GB
Notification: Performance of NFTyping.typeBindings: time 0.00549/0.06881, allocations: 1.387 MB / 0.806 GB, free: 6.594 MB / 0.7605 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006707/0.07552, allocations: 2.069 MB / 0.808 GB, free: 4.527 MB / 0.7605 GB
Notification: Performance of NFFlatten.flatten: time 0.01112/0.08664, allocations: 6.274 MB / 0.8141 GB, free: 14.29 MB / 0.7761 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.00716/0.0938, allocations: 2.762 MB / 0.8168 GB, free: 11.46 MB / 0.7761 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00822/0.102, allocations: 3.153 MB / 0.8199 GB, free: 8.309 MB / 0.7761 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003885/0.1059, allocations: 1.349 MB / 0.8212 GB, free: 6.957 MB / 0.7761 GB
Notification: Performance of NFPackage.collectConstants: time 0.001419/0.1073, allocations: 400 kB / 0.8216 GB, free: 6.566 MB / 0.7761 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004229/0.1116, allocations: 1.276 MB / 0.8229 GB, free: 5.289 MB / 0.7761 GB
Notification: Performance of combineBinaries: time 0.008385/0.1199, allocations: 5.311 MB / 0.828 GB, free: 15.95 MB / 0.7917 GB
Notification: Performance of replaceArrayConstructors: time 0.004204/0.1241, allocations: 3.14 MB / 0.8311 GB, free: 12.79 MB / 0.7917 GB
Notification: Performance of NFVerifyModel.verify: time 0.001361/0.1255, allocations: 236 kB / 0.8313 GB, free: 12.56 MB / 0.7917 GB
Notification: Performance of FrontEnd: time 0.001401/0.1269, allocations: 251.5 kB / 0.8316 GB, free: 12.31 MB / 0.7917 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 651 (628)
 * Number of variables: 651 (617)
Notification: Performance of [SIM] Bindings: time 0.02637/0.1533, allocations: 16.11 MB / 0.8473 GB, free: 11.7 MB / 0.8073 GB
Notification: Performance of [SIM] FunctionAlias: time 0.004785/0.1581, allocations: 2.518 MB / 0.8498 GB, free: 9.109 MB / 0.8073 GB
Notification: Performance of [SIM] Early Inline: time 0.02449/0.1825, allocations: 13.59 MB / 0.863 GB, free: 11.37 MB / 0.823 GB
Notification: Performance of [SIM] Simplify 1: time 0.0039/0.1864, allocations: 1.293 MB / 0.8643 GB, free: 9.844 MB / 0.823 GB
Warning: NBAlias.selectStartByConfidence: Alias set with conflicting unfixed start values of equal confidence detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.02993/0.2164, allocations: 14.96 MB / 0.8789 GB, free: 9.566 MB / 0.8386 GB
Notification: Performance of [SIM] Simplify 2: time 0.002404/0.2188, allocations: 0.772 MB / 0.8797 GB, free: 8.688 MB / 0.8386 GB
Notification: Performance of [SIM] Remove Stream: time 0.001331/0.2201, allocations: 0.6047 MB / 0.8803 GB, free: 8.027 MB / 0.8386 GB
Notification: Performance of [SIM] Detect States: time 0.004147/0.2243, allocations: 2.185 MB / 0.8824 GB, free: 5.746 MB / 0.8386 GB
Notification: Performance of [SIM] Events: time 0.00173/0.226, allocations: 0.8647 MB / 0.8832 GB, free: 4.836 MB / 0.8386 GB
Notification: Performance of [SIM] Partitioning: time 0.005625/0.2316, allocations: 2.503 MB / 0.8857 GB, free: 2.125 MB / 0.8386 GB
Notification: Performance of [SIM] Causalize: time 0.008289/0.2399, allocations: 3.708 MB / 0.8893 GB, free: 14.29 MB / 0.8542 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.008741/0.2486, allocations: 5.492 MB / 0.8947 GB, free: 8.797 MB / 0.8542 GB
Notification: Performance of [INI] Simplify: time 0.01179/0.2604, allocations: 4.418 MB / 0.899 GB, free: 4.23 MB / 0.8542 GB
Notification: Performance of [INI] Inline: time 0.01545/0.2759, allocations: 10.39 MB / 0.9091 GB, free: 9.812 MB / 0.8698 GB
Notification: Performance of [INI] Partitioning: time 0.0007312/0.2766, allocations: 158.5 kB / 0.9093 GB, free: 9.621 MB / 0.8698 GB
Notification: Performance of [INI] Cleanup: time 0.002994/0.2796, allocations: 2.391 MB / 0.9116 GB, free: 7.207 MB / 0.8698 GB
Notification: Performance of [INI] Causalize: time 0.04942/0.329, allocations: 26.94 MB / 0.9379 GB, free: 11.83 MB / 0.9011 GB
Notification: Performance of [INI] Tearing: time 0.001316/0.3303, allocations: 0.8221 MB / 0.9387 GB, free: 11.01 MB / 0.9011 GB
Notification: Performance of [SIM] Initialization: time 1.984e-06/0.3303, allocations: 4 kB / 0.9387 GB, free: 11 MB / 0.9011 GB
Notification: Performance of [SIM] Remove Dummies: time 3.785e-05/0.3304, allocations: 2 kB / 0.9387 GB, free: 11 MB / 0.9011 GB
Notification: Performance of [SIM] Tearing: time 0.0005587/0.3309, allocations: 348.9 kB / 0.9391 GB, free: 10.66 MB / 0.9011 GB
Notification: Performance of [SIM] Categorize: time 2.615e-05/0.331, allocations: 8 kB / 0.9391 GB, free: 10.65 MB / 0.9011 GB
Notification: Performance of [SIM] Solve: time 0.007628/0.3386, allocations: 3.175 MB / 0.9422 GB, free: 7.449 MB / 0.9011 GB
Notification: Performance of [SIM] Jacobian: time 0.004231/0.3428, allocations: 2.157 MB / 0.9443 GB, free: 5.102 MB / 0.9011 GB
Notification: Performance of [SIM] Minimize Homotopy System: time 6.602e-06/0.3428, allocations: 0 / 0.9443 GB, free: 5.102 MB / 0.9011 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 2
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 0
 * 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: ............................. 6 (6) {dTEng.u1, eleFol.fil.dPLim.y, EEle.y, EThe.y, EEleVal.y, ETheVal.y}
 * Number of discrete states: .................... 9 (9) {valDat.nextTimeEvent, valDat.nextTimeEventScaled, avaSig.combiTimeTable.nextTimeEvent, avaSig.combiTimeTable.nextTimeEventScaled, eleFol.opeMod.timer.passed, eleFol.opeMod.timer.entryTime, eleFol.opeMod.transition4.t_start, eleFol.opeMod.warUpCtr.timer.passed, eleFol.opeMod.warUpCtr.timer.entryTime}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 169 (142) {eleFol.avaSig, eleFol.eneCon.booExp.y, eleFol.eneCon.switch3.u2, eleFol.eneCon.and1.u1, eleFol.opeMod.staBy.active, eleFol.opeMod.staBy.localActive, eleFol.opeMod.staBy.inPort.occupied, eleFol.opeMod.staBy.inPort.set, eleFol.opeMod.staBy.outPort.available, eleFol.opeMod.staBy.outPort.reset, eleFol.opeMod.staBy.newActive, eleFol.opeMod.pumOn.active, eleFol.opeMod.pumOn.localActive, eleFol.opeMod.pumOn.inPort.occupied, eleFol.opeMod.pumOn.inPort.set, eleFol.opeMod.pumOn.outPort.available, eleFol.opeMod.pumOn.outPort.reset, eleFol.opeMod.pumOn.newActive, eleFol.opeMod.warUp.localActive, eleFol.opeMod.warUp.inPort.occupied, eleFol.opeMod.warUp.inPort.set, eleFol.opeMod.warUp.outPort.available, eleFol.opeMod.warUp.outPort.reset, eleFol.opeMod.warUp.newActive, eleFol.opeMod.cooDow.localActive, eleFol.opeMod.cooDow.inPort.occupied, eleFol.opeMod.cooDow.inPort.set, eleFol.opeMod.cooDow.outPort.available, eleFol.opeMod.cooDow.outPort.reset, eleFol.opeMod.cooDow.newActive, eleFol.opeMod.hys.y, eleFol.opeMod.and1.u2, eleFol.opeMod.transition3.enableFire, eleFol.opeMod.transition3.fire, eleFol.opeMod.transition3.inPort.available, eleFol.opeMod.transition3.outPort.occupied, eleFol.opeMod.transition5.enableFire, eleFol.opeMod.transition5.fire, eleFol.opeMod.transition5.inPort.available, eleFol.opeMod.transition5.outPort.occupied, eleFol.opeMod.transition9.localCondition, eleFol.opeMod.transition9.enableFire, eleFol.opeMod.transition9.fire, eleFol.opeMod.transition9.inPort.available, eleFol.opeMod.transition9.outPort.occupied, eleFol.opeMod.transition7.localCondition, eleFol.opeMod.transition7.enableFire, eleFol.opeMod.transition7.fire, eleFol.opeMod.transition7.inPort.available, eleFol.opeMod.transition7.outPort.occupied, eleFol.opeMod.transition10.localCondition, eleFol.opeMod.transition10.enableFire, eleFol.opeMod.transition10.fire, eleFol.opeMod.transition10.inPort.available, eleFol.opeMod.transition10.outPort.occupied, eleFol.opeMod.assWatMas.nand.y, eleFol.opeMod.assWatMas.hys.y, eleFol.opeMod.assWatMas.not1.u, eleFol.opeMod.actMod, eleFol.opeMod.transition1.enableFire, eleFol.opeMod.transition1.fire, eleFol.opeMod.transition1.inPort.available, eleFol.opeMod.transition1.outPort.occupied, eleFol.opeMod.transition2.enableFire, eleFol.opeMod.transition2.fire, eleFol.opeMod.transition2.inPort.available, eleFol.opeMod.transition2.outPort.occupied, eleFol.opeMod.nor.active, eleFol.opeMod.nor.localActive, eleFol.opeMod.nor.inPort.occupied, eleFol.opeMod.nor.inPort.set, eleFol.opeMod.nor.outPort.available, eleFol.opeMod.nor.outPort.reset, eleFol.opeMod.nor.newActive, eleFol.opeMod.transition8.enableFire, eleFol.opeMod.transition8.fire, eleFol.opeMod.transition8.inPort.available, eleFol.opeMod.transition8.outPort.occupied, eleFol.opeMod.transition6.localCondition, eleFol.opeMod.transition6.enableFire, eleFol.opeMod.transition6.fire, eleFol.opeMod.transition6.inPort.available, eleFol.opeMod.transition6.outPort.occupied, eleFol.opeMod.plaOff.active, eleFol.opeMod.plaOff.localActive, eleFol.opeMod.plaOff.inPort.occupied, eleFol.opeMod.plaOff.inPort.set, eleFol.opeMod.plaOff.outPort.available, eleFol.opeMod.plaOff.outPort.reset, eleFol.opeMod.plaOff.newActive, eleFol.opeMod.transition4.enableFire, eleFol.opeMod.transition4.fire, eleFol.opeMod.transition4.inPort.available, eleFol.opeMod.transition4.outPort.occupied, eleFol.runSig.y, eleFol.hys.y, eleFol.fil.assPow.nor.y, eleFol.fil.assPow.maxPow.y, eleFol.fil.assPow.minPow.y, eleFol.conWat.offStaBy.y, eleFol.assWatTem.hys.y, eleFol.powCon.staBy.y, eleFol.powCon.cooDow.y, eleFol.stateGraphRoot.activeSteps, $FUN_11, $FUN_12, $FUN_13, $FUN_14, $FUN_15, $FUN_16, $FUN_17, $FUN_18, $FUN_19, $FUN_20, $FUN_21, $FUN_22, $WC_3, $WC_2, $WC_1, $WC_0, $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_7, $TEV_6, $TEV_5, $TEV_4, $TEV_3, $TEV_2, $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: ........... 217 (scalar:217, array:0, record:0)
 * Number of multi strong components: ............ 8 (algorithm:0, when:8, if:0, tuple:0)
 * Number of for-loop strong components: ......... 9 (resizable: 3, generic: 0, entwined:6)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 343 (scalar:325, array:18, record:0)
 * Number of multi strong components: ............ 4 (algorithm:4, when:0, if:0, tuple:4)
 * Number of for-loop strong components: ......... 9 (resizable: 3, generic: 0, entwined:6)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 343 (scalar:325, array:18, record:0)
 * Number of multi strong components: ............ 4 (algorithm:4, when:0, if:0, tuple:4)
 * Number of for-loop strong components: ......... 9 (resizable: 3, generic: 0, entwined:6)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: Performance of backend: time 0.0002986/0.3431, allocations: 219.1 kB / 0.9445 GB, free: 4.863 MB / 0.9011 GB
Notification: Performance of SimCode: time 0.02553/0.3687, allocations: 10.13 MB / 0.9544 GB, free: 10.82 MB / 0.9167 GB
Notification: Performance of Templates: time 0.06613/0.4348, allocations: 30.39 MB / 0.9841 GB, free: 12.7 MB / 0.948 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.pipe ; mkfifo Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.pipe ; head -c 1048576 < Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.pipe >> ../files/Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.sim & ./Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 9.769085262902081]