Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer.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.001664/0.001664, allocations: 105.2 kB / 19.72 MB, free: 2.234 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.001832/0.001832, allocations: 214.7 kB / 23.04 MB, free: 5.07 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.423/1.423, allocations: 230.6 MB / 256.8 MB, free: 7.996 MB / 206.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: 476.8 MB / 0.7715 GB, free: 4.297 MB / 0.6231 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: "" <> buildModelFMU(Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer,fileNamePrefix="Buildings_11_Buildings_Applications_DataCenters_DXCooled_Examples_DXCooledAirsideEconomizer",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer,fileNamePrefix="Buildings_11_Buildings_Applications_DataCenters_DXCooled_Examples_DXCooledAirsideEconomizer",fmuType="cs",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.001938/0.001938, allocations: 66.69 kB / 1.118 GB, free: 39.77 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3128/0.3148, allocations: 170 MB / 1.284 GB, free: 11.62 MB / 0.9825 GB
Notification: Performance of NFInst.instantiate(Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer): time 0.366/0.6807, allocations: 324.1 MB / 1.601 GB, free: 6.387 MB / 1.295 GB
Notification: Performance of NFInst.instExpressions: time 0.8751/1.556, allocations: 48.44 MB / 1.648 GB, free: 19 MB / 1.295 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01023/1.566, allocations: 242.9 kB / 1.648 GB, free: 19 MB / 1.295 GB
Notification: Performance of NFTyping.typeComponents: time 0.01225/1.578, allocations: 4.106 MB / 1.652 GB, free: 18.92 MB / 1.295 GB
Notification: Performance of NFTyping.typeBindings: time 0.02337/1.602, allocations: 7.686 MB / 1.66 GB, free: 18.69 MB / 1.295 GB
Notification: Performance of NFTyping.typeClassSections: time 0.02362/1.625, allocations: 8.135 MB / 1.668 GB, free: 18.42 MB / 1.295 GB
Notification: Performance of NFFlatten.flatten: time 0.0319/1.657, allocations: 23.3 MB / 1.69 GB, free: 18.31 MB / 1.295 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01154/1.669, allocations: 6.513 MB / 1.697 GB, free: 17.73 MB / 1.295 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01731/1.686, allocations: 7.371 MB / 1.704 GB, free: 17.46 MB / 1.295 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01325/1.699, allocations: 6.627 MB / 1.71 GB, free: 16.39 MB / 1.295 GB
Notification: Performance of NFPackage.collectConstants: time 0.005585/1.705, allocations: 1.389 MB / 1.712 GB, free: 16.39 MB / 1.295 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02051/1.725, allocations: 9.058 MB / 1.721 GB, free: 16.09 MB / 1.295 GB
Notification: Performance of NFScalarize.scalarize: time 0.007222/1.733, allocations: 4.333 MB / 1.725 GB, free: 13.88 MB / 1.295 GB
Notification: Performance of NFVerifyModel.verify: time 0.01445/1.747, allocations: 6.745 MB / 1.731 GB, free: 9.352 MB / 1.295 GB
Notification: Performance of NFConvertDAE.convert: time 0.04182/1.789, allocations: 25.42 MB / 1.756 GB, free: 8.125 MB / 1.311 GB
Notification: Performance of FrontEnd - DAE generated: time 4.969e-06/1.789, allocations: 0 / 1.756 GB, free: 8.125 MB / 1.311 GB
Notification: Performance of FrontEnd: time 1.834e-06/1.789, allocations: 0 / 1.756 GB, free: 8.125 MB / 1.311 GB
Notification: Performance of Transformations before backend: time 0.000741/1.79, allocations: 6.688 kB / 1.756 GB, free: 8.121 MB / 1.311 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1251
 * Number of variables: 1251
Notification: Performance of Generate backend data structure: time 0.05373/1.843, allocations: 15.15 MB / 1.771 GB, free: 12.19 MB / 1.326 GB
Notification: Performance of prepare preOptimizeDAE: time 6.069e-05/1.843, allocations: 16.03 kB / 1.771 GB, free: 12.17 MB / 1.326 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0002122/1.844, allocations: 127.1 kB / 1.771 GB, free: 12.05 MB / 1.326 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.00339/1.847, allocations: 0.6681 MB / 1.772 GB, free: 11.37 MB / 1.326 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04715/1.894, allocations: 5.349 MB / 1.777 GB, free: 6.023 MB / 1.326 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03887/1.933, allocations: 13.95 MB / 1.791 GB, free: 8.43 MB / 1.342 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001739/1.935, allocations: 0.8358 MB / 1.792 GB, free: 7.527 MB / 1.342 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003258/1.938, allocations: 0.5341 MB / 1.792 GB, free: 6.996 MB / 1.342 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03275/1.971, allocations: 10.84 MB / 1.803 GB, free: 12 MB / 1.357 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001988/1.971, allocations: 6.812 kB / 1.803 GB, free: 12 MB / 1.357 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002273/1.973, allocations: 208 kB / 1.803 GB, free: 11.8 MB / 1.357 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002254/1.973, allocations: 189.4 kB / 1.803 GB, free: 11.61 MB / 1.357 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03901/2.012, allocations: 13 MB / 1.816 GB, free: 14.62 MB / 1.373 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.09666/2.109, allocations: 41.36 MB / 1.856 GB, free: 4.828 MB / 1.404 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0283/2.137, allocations: 8.887 MB / 1.865 GB, free: 11.95 MB / 1.42 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01607/2.153, allocations: 5.582 MB / 1.87 GB, free: 6.367 MB / 1.42 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001459/2.155, allocations: 273 kB / 1.87 GB, free: 6.129 MB / 1.42 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02135/2.176, allocations: 7.559 MB / 1.878 GB, free: 14.55 MB / 1.436 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.007902/2.184, allocations: 0.6579 MB / 1.878 GB, free: 13.89 MB / 1.436 GB
Notification: Performance of pre-optimization done (n=470): time 1.739e-05/2.184, allocations: 0 / 1.878 GB, free: 13.89 MB / 1.436 GB
Notification: Performance of matching and sorting (n=470): time 0.08483/2.269, allocations: 19.38 MB / 1.897 GB, free: 10.51 MB / 1.451 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002538/2.269, allocations: 303 kB / 1.898 GB, free: 10.16 MB / 1.451 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03995/2.309, allocations: 14.22 MB / 1.912 GB, free: 11.99 MB / 1.467 GB
Notification: Performance of collectPreVariables (initialization): time 0.002367/2.312, allocations: 193.7 kB / 1.912 GB, free: 11.8 MB / 1.467 GB
Notification: Performance of collectInitialEqns (initialization): time 0.008548/2.32, allocations: 4.558 MB / 1.916 GB, free: 7.242 MB / 1.467 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003196/2.323, allocations: 1.572 MB / 1.918 GB, free: 5.699 MB / 1.467 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00652/2.33, allocations: 1.32 MB / 1.919 GB, free: 4.371 MB / 1.467 GB
Notification: Performance of setup shared object (initialization): time 0.0001256/2.33, allocations: 305.1 kB / 1.919 GB, free: 4.07 MB / 1.467 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01816/2.348, allocations: 6.901 MB / 1.926 GB, free: 13.16 MB / 1.482 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0202/2.368, allocations: 7.85 MB / 1.934 GB, free: 4.895 MB / 1.482 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4999/2.868, allocations: 162.1 MB / 2.092 GB, free: 2.262 MB / 1.639 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.928e-05/2.868, allocations: 7.938 kB / 2.092 GB, free: 2.254 MB / 1.639 GB
Notification: Performance of matching and sorting (n=716) (initialization): time 0.06906/2.937, allocations: 17.28 MB / 2.109 GB, free: 0.9727 MB / 1.654 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002192/2.938, allocations: 137.1 kB / 2.109 GB, free: 0.8203 MB / 1.654 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.022e-05/2.938, allocations: 16 kB / 2.109 GB, free: 0.8047 MB / 1.654 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008435/2.946, allocations: 1.764 MB / 2.111 GB, free: 15.02 MB / 1.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01067/2.957, allocations: 1.535 MB / 2.112 GB, free: 13.55 MB / 1.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02257/2.979, allocations: 16.22 MB / 2.128 GB, free: 12.48 MB / 1.686 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01485/2.994, allocations: 0.8213 MB / 2.129 GB, free: 11.66 MB / 1.686 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002301/2.996, allocations: 331.7 kB / 2.129 GB, free: 11.34 MB / 1.686 GB
Warning: Assuming fixed start value for the following 1 variables:
         varSpeDX.watVapEva.off:DISCRETE(fixed = true protected = true )  \"Signal, true when component is off\" type: Boolean
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.02752/3.024, allocations: 9.79 MB / 2.139 GB, free: 1.535 MB / 1.686 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.02012/3.044, allocations: 7.732 MB / 2.146 GB, free: 9.406 MB / 1.701 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 1.155/4.199, allocations: 161.6 MB / 2.304 GB, free: 0.766 GB / 1.811 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.737e-05/4.199, allocations: 3.75 kB / 2.304 GB, free: 0.766 GB / 1.811 GB
Notification: Performance of matching and sorting (n=716) (initialization_lambda0): time 0.05799/4.257, allocations: 16.83 MB / 2.321 GB, free: 0.7599 GB / 1.811 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001912/4.258, allocations: 139.1 kB / 2.321 GB, free: 0.7598 GB / 1.811 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.498e-05/4.258, allocations: 15.28 kB / 2.321 GB, free: 0.7598 GB / 1.811 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006043/4.264, allocations: 1.517 MB / 2.322 GB, free: 0.7591 GB / 1.811 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01043/4.274, allocations: 1.548 MB / 2.324 GB, free: 0.7586 GB / 1.811 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01387/4.288, allocations: 15.45 MB / 2.339 GB, free: 0.7428 GB / 1.811 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0145/4.303, allocations: 0.8826 MB / 2.34 GB, free: 0.742 GB / 1.811 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001808/4.304, allocations: 323.7 kB / 2.34 GB, free: 0.7417 GB / 1.811 GB
Warning: Assuming fixed start value for the following 1 variables:
         varSpeDX.watVapEva.off:DISCRETE(fixed = true protected = true )  \"Signal, true when component is off\" type: Boolean
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 76
 * Number of states: 0 ()
 * Number of discrete variables: 152 (varSpeDX.i_x,$PRE.cooModCon.con1.enableFire,$PRE.cooModCon.con2.enableFire,$PRE.cooModCon.con3.enableFire,$PRE.cooModCon.con4.enableFire,swiTim.y,$PRE.swiTim.y,cooModCon.stateGraphRoot.resume,cooModCon.stateGraphRoot.suspend,speCon.conInt.y,ecoCon.conInt.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.varSpeDX.deaBan.y,$PRE.varSpeDX.watVapEva.off,$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[4],$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[3],$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[2],$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[1],$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[4],$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[3],$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[2],$PRE.varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[1],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[4],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[3],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[2],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[1],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[4],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[3],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[2],$PRE.varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[1],cooModCon.swi.expr[3],cooModCon.swi.expr[2],cooModCon.swi.expr[1],speCon.not1.y,ecoCon.ecoOff.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,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,varSpeDX.deaBan.y,varSpeDX.watVapEva.off,varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[4],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[3],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[2],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[1],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[4],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[3],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[2],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[1],varSpeDX.dxCoi.dryCoi.coiCap.stage,varSpeDX.dxCoi.wetCoi.shr.on,varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[4],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[3],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[2],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[1],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[4],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[3],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[2],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[1],varSpeDX.dxCoi.wetCoi.coiCap.stage,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,swiTim.local_set,swiTim.local_reset,fanSpe.I.trigger_internal,ecoCon.con.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 (635):
 * Single equations (assignments): 618
 * Array equations: 2
 * Algorithm blocks: 3
 * Record equations: 1
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * Torn equation systems: 9
 * 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): 2 systems
   {7, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 9 systems
   {(1,1), (1,2), (1,12), (1,2), (1,2), (1,2), (1,2), (1,2), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.003269/4.308, allocations: 0.5777 MB / 2.341 GB, free: 0.7411 GB / 1.811 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004531/4.312, allocations: 1.025 MB / 2.342 GB, free: 0.7407 GB / 1.811 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05377/4.366, allocations: 14.28 MB / 2.356 GB, free: 0.727 GB / 1.811 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.198e-05/4.366, allocations: 48 kB / 2.356 GB, free: 0.727 GB / 1.811 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.899e-05/4.366, allocations: 0 / 2.356 GB, free: 0.727 GB / 1.811 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.04728/4.413, allocations: 12.4 MB / 2.368 GB, free: 0.715 GB / 1.811 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.08414/4.497, allocations: 28.43 MB / 2.395 GB, free: 0.6875 GB / 1.811 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.136e-05/4.497, allocations: 0 / 2.395 GB, free: 0.6875 GB / 1.811 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0084/4.506, allocations: 1.23 MB / 2.397 GB, free: 0.6863 GB / 1.811 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002361/4.508, allocations: 0.6505 MB / 2.397 GB, free: 0.6857 GB / 1.811 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001568/4.51, allocations: 191.7 kB / 2.397 GB, free: 0.6855 GB / 1.811 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00589/4.516, allocations: 5.987 MB / 2.403 GB, free: 0.6794 GB / 1.811 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.274e-06/4.516, allocations: 0 / 2.403 GB, free: 0.6794 GB / 1.811 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.06815/4.584, allocations: 21.18 MB / 2.424 GB, free: 0.6588 GB / 1.811 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.008006/4.592, allocations: 2.833 MB / 2.427 GB, free: 0.6561 GB / 1.811 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004117/4.596, allocations: 231.9 kB / 2.427 GB, free: 0.6558 GB / 1.811 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01166/4.608, allocations: 0.7278 MB / 2.428 GB, free: 0.6551 GB / 1.811 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001274/4.609, allocations: 427.1 kB / 2.428 GB, free: 0.6547 GB / 1.811 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001164/4.61, allocations: 251.8 kB / 2.428 GB, free: 0.6545 GB / 1.811 GB
Notification: Performance of sorting global known variables: time 0.01775/4.628, allocations: 9.913 MB / 2.438 GB, free: 0.6449 GB / 1.811 GB
Notification: Performance of sort global known variables: time 2.61e-07/4.628, allocations: 4 kB / 2.438 GB, free: 0.6449 GB / 1.811 GB
Notification: Performance of remove unused functions: time 0.01816/4.646, allocations: 4.369 MB / 2.442 GB, free: 0.6408 GB / 1.811 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 23 (EIT.y,FCTim.y,PMCTim.y,FMCHou.y,EHVAC.y,roo.rooVol.dynBal.medium.Xi[1],roo.rooVol.dynBal.U,roo.rooVol.dynBal.m,varSpeDX.vol.dynBal.medium.Xi[1],varSpeDX.vol.dynBal.U,varSpeDX.vol.dynBal.m,varSpeDX.watVapEva.m,fan.filter.s[1],fan.filter.s[2],senTemSupAir.T,dam1.filter.s[1],dam1.filter.s[2],dam2.filter.s[1],dam2.filter.s[2],senTemMixAir.T,ecoCon.con.I.y,fanSpe.I.y,speCon.dxSpe.I.y)
 * Number of discrete variables: 92 (sigCha.y,cooModCon.con3.enableFire,cooModCon.con4.enableFire,cooModCon.con2.enableFire,cooModCon.con1.enableFire,speCon.not1.y,cooModCon.parMecCoo.active,cooModCon.freCoo.active,cooModCon.fulMecCoo.active,$whenCondition28,$whenCondition27,$whenCondition26,$whenCondition25,$whenCondition24,$whenCondition23,$whenCondition22,$whenCondition21,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,sigCha.u,swiTim.y,varSpeDX.dxCoi.wetCoi.coiCap.stage,varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[1],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[2],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[3],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[4],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[1],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[2],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[3],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[4],varSpeDX.dxCoi.wetCoi.shr.on,varSpeDX.dxCoi.dryCoi.coiCap.stage,varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[1],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[2],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[3],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[4],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[1],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[2],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[3],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[4],varSpeDX.watVapEva.off,varSpeDX.deaBan.y,weaDat.locTim.tNext,weaDat.conTimMin.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext,cooModCon.con1.localCondition,cooModCon.con1.fire,cooModCon.con1.t_start,cooModCon.parMecCoo.localActive,cooModCon.parMecCoo.inPort[2].occupied,cooModCon.parMecCoo.outPort[2].available,cooModCon.parMecCoo.newActive,cooModCon.parMecCoo.oldActive,cooModCon.freCoo.localActive,cooModCon.freCoo.newActive,cooModCon.freCoo.oldActive,cooModCon.fulMecCoo.localActive,cooModCon.fulMecCoo.newActive,cooModCon.fulMecCoo.oldActive,cooModCon.con2.localCondition,cooModCon.con2.fire,cooModCon.con2.t_start,cooModCon.con3.localCondition,cooModCon.con3.fire,cooModCon.con3.t_start,cooModCon.con4.localCondition,cooModCon.con4.fire,cooModCon.con4.t_start,cooModCon.stateGraphRoot.activeSteps,cooModCon.swi.y,cooModCon.swi.firstActiveIndex,ecoCon.ecoOff.y)
 * Number of discrete states: 19 (cooModCon.fulMecCoo.newActive,cooModCon.freCoo.newActive,cooModCon.parMecCoo.newActive,cooModCon.swi.y,weaDat.conTimMin.tNext,weaDat.decAng.tNext,weaDat.eqnTim.tNext,weaDat.conTim.tNext,weaDat.locTim.tNext,varSpeDX.deaBan.y,varSpeDX.dxCoi.wetCoi.coiCap.stage,varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTCon[varSpeDX.dxCoi.wetCoi.coiCap.stage],varSpeDX.dxCoi.wetCoi.coiCap.checkBoundsTEva[varSpeDX.dxCoi.wetCoi.coiCap.stage],varSpeDX.dxCoi.dryCoi.coiCap.stage,varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTCon[varSpeDX.dxCoi.dryCoi.coiCap.stage],varSpeDX.dxCoi.dryCoi.coiCap.checkBoundsTEva[varSpeDX.dxCoi.dryCoi.coiCap.stage],varSpeDX.watVapEva.off,sigCha.u,swiTim.y)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (480):
 * Single equations (assignments): 445
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 29
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 5
 * 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
   {7}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 5 systems
   {(1,1), (1,3), (1,11), (1,1), (1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.05446/4.7, allocations: 21.69 MB / 2.463 GB, free: 0.6199 GB / 1.811 GB
Notification: Performance of simCode: created initialization part: time 0.05293/4.753, allocations: 23.39 MB / 2.486 GB, free: 0.5968 GB / 1.811 GB
Notification: Performance of simCode: created event and clocks part: time 1.049e-05/4.753, allocations: 5 kB / 2.486 GB, free: 0.5968 GB / 1.811 GB
Notification: Performance of simCode: created simulation system equations: time 0.01537/4.769, allocations: 6.16 MB / 2.492 GB, free: 0.5908 GB / 1.811 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02033/4.789, allocations: 3.667 MB / 2.496 GB, free: 0.5873 GB / 1.811 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3418/5.131, allocations: 137.9 MB / 2.631 GB, free: 462.6 MB / 1.811 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008438/5.139, allocations: 5.988 MB / 2.636 GB, free: 456.6 MB / 1.811 GB
Notification: Performance of simCode: alias equations: time 0.0153/5.155, allocations: 3.625 MB / 2.64 GB, free: 452.9 MB / 1.811 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01021/5.165, allocations: 4.674 MB / 2.645 GB, free: 448.3 MB / 1.811 GB
Notification: Performance of SimCode: time 1.543e-06/5.165, allocations: 0 / 2.645 GB, free: 448.3 MB / 1.811 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.389/5.554, allocations: 314.1 MB / 2.951 GB, free: 135.6 MB / 1.811 GB
Notification: Performance of buildModelFMU: Generate platform static: time 44.19/49.74, allocations: 15.86 kB / 2.951 GB, free: 135.6 MB / 1.811 GB
"
[Timeout remaining time 249]
(rm -f Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer.pipe ; mkfifo Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer.pipe ; head -c 1048576 < Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer.pipe >> ../files/Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer_res.mat --tempDir=temp_Buildings_11_Buildings_Applications_DataCenters_DXCooled_Examples_DXCooledAirsideEconomizer_fmu --startTime=1.188e+07 --stopTime=1.26e+07 --stepSize=288 --timeout=400 --tolerance=1e-06 Buildings_11_Buildings_Applications_DataCenters_DXCooled_Examples_DXCooledAirsideEconomizer.fmu > Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer.pipe 2>&1) [Timeout 420.0]
[Calling os._exit(0), Time elapsed: 57.06030336598633]
Failed to read output from testmodel.py, exit status != 0:
50.66874785203254 50.226840215 48.433635249
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe5cc9cb3a0>