Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_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.001444/0.001444, allocations: 99.64 kB / 18.95 MB, free: 2.668 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.001904/0.001904, allocations: 211.2 kB / 22.24 MB, free: 5.262 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.483/1.483, allocations: 230.6 MB / 256 MB, free: 7.793 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 3.339/3.339, allocations: 0.5107 GB / 0.8158 GB, free: 17.82 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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: "" <> buildModelFMU(Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone,fileNamePrefix="Buildings_latest_Buildings_Examples_ScalableBenchmarks_BuildingVAV_Examples_OneFloor_OneZone",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone,fileNamePrefix="Buildings_latest_Buildings_Examples_ScalableBenchmarks_BuildingVAV_Examples_OneFloor_OneZone",fmuType="me",version="2.0",platforms={"static"}) [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.002309/0.002309, allocations: 65.98 kB / 1.186 GB, free: 13.72 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3738/0.3761, allocations: 181.9 MB / 1.363 GB, free: 14.09 MB / 1.061 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone): time 1.598/1.974, allocations: 0.5394 GB / 1.903 GB, free: 10.87 MB / 1.42 GB
Notification: Performance of NFInst.instExpressions: time 0.2055/2.18, allocations: 104.6 MB / 2.005 GB, free: 1.965 MB / 1.514 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.03924/2.219, allocations: 0.9378 MB / 2.006 GB, free: 1.02 MB / 1.514 GB
Notification: Performance of NFTyping.typeComponents: time 0.0419/2.261, allocations: 13.41 MB / 2.019 GB, free: 3.543 MB / 1.529 GB
Notification: Performance of NFTyping.typeBindings: time 0.07767/2.339, allocations: 22.96 MB / 2.041 GB, free: 12.5 MB / 1.561 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05198/2.391, allocations: 17.95 MB / 2.059 GB, free: 10.59 MB / 1.576 GB
Notification: Performance of NFFlatten.flatten: time 0.1138/2.505, allocations: 74.62 MB / 2.132 GB, free: 15.82 MB / 1.654 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.06011/2.565, allocations: 25.85 MB / 2.157 GB, free: 5.781 MB / 1.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.07669/2.641, allocations: 29.92 MB / 2.186 GB, free: 7.836 MB / 1.701 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.05912/2.7, allocations: 26.9 MB / 2.213 GB, free: 12.89 MB / 1.732 GB
Notification: Performance of NFPackage.collectConstants: time 0.01913/2.72, allocations: 4.488 MB / 2.217 GB, free: 8.402 MB / 1.732 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0541/2.774, allocations: 17.47 MB / 2.234 GB, free: 6.898 MB / 1.748 GB
Notification: Performance of NFScalarize.scalarize: time 0.0302/2.804, allocations: 14.22 MB / 2.248 GB, free: 8.648 MB / 1.764 GB
Notification: Performance of NFVerifyModel.verify: time 0.04793/2.852, allocations: 17.26 MB / 2.265 GB, free: 7.32 MB / 1.779 GB
Notification: Performance of NFConvertDAE.convert: time 2.272/5.124, allocations: 90.13 MB / 2.353 GB, free: 161.7 MB / 1.811 GB
Notification: Performance of FrontEnd - DAE generated: time 8.416e-06/5.124, allocations: 0.5469 kB / 2.353 GB, free: 161.7 MB / 1.811 GB
Notification: Performance of FrontEnd: time 3.106e-06/5.124, allocations: 2.25 kB / 2.353 GB, free: 161.7 MB / 1.811 GB
Notification: Performance of Transformations before backend: time 0.003182/5.127, allocations: 0 / 2.353 GB, free: 161.7 MB / 1.811 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3727
 * Number of variables: 3727
Notification: Performance of Generate backend data structure: time 0.2733/5.401, allocations: 62.53 MB / 2.414 GB, free: 144.9 MB / 1.811 GB
Notification: Performance of prepare preOptimizeDAE: time 5.494e-05/5.401, allocations: 11.28 kB / 2.414 GB, free: 144.9 MB / 1.811 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.01562/5.416, allocations: 2.063 MB / 2.416 GB, free: 144.8 MB / 1.811 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1051/5.522, allocations: 19.99 MB / 2.435 GB, free: 142.5 MB / 1.811 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.4726/5.994, allocations: 391.2 MB / 2.817 GB, free: 6.953 MB / 1.951 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00373/5.998, allocations: 2.414 MB / 2.82 GB, free: 4.277 MB / 1.951 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.02051/6.018, allocations: 1.686 MB / 2.821 GB, free: 2.598 MB / 1.951 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1246/6.143, allocations: 35.96 MB / 2.857 GB, free: 13.79 MB / 1.998 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0008923/6.144, allocations: 26.62 kB / 2.857 GB, free: 13.78 MB / 1.998 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01219/6.156, allocations: 0.5996 MB / 2.857 GB, free: 13.18 MB / 1.998 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000697/6.157, allocations: 0.5354 MB / 2.858 GB, free: 12.65 MB / 1.998 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1143/6.271, allocations: 39.72 MB / 2.896 GB, free: 4.996 MB / 2.029 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.5087/6.78, allocations: 213.1 MB / 3.105 GB, free: 360 kB / 2.233 GB
Notification: Performance of preOpt comSubExp (simulation): time 1.098/7.878, allocations: 24.69 MB / 3.129 GB, free: 1.016 GB / 2.233 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0366/7.915, allocations: 15.53 MB / 3.144 GB, free: 1.016 GB / 2.233 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.1959/8.111, allocations: 55.67 MB / 3.198 GB, free: 0.9984 GB / 2.233 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.04482/8.155, allocations: 17.55 MB / 3.215 GB, free: 0.9928 GB / 2.233 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.03158/8.187, allocations: 1.521 MB / 3.217 GB, free: 0.9926 GB / 2.233 GB
Notification: Performance of pre-optimization done (n=1031): time 0.0001055/8.187, allocations: 0 / 3.217 GB, free: 0.9926 GB / 2.233 GB
Notification: Performance of matching and sorting (n=1037): time 0.5236/8.711, allocations: 125.5 MB / 3.339 GB, free: 0.8869 GB / 2.233 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0006969/8.711, allocations: 0.8806 MB / 3.34 GB, free: 0.8859 GB / 2.233 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.6525/9.364, allocations: 474.4 MB / 3.804 GB, free: 433.1 MB / 2.233 GB
Notification: Performance of collectPreVariables (initialization): time 0.009913/9.374, allocations: 397.6 kB / 3.804 GB, free: 432.7 MB / 2.233 GB
Notification: Performance of collectInitialEqns (initialization): time 0.04154/9.415, allocations: 14.86 MB / 3.818 GB, free: 418 MB / 2.233 GB
Notification: Performance of collectInitialBindings (initialization): time 0.009789/9.425, allocations: 3.685 MB / 3.822 GB, free: 414.3 MB / 2.233 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.04038/9.466, allocations: 4.509 MB / 3.826 GB, free: 409.8 MB / 2.233 GB
Notification: Performance of setup shared object (initialization): time 9.536e-05/9.466, allocations: 301.1 kB / 3.827 GB, free: 409.5 MB / 2.233 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.05163/9.517, allocations: 23.18 MB / 3.849 GB, free: 386.4 MB / 2.233 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.05863/9.576, allocations: 31.31 MB / 3.88 GB, free: 352.8 MB / 2.233 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)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: fanRet[1].vol.dynBal.medium.p = fanRet[1].vol.dynBal.p_start (buiZon.theZon[1,1].roo.air.vol.dynBal.p_start = fanRet[1].vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.497/10.07, allocations: 181.8 MB / 4.058 GB, free: 169.9 MB / 2.233 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002341/10.07, allocations: 31.94 kB / 4.058 GB, free: 169.8 MB / 2.233 GB
Notification: Performance of matching and sorting (n=2150) (initialization): time 0.1694/10.24, allocations: 54.76 MB / 4.111 GB, free: 114.9 MB / 2.233 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000511/10.24, allocations: 197.6 kB / 4.111 GB, free: 114.7 MB / 2.233 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1241/10.37, allocations: 45.33 MB / 4.155 GB, free: 69.28 MB / 2.233 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01856/10.39, allocations: 6.027 MB / 4.161 GB, free: 63.21 MB / 2.233 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03395/10.42, allocations: 5.936 MB / 4.167 GB, free: 57.27 MB / 2.233 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02601/10.45, allocations: 21.22 MB / 4.188 GB, free: 35.15 MB / 2.233 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.06294/10.51, allocations: 4.733 MB / 4.192 GB, free: 30.41 MB / 2.233 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01186/10.52, allocations: 0.7263 MB / 4.193 GB, free: 29.69 MB / 2.233 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 1.246/11.77, allocations: 42.16 MB / 4.234 GB, free: 0.9858 GB / 2.233 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.06968/11.84, allocations: 30.96 MB / 4.265 GB, free: 0.9777 GB / 2.233 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)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: fanRet[1].vol.dynBal.medium.p = fanRet[1].vol.dynBal.p_start (buiZon.theZon[1,1].roo.air.vol.dynBal.p_start = fanRet[1].vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.5076/12.34, allocations: 179.4 MB / 4.44 GB, free: 0.8843 GB / 2.233 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0002855/12.34, allocations: 28 kB / 4.44 GB, free: 0.8843 GB / 2.233 GB
Notification: Performance of matching and sorting (n=2150) (initialization_lambda0): time 0.1752/12.52, allocations: 56.16 MB / 4.495 GB, free: 0.8309 GB / 2.233 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0005453/12.52, allocations: 193.6 kB / 4.495 GB, free: 0.8307 GB / 2.233 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1342/12.65, allocations: 46.67 MB / 4.54 GB, free: 0.785 GB / 2.233 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01528/12.67, allocations: 5.808 MB / 4.546 GB, free: 0.7793 GB / 2.233 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03224/12.7, allocations: 5.871 MB / 4.552 GB, free: 0.7736 GB / 2.233 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05562/12.76, allocations: 45.2 MB / 4.596 GB, free: 0.7286 GB / 2.233 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05121/12.81, allocations: 4.858 MB / 4.601 GB, free: 0.7239 GB / 2.233 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01048/12.82, allocations: 0.7223 MB / 4.601 GB, free: 0.7232 GB / 2.233 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: 440
 * Number of states: 0 ()
 * Number of discrete variables: 221 (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,vavTer[1,1].terHea.flowRegime_nominal,vavTer[1,1].terHea.flowRegime,hex[1].flowRegime_nominal,hex[1].flowRegime,cooCoi[1].flowRegime_nominal,cooCoi[1].dryWetCalcs.fullyDry.cfg,cooCoi[1].dryWetCalcs.fullyWet.cfg,cooCoi[1].dryWetCalcs.cfg,cooCoi[1].flowRegime,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].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 (1929):
 * Single equations (assignments): 1908
 * Array equations: 5
 * Algorithm blocks: 1
 * Record equations: 4
 * 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.01845/12.84, allocations: 1.464 MB / 4.603 GB, free: 0.7218 GB / 2.233 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02413/12.86, allocations: 2.876 MB / 4.606 GB, free: 0.7206 GB / 2.233 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.117/12.98, allocations: 35.49 MB / 4.64 GB, free: 0.6859 GB / 2.233 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001682/12.98, allocations: 104 kB / 4.64 GB, free: 0.6858 GB / 2.233 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.935e-05/12.98, allocations: 8 kB / 4.64 GB, free: 0.6858 GB / 2.233 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.08216/13.06, allocations: 29.24 MB / 4.669 GB, free: 0.657 GB / 2.233 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.25/13.31, allocations: 73.8 MB / 4.741 GB, free: 0.5847 GB / 2.233 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001001/13.31, allocations: 8.031 kB / 4.741 GB, free: 0.5847 GB / 2.233 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01923/13.33, allocations: 3.69 MB / 4.745 GB, free: 0.5811 GB / 2.233 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0145/13.34, allocations: 4.862 MB / 4.749 GB, free: 0.5763 GB / 2.233 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.005864/13.35, allocations: 354.1 kB / 4.75 GB, free: 0.576 GB / 2.233 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02106/13.37, allocations: 20.15 MB / 4.769 GB, free: 0.5556 GB / 2.233 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.166e-06/13.37, allocations: 12.06 kB / 4.769 GB, free: 0.5555 GB / 2.233 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.14/13.51, allocations: 52.44 MB / 4.821 GB, free: 0.5051 GB / 2.233 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.04519/13.56, allocations: 11.55 MB / 4.832 GB, free: 0.4938 GB / 2.233 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.02502/13.58, allocations: 303.8 kB / 4.832 GB, free: 0.4935 GB / 2.233 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.03958/13.62, allocations: 3.435 MB / 4.836 GB, free: 0.4901 GB / 2.233 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.005745/13.63, allocations: 0.8776 MB / 4.836 GB, free: 0.4893 GB / 2.233 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.005246/13.63, allocations: 399.7 kB / 4.837 GB, free: 0.4889 GB / 2.233 GB
Notification: Performance of sorting global known variables: time 0.3631/14, allocations: 368.9 MB / 5.197 GB, free: 131.8 MB / 2.233 GB
Notification: Performance of sort global known variables: time 1.042e-06/14, allocations: 0 / 5.197 GB, free: 131.8 MB / 2.233 GB
Notification: Performance of remove unused functions: time 0.105/14.1, allocations: 13.43 MB / 5.21 GB, free: 118.4 MB / 2.233 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 21
 * Number of states: 46 (conTSup[1].con.I.y,conFanRet[1].con.I.y,conEco[1].conV_flow.I.y,TSup[1].T,TCoiHeaOut[1].T,valCoo[1].actPos.y,valHea[1].actPos.y,fanRet[1].motSpe.y,fanRet[1].vol.dynBal.U,fanRet[1].vol.dynBal.medium.Xi[1],TRet[1].T,TMix[1].T,eco[1].actPos.y,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].motSpe.y,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.actPos.y,vavTer[1,1].TSup.T,vavTer[1,1].con.conCoo.I.y,vavTer[1,1].con.conHea.I.y,vavTer[1,1].vav.actPos.y)
 * 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 (1006):
 * Single equations (assignments): 953
 * 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.1415/14.24, allocations: 56.24 MB / 5.265 GB, free: 62.34 MB / 2.233 GB
Notification: Performance of simCode: created initialization part: time 1.2/15.44, allocations: 69.61 MB / 5.333 GB, free: 1.013 GB / 2.233 GB
Notification: Performance of simCode: created event and clocks part: time 3.779e-05/15.44, allocations: 6.844 kB / 5.333 GB, free: 1.013 GB / 2.233 GB
Notification: Performance of simCode: created simulation system equations: time 0.05037/15.49, allocations: 17.49 MB / 5.35 GB, free: 1.012 GB / 2.233 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.101/15.59, allocations: 11.15 MB / 5.361 GB, free: 1.011 GB / 2.233 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 4.668/20.26, allocations: 2.097 GB / 7.458 GB, free: 64.3 MB / 2.233 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.07853/20.34, allocations: 25.54 MB / 7.483 GB, free: 39.02 MB / 2.233 GB
Notification: Performance of simCode: alias equations: time 1.189/21.53, allocations: 21.79 MB / 7.505 GB, free: 0.9773 GB / 2.233 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.08711/21.62, allocations: 14.92 MB / 7.519 GB, free: 0.9773 GB / 2.233 GB
Notification: Performance of SimCode: time 2.635e-06/21.62, allocations: 0 / 7.519 GB, free: 0.9773 GB / 2.233 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 2.944/24.56, allocations: 1.237 GB / 8.757 GB, free: 0.9032 GB / 2.233 GB
Notification: Performance of buildModelFMU: Generate platform static: time 239.8/264.4, allocations: 13.97 kB / 8.757 GB, free: 0.9032 GB / 2.233 GB
"
[Timeout remaining time 35]
(rm -f Buildings_latest_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe ; mkfifo Buildings_latest_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe ; head -c 1048576 < Buildings_latest_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe >> ../files/Buildings_latest_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_latest_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone_res.mat --tempDir=temp_Buildings_latest_Buildings_Examples_ScalableBenchmarks_BuildingVAV_Examples_OneFloor_OneZone_fmu --startTime=0 --stopTime=604800 --stepSize=241.92 --timeout=800 --tolerance=1e-06 Buildings_latest_Buildings_Examples_ScalableBenchmarks_BuildingVAV_Examples_OneFloor_OneZone.fmu > Buildings_latest_Buildings.Examples.ScalableBenchmarks.BuildingVAV.Examples.OneFloor_OneZone.pipe 2>&1) [Timeout 840.0]
[Calling os._exit(0), Time elapsed: 276.5847398196347]
Failed to read output from testmodel.py, exit status != 0:
265.1364889126271 265.152705576 260.024193939
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fc252caefb0>