Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001984/0.001984, allocations: 112.5 kB / 17.13 MB, free: 5.914 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002203/0.002203, allocations: 198 kB / 20.08 MB, free: 2.977 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.499/1.499, allocations: 225.6 MB / 248.5 MB, free: 3.621 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo): time 2.706/2.706, allocations: 443.5 MB / 0.7293 GB, free: 14.47 MB / 0.5762 GB
"
[Timeout remaining time 176]
Using package Buildings with version 10.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|bui.ets.proHeaWat.heaPum.QCon_flow_nominal|bui.ets.proHeaWat.heaPum.QCon_flow|bui.ets.proHotWat.heaPum.QCon_flow_nominal|bui.ets.proHotWat.heaPum.QCon_flow|bui.PHea|bui.PPum|bui.QHea_flow|bui.QCoo_flow",fileNamePrefix="Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS")
translateModel(Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|bui.ets.proHeaWat.heaPum.QCon_flow_nominal|bui.ets.proHeaWat.heaPum.QCon_flow|bui.ets.proHotWat.heaPum.QCon_flow_nominal|bui.ets.proHotWat.heaPum.QCon_flow|bui.PHea|bui.PPum|bui.QHea_flow|bui.QCoo_flow",fileNamePrefix="Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.001946/0.001946, allocations: 71.88 kB / 1.057 GB, free: 1.938 MB / 0.795 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3797/0.3817, allocations: 160.7 MB / 1.214 GB, free: 0.9062 MB / 0.9512 GB
Notification: Performance of NFInst.instantiate(Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS): time 4.845/5.227, allocations: 1.332 GB / 2.546 GB, free: 79.16 MB / 1.779 GB
Notification: Performance of NFInst.instExpressions: time 0.4104/5.637, allocations: 283.5 MB / 2.823 GB, free: 44.78 MB / 1.779 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.07816/5.716, allocations: 1.29 MB / 2.824 GB, free: 44.78 MB / 1.779 GB
Notification: Performance of NFTyping.typeComponents: time 0.1134/5.829, allocations: 23.62 MB / 2.847 GB, free: 34.62 MB / 1.779 GB
Notification: Performance of NFTyping.typeBindings: time 0.1186/5.948, allocations: 36.13 MB / 2.882 GB, free: 15.56 MB / 1.779 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0695/6.017, allocations: 27.23 MB / 2.909 GB, free: 7.395 MB / 1.795 GB
Notification: Performance of NFFlatten.flatten: time 0.214/6.231, allocations: 102.3 MB / 3.009 GB, free: 4.816 MB / 1.889 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.06589/6.297, allocations: 22.34 MB / 3.031 GB, free: 14.68 MB / 1.92 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.08418/6.381, allocations: 29.84 MB / 3.06 GB, free: 2.355 MB / 1.936 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.07647/6.458, allocations: 22.9 MB / 3.082 GB, free: 12.84 MB / 1.967 GB
Notification: Performance of NFPackage.collectConstants: time 0.01798/6.476, allocations: 4.125 MB / 3.086 GB, free: 8.711 MB / 1.967 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.06501/6.541, allocations: 18.47 MB / 3.104 GB, free: 7.496 MB / 1.982 GB
Notification: Performance of NFScalarize.scalarize: time 0.02527/6.566, allocations: 9.524 MB / 3.114 GB, free: 14.09 MB / 1.998 GB
Notification: Performance of NFVerifyModel.verify: time 0.04734/6.613, allocations: 17.9 MB / 3.131 GB, free: 12.23 MB / 2.014 GB
Notification: Performance of NFConvertDAE.convert: time 0.1364/6.75, allocations: 68.34 MB / 3.198 GB, free: 9.367 MB / 2.076 GB
Notification: Performance of FrontEnd - DAE generated: time 7.604e-06/6.75, allocations: 0 / 3.198 GB, free: 9.367 MB / 2.076 GB
Notification: Performance of FrontEnd: time 2.234e-06/6.75, allocations: 0 / 3.198 GB, free: 9.367 MB / 2.076 GB
Notification: Performance of Transformations before backend: time 0.002225/6.752, allocations: 0 / 3.198 GB, free: 9.367 MB / 2.076 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3741
 * Number of variables: 3741
Notification: Performance of Generate backend data structure: time 0.1105/6.862, allocations: 31.97 MB / 3.229 GB, free: 9.438 MB / 2.107 GB
Notification: Performance of prepare preOptimizeDAE: time 4.758e-05/6.862, allocations: 8.031 kB / 3.229 GB, free: 9.43 MB / 2.107 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05/6.912, allocations: 7.686 MB / 3.236 GB, free: 1.727 MB / 2.107 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1126/7.025, allocations: 48.65 MB / 3.284 GB, free: 1.742 MB / 2.154 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002062/7.027, allocations: 1.249 MB / 3.285 GB, free: 500 kB / 2.154 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.007304/7.034, allocations: 1.563 MB / 3.287 GB, free: 14.93 MB / 2.17 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.8884/7.923, allocations: 20.65 MB / 3.307 GB, free: 1.1 GB / 2.17 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000796/7.924, allocations: 41.09 kB / 3.307 GB, free: 1.1 GB / 2.17 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.006229/7.93, allocations: 0.6234 MB / 3.308 GB, free: 1.1 GB / 2.17 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0006006/7.93, allocations: 0.5211 MB / 3.308 GB, free: 1.1 GB / 2.17 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.06448/7.995, allocations: 17.85 MB / 3.325 GB, free: 1.095 GB / 2.17 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.2147/8.21, allocations: 115.7 MB / 3.438 GB, free: 1.012 GB / 2.17 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03207/8.242, allocations: 11.69 MB / 3.45 GB, free: 1.006 GB / 2.17 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01665/8.258, allocations: 6.361 MB / 3.456 GB, free: 1.002 GB / 2.17 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.08098/8.339, allocations: 34.8 MB / 3.49 GB, free: 0.9736 GB / 2.17 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02216/8.362, allocations: 7.444 MB / 3.497 GB, free: 0.9693 GB / 2.17 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.016/8.378, allocations: 2.394 MB / 3.5 GB, free: 0.9681 GB / 2.17 GB
Notification: Performance of pre-optimization done (n=783): time 7.459e-05/8.378, allocations: 0 / 3.5 GB, free: 0.9681 GB / 2.17 GB
Notification: Performance of matching and sorting (n=783): time 0.1622/8.54, allocations: 34.97 MB / 3.534 GB, free: 0.9512 GB / 2.17 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002878/8.54, allocations: 0.8008 MB / 3.535 GB, free: 0.9503 GB / 2.17 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.06424/8.604, allocations: 23.53 MB / 3.558 GB, free: 0.938 GB / 2.17 GB
Notification: Performance of collectPreVariables (initialization): time 0.003528/8.608, allocations: 324.5 kB / 3.558 GB, free: 0.9378 GB / 2.17 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01785/8.626, allocations: 11.62 MB / 3.569 GB, free: 0.927 GB / 2.17 GB
Notification: Performance of collectInitialBindings (initialization): time 0.005355/8.631, allocations: 2.727 MB / 3.572 GB, free: 0.9244 GB / 2.17 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01518/8.646, allocations: 3.729 MB / 3.576 GB, free: 0.9208 GB / 2.17 GB
Notification: Performance of setup shared object (initialization): time 6.086e-05/8.646, allocations: 305.1 kB / 3.576 GB, free: 0.9205 GB / 2.17 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01968/8.666, allocations: 8.889 MB / 3.585 GB, free: 0.9118 GB / 2.17 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.02351/8.69, allocations: 13.73 MB / 3.598 GB, free: 0.8966 GB / 2.17 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.03791/8.727, allocations: 20.57 MB / 3.618 GB, free: 0.875 GB / 2.17 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001708/8.728, allocations: 35.84 kB / 3.618 GB, free: 0.875 GB / 2.17 GB
Notification: Performance of matching and sorting (n=2066) (initialization): time 0.07768/8.805, allocations: 27.35 MB / 3.645 GB, free: 0.8482 GB / 2.17 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001002/8.805, allocations: 59.94 kB / 3.645 GB, free: 0.8481 GB / 2.17 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001973/8.806, allocations: 120 kB / 3.645 GB, free: 0.848 GB / 2.17 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02249/8.828, allocations: 6.143 MB / 3.651 GB, free: 0.8421 GB / 2.17 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01446/8.843, allocations: 2.925 MB / 3.654 GB, free: 0.8393 GB / 2.17 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02951/8.872, allocations: 22.04 MB / 3.675 GB, free: 0.8169 GB / 2.17 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02544/8.897, allocations: 1.073 MB / 3.676 GB, free: 0.8158 GB / 2.17 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004559/8.902, allocations: 0.742 MB / 3.677 GB, free: 0.8151 GB / 2.17 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.03833/8.94, allocations: 14.97 MB / 3.692 GB, free: 0.8005 GB / 2.17 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.02329/8.964, allocations: 13.37 MB / 3.705 GB, free: 0.7855 GB / 2.17 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.03687/9.001, allocations: 19.86 MB / 3.724 GB, free: 0.7644 GB / 2.17 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001558/9.001, allocations: 36 kB / 3.724 GB, free: 0.7643 GB / 2.17 GB
Notification: Performance of matching and sorting (n=2066) (initialization_lambda0): time 0.08195/9.083, allocations: 27.3 MB / 3.751 GB, free: 0.7376 GB / 2.17 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001025/9.083, allocations: 56 kB / 3.751 GB, free: 0.7375 GB / 2.17 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002308/9.083, allocations: 120 kB / 3.751 GB, free: 0.7374 GB / 2.17 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02176/9.105, allocations: 5.851 MB / 3.757 GB, free: 0.7318 GB / 2.17 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01361/9.118, allocations: 2.925 MB / 3.76 GB, free: 0.7289 GB / 2.17 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02665/9.145, allocations: 20.34 MB / 3.779 GB, free: 0.7082 GB / 2.17 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02472/9.17, allocations: 1.121 MB / 3.781 GB, free: 0.7071 GB / 2.17 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004404/9.174, allocations: 0.7292 MB / 3.781 GB, free: 0.7064 GB / 2.17 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 548
 * Number of states: 0 ()
 * Number of discrete variables: 225 ($PRE.bui.ets.swiFlo.con.truFalHol.onDel1.u,$PRE.bui.ets.swiFlo.con.truFalHol.onDel2.u,$PRE.bui.ets.swiFlo.con.truFalHol.toTrue.enableFire,$PRE.bui.ets.swiFlo.con.truFalHol.toFalse.enableFire,$PRE.bui.ets.swiFlo.con.truFalHol.toTrue1.enableFire,$PRE.bui.ets.swiFlo.con.truFalHol.toFalse1.enableFire,$PRE.bui.ets.enaHea.onDel1.u,$PRE.bui.ets.enaHea.onDel2.u,$PRE.bui.ets.enaHea.toTrue.enableFire,$PRE.bui.ets.enaHea.toFalse.enableFire,$PRE.bui.ets.enaHea.toTrue1.enableFire,$PRE.bui.ets.enaHea.toFalse1.enableFire,$PRE.bui.ets.enaSHW.onDel1.u,$PRE.bui.ets.enaSHW.onDel2.u,$PRE.bui.ets.enaSHW.toTrue.enableFire,$PRE.bui.ets.enaSHW.toFalse.enableFire,$PRE.bui.ets.enaSHW.toTrue1.enableFire,$PRE.bui.ets.enaSHW.toFalse1.enableFire,bui.ets.enaSHW.toFalse1.t_start,$PRE.bui.ets.enaSHW.toFalse1.t_start,bui.ets.enaSHW.toTrue1.t_start,$PRE.bui.ets.enaSHW.toTrue1.t_start,bui.ets.enaSHW.toFalse.t_start,$PRE.bui.ets.enaSHW.toFalse.t_start,bui.ets.enaSHW.toTrue.t_start,$PRE.bui.ets.enaSHW.toTrue.t_start,bui.ets.enaHea.toFalse1.t_start,$PRE.bui.ets.enaHea.toFalse1.t_start,bui.ets.enaHea.toTrue1.t_start,$PRE.bui.ets.enaHea.toTrue1.t_start,bui.ets.enaHea.toFalse.t_start,$PRE.bui.ets.enaHea.toFalse.t_start,bui.ets.enaHea.toTrue.t_start,$PRE.bui.ets.enaHea.toTrue.t_start,bui.ets.swiFlo.con.truFalHol.toFalse1.t_start,$PRE.bui.ets.swiFlo.con.truFalHol.toFalse1.t_start,bui.ets.swiFlo.con.truFalHol.toTrue1.t_start,$PRE.bui.ets.swiFlo.con.truFalHol.toTrue1.t_start,bui.ets.swiFlo.con.truFalHol.toFalse.t_start,$PRE.bui.ets.swiFlo.con.truFalHol.toFalse.t_start,bui.ets.swiFlo.con.truFalHol.toTrue.t_start,$PRE.bui.ets.swiFlo.con.truFalHol.toTrue.t_start,$PRE.bui.ets.enaSHW.onDel2.t_next,$whenCondition17,$PRE.bui.ets.enaSHW.onDel1.t_next,$whenCondition14,$PRE.bui.ets.enaHea.onDel2.t_next,$whenCondition11,$PRE.bui.ets.enaHea.onDel1.t_next,$whenCondition8,$PRE.bui.ets.swiFlo.con.truFalHol.onDel2.t_next,$whenCondition5,$PRE.bui.ets.swiFlo.con.truFalHol.onDel1.t_next,$whenCondition2,$PRE.bui.bui.loa.nextTimeEvent,$whenCondition1,bui.ets.enaSHW.onDel2.t_next,bui.ets.enaSHW.onDel1.t_next,bui.ets.enaHea.onDel2.t_next,bui.ets.enaHea.onDel1.t_next,bui.ets.swiFlo.con.truFalHol.onDel2.t_next,bui.ets.swiFlo.con.truFalHol.onDel1.t_next,bui.ets.enaSHW.toFalse1.localCondition,bui.ets.enaSHW.toFalse1.condition,bui.ets.enaSHW.and2.u2,bui.ets.enaSHW.notU.y,bui.enaSHW.y,bui.ets.enaSHW.stateGraphRoot.resume,bui.ets.enaSHW.stateGraphRoot.suspend,bui.ets.enaHea.stateGraphRoot.resume,bui.ets.enaHea.stateGraphRoot.suspend,bui.ets.swiFlo.con.truFalHol.stateGraphRoot.resume,bui.ets.swiFlo.con.truFalHol.stateGraphRoot.suspend,bui.bui.terUniCoo.con1.y,bui.bui.terUniHea.con1.y,bui.ets.enaSHW.toFalse.localCondition,bui.bui.terUniHea.hex.flowRegime_nominal,bui.bui.terUniHea.hex.flowRegime,bui.ets.hexChi.flowRegime_nominal,bui.ets.hexChi.flowRegime,bui.bui.terUniCoo.hexWetNtu.flowRegime_nominal,bui.bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyDry.cfg,bui.bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.cfg,bui.bui.terUniCoo.hexWetNtu.dryWetCalcs.cfg,bui.bui.terUniCoo.hexWetNtu.flowRegime,bui.bui.terUniCoo.hexWetNtu.UAFroRated.isFulWet,bui.bui.terUniCoo.hexWetNtu.UAFroRated.isFulDry,bui.ets.enaSHW.toFalse1.enableFire,bui.ets.enaSHW.toFalse.enableFire,bui.enaHeaCoo[2].y,bui.enaHeaCoo[1].y,bui.ets.enaSHW.toTrue1.enableFire,bui.ets.enaSHW.initialStep.oldActive,$PRE.bui.ets.enaSHW.initialStep.oldActive,bui.ets.enaSHW.initialStep.newActive,$PRE.bui.ets.enaSHW.initialStep.newActive,bui.ets.enaSHW.initialStep.outPort[2].available,bui.ets.enaSHW.initialStep.localActive,$PRE.bui.ets.enaSHW.initialStep.localActive,bui.ets.enaSHW.initialStep.active,bui.ets.enaSHW.outputTrue.active,bui.ets.enaSHW.outputTrue.oldActive,$PRE.bui.ets.enaSHW.outputTrue.oldActive,bui.ets.enaSHW.outputTrue.newActive,$PRE.bui.ets.enaSHW.outputTrue.newActive,bui.ets.enaSHW.outputTrue.inPort[2].occupied,bui.ets.enaSHW.outputTrue.localActive,$PRE.bui.ets.enaSHW.outputTrue.localActive,bui.ets.enaSHW.toTrue.enableFire,bui.ets.enaSHW.outputFalse.active,bui.ets.enaSHW.outputFalse.oldActive,$PRE.bui.ets.enaSHW.outputFalse.oldActive,bui.ets.enaSHW.outputFalse.newActive,$PRE.bui.ets.enaSHW.outputFalse.newActive,bui.ets.enaSHW.outputFalse.inPort[2].occupied,bui.ets.enaSHW.outputFalse.localActive,$PRE.bui.ets.enaSHW.outputFalse.localActive,bui.ets.enaSHW.stateGraphRoot.activeSteps,bui.ets.enaSHW.onDel2.y,bui.ets.enaSHW.onDel2.u,bui.ets.enaSHW.onDel1.y,bui.ets.enaSHW.onDel1.u,bui.ets.proHotWat.ena.y,bui.ets.proHotWat.staPum[2].greHys.y,$PRE.bui.ets.proHotWat.staPum[2].greHys.y,bui.ets.proHotWat.staPum[1].greHys.y,$PRE.bui.ets.proHotWat.staPum[1].greHys.y,bui.ets.proHeaWat.ena.y,bui.ets.proHeaWat.staPum[2].greHys.y,$PRE.bui.ets.proHeaWat.staPum[2].greHys.y,bui.ets.proHeaWat.staPum[1].greHys.y,$PRE.bui.ets.proHeaWat.staPum[1].greHys.y,bui.ets.enaHea.toFalse1.enableFire,bui.ets.enaHea.toTrue1.enableFire,bui.ets.enaHea.initialStep.oldActive,$PRE.bui.ets.enaHea.initialStep.oldActive,bui.ets.enaHea.initialStep.newActive,$PRE.bui.ets.enaHea.initialStep.newActive,bui.ets.enaHea.initialStep.outPort[2].available,bui.ets.enaHea.initialStep.localActive,$PRE.bui.ets.enaHea.initialStep.localActive,bui.ets.enaHea.initialStep.active,bui.ets.enaHea.toFalse.enableFire,bui.ets.enaHea.toFalse.localCondition,bui.ets.enaHea.outputTrue.active,bui.ets.enaHea.outputTrue.oldActive,$PRE.bui.ets.enaHea.outputTrue.oldActive,bui.ets.enaHea.outputTrue.newActive,$PRE.bui.ets.enaHea.outputTrue.newActive,bui.ets.enaHea.outputTrue.inPort[2].occupied,bui.ets.enaHea.outputTrue.localActive,$PRE.bui.ets.enaHea.outputTrue.localActive,bui.ets.enaHea.toTrue.enableFire,bui.ets.enaHea.toTrue.localCondition,bui.ets.enaHea.outputFalse.active,bui.ets.enaHea.outputFalse.oldActive,$PRE.bui.ets.enaHea.outputFalse.oldActive,bui.ets.enaHea.outputFalse.newActive,$PRE.bui.ets.enaHea.outputFalse.newActive,bui.ets.enaHea.outputFalse.inPort[2].occupied,bui.ets.enaHea.outputFalse.localActive,$PRE.bui.ets.enaHea.outputFalse.localActive,bui.ets.enaHea.stateGraphRoot.activeSteps,bui.ets.enaHea.onDel2.y,bui.ets.enaHea.onDel2.u,bui.ets.enaHea.onDel1.y,bui.ets.enaHea.onDel1.u,bui.ets.swiFlo.con.truFalHol.toFalse1.enableFire,bui.ets.swiFlo.con.truFalHol.toTrue1.enableFire,bui.ets.swiFlo.con.truFalHol.initialStep.oldActive,$PRE.bui.ets.swiFlo.con.truFalHol.initialStep.oldActive,bui.ets.swiFlo.con.truFalHol.initialStep.newActive,$PRE.bui.ets.swiFlo.con.truFalHol.initialStep.newActive,bui.ets.swiFlo.con.truFalHol.initialStep.outPort[2].available,bui.ets.swiFlo.con.truFalHol.initialStep.localActive,$PRE.bui.ets.swiFlo.con.truFalHol.initialStep.localActive,bui.ets.swiFlo.con.truFalHol.initialStep.active,bui.ets.swiFlo.con.truFalHol.toFalse.enableFire,bui.ets.swiFlo.con.truFalHol.toFalse.localCondition,bui.ets.swiFlo.con.truFalHol.outputTrue.active,bui.ets.swiFlo.con.truFalHol.outputTrue.oldActive,$PRE.bui.ets.swiFlo.con.truFalHol.outputTrue.oldActive,bui.ets.swiFlo.con.truFalHol.outputTrue.newActive,$PRE.bui.ets.swiFlo.con.truFalHol.outputTrue.newActive,bui.ets.swiFlo.con.truFalHol.outputTrue.inPort[2].occupied,bui.ets.swiFlo.con.truFalHol.outputTrue.localActive,$PRE.bui.ets.swiFlo.con.truFalHol.outputTrue.localActive,bui.ets.swiFlo.con.truFalHol.toTrue.enableFire,bui.ets.swiFlo.con.truFalHol.toTrue.localCondition,bui.ets.swiFlo.con.truFalHol.outputFalse.active,bui.ets.swiFlo.con.truFalHol.outputFalse.oldActive,$PRE.bui.ets.swiFlo.con.truFalHol.outputFalse.oldActive,bui.ets.swiFlo.con.truFalHol.outputFalse.newActive,$PRE.bui.ets.swiFlo.con.truFalHol.outputFalse.newActive,bui.ets.swiFlo.con.truFalHol.outputFalse.inPort[2].occupied,bui.ets.swiFlo.con.truFalHol.outputFalse.localActive,$PRE.bui.ets.swiFlo.con.truFalHol.outputFalse.localActive,bui.ets.swiFlo.con.truFalHol.stateGraphRoot.activeSteps,bui.ets.swiFlo.con.truFalHol.onDel2.y,bui.ets.swiFlo.con.truFalHol.onDel2.u,bui.ets.swiFlo.con.truFalHol.onDel1.y,bui.ets.swiFlo.con.truFalHol.onDel1.u,bui.ets.swiFlo.con.truFalHol.u,bui.bui.terUniCoo.greThr.greHys.y,$PRE.bui.bui.terUniCoo.greThr.greHys.y,bui.bui.terUniHea.greThr.greHys.y,$PRE.bui.bui.terUniHea.greThr.greHys.y,bui.bui.loa.nextTimeEventScaled,$PRE.bui.bui.loa.nextTimeEventScaled,bui.bui.loa.nextTimeEvent,$whenCondition4,$whenCondition3,$whenCondition7,$whenCondition6,$whenCondition10,$whenCondition9,$whenCondition13,$whenCondition12,$whenCondition16,$whenCondition15,$whenCondition19,$whenCondition18,$whenCondition20,$whenCondition21,$whenCondition22)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1617):
 * Single equations (assignments): 1587
 * Array equations: 10
 * Algorithm blocks: 0
 * Record equations: 8
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 3
 * Torn equation systems: 9
 * 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): 3 systems
   {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): 3 systems
   {(4,8,93.8%), (1,4,100.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 6 systems
   {(5,51), (1,34), (1,4), (1,2), (1,60), (5,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.006738/9.181, allocations: 1.244 MB / 3.782 GB, free: 0.7052 GB / 2.17 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.009888/9.191, allocations: 2.251 MB / 3.785 GB, free: 0.703 GB / 2.17 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.06674/9.257, allocations: 17.83 MB / 3.802 GB, free: 0.6857 GB / 2.17 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002868/9.258, allocations: 99.97 kB / 3.802 GB, free: 0.6856 GB / 2.17 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.443e-05/9.258, allocations: 11.94 kB / 3.802 GB, free: 0.6855 GB / 2.17 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.05367/9.311, allocations: 14.17 MB / 3.816 GB, free: 0.6716 GB / 2.17 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.1272/9.439, allocations: 42.05 MB / 3.857 GB, free: 0.6305 GB / 2.17 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001558/9.439, allocations: 12 kB / 3.857 GB, free: 0.6304 GB / 2.17 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.008312/9.447, allocations: 1.319 MB / 3.858 GB, free: 0.6292 GB / 2.17 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01838/9.466, allocations: 4.759 MB / 3.863 GB, free: 0.6245 GB / 2.17 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.002896/9.468, allocations: 263.7 kB / 3.863 GB, free: 0.6242 GB / 2.17 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02506/9.494, allocations: 18.71 MB / 3.882 GB, free: 0.6051 GB / 2.17 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.899e-06/9.494, allocations: 23.88 kB / 3.882 GB, free: 0.6051 GB / 2.17 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.08138/9.575, allocations: 26.26 MB / 3.907 GB, free: 0.5792 GB / 2.17 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01499/9.59, allocations: 4.55 MB / 3.912 GB, free: 0.5747 GB / 2.17 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.008924/9.599, allocations: 224 kB / 3.912 GB, free: 0.5745 GB / 2.17 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0199/9.619, allocations: 0.8249 MB / 3.913 GB, free: 0.5737 GB / 2.17 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002868/9.622, allocations: 0.685 MB / 3.913 GB, free: 0.573 GB / 2.17 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.002962/9.625, allocations: 407.7 kB / 3.914 GB, free: 0.5726 GB / 2.17 GB
Notification: Performance of sorting global known variables: time 0.04008/9.665, allocations: 15.93 MB / 3.929 GB, free: 0.5571 GB / 2.17 GB
Notification: Performance of sort global known variables: time 3.1e-07/9.665, allocations: 4 kB / 3.929 GB, free: 0.5571 GB / 2.17 GB
Notification: Performance of remove unused functions: time 0.05148/9.716, allocations: 10.79 MB / 3.94 GB, free: 0.5466 GB / 2.17 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 50
 * Number of states: 22 (bui.bui.terUniHea.con.I.y,bui.bui.terUniHea.TLoaODE.TAir,bui.bui.disFloHea.heaCoo.vol.dynBal.U,bui.bui.disFloHea.senTSup.T,bui.bui.disFloCoo.heaCoo.vol.dynBal.U,bui.bui.disFloCoo.senTSup.T,bui.bui.terUniCoo.con.I.y,bui.bui.terUniCoo.hexWetNtu.dryWetCalcs.fullyWet.TWatOutEst,bui.bui.terUniCoo.TLoaODE.TAir,bui.ets.volMix_a.dynBal.U,bui.ets.volMix_b.dynBal.U,bui.ets.volHeaWatRet.dynBal.U,bui.ets.volChiWat.dynBal.U,bui.ets.conTChiWat.conPID.I.y,bui.ets.senTHeaWatSup.T,bui.ets.senTChiWatSup.T,bui.ets.proHeaWat.senTConLvg.T,bui.ets.proHeaWat.senTConEnt.T,bui.ets.proHotWat.senTConLvg.T,bui.ets.proHotWat.senTConEnt.T,bui.ets.senTHeaWatRet.T,bui.ets.senTChiWatRet.T)
 * Number of discrete variables: 140 (bui.enaHeaCoo[2].y,bui.ets.enaSHW.initialStep.localActive,bui.ets.enaSHW.outputTrue.active,bui.ets.enaSHW.outputTrue.localActive,bui.ets.enaSHW.onDel2.u,bui.ets.enaSHW.outputFalse.active,bui.ets.enaSHW.outputFalse.localActive,bui.ets.enaSHW.onDel1.u,bui.ets.enaHea.initialStep.localActive,bui.ets.enaHea.outputTrue.active,bui.ets.enaHea.outputTrue.localActive,bui.ets.enaHea.onDel2.u,bui.ets.enaHea.outputFalse.active,bui.ets.enaHea.outputFalse.localActive,bui.ets.enaHea.onDel1.u,bui.ets.swiFlo.con.truFalHol.initialStep.localActive,bui.ets.swiFlo.con.truFalHol.outputTrue.active,bui.ets.swiFlo.con.truFalHol.outputTrue.localActive,bui.ets.swiFlo.con.truFalHol.onDel2.u,bui.ets.swiFlo.con.truFalHol.outputFalse.active,bui.ets.swiFlo.con.truFalHol.outputFalse.localActive,bui.ets.swiFlo.con.truFalHol.onDel1.u,$cse96,$cse97,$cse100,$cse109,$cse110,$cse111,$cse112,$cse113,$cse125,$cse126,$cse127,$cse128,$cse129,$whenCondition22,$whenCondition21,$whenCondition20,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition14,$whenCondition15,$whenCondition16,$whenCondition11,$whenCondition12,$whenCondition13,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition1,bui.bui.loa.nextTimeEvent,bui.bui.loa.nextTimeEventScaled,bui.bui.terUniHea.greThr.greHys.y,bui.bui.terUniCoo.greThr.greHys.y,bui.ets.swiFlo.con.truFalHol.u,bui.ets.swiFlo.con.truFalHol.onDel1.y,bui.ets.swiFlo.con.truFalHol.onDel1.t_next,bui.ets.swiFlo.con.truFalHol.onDel2.y,bui.ets.swiFlo.con.truFalHol.onDel2.t_next,bui.ets.swiFlo.con.truFalHol.stateGraphRoot.activeSteps,bui.ets.swiFlo.con.truFalHol.outputFalse.inPort[2].occupied,bui.ets.swiFlo.con.truFalHol.outputFalse.newActive,bui.ets.swiFlo.con.truFalHol.outputFalse.oldActive,bui.ets.swiFlo.con.truFalHol.toTrue.localCondition,bui.ets.swiFlo.con.truFalHol.toTrue.enableFire,bui.ets.swiFlo.con.truFalHol.outputTrue.inPort[2].occupied,bui.ets.swiFlo.con.truFalHol.outputTrue.newActive,bui.ets.swiFlo.con.truFalHol.outputTrue.oldActive,bui.ets.swiFlo.con.truFalHol.toFalse.localCondition,bui.ets.swiFlo.con.truFalHol.toFalse.enableFire,bui.ets.swiFlo.con.truFalHol.initialStep.active,bui.ets.swiFlo.con.truFalHol.initialStep.outPort[2].available,bui.ets.swiFlo.con.truFalHol.initialStep.newActive,bui.ets.swiFlo.con.truFalHol.initialStep.oldActive,bui.ets.swiFlo.con.truFalHol.toTrue1.enableFire,bui.ets.swiFlo.con.truFalHol.toFalse1.enableFire,bui.ets.enaHea.onDel1.y,bui.ets.enaHea.onDel1.t_next,bui.ets.enaHea.onDel2.y,bui.ets.enaHea.onDel2.t_next,bui.ets.enaHea.stateGraphRoot.activeSteps,bui.ets.enaHea.outputFalse.inPort[2].occupied,bui.ets.enaHea.outputFalse.newActive,bui.ets.enaHea.outputFalse.oldActive,bui.ets.enaHea.toTrue.localCondition,bui.ets.enaHea.toTrue.enableFire,bui.ets.enaHea.outputTrue.inPort[2].occupied,bui.ets.enaHea.outputTrue.newActive,bui.ets.enaHea.outputTrue.oldActive,bui.ets.enaHea.toFalse.localCondition,bui.ets.enaHea.toFalse.enableFire,bui.ets.enaHea.initialStep.active,bui.ets.enaHea.initialStep.outPort[2].available,bui.ets.enaHea.initialStep.newActive,bui.ets.enaHea.initialStep.oldActive,bui.ets.enaHea.toTrue1.enableFire,bui.ets.enaHea.toFalse1.enableFire,bui.ets.proHeaWat.staPum[1].greHys.y,bui.ets.proHeaWat.staPum[2].greHys.y,bui.ets.proHeaWat.ena.y,bui.ets.proHotWat.staPum[1].greHys.y,bui.ets.proHotWat.staPum[2].greHys.y,bui.ets.proHotWat.ena.y,bui.ets.enaSHW.onDel1.y,bui.ets.enaSHW.onDel1.t_next,bui.ets.enaSHW.onDel2.y,bui.ets.enaSHW.onDel2.t_next,bui.ets.enaSHW.stateGraphRoot.activeSteps,bui.ets.enaSHW.outputFalse.inPort[2].occupied,bui.ets.enaSHW.outputFalse.newActive,bui.ets.enaSHW.outputFalse.oldActive,bui.ets.enaSHW.toTrue.enableFire,bui.ets.enaSHW.outputTrue.inPort[2].occupied,bui.ets.enaSHW.outputTrue.newActive,bui.ets.enaSHW.outputTrue.oldActive,bui.ets.enaSHW.initialStep.active,bui.ets.enaSHW.initialStep.outPort[2].available,bui.ets.enaSHW.initialStep.newActive,bui.ets.enaSHW.initialStep.oldActive,bui.ets.enaSHW.toTrue1.enableFire,bui.enaHeaCoo[1].y,bui.ets.swiFlo.con.truFalHol.toTrue.t_start,bui.ets.swiFlo.con.truFalHol.toFalse.t_start,bui.ets.swiFlo.con.truFalHol.toTrue1.t_start,bui.ets.swiFlo.con.truFalHol.toFalse1.t_start,bui.ets.enaHea.toTrue.t_start,bui.ets.enaHea.toFalse.t_start,bui.ets.enaHea.toTrue1.t_start,bui.ets.enaHea.toFalse1.t_start,bui.ets.enaSHW.toTrue.t_start,bui.ets.enaSHW.toFalse.t_start,bui.ets.enaSHW.toTrue1.t_start,bui.ets.enaSHW.toFalse1.t_start)
 * Number of discrete states: 35 (bui.ets.enaSHW.toFalse1.t_start,bui.ets.enaSHW.toTrue1.t_start,bui.ets.enaSHW.toFalse.t_start,bui.ets.enaSHW.toTrue.t_start,bui.ets.enaHea.toFalse1.t_start,bui.ets.enaHea.toTrue1.t_start,bui.ets.enaHea.toFalse.t_start,bui.ets.enaHea.toTrue.t_start,bui.ets.swiFlo.con.truFalHol.toFalse1.t_start,bui.ets.swiFlo.con.truFalHol.toTrue1.t_start,bui.ets.swiFlo.con.truFalHol.toFalse.t_start,bui.ets.swiFlo.con.truFalHol.toTrue.t_start,bui.ets.enaSHW.onDel2.t_next,bui.ets.enaSHW.onDel1.t_next,bui.ets.enaHea.onDel2.t_next,bui.ets.enaHea.onDel1.t_next,bui.ets.swiFlo.con.truFalHol.onDel2.t_next,bui.ets.swiFlo.con.truFalHol.onDel1.t_next,bui.bui.loa.nextTimeEvent,bui.ets.swiFlo.con.truFalHol.outputFalse.newActive,bui.ets.swiFlo.con.truFalHol.outputTrue.newActive,bui.ets.swiFlo.con.truFalHol.initialStep.newActive,bui.ets.enaHea.outputFalse.newActive,bui.ets.enaHea.outputTrue.newActive,bui.ets.enaHea.initialStep.newActive,bui.ets.proHotWat.staPum[1].greHys.y,bui.ets.enaSHW.outputFalse.newActive,bui.ets.enaSHW.outputTrue.newActive,bui.ets.enaSHW.initialStep.newActive,bui.bui.loa.nextTimeEventScaled,bui.bui.terUniHea.greThr.greHys.y,bui.ets.proHeaWat.staPum[1].greHys.y,bui.ets.proHeaWat.staPum[2].greHys.y,bui.ets.proHotWat.staPum[2].greHys.y,bui.bui.terUniCoo.greThr.greHys.y)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (713):
 * Single equations (assignments): 635
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 32
 * When equations: 35
 * If-equations: 0
 * Equation systems (not torn): 2
 * Torn equation systems: 9
 * 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): 2 systems
   {1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 3 systems
   {(5,7,84.0%), (1,4,100.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 6 systems
   {(1,4), (1,2), (1,56), (1,1), (4,58), (1,36)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0107/9.727, allocations: 3.974 MB / 3.944 GB, free: 0.5431 GB / 2.17 GB
Notification: Performance of simCode: created initialization part: time 0.07518/9.802, allocations: 29.66 MB / 3.973 GB, free: 0.5141 GB / 2.17 GB
Notification: Performance of simCode: created event and clocks part: time 3.332e-05/9.802, allocations: 6.656 kB / 3.973 GB, free: 0.5141 GB / 2.17 GB
Notification: Performance of simCode: created simulation system equations: time 0.02189/9.824, allocations: 8.09 MB / 3.981 GB, free: 0.5061 GB / 2.17 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0619/9.886, allocations: 11.38 MB / 3.992 GB, free: 0.4954 GB / 2.17 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1113/9.997, allocations: 45.94 MB / 4.037 GB, free: 461.1 MB / 2.17 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.02281/10.02, allocations: 10.85 MB / 4.047 GB, free: 450.2 MB / 2.17 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00686/10.03, allocations: 1.589 MB / 4.049 GB, free: 448.6 MB / 2.17 GB
Notification: Performance of SimCode: time 1.252e-06/10.03, allocations: 0 / 4.049 GB, free: 448.6 MB / 2.17 GB
Notification: Performance of Templates: time 4.336/14.36, allocations: 2.132 GB / 6.181 GB, free: 0.501 GB / 2.17 GB
"
[Timeout remaining time 286]
make -j1 -f Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS.makefile [Timeout 300]
(rm -f Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS.pipe ; mkfifo Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS.pipe ; head -c 1048576 < Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS.pipe >> ../files/Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS.sim & ./Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_10_Buildings.Experimental.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETS.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 108.88334098807536]
Failed to read output from testmodel.py, exit status != 0:
14.37437820318155 14.382776972 7.630345933
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fbc77906050>