Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank.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.0009668/0.0009668, allocations: 80.81 kB / 21.55 MB, free: 2.941 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001008/0.001008, allocations: 168.8 kB / 24.97 MB, free: 9.18 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.087/1.087, allocations: 177.1 MB / 205.4 MB, free: 5.488 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 2.199/2.199, allocations: 389.6 MB / 0.6362 GB, free: 12.29 MB / 0.6042 GB
"
[Timeout remaining time 177]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.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.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank")
translateModel(Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 2.264e-06/2.264e-06, allocations: 0 / 0.776 GB, free: 5.34 MB / 0.7292 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 3.4e-05/3.627e-05, allocations: 5.688 kB / 0.776 GB, free: 5.336 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank): time 0.1039/0.1039, allocations: 85.09 MB / 0.8591 GB, free: 15.99 MB / 0.823 GB
Notification: Performance of NFInst.instExpressions: time 0.08849/0.1924, allocations: 37.81 MB / 0.896 GB, free: 10.27 MB / 0.8542 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01964/0.2121, allocations: 0.6238 MB / 0.8966 GB, free: 9.641 MB / 0.8542 GB
Notification: Performance of NFTyping.typeComponents: time 0.01383/0.2259, allocations: 3.773 MB / 0.9003 GB, free: 5.844 MB / 0.8542 GB
Notification: Performance of NFTyping.typeBindings: time 0.03186/0.2578, allocations: 9.924 MB / 0.91 GB, free: 11.86 MB / 0.8698 GB
Notification: Performance of NFTyping.typeClassSections: time 0.02448/0.2822, allocations: 9.916 MB / 0.9197 GB, free: 1.941 MB / 0.8698 GB
Notification: Performance of NFFlatten.flatten: time 0.07529/0.3575, allocations: 56.37 MB / 0.9747 GB, free: 9.457 MB / 0.9323 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02395/0.3815, allocations: 10.35 MB / 0.9848 GB, free: 15.07 MB / 0.948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.04255/0.424, allocations: 15.88 MB / 1 GB, free: 15.24 MB / 0.9636 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01899/0.443, allocations: 8.014 MB / 1.008 GB, free: 7.203 MB / 0.9636 GB
Notification: Performance of NFPackage.collectConstants: time 0.007096/0.4501, allocations: 1.594 MB / 1.01 GB, free: 5.609 MB / 0.9636 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01356/0.4637, allocations: 3.266 MB / 1.013 GB, free: 2.34 MB / 0.9636 GB
Notification: Performance of NFScalarize.scalarize: time 0.009415/0.4731, allocations: 4.154 MB / 1.017 GB, free: 14.17 MB / 0.9792 GB
Notification: Performance of NFVerifyModel.verify: time 0.01653/0.4896, allocations: 5.141 MB / 1.022 GB, free: 9.02 MB / 0.9792 GB
Notification: Performance of NFConvertDAE.convert: time 0.03358/0.5232, allocations: 12.66 MB / 1.034 GB, free: 12.32 MB / 0.9948 GB
Notification: Performance of FrontEnd - DAE generated: time 1.111e-05/0.5232, allocations: 0 / 1.034 GB, free: 12.32 MB / 0.9948 GB
Notification: Performance of FrontEnd: time 1.873e-06/0.5232, allocations: 0 / 1.034 GB, free: 12.32 MB / 0.9948 GB
Notification: Performance of Transformations before backend: time 0.0008881/0.5241, allocations: 0 / 1.034 GB, free: 12.32 MB / 0.9948 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1841
 * Number of variables: 1841
Notification: Performance of Generate backend data structure: time 0.9705/1.495, allocations: 13.63 MB / 1.048 GB, free: 153 MB / 0.9949 GB
Notification: Performance of prepare preOptimizeDAE: time 4.771e-05/1.495, allocations: 9.312 kB / 1.048 GB, free: 153 MB / 0.9949 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008337/1.503, allocations: 2.581 MB / 1.05 GB, free: 153 MB / 0.9949 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.029/1.532, allocations: 9.801 MB / 1.06 GB, free: 151.2 MB / 0.9949 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006068/1.533, allocations: 0.5631 MB / 1.06 GB, free: 151.2 MB / 0.9949 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002112/1.535, allocations: 0.7694 MB / 1.061 GB, free: 151.2 MB / 0.9949 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01068/1.545, allocations: 6.988 MB / 1.068 GB, free: 148.9 MB / 0.9949 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001682/1.546, allocations: 12.97 kB / 1.068 GB, free: 148.9 MB / 0.9949 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006546/1.546, allocations: 305.5 kB / 1.068 GB, free: 148.9 MB / 0.9949 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001747/1.546, allocations: 196 kB / 1.068 GB, free: 148.9 MB / 0.9949 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005027/1.551, allocations: 2.447 MB / 1.071 GB, free: 148.7 MB / 0.9949 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.04063/1.592, allocations: 27.27 MB / 1.097 GB, free: 138.1 MB / 0.9949 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003784/1.596, allocations: 2.568 MB / 1.1 GB, free: 137.8 MB / 0.9949 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001671/1.598, allocations: 1.339 MB / 1.101 GB, free: 137.8 MB / 0.9949 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.0123/1.61, allocations: 5.884 MB / 1.107 GB, free: 137.2 MB / 0.9949 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002123/1.612, allocations: 2.505 MB / 1.109 GB, free: 135.9 MB / 0.9949 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.002948/1.615, allocations: 0.6445 MB / 1.11 GB, free: 135.7 MB / 0.9949 GB
Notification: Performance of pre-optimization done (n=375): time 2.222e-05/1.615, allocations: 0 / 1.11 GB, free: 135.7 MB / 0.9949 GB
Notification: Performance of matching and sorting (n=375): time 0.01043/1.625, allocations: 4.424 MB / 1.114 GB, free: 135.4 MB / 0.9949 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.033e-05/1.625, allocations: 249.5 kB / 1.115 GB, free: 135.2 MB / 0.9949 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008686/1.634, allocations: 7.519 MB / 1.122 GB, free: 134.4 MB / 0.9949 GB
Notification: Performance of collectPreVariables (initialization): time 0.0009696/1.635, allocations: 123.2 kB / 1.122 GB, free: 134.3 MB / 0.9949 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002135/1.637, allocations: 3.511 MB / 1.125 GB, free: 132.6 MB / 0.9949 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00132/1.639, allocations: 1.342 MB / 1.127 GB, free: 131.7 MB / 0.9949 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001306/1.64, allocations: 1.099 MB / 1.128 GB, free: 131.4 MB / 0.9949 GB
Notification: Performance of setup shared object (initialization): time 3.034e-05/1.64, allocations: 305.6 kB / 1.128 GB, free: 131.1 MB / 0.9949 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001834/1.642, allocations: 0.8887 MB / 1.129 GB, free: 130.9 MB / 0.9949 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0024/1.644, allocations: 4.292 MB / 1.133 GB, free: 125.7 MB / 0.9949 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003858/1.648, allocations: 3.726 MB / 1.137 GB, free: 121.8 MB / 0.9949 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.9e-05/1.648, allocations: 12 kB / 1.137 GB, free: 121.8 MB / 0.9949 GB
Notification: Performance of matching and sorting (n=670) (initialization): time 0.007318/1.655, allocations: 4.164 MB / 1.141 GB, free: 118.3 MB / 0.9949 GB
Notification: Performance of prepare postOptimizeDAE: time 4.15e-05/1.655, allocations: 31.56 kB / 1.141 GB, free: 118.2 MB / 0.9949 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.579e-05/1.655, allocations: 48.14 kB / 1.141 GB, free: 118.2 MB / 0.9949 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002631/1.658, allocations: 0.9748 MB / 1.142 GB, free: 117.3 MB / 0.9949 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002976/1.661, allocations: 1.272 MB / 1.143 GB, free: 116.1 MB / 0.9949 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003269/1.664, allocations: 6.807 MB / 1.15 GB, free: 109.4 MB / 0.9949 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005115/1.669, allocations: 374.2 kB / 1.15 GB, free: 109.1 MB / 0.9949 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007728/1.67, allocations: 298 kB / 1.15 GB, free: 108.9 MB / 0.9949 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003559/1.674, allocations: 1.023 MB / 1.151 GB, free: 108.1 MB / 0.9949 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002174/1.676, allocations: 4.257 MB / 1.156 GB, free: 102.9 MB / 0.9949 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.003623/1.68, allocations: 3.664 MB / 1.159 GB, free: 99.08 MB / 0.9949 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.461e-05/1.68, allocations: 16 kB / 1.159 GB, free: 99.06 MB / 0.9949 GB
Notification: Performance of matching and sorting (n=670) (initialization_lambda0): time 0.00726/1.687, allocations: 4.135 MB / 1.163 GB, free: 95.58 MB / 0.9949 GB
Notification: Performance of prepare postOptimizeDAE: time 2.935e-05/1.687, allocations: 30.34 kB / 1.163 GB, free: 95.55 MB / 0.9949 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.889e-05/1.687, allocations: 48 kB / 1.163 GB, free: 95.5 MB / 0.9949 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002427/1.689, allocations: 0.9421 MB / 1.164 GB, free: 94.62 MB / 0.9949 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002903/1.692, allocations: 1.262 MB / 1.166 GB, free: 93.47 MB / 0.9949 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005409/1.698, allocations: 8.815 MB / 1.174 GB, free: 85 MB / 0.9949 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006171/1.704, allocations: 369.2 kB / 1.174 GB, free: 84.73 MB / 0.9949 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009752/1.705, allocations: 265.8 kB / 1.175 GB, free: 84.52 MB / 0.9949 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 222
 * Number of states: 0 ()
 * Number of discrete variables: 25 ($whenCondition4,$PRE.sch.nextTimeEvent,$whenCondition3,theMixVal.conPID.con1.y,sch.nextTimeEventScaled,$PRE.sch.nextTimeEventScaled,sch.nextTimeEvent,heaPum.and2.y,heaPum.heaPumTan.conVal.cha.y,$PRE.heaPum.heaPumTan.conVal.cha.y,heaPum.heaPumTan.conCha.cha1.y,$PRE.heaPum.heaPumTan.conCha.cha1.y,heaPum.heaPumTan.conCha.cha.y,$PRE.heaPum.heaPumTan.conCha.cha.y,heaPum.heaPumTan.conCha.lat.y,$PRE.heaPum.heaPumTan.conCha.lat.y,heaPum.heaPumTan.conCha.lat.clr,heaPum.heaPumTan.conCha.lat.u,heaPum.heaPumTan.conPum.hysMasFlo.y,$PRE.heaPum.heaPumTan.conPum.hysMasFlo.y,$whenCondition1,$whenCondition2,$whenCondition5,$whenCondition6,heaPum.heaPumTan.conVal.ram.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (602):
 * Single equations (assignments): 591
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * Torn equation systems: 6
 * 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): 2 systems
   {(1,3,100.0%), (1,10,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 4 systems
   {(1,4), (2,17), (2,12), (2,10)}
Notification: Performance of prepare postOptimizeDAE: time 0.001605/1.706, allocations: 0.5359 MB / 1.175 GB, free: 84.2 MB / 0.9949 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00215/1.708, allocations: 0.6548 MB / 1.176 GB, free: 83.59 MB / 0.9949 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006708/1.715, allocations: 3.878 MB / 1.18 GB, free: 80.3 MB / 0.9949 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002992/1.718, allocations: 2.188 MB / 1.182 GB, free: 78.09 MB / 0.9949 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.708e-06/1.718, allocations: 4 kB / 1.182 GB, free: 78.09 MB / 0.9949 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003516/1.722, allocations: 1.246 MB / 1.183 GB, free: 77 MB / 0.9949 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001386/1.723, allocations: 92.44 kB / 1.183 GB, free: 76.97 MB / 0.9949 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002723/1.726, allocations: 277.4 kB / 1.183 GB, free: 76.76 MB / 0.9949 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004661/1.726, allocations: 331.5 kB / 1.184 GB, free: 76.47 MB / 0.9949 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005216/1.732, allocations: 3.038 MB / 1.187 GB, free: 73.64 MB / 0.9949 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.005434/1.737, allocations: 3.769 MB / 1.19 GB, free: 70.12 MB / 0.9949 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001206/1.738, allocations: 0.9569 MB / 1.191 GB, free: 69.33 MB / 0.9949 GB
Notification: Performance of sorting global known variables: time 0.004657/1.743, allocations: 3.681 MB / 1.195 GB, free: 66.33 MB / 0.9949 GB
Notification: Performance of Backend: time 5.71e-07/1.743, allocations: 0 / 1.195 GB, free: 66.33 MB / 0.9949 GB
Notification: Performance of simCode: created initialization part: time 0.01586/1.759, allocations: 6.739 MB / 1.201 GB, free: 60.99 MB / 0.9949 GB
Notification: Performance of SimCode: time 0.04239/1.801, allocations: 24.13 MB / 1.225 GB, free: 38.27 MB / 0.9949 GB
Notification: Performance of Templates: time 0.3203/2.121, allocations: 255.4 MB / 1.474 GB, free: 10.2 MB / 1.214 GB
"
[Timeout remaining time 298]
make -j1 -f Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank.pipe ; mkfifo Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank.pipe ; head -c 1048576 < Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank.pipe >> ../files/Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank.sim & ./Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.DHC.ETS.Combined.Subsystems.Validation.HeatPumpDHWTank.pipe 2>&1) [Timeout 240]
[Calling os._exit(0), Time elapsed: 11.355238824151456]