Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.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.001396/0.001396, allocations: 78.09 kB / 19.76 MB, free: 1.875 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.001488/0.001488, allocations: 157.4 kB / 23.01 MB, free: 4.605 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.451/1.451, allocations: 177.2 MB / 203.4 MB, free: 8.969 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/package.mo): time 2.66/2.66, allocations: 359.1 MB / 0.6044 GB, free: 14.82 MB / 0.5762 GB
"
[Timeout remaining time 177]
Using package Buildings with version 11.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.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.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|weaDat.weaBus.TDryBul|weaDat.weaBus.HDirNor|weaDat.weaBus.HGloHor|buiZon.theZon.1|1..roo.heaPorAir.T|buiZon.theZon.1|1..EHea|buiZon.theZon.1|1..ECoo|conEco.1..VOut_flow_min|conEco.1..VOut_flow|conEco.1..yOA|conEco.1..conV_flow.y|conEco.1..uOATSup",fileNamePrefix="Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone")
translateModel(Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|weaDat.weaBus.TDryBul|weaDat.weaBus.HDirNor|weaDat.weaBus.HGloHor|buiZon.theZon.1|1..roo.heaPorAir.T|buiZon.theZon.1|1..EHea|buiZon.theZon.1|1..ECoo|conEco.1..VOut_flow_min|conEco.1..VOut_flow|conEco.1..yOA|conEco.1..conV_flow.y|conEco.1..uOATSup",fileNamePrefix="Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone") [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.002146/0.002146, allocations: 75.89 kB / 0.9515 GB, free: 12.73 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3014/0.3036, allocations: 169.9 MB / 1.117 GB, free: 2.496 MB / 1.061 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone): time 1.198/1.502, allocations: 0.5392 GB / 1.657 GB, free: 14.84 MB / 1.498 GB
Notification: Performance of NFInst.instExpressions: time 0.9819/2.484, allocations: 104.7 MB / 1.759 GB, free: 29.1 MB / 1.545 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02863/2.512, allocations: 0.9215 MB / 1.76 GB, free: 29.1 MB / 1.545 GB
Notification: Performance of NFTyping.typeComponents: time 0.03725/2.549, allocations: 13.42 MB / 1.773 GB, free: 29.02 MB / 1.545 GB
Notification: Performance of NFTyping.typeBindings: time 0.0629/2.612, allocations: 23.05 MB / 1.795 GB, free: 28.26 MB / 1.545 GB
Notification: Performance of NFTyping.typeClassSections: time 0.04272/2.655, allocations: 17.99 MB / 1.813 GB, free: 27.85 MB / 1.545 GB
Notification: Performance of NFFlatten.flatten: time 0.1107/2.766, allocations: 78.35 MB / 1.889 GB, free: 27.54 MB / 1.545 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.04945/2.815, allocations: 25.87 MB / 1.915 GB, free: 14.86 MB / 1.545 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.06031/2.876, allocations: 28.98 MB / 1.943 GB, free: 2.246 MB / 1.545 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.05435/2.93, allocations: 27.15 MB / 1.97 GB, free: 3.113 MB / 1.561 GB
Notification: Performance of NFPackage.collectConstants: time 0.02061/2.95, allocations: 4.498 MB / 1.974 GB, free: 0.5078 MB / 1.561 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.05416/3.005, allocations: 17.29 MB / 1.991 GB, free: 1.328 MB / 1.576 GB
Notification: Performance of NFScalarize.scalarize: time 0.02789/3.033, allocations: 14.05 MB / 2.005 GB, free: 3.547 MB / 1.592 GB
Notification: Performance of NFVerifyModel.verify: time 0.04941/3.082, allocations: 15.6 MB / 2.02 GB, free: 4.117 MB / 1.607 GB
Notification: Performance of NFConvertDAE.convert: time 0.1329/3.215, allocations: 80.1 MB / 2.098 GB, free: 6.203 MB / 1.686 GB
Notification: Performance of FrontEnd - DAE generated: time 6.152e-06/3.215, allocations: 4 kB / 2.098 GB, free: 6.199 MB / 1.686 GB
Notification: Performance of FrontEnd: time 1.693e-06/3.215, allocations: 0 / 2.098 GB, free: 6.199 MB / 1.686 GB
Notification: Performance of Transformations before backend: time 0.00188/3.217, allocations: 0 / 2.098 GB, free: 6.199 MB / 1.686 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3740
 * Number of variables: 3740
Notification: Performance of Generate backend data structure: time 0.1783/3.395, allocations: 55.83 MB / 2.153 GB, free: 14.42 MB / 1.748 GB
Notification: Performance of prepare preOptimizeDAE: time 4.551e-05/3.395, allocations: 8.031 kB / 2.153 GB, free: 14.41 MB / 1.748 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.07654/3.472, allocations: 14.08 MB / 2.166 GB, free: 324 kB / 1.748 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1257/3.597, allocations: 54.72 MB / 2.22 GB, free: 10.41 MB / 1.811 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002888/3.6, allocations: 2.421 MB / 2.222 GB, free: 7.73 MB / 1.811 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01017/3.61, allocations: 1.688 MB / 2.224 GB, free: 6.047 MB / 1.811 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.08757/3.698, allocations: 32.7 MB / 2.256 GB, free: 4.512 MB / 1.842 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0007017/3.699, allocations: 22.22 kB / 2.256 GB, free: 4.5 MB / 1.842 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.007092/3.706, allocations: 0.5984 MB / 2.256 GB, free: 3.906 MB / 1.842 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0006436/3.706, allocations: 0.5531 MB / 2.257 GB, free: 3.355 MB / 1.842 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.08199/3.788, allocations: 33.63 MB / 2.29 GB, free: 1.809 MB / 1.873 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.285/4.073, allocations: 115.3 MB / 2.402 GB, free: 15.43 MB / 1.998 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.05056/4.124, allocations: 21.88 MB / 2.424 GB, free: 9.562 MB / 2.014 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0311/4.155, allocations: 13.39 MB / 2.437 GB, free: 12.13 MB / 2.029 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.9494/5.104, allocations: 47.94 MB / 2.484 GB, free: 0.8739 GB / 2.029 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.04336/5.148, allocations: 15.31 MB / 2.498 GB, free: 0.8686 GB / 2.029 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01669/5.164, allocations: 1.414 MB / 2.5 GB, free: 0.8683 GB / 2.029 GB
Notification: Performance of pre-optimization done (n=1043): time 6.405e-05/5.164, allocations: 0 / 2.5 GB, free: 0.8683 GB / 2.029 GB
Notification: Performance of matching and sorting (n=1049): time 0.334/5.498, allocations: 111.7 MB / 2.609 GB, free: 0.8221 GB / 2.029 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004965/5.499, allocations: 0.8864 MB / 2.61 GB, free: 0.8213 GB / 2.029 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.114/5.613, allocations: 59.53 MB / 2.668 GB, free: 0.7743 GB / 2.029 GB
Notification: Performance of collectPreVariables (initialization): time 0.006629/5.62, allocations: 397.6 kB / 2.668 GB, free: 0.774 GB / 2.029 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03006/5.65, allocations: 13.52 MB / 2.682 GB, free: 0.7613 GB / 2.029 GB
Notification: Performance of collectInitialBindings (initialization): time 0.007411/5.657, allocations: 3.738 MB / 2.685 GB, free: 0.7577 GB / 2.029 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02564/5.683, allocations: 7.18 MB / 2.692 GB, free: 0.7507 GB / 2.029 GB
Notification: Performance of setup shared object (initialization): time 4.852e-05/5.683, allocations: 301.1 kB / 2.692 GB, free: 0.7504 GB / 2.029 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.05427/5.737, allocations: 45.73 MB / 2.737 GB, free: 0.7057 GB / 2.029 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.06115/5.798, allocations: 54.07 MB / 2.79 GB, free: 0.6508 GB / 2.029 GB
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: cooCoi[1].heaCooHum_u.vol.dynBal.medium.p = cooCoi[1].heaCooHum_u.vol.dynBal.p_start (fan[1].vol.dynBal.p_start = cooCoi[1].heaCooHum_u.vol.dynBal.p_start)
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Notification: Performance of analyzeInitialSystem (initialization): time 0.6022/6.4, allocations: 269 MB / 3.053 GB, free: 397.9 MB / 2.029 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002013/6.401, allocations: 28 kB / 3.053 GB, free: 397.9 MB / 2.029 GB
Notification: Performance of matching and sorting (n=2770) (initialization): time 0.1547/6.555, allocations: 102.8 MB / 3.153 GB, free: 295 MB / 2.029 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003812/6.556, allocations: 197.4 kB / 3.153 GB, free: 294.8 MB / 2.029 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1153/6.671, allocations: 77.38 MB / 3.229 GB, free: 217.3 MB / 2.029 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01494/6.686, allocations: 5.993 MB / 3.235 GB, free: 211.3 MB / 2.029 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02584/6.712, allocations: 5.226 MB / 3.24 GB, free: 206.1 MB / 2.029 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02142/6.733, allocations: 21.1 MB / 3.26 GB, free: 184.2 MB / 2.029 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03682/6.77, allocations: 3.662 MB / 3.264 GB, free: 180.5 MB / 2.029 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0074/6.777, allocations: 0.7418 MB / 3.265 GB, free: 179.8 MB / 2.029 GB
Warning: The initial conditions are over specified. The following 4 initial equations are redundant, so they are removed from the initialization system:
         cooCoi[1].UAFroRated.TAirIn = 293.15
         cooCoi[1].UAFroRated.TWatIn = 293.15
         cooCoi[1].heaCooHum_u.vol.dynBal.medium.p = cooCoi[1].heaCooHum_u.vol.dynBal.p_start
         fanRet[1].vol.dynBal.medium.p = fanRet[1].vol.dynBal.p_start.
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.08577/6.863, allocations: 63.15 MB / 3.326 GB, free: 116.6 MB / 2.029 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0625/6.926, allocations: 53.79 MB / 3.379 GB, free: 60.46 MB / 2.029 GB
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: cooCoi[1].heaCooHum_u.vol.dynBal.medium.p = cooCoi[1].heaCooHum_u.vol.dynBal.p_start (fan[1].vol.dynBal.p_start = cooCoi[1].heaCooHum_u.vol.dynBal.p_start)
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 1.319/8.245, allocations: 267.2 MB / 3.64 GB, free: 0.7687 GB / 2.029 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.00017/8.245, allocations: 28 kB / 3.64 GB, free: 0.7686 GB / 2.029 GB
Notification: Performance of matching and sorting (n=2770) (initialization_lambda0): time 0.1522/8.397, allocations: 104.5 MB / 3.742 GB, free: 0.668 GB / 2.029 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000334/8.397, allocations: 193.6 kB / 3.742 GB, free: 0.6678 GB / 2.029 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1111/8.508, allocations: 79.1 MB / 3.819 GB, free: 0.5904 GB / 2.029 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01351/8.522, allocations: 5.793 MB / 3.825 GB, free: 0.5847 GB / 2.029 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02561/8.548, allocations: 5.16 MB / 3.83 GB, free: 0.5797 GB / 2.029 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04637/8.594, allocations: 45.01 MB / 3.874 GB, free: 0.5351 GB / 2.029 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03486/8.629, allocations: 3.787 MB / 3.878 GB, free: 0.5314 GB / 2.029 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.006886/8.636, allocations: 0.7139 MB / 3.878 GB, free: 0.5307 GB / 2.029 GB
Warning: The initial conditions are over specified. The following 4 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         cooCoi[1].UAFroRated.TAirIn = 293.15
         cooCoi[1].UAFroRated.TWatIn = 293.15
         cooCoi[1].heaCooHum_u.vol.dynBal.medium.p = cooCoi[1].heaCooHum_u.vol.dynBal.p_start
         fanRet[1].vol.dynBal.medium.p = fanRet[1].vol.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 417
 * Number of states: 0 ()
 * Number of discrete variables: 217 (cooCoi[1].UAFroRated.isFulWet,cooCoi[1].UAFroRated.isFulDry,$PRE.modeSelector[1].start.enableFire,$PRE.modeSelector[1].t2.enableFire,$PRE.modeSelector[1].t1.enableFire,$PRE.modeSelector[1].t6.enableFire,$PRE.modeSelector[1].t5.enableFire,$PRE.modeSelector[1].t3.enableFire,$PRE.modeSelector[1].t4.enableFire,$PRE.modeSelector[1].t7.enableFire,$PRE.modeSelector[1].t9.enableFire,$PRE.modeSelector[1].t8.enableFire,modeSelector[1].t8.t_start,$PRE.modeSelector[1].t8.t_start,modeSelector[1].t9.t_start,$PRE.modeSelector[1].t9.t_start,modeSelector[1].t7.t_start,$PRE.modeSelector[1].t7.t_start,modeSelector[1].t4.t_start,$PRE.modeSelector[1].t4.t_start,modeSelector[1].t3.t_start,$PRE.modeSelector[1].t3.t_start,modeSelector[1].t5.t_start,$PRE.modeSelector[1].t5.t_start,modeSelector[1].start.t_start,$PRE.modeSelector[1].start.t_start,$PRE.TSetCoo1.nextTimeEvent,$whenCondition13,$PRE.TSetHea.nextTimeEvent,$whenCondition12,$PRE.buiZon.theZon[1,1].intLoad.nextTimeEvent,$whenCondition3,modeSelector[1].stateGraphRoot.resume,modeSelector[1].stateGraphRoot.suspend,modeSelector[1].start.condition,conEco[1].conV_flow.con1.y,vavTer[1,1].con.conCoo.con1.y,vavTer[1,1].con.conHea.con1.y,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,cooCoi[1].flowRegime_nominal,hex[1].flowRegime_nominal,buiZon.theZon[1,1].roo.solRadExc.is_flo[10],buiZon.theZon[1,1].roo.solRadExc.is_flo[9],buiZon.theZon[1,1].roo.solRadExc.is_flo[8],buiZon.theZon[1,1].roo.solRadExc.is_flo[7],buiZon.theZon[1,1].roo.solRadExc.is_flo[6],buiZon.theZon[1,1].roo.solRadExc.is_flo[5],buiZon.theZon[1,1].roo.solRadExc.is_flo[4],buiZon.theZon[1,1].roo.solRadExc.is_flo[3],buiZon.theZon[1,1].roo.solRadExc.is_flo[2],buiZon.theZon[1,1].roo.solRadExc.is_flo[1],vavTer[1,1].terHea.flowRegime_nominal,vavTer[1,1].vav.idx_sorted[13],vavTer[1,1].vav.idx_sorted[12],vavTer[1,1].vav.idx_sorted[11],vavTer[1,1].vav.idx_sorted[10],vavTer[1,1].vav.idx_sorted[9],vavTer[1,1].vav.idx_sorted[8],vavTer[1,1].vav.idx_sorted[7],vavTer[1,1].vav.idx_sorted[6],vavTer[1,1].vav.idx_sorted[5],vavTer[1,1].vav.idx_sorted[4],vavTer[1,1].vav.idx_sorted[3],vavTer[1,1].vav.idx_sorted[2],vavTer[1,1].vav.idx_sorted[1],$PRE.vavTer[1,1].con.hysWitHol.y,$PRE.modeSelector[1].initialStepWithSignal.localActive,$PRE.modeSelector[1].initialStepWithSignal.newActive,$PRE.modeSelector[1].initialStepWithSignal.oldActive,$PRE.modeSelector[1].unOccOff.localActive,$PRE.modeSelector[1].unOccOff.newActive,$PRE.modeSelector[1].unOccOff.oldActive,$PRE.modeSelector[1].unOccNigSetBac.localActive,$PRE.modeSelector[1].unOccNigSetBac.newActive,$PRE.modeSelector[1].unOccNigSetBac.oldActive,$PRE.modeSelector[1].t2.t_start,$PRE.modeSelector[1].t1.t_start,$PRE.modeSelector[1].morWarUp.localActive,$PRE.modeSelector[1].morWarUp.newActive,$PRE.modeSelector[1].morWarUp.oldActive,$PRE.modeSelector[1].t6.t_start,$PRE.modeSelector[1].occ.localActive,$PRE.modeSelector[1].occ.newActive,$PRE.modeSelector[1].occ.oldActive,$PRE.modeSelector[1].morPreCoo.localActive,$PRE.modeSelector[1].morPreCoo.newActive,$PRE.modeSelector[1].morPreCoo.oldActive,$PRE.conEco[1].hysLoc.y,$PRE.occSch.occupied,$PRE.occSch.tOcc,$PRE.occSch.tNonOcc,$PRE.occSch.tNext,vavTer[1,1].con.hysWitHol.y,vavTer[1,1].con.greThr.y,buiZon.theZon[1,1].roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,$PRE.buiZon.theZon[1,1].roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,buiZon.theZon[1,1].roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,$PRE.buiZon.theZon[1,1].roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,buiZon.theZon[1,1].intLoad.nextTimeEvent,buiZon.theZon[1,1].intLoad.nextTimeEventScaled,$PRE.buiZon.theZon[1,1].intLoad.nextTimeEventScaled,modeSelector[1].initialStepWithSignal.localActive,modeSelector[1].initialStepWithSignal.newActive,modeSelector[1].initialStepWithSignal.oldActive,modeSelector[1].initialStepWithSignal.active,modeSelector[1].start.enableFire,modeSelector[1].unOccOff.localActive,modeSelector[1].unOccOff.outPort[2].available,modeSelector[1].unOccOff.outPort[3].available,modeSelector[1].unOccOff.outPort[4].available,modeSelector[1].unOccOff.newActive,modeSelector[1].unOccOff.oldActive,modeSelector[1].unOccOff.active,modeSelector[1].unOccOff.y,modeSelector[1].unOccNigSetBac.localActive,modeSelector[1].unOccNigSetBac.outPort[2].available,modeSelector[1].unOccNigSetBac.newActive,modeSelector[1].unOccNigSetBac.oldActive,modeSelector[1].unOccNigSetBac.active,modeSelector[1].unOccNigSetBac.y,modeSelector[1].t2.localCondition,modeSelector[1].t2.enableFire,modeSelector[1].t2.fire,modeSelector[1].t2.t_start,modeSelector[1].t1.localCondition,modeSelector[1].t1.enableFire,modeSelector[1].t1.fire,modeSelector[1].t1.outPort.occupied,modeSelector[1].t1.t_start,modeSelector[1].stateGraphRoot.activeSteps,modeSelector[1].morWarUp.localActive,modeSelector[1].morWarUp.newActive,modeSelector[1].morWarUp.oldActive,modeSelector[1].morWarUp.active,modeSelector[1].morWarUp.y,modeSelector[1].t6.enableFire,modeSelector[1].t6.fire,modeSelector[1].t6.t_start,modeSelector[1].t5.localCondition,modeSelector[1].t5.enableFire,modeSelector[1].t5.outPort.occupied,modeSelector[1].occ.localActive,modeSelector[1].occ.newActive,modeSelector[1].occ.oldActive,modeSelector[1].occ.active,modeSelector[1].occ.y,modeSelector[1].t3.localCondition,modeSelector[1].t3.enableFire,modeSelector[1].t4.localCondition,modeSelector[1].t4.enableFire,modeSelector[1].t4.outPort.occupied,modeSelector[1].morPreCoo.localActive,modeSelector[1].morPreCoo.newActive,modeSelector[1].morPreCoo.oldActive,modeSelector[1].morPreCoo.active,modeSelector[1].morPreCoo.y,modeSelector[1].t7.localCondition,modeSelector[1].t7.enableFire,modeSelector[1].t7.outPort.occupied,modeSelector[1].preCooSta.greater.y,modeSelector[1].preCooSta.greater2.y,modeSelector[1].preCooSta.greater1.y,modeSelector[1].t9.localCondition,modeSelector[1].t9.enableFire,modeSelector[1].not1.y,modeSelector[1].t8.enableFire,modeSelector[1].t8.outPort.occupied,modeSelector[1].yFan,modeSelector[1].yEco,modeSelector[1].or2.y,modeSelector[1].modIni.y,conEco[1].hysLoc.y,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,TSetHea.nextTimeEvent,TSetHea.nextTimeEventScaled,$PRE.TSetHea.nextTimeEventScaled,TSetCoo1.nextTimeEvent,TSetCoo1.nextTimeEventScaled,$PRE.TSetCoo1.nextTimeEventScaled,occSch.occupied,occSch.tOcc,occSch.tNonOcc,occSch.tNext,fan_dP_On_Off[1].extractor.index,$PRE.fan_dP_On_Off[1].extractor.index,fan_dP_On_Off[1].extractor.k[1],fan_dP_On_Off[1].extractor.k[2],fan_dP_On_Off[1].extractor.k[3],fan_dP_On_Off[1].extractor.k[4],fan_dP_On_Off[1].extractor.k[5],fan_dP_On_Off[1].extractor.k[6],$whenCondition1,$whenCondition2,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition14,$whenCondition15,$whenCondition16,conTSup[1].con.cheYMinMax.y,buiZon.theZon[1,1].intECoo.local_reset,buiZon.theZon[1,1].intEHea.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1920):
 * Single equations (assignments): 1862
 * Array equations: 44
 * Algorithm blocks: 1
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 10
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 4 systems
   {(1,3,100.0%), (1,4,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 6 systems
   {(1,1), (1,3), (14,55), (1,2), (2,11), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.01276/8.648, allocations: 1.456 MB / 3.88 GB, free: 0.5293 GB / 2.029 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01228/8.661, allocations: 2.567 MB / 3.882 GB, free: 0.5282 GB / 2.029 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.08182/8.742, allocations: 32.85 MB / 3.914 GB, free: 0.4961 GB / 2.029 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001549/8.743, allocations: 112 kB / 3.915 GB, free: 0.496 GB / 2.029 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.867e-05/8.743, allocations: 4 kB / 3.915 GB, free: 0.496 GB / 2.029 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.06282/8.806, allocations: 27.35 MB / 3.941 GB, free: 480.4 MB / 2.029 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.1621/8.968, allocations: 59.48 MB / 3.999 GB, free: 421 MB / 2.029 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001244/8.968, allocations: 8 kB / 3.999 GB, free: 421 MB / 2.029 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01529/8.983, allocations: 2.957 MB / 4.002 GB, free: 418.1 MB / 2.029 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01264/8.996, allocations: 4.875 MB / 4.007 GB, free: 413.2 MB / 2.029 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003937/9, allocations: 363.7 kB / 4.007 GB, free: 412.8 MB / 2.029 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02136/9.021, allocations: 19.96 MB / 4.027 GB, free: 392.1 MB / 2.029 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.588e-06/9.021, allocations: 12.09 kB / 4.027 GB, free: 392.1 MB / 2.029 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0941/9.115, allocations: 45.6 MB / 4.071 GB, free: 347.5 MB / 2.029 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02451/9.14, allocations: 8.626 MB / 4.08 GB, free: 338.8 MB / 2.029 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01183/9.151, allocations: 319.9 kB / 4.08 GB, free: 338.5 MB / 2.029 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02373/9.175, allocations: 2.378 MB / 4.082 GB, free: 336.2 MB / 2.029 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003969/9.179, allocations: 0.8855 MB / 4.083 GB, free: 335.3 MB / 2.029 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.003499/9.183, allocations: 411.7 kB / 4.084 GB, free: 334.9 MB / 2.029 GB
Notification: Performance of sorting global known variables: time 0.07166/9.254, allocations: 41.15 MB / 4.124 GB, free: 293.8 MB / 2.029 GB
Notification: Performance of sort global known variables: time 5.81e-07/9.254, allocations: 0 / 4.124 GB, free: 293.8 MB / 2.029 GB
Notification: Performance of remove unused functions: time 0.06607/9.32, allocations: 13.13 MB / 4.137 GB, free: 280.8 MB / 2.029 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 21
 * Number of states: 53 (conTSup[1].con.I.y,conFanRet[1].con.I.y,conEco[1].conV_flow.I.y,TSup[1].T,TCoiHeaOut[1].T,valCoo[1].filter.s[2],valCoo[1].filter.s[1],valHea[1].filter.s[2],valHea[1].filter.s[1],fanRet[1].filter.s[2],fanRet[1].filter.s[1],fanRet[1].vol.dynBal.U,fanRet[1].vol.dynBal.medium.Xi[1],TRet[1].T,TMix[1].T,eco[1].filter.s[2],eco[1].filter.s[1],cooCoi[1].dryWetCalcs.fullyWet.TWatOutEst,cooCoi[1].heaCooHum_u.vol.dynBal.U,cooCoi[1].heaCooHum_u.vol.dynBal.medium.Xi[1],cooCoi[1].heaCoo.vol.dynBal.U,fan[1].filter.s[2],fan[1].filter.s[1],fan[1].vol.dynBal.m,fan[1].vol.dynBal.U,fan[1].vol.dynBal.medium.Xi[1],buiZon.theZon[1,1].intECoo.y,buiZon.theZon[1,1].intEHea.y,buiZon.theZon[1,1].roo.air.vol.dynBal.m,buiZon.theZon[1,1].roo.air.vol.dynBal.U,buiZon.theZon[1,1].roo.air.vol.dynBal.medium.Xi[1],buiZon.theZon[1,1].roo.conBou[2].opa.lay[1].T[2],buiZon.theZon[1,1].roo.conBou[2].opa.lay[1].T[1],buiZon.theZon[1,1].roo.conBou[1].opa.lay[1].T[2],buiZon.theZon[1,1].roo.conBou[1].opa.lay[1].T[1],buiZon.theZon[1,1].roo.conPar[1].opa.lay[1].T[2],buiZon.theZon[1,1].roo.conPar[1].opa.lay[1].T[1],buiZon.theZon[1,1].roo.conExtWin[1].win.capFra.T,buiZon.theZon[1,1].roo.conExtWin[1].win.capGla.TUns,buiZon.theZon[1,1].roo.conExtWin[1].opa.lay[3].T[2],buiZon.theZon[1,1].roo.conExtWin[1].opa.lay[3].T[1],buiZon.theZon[1,1].roo.conExtWin[1].opa.lay[2].T[3],buiZon.theZon[1,1].roo.conExtWin[1].opa.lay[2].T[2],buiZon.theZon[1,1].roo.conExtWin[1].opa.lay[2].T[1],buiZon.theZon[1,1].roo.conExtWin[1].opa.lay[1].T[2],buiZon.theZon[1,1].roo.conExtWin[1].opa.lay[1].T[1],vavTer[1,1].valHea.filter.s[2],vavTer[1,1].valHea.filter.s[1],vavTer[1,1].TSup.T,vavTer[1,1].con.conCoo.I.y,vavTer[1,1].con.conHea.I.y,vavTer[1,1].vav.filter.s[2],vavTer[1,1].vav.filter.s[1])
 * Number of discrete variables: 120 (modeSelector[1].t6.enableFire,modeSelector[1].t2.enableFire,modeSelector[1].t1.enableFire,modeSelector[1].yFan,modeSelector[1].morPreCoo.active,modeSelector[1].occ.active,modeSelector[1].morWarUp.active,modeSelector[1].unOccNigSetBac.active,modeSelector[1].unOccOff.active,modeSelector[1].initialStepWithSignal.active,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,fan_dP_On_Off[1].extractor.k[6],fan_dP_On_Off[1].extractor.k[5],fan_dP_On_Off[1].extractor.k[4],fan_dP_On_Off[1].extractor.k[3],fan_dP_On_Off[1].extractor.k[2],fan_dP_On_Off[1].extractor.k[1],fan_dP_On_Off[1].extractor.index,occSch.tNext,occSch.tNonOcc,occSch.tOcc,occSch.occupied,TSetCoo1.nextTimeEventScaled,TSetCoo1.nextTimeEvent,TSetHea.nextTimeEventScaled,TSetHea.nextTimeEvent,weaDat.decAng.tNext,weaDat.eqnTim.tNext,weaDat.conTim.tNext,weaDat.conTimMin.tNext,weaDat.locTim.tNext,conEco[1].hysLoc.y,modeSelector[1].modIni.y,modeSelector[1].or2.y,modeSelector[1].yEco,modeSelector[1].t8.outPort.occupied,modeSelector[1].t8.enableFire,modeSelector[1].not1.y,modeSelector[1].t9.enableFire,modeSelector[1].t9.localCondition,modeSelector[1].preCooSta.greater1.y,modeSelector[1].preCooSta.greater2.y,modeSelector[1].preCooSta.greater.y,modeSelector[1].t7.outPort.occupied,modeSelector[1].t7.enableFire,modeSelector[1].t7.localCondition,modeSelector[1].morPreCoo.y,modeSelector[1].morPreCoo.oldActive,modeSelector[1].morPreCoo.newActive,modeSelector[1].morPreCoo.localActive,modeSelector[1].t4.outPort.occupied,modeSelector[1].t4.enableFire,modeSelector[1].t4.localCondition,modeSelector[1].t3.enableFire,modeSelector[1].t3.localCondition,modeSelector[1].occ.y,modeSelector[1].occ.oldActive,modeSelector[1].occ.newActive,modeSelector[1].occ.localActive,modeSelector[1].t5.outPort.occupied,modeSelector[1].t5.enableFire,modeSelector[1].t5.localCondition,modeSelector[1].t6.t_start,modeSelector[1].t6.fire,modeSelector[1].morWarUp.y,modeSelector[1].morWarUp.oldActive,modeSelector[1].morWarUp.newActive,modeSelector[1].morWarUp.localActive,modeSelector[1].stateGraphRoot.activeSteps,modeSelector[1].t1.t_start,modeSelector[1].t1.outPort.occupied,modeSelector[1].t1.fire,modeSelector[1].t1.localCondition,modeSelector[1].t2.t_start,modeSelector[1].t2.fire,modeSelector[1].t2.localCondition,modeSelector[1].unOccNigSetBac.y,modeSelector[1].unOccNigSetBac.oldActive,modeSelector[1].unOccNigSetBac.newActive,modeSelector[1].unOccNigSetBac.outPort[2].available,modeSelector[1].unOccNigSetBac.localActive,modeSelector[1].unOccOff.y,modeSelector[1].unOccOff.oldActive,modeSelector[1].unOccOff.newActive,modeSelector[1].unOccOff.outPort[4].available,modeSelector[1].unOccOff.outPort[3].available,modeSelector[1].unOccOff.outPort[2].available,modeSelector[1].unOccOff.localActive,modeSelector[1].start.enableFire,modeSelector[1].initialStepWithSignal.oldActive,modeSelector[1].initialStepWithSignal.newActive,modeSelector[1].initialStepWithSignal.localActive,buiZon.theZon[1,1].intLoad.nextTimeEventScaled,buiZon.theZon[1,1].intLoad.nextTimeEvent,buiZon.theZon[1,1].roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,buiZon.theZon[1,1].roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,vavTer[1,1].con.greThr.y,vavTer[1,1].con.hysWitHol.y,modeSelector[1].start.t_start,modeSelector[1].t5.t_start,modeSelector[1].t3.t_start,modeSelector[1].t4.t_start,modeSelector[1].t7.t_start,modeSelector[1].t9.t_start,modeSelector[1].t8.t_start)
 * Number of discrete states: 30 (modeSelector[1].t8.t_start,modeSelector[1].t9.t_start,modeSelector[1].t7.t_start,modeSelector[1].t4.t_start,modeSelector[1].t3.t_start,modeSelector[1].t5.t_start,modeSelector[1].start.t_start,occSch.tNext,TSetCoo1.nextTimeEvent,TSetHea.nextTimeEvent,buiZon.theZon[1,1].intLoad.nextTimeEvent,modeSelector[1].initialStepWithSignal.newActive,modeSelector[1].unOccOff.newActive,modeSelector[1].unOccNigSetBac.newActive,modeSelector[1].morWarUp.newActive,modeSelector[1].occ.newActive,modeSelector[1].morPreCoo.newActive,fan_dP_On_Off[1].extractor.index,buiZon.theZon[1,1].roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,buiZon.theZon[1,1].roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,weaDat.locTim.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext,weaDat.conTimMin.tNext,TSetCoo1.nextTimeEventScaled,TSetHea.nextTimeEventScaled,vavTer[1,1].con.hysWitHol.y,conEco[1].hysLoc.y,buiZon.theZon[1,1].intLoad.nextTimeEventScaled)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (1018):
 * Single equations (assignments): 965
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 6
 * When equations: 36
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 10
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 5 systems
   {(1,2,100.0%), (1,3,100.0%), (1,1,100.0%), (1,1,100.0%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 5 systems
   {(1,2), (1,1), (2,12), (12,37), (1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01194/9.332, allocations: 3.133 MB / 4.14 GB, free: 278.2 MB / 2.029 GB
Notification: Performance of simCode: created initialization part: time 0.1601/9.492, allocations: 112.8 MB / 4.25 GB, free: 165.1 MB / 2.029 GB
Notification: Performance of simCode: created event and clocks part: time 2.109e-05/9.492, allocations: 14.16 kB / 4.25 GB, free: 165.1 MB / 2.029 GB
Notification: Performance of simCode: created simulation system equations: time 0.03231/9.525, allocations: 15.32 MB / 4.265 GB, free: 149.7 MB / 2.029 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.06264/9.587, allocations: 10.79 MB / 4.275 GB, free: 139.1 MB / 2.029 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1944/9.782, allocations: 92.44 MB / 4.366 GB, free: 46.52 MB / 2.029 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.08008/9.862, allocations: 25.64 MB / 4.391 GB, free: 20.81 MB / 2.029 GB
Notification: Performance of simCode: alias equations: time 0.06237/9.924, allocations: 19.26 MB / 4.41 GB, free: 1.547 MB / 2.029 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 1.454/11.38, allocations: 1.68 MB / 4.411 GB, free: 0.7723 GB / 2.029 GB
Notification: Performance of SimCode: time 1.603e-06/11.38, allocations: 2.625 kB / 4.411 GB, free: 0.7723 GB / 2.029 GB
Notification: Performance of Templates: time 0.8405/12.22, allocations: 0.6052 GB / 5.016 GB, free: 353.2 MB / 2.029 GB
"
[Timeout remaining time 288]
make -j1 -f Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.makefile [Timeout 300]
(rm -f Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe ; mkfifo Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe ; head -c 1048576 < Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe >> ../files/Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.sim & ./Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone  -abortSlowSimulation -alarm=400 -s ida -emit_protected -lv LOG_STATS > Buildings_11_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 35.006312092998996]
Failed to read output from testmodel.py, exit status != 0:
12.22941551799886 12.23916958 9.021653728
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe906226fe0>