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.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.001378/0.001378, allocations: 81.17 kB / 19.87 MB, free: 4.621 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.001638/0.001638, allocations: 169 kB / 23.27 MB, free: 1.223 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 0.9349/0.9349, allocations: 177.2 MB / 203.7 MB, free: 5.516 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 2.429/2.429, allocations: 438.1 MB / 0.682 GB, free: 14.21 MB / 0.6511 GB
"
[Timeout remaining time 177]
Using package Buildings with version 14.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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.DHC.Examples.Cooling.ElectricChillersDirectETS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|buiETS.[0-9,]+..bui.loa.y.[0-9,]+.|dis.mCon_flow.[0-9,]+.|pla.senMasFlo.m_flow|pla.senTCHWSup.T|buiETS.[0-9,]+..ets.senTBuiSup.T|buiETS.[0-9,]+..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.[0-9,]+..bui.loa.y.[0-9,]+.|dis.mCon_flow.[0-9,]+.|pla.senMasFlo.m_flow|pla.senTCHWSup.T|buiETS.[0-9,]+..ets.senTBuiSup.T|buiETS.[0-9,]+..ets.senTBuiRet.T",fileNamePrefix="Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 2.484e-06/2.484e-06, allocations: 0 / 0.8897 GB, free: 9.805 MB / 0.8542 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 4.039e-05/4.287e-05, allocations: 6.25 kB / 0.8897 GB, free: 9.797 MB / 0.8542 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS): time 0.8158/0.8158, allocations: 431.8 MB / 1.311 GB, free: 1.383 MB / 1.073 GB
Notification: Performance of NFInst.instExpressions: time 0.1993/1.015, allocations: 124.6 MB / 1.433 GB, free: 4.613 MB / 1.198 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.05216/1.067, allocations: 1.945 MB / 1.435 GB, free: 2.652 MB / 1.198 GB
Notification: Performance of NFTyping.typeComponents: time 0.02662/1.094, allocations: 8.455 MB / 1.443 GB, free: 10.14 MB / 1.214 GB
Notification: Performance of NFTyping.typeBindings: time 0.06217/1.156, allocations: 25.25 MB / 1.468 GB, free: 0.7539 MB / 1.229 GB
Notification: Performance of NFTyping.typeClassSections: time 0.04241/1.199, allocations: 24.92 MB / 1.492 GB, free: 7.82 MB / 1.26 GB
Notification: Performance of NFFlatten.flatten: time 1.474/2.672, allocations: 292.8 MB / 1.778 GB, free: 23.78 MB / 1.307 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1156/2.788, allocations: 53.47 MB / 1.83 GB, free: 4.773 MB / 1.323 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1915/2.979, allocations: 90.69 MB / 1.919 GB, free: 14.68 MB / 1.417 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.07313/3.052, allocations: 44.35 MB / 1.962 GB, free: 5.656 MB / 1.448 GB
Notification: Performance of NFPackage.collectConstants: time 0.02368/3.076, allocations: 7.809 MB / 1.97 GB, free: 13.84 MB / 1.464 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.04779/3.124, allocations: 12.81 MB / 1.982 GB, free: 1.492 MB / 1.464 GB
Notification: Performance of NFScalarize.scalarize: time 0.03282/3.157, allocations: 20.27 MB / 2.002 GB, free: 14.09 MB / 1.495 GB
Notification: Performance of NFVerifyModel.verify: time 0.06698/3.224, allocations: 24.69 MB / 2.026 GB, free: 6.203 MB / 1.511 GB
Notification: Performance of NFConvertDAE.convert: time 0.1105/3.334, allocations: 65.27 MB / 2.09 GB, free: 8.711 MB / 1.573 GB
Notification: Performance of FrontEnd - DAE generated: time 6.983e-06/3.334, allocations: 0 / 2.09 GB, free: 8.711 MB / 1.573 GB
Notification: Performance of FrontEnd: time 1.182e-06/3.334, allocations: 0 / 2.09 GB, free: 8.711 MB / 1.573 GB
Notification: Performance of Transformations before backend: time 0.00314/3.337, allocations: 0 / 2.09 GB, free: 8.711 MB / 1.573 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 7788
 * Number of variables: 7788
Notification: Performance of Generate backend data structure: time 1.341/4.678, allocations: 117.7 MB / 2.205 GB, free: 308.4 MB / 1.589 GB
Notification: Performance of prepare preOptimizeDAE: time 5.45e-05/4.678, allocations: 10.16 kB / 2.205 GB, free: 308.4 MB / 1.589 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06712/4.745, allocations: 17.04 MB / 2.222 GB, free: 304.6 MB / 1.589 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1556/4.901, allocations: 90.05 MB / 2.31 GB, free: 296.8 MB / 1.589 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.004611/4.906, allocations: 3.001 MB / 2.313 GB, free: 296.4 MB / 1.589 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01717/4.923, allocations: 3.499 MB / 2.316 GB, free: 296.3 MB / 1.589 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.09451/5.017, allocations: 33.08 MB / 2.348 GB, free: 275.6 MB / 1.589 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.002012/5.019, allocations: 87.55 kB / 2.348 GB, free: 275.6 MB / 1.589 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01094/5.03, allocations: 1.269 MB / 2.35 GB, free: 274.3 MB / 1.589 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0008081/5.031, allocations: 1.148 MB / 2.351 GB, free: 273.2 MB / 1.589 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04847/5.08, allocations: 12.41 MB / 2.363 GB, free: 261.1 MB / 1.589 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.265/5.345, allocations: 189.4 MB / 2.548 GB, free: 80.64 MB / 1.589 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.026/5.371, allocations: 14.07 MB / 2.562 GB, free: 66.92 MB / 1.589 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0126/5.383, allocations: 4.863 MB / 2.566 GB, free: 62.11 MB / 1.589 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.113/5.496, allocations: 46.48 MB / 2.612 GB, free: 16.69 MB / 1.589 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01827/5.514, allocations: 12.2 MB / 2.624 GB, free: 3.883 MB / 1.589 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02113/5.536, allocations: 3.59 MB / 2.627 GB, free: 312 kB / 1.589 GB
Notification: Performance of pre-optimization done (n=1714): time 6.784e-05/5.536, allocations: 4 kB / 2.627 GB, free: 308 kB / 1.589 GB
Notification: Performance of matching and sorting (n=1714): time 0.146/5.682, allocations: 40.6 MB / 2.667 GB, free: 7.789 MB / 1.636 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003796/5.682, allocations: 0.8013 MB / 2.668 GB, free: 6.84 MB / 1.636 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.7915/6.474, allocations: 47.5 MB / 2.714 GB, free: 334.1 MB / 1.667 GB
Notification: Performance of collectPreVariables (initialization): time 0.005524/6.479, allocations: 0.5766 MB / 2.714 GB, free: 334.1 MB / 1.667 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03504/6.514, allocations: 31.79 MB / 2.746 GB, free: 333.5 MB / 1.667 GB
Notification: Performance of collectInitialBindings (initialization): time 0.009686/6.524, allocations: 6.505 MB / 2.752 GB, free: 332.1 MB / 1.667 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02464/6.548, allocations: 7.843 MB / 2.76 GB, free: 331.7 MB / 1.667 GB
Notification: Performance of setup shared object (initialization): time 4.71e-05/6.549, allocations: 478.4 kB / 2.76 GB, free: 331.2 MB / 1.667 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0181/6.567, allocations: 4.616 MB / 2.765 GB, free: 331 MB / 1.667 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.02822/6.595, allocations: 28.05 MB / 2.792 GB, free: 302.6 MB / 1.667 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.04018/6.635, allocations: 25.91 MB / 2.817 GB, free: 283.8 MB / 1.667 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0007154/6.636, allocations: 99.47 kB / 2.817 GB, free: 283.8 MB / 1.667 GB
Notification: Performance of matching and sorting (n=3740) (initialization): time 0.1047/6.74, allocations: 29.03 MB / 2.846 GB, free: 280.4 MB / 1.667 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002658/6.741, allocations: 152.7 kB / 2.846 GB, free: 280.4 MB / 1.667 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0006405/6.741, allocations: 361.1 kB / 2.846 GB, free: 280.4 MB / 1.667 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.4908/7.232, allocations: 126.2 MB / 2.969 GB, free: 250.6 MB / 1.667 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02116/7.253, allocations: 6.622 MB / 2.976 GB, free: 244.7 MB / 1.667 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01623/7.269, allocations: 28.3 MB / 3.003 GB, free: 216.7 MB / 1.667 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03865/7.308, allocations: 1.681 MB / 3.005 GB, free: 215.5 MB / 1.667 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.008315/7.316, allocations: 1.584 MB / 3.007 GB, free: 214.1 MB / 1.667 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.03778/7.354, allocations: 5.078 MB / 3.012 GB, free: 210.1 MB / 1.667 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03311/7.387, allocations: 27.94 MB / 3.039 GB, free: 175.4 MB / 1.667 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.03842/7.426, allocations: 25.7 MB / 3.064 GB, free: 147.4 MB / 1.667 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0009227/7.427, allocations: 96 kB / 3.064 GB, free: 147.3 MB / 1.667 GB
Notification: Performance of matching and sorting (n=3740) (initialization_lambda0): time 0.09253/7.519, allocations: 28.87 MB / 3.092 GB, free: 123.5 MB / 1.667 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000207/7.519, allocations: 158.2 kB / 3.092 GB, free: 123.3 MB / 1.667 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0005118/7.52, allocations: 364 kB / 3.093 GB, free: 123 MB / 1.667 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.4639/7.984, allocations: 125.4 MB / 3.215 GB, free: 14.02 MB / 1.683 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02062/8.004, allocations: 6.604 MB / 3.222 GB, free: 8.094 MB / 1.683 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02204/8.026, allocations: 29.56 MB / 3.251 GB, free: 11.98 MB / 1.714 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03475/8.061, allocations: 1.755 MB / 3.252 GB, free: 10.72 MB / 1.714 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.006259/8.067, allocations: 1.476 MB / 3.254 GB, free: 9.48 MB / 1.714 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1562
 * Number of states: 0 ()
 * Number of discrete variables: 147 ($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[2].ets.con.con1.y,buiETS[1].ets.con.con1.y,pla.cooTowWitByp.cooTowSpeCon.con1.y,pla.mulChiSys.chi[2].tru.y,pla.mulChiSys.chi[1].tru.y,buiETS[3].bui.terUniCoo.hexWetNtu.flowRegime_nominal,buiETS[3].bui.terUniCoo.hexWetNtu.UAFroRated.isFulWet,buiETS[3].bui.terUniCoo.hexWetNtu.UAFroRated.isFulDry,buiETS[2].bui.terUniCoo.hexWetNtu.flowRegime_nominal,buiETS[2].bui.terUniCoo.hexWetNtu.UAFroRated.isFulWet,buiETS[2].bui.terUniCoo.hexWetNtu.UAFroRated.isFulDry,buiETS[1].bui.terUniCoo.hexWetNtu.flowRegime_nominal,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.locTim.k,$PRE.weaDat.locTim.k,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.conTimMin.k,$PRE.weaDat.conTimMin.k,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTim.k,$PRE.weaDat.conTim.k,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.eqnTim.k,$PRE.weaDat.eqnTim.k,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.decAng.k,$PRE.weaDat.decAng.k,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,buiETS[3].ets.int.local_reset,buiETS[2].ets.int.local_reset,buiETS[1].ets.int.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (3399):
 * Single equations (assignments): 3346
 * Array equations: 15
 * Algorithm blocks: 0
 * Record equations: 14
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 8
 * Torn equation systems: 16
 * 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): 16 systems
   {(7,50), (1,6), (1,6), (4,11), (1,1), (1,1), (1,2), (1,2), (2,59), (5,2), (1,2), (2,59), (5,2), (1,2), (2,59), (5,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.007719/8.075, allocations: 2.331 MB / 3.256 GB, free: 8.586 MB / 1.714 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01459/8.09, allocations: 5.41 MB / 3.261 GB, free: 3.352 MB / 1.714 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0554/8.145, allocations: 23.85 MB / 3.285 GB, free: 88 kB / 1.729 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001364/8.145, allocations: 221.1 kB / 3.285 GB, free: 15.88 MB / 1.745 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.259e-05/8.145, allocations: 4 kB / 3.285 GB, free: 15.88 MB / 1.745 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02694/8.172, allocations: 13.33 MB / 3.298 GB, free: 3.895 MB / 1.745 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.7751/8.947, allocations: 68.13 MB / 3.364 GB, free: 392.7 MB / 1.761 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001034/8.947, allocations: 27.89 kB / 3.364 GB, free: 392.7 MB / 1.761 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.009237/8.957, allocations: 3.257 MB / 3.368 GB, free: 392.7 MB / 1.761 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.2417/9.198, allocations: 66.33 MB / 3.432 GB, free: 389.7 MB / 1.761 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.00502/9.203, allocations: 0.621 MB / 3.433 GB, free: 389.7 MB / 1.761 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01415/9.218, allocations: 22.9 MB / 3.455 GB, free: 370.3 MB / 1.761 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.699e-05/9.218, allocations: 18 kB / 3.455 GB, free: 370.3 MB / 1.761 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.08809/9.306, allocations: 26.01 MB / 3.481 GB, free: 366.8 MB / 1.761 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.03226/9.338, allocations: 9.404 MB / 3.49 GB, free: 366.8 MB / 1.761 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01935/9.357, allocations: 458.2 kB / 3.49 GB, free: 366.8 MB / 1.761 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.03073/9.388, allocations: 1.125 MB / 3.491 GB, free: 366.7 MB / 1.761 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.005975/9.394, allocations: 1.374 MB / 3.493 GB, free: 366.7 MB / 1.761 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.006578/9.401, allocations: 0.8035 MB / 3.494 GB, free: 366.7 MB / 1.761 GB
Notification: Performance of sorting global known variables: time 0.07843/9.479, allocations: 24.74 MB / 3.518 GB, free: 365 MB / 1.761 GB
Notification: Performance of sort global known variables: time 3e-07/9.479, allocations: 0 / 3.518 GB, free: 365 MB / 1.761 GB
Notification: Performance of remove unused functions: time 0.09899/9.578, allocations: 23.11 MB / 3.54 GB, free: 352.9 MB / 1.761 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: 81 (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,$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.k,weaDat.decAng.tNext,weaDat.eqnTim.k,weaDat.eqnTim.tNext,weaDat.conTim.k,weaDat.conTim.tNext,weaDat.conTimMin.k,weaDat.conTimMin.tNext,weaDat.locTim.k,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: 29 (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.conTimMin.k,weaDat.conTim.tNext,weaDat.conTim.k,weaDat.locTim.tNext,weaDat.locTim.k,weaDat.eqnTim.tNext,weaDat.eqnTim.k,weaDat.decAng.tNext,weaDat.decAng.k,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 (1687):
 * Single equations (assignments): 1539
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 104
 * When equations: 25
 * If-equations: 0
 * Equation systems (not torn): 5
 * Torn equation systems: 14
 * 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): 14 systems
   {(1,2), (7,49), (3,54), (1,1), (1,2), (3,54), (1,1), (1,2), (3,54), (1,1), (1,6), (1,6), (1,3), (4,11)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01109/9.589, allocations: 2.611 MB / 3.543 GB, free: 351.5 MB / 1.761 GB
Notification: Performance of simCode: created initialization part: time 0.0887/9.678, allocations: 30.52 MB / 3.573 GB, free: 330 MB / 1.761 GB
Notification: Performance of simCode: created event and clocks part: time 3.629e-05/9.678, allocations: 18.44 kB / 3.573 GB, free: 330 MB / 1.761 GB
Notification: Performance of simCode: created simulation system equations: time 0.02374/9.702, allocations: 8.149 MB / 3.581 GB, free: 323.8 MB / 1.761 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.1284/9.83, allocations: 28.79 MB / 3.609 GB, free: 300.4 MB / 1.761 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2295/10.06, allocations: 95.22 MB / 3.702 GB, free: 214.3 MB / 1.761 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.04733/10.11, allocations: 16.93 MB / 3.718 GB, free: 201.8 MB / 1.761 GB
Notification: Performance of simCode: alias equations: time 0.06536/10.17, allocations: 9.919 MB / 3.728 GB, free: 192.1 MB / 1.761 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.09569/10.27, allocations: 18.78 MB / 3.746 GB, free: 173.9 MB / 1.761 GB
Notification: Performance of SimCode: time 2.645e-06/10.27, allocations: 0 / 3.746 GB, free: 173.9 MB / 1.761 GB
Notification: Performance of Templates: time 3.151/13.42, allocations: 1.163 GB / 4.909 GB, free: 286.2 MB / 1.839 GB
"
[Timeout remaining time 287]
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=240  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe 2>&1) [Timeout 240]
[Calling os._exit(0), Time elapsed: 278.8406743570231]