Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.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.001459/0.001459, allocations: 114.2 kB / 18.94 MB, free: 2.93 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.001628/0.001628, allocations: 189 kB / 22.22 MB, free: 5.105 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.314/1.314, allocations: 225.6 MB / 251 MB, free: 13.02 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/package.mo): time 2.894/2.894, allocations: 0.4893 GB / 0.7884 GB, free: 33.28 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-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.Utilities.Plotters.Examples.SingleZoneVAV,tolerance=1e-06,outputFormat="mat",numberOfIntervals=120,variableFilter="time|hvac.PFan",fileNamePrefix="Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV")
translateModel(Buildings.Utilities.Plotters.Examples.SingleZoneVAV,tolerance=1e-06,outputFormat="mat",numberOfIntervals=120,variableFilter="time|hvac.PFan",fileNamePrefix="Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.001888/0.001888, allocations: 69 kB / 1.145 GB, free: 30.62 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3235/0.3253, allocations: 174.9 MB / 1.316 GB, free: 9.723 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Utilities.Plotters.Examples.SingleZoneVAV): time 1.123/1.448, allocations: 0.4995 GB / 1.816 GB, free: 15.65 MB / 1.311 GB
Notification: Performance of NFInst.instExpressions: time 0.1518/1.6, allocations: 98.11 MB / 1.911 GB, free: 3.66 MB / 1.389 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02287/1.623, allocations: 0.8215 MB / 1.912 GB, free: 2.832 MB / 1.389 GB
Notification: Performance of NFTyping.typeComponents: time 0.03289/1.656, allocations: 12.8 MB / 1.925 GB, free: 5.973 MB / 1.404 GB
Notification: Performance of NFTyping.typeBindings: time 0.04874/1.705, allocations: 17.62 MB / 1.942 GB, free: 4.289 MB / 1.42 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0345/1.739, allocations: 15.03 MB / 1.956 GB, free: 5.309 MB / 1.436 GB
Notification: Performance of NFFlatten.flatten: time 0.1014/1.841, allocations: 81.03 MB / 2.036 GB, free: 4.133 MB / 1.514 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.03807/1.879, allocations: 23.07 MB / 2.058 GB, free: 12.88 MB / 1.545 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.06134/1.94, allocations: 33.9 MB / 2.091 GB, free: 10.94 MB / 1.576 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.06324/2.003, allocations: 28 MB / 2.119 GB, free: 15.59 MB / 1.607 GB
Notification: Performance of NFPackage.collectConstants: time 0.01582/2.019, allocations: 5.395 MB / 2.124 GB, free: 10.2 MB / 1.607 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.04114/2.06, allocations: 17.71 MB / 2.141 GB, free: 8.469 MB / 1.623 GB
Notification: Performance of NFScalarize.scalarize: time 0.02516/2.085, allocations: 16.45 MB / 2.157 GB, free: 7.977 MB / 1.639 GB
Notification: Performance of NFVerifyModel.verify: time 0.04489/2.13, allocations: 24.55 MB / 2.181 GB, free: 15.32 MB / 1.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.1287/2.259, allocations: 91.64 MB / 2.271 GB, free: 3.402 MB / 1.748 GB
Notification: Performance of FrontEnd - DAE generated: time 6.031e-06/2.259, allocations: 3.938 kB / 2.271 GB, free: 3.398 MB / 1.748 GB
Notification: Performance of FrontEnd: time 1.392e-06/2.259, allocations: 0 / 2.271 GB, free: 3.398 MB / 1.748 GB
Notification: Performance of Transformations before backend: time 0.00181/2.261, allocations: 0 / 2.271 GB, free: 3.398 MB / 1.748 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 4176
 * Number of variables: 4176
Notification: Performance of Generate backend data structure: time 1.249/3.509, allocations: 51.67 MB / 2.321 GB, free: 0.6201 GB / 1.748 GB
Notification: Performance of prepare preOptimizeDAE: time 3.683e-05/3.509, allocations: 7.938 kB / 2.321 GB, free: 0.6201 GB / 1.748 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06891/3.578, allocations: 13.46 MB / 2.334 GB, free: 0.614 GB / 1.748 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.09741/3.676, allocations: 61.82 MB / 2.395 GB, free: 0.5637 GB / 1.748 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001548/3.677, allocations: 2.14 MB / 2.397 GB, free: 0.5614 GB / 1.748 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00707/3.684, allocations: 1.895 MB / 2.399 GB, free: 0.5596 GB / 1.748 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.06722/3.752, allocations: 29.56 MB / 2.427 GB, free: 0.53 GB / 1.748 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0007001/3.752, allocations: 44.36 kB / 2.428 GB, free: 0.5299 GB / 1.748 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00532/3.758, allocations: 0.6725 MB / 2.428 GB, free: 0.5293 GB / 1.748 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004183/3.758, allocations: 0.5998 MB / 2.429 GB, free: 0.5287 GB / 1.748 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.06311/3.821, allocations: 30.4 MB / 2.458 GB, free: 0.499 GB / 1.748 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.2238/4.045, allocations: 155.5 MB / 2.61 GB, free: 351.6 MB / 1.748 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03379/4.079, allocations: 19.07 MB / 2.629 GB, free: 332.6 MB / 1.748 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.02091/4.1, allocations: 11.55 MB / 2.64 GB, free: 321 MB / 1.748 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.09159/4.191, allocations: 52.94 MB / 2.692 GB, free: 268 MB / 1.748 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02284/4.214, allocations: 12.18 MB / 2.704 GB, free: 255.7 MB / 1.748 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01409/4.228, allocations: 1.361 MB / 2.705 GB, free: 254.4 MB / 1.748 GB
Notification: Performance of pre-optimization done (n=1102): time 2.785e-05/4.228, allocations: 4 kB / 2.705 GB, free: 254.4 MB / 1.748 GB
Notification: Performance of matching and sorting (n=1108): time 0.1759/4.404, allocations: 69.65 MB / 2.773 GB, free: 184.4 MB / 1.748 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003345/4.404, allocations: 467.8 kB / 2.774 GB, free: 183.9 MB / 1.748 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.108/4.512, allocations: 64.71 MB / 2.837 GB, free: 119.4 MB / 1.748 GB
Notification: Performance of collectPreVariables (initialization): time 0.004735/4.517, allocations: 393.7 kB / 2.837 GB, free: 119 MB / 1.748 GB
Notification: Performance of collectInitialEqns (initialization): time 0.02294/4.54, allocations: 16.99 MB / 2.854 GB, free: 102 MB / 1.748 GB
Notification: Performance of collectInitialBindings (initialization): time 0.007008/4.547, allocations: 3.979 MB / 2.858 GB, free: 97.96 MB / 1.748 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02097/4.568, allocations: 7.271 MB / 2.865 GB, free: 90.67 MB / 1.748 GB
Notification: Performance of setup shared object (initialization): time 4.265e-05/4.568, allocations: 305.1 kB / 2.865 GB, free: 90.37 MB / 1.748 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04357/4.612, allocations: 38.74 MB / 2.903 GB, free: 51.6 MB / 1.748 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.04832/4.66, allocations: 45.22 MB / 2.947 GB, free: 3.961 MB / 1.748 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: hvac.heaCoi.vol.dynBal.medium.p = hvac.heaCoi.vol.dynBal.p_start (weaDat.pAtmSel.p = hvac.heaCoi.vol.dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: hvac.chi.vol1.dynBal.medium.p = hvac.chi.vol1.dynBal.p_start (weaDat.pAtmSel.p = hvac.chi.vol1.dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: zon.roo.air.vol.dynBal.medium.p = zon.roo.air.vol.dynBal.p_start (weaDat.pAtmSel.p = zon.roo.air.vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 1.623/6.283, allocations: 364.7 MB / 3.303 GB, free: 306 MB / 1.748 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001202/6.283, allocations: 48 kB / 3.303 GB, free: 306 MB / 1.748 GB
Notification: Performance of matching and sorting (n=3216) (initialization): time 0.1272/6.41, allocations: 92.22 MB / 3.393 GB, free: 215.7 MB / 1.748 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003807/6.411, allocations: 256.4 kB / 3.394 GB, free: 215.4 MB / 1.748 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.09039/6.501, allocations: 65.18 MB / 3.457 GB, free: 150.1 MB / 1.748 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01386/6.515, allocations: 4.946 MB / 3.462 GB, free: 145.1 MB / 1.748 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02281/6.538, allocations: 5.039 MB / 3.467 GB, free: 140.1 MB / 1.748 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03765/6.575, allocations: 43.03 MB / 3.509 GB, free: 95.9 MB / 1.748 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03647/6.612, allocations: 3.353 MB / 3.512 GB, free: 92.57 MB / 1.748 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005349/6.617, allocations: 0.9058 MB / 3.513 GB, free: 91.66 MB / 1.748 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization system:
         hvac.heaCoi.vol.dynBal.medium.p = hvac.heaCoi.vol.dynBal.p_start
         hvac.chi.vol1.dynBal.medium.p = hvac.chi.vol1.dynBal.p_start
         zon.roo.air.vol.dynBal.medium.p = zon.roo.air.vol.dynBal.p_start.
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.06475/6.682, allocations: 55.41 MB / 3.567 GB, free: 36.23 MB / 1.748 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.7352/7.417, allocations: 45.16 MB / 3.611 GB, free: 0.5744 GB / 1.764 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: hvac.heaCoi.vol.dynBal.medium.p = hvac.heaCoi.vol.dynBal.p_start (weaDat.pAtmSel.p = hvac.heaCoi.vol.dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: hvac.chi.vol1.dynBal.medium.p = hvac.chi.vol1.dynBal.p_start (weaDat.pAtmSel.p = hvac.chi.vol1.dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: zon.roo.air.vol.dynBal.medium.p = zon.roo.air.vol.dynBal.p_start (weaDat.pAtmSel.p = zon.roo.air.vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.9986/8.416, allocations: 364.2 MB / 3.967 GB, free: 307.7 MB / 1.764 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.000387/8.416, allocations: 51.98 kB / 3.967 GB, free: 307.7 MB / 1.764 GB
Notification: Performance of matching and sorting (n=3216) (initialization_lambda0): time 0.1352/8.551, allocations: 92.86 MB / 4.058 GB, free: 216.8 MB / 1.764 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0005258/8.552, allocations: 259.3 kB / 4.058 GB, free: 216.5 MB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.09477/8.646, allocations: 65.7 MB / 4.122 GB, free: 150.7 MB / 1.764 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0133/8.66, allocations: 4.746 MB / 4.127 GB, free: 145.9 MB / 1.764 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02289/8.683, allocations: 5.027 MB / 4.132 GB, free: 140.9 MB / 1.764 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04954/8.732, allocations: 54.3 MB / 4.185 GB, free: 85.46 MB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.037/8.769, allocations: 3.375 MB / 4.188 GB, free: 82.1 MB / 1.764 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005191/8.774, allocations: 0.9059 MB / 4.189 GB, free: 81.2 MB / 1.764 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         hvac.heaCoi.vol.dynBal.medium.p = hvac.heaCoi.vol.dynBal.p_start
         hvac.chi.vol1.dynBal.medium.p = hvac.chi.vol1.dynBal.p_start
         zon.roo.air.vol.dynBal.medium.p = zon.roo.air.vol.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 736
 * Number of states: 0 ()
 * Number of discrete variables: 166 (plo.ploTOut.timeConverted,$PRE.plo.ploTOut.timeConverted,$PRE.TSetRooCoo.tab.nextTimeEvent,$whenCondition24,$PRE.TSetRooHea.tab.nextTimeEvent,$whenCondition23,$PRE.zon.intLoad.tab.nextTimeEvent,$whenCondition11,plo.ploTOut.sampleTrigger,plo.ploTOut.tActivateLast,$PRE.plo.ploTOut.tActivateLast,plo.ploTOut.active,$whenCondition27,plo.scaPFan.sampleTrigger,plo.scaPFan.tActivateLast,$PRE.plo.scaPFan.tActivateLast,plo.scaPFan.active,$whenCondition30,plo.scaPFan.activate_internal,plo.ploTOut.activate_internal,hvac.chi.tru.y,EPum.local_reset,ECoo.local_reset,EHea.local_reset,EFan.local_reset,hvac.cooCoi.flowRegime_nominal,hvac.cooCoi.flowRegime,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,zon.roo.solRadExc.is_flo[11],zon.roo.solRadExc.is_flo[10],zon.roo.solRadExc.is_flo[9],zon.roo.solRadExc.is_flo[8],zon.roo.solRadExc.is_flo[7],zon.roo.solRadExc.is_flo[6],zon.roo.solRadExc.is_flo[5],zon.roo.solRadExc.is_flo[4],zon.roo.solRadExc.is_flo[3],zon.roo.solRadExc.is_flo[2],zon.roo.solRadExc.is_flo[1],$PRE.plo.scaTRoo.tActivateLast,$PRE.plo.scaEco.tActivateLast,$PRE.plo.plotConfiguration.tActivateLast,$PRE.occSch.tNext,$PRE.occSch.tNonOcc,$PRE.occSch.tOcc,$PRE.occSch.occupied,$PRE.con.heaOnTRoo.greHys.y,$PRE.con.chiOnTRoo.greHys.y,$PRE.con.conEco.pre1.u,$PRE.con.conEco.hysTMix.y,$PRE.con.conEco.truFalHolTMix.entryTimeFalse,$PRE.con.conEco.truFalHolTMix.entryTimeTrue,$PRE.con.conEco.truFalHolTMix.not_y,$PRE.con.conEco.truFalHolTMix.not_u,$PRE.con.conEco.truFalHolTMix.y,$PRE.con.conEco.truFalHolTMix.u,$PRE.con.conEco.truFalHolCooPot.entryTimeFalse,$PRE.con.conEco.truFalHolCooPot.entryTimeTrue,$PRE.con.conEco.truFalHolCooPot.not_y,$PRE.con.conEco.truFalHolCooPot.not_u,$PRE.con.conEco.truFalHolCooPot.y,$PRE.con.conEco.truFalHolCooPot.u,$PRE.con.conEco.hysCooPot.y,$PRE.con.conEco.hysChiPla.y,plo.scaTRoo.sampleTrigger,plo.scaTRoo.tActivateLast,plo.scaTRoo.active,plo.scaEco.sampleTrigger,plo.scaEco.tActivateLast,plo.scaEco.active,plo.plotConfiguration.tActivateLast,plo.plotConfiguration.active,occSch.tNext,occSch.tNonOcc,occSch.tOcc,occSch.occupied,TSetRooCoo.tab.nextTimeEventScaled,$PRE.TSetRooCoo.tab.nextTimeEventScaled,TSetRooCoo.tab.nextTimeEvent,TSetRooHea.tab.nextTimeEventScaled,$PRE.TSetRooHea.tab.nextTimeEventScaled,TSetRooHea.tab.nextTimeEvent,con.heaOnTRoo.greHys.y,con.chiOnTRoo.greHys.y,con.orFan.uTemp[2],con.orFan.y,con.conEco.pre1.u,con.conEco.hysTMix.y,con.conEco.truFalHolTMix.entryTimeFalse,con.conEco.truFalHolTMix.entryTimeTrue,con.conEco.truFalHolTMix.not_y,con.conEco.truFalHolTMix.not_u,con.conEco.truFalHolTMix.y,con.conEco.truFalHolTMix.u,con.conEco.truFalHolCooPot.entryTimeFalse,con.conEco.truFalHolCooPot.entryTimeTrue,con.conEco.truFalHolCooPot.not_y,con.conEco.truFalHolCooPot.not_u,con.conEco.truFalHolCooPot.y,con.conEco.truFalHolCooPot.u,con.conEco.hysCooPot.y,con.conEco.not1.y,con.conEco.yCoiSta,con.conEco.hysChiPla.y,con.chiOn,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,zon.greThr.y,zon.intLoad.tab.nextTimeEventScaled,$PRE.zon.intLoad.tab.nextTimeEventScaled,zon.intLoad.tab.nextTimeEvent,zon.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,$PRE.zon.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,zon.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,$PRE.zon.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[4].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDifTil[4].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[3].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDifTil[3].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[2].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDifTil[2].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[1].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDifTil[1].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[4].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDirTil[4].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[3].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDirTil[3].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[2].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDirTil[2].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[1].incAng.decAng.tNext,$PRE.zon.roo.bouConExt.HDirTil[1].incAng.decAng.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition12,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition20,$whenCondition21,$whenCondition22,$whenCondition25,$whenCondition26,$whenCondition28,$whenCondition29,$whenCondition31)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2289):
 * Single equations (assignments): 2227
 * Array equations: 43
 * Algorithm blocks: 2
 * Record equations: 3
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 14
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 11 systems
   {(18,37,16.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(1,16), (12,43), (1,14)}
Notification: Performance of prepare postOptimizeDAE: time 0.00865/8.783, allocations: 1.588 MB / 4.19 GB, free: 79.69 MB / 1.764 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01065/8.794, allocations: 3.39 MB / 4.194 GB, free: 78.21 MB / 1.764 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0622/8.856, allocations: 28.68 MB / 4.222 GB, free: 49.47 MB / 1.764 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.164e-05/8.856, allocations: 120 kB / 4.222 GB, free: 49.36 MB / 1.764 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.89e-05/8.856, allocations: 15.94 kB / 4.222 GB, free: 49.34 MB / 1.764 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.05213/8.908, allocations: 23.15 MB / 4.245 GB, free: 26.04 MB / 1.764 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.8237/9.732, allocations: 70.71 MB / 4.314 GB, free: 0.5681 GB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001012/9.732, allocations: 9.062 kB / 4.314 GB, free: 0.5681 GB / 1.764 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01532/9.747, allocations: 2.755 MB / 4.316 GB, free: 0.568 GB / 1.764 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.009173/9.756, allocations: 3.251 MB / 4.319 GB, free: 0.5672 GB / 1.764 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003158/9.76, allocations: 407.9 kB / 4.32 GB, free: 0.567 GB / 1.764 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03627/9.796, allocations: 45.62 MB / 4.364 GB, free: 0.5374 GB / 1.764 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.494e-06/9.796, allocations: 19.41 kB / 4.364 GB, free: 0.5374 GB / 1.764 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.08403/9.88, allocations: 41.88 MB / 4.405 GB, free: 0.5189 GB / 1.764 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02691/9.907, allocations: 9.611 MB / 4.415 GB, free: 0.5142 GB / 1.764 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01339/9.92, allocations: 356 kB / 4.415 GB, free: 0.5139 GB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02922/9.949, allocations: 2.289 MB / 4.417 GB, free: 0.5117 GB / 1.764 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003452/9.953, allocations: 0.8488 MB / 4.418 GB, free: 0.5109 GB / 1.764 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001871/9.955, allocations: 447.7 kB / 4.419 GB, free: 0.5104 GB / 1.764 GB
Notification: Performance of sorting global known variables: time 0.06757/10.02, allocations: 44.68 MB / 4.462 GB, free: 478.2 MB / 1.764 GB
Notification: Performance of sort global known variables: time 3.71e-07/10.02, allocations: 3.344 kB / 4.462 GB, free: 478.2 MB / 1.764 GB
Notification: Performance of remove unused functions: time 0.06883/10.09, allocations: 19.38 MB / 4.481 GB, free: 459 MB / 1.764 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 30
 * Number of states: 70 (hvac.heaCoi.vol.dynBal.medium.Xi[1],hvac.heaCoi.vol.dynBal.U,hvac.heaCoi.vol.dynBal.mC[1],hvac.chi.vol1.dynBal.medium.Xi[1],hvac.chi.vol1.dynBal.U,hvac.chi.vol1.dynBal.mC[1],hvac.chi.vol2.dynBal.U,zon.soi.T[1],zon.soi.T[2],zon.soi.T[3],zon.soi.T[4],zon.soi.T[5],zon.soi.T[6],zon.soi.T[7],zon.soi.T[8],zon.soi.T[9],zon.soi.T[10],zon.soi.T[11],zon.soi.T[12],zon.soi.T[13],zon.soi.T[14],zon.soi.T[15],zon.soi.T[16],zon.soi.T[17],zon.soi.T[18],zon.roo.conExt[1].opa.lay[1].T[1],zon.roo.conExt[1].opa.lay[1].T[2],zon.roo.conExt[1].opa.lay[2].T[1],zon.roo.conExt[1].opa.lay[2].T[2],zon.roo.conExt[1].opa.lay[2].T[3],zon.roo.conExt[1].opa.lay[2].T[4],zon.roo.conExt[1].opa.lay[3].T[1],zon.roo.conExt[1].opa.lay[3].T[2],zon.roo.conExt[2].opa.lay[1].T[1],zon.roo.conExt[2].opa.lay[1].T[2],zon.roo.conExt[2].opa.lay[2].T[1],zon.roo.conExt[2].opa.lay[2].T[2],zon.roo.conExt[2].opa.lay[3].T[1],zon.roo.conExt[2].opa.lay[3].T[2],zon.roo.conExt[3].opa.lay[1].T[1],zon.roo.conExt[3].opa.lay[1].T[2],zon.roo.conExt[3].opa.lay[2].T[1],zon.roo.conExt[3].opa.lay[2].T[2],zon.roo.conExt[3].opa.lay[3].T[1],zon.roo.conExt[3].opa.lay[3].T[2],zon.roo.conExt[4].opa.lay[1].T[1],zon.roo.conExt[4].opa.lay[1].T[2],zon.roo.conExt[4].opa.lay[2].T[1],zon.roo.conExt[4].opa.lay[2].T[2],zon.roo.conExt[4].opa.lay[3].T[1],zon.roo.conExt[4].opa.lay[3].T[2],zon.roo.conExtWin[1].opa.lay[1].T[1],zon.roo.conExtWin[1].opa.lay[1].T[2],zon.roo.conExtWin[1].opa.lay[2].T[1],zon.roo.conExtWin[1].opa.lay[2].T[2],zon.roo.conExtWin[1].opa.lay[3].T[1],zon.roo.conExtWin[1].opa.lay[3].T[2],zon.roo.conExtWin[1].win.capGla.TUns,zon.roo.conExtWin[1].win.capFra.T,zon.roo.conBou[1].opa.lay[2].T[1],zon.roo.conBou[1].opa.lay[2].T[2],zon.roo.conBou[1].opa.lay[2].T[3],zon.roo.conBou[1].opa.lay[2].T[4],zon.roo.air.vol.dynBal.medium.Xi[1],zon.roo.air.vol.dynBal.U,zon.roo.air.vol.dynBal.mC[1],EFan.y,EHea.y,ECoo.y,EPum.y)
 * Number of discrete variables: 95 (plo.scaTRoo.sampleTrigger,plo.scaEco.sampleTrigger,plo.plotConfiguration.active,con.chiOn,plo.ploTOut.sampleTrigger,plo.scaPFan.sampleTrigger,$whenCondition31,$whenCondition30,$whenCondition29,$whenCondition28,$whenCondition27,$whenCondition26,$whenCondition25,$whenCondition24,$whenCondition23,$whenCondition22,$whenCondition21,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,zon.roo.bouConExt.HDirTil[1].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[2].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[3].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[4].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[1].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[2].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[3].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[4].incAng.decAng.tNext,zon.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,zon.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,zon.intLoad.tab.nextTimeEvent,zon.intLoad.tab.nextTimeEventScaled,zon.greThr.y,weaDat.locTim.tNext,weaDat.conTimMin.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext,con.conEco.hysChiPla.y,con.conEco.yCoiSta,con.conEco.not1.y,con.conEco.hysCooPot.y,con.conEco.truFalHolCooPot.u,con.conEco.truFalHolCooPot.y,con.conEco.truFalHolCooPot.not_u,con.conEco.truFalHolCooPot.not_y,con.conEco.truFalHolCooPot.entryTimeTrue,con.conEco.truFalHolCooPot.entryTimeFalse,con.conEco.truFalHolTMix.u,con.conEco.truFalHolTMix.y,con.conEco.truFalHolTMix.not_u,con.conEco.truFalHolTMix.not_y,con.conEco.truFalHolTMix.entryTimeTrue,con.conEco.truFalHolTMix.entryTimeFalse,con.conEco.hysTMix.y,con.conEco.pre1.u,con.orFan.y,con.orFan.uTemp[2],con.chiOnTRoo.greHys.y,con.heaOnTRoo.greHys.y,TSetRooHea.tab.nextTimeEvent,TSetRooHea.tab.nextTimeEventScaled,TSetRooCoo.tab.nextTimeEvent,TSetRooCoo.tab.nextTimeEventScaled,occSch.occupied,occSch.tOcc,occSch.tNonOcc,occSch.tNext,plo.plotConfiguration.tActivateLast,plo.ploTOut.active,plo.ploTOut.tActivateLast,plo.ploTOut.timeConverted,plo.scaEco.active,plo.scaEco.tActivateLast,plo.scaPFan.active,plo.scaPFan.tActivateLast,plo.scaTRoo.active,plo.scaTRoo.tActivateLast)
 * Number of discrete states: 40 (occSch.tNext,TSetRooCoo.tab.nextTimeEvent,TSetRooHea.tab.nextTimeEvent,con.conEco.truFalHolTMix.entryTimeFalse,con.conEco.truFalHolTMix.entryTimeTrue,con.conEco.truFalHolCooPot.entryTimeFalse,con.conEco.truFalHolCooPot.entryTimeTrue,zon.intLoad.tab.nextTimeEvent,zon.roo.bouConExt.HDirTil[1].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[2].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[3].incAng.decAng.tNext,zon.roo.bouConExt.HDirTil[4].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[1].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[2].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[3].incAng.decAng.tNext,zon.roo.bouConExt.HDifTil[4].incAng.decAng.tNext,zon.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,zon.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,weaDat.locTim.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext,weaDat.conTimMin.tNext,con.conEco.pre1.u,TSetRooCoo.tab.nextTimeEventScaled,con.chiOnTRoo.greHys.y,TSetRooHea.tab.nextTimeEventScaled,con.heaOnTRoo.greHys.y,con.conEco.hysCooPot.y,con.conEco.truFalHolCooPot.u,con.conEco.truFalHolCooPot.not_u,con.conEco.truFalHolCooPot.y,con.conEco.truFalHolCooPot.not_y,con.conEco.hysTMix.y,con.conEco.truFalHolTMix.u,con.conEco.truFalHolTMix.not_u,con.conEco.truFalHolTMix.y,con.conEco.truFalHolTMix.not_y,con.conEco.hysChiPla.y,zon.intLoad.tab.nextTimeEventScaled)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (1043):
 * Single equations (assignments): 989
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 4
 * When equations: 34
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 15
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 13 systems
   {(9,26,85.2%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,18,100.0%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,17), (1,16)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.009536/10.1, allocations: 2.916 MB / 4.484 GB, free: 457.1 MB / 1.764 GB
Notification: Performance of simCode: created initialization part: time 0.1268/10.23, allocations: 99.57 MB / 4.581 GB, free: 357.6 MB / 1.764 GB
Notification: Performance of simCode: created event and clocks part: time 1.669e-05/10.23, allocations: 13.56 kB / 4.581 GB, free: 357.6 MB / 1.764 GB
Notification: Performance of simCode: created simulation system equations: time 0.02743/10.25, allocations: 14.2 MB / 4.595 GB, free: 343.3 MB / 1.764 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.07468/10.33, allocations: 12.99 MB / 4.608 GB, free: 330.9 MB / 1.764 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1509/10.48, allocations: 93.39 MB / 4.699 GB, free: 237.8 MB / 1.764 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.04472/10.53, allocations: 25.02 MB / 4.723 GB, free: 212.7 MB / 1.764 GB
Notification: Performance of simCode: alias equations: time 0.05224/10.58, allocations: 17.04 MB / 4.74 GB, free: 195.6 MB / 1.764 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01344/10.59, allocations: 2.038 MB / 4.742 GB, free: 193.6 MB / 1.764 GB
Notification: Performance of SimCode: time 1.533e-06/10.59, allocations: 0 / 4.742 GB, free: 193.6 MB / 1.764 GB
Notification: Performance of Templates: time 2.214/12.8, allocations: 0.6553 GB / 5.397 GB, free: 156 MB / 1.764 GB
"
[Timeout remaining time 287]
make -j1 -f Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe ; mkfifo Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe ; head -c 1048576 < Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe >> ../files/Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.sim & ./Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe 2>&1) [Timeout 800]
diffSimulationResults("Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Utilities_Plotters_Examples_SingleZoneVAV.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 41.21552852727473]