Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.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.001861/0.001861, allocations: 108.9 kB / 16.46 MB, free: 6.453 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.002794/0.002794, allocations: 216.2 kB / 19.43 MB, free: 3.625 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.49/1.49, allocations: 230.6 MB / 252.8 MB, free: 8.949 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.266/3.266, allocations: 0.49 GB / 0.7916 GB, free: 33.23 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Buildings.Utilities.Plotters.Examples.SingleZoneVAV,tolerance=1e-06,outputFormat="mat",numberOfIntervals=120,variableFilter="time|hvac.PFan",fileNamePrefix="Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV")
translateModel(Buildings.Utilities.Plotters.Examples.SingleZoneVAV,tolerance=1e-06,outputFormat="mat",numberOfIntervals=120,variableFilter="time|hvac.PFan",fileNamePrefix="Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV") [Timeout 300]
"Notification: Buildings requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.001964/0.001964, allocations: 72.48 kB / 1.149 GB, free: 26.02 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3482/0.3501, allocations: 176.1 MB / 1.321 GB, free: 2.75 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Utilities.Plotters.Examples.SingleZoneVAV): time 1.409/1.759, allocations: 0.5005 GB / 1.822 GB, free: 5.684 MB / 1.311 GB
Notification: Performance of NFInst.instExpressions: time 0.1785/1.938, allocations: 97.73 MB / 1.917 GB, free: 7.77 MB / 1.404 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.03366/1.971, allocations: 0.8254 MB / 1.918 GB, free: 6.938 MB / 1.404 GB
Notification: Performance of NFTyping.typeComponents: time 0.03735/2.009, allocations: 12.46 MB / 1.93 GB, free: 10.41 MB / 1.42 GB
Notification: Performance of NFTyping.typeBindings: time 0.05653/2.065, allocations: 17.31 MB / 1.947 GB, free: 9.039 MB / 1.436 GB
Notification: Performance of NFTyping.typeClassSections: time 0.03769/2.103, allocations: 14.44 MB / 1.961 GB, free: 10.64 MB / 1.451 GB
Notification: Performance of NFFlatten.flatten: time 0.1062/2.209, allocations: 79.65 MB / 2.039 GB, free: 10.85 MB / 1.529 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.04982/2.259, allocations: 26.85 MB / 2.065 GB, free: 15.82 MB / 1.561 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.06788/2.327, allocations: 33.87 MB / 2.098 GB, free: 13.89 MB / 1.592 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.06754/2.394, allocations: 27.63 MB / 2.125 GB, free: 2.914 MB / 1.607 GB
Notification: Performance of NFPackage.collectConstants: time 0.01704/2.411, allocations: 5.398 MB / 2.13 GB, free: 13.51 MB / 1.623 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.04513/2.456, allocations: 17.19 MB / 2.147 GB, free: 12.3 MB / 1.639 GB
Notification: Performance of NFScalarize.scalarize: time 0.02771/2.484, allocations: 16.36 MB / 2.163 GB, free: 11.91 MB / 1.654 GB
Notification: Performance of NFVerifyModel.verify: time 0.05715/2.541, allocations: 24.56 MB / 2.187 GB, free: 3.246 MB / 1.67 GB
Notification: Performance of NFConvertDAE.convert: time 1.846/4.387, allocations: 91.7 MB / 2.277 GB, free: 177 MB / 1.748 GB
Notification: Performance of FrontEnd - DAE generated: time 6.422e-06/4.387, allocations: 0 / 2.277 GB, free: 177 MB / 1.748 GB
Notification: Performance of FrontEnd: time 2.876e-06/4.387, allocations: 2.906 kB / 2.277 GB, free: 177 MB / 1.748 GB
Notification: Performance of Transformations before backend: time 0.002183/4.389, allocations: 3.812 kB / 2.277 GB, free: 177 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 0.1644/4.554, allocations: 51.59 MB / 2.327 GB, free: 158.3 MB / 1.748 GB
Notification: Performance of prepare preOptimizeDAE: time 4.148e-05/4.554, allocations: 11.53 kB / 2.327 GB, free: 158.3 MB / 1.748 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.08716/4.641, allocations: 14.35 MB / 2.341 GB, free: 155.8 MB / 1.748 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1244/4.765, allocations: 61.52 MB / 2.401 GB, free: 149.4 MB / 1.748 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002475/4.768, allocations: 2.14 MB / 2.403 GB, free: 148.6 MB / 1.748 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01012/4.778, allocations: 1.901 MB / 2.405 GB, free: 148.6 MB / 1.748 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.08386/4.862, allocations: 29.55 MB / 2.434 GB, free: 145.9 MB / 1.748 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0008433/4.862, allocations: 40.11 kB / 2.434 GB, free: 145.9 MB / 1.748 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.007115/4.87, allocations: 0.6801 MB / 2.435 GB, free: 145.9 MB / 1.748 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005424/4.87, allocations: 0.591 MB / 2.435 GB, free: 145.8 MB / 1.748 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.07963/4.95, allocations: 30.41 MB / 2.465 GB, free: 145.3 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 removeSimpleEquations (simulation): time 0.2787/5.228, allocations: 155.1 MB / 2.617 GB, free: 11.05 MB / 1.764 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.05037/5.279, allocations: 19.22 MB / 2.635 GB, free: 7.82 MB / 1.779 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.03187/5.311, allocations: 11.57 MB / 2.647 GB, free: 12.23 MB / 1.795 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.144/5.455, allocations: 54.08 MB / 2.699 GB, free: 6.086 MB / 1.842 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.03889/5.494, allocations: 12.19 MB / 2.711 GB, free: 9.816 MB / 1.858 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02385/5.517, allocations: 1.356 MB / 2.713 GB, free: 8.453 MB / 1.858 GB
Notification: Performance of pre-optimization done (n=1102): time 6.328e-05/5.517, allocations: 0 / 2.713 GB, free: 8.453 MB / 1.858 GB
Notification: Performance of matching and sorting (n=1108): time 0.2591/5.777, allocations: 70.8 MB / 2.782 GB, free: 1.176 MB / 1.92 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0005442/5.777, allocations: 474.4 kB / 2.782 GB, free: 0.6562 MB / 1.92 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1831/5.96, allocations: 64.77 MB / 2.846 GB, free: 104 kB / 1.983 GB
Notification: Performance of collectPreVariables (initialization): time 0.007621/5.968, allocations: 390 kB / 2.846 GB, free: 15.72 MB / 1.998 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03769/6.006, allocations: 17 MB / 2.862 GB, free: 14.77 MB / 2.014 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0107/6.016, allocations: 3.988 MB / 2.866 GB, free: 10.84 MB / 2.014 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.03325/6.05, allocations: 7.266 MB / 2.873 GB, free: 3.586 MB / 2.014 GB
Notification: Performance of setup shared object (initialization): time 0.0001762/6.05, allocations: 317.1 kB / 2.874 GB, free: 3.273 MB / 2.014 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.07296/6.123, allocations: 38.73 MB / 2.912 GB, free: 12.52 MB / 2.061 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.07632/6.199, allocations: 45.19 MB / 2.956 GB, free: 12.99 MB / 2.108 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 2.019/8.218, allocations: 364.7 MB / 3.312 GB, free: 0.8153 GB / 2.186 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000211/8.219, allocations: 44 kB / 3.312 GB, free: 0.8153 GB / 2.186 GB
Notification: Performance of matching and sorting (n=3216) (initialization): time 0.1412/8.36, allocations: 91.96 MB / 3.402 GB, free: 0.7274 GB / 2.186 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004329/8.36, allocations: 251.3 kB / 3.402 GB, free: 0.7272 GB / 2.186 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1048/8.465, allocations: 65.01 MB / 3.465 GB, free: 0.6636 GB / 2.186 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01502/8.48, allocations: 4.944 MB / 3.47 GB, free: 0.6587 GB / 2.186 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02661/8.507, allocations: 5.042 MB / 3.475 GB, free: 0.6538 GB / 2.186 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04138/8.548, allocations: 44.24 MB / 3.518 GB, free: 0.6091 GB / 2.186 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04334/8.591, allocations: 3.352 MB / 3.522 GB, free: 0.6059 GB / 2.186 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.006319/8.598, allocations: 0.9058 MB / 3.523 GB, free: 0.605 GB / 2.186 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.07975/8.677, allocations: 55.41 MB / 3.577 GB, free: 0.5509 GB / 2.186 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.05599/8.733, allocations: 45.16 MB / 3.621 GB, free: 0.5044 GB / 2.186 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 1.085/9.818, allocations: 364.2 MB / 3.976 GB, free: 150.1 MB / 2.186 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0002268/9.818, allocations: 44 kB / 3.976 GB, free: 150 MB / 2.186 GB
Notification: Performance of matching and sorting (n=3216) (initialization_lambda0): time 0.161/9.979, allocations: 92.6 MB / 4.067 GB, free: 59.29 MB / 2.186 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004951/9.98, allocations: 251.3 kB / 4.067 GB, free: 59.04 MB / 2.186 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.046/11.03, allocations: 65.54 MB / 4.131 GB, free: 0.9616 GB / 2.186 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01466/11.04, allocations: 4.759 MB / 4.136 GB, free: 0.9606 GB / 2.186 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02772/11.07, allocations: 5.025 MB / 4.141 GB, free: 0.9604 GB / 2.186 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05667/11.12, allocations: 55.6 MB / 4.195 GB, free: 0.9268 GB / 2.186 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04405/11.17, allocations: 3.369 MB / 4.198 GB, free: 0.9265 GB / 2.186 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.009246/11.18, allocations: 0.9126 MB / 4.199 GB, free: 0.9264 GB / 2.186 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.01355/11.19, allocations: 1.588 MB / 4.201 GB, free: 0.9254 GB / 2.186 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01464/11.21, allocations: 3.403 MB / 4.204 GB, free: 0.9254 GB / 2.186 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0882/11.29, allocations: 28.67 MB / 4.232 GB, free: 0.9154 GB / 2.186 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001303/11.29, allocations: 109.8 kB / 4.232 GB, free: 0.9154 GB / 2.186 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001091/11.29, allocations: 13.94 kB / 4.232 GB, free: 0.9154 GB / 2.186 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.06217/11.36, allocations: 23.15 MB / 4.255 GB, free: 0.9074 GB / 2.186 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.1554/11.51, allocations: 70.39 MB / 4.323 GB, free: 0.8466 GB / 2.186 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001306/11.51, allocations: 8 kB / 4.323 GB, free: 0.8466 GB / 2.186 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01656/11.53, allocations: 2.757 MB / 4.326 GB, free: 0.8439 GB / 2.186 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01055/11.54, allocations: 3.247 MB / 4.329 GB, free: 0.8407 GB / 2.186 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003751/11.54, allocations: 415.6 kB / 4.33 GB, free: 0.8403 GB / 2.186 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04275/11.59, allocations: 47.02 MB / 4.376 GB, free: 0.7929 GB / 2.186 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.392e-06/11.59, allocations: 11.94 kB / 4.376 GB, free: 0.7929 GB / 2.186 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.09941/11.69, allocations: 41.84 MB / 4.417 GB, free: 0.7538 GB / 2.186 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02738/11.71, allocations: 9.28 MB / 4.426 GB, free: 0.7457 GB / 2.186 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01742/11.73, allocations: 360.9 kB / 4.426 GB, free: 0.7454 GB / 2.186 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.03042/11.76, allocations: 2.292 MB / 4.428 GB, free: 0.7432 GB / 2.186 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.004333/11.76, allocations: 0.8076 MB / 4.429 GB, free: 0.7424 GB / 2.186 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.003835/11.77, allocations: 455.6 kB / 4.429 GB, free: 0.7419 GB / 2.186 GB
Notification: Performance of sorting global known variables: time 0.08549/11.85, allocations: 44.72 MB / 4.473 GB, free: 0.6985 GB / 2.186 GB
Notification: Performance of sort global known variables: time 3.11e-07/11.85, allocations: 0 / 4.473 GB, free: 0.6985 GB / 2.186 GB
Notification: Performance of remove unused functions: time 0.08247/11.94, allocations: 19.4 MB / 4.492 GB, free: 0.6797 GB / 2.186 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.hysChiPla.y,con.conEco.hysTMix.y,con.conEco.truFalHolTMix.u,con.conEco.truFalHolTMix.not_u,con.conEco.truFalHolTMix.y,con.conEco.truFalHolTMix.not_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.01176/11.95, allocations: 2.908 MB / 4.495 GB, free: 0.6778 GB / 2.186 GB
Notification: Performance of simCode: created initialization part: time 0.1487/12.1, allocations: 99.52 MB / 4.592 GB, free: 0.5803 GB / 2.186 GB
Notification: Performance of simCode: created event and clocks part: time 1.706e-05/12.1, allocations: 5.875 kB / 4.592 GB, free: 0.5803 GB / 2.186 GB
Notification: Performance of simCode: created simulation system equations: time 0.03267/12.13, allocations: 14.22 MB / 4.606 GB, free: 0.5662 GB / 2.186 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.08415/12.21, allocations: 13 MB / 4.619 GB, free: 0.5537 GB / 2.186 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1873/12.4, allocations: 92.94 MB / 4.709 GB, free: 473.9 MB / 2.186 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.05256/12.45, allocations: 25.04 MB / 4.734 GB, free: 448.8 MB / 2.186 GB
Notification: Performance of simCode: alias equations: time 0.06228/12.52, allocations: 17.08 MB / 4.751 GB, free: 431.7 MB / 2.186 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01852/12.53, allocations: 2.049 MB / 4.753 GB, free: 429.7 MB / 2.186 GB
Notification: Performance of SimCode: time 1.473e-06/12.53, allocations: 0 / 4.753 GB, free: 429.7 MB / 2.186 GB
Notification: Performance of Templates: time 2.572/15.11, allocations: 0.6517 GB / 5.404 GB, free: 0.8091 GB / 2.186 GB
"
[Timeout remaining time 285]
make -j1 -f Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe ; mkfifo Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe ; head -c 1048576 < Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe >> ../files/Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.sim & ./Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_12_Buildings.Utilities.Plotters.Examples.SingleZoneVAV_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_12.x/Buildings_Utilities_Plotters_Examples_SingleZoneVAV.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_12_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: 42.53612556029111]