Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl.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.0009461/0.0009461, allocations: 80.88 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.001141/0.001141, allocations: 165.8 kB / 24.97 MB, free: 9.184 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.9985/0.9985, allocations: 177.1 MB / 205.4 MB, free: 5.496 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.935/1.935, allocations: 389.6 MB / 0.6362 GB, free: 12.32 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.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl")
translateModel(Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 2.474e-06/2.474e-06, allocations: 0 / 0.776 GB, free: 5.367 MB / 0.7292 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.419e-05/2.666e-05, allocations: 2.312 kB / 0.776 GB, free: 5.363 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl): time 0.005159/0.005185, allocations: 4.752 MB / 0.7806 GB, free: 0.6289 MB / 0.7292 GB
Notification: Performance of NFInst.instExpressions: time 0.004341/0.009526, allocations: 2.773 MB / 0.7833 GB, free: 13.85 MB / 0.7448 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006323/0.01016, allocations: 51.62 kB / 0.7834 GB, free: 13.8 MB / 0.7448 GB
Notification: Performance of NFTyping.typeComponents: time 0.0006252/0.01078, allocations: 313.3 kB / 0.7837 GB, free: 13.49 MB / 0.7448 GB
Notification: Performance of NFTyping.typeBindings: time 0.0007039/0.01149, allocations: 344.1 kB / 0.784 GB, free: 13.16 MB / 0.7448 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001121/0.01261, allocations: 0.742 MB / 0.7847 GB, free: 12.41 MB / 0.7448 GB
Notification: Performance of NFFlatten.flatten: time 0.00126/0.01387, allocations: 1.94 MB / 0.7866 GB, free: 10.47 MB / 0.7448 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001183/0.01505, allocations: 1.24 MB / 0.7878 GB, free: 9.176 MB / 0.7448 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008229/0.01587, allocations: 1.041 MB / 0.7888 GB, free: 8.133 MB / 0.7448 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004751/0.01635, allocations: 0.5696 MB / 0.7894 GB, free: 7.562 MB / 0.7448 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001522/0.0165, allocations: 189.3 kB / 0.7896 GB, free: 7.379 MB / 0.7448 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0003376/0.01684, allocations: 251.9 kB / 0.7898 GB, free: 7.133 MB / 0.7448 GB
Notification: Performance of NFScalarize.scalarize: time 0.0002607/0.0171, allocations: 482.4 kB / 0.7903 GB, free: 6.66 MB / 0.7448 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003354/0.01744, allocations: 0.5181 MB / 0.7908 GB, free: 6.141 MB / 0.7448 GB
Notification: Performance of NFConvertDAE.convert: time 0.001176/0.01861, allocations: 1.347 MB / 0.7921 GB, free: 4.789 MB / 0.7448 GB
Notification: Performance of FrontEnd - DAE generated: time 4.097e-06/0.01862, allocations: 1.344 kB / 0.7921 GB, free: 4.789 MB / 0.7448 GB
Notification: Performance of FrontEnd: time 7.91e-07/0.01862, allocations: 0 / 0.7921 GB, free: 4.789 MB / 0.7448 GB
Notification: Performance of Transformations before backend: time 1.248e-05/0.01863, allocations: 0 / 0.7921 GB, free: 4.789 MB / 0.7448 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 335
 * Number of variables: 335
Notification: Performance of Generate backend data structure: time 0.001808/0.02044, allocations: 1.839 MB / 0.7939 GB, free: 2.914 MB / 0.7448 GB
Notification: Performance of prepare preOptimizeDAE: time 3.887e-05/0.02048, allocations: 8.75 kB / 0.7939 GB, free: 2.906 MB / 0.7448 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0004118/0.02089, allocations: 281.8 kB / 0.7942 GB, free: 2.633 MB / 0.7448 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007773/0.02167, allocations: 0.6687 MB / 0.7948 GB, free: 1.938 MB / 0.7448 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001336/0.0218, allocations: 234.5 kB / 0.7951 GB, free: 1.684 MB / 0.7448 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001209/0.02192, allocations: 160.3 kB / 0.7952 GB, free: 1.527 MB / 0.7448 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00097/0.02289, allocations: 0.997 MB / 0.7962 GB, free: 0.4961 MB / 0.7448 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.446e-05/0.02291, allocations: 4 kB / 0.7962 GB, free: 0.4922 MB / 0.7448 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 6.334e-05/0.02298, allocations: 60.59 kB / 0.7962 GB, free: 444 kB / 0.7448 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.827e-05/0.023, allocations: 32 kB / 0.7963 GB, free: 412 kB / 0.7448 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004742/0.02347, allocations: 394.1 kB / 0.7966 GB, free: 8 kB / 0.7448 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.002627/0.0261, allocations: 2.012 MB / 0.7986 GB, free: 13.97 MB / 0.7605 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0007508/0.02685, allocations: 0.7422 MB / 0.7993 GB, free: 13.22 MB / 0.7605 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002252/0.02707, allocations: 216.9 kB / 0.7995 GB, free: 13.01 MB / 0.7605 GB
Notification: Performance of preOpt evalFunc (simulation): time 4.566e-05/0.02712, allocations: 25 kB / 0.7996 GB, free: 12.99 MB / 0.7605 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0006289/0.02775, allocations: 0.6666 MB / 0.8002 GB, free: 12.3 MB / 0.7605 GB
Notification: Performance of pre-optimization done (n=161): time 3.556e-06/0.02775, allocations: 0 / 0.8002 GB, free: 12.3 MB / 0.7605 GB
Notification: Performance of matching and sorting (n=161): time 0.0009129/0.02866, allocations: 0.8573 MB / 0.8011 GB, free: 11.45 MB / 0.7605 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.999e-05/0.02871, allocations: 91.83 kB / 0.8011 GB, free: 11.34 MB / 0.7605 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0004817/0.0292, allocations: 0.7286 MB / 0.8019 GB, free: 10.6 MB / 0.7605 GB
Notification: Performance of collectPreVariables (initialization): time 7.961e-05/0.02928, allocations: 101.7 kB / 0.802 GB, free: 10.5 MB / 0.7605 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002155/0.02949, allocations: 444.5 kB / 0.8024 GB, free: 10.05 MB / 0.7605 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002125/0.0297, allocations: 428.6 kB / 0.8028 GB, free: 9.633 MB / 0.7605 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002768/0.02998, allocations: 359.2 kB / 0.8031 GB, free: 9.273 MB / 0.7605 GB
Notification: Performance of setup shared object (initialization): time 6.229e-05/0.03004, allocations: 301.1 kB / 0.8034 GB, free: 8.977 MB / 0.7605 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002193/0.03026, allocations: 170.7 kB / 0.8036 GB, free: 8.809 MB / 0.7605 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002805/0.03054, allocations: 344 kB / 0.8039 GB, free: 8.449 MB / 0.7605 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0004968/0.03104, allocations: 0.5574 MB / 0.8045 GB, free: 7.867 MB / 0.7605 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.508e-06/0.03104, allocations: 0 / 0.8045 GB, free: 7.867 MB / 0.7605 GB
Notification: Performance of matching and sorting (n=224) (initialization): time 0.0007029/0.03175, allocations: 0.7244 MB / 0.8052 GB, free: 7.137 MB / 0.7605 GB
Notification: Performance of prepare postOptimizeDAE: time 1.895e-05/0.03177, allocations: 5.312 kB / 0.8052 GB, free: 7.133 MB / 0.7605 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.568e-06/0.03177, allocations: 4 kB / 0.8052 GB, free: 7.129 MB / 0.7605 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.062e-05/0.0318, allocations: 8 kB / 0.8052 GB, free: 7.121 MB / 0.7605 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004274/0.03222, allocations: 207.6 kB / 0.8054 GB, free: 6.918 MB / 0.7605 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.149e-05/0.03223, allocations: 16.59 kB / 0.8054 GB, free: 6.902 MB / 0.7605 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003128/0.03255, allocations: 153.6 kB / 0.8055 GB, free: 6.754 MB / 0.7605 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.425e-05/0.0326, allocations: 36 kB / 0.8056 GB, free: 6.719 MB / 0.7605 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * Number of states: 0 ()
 * Number of discrete variables: 165 ($PRE.ena.truDel.u,$PRE.sta.offToOn.enableFire,$PRE.sta.onToOff.enableFire,$PRE.ena.truDel.t_next,$whenCondition32,$PRE.ratV_flow.tab.nextTimeEvent,$whenCondition1,ena.truDel.t_next,sta.stateGraphRoot.suspend,sta.stateGraphRoot.resume,mulAnd.uTemp[2],mulAnd.u[3],mulAnd.u[2],preMod.u,$PRE.preMod.u,setMod.preMod.y,setMod.preMod.u,$PRE.setMod.preMod.u,setMod.les.lesHys.y,$PRE.setMod.les.lesHys.y,sta.onToOff.t_start,$PRE.sta.onToOff.t_start,sta.onToOff.fire,sta.onToOff.enableFire,sta.on.active,sta.on.oldActive,$PRE.sta.on.oldActive,sta.on.newActive,$PRE.sta.on.newActive,sta.on.localActive,$PRE.sta.on.localActive,sta.offToOn.t_start,$PRE.sta.offToOn.t_start,sta.offToOn.fire,sta.offToOn.enableFire,sta.off.active,sta.off.oldActive,$PRE.sta.off.oldActive,sta.off.newActive,$PRE.sta.off.newActive,sta.off.localActive,$PRE.sta.off.localActive,sta.stateGraphRoot.activeSteps,loaHeaWat.movAve.mode,$PRE.loaHeaWat.movAve.mode,ena.edg.u,$PRE.ena.edg.u,ena.truDel.y,ena.truDel.u,ena.y1SetMod,ena.enaDis.y,$PRE.ena.enaDis.y,ena.enaDis.clr,ena.enaDis.u,ena.anyTHeaWatLvg.y,ena.anyTChiWatLvg.y,ena.enaAndHea.y,ena.enaAndCoo.y,ena.off.not_u,$PRE.ena.off.not_u,ena.off.y,ena.anyLowLoa.y,ena.lowLoaHea.lesHys.y,$PRE.ena.lowLoaHea.lesHys.y,ena.lowLoaCoo.lesHys.y,$PRE.ena.lowLoaCoo.lesHys.y,ena.anyDis.uTemp[4],ena.anyDis.uTemp[3],ena.anyDis.uTemp[2],ena.anyDis.u[5],ena.anyDis.u[4],ena.anyDis.u[3],ena.anyDis.u[2],ena.anyDis.u[1],ena.allEna.uTemp[4],ena.allEna.uTemp[3],ena.allEna.uTemp[2],ena.timLoaHea.entryTime,$PRE.ena.timLoaHea.entryTime,ena.timLoaHea.passed,$PRE.ena.timLoaHea.passed,ena.timLoaCoo.entryTime,$PRE.ena.timLoaCoo.entryTime,ena.timLoaCoo.passed,$PRE.ena.timLoaCoo.passed,ena.timTChiWatLvg2.entryTime,$PRE.ena.timTChiWatLvg2.entryTime,ena.timTChiWatLvg2.passed,$PRE.ena.timTChiWatLvg2.passed,ena.timTChiWatLvg1.entryTime,$PRE.ena.timTChiWatLvg1.entryTime,ena.timTChiWatLvg1.passed,$PRE.ena.timTChiWatLvg1.passed,ena.timTHeaWatLvg2.entryTime,$PRE.ena.timTHeaWatLvg2.entryTime,ena.timTHeaWatLvg2.passed,$PRE.ena.timTHeaWatLvg2.passed,ena.timTHeaWatLvg1.entryTime,$PRE.ena.timTHeaWatLvg1.entryTime,ena.timTHeaWatLvg1.passed,$PRE.ena.timTHeaWatLvg1.passed,ena.lowTChiWatLvg2.y,ena.higTHeaWatLvg2.y,ena.higTHeaWatLvg1.y,ena.lowTChiWatLvg1.y,ena.higLoaHea.greHys.y,$PRE.ena.higLoaHea.greHys.y,ena.higLoaCoo.greHys.y,$PRE.ena.higLoaCoo.greHys.y,ena.timHeaEna.entryTime,$PRE.ena.timHeaEna.entryTime,ena.timHeaEna.passed,$PRE.ena.timHeaEna.passed,ena.runDis.entryTime,$PRE.ena.runDis.entryTime,ena.runDis.passed,$PRE.ena.runDis.passed,ena.dis.y,ena.timCooEna.entryTime,$PRE.ena.timCooEna.entryTime,ena.timCooEna.passed,$PRE.ena.timCooEna.passed,ena.preEna.u,$PRE.ena.preEna.u,ena.u1CooHrc,loaChiWat.movAve.mode,$PRE.loaChiWat.movAve.mode,ratV_flow.tab.nextTimeEventScaled,$PRE.ratV_flow.tab.nextTimeEventScaled,ratV_flow.tab.nextTimeEvent,$whenCondition2,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition23,$whenCondition22,$whenCondition21,$whenCondition26,$whenCondition25,$whenCondition24,$whenCondition29,$whenCondition28,$whenCondition27,$whenCondition30,$whenCondition31,$whenCondition34,$whenCondition33,$whenCondition35,$whenCondition36,$whenCondition37)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (224):
 * Single equations (assignments): 224
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0002971/0.0329, allocations: 281.9 kB / 0.8058 GB, free: 6.434 MB / 0.7605 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000133/0.03303, allocations: 156.8 kB / 0.806 GB, free: 6.281 MB / 0.7605 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0007041/0.03374, allocations: 0.7054 MB / 0.8067 GB, free: 5.559 MB / 0.7605 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.004e-05/0.03375, allocations: 9 kB / 0.8067 GB, free: 5.551 MB / 0.7605 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.396e-06/0.03375, allocations: 4 kB / 0.8067 GB, free: 5.547 MB / 0.7605 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001108/0.03386, allocations: 122.9 kB / 0.8068 GB, free: 5.426 MB / 0.7605 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001097/0.03397, allocations: 56.92 kB / 0.8069 GB, free: 5.371 MB / 0.7605 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002366/0.03421, allocations: 77.69 kB / 0.8069 GB, free: 5.297 MB / 0.7605 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002402/0.03445, allocations: 185 kB / 0.8071 GB, free: 5.117 MB / 0.7605 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0006687/0.03511, allocations: 0.7442 MB / 0.8078 GB, free: 4.371 MB / 0.7605 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.00106/0.03617, allocations: 1.213 MB / 0.809 GB, free: 3.137 MB / 0.7605 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0003375/0.03651, allocations: 397.7 kB / 0.8094 GB, free: 2.746 MB / 0.7605 GB
Notification: Performance of sorting global known variables: time 0.0002416/0.03675, allocations: 305.5 kB / 0.8097 GB, free: 2.449 MB / 0.7605 GB
Notification: Performance of Backend: time 4.1e-07/0.03675, allocations: 0 / 0.8097 GB, free: 2.449 MB / 0.7605 GB
Notification: Performance of simCode: created initialization part: time 0.002066/0.03882, allocations: 1.119 MB / 0.8108 GB, free: 1.617 MB / 0.7605 GB
Notification: Performance of SimCode: time 0.002969/0.04179, allocations: 2.803 MB / 0.8135 GB, free: 14.76 MB / 0.7761 GB
Notification: Performance of Templates: time 0.02586/0.06765, allocations: 20.99 MB / 0.834 GB, free: 10.27 MB / 0.7917 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl.pipe ; mkfifo Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl.pipe ; head -c 1048576 < Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl.pipe >> ../files/Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl.sim & ./Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Templates.Plants.Controls.HeatRecoveryChillers.Validation.EnableAndModeControl.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 5.273970567621291]