Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.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.001672/0.001673, allocations: 112.1 kB / 17.08 MB, free: 6.012 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.002032/0.002032, allocations: 193.2 kB / 20.03 MB, free: 3.082 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.518/1.518, allocations: 222.9 MB / 245.7 MB, free: 4.219 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo): time 3.14/3.14, allocations: 489.3 MB / 0.7687 GB, free: 3.836 MB / 0.6075 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|bui.ets.proHeaWat.heaPum.QCon_flow_nominal|bui.ets.proHeaWat.heaPum.QCon_flow|bui.bui.QReqHea_flow|bui.bui.QReqCoo_flow|bui.bui.QHea_flow|bui.bui.QCoo_flow|bui.PHea|bui.PPum|bui.ets.proHeaWat.senTDisSup.T|bui.ets.proHeaWat.senTEvaRet.T|bui.ets.QReqHotWat_flow|bui.ets.THotWatSupSet|bui.ets.theMixVal.TMixSet|bui.ets.proHotWat.THotSouSet.y|bui.ets.theMixVal.senTHot.T|bui.ets.proHotWat.heaPumTan.conCha.TTanTopSet|bui.ets.proHotWat.heaPumTan.conCha.TTanTop|bui.ets.proHotWat.heaPumTan.conCha.TTanBot|bui.ets.proHotWat.PHea|bui.ets.proHotWat.PPum|bui.ets.proHotWat.senTDisSup.T|bui.ets.proHotWat.senTEvaRet.T",fileNamePrefix="Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank")
translateModel(Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|bui.ets.proHeaWat.heaPum.QCon_flow_nominal|bui.ets.proHeaWat.heaPum.QCon_flow|bui.bui.QReqHea_flow|bui.bui.QReqCoo_flow|bui.bui.QHea_flow|bui.bui.QCoo_flow|bui.PHea|bui.PPum|bui.ets.proHeaWat.senTDisSup.T|bui.ets.proHeaWat.senTEvaRet.T|bui.ets.QReqHotWat_flow|bui.ets.THotWatSupSet|bui.ets.theMixVal.TMixSet|bui.ets.proHotWat.THotSouSet.y|bui.ets.theMixVal.senTHot.T|bui.ets.proHotWat.heaPumTan.conCha.TTanTopSet|bui.ets.proHotWat.heaPumTan.conCha.TTanTop|bui.ets.proHotWat.heaPumTan.conCha.TTanBot|bui.ets.proHotWat.PHea|bui.ets.proHotWat.PPum|bui.ets.proHotWat.senTDisSup.T|bui.ets.proHotWat.senTEvaRet.T",fileNamePrefix="Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.002601/0.002601, allocations: 63.89 kB / 1.103 GB, free: 0.8711 MB / 0.8419 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.242/1.245, allocations: 163.8 MB / 1.263 GB, free: 8.012 MB / 0.9981 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank): time 4.42/5.665, allocations: 1.687 GB / 2.95 GB, free: 62.15 MB / 1.826 GB
Notification: Performance of NFInst.instExpressions: time 0.6151/6.28, allocations: 354.8 MB / 3.296 GB, free: 2.07 MB / 2.107 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.1014/6.381, allocations: 1.655 MB / 3.298 GB, free: 416 kB / 2.107 GB
Notification: Performance of NFTyping.typeComponents: time 0.1652/6.547, allocations: 31.1 MB / 3.328 GB, free: 4.082 MB / 2.139 GB
Notification: Performance of NFTyping.typeBindings: time 1.789/8.336, allocations: 46.18 MB / 3.373 GB, free: 31.45 MB / 2.154 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1004/8.436, allocations: 36.86 MB / 3.409 GB, free: 31.11 MB / 2.154 GB
Notification: Performance of NFFlatten.flatten: time 0.2199/8.656, allocations: 153.3 MB / 3.559 GB, free: 30.2 MB / 2.154 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.09767/8.754, allocations: 43.98 MB / 3.602 GB, free: 25.18 MB / 2.154 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1031/8.857, allocations: 42.64 MB / 3.644 GB, free: 22.84 MB / 2.154 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.05056/8.908, allocations: 26.74 MB / 3.67 GB, free: 12.34 MB / 2.154 GB
Notification: Performance of NFPackage.collectConstants: time 0.02002/8.928, allocations: 6.145 MB / 3.676 GB, free: 6.191 MB / 2.154 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.06134/8.989, allocations: 22.45 MB / 3.698 GB, free: 8.285 MB / 2.17 GB
Notification: Performance of NFScalarize.scalarize: time 0.02371/9.013, allocations: 13.22 MB / 3.711 GB, free: 2.105 MB / 2.17 GB
Notification: Performance of NFVerifyModel.verify: time 0.06223/9.075, allocations: 25.51 MB / 3.736 GB, free: 13.28 MB / 2.201 GB
Notification: Performance of NFConvertDAE.convert: time 0.1871/9.262, allocations: 92.13 MB / 3.826 GB, free: 3.332 MB / 2.279 GB
Notification: Performance of FrontEnd - DAE generated: time 8.366e-06/9.263, allocations: 4 kB / 3.826 GB, free: 3.328 MB / 2.279 GB
Notification: Performance of FrontEnd: time 1.904e-06/9.263, allocations: 0 / 3.826 GB, free: 3.328 MB / 2.279 GB
Notification: Performance of Transformations before backend: time 0.002432/9.265, allocations: 0 / 3.826 GB, free: 3.328 MB / 2.279 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 5055
 * Number of variables: 5055
Notification: Performance of Generate backend data structure: time 0.1346/9.4, allocations: 43.75 MB / 3.868 GB, free: 7.941 MB / 2.326 GB
Notification: Performance of prepare preOptimizeDAE: time 4.603e-05/9.4, allocations: 16.02 kB / 3.868 GB, free: 7.926 MB / 2.326 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05979/9.459, allocations: 9.426 MB / 3.878 GB, free: 14.48 MB / 2.342 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1484/9.608, allocations: 65.68 MB / 3.942 GB, free: 14.35 MB / 2.404 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002441/9.61, allocations: 1.677 MB / 3.943 GB, free: 12.67 MB / 2.404 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.009347/9.62, allocations: 2.084 MB / 3.945 GB, free: 10.59 MB / 2.404 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.09201/9.712, allocations: 30.14 MB / 3.975 GB, free: 11.67 MB / 2.436 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0009704/9.713, allocations: 55.41 kB / 3.975 GB, free: 11.64 MB / 2.436 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00835/9.721, allocations: 0.8377 MB / 3.976 GB, free: 10.81 MB / 2.436 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007847/9.722, allocations: 0.7155 MB / 3.976 GB, free: 10.1 MB / 2.436 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.08297/9.805, allocations: 26.18 MB / 4.002 GB, free: 144 kB / 2.451 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.305/10.11, allocations: 169.9 MB / 4.168 GB, free: 15.35 MB / 2.639 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.05361/10.16, allocations: 17.53 MB / 4.185 GB, free: 13.83 MB / 2.654 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.02905/10.19, allocations: 10.43 MB / 4.195 GB, free: 3.383 MB / 2.654 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.1122/10.31, allocations: 50.55 MB / 4.245 GB, free: 15.05 MB / 2.717 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.03495/10.34, allocations: 11.45 MB / 4.256 GB, free: 3.52 MB / 2.717 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01765/10.36, allocations: 2.485 MB / 4.258 GB, free: 1.047 MB / 2.717 GB
Notification: Performance of pre-optimization done (n=1030): time 5.49e-05/10.36, allocations: 0 / 4.258 GB, free: 1.047 MB / 2.717 GB
Notification: Performance of matching and sorting (n=1030): time 0.1935/10.55, allocations: 43.07 MB / 4.3 GB, free: 5.781 MB / 2.764 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004301/10.55, allocations: 0.5894 MB / 4.301 GB, free: 5.078 MB / 2.764 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.9371/11.49, allocations: 32.91 MB / 4.333 GB, free: 1.597 GB / 2.779 GB
Notification: Performance of collectPreVariables (initialization): time 0.004615/11.49, allocations: 339.7 kB / 4.333 GB, free: 1.597 GB / 2.779 GB
Notification: Performance of collectInitialEqns (initialization): time 0.02509/11.52, allocations: 16.29 MB / 4.349 GB, free: 1.589 GB / 2.779 GB
Notification: Performance of collectInitialBindings (initialization): time 0.007896/11.53, allocations: 3.746 MB / 4.353 GB, free: 1.587 GB / 2.779 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0241/11.55, allocations: 4.754 MB / 4.357 GB, free: 1.585 GB / 2.779 GB
Notification: Performance of setup shared object (initialization): time 5.87e-05/11.55, allocations: 303.5 kB / 4.358 GB, free: 1.585 GB / 2.779 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.03241/11.58, allocations: 13.98 MB / 4.371 GB, free: 1.579 GB / 2.779 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.04315/11.63, allocations: 20 MB / 4.391 GB, free: 1.566 GB / 2.779 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.072/11.7, allocations: 31.61 MB / 4.422 GB, free: 1.548 GB / 2.779 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000409/11.7, allocations: 39.97 kB / 4.422 GB, free: 1.548 GB / 2.779 GB
Notification: Performance of matching and sorting (n=2623) (initialization): time 0.1161/11.82, allocations: 40.92 MB / 4.462 GB, free: 1.532 GB / 2.779 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001853/11.82, allocations: 63.25 kB / 4.462 GB, free: 1.532 GB / 2.779 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003034/11.82, allocations: 142 kB / 4.462 GB, free: 1.532 GB / 2.779 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02654/11.84, allocations: 7.068 MB / 4.469 GB, free: 1.53 GB / 2.779 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02073/11.86, allocations: 4.071 MB / 4.473 GB, free: 1.53 GB / 2.779 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03901/11.9, allocations: 33.33 MB / 4.505 GB, free: 1.505 GB / 2.779 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03128/11.93, allocations: 1.42 MB / 4.507 GB, free: 1.504 GB / 2.779 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.006479/11.94, allocations: 1.029 MB / 4.508 GB, free: 1.504 GB / 2.779 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0563/12, allocations: 22.17 MB / 4.529 GB, free: 1.493 GB / 2.779 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03201/12.03, allocations: 18.7 MB / 4.548 GB, free: 1.481 GB / 2.779 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05311/12.08, allocations: 29.07 MB / 4.576 GB, free: 1.464 GB / 2.779 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.000234/12.08, allocations: 42.09 kB / 4.576 GB, free: 1.464 GB / 2.779 GB
Notification: Performance of matching and sorting (n=2623) (initialization_lambda0): time 0.106/12.19, allocations: 38.2 MB / 4.613 GB, free: 1.438 GB / 2.779 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001181/12.19, allocations: 65.41 kB / 4.614 GB, free: 1.438 GB / 2.779 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000256/12.19, allocations: 140 kB / 4.614 GB, free: 1.438 GB / 2.779 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02441/12.21, allocations: 6.752 MB / 4.62 GB, free: 1.432 GB / 2.779 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0191/12.23, allocations: 4.031 MB / 4.624 GB, free: 1.429 GB / 2.779 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04479/12.28, allocations: 39.83 MB / 4.663 GB, free: 1.389 GB / 2.779 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03144/12.31, allocations: 1.487 MB / 4.665 GB, free: 1.387 GB / 2.779 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005296/12.31, allocations: 0.9645 MB / 4.666 GB, free: 1.386 GB / 2.779 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 638
 * Number of states: 0 ()
 * Number of discrete variables: 95 ($whenCondition14,$PRE.bui.bui.loa.nextTimeEvent,$whenCondition1,bui.enaSHW.y,bui.ets.theMixVal.conPID.con1.y,bui.bui.terUniCoo.con1.y,bui.bui.terUniHea.con1.y,bui.ets.enaSHW.not_u,$PRE.bui.ets.enaSHW.not_u,$whenCondition11,bui.ets.proHotWat.heaPumTan.conVal.ram.local_reset,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.u,$PRE.bui.ets.enaSHW.u,bui.enaHeaCoo[2].y,bui.ets.proHotWat.and2.y,bui.ets.proHotWat.heaPumTan.conVal.cha.y,$PRE.bui.ets.proHotWat.heaPumTan.conVal.cha.y,bui.ets.proHotWat.heaPumTan.conCha.cha1.y,$PRE.bui.ets.proHotWat.heaPumTan.conCha.cha1.y,bui.ets.proHotWat.heaPumTan.conCha.cha.y,$PRE.bui.ets.proHotWat.heaPumTan.conCha.cha.y,bui.ets.proHotWat.heaPumTan.conCha.lat.y,bui.ets.proHotWat.heaPumTan.conPum.hysMasFlo.y,$PRE.bui.ets.proHotWat.heaPumTan.conPum.hysMasFlo.y,bui.ets.enaSHW.entryTimeFalse,$PRE.bui.ets.enaSHW.entryTimeFalse,bui.ets.enaSHW.entryTimeTrue,$PRE.bui.ets.enaSHW.entryTimeTrue,bui.ets.enaSHW.not_y,$PRE.bui.ets.enaSHW.not_y,bui.ets.enaSHW.y,$PRE.bui.ets.enaSHW.y,bui.ets.enaHea.entryTimeFalse,$PRE.bui.ets.enaHea.entryTimeFalse,bui.ets.enaHea.entryTimeTrue,$PRE.bui.ets.enaHea.entryTimeTrue,bui.ets.enaHea.not_y,$PRE.bui.ets.enaHea.not_y,bui.ets.enaHea.not_u,$PRE.bui.ets.enaHea.not_u,bui.ets.enaHea.y,$PRE.bui.ets.enaHea.y,bui.ets.enaHea.u,$PRE.bui.ets.enaHea.u,bui.ets.swiFlo.con.movAve.mode,$PRE.bui.ets.swiFlo.con.movAve.mode,bui.ets.swiFlo.con.truFalHol.entryTimeFalse,$PRE.bui.ets.swiFlo.con.truFalHol.entryTimeFalse,bui.ets.swiFlo.con.truFalHol.entryTimeTrue,$PRE.bui.ets.swiFlo.con.truFalHol.entryTimeTrue,bui.ets.swiFlo.con.truFalHol.not_y,$PRE.bui.ets.swiFlo.con.truFalHol.not_y,bui.ets.swiFlo.con.truFalHol.not_u,$PRE.bui.ets.swiFlo.con.truFalHol.not_u,bui.ets.swiFlo.con.truFalHol.y,$PRE.bui.ets.swiFlo.con.truFalHol.y,bui.ets.swiFlo.con.truFalHol.u,$PRE.bui.ets.swiFlo.con.truFalHol.u,bui.ets.swiFlo.con.posDom.greHys.y,$PRE.bui.ets.swiFlo.con.posDom.greHys.y,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,$whenCondition3,$whenCondition4,$whenCondition2,$whenCondition5,$whenCondition7,$whenCondition8,$whenCondition6,$whenCondition10,$whenCondition9,$whenCondition12,$whenCondition13,$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 (2063):
 * Single equations (assignments): 2019
 * Array equations: 12
 * Algorithm blocks: 0
 * Record equations: 10
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 7
 * Torn equation systems: 15
 * 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): 7 systems
   {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): 5 systems
   {(1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (4,8,93.8%), (1,10,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 10 systems
   {(1,4), (1,34), (1,4), (1,2), (1,60), (5,2), (1,4), (6,32), (3,16), (2,12)}
Notification: Performance of prepare postOptimizeDAE: time 0.009134/12.32, allocations: 1.323 MB / 4.667 GB, free: 1.385 GB / 2.779 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01145/12.33, allocations: 3.066 MB / 4.67 GB, free: 1.382 GB / 2.779 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.08477/12.42, allocations: 25.3 MB / 4.694 GB, free: 1.358 GB / 2.779 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002598/12.42, allocations: 132 kB / 4.695 GB, free: 1.358 GB / 2.779 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.905e-05/12.42, allocations: 8 kB / 4.695 GB, free: 1.358 GB / 2.779 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.06861/12.49, allocations: 20.01 MB / 4.714 GB, free: 1.338 GB / 2.779 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.1649/12.65, allocations: 60.92 MB / 4.774 GB, free: 1.279 GB / 2.779 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001866/12.65, allocations: 8 kB / 4.774 GB, free: 1.279 GB / 2.779 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01287/12.67, allocations: 2.209 MB / 4.776 GB, free: 1.277 GB / 2.779 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01935/12.69, allocations: 5.064 MB / 4.781 GB, free: 1.272 GB / 2.779 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003286/12.69, allocations: 367.7 kB / 4.781 GB, free: 1.271 GB / 2.779 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0368/12.73, allocations: 31.9 MB / 4.812 GB, free: 1.239 GB / 2.779 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.005e-05/12.73, allocations: 15.94 kB / 4.812 GB, free: 1.239 GB / 2.779 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.101/12.83, allocations: 36.41 MB / 4.848 GB, free: 1.203 GB / 2.779 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02068/12.85, allocations: 6.43 MB / 4.854 GB, free: 1.196 GB / 2.779 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01297/12.86, allocations: 304 kB / 4.854 GB, free: 1.196 GB / 2.779 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02599/12.89, allocations: 1.113 MB / 4.856 GB, free: 1.195 GB / 2.779 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003595/12.89, allocations: 0.8003 MB / 4.856 GB, free: 1.194 GB / 2.779 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.003869/12.89, allocations: 0.562 MB / 4.857 GB, free: 1.194 GB / 2.779 GB
Notification: Performance of sorting global known variables: time 0.05631/12.95, allocations: 22.58 MB / 4.879 GB, free: 1.172 GB / 2.779 GB
Notification: Performance of sort global known variables: time 5.81e-07/12.95, allocations: 0 / 4.879 GB, free: 1.172 GB / 2.779 GB
Notification: Performance of remove unused functions: time 0.0687/13.02, allocations: 15.18 MB / 4.894 GB, free: 1.157 GB / 2.779 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 40
 * Number of states: 44 (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.swiFlo.con.movAve.mu,bui.ets.proHeaWat.heaPum.con.outCon.T,bui.ets.proHeaWat.heaPum.eva.vol.dynBal.U,bui.ets.proHeaWat.pumCon.vol.dynBal.U,bui.ets.proHeaWat.valHeaPumEva.actPos.y,bui.ets.proHeaWat.conPI.I.y,bui.ets.senTHeaWatRet.T,bui.ets.senTChiWatRet.T,bui.ets.proHotWat.heaPum.con.outCon.T,bui.ets.proHotWat.heaPum.eva.vol.dynBal.U,bui.ets.proHotWat.pumCon.vol.dynBal.U,bui.ets.proHotWat.pumCon.motSpe.y,bui.ets.proHotWat.pumEva.motSpe.y,bui.ets.proHotWat.valHeaPumEva.actPos.y,bui.ets.proHotWat.conPI.I.y,bui.ets.proHotWat.heaPumTan.tan.vol[1].dynBal.U,bui.ets.proHotWat.heaPumTan.tan.vol[2].dynBal.U,bui.ets.proHotWat.heaPumTan.tan.vol[3].dynBal.U,bui.ets.proHotWat.heaPumTan.tan.vol[4].dynBal.U,bui.ets.proHotWat.heaPumTan.tan.vol[5].dynBal.U,bui.ets.proHotWat.heaPumTan.senTemHot.T,bui.ets.proHotWat.heaPumTan.conPum.conPI.I.y,bui.ets.proHotWat.heaPumTan.senTemRet.T,bui.ets.proHotWat.heaPumTan.conVal.ram.y,bui.ets.theMixVal.conPID.I.y,bui.ets.theMixVal.jun.vol.dynBal.U,bui.ets.theMixVal.eneMetReq.EInt,bui.ets.theMixVal.eneMetAct.EInt)
 * Number of discrete variables: 49 (bui.enaHeaCoo[2].y,bui.ets.proHotWat.and2.y,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition9,$whenCondition11,$whenCondition10,$whenCondition6,$whenCondition8,$whenCondition7,$whenCondition5,$whenCondition2,$whenCondition4,$whenCondition3,$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.posDom.greHys.y,bui.ets.swiFlo.con.truFalHol.u,bui.ets.swiFlo.con.truFalHol.y,bui.ets.swiFlo.con.truFalHol.not_u,bui.ets.swiFlo.con.truFalHol.not_y,bui.ets.swiFlo.con.truFalHol.entryTimeTrue,bui.ets.swiFlo.con.truFalHol.entryTimeFalse,bui.ets.swiFlo.con.movAve.mode,bui.ets.enaHea.u,bui.ets.enaHea.y,bui.ets.enaHea.not_u,bui.ets.enaHea.not_y,bui.ets.enaHea.entryTimeTrue,bui.ets.enaHea.entryTimeFalse,bui.ets.enaSHW.y,bui.ets.enaSHW.not_y,bui.ets.enaSHW.entryTimeTrue,bui.ets.enaSHW.entryTimeFalse,bui.ets.proHotWat.heaPumTan.conPum.hysMasFlo.y,bui.ets.proHotWat.heaPumTan.conCha.lat.y,bui.ets.proHotWat.heaPumTan.conCha.cha.y,bui.ets.proHotWat.heaPumTan.conCha.cha1.y,bui.ets.proHotWat.heaPumTan.conVal.cha.y)
 * Number of discrete states: 25 (bui.ets.enaSHW.entryTimeFalse,bui.ets.enaSHW.entryTimeTrue,bui.ets.enaHea.entryTimeFalse,bui.ets.enaHea.entryTimeTrue,bui.ets.swiFlo.con.truFalHol.entryTimeFalse,bui.ets.swiFlo.con.truFalHol.entryTimeTrue,bui.bui.loa.nextTimeEvent,bui.ets.proHotWat.heaPumTan.conVal.cha.y,bui.ets.enaSHW.y,bui.ets.enaSHW.not_y,bui.ets.swiFlo.con.posDom.greHys.y,bui.ets.swiFlo.con.truFalHol.u,bui.ets.swiFlo.con.truFalHol.not_u,bui.ets.swiFlo.con.truFalHol.y,bui.ets.swiFlo.con.truFalHol.not_y,bui.bui.loa.nextTimeEventScaled,bui.ets.proHotWat.heaPumTan.conPum.hysMasFlo.y,bui.ets.proHotWat.heaPumTan.conCha.cha.y,bui.ets.proHotWat.heaPumTan.conCha.cha1.y,bui.ets.enaHea.u,bui.ets.enaHea.not_u,bui.ets.enaHea.y,bui.ets.enaHea.not_y,bui.bui.terUniHea.greThr.greHys.y,bui.bui.terUniCoo.greThr.greHys.y)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (970):
 * Single equations (assignments): 888
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 48
 * When equations: 13
 * If-equations: 0
 * Equation systems (not torn): 6
 * Torn equation systems: 15
 * 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): 6 systems
   {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): 5 systems
   {(1,10,100.0%), (5,7,88.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 10 systems
   {(2,23), (1,4), (2,12), (1,4), (6,34), (1,4), (1,2), (1,56), (1,1), (1,36)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01361/13.03, allocations: 5.053 MB / 4.899 GB, free: 1.153 GB / 2.779 GB
Notification: Performance of simCode: created initialization part: time 0.1138/13.15, allocations: 46.74 MB / 4.944 GB, free: 1.107 GB / 2.779 GB
Notification: Performance of simCode: created event and clocks part: time 2.458e-05/13.15, allocations: 11.38 kB / 4.944 GB, free: 1.107 GB / 2.779 GB
Notification: Performance of simCode: created simulation system equations: time 0.03022/13.18, allocations: 12.25 MB / 4.956 GB, free: 1.095 GB / 2.779 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.08866/13.27, allocations: 16.41 MB / 4.972 GB, free: 1.079 GB / 2.779 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.156/13.42, allocations: 65.6 MB / 5.036 GB, free: 1.015 GB / 2.779 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.03579/13.46, allocations: 15.22 MB / 5.051 GB, free: 1 GB / 2.779 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01264/13.47, allocations: 2.225 MB / 5.053 GB, free: 0.998 GB / 2.779 GB
Notification: Performance of SimCode: time 1.383e-06/13.47, allocations: 0 / 5.053 GB, free: 0.998 GB / 2.779 GB
Notification: Performance of Templates: time 8.062/21.53, allocations: 5.143 GB / 10.2 GB, free: 0.719 GB / 2.78 GB
"
[Timeout remaining time 278]
make -j1 -f Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe ; mkfifo Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe ; head -c 1048576 < Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe >> ../files/Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.sim & ./Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe 2>&1) [Timeout 800]
[Calling os._exit(0), Time elapsed: 158.21994221908972]
Failed to read output from testmodel.py, exit status != 0:
21.541019016876817 21.548524285 12.284050272
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f7d67baa050>