Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.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.001754/0.001754, allocations: 123.1 kB / 17.13 MB, free: 5.941 MB / 14.72 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.002325/0.002325, allocations: 213.8 kB / 20.1 MB, free: 2.992 MB / 14.72 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.525/1.525, allocations: 230.6 MB / 253.5 MB, free: 15.11 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 2.938/2.938, allocations: 0.49 GB / 0.7923 GB, free: 1.34 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.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.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_12_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_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank") [Timeout 300]
"Notification: Buildings requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002662/0.002662, allocations: 63.91 kB / 1.15 GB, free: 14.95 MB / 0.9356 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.138/1.14, allocations: 176.1 MB / 1.322 GB, free: 25.07 MB / 1.061 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank): time 4.244/5.384, allocations: 1.706 GB / 3.028 GB, free: 71.09 MB / 1.889 GB
Notification: Performance of NFInst.instExpressions: time 0.5659/5.95, allocations: 356.1 MB / 3.376 GB, free: 11.7 MB / 2.17 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.08312/6.033, allocations: 2.263 MB / 3.378 GB, free: 9.422 MB / 2.17 GB
Notification: Performance of NFTyping.typeComponents: time 0.1646/6.198, allocations: 31.77 MB / 3.409 GB, free: 12.2 MB / 2.201 GB
Notification: Performance of NFTyping.typeBindings: time 1.818/8.016, allocations: 48.88 MB / 3.457 GB, free: 43.56 MB / 2.232 GB
Notification: Performance of NFTyping.typeClassSections: time 0.09456/8.111, allocations: 38.15 MB / 3.494 GB, free: 43.24 MB / 2.232 GB
Notification: Performance of NFFlatten.flatten: time 0.2101/8.321, allocations: 153.5 MB / 3.644 GB, free: 42.43 MB / 2.232 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.105/8.426, allocations: 33.1 MB / 3.676 GB, free: 40.37 MB / 2.232 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1125/8.538, allocations: 44.35 MB / 3.719 GB, free: 38.36 MB / 2.232 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0568/8.595, allocations: 27.91 MB / 3.747 GB, free: 33.87 MB / 2.232 GB
Notification: Performance of NFPackage.collectConstants: time 0.0225/8.617, allocations: 6.157 MB / 3.753 GB, free: 33.87 MB / 2.232 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.06532/8.683, allocations: 23.42 MB / 3.776 GB, free: 26.71 MB / 2.232 GB
Notification: Performance of NFScalarize.scalarize: time 0.02324/8.706, allocations: 13.55 MB / 3.789 GB, free: 22.37 MB / 2.232 GB
Notification: Performance of NFVerifyModel.verify: time 0.05993/8.766, allocations: 25.57 MB / 3.814 GB, free: 10.23 MB / 2.232 GB
Notification: Performance of NFConvertDAE.convert: time 0.1678/8.934, allocations: 92.89 MB / 3.904 GB, free: 6.887 MB / 2.311 GB
Notification: Performance of FrontEnd - DAE generated: time 1.044e-05/8.934, allocations: 3.984 kB / 3.904 GB, free: 6.883 MB / 2.311 GB
Notification: Performance of FrontEnd: time 1.743e-06/8.934, allocations: 0 / 3.904 GB, free: 6.883 MB / 2.311 GB
Notification: Performance of Transformations before backend: time 0.002186/8.936, allocations: 0 / 3.904 GB, free: 6.883 MB / 2.311 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.1518/9.088, allocations: 43.62 MB / 3.947 GB, free: 11.63 MB / 2.357 GB
Notification: Performance of prepare preOptimizeDAE: time 5.54e-05/9.088, allocations: 12.03 kB / 3.947 GB, free: 11.62 MB / 2.357 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06337/9.151, allocations: 9.5 MB / 3.956 GB, free: 2.094 MB / 2.357 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1496/9.301, allocations: 66.17 MB / 4.021 GB, free: 1.473 MB / 2.42 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002614/9.303, allocations: 1.673 MB / 4.023 GB, free: 15.79 MB / 2.436 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.009959/9.313, allocations: 2.087 MB / 4.025 GB, free: 13.71 MB / 2.436 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1009/9.414, allocations: 30.2 MB / 4.054 GB, free: 14.74 MB / 2.467 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.001117/9.415, allocations: 54.67 kB / 4.054 GB, free: 14.71 MB / 2.467 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00899/9.424, allocations: 0.8356 MB / 4.055 GB, free: 13.88 MB / 2.467 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0008623/9.425, allocations: 0.7222 MB / 4.056 GB, free: 13.16 MB / 2.467 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.08968/9.515, allocations: 26.26 MB / 4.081 GB, free: 3.121 MB / 2.482 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.331/9.846, allocations: 170 MB / 4.247 GB, free: 2.402 MB / 2.654 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.04738/9.893, allocations: 16.76 MB / 4.264 GB, free: 1.652 MB / 2.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.02796/9.921, allocations: 9.848 MB / 4.273 GB, free: 7.793 MB / 2.686 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.1205/10.04, allocations: 50.62 MB / 4.323 GB, free: 3.449 MB / 2.732 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.03525/10.08, allocations: 11.49 MB / 4.334 GB, free: 7.883 MB / 2.748 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02179/10.1, allocations: 2.728 MB / 4.337 GB, free: 5.168 MB / 2.748 GB
Notification: Performance of pre-optimization done (n=1030): time 6.432e-05/10.1, allocations: 0 / 4.337 GB, free: 5.168 MB / 2.748 GB
Notification: Performance of matching and sorting (n=1030): time 0.1967/10.3, allocations: 42.94 MB / 4.379 GB, free: 10.04 MB / 2.795 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004181/10.3, allocations: 0.5926 MB / 4.379 GB, free: 9.328 MB / 2.795 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.09803/10.39, allocations: 33.05 MB / 4.411 GB, free: 8.516 MB / 2.826 GB
Notification: Performance of collectPreVariables (initialization): time 0.004795/10.4, allocations: 335.9 kB / 4.412 GB, free: 8.184 MB / 2.826 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03192/10.43, allocations: 16.37 MB / 4.428 GB, free: 7.949 MB / 2.842 GB
Notification: Performance of collectInitialBindings (initialization): time 0.008843/10.44, allocations: 3.748 MB / 4.431 GB, free: 4.254 MB / 2.842 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02249/10.46, allocations: 4.751 MB / 4.436 GB, free: 15.49 MB / 2.857 GB
Notification: Performance of setup shared object (initialization): time 0.0001518/10.46, allocations: 305.1 kB / 4.436 GB, free: 15.19 MB / 2.857 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.03647/10.5, allocations: 13.99 MB / 4.45 GB, free: 1.207 MB / 2.857 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 1.778/12.28, allocations: 19.99 MB / 4.469 GB, free: 0.5466 GB / 2.857 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.07373/12.35, allocations: 31.61 MB / 4.5 GB, free: 0.5414 GB / 2.857 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0004917/12.35, allocations: 39.97 kB / 4.5 GB, free: 0.5414 GB / 2.857 GB
Notification: Performance of matching and sorting (n=2623) (initialization): time 0.1363/12.49, allocations: 40.83 MB / 4.54 GB, free: 0.5409 GB / 2.857 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000281/12.49, allocations: 66.25 kB / 4.54 GB, free: 0.5409 GB / 2.857 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003591/12.49, allocations: 140.7 kB / 4.54 GB, free: 0.5409 GB / 2.857 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02936/12.52, allocations: 7.069 MB / 4.547 GB, free: 0.5405 GB / 2.857 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02262/12.54, allocations: 4.078 MB / 4.551 GB, free: 0.5405 GB / 2.857 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04374/12.58, allocations: 33.35 MB / 4.584 GB, free: 0.523 GB / 2.857 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03301/12.62, allocations: 1.422 MB / 4.585 GB, free: 0.523 GB / 2.857 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00652/12.62, allocations: 1.037 MB / 4.586 GB, free: 0.523 GB / 2.857 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.05406/12.68, allocations: 22.2 MB / 4.608 GB, free: 0.5229 GB / 2.857 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03703/12.71, allocations: 18.7 MB / 4.626 GB, free: 0.5163 GB / 2.857 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.06219/12.78, allocations: 29.06 MB / 4.655 GB, free: 0.5106 GB / 2.857 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0003404/12.78, allocations: 40.38 kB / 4.655 GB, free: 0.5106 GB / 2.857 GB
Notification: Performance of matching and sorting (n=2623) (initialization_lambda0): time 0.1159/12.89, allocations: 38.14 MB / 4.692 GB, free: 0.5097 GB / 2.857 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001801/12.89, allocations: 65.84 kB / 4.692 GB, free: 0.5097 GB / 2.857 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003051/12.89, allocations: 143.2 kB / 4.692 GB, free: 0.5097 GB / 2.857 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02656/12.92, allocations: 6.749 MB / 4.699 GB, free: 0.5091 GB / 2.857 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02049/12.94, allocations: 4.029 MB / 4.703 GB, free: 0.5091 GB / 2.857 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05242/12.99, allocations: 39.9 MB / 4.742 GB, free: 0.4925 GB / 2.857 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03361/13.03, allocations: 1.488 MB / 4.743 GB, free: 0.4925 GB / 2.857 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.006566/13.03, allocations: 0.9676 MB / 4.744 GB, free: 0.4925 GB / 2.857 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,$whenCondition10,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,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition11,$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.01149/13.04, allocations: 1.318 MB / 4.745 GB, free: 0.4923 GB / 2.857 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01356/13.06, allocations: 3.067 MB / 4.748 GB, free: 0.4909 GB / 2.857 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.09914/13.16, allocations: 25.2 MB / 4.773 GB, free: 0.4896 GB / 2.857 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002873/13.16, allocations: 134 kB / 4.773 GB, free: 0.4896 GB / 2.857 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.779e-05/13.16, allocations: 7.406 kB / 4.773 GB, free: 0.4896 GB / 2.857 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.07177/13.23, allocations: 19.88 MB / 4.792 GB, free: 0.4887 GB / 2.857 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.1918/13.42, allocations: 60.78 MB / 4.852 GB, free: 476.3 MB / 2.857 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001849/13.42, allocations: 8.188 kB / 4.852 GB, free: 476.3 MB / 2.857 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0137/13.43, allocations: 2.204 MB / 4.854 GB, free: 474.6 MB / 2.857 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0201/13.45, allocations: 5.061 MB / 4.859 GB, free: 470.4 MB / 2.857 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003579/13.46, allocations: 357 kB / 4.859 GB, free: 470.1 MB / 2.857 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03757/13.5, allocations: 31.94 MB / 4.89 GB, free: 438.6 MB / 2.857 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.224e-05/13.5, allocations: 15.38 kB / 4.89 GB, free: 438.6 MB / 2.857 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.1033/13.6, allocations: 36.27 MB / 4.926 GB, free: 405.8 MB / 2.857 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02007/13.62, allocations: 6.439 MB / 4.932 GB, free: 400.1 MB / 2.857 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0126/13.63, allocations: 299.7 kB / 4.932 GB, free: 399.8 MB / 2.857 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02705/13.66, allocations: 1.102 MB / 4.934 GB, free: 399.1 MB / 2.857 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003706/13.66, allocations: 0.8107 MB / 4.934 GB, free: 398.5 MB / 2.857 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.003818/13.67, allocations: 0.553 MB / 4.935 GB, free: 398.1 MB / 2.857 GB
Notification: Performance of sorting global known variables: time 0.05741/13.72, allocations: 22.67 MB / 4.957 GB, free: 378.8 MB / 2.857 GB
Notification: Performance of sort global known variables: time 2.4e-07/13.72, allocations: 0 / 4.957 GB, free: 378.8 MB / 2.857 GB
Notification: Performance of remove unused functions: time 0.07001/13.79, allocations: 15.22 MB / 4.972 GB, free: 364.8 MB / 2.857 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,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$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.01462/13.81, allocations: 5.042 MB / 4.977 GB, free: 361.2 MB / 2.857 GB
Notification: Performance of simCode: created initialization part: time 0.1073/13.92, allocations: 44.32 MB / 5.02 GB, free: 322.4 MB / 2.857 GB
Notification: Performance of simCode: created event and clocks part: time 2.557e-05/13.92, allocations: 11.38 kB / 5.02 GB, free: 322.4 MB / 2.857 GB
Notification: Performance of simCode: created simulation system equations: time 0.02957/13.95, allocations: 11.7 MB / 5.031 GB, free: 311.1 MB / 2.857 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.08412/14.03, allocations: 16.41 MB / 5.048 GB, free: 295.3 MB / 2.857 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1537/14.18, allocations: 65.68 MB / 5.112 GB, free: 229.5 MB / 2.857 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.04378/14.23, allocations: 15.25 MB / 5.127 GB, free: 214.2 MB / 2.857 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01088/14.24, allocations: 2.233 MB / 5.129 GB, free: 212 MB / 2.857 GB
Notification: Performance of SimCode: time 1.583e-06/14.24, allocations: 0 / 5.129 GB, free: 212 MB / 2.857 GB
Notification: Performance of Templates: time 8.541/22.78, allocations: 5.163 GB / 10.29 GB, free: 0.5748 GB / 3.014 GB
"
[Timeout remaining time 277]
make -j1 -f Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe ; mkfifo Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe ; head -c 1048576 < Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe >> ../files/Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.sim & ./Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.DHC.Loads.Combined.Examples.BuildingTimeSeriesWithETSDHWTank.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 165.50490866099426]
Failed to read output from testmodel.py, exit status != 0:
22.791398804009077 22.798662164 13.862181735
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f60d1fdaf50>