Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001329/0.001329, allocations: 110.2 kB / 18.93 MB, free: 2.945 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001479/0.001479, allocations: 193 kB / 22.23 MB, free: 5.16 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.418/1.418, allocations: 225.6 MB / 251 MB, free: 13.01 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/package.mo): time 3.192/3.192, allocations: 0.4893 GB / 0.7884 GB, free: 33.27 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/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.DHC.Examples.Cooling.ElectricChillersDirectETS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|buiETS.1..bui.loa.y.1.|dis.mCon_flow.1.|pla.senMasFlo.m_flow|pla.senTCHWSup.T|buiETS.2..ets.senTBuiSup.T|buiETS.2..ets.senTBuiRet.T",fileNamePrefix="Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS")
translateModel(Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|buiETS.1..bui.loa.y.1.|dis.mCon_flow.1.|pla.senMasFlo.m_flow|pla.senTCHWSup.T|buiETS.2..ets.senTBuiSup.T|buiETS.2..ets.senTBuiRet.T",fileNamePrefix="Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.002068/0.002068, allocations: 62.89 kB / 1.145 GB, free: 30.73 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3638/0.3659, allocations: 174.9 MB / 1.316 GB, free: 9.73 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS): time 3.676/4.042, allocations: 1.605 GB / 2.921 GB, free: 14.72 MB / 1.904 GB
Notification: Performance of NFInst.instExpressions: time 1.902/5.944, allocations: 300.5 MB / 3.215 GB, free: 63.24 MB / 1.982 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.07466/6.019, allocations: 1.774 MB / 3.216 GB, free: 63.24 MB / 1.982 GB
Notification: Performance of NFTyping.typeComponents: time 0.0395/6.058, allocations: 13.76 MB / 3.23 GB, free: 63.04 MB / 1.982 GB
Notification: Performance of NFTyping.typeBindings: time 0.1027/6.161, allocations: 35.19 MB / 3.264 GB, free: 61.82 MB / 1.982 GB
Notification: Performance of NFTyping.typeClassSections: time 0.06313/6.224, allocations: 24.89 MB / 3.289 GB, free: 60.04 MB / 1.982 GB
Notification: Performance of NFFlatten.flatten: time 0.6343/6.858, allocations: 245.7 MB / 3.528 GB, free: 5.699 MB / 2.107 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1396/6.998, allocations: 48.55 MB / 3.576 GB, free: 5.766 MB / 2.154 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1552/7.153, allocations: 57.88 MB / 3.632 GB, free: 14.35 MB / 2.217 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1047/7.258, allocations: 43.4 MB / 3.675 GB, free: 5.633 MB / 2.248 GB
Notification: Performance of NFPackage.collectConstants: time 0.03106/7.289, allocations: 8.125 MB / 3.683 GB, free: 13.5 MB / 2.264 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.08413/7.373, allocations: 23.07 MB / 3.705 GB, free: 7.539 MB / 2.279 GB
Notification: Performance of NFScalarize.scalarize: time 0.04004/7.413, allocations: 19.8 MB / 3.725 GB, free: 4.027 MB / 2.295 GB
Notification: Performance of NFVerifyModel.verify: time 0.09605/7.509, allocations: 37.65 MB / 3.761 GB, free: 14.54 MB / 2.342 GB
Notification: Performance of NFConvertDAE.convert: time 0.2339/7.743, allocations: 129.7 MB / 3.888 GB, free: 15.38 MB / 2.467 GB
Notification: Performance of FrontEnd - DAE generated: time 6.603e-06/7.743, allocations: 0 / 3.888 GB, free: 15.38 MB / 2.467 GB
Notification: Performance of FrontEnd: time 1.383e-06/7.743, allocations: 0 / 3.888 GB, free: 15.38 MB / 2.467 GB
Notification: Performance of Transformations before backend: time 0.004237/7.747, allocations: 0 / 3.888 GB, free: 15.38 MB / 2.467 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 7769
 * Number of variables: 7769
Notification: Performance of Generate backend data structure: time 1.835/9.582, allocations: 98.14 MB / 3.984 GB, free: 1.275 GB / 2.483 GB
Notification: Performance of prepare preOptimizeDAE: time 5.818e-05/9.582, allocations: 11.91 kB / 3.984 GB, free: 1.275 GB / 2.483 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1113/9.693, allocations: 21 MB / 4.004 GB, free: 1.266 GB / 2.483 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1982/9.891, allocations: 95.64 MB / 4.098 GB, free: 1.184 GB / 2.483 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.003073/9.894, allocations: 2.71 MB / 4.1 GB, free: 1.181 GB / 2.483 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01584/9.91, allocations: 3.183 MB / 4.104 GB, free: 1.178 GB / 2.483 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.151/10.06, allocations: 57.18 MB / 4.159 GB, free: 1.121 GB / 2.483 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.00177/10.06, allocations: 87.28 kB / 4.159 GB, free: 1.121 GB / 2.483 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01656/10.08, allocations: 1.261 MB / 4.161 GB, free: 1.12 GB / 2.483 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000901/10.08, allocations: 1.148 MB / 4.162 GB, free: 1.119 GB / 2.483 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1421/10.22, allocations: 56.65 MB / 4.217 GB, free: 1.063 GB / 2.483 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.4404/10.66, allocations: 259.2 MB / 4.47 GB, free: 0.8015 GB / 2.483 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.07483/10.74, allocations: 33.73 MB / 4.503 GB, free: 0.7689 GB / 2.483 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.04289/10.78, allocations: 19.12 MB / 4.522 GB, free: 0.7502 GB / 2.483 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt evalFunc (simulation): time 0.207/10.99, allocations: 85.04 MB / 4.605 GB, free: 0.6679 GB / 2.483 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.05139/11.04, allocations: 21.94 MB / 4.626 GB, free: 0.6464 GB / 2.483 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02984/11.07, allocations: 3.251 MB / 4.629 GB, free: 0.6436 GB / 2.483 GB
Notification: Performance of pre-optimization done (n=1711): time 8.751e-05/11.07, allocations: 4 kB / 4.629 GB, free: 0.6436 GB / 2.483 GB
Notification: Performance of matching and sorting (n=1711): time 0.3736/11.44, allocations: 108.6 MB / 4.736 GB, free: 0.5375 GB / 2.483 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002522/11.44, allocations: 0.874 MB / 4.736 GB, free: 0.5365 GB / 2.483 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1368/11.58, allocations: 59.81 MB / 4.795 GB, free: 489.9 MB / 2.483 GB
Notification: Performance of collectPreVariables (initialization): time 0.006849/11.59, allocations: 495.5 kB / 4.795 GB, free: 489.4 MB / 2.483 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0366/11.62, allocations: 26.03 MB / 4.821 GB, free: 463.5 MB / 2.483 GB
Notification: Performance of collectInitialBindings (initialization): time 0.01081/11.63, allocations: 6.292 MB / 4.827 GB, free: 457.2 MB / 2.483 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02972/11.66, allocations: 7.182 MB / 4.834 GB, free: 450.1 MB / 2.483 GB
Notification: Performance of setup shared object (initialization): time 4.488e-05/11.66, allocations: 301.1 kB / 4.834 GB, free: 449.8 MB / 2.483 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04841/11.71, allocations: 24.69 MB / 4.858 GB, free: 425 MB / 2.483 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0628/11.77, allocations: 33.96 MB / 4.891 GB, free: 387.5 MB / 2.483 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.1114/11.89, allocations: 53.92 MB / 4.944 GB, free: 330.6 MB / 2.483 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0003044/11.89, allocations: 64 kB / 4.944 GB, free: 330.5 MB / 2.483 GB
Notification: Performance of matching and sorting (n=3821) (initialization): time 0.1875/12.07, allocations: 71.46 MB / 5.014 GB, free: 258.9 MB / 2.483 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001422/12.07, allocations: 92 kB / 5.014 GB, free: 258.8 MB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004299/12.07, allocations: 236 kB / 5.014 GB, free: 258.6 MB / 2.483 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.047/15.12, allocations: 355.5 MB / 5.361 GB, free: 1.206 GB / 2.483 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03408/15.16, allocations: 6.782 MB / 5.368 GB, free: 1.205 GB / 2.483 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05778/15.21, allocations: 44.53 MB / 5.411 GB, free: 1.18 GB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0537/15.27, allocations: 3.591 MB / 5.415 GB, free: 1.179 GB / 2.483 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.008424/15.28, allocations: 1.501 MB / 5.416 GB, free: 1.179 GB / 2.483 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1287/15.4, allocations: 44.3 MB / 5.46 GB, free: 1.153 GB / 2.483 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.05848/15.46, allocations: 32.58 MB / 5.491 GB, free: 1.118 GB / 2.483 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.09537/15.56, allocations: 51.25 MB / 5.542 GB, free: 1.065 GB / 2.483 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0003488/15.56, allocations: 60 kB / 5.542 GB, free: 1.065 GB / 2.483 GB
Notification: Performance of matching and sorting (n=3821) (initialization_lambda0): time 0.1878/15.75, allocations: 69.1 MB / 5.609 GB, free: 0.9995 GB / 2.483 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001466/15.75, allocations: 96 kB / 5.609 GB, free: 0.9994 GB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004086/15.75, allocations: 224 kB / 5.609 GB, free: 0.9992 GB / 2.483 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.02/17.77, allocations: 354.4 MB / 5.955 GB, free: 0.6528 GB / 2.483 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03125/17.8, allocations: 6.743 MB / 5.962 GB, free: 0.6462 GB / 2.483 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.065/17.86, allocations: 49.91 MB / 6.011 GB, free: 0.5964 GB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05607/17.92, allocations: 3.69 MB / 6.014 GB, free: 0.5928 GB / 2.483 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.009488/17.93, allocations: 1.435 MB / 6.016 GB, free: 0.5914 GB / 2.483 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 938
 * Number of states: 0 ()
 * Number of discrete variables: 152 ($PRE.pla.chiStaCon.offToOne.enableFire,$PRE.pla.chiStaCon.oneToTwo.enableFire,$PRE.pla.chiStaCon.twoToOne.enableFire,$PRE.pla.chiStaCon.oneToOff.enableFire,$PRE.buiETS[1].bui.loa.nextTimeEvent,$whenCondition6,$PRE.buiETS[2].bui.loa.nextTimeEvent,$whenCondition5,$PRE.buiETS[3].bui.loa.nextTimeEvent,$whenCondition4,pla.chiStaCon.stateGraphRoot.resume,pla.chiStaCon.stateGraphRoot.suspend,buiETS[3].ets.con.con1.y,buiETS[3].bui.terUniCoo.con1.y,buiETS[2].ets.con.con1.y,buiETS[2].bui.terUniCoo.con1.y,buiETS[1].ets.con.con1.y,buiETS[1].bui.terUniCoo.con1.y,pla.cooTowWitByp.cooTowSpeCon.con1.y,pla.mulChiSys.chi[2].tru.y,pla.mulChiSys.chi[1].tru.y,buiETS[3].ets.int.local_reset,buiETS[2].ets.int.local_reset,buiETS[1].ets.int.local_reset,buiETS[1].bui.terUniCoo.hexWetNtu.flowRegime_nominal,buiETS[1].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyDry.cfg,buiETS[1].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.cfg,buiETS[1].bui.terUniCoo.hexWetNtu.dryWetCalcs.cfg,buiETS[1].bui.terUniCoo.hexWetNtu.flowRegime,buiETS[2].bui.terUniCoo.hexWetNtu.flowRegime_nominal,buiETS[2].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyDry.cfg,buiETS[2].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.cfg,buiETS[2].bui.terUniCoo.hexWetNtu.dryWetCalcs.cfg,buiETS[2].bui.terUniCoo.hexWetNtu.flowRegime,buiETS[3].bui.terUniCoo.hexWetNtu.flowRegime_nominal,buiETS[3].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyDry.cfg,buiETS[3].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.cfg,buiETS[3].bui.terUniCoo.hexWetNtu.dryWetCalcs.cfg,buiETS[3].bui.terUniCoo.hexWetNtu.flowRegime,buiETS[3].bui.terUniCoo.hexWetNtu.UAFroRated.isFulWet,buiETS[3].bui.terUniCoo.hexWetNtu.UAFroRated.isFulDry,buiETS[2].bui.terUniCoo.hexWetNtu.UAFroRated.isFulWet,buiETS[2].bui.terUniCoo.hexWetNtu.UAFroRated.isFulDry,buiETS[1].bui.terUniCoo.hexWetNtu.UAFroRated.isFulWet,buiETS[1].bui.terUniCoo.hexWetNtu.UAFroRated.isFulDry,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,pla.cooTowWitByp.hys.y,$PRE.pla.cooTowWitByp.hys.y,pla.pumCHW.hys[1].y,$PRE.pla.pumCHW.hys[1].y,pla.pumCHW.hys[2].y,$PRE.pla.pumCHW.hys[2].y,pla.pumCW.hys[1].y,$PRE.pla.pumCW.hys[1].y,pla.pumCW.hys[2].y,$PRE.pla.pumCW.hys[2].y,pla.chiStaCon.stateGraphRoot.activeSteps,pla.chiStaCon.y[1],pla.chiStaCon.off.active,pla.chiStaCon.off.localActive,$PRE.pla.chiStaCon.off.localActive,pla.chiStaCon.off.newActive,$PRE.pla.chiStaCon.off.newActive,pla.chiStaCon.off.oldActive,$PRE.pla.chiStaCon.off.oldActive,pla.chiStaCon.oneOn.localActive,$PRE.pla.chiStaCon.oneOn.localActive,pla.chiStaCon.oneOn.inPort[2].occupied,pla.chiStaCon.oneOn.outPort[2].available,pla.chiStaCon.oneOn.newActive,$PRE.pla.chiStaCon.oneOn.newActive,pla.chiStaCon.oneOn.oldActive,$PRE.pla.chiStaCon.oneOn.oldActive,pla.chiStaCon.oneOn.active,pla.chiStaCon.twoOn.localActive,$PRE.pla.chiStaCon.twoOn.localActive,pla.chiStaCon.twoOn.newActive,$PRE.pla.chiStaCon.twoOn.newActive,pla.chiStaCon.twoOn.oldActive,$PRE.pla.chiStaCon.twoOn.oldActive,pla.chiStaCon.twoOn.active,pla.chiStaCon.offToOne.enableFire,pla.chiStaCon.offToOne.fire,pla.chiStaCon.offToOne.t_start,$PRE.pla.chiStaCon.offToOne.t_start,pla.chiStaCon.oneToTwo.enableFire,pla.chiStaCon.oneToTwo.fire,pla.chiStaCon.oneToTwo.t_start,$PRE.pla.chiStaCon.oneToTwo.t_start,pla.chiStaCon.twoToOne.localCondition,pla.chiStaCon.twoToOne.enableFire,pla.chiStaCon.twoToOne.fire,pla.chiStaCon.twoToOne.t_start,$PRE.pla.chiStaCon.twoToOne.t_start,pla.chiStaCon.oneToOff.enableFire,pla.chiStaCon.oneToOff.fire,pla.chiStaCon.oneToOff.t_start,$PRE.pla.chiStaCon.oneToOff.t_start,pla.chiStaCon.thrOneToTwo.y,$PRE.pla.chiStaCon.thrOneToTwo.y,pla.chiBypCon.booToInt[1].y,pla.chiBypCon.booToInt[2].y,pla.chiBypCon.intGreThr.y,pla.chiBypCon.numChiOn.y,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,buiETS[1].bui.loa.nextTimeEvent,buiETS[1].bui.loa.nextTimeEventScaled,$PRE.buiETS[1].bui.loa.nextTimeEventScaled,buiETS[1].bui.terUniCoo.greThr.greHys.y,$PRE.buiETS[1].bui.terUniCoo.greThr.greHys.y,buiETS[2].bui.loa.nextTimeEvent,buiETS[2].bui.loa.nextTimeEventScaled,$PRE.buiETS[2].bui.loa.nextTimeEventScaled,buiETS[2].bui.terUniCoo.greThr.greHys.y,$PRE.buiETS[2].bui.terUniCoo.greThr.greHys.y,buiETS[3].bui.loa.nextTimeEvent,buiETS[3].bui.loa.nextTimeEventScaled,$PRE.buiETS[3].bui.loa.nextTimeEventScaled,buiETS[3].bui.terUniCoo.greThr.greHys.y,$PRE.buiETS[3].bui.terUniCoo.greThr.greHys.y,offCoo.y,tim.passed,$PRE.tim.passed,tim.entryTime,$PRE.tim.entryTime,$whenCondition3,$whenCondition2,$whenCondition1,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition20)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (3145):
 * Single equations (assignments): 3076
 * Array equations: 18
 * Algorithm blocks: 0
 * Record equations: 24
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 8
 * Torn equation systems: 19
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 8 systems
   {1, 1, 1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 19 systems
   {(7,50), (1,6), (1,6), (4,11), (1,1), (1,1), (1,4), (1,2), (1,60), (5,2), (1,4), (1,2), (1,60), (5,2), (1,4), (1,2), (1,60), (5,2), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.01549/17.94, allocations: 1.935 MB / 6.018 GB, free: 0.5897 GB / 2.483 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0217/17.97, allocations: 4.702 MB / 6.022 GB, free: 0.5876 GB / 2.483 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1598/18.13, allocations: 55.15 MB / 6.076 GB, free: 0.5341 GB / 2.483 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0004011/18.13, allocations: 219.9 kB / 6.076 GB, free: 0.5339 GB / 2.483 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.9e-05/18.13, allocations: 0 / 6.076 GB, free: 0.5339 GB / 2.483 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.128/18.25, allocations: 44.12 MB / 6.119 GB, free: 0.4908 GB / 2.483 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.2841/18.54, allocations: 114.2 MB / 6.231 GB, free: 388.6 MB / 2.483 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0004093/18.54, allocations: 27.98 kB / 6.231 GB, free: 388.6 MB / 2.483 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.02172/18.56, allocations: 4.115 MB / 6.235 GB, free: 384.4 MB / 2.483 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.046/19.61, allocations: 159.2 MB / 6.39 GB, free: 224.9 MB / 2.483 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.005965/19.61, allocations: 0.6245 MB / 6.391 GB, free: 224.3 MB / 2.483 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04686/19.66, allocations: 37.98 MB / 6.428 GB, free: 185.3 MB / 2.483 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.127e-06/19.66, allocations: 19.94 kB / 6.428 GB, free: 185.3 MB / 2.483 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.2145/19.87, allocations: 78.92 MB / 6.505 GB, free: 106.4 MB / 2.483 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.03296/19.91, allocations: 11.12 MB / 6.516 GB, free: 95.23 MB / 2.483 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01766/19.92, allocations: 467.8 kB / 6.517 GB, free: 94.77 MB / 2.483 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.04085/19.96, allocations: 2.994 MB / 6.519 GB, free: 91.79 MB / 2.483 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.005376/19.97, allocations: 1.496 MB / 6.521 GB, free: 90.29 MB / 2.483 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0052/19.98, allocations: 0.8977 MB / 6.522 GB, free: 89.39 MB / 2.483 GB
Notification: Performance of sorting global known variables: time 0.08913/20.06, allocations: 42.34 MB / 6.563 GB, free: 47.32 MB / 2.483 GB
Notification: Performance of sort global known variables: time 2e-07/20.06, allocations: 0 / 6.563 GB, free: 47.32 MB / 2.483 GB
Notification: Performance of remove unused functions: time 1.071/21.14, allocations: 23.96 MB / 6.587 GB, free: 1.193 GB / 2.483 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 50
 * Number of states: 94 (buiETS[3].ets.senTBuiSup.T,buiETS[3].ets.con.I.y,buiETS[3].ets.int.y,buiETS[3].ets.senTBuiRet.T,buiETS[3].ets.conVal.actPos.y,buiETS[3].ets.spl.vol.dynBal.U,buiETS[3].ets.senTDisRet.T,buiETS[3].ets.jun.vol.dynBal.U,buiETS[3].ets.senTDisSup.T,buiETS[3].bui.terUniCoo.TLoaODE.TAir,buiETS[3].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.TWatOutEst,buiETS[3].bui.terUniCoo.con.I.y,buiETS[3].bui.disFloCoo.senTSup.T,buiETS[3].bui.disFloCoo.heaCoo.vol.dynBal.U,buiETS[2].ets.senTBuiSup.T,buiETS[2].ets.con.I.y,buiETS[2].ets.int.y,buiETS[2].ets.senTBuiRet.T,buiETS[2].ets.conVal.actPos.y,buiETS[2].ets.spl.vol.dynBal.U,buiETS[2].ets.senTDisRet.T,buiETS[2].ets.jun.vol.dynBal.U,buiETS[2].ets.senTDisSup.T,buiETS[2].bui.terUniCoo.TLoaODE.TAir,buiETS[2].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.TWatOutEst,buiETS[2].bui.terUniCoo.con.I.y,buiETS[2].bui.disFloCoo.senTSup.T,buiETS[2].bui.disFloCoo.heaCoo.vol.dynBal.U,buiETS[1].ets.senTBuiSup.T,buiETS[1].ets.con.I.y,buiETS[1].ets.int.y,buiETS[1].ets.senTBuiRet.T,buiETS[1].ets.conVal.actPos.y,buiETS[1].ets.spl.vol.dynBal.U,buiETS[1].ets.senTDisRet.T,buiETS[1].ets.jun.vol.dynBal.U,buiETS[1].ets.senTDisSup.T,buiETS[1].bui.terUniCoo.TLoaODE.TAir,buiETS[1].bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.TWatOutEst,buiETS[1].bui.terUniCoo.con.I.y,buiETS[1].bui.disFloCoo.senTSup.T,buiETS[1].bui.disFloCoo.heaCoo.vol.dynBal.U,dis.pipEnd.vol_a.dynBal.U,dis.pipEnd.vol_b.dynBal.U,dis.pipEnd.timDel.x,dis.pipEnd.del.x,dis.con[3].junConRet.vol.dynBal.U,dis.con[3].junConSup.vol.dynBal.U,dis.con[3].pipDisRet.vol_a.dynBal.U,dis.con[3].pipDisRet.vol_b.dynBal.U,dis.con[3].pipDisRet.timDel.x,dis.con[3].pipDisRet.del.x,dis.con[3].pipDisSup.vol_a.dynBal.U,dis.con[3].pipDisSup.vol_b.dynBal.U,dis.con[3].pipDisSup.timDel.x,dis.con[3].pipDisSup.del.x,dis.con[2].junConRet.vol.dynBal.U,dis.con[2].junConSup.vol.dynBal.U,dis.con[2].pipDisRet.vol_a.dynBal.U,dis.con[2].pipDisRet.vol_b.dynBal.U,dis.con[2].pipDisRet.timDel.x,dis.con[2].pipDisRet.del.x,dis.con[2].pipDisSup.vol_a.dynBal.U,dis.con[2].pipDisSup.vol_b.dynBal.U,dis.con[2].pipDisSup.timDel.x,dis.con[2].pipDisSup.del.x,dis.con[1].junConRet.vol.dynBal.U,dis.con[1].junConSup.vol.dynBal.U,dis.con[1].pipDisRet.vol_a.dynBal.U,dis.con[1].pipDisRet.vol_b.dynBal.U,dis.con[1].pipDisRet.timDel.x,dis.con[1].pipDisRet.del.x,dis.con[1].pipDisSup.vol_a.dynBal.U,dis.con[1].pipDisSup.vol_b.dynBal.U,dis.con[1].pipDisSup.timDel.x,dis.con[1].pipDisSup.del.x,pla.chiBypCon.bypValCon.I.y,pla.senTCHWRet.T,pla.senTCHWSup.T,pla.valByp.actPos.y,pla.pumCW.pum[2].vol.dynBal.U,pla.pumCW.pum[1].vol.dynBal.U,pla.pumCHW.pum[2].vol.dynBal.U,pla.pumCHW.pum[1].vol.dynBal.U,pla.cooTowWitByp.cooTowSpeCon.I.y,pla.cooTowWitByp.bypValCon.I.y,pla.cooTowWitByp.senTCWSup.T,pla.cooTowWitByp.cooTowSys.senTem.T,pla.cooTowWitByp.cooTowSys.cooTow[2].vol.dynBal.U,pla.cooTowWitByp.cooTowSys.cooTow[1].vol.dynBal.U,pla.mulChiSys.chi[2].vol2.dynBal.U,pla.mulChiSys.chi[2].vol1.dynBal.U,pla.mulChiSys.chi[1].vol2.dynBal.U,pla.mulChiSys.chi[1].vol1.dynBal.U)
 * Number of discrete variables: 82 (pla.chiStaCon.twoToOne.enableFire,pla.chiStaCon.oneToOff.enableFire,pla.chiStaCon.oneToTwo.enableFire,pla.chiStaCon.off.active,pla.chiStaCon.offToOne.enableFire,pla.chiStaCon.oneOn.active,pla.chiStaCon.y[1],pla.chiBypCon.intGreThr.y,pla.chiStaCon.twoOn.active,offCoo.y,$cse79,$cse80,$cse81,$cse82,$cse83,$cse84,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition1,$whenCondition2,$whenCondition3,tim.entryTime,tim.passed,buiETS[3].bui.terUniCoo.greThr.greHys.y,buiETS[3].bui.loa.nextTimeEventScaled,buiETS[3].bui.loa.nextTimeEvent,buiETS[2].bui.terUniCoo.greThr.greHys.y,buiETS[2].bui.loa.nextTimeEventScaled,buiETS[2].bui.loa.nextTimeEvent,buiETS[1].bui.terUniCoo.greThr.greHys.y,buiETS[1].bui.loa.nextTimeEventScaled,buiETS[1].bui.loa.nextTimeEvent,weaDat.decAng.tNext,weaDat.eqnTim.tNext,weaDat.conTim.tNext,weaDat.conTimMin.tNext,weaDat.locTim.tNext,pla.chiBypCon.numChiOn.y,pla.chiBypCon.booToInt[2].y,pla.chiBypCon.booToInt[1].y,pla.chiStaCon.thrOneToTwo.y,pla.chiStaCon.oneToOff.t_start,pla.chiStaCon.oneToOff.fire,pla.chiStaCon.twoToOne.t_start,pla.chiStaCon.twoToOne.fire,pla.chiStaCon.twoToOne.localCondition,pla.chiStaCon.oneToTwo.t_start,pla.chiStaCon.oneToTwo.fire,pla.chiStaCon.offToOne.t_start,pla.chiStaCon.offToOne.fire,pla.chiStaCon.twoOn.oldActive,pla.chiStaCon.twoOn.newActive,pla.chiStaCon.twoOn.localActive,pla.chiStaCon.oneOn.oldActive,pla.chiStaCon.oneOn.newActive,pla.chiStaCon.oneOn.outPort[2].available,pla.chiStaCon.oneOn.inPort[2].occupied,pla.chiStaCon.oneOn.localActive,pla.chiStaCon.off.oldActive,pla.chiStaCon.off.newActive,pla.chiStaCon.off.localActive,pla.chiStaCon.stateGraphRoot.activeSteps,pla.pumCW.hys[2].y,pla.pumCW.hys[1].y,pla.pumCHW.hys[2].y,pla.pumCHW.hys[1].y,pla.cooTowWitByp.hys.y)
 * Number of discrete states: 24 (buiETS[1].bui.loa.nextTimeEvent,buiETS[2].bui.loa.nextTimeEvent,buiETS[3].bui.loa.nextTimeEvent,buiETS[3].bui.loa.nextTimeEventScaled,buiETS[3].bui.terUniCoo.greThr.greHys.y,buiETS[2].bui.loa.nextTimeEventScaled,buiETS[2].bui.terUniCoo.greThr.greHys.y,buiETS[1].bui.loa.nextTimeEventScaled,tim.entryTime,buiETS[1].bui.terUniCoo.greThr.greHys.y,weaDat.conTimMin.tNext,weaDat.decAng.tNext,weaDat.eqnTim.tNext,weaDat.conTim.tNext,weaDat.locTim.tNext,pla.chiStaCon.twoOn.newActive,pla.chiStaCon.oneOn.newActive,pla.chiStaCon.off.newActive,pla.pumCW.hys[2].y,pla.pumCW.hys[1].y,pla.pumCHW.hys[2].y,pla.pumCHW.hys[1].y,pla.cooTowWitByp.hys.y,pla.chiStaCon.thrOneToTwo.y)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (1685):
 * Single equations (assignments): 1533
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 110
 * When equations: 20
 * If-equations: 0
 * Equation systems (not torn): 5
 * Torn equation systems: 17
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 5 systems
   {1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 17 systems
   {(1,4), (1,2), (7,49), (1,56), (1,1), (1,4), (1,2), (1,56), (1,1), (1,4), (1,2), (1,56), (1,1), (1,6), (1,6), (1,3), (4,11)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0159/21.15, allocations: 5.316 MB / 6.592 GB, free: 1.192 GB / 2.483 GB
Notification: Performance of simCode: created initialization part: time 0.226/21.38, allocations: 79.22 MB / 6.669 GB, free: 1.174 GB / 2.483 GB
Notification: Performance of simCode: created event and clocks part: time 3.95e-05/21.38, allocations: 25.59 kB / 6.669 GB, free: 1.174 GB / 2.483 GB
Notification: Performance of simCode: created simulation system equations: time 0.05785/21.43, allocations: 24.09 MB / 6.693 GB, free: 1.166 GB / 2.483 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.1375/21.57, allocations: 26.9 MB / 6.719 GB, free: 1.159 GB / 2.483 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2609/21.83, allocations: 125.6 MB / 6.842 GB, free: 1.064 GB / 2.483 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.07157/21.9, allocations: 36.86 MB / 6.878 GB, free: 1.028 GB / 2.483 GB
Notification: Performance of simCode: alias equations: time 0.07708/21.98, allocations: 21.96 MB / 6.899 GB, free: 1.007 GB / 2.483 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.017/22, allocations: 3.447 MB / 6.902 GB, free: 1.003 GB / 2.483 GB
Notification: Performance of SimCode: time 1.533e-06/22, allocations: 0 / 6.902 GB, free: 1.003 GB / 2.483 GB
Notification: Performance of Templates: time 3.42/25.42, allocations: 1.148 GB / 8.05 GB, free: 1.058 GB / 2.483 GB
"
[Timeout remaining time 275]
make -j1 -f Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe ; mkfifo Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe ; head -c 1048576 < Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe >> ../files/Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.sim & ./Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe 2>&1) [Timeout 800]
Could not SIGINT process: 1011714.
Could not SIGINT process: 1011715.
Could not SIGINT process: 1011718.
Could not SIGINT process: 1007475.
[Calling os._exit(0), Time elapsed: 867.0508948108181]
Failed to read output from testmodel.py, exit status != 0:
25.429774423129857 25.443791593 17.695083081
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa598ee17b0>