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.001739/0.001739, allocations: 114.3 kB / 18.94 MB, free: 2.957 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.001872/0.001872, allocations: 217.3 kB / 22.26 MB, free: 5.113 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.477/1.477, allocations: 230.6 MB / 256 MB, free: 8.008 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.835/2.835, allocations: 476.8 MB / 0.7707 GB, free: 4.277 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="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer,fileNamePrefix="Buildings_11_Buildings_Applications_DataCenters_DXCooled_Examples_DXCooledAirsideEconomizer",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.002016/0.002016, allocations: 69.75 kB / 1.117 GB, free: 8.98 MB / 0.8419 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2994/0.3014, allocations: 170 MB / 1.283 GB, free: 3.793 MB / 0.9825 GB
Notification: Performance of NFInst.instantiate(Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer): time 1.046/1.348, allocations: 324 MB / 1.6 GB, free: 20.97 MB / 1.264 GB
Notification: Performance of NFInst.instExpressions: time 0.06412/1.412, allocations: 48.47 MB / 1.647 GB, free: 20.44 MB / 1.264 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.009057/1.421, allocations: 246.3 kB / 1.647 GB, free: 20.44 MB / 1.264 GB
Notification: Performance of NFTyping.typeComponents: time 0.01121/1.432, allocations: 4.107 MB / 1.651 GB, free: 20.34 MB / 1.264 GB
Notification: Performance of NFTyping.typeBindings: time 0.02115/1.453, allocations: 7.673 MB / 1.659 GB, free: 20.02 MB / 1.264 GB
Notification: Performance of NFTyping.typeClassSections: time 0.018/1.471, allocations: 8.135 MB / 1.667 GB, free: 19.69 MB / 1.264 GB
Notification: Performance of NFFlatten.flatten: time 0.02901/1.5, allocations: 23.3 MB / 1.69 GB, free: 16.83 MB / 1.264 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01145/1.512, allocations: 6.518 MB / 1.696 GB, free: 13.82 MB / 1.264 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01489/1.526, allocations: 7.369 MB / 1.703 GB, free: 10.91 MB / 1.264 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01132/1.538, allocations: 6.625 MB / 1.71 GB, free: 7.684 MB / 1.264 GB
Notification: Performance of NFPackage.collectConstants: time 0.004923/1.543, allocations: 1.388 MB / 1.711 GB, free: 7.684 MB / 1.264 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01947/1.562, allocations: 9.054 MB / 1.72 GB, free: 4.453 MB / 1.264 GB
Notification: Performance of NFScalarize.scalarize: time 0.007352/1.569, allocations: 4.342 MB / 1.724 GB, free: 1.348 MB / 1.264 GB
Notification: Performance of NFVerifyModel.verify: time 0.01684/1.586, allocations: 6.742 MB / 1.731 GB, free: 12.36 MB / 1.279 GB
Notification: Performance of NFConvertDAE.convert: time 0.04208/1.628, allocations: 25.42 MB / 1.756 GB, free: 6.605 MB / 1.295 GB
Notification: Performance of FrontEnd - DAE generated: time 6.873e-06/1.628, allocations: 0 / 1.756 GB, free: 6.605 MB / 1.295 GB
Notification: Performance of FrontEnd: time 1.502e-06/1.628, allocations: 0 / 1.756 GB, free: 6.605 MB / 1.295 GB
Notification: Performance of Transformations before backend: time 0.0004975/1.629, allocations: 0 / 1.756 GB, free: 6.605 MB / 1.295 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.04385/1.673, allocations: 15.16 MB / 1.77 GB, free: 7.336 MB / 1.311 GB
Notification: Performance of prepare preOptimizeDAE: time 4.815e-05/1.673, allocations: 12.03 kB / 1.77 GB, free: 7.324 MB / 1.311 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002205/1.675, allocations: 0.6622 MB / 1.771 GB, free: 6.652 MB / 1.311 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04507/1.72, allocations: 5.341 MB / 1.776 GB, free: 1.309 MB / 1.311 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03405/1.754, allocations: 13.97 MB / 1.79 GB, free: 3.699 MB / 1.326 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00119/1.755, allocations: 0.8292 MB / 1.791 GB, free: 2.805 MB / 1.326 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002117/1.757, allocations: 0.5351 MB / 1.791 GB, free: 2.27 MB / 1.326 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0272/1.785, allocations: 10.84 MB / 1.802 GB, free: 7.273 MB / 1.342 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001099/1.785, allocations: 5.25 kB / 1.802 GB, free: 7.273 MB / 1.342 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001247/1.786, allocations: 208 kB / 1.802 GB, free: 7.07 MB / 1.342 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001897/1.786, allocations: 185.4 kB / 1.802 GB, free: 6.887 MB / 1.342 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03648/1.823, allocations: 13 MB / 1.815 GB, free: 9.898 MB / 1.357 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.09024/1.913, allocations: 41.37 MB / 1.855 GB, free: 15.99 MB / 1.404 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02573/1.939, allocations: 8.896 MB / 1.864 GB, free: 7.09 MB / 1.404 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01481/1.953, allocations: 5.571 MB / 1.869 GB, free: 1.508 MB / 1.404 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001361/1.955, allocations: 277.5 kB / 1.87 GB, free: 1.266 MB / 1.404 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01825/1.973, allocations: 7.565 MB / 1.877 GB, free: 9.676 MB / 1.42 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.006001/1.979, allocations: 0.646 MB / 1.878 GB, free: 9.035 MB / 1.42 GB
Notification: Performance of pre-optimization done (n=470): time 1.456e-05/1.979, allocations: 0 / 1.878 GB, free: 9.035 MB / 1.42 GB
Notification: Performance of matching and sorting (n=470): time 0.08181/2.061, allocations: 19.37 MB / 1.897 GB, free: 5.656 MB / 1.436 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002461/2.061, allocations: 316.8 kB / 1.897 GB, free: 5.297 MB / 1.436 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03203/2.093, allocations: 14.22 MB / 1.911 GB, free: 7.129 MB / 1.451 GB
Notification: Performance of collectPreVariables (initialization): time 0.001961/2.095, allocations: 189.7 kB / 1.911 GB, free: 6.938 MB / 1.451 GB
Notification: Performance of collectInitialEqns (initialization): time 0.006831/2.102, allocations: 4.557 MB / 1.915 GB, free: 2.383 MB / 1.451 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003025/2.105, allocations: 1.575 MB / 1.917 GB, free: 0.8359 MB / 1.451 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00461/2.11, allocations: 1.312 MB / 1.918 GB, free: 15.52 MB / 1.467 GB
Notification: Performance of setup shared object (initialization): time 0.0001269/2.11, allocations: 305.1 kB / 1.918 GB, free: 15.21 MB / 1.467 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01657/2.126, allocations: 6.896 MB / 1.925 GB, free: 8.301 MB / 1.467 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01858/2.145, allocations: 7.857 MB / 1.933 GB, free: 40 kB / 1.467 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4992/2.644, allocations: 162.1 MB / 2.091 GB, free: 13.4 MB / 1.639 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.27e-05/2.644, allocations: 8 kB / 2.091 GB, free: 13.39 MB / 1.639 GB
Notification: Performance of matching and sorting (n=716) (initialization): time 0.06365/2.708, allocations: 17.27 MB / 2.108 GB, free: 12.12 MB / 1.654 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002186/2.708, allocations: 137.1 kB / 2.108 GB, free: 11.97 MB / 1.654 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.126e-05/2.708, allocations: 19.94 kB / 2.108 GB, free: 11.95 MB / 1.654 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008002/2.716, allocations: 1.768 MB / 2.11 GB, free: 10.16 MB / 1.654 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01034/2.726, allocations: 1.54 MB / 2.111 GB, free: 8.68 MB / 1.654 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02085/2.747, allocations: 16.23 MB / 2.127 GB, free: 7.617 MB / 1.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01406/2.761, allocations: 0.8203 MB / 2.128 GB, free: 6.801 MB / 1.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001939/2.763, allocations: 323.7 kB / 2.128 GB, free: 6.484 MB / 1.67 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.02478/2.788, allocations: 9.792 MB / 2.138 GB, free: 12.68 MB / 1.686 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01803/2.806, allocations: 7.733 MB / 2.146 GB, free: 4.535 MB / 1.686 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 1.105/3.911, allocations: 161.6 MB / 2.303 GB, free: 0.6637 GB / 1.748 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.806e-05/3.911, allocations: 4 kB / 2.303 GB, free: 0.6637 GB / 1.748 GB
Notification: Performance of matching and sorting (n=716) (initialization_lambda0): time 0.05544/3.966, allocations: 16.83 MB / 2.32 GB, free: 0.6495 GB / 1.748 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001846/3.967, allocations: 137.1 kB / 2.32 GB, free: 0.6494 GB / 1.748 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.175e-05/3.967, allocations: 20 kB / 2.32 GB, free: 0.6494 GB / 1.748 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005737/3.972, allocations: 1.518 MB / 2.321 GB, free: 0.6479 GB / 1.748 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009498/3.982, allocations: 1.549 MB / 2.323 GB, free: 0.6465 GB / 1.748 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01267/3.994, allocations: 15.45 MB / 2.338 GB, free: 0.6306 GB / 1.748 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01361/4.008, allocations: 0.884 MB / 2.339 GB, free: 0.6298 GB / 1.748 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001614/4.01, allocations: 319.7 kB / 2.339 GB, free: 0.6295 GB / 1.748 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.002226/4.012, allocations: 0.5725 MB / 2.34 GB, free: 0.6289 GB / 1.748 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004056/4.016, allocations: 1.023 MB / 2.341 GB, free: 0.6285 GB / 1.748 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04991/4.066, allocations: 14.29 MB / 2.355 GB, free: 0.6146 GB / 1.748 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.148e-05/4.066, allocations: 55.92 kB / 2.355 GB, free: 0.6145 GB / 1.748 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.168e-05/4.066, allocations: 3.938 kB / 2.355 GB, free: 0.6145 GB / 1.748 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.04448/4.11, allocations: 12.4 MB / 2.367 GB, free: 0.6023 GB / 1.748 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.07419/4.185, allocations: 28.43 MB / 2.395 GB, free: 0.5747 GB / 1.748 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.103e-05/4.185, allocations: 0 / 2.395 GB, free: 0.5747 GB / 1.748 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00826/4.193, allocations: 1.24 MB / 2.396 GB, free: 0.5735 GB / 1.748 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002281/4.195, allocations: 0.6438 MB / 2.396 GB, free: 0.5729 GB / 1.748 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001324/4.197, allocations: 191.7 kB / 2.397 GB, free: 0.5727 GB / 1.748 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.005493/4.202, allocations: 5.985 MB / 2.402 GB, free: 0.5665 GB / 1.748 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.746e-06/4.202, allocations: 0 / 2.402 GB, free: 0.5665 GB / 1.748 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.06287/4.265, allocations: 21.17 MB / 2.423 GB, free: 0.5458 GB / 1.748 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.008418/4.273, allocations: 2.849 MB / 2.426 GB, free: 0.5431 GB / 1.748 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004043/4.277, allocations: 219.9 kB / 2.426 GB, free: 0.5428 GB / 1.748 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01294/4.29, allocations: 0.7372 MB / 2.427 GB, free: 0.5421 GB / 1.748 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001606/4.292, allocations: 423.2 kB / 2.427 GB, free: 0.5417 GB / 1.748 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001784/4.294, allocations: 243.8 kB / 2.427 GB, free: 0.5415 GB / 1.748 GB
Notification: Performance of sorting global known variables: time 0.01967/4.313, allocations: 9.917 MB / 2.437 GB, free: 0.5318 GB / 1.748 GB
Notification: Performance of sort global known variables: time 4.1e-07/4.313, allocations: 0 / 2.437 GB, free: 0.5318 GB / 1.748 GB
Notification: Performance of remove unused functions: time 0.01783/4.331, allocations: 4.363 MB / 2.441 GB, free: 0.5278 GB / 1.748 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.04982/4.381, allocations: 21.7 MB / 2.463 GB, free: 0.5069 GB / 1.748 GB
Notification: Performance of simCode: created initialization part: time 0.04897/4.43, allocations: 23.4 MB / 2.485 GB, free: 495.4 MB / 1.748 GB
Notification: Performance of simCode: created event and clocks part: time 8.075e-06/4.43, allocations: 5 kB / 2.485 GB, free: 495.4 MB / 1.748 GB
Notification: Performance of simCode: created simulation system equations: time 0.01458/4.445, allocations: 6.153 MB / 2.491 GB, free: 489.2 MB / 1.748 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01823/4.463, allocations: 3.657 MB / 2.495 GB, free: 485.7 MB / 1.748 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3281/4.791, allocations: 137.9 MB / 2.63 GB, free: 346.9 MB / 1.748 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01072/4.802, allocations: 5.984 MB / 2.636 GB, free: 340.8 MB / 1.748 GB
Notification: Performance of simCode: alias equations: time 0.01551/4.817, allocations: 3.621 MB / 2.639 GB, free: 337.2 MB / 1.748 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.009383/4.826, allocations: 4.674 MB / 2.644 GB, free: 332.5 MB / 1.748 GB
Notification: Performance of SimCode: time 1.523e-06/4.826, allocations: 0 / 2.644 GB, free: 332.5 MB / 1.748 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.4075/5.234, allocations: 314.1 MB / 2.95 GB, free: 19.92 MB / 1.748 GB
Notification: Performance of buildModelFMU: Generate platform static: time 48.56/53.8, allocations: 0 / 2.95 GB, free: 19.92 MB / 1.748 GB
"
[Timeout remaining time 246]
(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 & python3 -m fmpy simulate  --output-file Buildings_11_Buildings.Applications.DataCenters.DXCooled.Examples.DXCooledAirsideEconomizer_res.csv --start-time 1.188e+07 --stop-time 1.26e+07 --timeout 400 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 288 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: 60.96025022499998]
Failed to read output from testmodel.py, exit status != 0:
54.20354214500003 54.213196847 52.582102526
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f8a54a2af50>