Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_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.001503/0.001503, allocations: 80.41 kB / 20.23 MB, free: 4.195 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.001747/0.001747, allocations: 161.7 kB / 23.54 MB, free: 0.8867 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.467/1.467, allocations: 177.1 MB / 203.9 MB, free: 5.59 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo): time 2.699/2.699, allocations: 359 MB / 0.6048 GB, free: 10.93 MB / 0.573 GB
"
[Timeout remaining time 177]
Using package Buildings with version 11.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Buildings.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_11_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_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 2.154e-06/2.154e-06, allocations: 0 / 0.7863 GB, free: 12.82 MB / 0.7448 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.847e-05/3.063e-05, allocations: 6.031 kB / 0.7864 GB, free: 12.81 MB / 0.7448 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS): time 3.395/3.395, allocations: 1.433 GB / 2.219 GB, free: 40.69 MB / 1.417 GB
Notification: Performance of NFInst.instExpressions: time 0.3948/3.789, allocations: 286.8 MB / 2.499 GB, free: 15.89 MB / 1.526 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0696/3.859, allocations: 1.79 MB / 2.501 GB, free: 14.09 MB / 1.526 GB
Notification: Performance of NFTyping.typeComponents: time 0.05269/3.912, allocations: 13.32 MB / 2.514 GB, free: 0.6914 MB / 1.526 GB
Notification: Performance of NFTyping.typeBindings: time 0.1211/4.033, allocations: 36.05 MB / 2.549 GB, free: 12.46 MB / 1.573 GB
Notification: Performance of NFTyping.typeClassSections: time 0.06879/4.102, allocations: 25.07 MB / 2.574 GB, free: 3.359 MB / 1.589 GB
Notification: Performance of NFFlatten.flatten: time 2.678/6.779, allocations: 291.3 MB / 2.858 GB, free: 51.19 MB / 1.682 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.122/6.901, allocations: 51.6 MB / 2.909 GB, free: 46.97 MB / 1.682 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.2559/7.157, allocations: 91.72 MB / 2.998 GB, free: 14.52 MB / 1.682 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1035/7.261, allocations: 45.4 MB / 3.043 GB, free: 11.91 MB / 1.714 GB
Notification: Performance of NFPackage.collectConstants: time 0.03366/7.294, allocations: 7.977 MB / 3.05 GB, free: 3.934 MB / 1.714 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.07631/7.371, allocations: 19.39 MB / 3.069 GB, free: 1.902 MB / 1.729 GB
Notification: Performance of NFScalarize.scalarize: time 0.04239/7.413, allocations: 20.24 MB / 3.089 GB, free: 14.51 MB / 1.761 GB
Notification: Performance of NFVerifyModel.verify: time 0.0847/7.498, allocations: 24.49 MB / 3.113 GB, free: 6.824 MB / 1.776 GB
Notification: Performance of NFConvertDAE.convert: time 0.2255/7.723, allocations: 130.4 MB / 3.24 GB, free: 8.973 MB / 1.901 GB
Notification: Performance of FrontEnd - DAE generated: time 5.38e-06/7.723, allocations: 4 kB / 3.24 GB, free: 8.969 MB / 1.901 GB
Notification: Performance of FrontEnd: time 1.733e-06/7.723, allocations: 3.938 kB / 3.24 GB, free: 8.965 MB / 1.901 GB
Notification: Performance of Transformations before backend: time 0.003472/7.727, allocations: 0 / 3.24 GB, free: 8.965 MB / 1.901 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 7807
 * Number of variables: 7807
Notification: Performance of Generate backend data structure: time 0.296/8.023, allocations: 97.78 MB / 3.336 GB, free: 7.723 MB / 1.995 GB
Notification: Performance of prepare preOptimizeDAE: time 5.432e-05/8.023, allocations: 12.03 kB / 3.336 GB, free: 7.711 MB / 1.995 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1467/8.169, allocations: 25.23 MB / 3.36 GB, free: 14.45 MB / 2.026 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.7955/8.965, allocations: 107 MB / 3.465 GB, free: 1.173 GB / 2.073 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.005282/8.97, allocations: 2.848 MB / 3.468 GB, free: 1.172 GB / 2.073 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01756/8.988, allocations: 3.295 MB / 3.471 GB, free: 1.171 GB / 2.073 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1586/9.146, allocations: 61.36 MB / 3.531 GB, free: 1.143 GB / 2.073 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.001723/9.148, allocations: 88.12 kB / 3.531 GB, free: 1.143 GB / 2.073 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01372/9.162, allocations: 1.27 MB / 3.532 GB, free: 1.142 GB / 2.073 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000974/9.163, allocations: 1.167 MB / 3.533 GB, free: 1.142 GB / 2.073 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1362/9.299, allocations: 55.14 MB / 3.587 GB, free: 1.115 GB / 2.073 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.3748/9.674, allocations: 195.4 MB / 3.778 GB, free: 0.927 GB / 2.073 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.06807/9.742, allocations: 32.29 MB / 3.809 GB, free: 0.8962 GB / 2.073 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.04069/9.783, allocations: 18.59 MB / 3.828 GB, free: 0.8781 GB / 2.073 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.1769/9.959, allocations: 72.25 MB / 3.898 GB, free: 0.8081 GB / 2.073 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.05027/10.01, allocations: 25.26 MB / 3.923 GB, free: 0.7824 GB / 2.073 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02477/10.03, allocations: 3.388 MB / 3.926 GB, free: 0.7791 GB / 2.073 GB
Notification: Performance of pre-optimization done (n=1733): time 5.301e-05/10.03, allocations: 1.75 kB / 3.926 GB, free: 0.7791 GB / 2.073 GB
Notification: Performance of matching and sorting (n=1733): time 0.3784/10.41, allocations: 105.9 MB / 4.029 GB, free: 0.6766 GB / 2.073 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002911/10.41, allocations: 0.8742 MB / 4.03 GB, free: 0.6756 GB / 2.073 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1273/10.54, allocations: 55.62 MB / 4.085 GB, free: 0.6216 GB / 2.073 GB
Notification: Performance of collectPreVariables (initialization): time 0.006817/10.55, allocations: 0.496 MB / 4.085 GB, free: 0.6211 GB / 2.073 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03621/10.58, allocations: 26.91 MB / 4.111 GB, free: 0.5949 GB / 2.073 GB
Notification: Performance of collectInitialBindings (initialization): time 0.01042/10.59, allocations: 6.227 MB / 4.118 GB, free: 0.5889 GB / 2.073 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02385/10.62, allocations: 5.868 MB / 4.123 GB, free: 0.5831 GB / 2.073 GB
Notification: Performance of setup shared object (initialization): time 3.971e-05/10.62, allocations: 305.1 kB / 4.124 GB, free: 0.5828 GB / 2.073 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0427/10.66, allocations: 20.02 MB / 4.143 GB, free: 0.5633 GB / 2.073 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.06724/10.73, allocations: 35.43 MB / 4.178 GB, free: 0.5236 GB / 2.073 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0894/10.82, allocations: 45.77 MB / 4.222 GB, free: 487.5 MB / 2.073 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002747/10.82, allocations: 56 kB / 4.222 GB, free: 487.4 MB / 2.073 GB
Notification: Performance of matching and sorting (n=3156) (initialization): time 0.1873/11, allocations: 64.23 MB / 4.285 GB, free: 423 MB / 2.073 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003394/11.01, allocations: 92 kB / 4.285 GB, free: 422.9 MB / 2.073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0005553/11.01, allocations: 228 kB / 4.285 GB, free: 422.7 MB / 2.073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.194/13.2, allocations: 355.9 MB / 4.633 GB, free: 66.19 MB / 2.073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03433/13.23, allocations: 6.354 MB / 4.639 GB, free: 59.83 MB / 2.073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06495/13.3, allocations: 43.98 MB / 4.682 GB, free: 14.62 MB / 2.073 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05641/13.36, allocations: 3.378 MB / 4.685 GB, free: 11.26 MB / 2.073 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01122/13.37, allocations: 1.479 MB / 4.687 GB, free: 9.781 MB / 2.073 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.6384/14.01, allocations: 40.5 MB / 4.726 GB, free: 1.126 GB / 2.073 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.05585/14.06, allocations: 34.75 MB / 4.76 GB, free: 1.105 GB / 2.073 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.08862/14.15, allocations: 44.53 MB / 4.804 GB, free: 1.084 GB / 2.073 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0002917/14.15, allocations: 58.88 kB / 4.804 GB, free: 1.084 GB / 2.073 GB
Notification: Performance of matching and sorting (n=3156) (initialization_lambda0): time 0.2028/14.35, allocations: 66.01 MB / 4.868 GB, free: 1.066 GB / 2.073 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003106/14.35, allocations: 95.73 kB / 4.868 GB, free: 1.066 GB / 2.073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004915/14.35, allocations: 218.2 kB / 4.869 GB, free: 1.066 GB / 2.073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.076/16.43, allocations: 354.9 MB / 5.215 GB, free: 0.7393 GB / 2.073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03094/16.46, allocations: 6.283 MB / 5.221 GB, free: 0.7331 GB / 2.073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06967/16.53, allocations: 48.09 MB / 5.268 GB, free: 0.6852 GB / 2.073 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05082/16.58, allocations: 3.464 MB / 5.272 GB, free: 0.6819 GB / 2.073 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.008182/16.59, allocations: 1.379 MB / 5.273 GB, free: 0.6805 GB / 2.073 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 930
 * 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].ets.int.local_reset,buiETS[2].ets.int.local_reset,buiETS[1].ets.int.local_reset,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)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2794):
 * Single equations (assignments): 2735
 * Array equations: 18
 * Algorithm blocks: 0
 * Record equations: 14
 * 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,56), (1,6), (1,6), (4,11), (1,1), (1,1), (1,2), (1,4), (1,2), (2,59), (5,2), (1,4), (1,2), (2,59), (5,2), (1,4), (1,2), (2,59), (5,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.01597/16.61, allocations: 1.817 MB / 5.275 GB, free: 0.6788 GB / 2.073 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02805/16.63, allocations: 5.193 MB / 5.28 GB, free: 0.6746 GB / 2.073 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1816/16.81, allocations: 54.81 MB / 5.333 GB, free: 0.6213 GB / 2.073 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.000435/16.82, allocations: 231.9 kB / 5.334 GB, free: 0.6211 GB / 2.073 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001105/16.82, allocations: 0 / 5.334 GB, free: 0.6211 GB / 2.073 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.1349/16.95, allocations: 43.47 MB / 5.376 GB, free: 0.5785 GB / 2.073 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.3009/17.25, allocations: 99.31 MB / 5.473 GB, free: 493.3 MB / 2.073 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003656/17.25, allocations: 27.98 kB / 5.473 GB, free: 493.3 MB / 2.073 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.02262/17.27, allocations: 4.139 MB / 5.477 GB, free: 489.1 MB / 2.073 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.073/18.35, allocations: 159.6 MB / 5.633 GB, free: 329.3 MB / 2.073 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.006084/18.35, allocations: 0.6401 MB / 5.634 GB, free: 328.6 MB / 2.073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04809/18.4, allocations: 37.23 MB / 5.67 GB, free: 290.3 MB / 2.073 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.073e-05/18.4, allocations: 15.94 kB / 5.67 GB, free: 290.3 MB / 2.073 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.2073/18.61, allocations: 76.79 MB / 5.745 GB, free: 213.1 MB / 2.073 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.03863/18.65, allocations: 10.74 MB / 5.755 GB, free: 202.3 MB / 2.073 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01804/18.66, allocations: 479.9 kB / 5.756 GB, free: 201.9 MB / 2.073 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.03955/18.7, allocations: 2.823 MB / 5.759 GB, free: 199.1 MB / 2.073 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.005803/18.71, allocations: 1.519 MB / 5.76 GB, free: 197.5 MB / 2.073 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.006041/18.72, allocations: 0.929 MB / 5.761 GB, free: 196.6 MB / 2.073 GB
Notification: Performance of sorting global known variables: time 0.1065/18.82, allocations: 39.58 MB / 5.8 GB, free: 157.3 MB / 2.073 GB
Notification: Performance of sort global known variables: time 1.9e-07/18.82, allocations: 0 / 5.8 GB, free: 157.3 MB / 2.073 GB
Notification: Performance of remove unused functions: time 0.1051/18.93, allocations: 23.33 MB / 5.823 GB, free: 135.7 MB / 2.073 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 50
 * Number of states: 98 (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.filter.s[2],buiETS[3].ets.conVal.filter.s[1],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.filter.s[2],buiETS[2].ets.conVal.filter.s[1],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.filter.s[2],buiETS[1].ets.conVal.filter.s[1],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.filter.s[2],pla.valByp.filter.s[1],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 (1701):
 * Single equations (assignments): 1544
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 110
 * When equations: 25
 * 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,58), (3,54), (1,1), (1,4), (1,2), (3,54), (1,1), (1,4), (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.01887/18.95, allocations: 5.147 MB / 5.828 GB, free: 131.6 MB / 2.073 GB
Notification: Performance of simCode: created initialization part: time 0.1636/19.11, allocations: 69.43 MB / 5.895 GB, free: 61.89 MB / 2.073 GB
Notification: Performance of simCode: created event and clocks part: time 3.251e-05/19.11, allocations: 18.06 kB / 5.895 GB, free: 61.87 MB / 2.073 GB
Notification: Performance of simCode: created simulation system equations: time 0.05295/19.16, allocations: 23.45 MB / 5.918 GB, free: 38.27 MB / 2.073 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.6747/19.84, allocations: 28.02 MB / 5.946 GB, free: 1.097 GB / 2.073 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2882/20.13, allocations: 124.3 MB / 6.067 GB, free: 1.054 GB / 2.073 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.1008/20.23, allocations: 37.39 MB / 6.104 GB, free: 1.042 GB / 2.073 GB
Notification: Performance of simCode: alias equations: time 0.1007/20.33, allocations: 20.89 MB / 6.124 GB, free: 1.039 GB / 2.073 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.02329/20.35, allocations: 3.555 MB / 6.127 GB, free: 1.039 GB / 2.073 GB
Notification: Performance of SimCode: time 2.264e-06/20.35, allocations: 0 / 6.127 GB, free: 1.039 GB / 2.073 GB
Notification: Performance of Templates: time 2.791/23.14, allocations: 1.09 GB / 7.217 GB, free: 1.053 GB / 2.073 GB
"
[Timeout remaining time 277]
make -j1 -f Buildings_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.makefile [Timeout 300]
(rm -f Buildings_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe ; mkfifo Buildings_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe ; head -c 1048576 < Buildings_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe >> ../files/Buildings_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.sim & ./Buildings_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_11_Buildings.DHC.Examples.Cooling.ElectricChillersDirectETS.pipe 2>&1) [Timeout 400]
Could not SIGINT process: 3166430.
Could not SIGINT process: 3166431.
Could not SIGINT process: 3166434.
Could not SIGINT process: 3161790.
[Calling os._exit(0), Time elapsed: 462.89253924100194]