Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer.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.001645/0.001645, allocations: 109.1 kB / 19.71 MB, free: 2.145 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.001967/0.001967, allocations: 213.6 kB / 23 MB, free: 4.965 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.471/1.471, allocations: 230.6 MB / 256.8 MB, free: 7.82 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.221/3.221, allocations: 0.49 GB / 0.7959 GB, free: 35.43 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.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|cooModCon.y|chiOn.1..u|chiOn.2..u|wseOn.y|TCWSup.T|TCWRet.T|TCWSupSet.y|TCHWSup.T|TCHWRet.T|TCHWSupSet.y|chiWSE.TCHWSupWSE|TAirSup.T|TAirSupSet.y|chiWSE.chiPar.chi.1..P|chiWSE.chiPar.chi.2..P|chiWSE.chiPar.chi.1..COP|chiWSE.chiPar.chi.2..COP|chiWSE.chiPar.chi.1..QEva_flow|chiWSE.chiPar.chi.2..QEva_flow|chiWSE.wse.heaExc.hex.Q2_flow|ahu.cooCoi.Q2_flow",fileNamePrefix="Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer")
translateModel(Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|cooModCon.y|chiOn.1..u|chiOn.2..u|wseOn.y|TCWSup.T|TCWRet.T|TCWSupSet.y|TCHWSup.T|TCHWRet.T|TCHWSupSet.y|chiWSE.TCHWSupWSE|TAirSup.T|TAirSupSet.y|chiWSE.chiPar.chi.1..P|chiWSE.chiPar.chi.2..P|chiWSE.chiPar.chi.1..COP|chiWSE.chiPar.chi.2..COP|chiWSE.chiPar.chi.1..QEva_flow|chiWSE.chiPar.chi.2..QEva_flow|chiWSE.wse.heaExc.hex.Q2_flow|ahu.cooCoi.Q2_flow",fileNamePrefix="Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer") [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.001988/0.001988, allocations: 66.59 kB / 1.155 GB, free: 25.14 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3656/0.3676, allocations: 176.1 MB / 1.327 GB, free: 0.9102 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer): time 3.218/3.585, allocations: 1.062 GB / 2.389 GB, free: 172.9 MB / 1.732 GB
Notification: Performance of NFInst.instExpressions: time 0.1854/3.771, allocations: 126 MB / 2.512 GB, free: 170.5 MB / 1.732 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.04001/3.811, allocations: 1.059 MB / 2.513 GB, free: 170.5 MB / 1.732 GB
Notification: Performance of NFTyping.typeComponents: time 0.03031/3.841, allocations: 9.876 MB / 2.523 GB, free: 168.4 MB / 1.732 GB
Notification: Performance of NFTyping.typeBindings: time 0.07759/3.919, allocations: 26.43 MB / 2.549 GB, free: 161.5 MB / 1.732 GB
Notification: Performance of NFTyping.typeClassSections: time 0.04425/3.963, allocations: 17.22 MB / 2.566 GB, free: 153.1 MB / 1.732 GB
Notification: Performance of NFFlatten.flatten: time 0.1443/4.107, allocations: 103.8 MB / 2.667 GB, free: 84.61 MB / 1.732 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0365/4.144, allocations: 21.18 MB / 2.688 GB, free: 63.76 MB / 1.732 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.05237/4.196, allocations: 27.07 MB / 2.714 GB, free: 38.01 MB / 1.732 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.03599/4.232, allocations: 20.81 MB / 2.734 GB, free: 18.49 MB / 1.732 GB
Notification: Performance of NFPackage.collectConstants: time 0.01311/4.245, allocations: 3.168 MB / 2.738 GB, free: 15.32 MB / 1.732 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03751/4.283, allocations: 13.45 MB / 2.751 GB, free: 2.727 MB / 1.732 GB
Notification: Performance of NFScalarize.scalarize: time 0.01934/4.302, allocations: 10.22 MB / 2.761 GB, free: 8.68 MB / 1.748 GB
Notification: Performance of NFVerifyModel.verify: time 0.03953/4.342, allocations: 13.62 MB / 2.774 GB, free: 11.17 MB / 1.764 GB
Notification: Performance of NFConvertDAE.convert: time 0.1092/4.451, allocations: 56.49 MB / 2.829 GB, free: 4.324 MB / 1.811 GB
Notification: Performance of FrontEnd - DAE generated: time 6.613e-06/4.451, allocations: 0 / 2.829 GB, free: 4.324 MB / 1.811 GB
Notification: Performance of FrontEnd: time 1.693e-06/4.451, allocations: 0 / 2.829 GB, free: 4.324 MB / 1.811 GB
Notification: Performance of Transformations before backend: time 0.002047/4.453, allocations: 4 kB / 2.829 GB, free: 4.32 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: 3927
 * Number of variables: 3927
Notification: Performance of Generate backend data structure: time 0.1135/4.566, allocations: 35.89 MB / 2.864 GB, free: 496 kB / 1.842 GB
Notification: Performance of prepare preOptimizeDAE: time 5.15e-05/4.566, allocations: 8.031 kB / 2.864 GB, free: 488 kB / 1.842 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06908/4.635, allocations: 9.488 MB / 2.873 GB, free: 6.969 MB / 1.857 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.09573/4.731, allocations: 35.51 MB / 2.908 GB, free: 3.977 MB / 1.889 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002282/4.733, allocations: 1.562 MB / 2.91 GB, free: 2.371 MB / 1.889 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.008067/4.742, allocations: 1.664 MB / 2.911 GB, free: 0.7148 MB / 1.889 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.08829/4.83, allocations: 27.66 MB / 2.938 GB, free: 4.152 MB / 1.92 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000956/4.831, allocations: 27.77 kB / 2.938 GB, free: 4.125 MB / 1.92 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.007382/4.838, allocations: 0.6299 MB / 2.939 GB, free: 3.5 MB / 1.92 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007798/4.839, allocations: 0.5909 MB / 2.94 GB, free: 2.91 MB / 1.92 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.08576/4.925, allocations: 26.96 MB / 2.966 GB, free: 7.934 MB / 1.951 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 1.116/6.041, allocations: 81.22 MB / 3.045 GB, free: 0.8378 GB / 1.982 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.04094/6.082, allocations: 18.04 MB / 3.063 GB, free: 0.8318 GB / 1.982 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.02435/6.106, allocations: 10.6 MB / 3.073 GB, free: 0.8284 GB / 1.982 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.08206/6.188, allocations: 28.95 MB / 3.101 GB, free: 0.8164 GB / 1.982 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02791/6.216, allocations: 13.69 MB / 3.115 GB, free: 0.8079 GB / 1.982 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01599/6.232, allocations: 1.927 MB / 3.117 GB, free: 0.8074 GB / 1.982 GB
Notification: Performance of pre-optimization done (n=1205): time 6.222e-05/6.232, allocations: 3.781 kB / 3.117 GB, free: 0.8074 GB / 1.982 GB
Notification: Performance of matching and sorting (n=1216): time 0.179/6.411, allocations: 56.94 MB / 3.172 GB, free: 0.7638 GB / 1.982 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001941/6.411, allocations: 344.8 kB / 3.173 GB, free: 0.7634 GB / 1.982 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.06233/6.474, allocations: 28.45 MB / 3.2 GB, free: 0.736 GB / 1.982 GB
Notification: Performance of collectPreVariables (initialization): time 0.004616/6.478, allocations: 401.3 kB / 3.201 GB, free: 0.7356 GB / 1.982 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01801/6.496, allocations: 12.65 MB / 3.213 GB, free: 0.7238 GB / 1.982 GB
Notification: Performance of collectInitialBindings (initialization): time 0.006959/6.503, allocations: 4.116 MB / 3.217 GB, free: 0.7198 GB / 1.982 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01869/6.522, allocations: 4.301 MB / 3.221 GB, free: 0.7156 GB / 1.982 GB
Notification: Performance of setup shared object (initialization): time 4.949e-05/6.522, allocations: 301.1 kB / 3.222 GB, free: 0.7153 GB / 1.982 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.02558/6.548, allocations: 13.43 MB / 3.235 GB, free: 0.7023 GB / 1.982 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.02973/6.577, allocations: 20.48 MB / 3.255 GB, free: 0.6803 GB / 1.982 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: ahu.cooCoi.heaCooHum_u.vol.dynBal.medium.p = ahu.cooCoi.heaCooHum_u.vol.dynBal.p_start (ahu.fan.vol.dynBal.p_start = ahu.cooCoi.heaCooHum_u.vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.6459/7.223, allocations: 146.3 MB / 3.398 GB, free: 0.5373 GB / 1.982 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001357/7.223, allocations: 28 kB / 3.398 GB, free: 0.5373 GB / 1.982 GB
Notification: Performance of matching and sorting (n=2439) (initialization): time 0.08787/7.311, allocations: 35.89 MB / 3.433 GB, free: 0.5022 GB / 1.982 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001461/7.311, allocations: 43.84 kB / 3.433 GB, free: 0.5021 GB / 1.982 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000168/7.312, allocations: 92 kB / 3.433 GB, free: 0.502 GB / 1.982 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01348/7.325, allocations: 3.537 MB / 3.436 GB, free: 0.4986 GB / 1.982 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02231/7.347, allocations: 4.189 MB / 3.44 GB, free: 0.4945 GB / 1.982 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01736/7.365, allocations: 20.76 MB / 3.461 GB, free: 484.7 MB / 1.982 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02632/7.391, allocations: 1.704 MB / 3.462 GB, free: 483 MB / 1.982 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00501/7.396, allocations: 0.9251 MB / 3.463 GB, free: 482.1 MB / 1.982 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization system:
         ahu.cooCoi.UAFroRated.TAirIn = 293.15
         ahu.cooCoi.UAFroRated.TWatIn = 293.15
         ahu.cooCoi.heaCooHum_u.vol.dynBal.medium.p = ahu.cooCoi.heaCooHum_u.vol.dynBal.p_start.
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.04213/7.438, allocations: 20.68 MB / 3.483 GB, free: 461.4 MB / 1.982 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03045/7.469, allocations: 19.56 MB / 3.503 GB, free: 439.6 MB / 1.982 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: ahu.cooCoi.heaCooHum_u.vol.dynBal.medium.p = ahu.cooCoi.heaCooHum_u.vol.dynBal.p_start (ahu.fan.vol.dynBal.p_start = ahu.cooCoi.heaCooHum_u.vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.6626/8.131, allocations: 142.5 MB / 3.642 GB, free: 296.1 MB / 1.982 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0002011/8.131, allocations: 24 kB / 3.642 GB, free: 296.1 MB / 1.982 GB
Notification: Performance of matching and sorting (n=2439) (initialization_lambda0): time 0.09304/8.225, allocations: 33.69 MB / 3.675 GB, free: 262.3 MB / 1.982 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001822/8.225, allocations: 45 kB / 3.675 GB, free: 262.3 MB / 1.982 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002185/8.225, allocations: 99.92 kB / 3.675 GB, free: 262.2 MB / 1.982 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01337/8.238, allocations: 3.464 MB / 3.678 GB, free: 258.7 MB / 1.982 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02133/8.26, allocations: 4.165 MB / 3.682 GB, free: 254.5 MB / 1.982 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01853/8.278, allocations: 20.31 MB / 3.702 GB, free: 233.3 MB / 1.982 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02817/8.306, allocations: 1.788 MB / 3.704 GB, free: 231.5 MB / 1.982 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004993/8.311, allocations: 0.8746 MB / 3.705 GB, free: 230.6 MB / 1.982 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         ahu.cooCoi.UAFroRated.TAirIn = 293.15
         ahu.cooCoi.UAFroRated.TWatIn = 293.15
         ahu.cooCoi.heaCooHum_u.vol.dynBal.medium.p = ahu.cooCoi.heaCooHum_u.vol.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 406
 * Number of states: 0 ()
 * Number of discrete variables: 377 (ahu.cooCoi.UAFroRated.isFulWet,ahu.cooCoi.UAFroRated.isFulDry,$PRE.ahu.heaCon.offToOn.enableFire,$PRE.ahu.heaCon.onToOff.enableFire,$PRE.chiStaCon.con1.enableFire,$PRE.chiStaCon.con2.enableFire,$PRE.chiStaCon.con3.enableFire,$PRE.chiStaCon.con4.enableFire,$PRE.CWPumCon.con1.enableFire,$PRE.CWPumCon.con2.enableFire,$PRE.CWPumCon.con3.enableFire,$PRE.CWPumCon.con4.enableFire,$PRE.varSpeCon.con1.enableFire,$PRE.varSpeCon.con2.enableFire,$PRE.varSpeCon.con3.enableFire,$PRE.varSpeCon.con4.enableFire,$PRE.cooModCon.con1.enableFire,$PRE.cooModCon.con2.enableFire,$PRE.cooModCon.con3.enableFire,$PRE.cooModCon.con4.enableFire,$PRE.PriPumCon.con1.enableFire,$PRE.PriPumCon.con2.enableFire,$PRE.PriPumCon.con3.enableFire,$PRE.PriPumCon.con4.enableFire,swiTim.y,$PRE.swiTim.y,PriPumCon.stateGraphRoot.resume,PriPumCon.stateGraphRoot.suspend,cooModCon.stateGraphRoot.resume,cooModCon.stateGraphRoot.suspend,varSpeCon.stateGraphRoot.resume,varSpeCon.stateGraphRoot.suspend,CWPumCon.stateGraphRoot.resume,CWPumCon.stateGraphRoot.suspend,chiStaCon.stateGraphRoot.resume,chiStaCon.stateGraphRoot.suspend,ahu.heaCon.stateGraphRoot.resume,ahu.heaCon.stateGraphRoot.suspend,PriPumCon.zer.y,CWPumCon.zer.y,chiWSE.chiPar.chi[2].tru.y,chiWSE.chiPar.chi[1].tru.y,swiTim.local_set,swiTim.local_reset,ahu.cooCoi.flowRegime_nominal,weaData.conTim.canRepeatWeatherFile,weaData.conTimMin.canRepeatWeatherFile,$PRE.PriPumCon.con4.t_start,$PRE.PriPumCon.con3.t_start,$PRE.PriPumCon.con2.t_start,$PRE.PriPumCon.twoOn.oldActive,$PRE.PriPumCon.twoOn.newActive,$PRE.PriPumCon.twoOn.localActive,$PRE.PriPumCon.off.oldActive,$PRE.PriPumCon.off.newActive,$PRE.PriPumCon.off.localActive,$PRE.PriPumCon.oneOn.oldActive,$PRE.PriPumCon.oneOn.newActive,$PRE.PriPumCon.oneOn.localActive,$PRE.PriPumCon.con1.t_start,$PRE.secPum.hys[2].y,$PRE.secPum.hys[1].y,$PRE.cooModCon.swi.y,$PRE.cooModCon.con4.t_start,$PRE.cooModCon.con3.t_start,$PRE.cooModCon.con2.t_start,$PRE.cooModCon.fulMecCoo.oldActive,$PRE.cooModCon.fulMecCoo.newActive,$PRE.cooModCon.fulMecCoo.localActive,$PRE.cooModCon.freCoo.oldActive,$PRE.cooModCon.freCoo.newActive,$PRE.cooModCon.freCoo.localActive,$PRE.cooModCon.parMecCoo.oldActive,$PRE.cooModCon.parMecCoo.newActive,$PRE.cooModCon.parMecCoo.localActive,$PRE.cooModCon.con1.t_start,$PRE.varSpeCon.con4.t_start,$PRE.varSpeCon.con3.t_start,$PRE.varSpeCon.con2.t_start,$PRE.varSpeCon.twoOn.oldActive,$PRE.varSpeCon.twoOn.newActive,$PRE.varSpeCon.twoOn.localActive,$PRE.varSpeCon.off.oldActive,$PRE.varSpeCon.off.newActive,$PRE.varSpeCon.off.localActive,$PRE.varSpeCon.oneOn.oldActive,$PRE.varSpeCon.oneOn.newActive,$PRE.varSpeCon.oneOn.localActive,$PRE.varSpeCon.con1.t_start,$PRE.CWPumCon.con4.t_start,$PRE.CWPumCon.con3.t_start,$PRE.CWPumCon.con2.t_start,$PRE.CWPumCon.twoOn.oldActive,$PRE.CWPumCon.twoOn.newActive,$PRE.CWPumCon.twoOn.localActive,$PRE.CWPumCon.off.oldActive,$PRE.CWPumCon.off.newActive,$PRE.CWPumCon.off.localActive,$PRE.CWPumCon.oneOn.oldActive,$PRE.CWPumCon.oneOn.newActive,$PRE.CWPumCon.oneOn.localActive,$PRE.CWPumCon.con1.t_start,$PRE.chiStaCon.con4.t_start,$PRE.chiStaCon.con3.t_start,$PRE.chiStaCon.con2.t_start,$PRE.chiStaCon.twoOn.oldActive,$PRE.chiStaCon.twoOn.newActive,$PRE.chiStaCon.twoOn.localActive,$PRE.chiStaCon.off.oldActive,$PRE.chiStaCon.off.newActive,$PRE.chiStaCon.off.localActive,$PRE.chiStaCon.oneOn.oldActive,$PRE.chiStaCon.oneOn.newActive,$PRE.chiStaCon.oneOn.localActive,$PRE.chiStaCon.con1.t_start,$PRE.ahu.heaCon.onToOff.t_start,$PRE.ahu.heaCon.offToOn.t_start,$PRE.ahu.heaCon.on.oldActive,$PRE.ahu.heaCon.on.newActive,$PRE.ahu.heaCon.on.localActive,$PRE.ahu.heaCon.off.oldActive,$PRE.ahu.heaCon.off.newActive,$PRE.ahu.heaCon.off.localActive,$PRE.chiWSE.pum.hys[2].y,$PRE.chiWSE.pum.hys[1].y,cooModCon.swi.expr[3],cooModCon.swi.expr[2],cooModCon.swi.expr[1],PriPumCon.intSwi.y,PriPumCon.addInt.y,PriPumCon.booToInt1.y,PriPumCon.booToInt.y,PriPumCon.stateGraphRoot.activeSteps,PriPumCon.con4.t_start,PriPumCon.con4.fire,PriPumCon.con4.enableFire,PriPumCon.con4.localCondition,PriPumCon.con3.t_start,PriPumCon.con3.fire,PriPumCon.con3.enableFire,PriPumCon.con3.localCondition,PriPumCon.con2.t_start,PriPumCon.con2.fire,PriPumCon.con2.enableFire,PriPumCon.con2.localCondition,PriPumCon.twoOn.active,PriPumCon.twoOn.oldActive,PriPumCon.twoOn.newActive,PriPumCon.twoOn.localActive,PriPumCon.off.oldActive,PriPumCon.off.newActive,PriPumCon.off.localActive,PriPumCon.off.active,PriPumCon.oneOn.active,PriPumCon.oneOn.oldActive,PriPumCon.oneOn.newActive,PriPumCon.oneOn.outPort[2].available,PriPumCon.oneOn.inPort[2].occupied,PriPumCon.oneOn.localActive,PriPumCon.con1.t_start,PriPumCon.con1.fire,PriPumCon.con1.enableFire,PriPumCon.con1.localCondition,secPum.hys[2].y,secPum.hys[1].y,cooModCon.swi.firstActiveIndex,cooModCon.swi.y,cooModCon.stateGraphRoot.activeSteps,cooModCon.con4.t_start,cooModCon.con4.fire,cooModCon.con4.enableFire,cooModCon.con4.localCondition,cooModCon.con3.t_start,cooModCon.con3.fire,cooModCon.con3.enableFire,cooModCon.con3.localCondition,cooModCon.con2.t_start,cooModCon.con2.fire,cooModCon.con2.enableFire,cooModCon.con2.localCondition,cooModCon.fulMecCoo.active,cooModCon.fulMecCoo.oldActive,cooModCon.fulMecCoo.newActive,cooModCon.fulMecCoo.localActive,cooModCon.freCoo.active,cooModCon.freCoo.oldActive,cooModCon.freCoo.newActive,cooModCon.freCoo.localActive,cooModCon.parMecCoo.active,cooModCon.parMecCoo.oldActive,cooModCon.parMecCoo.newActive,cooModCon.parMecCoo.outPort[2].available,cooModCon.parMecCoo.inPort[2].occupied,cooModCon.parMecCoo.localActive,cooModCon.con1.t_start,cooModCon.con1.fire,cooModCon.con1.enableFire,cooModCon.con1.localCondition,chiOnSta.y,chiOnSta.u[2],chiOnSta.u[1],swi[1].u2,varSpeCon.addInt.y,varSpeCon.booToInt1.y,varSpeCon.booToInt.y,varSpeCon.stateGraphRoot.activeSteps,varSpeCon.con4.t_start,varSpeCon.con4.fire,varSpeCon.con4.enableFire,varSpeCon.con4.localCondition,varSpeCon.con3.t_start,varSpeCon.con3.fire,varSpeCon.con3.enableFire,varSpeCon.con3.localCondition,varSpeCon.con2.t_start,varSpeCon.con2.fire,varSpeCon.con2.enableFire,varSpeCon.con2.localCondition,varSpeCon.twoOn.active,varSpeCon.twoOn.oldActive,varSpeCon.twoOn.newActive,varSpeCon.twoOn.localActive,varSpeCon.off.oldActive,varSpeCon.off.newActive,varSpeCon.off.localActive,varSpeCon.off.active,varSpeCon.oneOn.active,varSpeCon.oneOn.oldActive,varSpeCon.oneOn.newActive,varSpeCon.oneOn.outPort[2].available,varSpeCon.oneOn.inPort[2].occupied,varSpeCon.oneOn.localActive,varSpeCon.con1.t_start,varSpeCon.con1.fire,varSpeCon.con1.enableFire,varSpeCon.con1.localCondition,cooTowSpeCon.fmcMod.y,cooTowSpeCon.pmcMod.y,chiNumOn.y,CWPumCon.intSwi.y,CWPumCon.addInt.y,CWPumCon.booToInt1.y,CWPumCon.booToInt.y,CWPumCon.stateGraphRoot.activeSteps,CWPumCon.con4.t_start,CWPumCon.con4.fire,CWPumCon.con4.enableFire,CWPumCon.con4.localCondition,CWPumCon.con3.t_start,CWPumCon.con3.fire,CWPumCon.con3.enableFire,CWPumCon.con2.t_start,CWPumCon.con2.fire,CWPumCon.con2.enableFire,CWPumCon.con2.localCondition,CWPumCon.twoOn.active,CWPumCon.twoOn.oldActive,CWPumCon.twoOn.newActive,CWPumCon.twoOn.localActive,CWPumCon.off.oldActive,CWPumCon.off.newActive,CWPumCon.off.localActive,CWPumCon.off.active,CWPumCon.oneOn.active,CWPumCon.oneOn.oldActive,CWPumCon.oneOn.newActive,CWPumCon.oneOn.outPort[2].available,CWPumCon.oneOn.inPort[2].occupied,CWPumCon.oneOn.localActive,CWPumCon.con1.t_start,CWPumCon.con1.fire,CWPumCon.con1.enableFire,CWPumCon.con1.localCondition,chiStaCon.addInt.y,chiStaCon.booToInt1.y,chiStaCon.booToInt.y,chiStaCon.stateGraphRoot.activeSteps,chiStaCon.con4.t_start,chiStaCon.con4.fire,chiStaCon.con4.enableFire,chiStaCon.con4.localCondition,chiStaCon.con3.t_start,chiStaCon.con3.fire,chiStaCon.con3.enableFire,chiStaCon.con3.localCondition,chiStaCon.con2.t_start,chiStaCon.con2.fire,chiStaCon.con2.enableFire,chiStaCon.con2.localCondition,chiStaCon.twoOn.active,chiStaCon.twoOn.oldActive,chiStaCon.twoOn.newActive,chiStaCon.twoOn.localActive,chiStaCon.off.oldActive,chiStaCon.off.newActive,chiStaCon.off.localActive,chiStaCon.off.active,chiStaCon.oneOn.active,chiStaCon.oneOn.oldActive,chiStaCon.oneOn.newActive,chiStaCon.oneOn.outPort[2].available,chiStaCon.oneOn.inPort[2].occupied,chiStaCon.oneOn.localActive,chiStaCon.con1.t_start,chiStaCon.con1.fire,chiStaCon.con1.enableFire,chiStaCon.con1.localCondition,ahu.heaCon.stateGraphRoot.activeSteps,ahu.heaCon.onToOff.t_start,ahu.heaCon.onToOff.fire,ahu.heaCon.onToOff.enableFire,ahu.heaCon.onToOff.localCondition,ahu.heaCon.offToOn.t_start,ahu.heaCon.offToOn.fire,ahu.heaCon.offToOn.enableFire,ahu.heaCon.offToOn.localCondition,ahu.heaCon.on.active,ahu.heaCon.on.oldActive,ahu.heaCon.on.newActive,ahu.heaCon.on.localActive,ahu.heaCon.off.active,ahu.heaCon.off.oldActive,ahu.heaCon.off.newActive,ahu.heaCon.off.localActive,weaData.decAng.tNext,$PRE.weaData.decAng.tNext,weaData.eqnTim.tNext,$PRE.weaData.eqnTim.tNext,weaData.conTim.tNext,$PRE.weaData.conTim.tNext,weaData.conTimMin.tNext,$PRE.weaData.conTimMin.tNext,weaData.locTim.tNext,$PRE.weaData.locTim.tNext,chiWSE.pum.hys[2].y,chiWSE.pum.hys[1].y,chiWSE.on[3],sigCha.y,sigCha.u,$PRE.sigCha.u,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition20,$whenCondition21,$whenCondition22,$whenCondition23,$whenCondition24,$whenCondition25,$whenCondition26,$whenCondition27,$whenCondition28,ahuFanSpeCon.I.trigger_internal,ahuValSig.I.trigger_internal,pumSpe.I.trigger_internal,cooTowSpeCon.conPID.I.trigger_internal,EIT.local_reset,EHVAC.local_reset,FMCHou.trigger_internal,PMCTim.trigger_internal,FCTim.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2033):
 * Single equations (assignments): 1989
 * Array equations: 17
 * Algorithm blocks: 0
 * Record equations: 7
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 9
 * Torn equation systems: 11
 * 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): 9 systems
   {1, 1, 1, 1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,5,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 9 systems
   {(1,1), (1,3), (1,2), (10,52), (1,4), (3,5), (3,30), (1,8), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.008867/8.32, allocations: 1.335 MB / 3.706 GB, free: 229.2 MB / 1.982 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0106/8.331, allocations: 2.503 MB / 3.708 GB, free: 226.7 MB / 1.982 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0758/8.407, allocations: 24.94 MB / 3.733 GB, free: 201.9 MB / 1.982 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.05525/8.462, allocations: 19.62 MB / 3.752 GB, free: 182.1 MB / 1.982 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.000115/8.462, allocations: 4 kB / 3.752 GB, free: 182.1 MB / 1.982 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01919/8.481, allocations: 5.041 MB / 3.757 GB, free: 177.1 MB / 1.982 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.009729/8.491, allocations: 272 kB / 3.757 GB, free: 176.8 MB / 1.982 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02235/8.513, allocations: 1.398 MB / 3.758 GB, free: 175.4 MB / 1.982 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.004001/8.517, allocations: 1.094 MB / 3.76 GB, free: 174.3 MB / 1.982 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.06839/8.586, allocations: 23.57 MB / 3.783 GB, free: 150.9 MB / 1.982 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.08131/8.667, allocations: 34.65 MB / 3.816 GB, free: 116.1 MB / 1.982 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.02022/8.687, allocations: 9.772 MB / 3.826 GB, free: 106.4 MB / 1.982 GB
Notification: Performance of sorting global known variables: time 0.04107/8.728, allocations: 19.39 MB / 3.845 GB, free: 87.11 MB / 1.982 GB
Notification: Performance of Backend: time 8.11e-07/8.728, allocations: 0 / 3.845 GB, free: 87.11 MB / 1.982 GB
Notification: Performance of simCode: created initialization part: time 0.1075/8.836, allocations: 38.52 MB / 3.882 GB, free: 49.17 MB / 1.982 GB
Notification: Performance of SimCode: time 1.495/10.33, allocations: 64.06 MB / 3.945 GB, free: 0.8277 GB / 1.983 GB
Notification: Performance of Templates: time 0.9061/11.24, allocations: 0.7093 GB / 4.654 GB, free: 204.2 MB / 1.983 GB
"
[Timeout remaining time 289]
make -j1 -f Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer.pipe ; mkfifo Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer.pipe ; head -c 1048576 < Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer.pipe >> ../files/Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer.sim & ./Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Applications.DataCenters.ChillerCooled.Examples.IntegratedPrimarySecondaryEconomizer.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 41.06760028399731]
Failed to read output from testmodel.py, exit status != 0:
11.24672229099815 11.256711075 6.803079661
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff6d307ef80>