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.001011/0.001011, allocations: 73.97 kB / 20.66 MB, free: 3.844 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.001007/0.001007, allocations: 169.4 kB / 24.06 MB, free: 456 kB / 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.8955/0.8955, allocations: 177.1 MB / 204.5 MB, free: 5.504 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 1.956/1.956, allocations: 429.5 MB / 0.6743 GB, free: 3.918 MB / 0.6355 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 1.373e-06/1.373e-06, allocations: 0 / 0.8794 GB, free: 6.07 MB / 0.8386 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.409e-05/1.546e-05, allocations: 2.312 kB / 0.8794 GB, free: 6.066 MB / 0.8386 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS): time 0.8191/0.8191, allocations: 431.9 MB / 1.301 GB, free: 4.02 MB / 1.057 GB
Notification: Performance of NFInst.instExpressions: time 0.2497/1.069, allocations: 124.6 MB / 1.423 GB, free: 8.094 MB / 1.182 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.07312/1.142, allocations: 1.945 MB / 1.425 GB, free: 6.133 MB / 1.182 GB
Notification: Performance of NFTyping.typeComponents: time 0.03155/1.173, allocations: 8.467 MB / 1.433 GB, free: 13.61 MB / 1.198 GB
Notification: Performance of NFTyping.typeBindings: time 0.07625/1.25, allocations: 25.24 MB / 1.458 GB, free: 4.234 MB / 1.214 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05238/1.302, allocations: 24.91 MB / 1.482 GB, free: 11.31 MB / 1.245 GB
Notification: Performance of NFFlatten.flatten: time 1.707/3.009, allocations: 292.8 MB / 1.768 GB, free: 24.13 MB / 1.292 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1323/3.141, allocations: 53.38 MB / 1.82 GB, free: 5.246 MB / 1.307 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.2288/3.37, allocations: 90.69 MB / 1.909 GB, free: 15.31 MB / 1.401 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.09259/3.463, allocations: 44.34 MB / 1.952 GB, free: 6.293 MB / 1.432 GB
Notification: Performance of NFPackage.collectConstants: time 0.02859/3.492, allocations: 7.805 MB / 1.96 GB, free: 14.48 MB / 1.448 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.05198/3.544, allocations: 12.81 MB / 1.972 GB, free: 2.133 MB / 1.448 GB
Notification: Performance of NFScalarize.scalarize: time 0.03719/3.581, allocations: 20.26 MB / 1.992 GB, free: 14.73 MB / 1.479 GB
Notification: Performance of NFVerifyModel.verify: time 0.07639/3.657, allocations: 24.69 MB / 2.016 GB, free: 6.844 MB / 1.495 GB
Notification: Performance of NFConvertDAE.convert: time 0.1291/3.786, allocations: 65.27 MB / 2.08 GB, free: 9.371 MB / 1.557 GB
Notification: Performance of FrontEnd - DAE generated: time 8.015e-06/3.786, allocations: 0 / 2.08 GB, free: 9.371 MB / 1.557 GB
Notification: Performance of FrontEnd: time 1.162e-06/3.786, allocations: 0 / 2.08 GB, free: 9.371 MB / 1.557 GB
Notification: Performance of Transformations before backend: time 0.00348/3.79, allocations: 3.188 kB / 2.08 GB, free: 9.371 MB / 1.557 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.39/5.18, allocations: 117.7 MB / 2.195 GB, free: 308 MB / 1.573 GB
Notification: Performance of prepare preOptimizeDAE: time 4.432e-05/5.18, allocations: 14.09 kB / 2.195 GB, free: 307.9 MB / 1.573 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.07546/5.256, allocations: 17.04 MB / 2.211 GB, free: 304.2 MB / 1.573 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1486/5.404, allocations: 90.06 MB / 2.299 GB, free: 296.3 MB / 1.573 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.003257/5.407, allocations: 3.004 MB / 2.302 GB, free: 295.8 MB / 1.573 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0111/5.419, allocations: 3.494 MB / 2.306 GB, free: 295.8 MB / 1.573 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.08908/5.508, allocations: 33.08 MB / 2.338 GB, free: 273.6 MB / 1.573 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.001793/5.509, allocations: 84.19 kB / 2.338 GB, free: 273.5 MB / 1.573 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01029/5.52, allocations: 1.275 MB / 2.339 GB, free: 272.3 MB / 1.573 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007811/5.52, allocations: 1.145 MB / 2.34 GB, free: 271.1 MB / 1.573 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05061/5.571, allocations: 12.41 MB / 2.352 GB, free: 259 MB / 1.573 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.2869/5.858, allocations: 189.4 MB / 2.537 GB, free: 78.56 MB / 1.573 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02736/5.885, allocations: 14.07 MB / 2.551 GB, free: 64.83 MB / 1.573 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01321/5.899, allocations: 4.872 MB / 2.556 GB, free: 60.02 MB / 1.573 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.1256/6.024, allocations: 46.17 MB / 2.601 GB, free: 14.9 MB / 1.573 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01946/6.044, allocations: 12.21 MB / 2.613 GB, free: 2.086 MB / 1.573 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02025/6.064, allocations: 3.591 MB / 2.616 GB, free: 14.51 MB / 1.589 GB
Notification: Performance of pre-optimization done (n=1714): time 4.722e-05/6.064, allocations: 4 kB / 2.616 GB, free: 14.5 MB / 1.589 GB
Notification: Performance of matching and sorting (n=1714): time 0.1497/6.214, allocations: 39.63 MB / 2.655 GB, free: 6.961 MB / 1.62 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003166/6.214, allocations: 0.8 MB / 2.656 GB, free: 6.016 MB / 1.62 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.753/6.967, allocations: 47.5 MB / 2.702 GB, free: 334.6 MB / 1.651 GB
Notification: Performance of collectPreVariables (initialization): time 0.006151/6.973, allocations: 0.5805 MB / 2.703 GB, free: 334.6 MB / 1.651 GB
Notification: Performance of collectInitialEqns (initialization): time 0.04079/7.014, allocations: 31.79 MB / 2.734 GB, free: 334 MB / 1.651 GB
Notification: Performance of collectInitialBindings (initialization): time 0.009812/7.024, allocations: 6.51 MB / 2.74 GB, free: 332.5 MB / 1.651 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02709/7.051, allocations: 7.833 MB / 2.748 GB, free: 332 MB / 1.651 GB
Notification: Performance of setup shared object (initialization): time 5.414e-05/7.051, allocations: 484.3 kB / 2.748 GB, free: 331.6 MB / 1.651 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01928/7.07, allocations: 4.622 MB / 2.753 GB, free: 331.3 MB / 1.651 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.03245/7.103, allocations: 28.05 MB / 2.78 GB, free: 302.9 MB / 1.651 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.04407/7.147, allocations: 25.91 MB / 2.806 GB, free: 284.2 MB / 1.651 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0007559/7.147, allocations: 96.09 kB / 2.806 GB, free: 284.2 MB / 1.651 GB
Notification: Performance of matching and sorting (n=3740) (initialization): time 0.099/7.246, allocations: 28.21 MB / 2.833 GB, free: 280.7 MB / 1.651 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000412/7.247, allocations: 151.9 kB / 2.833 GB, free: 280.7 MB / 1.651 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0007663/7.248, allocations: 363 kB / 2.834 GB, free: 280.7 MB / 1.651 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.5422/7.79, allocations: 126.2 MB / 2.957 GB, free: 249.8 MB / 1.651 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02054/7.81, allocations: 6.63 MB / 2.963 GB, free: 243.8 MB / 1.651 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01699/7.827, allocations: 28.29 MB / 2.991 GB, free: 215.8 MB / 1.651 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03448/7.862, allocations: 1.701 MB / 2.993 GB, free: 214.6 MB / 1.651 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.007505/7.869, allocations: 1.579 MB / 2.994 GB, free: 213.3 MB / 1.651 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.03472/7.904, allocations: 5.073 MB / 2.999 GB, free: 209.2 MB / 1.651 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.02531/7.929, allocations: 27.93 MB / 3.026 GB, free: 174.5 MB / 1.651 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.04032/7.97, allocations: 25.7 MB / 3.052 GB, free: 146.6 MB / 1.651 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0005325/7.97, allocations: 103.2 kB / 3.052 GB, free: 146.5 MB / 1.651 GB
Notification: Performance of matching and sorting (n=3740) (initialization_lambda0): time 0.08817/8.058, allocations: 28.11 MB / 3.079 GB, free: 123.4 MB / 1.651 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002114/8.059, allocations: 155.9 kB / 3.079 GB, free: 123.2 MB / 1.651 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0005007/8.059, allocations: 365.7 kB / 3.08 GB, free: 122.9 MB / 1.651 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.5151/8.574, allocations: 125.4 MB / 3.202 GB, free: 13.91 MB / 1.667 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02036/8.595, allocations: 6.603 MB / 3.208 GB, free: 7.98 MB / 1.667 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02325/8.618, allocations: 29.5 MB / 3.237 GB, free: 11.94 MB / 1.698 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03351/8.651, allocations: 1.748 MB / 3.239 GB, free: 10.7 MB / 1.698 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00803/8.659, allocations: 1.477 MB / 3.24 GB, free: 9.449 MB / 1.698 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.0135/8.673, allocations: 2.323 MB / 3.243 GB, free: 8.562 MB / 1.698 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02223/8.695, allocations: 5.413 MB / 3.248 GB, free: 3.328 MB / 1.698 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.07588/8.771, allocations: 22.85 MB / 3.27 GB, free: 1.047 MB / 1.714 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002826/8.771, allocations: 222.5 kB / 3.271 GB, free: 0.8398 MB / 1.714 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.169e-05/8.771, allocations: 4 kB / 3.271 GB, free: 0.8359 MB / 1.714 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.05101/8.822, allocations: 12.31 MB / 3.283 GB, free: 5.867 MB / 1.729 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.889/9.711, allocations: 67.07 MB / 3.348 GB, free: 393 MB / 1.745 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0002433/9.712, allocations: 32.77 kB / 3.348 GB, free: 393 MB / 1.745 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0125/9.724, allocations: 3.249 MB / 3.351 GB, free: 393 MB / 1.745 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.2762/10, allocations: 66.33 MB / 3.416 GB, free: 389.9 MB / 1.745 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.005316/10.01, allocations: 0.6258 MB / 3.417 GB, free: 389.9 MB / 1.745 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01456/10.02, allocations: 22.91 MB / 3.439 GB, free: 370.5 MB / 1.745 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.154e-05/10.02, allocations: 20 kB / 3.439 GB, free: 370.5 MB / 1.745 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0887/10.11, allocations: 25.06 MB / 3.463 GB, free: 367 MB / 1.745 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0313/10.14, allocations: 9.401 MB / 3.473 GB, free: 367 MB / 1.745 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01913/10.16, allocations: 465 kB / 3.473 GB, free: 367 MB / 1.745 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02684/10.19, allocations: 1.116 MB / 3.474 GB, free: 366.9 MB / 1.745 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.005263/10.19, allocations: 1.372 MB / 3.476 GB, free: 366.9 MB / 1.745 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.006146/10.2, allocations: 0.8033 MB / 3.476 GB, free: 366.9 MB / 1.745 GB
Notification: Performance of sorting global known variables: time 0.06265/10.26, allocations: 24.73 MB / 3.5 GB, free: 365.2 MB / 1.745 GB
Notification: Performance of sort global known variables: time 2.1e-07/10.26, allocations: 0 / 3.5 GB, free: 365.2 MB / 1.745 GB
Notification: Performance of remove unused functions: time 0.1048/10.36, allocations: 23.11 MB / 3.523 GB, free: 353.9 MB / 1.745 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.01007/10.38, allocations: 2.619 MB / 3.526 GB, free: 352.5 MB / 1.745 GB
Notification: Performance of simCode: created initialization part: time 0.0877/10.46, allocations: 30.51 MB / 3.555 GB, free: 331 MB / 1.745 GB
Notification: Performance of simCode: created event and clocks part: time 4.239e-05/10.46, allocations: 18.06 kB / 3.555 GB, free: 331 MB / 1.745 GB
Notification: Performance of simCode: created simulation system equations: time 0.02562/10.49, allocations: 8.155 MB / 3.563 GB, free: 324.6 MB / 1.745 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.1114/10.6, allocations: 28.8 MB / 3.592 GB, free: 300.6 MB / 1.745 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1827/10.78, allocations: 95.2 MB / 3.684 GB, free: 214.6 MB / 1.745 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.04215/10.82, allocations: 16.92 MB / 3.701 GB, free: 202 MB / 1.745 GB
Notification: Performance of simCode: alias equations: time 0.06277/10.89, allocations: 9.927 MB / 3.711 GB, free: 192.2 MB / 1.745 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.07374/10.96, allocations: 18.78 MB / 3.729 GB, free: 174.1 MB / 1.745 GB
Notification: Performance of SimCode: time 1.813e-06/10.96, allocations: 0 / 3.729 GB, free: 174.1 MB / 1.745 GB
Notification: Performance of Templates: time 2.975/13.94, allocations: 1.176 GB / 4.905 GB, free: 259.2 MB / 1.823 GB
"
[Timeout remaining time 286]
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: 281.30478893080726]