Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup.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.00107/0.00107, allocations: 80.81 kB / 21.55 MB, free: 2.945 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.001331/0.001331, allocations: 162.7 kB / 24.96 MB, free: 9.125 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 0.9788/0.9788, allocations: 177.1 MB / 205.4 MB, free: 5.477 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 1.948/1.948, allocations: 389.6 MB / 0.6362 GB, free: 12.3 MB / 0.6042 GB
"
[Timeout remaining time 178]
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.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup")
translateModel(Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.413e-06/1.413e-06, allocations: 0 / 0.776 GB, free: 5.352 MB / 0.7292 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.679e-05/1.821e-05, allocations: 6.312 kB / 0.776 GB, free: 5.344 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup): time 0.07763/0.07765, allocations: 106.4 MB / 0.8799 GB, free: 10.63 MB / 0.8386 GB
Notification: Performance of NFInst.instExpressions: time 0.0599/0.1375, allocations: 37.29 MB / 0.9163 GB, free: 5.418 MB / 0.8698 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01464/0.1522, allocations: 0.678 MB / 0.917 GB, free: 4.734 MB / 0.8698 GB
Notification: Performance of NFTyping.typeComponents: time 0.01056/0.1627, allocations: 4.903 MB / 0.9218 GB, free: 15.8 MB / 0.8855 GB
Notification: Performance of NFTyping.typeBindings: time 0.02399/0.1867, allocations: 12.65 MB / 0.9341 GB, free: 3.086 MB / 0.8855 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01805/0.2048, allocations: 10.84 MB / 0.9447 GB, free: 8.227 MB / 0.9011 GB
Notification: Performance of NFFlatten.flatten: time 0.08004/0.2848, allocations: 83.22 MB / 1.026 GB, free: 4.816 MB / 0.9792 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02419/0.309, allocations: 15 MB / 1.041 GB, free: 5.785 MB / 0.9948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.804/1.113, allocations: 24.08 MB / 1.064 GB, free: 105.4 MB / 0.9949 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02372/1.137, allocations: 13.17 MB / 1.077 GB, free: 105.4 MB / 0.9949 GB
Notification: Performance of NFPackage.collectConstants: time 0.005142/1.142, allocations: 1.981 MB / 1.079 GB, free: 105.4 MB / 0.9949 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01072/1.153, allocations: 3.294 MB / 1.082 GB, free: 105.4 MB / 0.9949 GB
Notification: Performance of NFScalarize.scalarize: time 0.005327/1.158, allocations: 6.527 MB / 1.089 GB, free: 105.4 MB / 0.9949 GB
Notification: Performance of NFVerifyModel.verify: time 0.01241/1.17, allocations: 7.731 MB / 1.096 GB, free: 105.4 MB / 0.9949 GB
Notification: Performance of NFConvertDAE.convert: time 0.02103/1.191, allocations: 19.44 MB / 1.115 GB, free: 103.9 MB / 0.9949 GB
Notification: Performance of FrontEnd - DAE generated: time 7.033e-06/1.191, allocations: 0 / 1.115 GB, free: 103.9 MB / 0.9949 GB
Notification: Performance of FrontEnd: time 1.613e-06/1.191, allocations: 1.719 kB / 1.115 GB, free: 103.9 MB / 0.9949 GB
Notification: Performance of Transformations before backend: time 0.0006922/1.192, allocations: 0 / 1.115 GB, free: 103.9 MB / 0.9949 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2586
 * Number of variables: 2586
Notification: Performance of Generate backend data structure: time 0.02427/1.216, allocations: 21.13 MB / 1.136 GB, free: 94.62 MB / 0.9949 GB
Notification: Performance of prepare preOptimizeDAE: time 4.095e-05/1.216, allocations: 6.438 kB / 1.136 GB, free: 94.62 MB / 0.9949 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01026/1.227, allocations: 4.183 MB / 1.14 GB, free: 94.52 MB / 0.9949 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02395/1.251, allocations: 15.86 MB / 1.155 GB, free: 90.75 MB / 0.9949 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007683/1.251, allocations: 1.094 MB / 1.156 GB, free: 90.48 MB / 0.9949 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002468/1.254, allocations: 1.159 MB / 1.157 GB, free: 90.46 MB / 0.9949 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01069/1.264, allocations: 9.969 MB / 1.167 GB, free: 85.3 MB / 0.9949 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001395/1.265, allocations: 18.78 kB / 1.167 GB, free: 85.3 MB / 0.9949 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007695/1.265, allocations: 423.3 kB / 1.168 GB, free: 85.2 MB / 0.9949 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001285/1.266, allocations: 281.3 kB / 1.168 GB, free: 84.94 MB / 0.9949 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005071/1.271, allocations: 3.588 MB / 1.171 GB, free: 81.72 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.04119/1.312, allocations: 33.3 MB / 1.204 GB, free: 55.5 MB / 0.9949 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.005279/1.317, allocations: 4.303 MB / 1.208 GB, free: 52.13 MB / 0.9949 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002078/1.319, allocations: 1.685 MB / 1.21 GB, free: 50.65 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.02219/1.341, allocations: 8.886 MB / 1.218 GB, free: 42.95 MB / 0.9949 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.003559/1.345, allocations: 3.894 MB / 1.222 GB, free: 38.75 MB / 0.9949 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.004195/1.349, allocations: 0.5502 MB / 1.223 GB, free: 38.2 MB / 0.9949 GB
Notification: Performance of pre-optimization done (n=608): time 1.618e-05/1.349, allocations: 4 kB / 1.223 GB, free: 38.2 MB / 0.9949 GB
Notification: Performance of matching and sorting (n=608): time 0.06073/1.41, allocations: 18.44 MB / 1.241 GB, free: 19.76 MB / 0.9949 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.083e-05/1.41, allocations: 120.2 kB / 1.241 GB, free: 19.63 MB / 0.9949 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01293/1.423, allocations: 13.28 MB / 1.254 GB, free: 8.785 MB / 0.9949 GB
Notification: Performance of collectPreVariables (initialization): time 0.001119/1.424, allocations: 210.9 kB / 1.254 GB, free: 8.574 MB / 0.9949 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002948/1.427, allocations: 5.046 MB / 1.259 GB, free: 4.266 MB / 0.9949 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001383/1.428, allocations: 2.137 MB / 1.261 GB, free: 2.266 MB / 0.9949 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002126/1.43, allocations: 1.89 MB / 1.263 GB, free: 0.5781 MB / 0.9949 GB
Notification: Performance of setup shared object (initialization): time 2.903e-05/1.43, allocations: 301.1 kB / 1.263 GB, free: 288 kB / 0.9949 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002078/1.432, allocations: 1.241 MB / 1.264 GB, free: 15.02 MB / 1.011 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006632/1.439, allocations: 9.192 MB / 1.273 GB, free: 3.484 MB / 1.011 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006832/1.446, allocations: 4.997 MB / 1.278 GB, free: 14.03 MB / 1.026 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.056e-05/1.446, allocations: 20 kB / 1.278 GB, free: 14.01 MB / 1.026 GB
Notification: Performance of matching and sorting (n=1043) (initialization): time 0.01713/1.463, allocations: 6.854 MB / 1.285 GB, free: 7.512 MB / 1.026 GB
Notification: Performance of prepare postOptimizeDAE: time 4.542e-05/1.463, allocations: 32 kB / 1.285 GB, free: 7.48 MB / 1.026 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.705e-05/1.463, allocations: 60.12 kB / 1.285 GB, free: 7.422 MB / 1.026 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01065/1.474, allocations: 5.244 MB / 1.29 GB, free: 2.184 MB / 1.026 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004046/1.478, allocations: 1.712 MB / 1.292 GB, free: 0.5781 MB / 1.026 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01496/1.493, allocations: 8.205 MB / 1.3 GB, free: 8.25 MB / 1.042 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009589/1.502, allocations: 0.6759 MB / 1.301 GB, free: 7.578 MB / 1.042 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001029/1.503, allocations: 403.8 kB / 1.301 GB, free: 7.184 MB / 1.042 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.006268/1.51, allocations: 1.455 MB / 1.302 GB, free: 5.715 MB / 1.042 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004878/1.515, allocations: 9.152 MB / 1.311 GB, free: 10.21 MB / 1.057 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.005311/1.52, allocations: 4.883 MB / 1.316 GB, free: 4.871 MB / 1.057 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.865e-05/1.52, allocations: 20 kB / 1.316 GB, free: 4.852 MB / 1.057 GB
Notification: Performance of matching and sorting (n=1043) (initialization_lambda0): time 0.01721/1.537, allocations: 6.997 MB / 1.323 GB, free: 14.23 MB / 1.073 GB
Notification: Performance of prepare postOptimizeDAE: time 4.379e-05/1.537, allocations: 28 kB / 1.323 GB, free: 14.2 MB / 1.073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.885e-05/1.537, allocations: 64.12 kB / 1.323 GB, free: 14.14 MB / 1.073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0108/1.548, allocations: 4.932 MB / 1.328 GB, free: 9.207 MB / 1.073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004199/1.552, allocations: 1.717 MB / 1.33 GB, free: 7.598 MB / 1.073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02161/1.574, allocations: 14.52 MB / 1.344 GB, free: 8.852 MB / 1.089 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01049/1.584, allocations: 0.8024 MB / 1.344 GB, free: 8.055 MB / 1.089 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008207/1.585, allocations: 358.2 kB / 1.345 GB, free: 7.707 MB / 1.089 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 267
 * Number of states: 0 ()
 * Number of discrete variables: 82 ($whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,idx[2].y,idx[1].y,y1PumChiWat.u[1],pumConWatEva.preY1[1].u,$PRE.pumConWatEva.preY1[1].u,pumConWatEva.isOpe.greHys.y,$PRE.pumConWatEva.isOpe.greHys.y,pumConWatEva.com.y1[1],pumConWatCon.preY1[1].u,$PRE.pumConWatCon.preY1[1].u,pumConWatCon.isOpe.greHys.y,$PRE.pumConWatCon.isOpe.greHys.y,pumConWatCon.com.y1[1],y1PumHeaWat.u[1],onAndHea[2].y,pumHeaWat.preY1[1].u,$PRE.pumHeaWat.preY1[1].u,pumHeaWat.isOpe.greHys.y,$PRE.pumHeaWat.isOpe.greHys.y,pumHeaWat.com.y1[1],y1PumConWatCon.u[1],pumChiWat.preY1[1].u,$PRE.pumChiWat.preY1[1].u,pumChiWat.isOpe.greHys.y,$PRE.pumChiWat.isOpe.greHys.y,pumChiWat.com.y1[1],mul1[2].y,mul1[1].y,intLes1[2].y,intLes1[1].y,rep1.y[2],booToInt1[2].y,booToInt1[1].y,heaAndCas[2].y,mul[2].y,mul[1].y,rep.y[2],intLes[2].y,intLes[1].y,booToInt[2].y,booToInt[1].y,hea[2].y,y1HeaCoo.intTimTab.idx,$PRE.y1HeaCoo.intTimTab.idx,y1HeaCoo.intTimTab.y[2],$PRE.y1HeaCoo.intTimTab.y[2],y1HeaCoo.intTimTab.y[1],$PRE.y1HeaCoo.intTimTab.y[1],y1HeaCoo.y[2],y1HeaCoo.y[1],y1Coo.intTimTab.idx,$PRE.y1Coo.intTimTab.idx,y1Coo.intTimTab.y[2],$PRE.y1Coo.intTimTab.y[2],y1Coo.intTimTab.y[1],$PRE.y1Coo.intTimTab.y[1],y1.intTimTab.idx,$PRE.y1.intTimTab.idx,y1.intTimTab.y[2],$PRE.y1.intTimTab.y[2],y1.intTimTab.y[1],$PRE.y1.intTimTab.y[1],y1.y[2],y1.y[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (911):
 * Single equations (assignments): 901
 * Array equations: 4
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 6
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 6 systems
   {(1,3), (1,1), (1,8), (1,8), (17,34), (17,34)}
Notification: Performance of prepare postOptimizeDAE: time 0.001927/1.587, allocations: 0.7429 MB / 1.346 GB, free: 7.059 MB / 1.089 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002237/1.589, allocations: 1.08 MB / 1.347 GB, free: 5.98 MB / 1.089 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01811/1.607, allocations: 7.765 MB / 1.354 GB, free: 14.35 MB / 1.104 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01323/1.621, allocations: 4.825 MB / 1.359 GB, free: 9.539 MB / 1.104 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.546e-05/1.621, allocations: 3.984 kB / 1.359 GB, free: 9.535 MB / 1.104 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006716/1.627, allocations: 1.996 MB / 1.361 GB, free: 7.516 MB / 1.104 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002058/1.629, allocations: 144 kB / 1.361 GB, free: 7.375 MB / 1.104 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003914/1.633, allocations: 419.4 kB / 1.361 GB, free: 6.965 MB / 1.104 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008369/1.634, allocations: 0.5195 MB / 1.362 GB, free: 6.441 MB / 1.104 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01167/1.646, allocations: 4.867 MB / 1.367 GB, free: 1.723 MB / 1.104 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.008456/1.654, allocations: 5.506 MB / 1.372 GB, free: 12.28 MB / 1.12 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001832/1.656, allocations: 1.402 MB / 1.373 GB, free: 10.9 MB / 1.12 GB
Notification: Performance of sorting global known variables: time 0.008488/1.665, allocations: 6.128 MB / 1.379 GB, free: 5.598 MB / 1.12 GB
Notification: Performance of Backend: time 4.5e-07/1.665, allocations: 0 / 1.379 GB, free: 5.598 MB / 1.12 GB
Notification: Performance of simCode: created initialization part: time 0.01997/1.685, allocations: 8.953 MB / 1.388 GB, free: 13.23 MB / 1.136 GB
Notification: Performance of SimCode: time 0.07485/1.76, allocations: 40.53 MB / 1.428 GB, free: 4.961 MB / 1.167 GB
Notification: Performance of Templates: time 0.8978/2.657, allocations: 457.5 MB / 1.875 GB, free: 39.68 MB / 1.229 GB
"
[Timeout remaining time 297]
make -j1 -f Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup.pipe ; mkfifo Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup.pipe ; head -c 1048576 < Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup.pipe >> ../files/Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup.sim & ./Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.DHC.Plants.Combined.Subsystems.Validation.ChillerHeatRecoveryGroup.pipe 2>&1) [Timeout 240]
[Calling os._exit(0), Time elapsed: 14.295138007029891]