Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.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.001293/0.001293, allocations: 116.8 kB / 17.17 MB, free: 5.977 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.0014/0.0014, allocations: 209.6 kB / 20.13 MB, free: 3.035 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.473/1.473, allocations: 230.6 MB / 253.5 MB, free: 15.1 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.748/2.748, allocations: 476.7 MB / 0.7678 GB, free: 9.359 MB / 0.6075 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.DHC.Plants.Cooling.Examples.StoragePlantDualSource,fileNamePrefix="Buildings_11_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource,fileNamePrefix="Buildings_11_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource",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.00199/0.00199, allocations: 63.89 kB / 1.113 GB, free: 15.84 MB / 0.8575 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.9261/0.9281, allocations: 169.9 MB / 1.279 GB, free: 11.73 MB / 0.9981 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource): time 1.619/2.547, allocations: 0.5563 GB / 1.835 GB, free: 98.61 MB / 1.498 GB
Notification: Performance of NFInst.instExpressions: time 0.1878/2.735, allocations: 116.8 MB / 1.949 GB, free: 97.24 MB / 1.498 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0366/2.772, allocations: 0.8155 MB / 1.95 GB, free: 97.24 MB / 1.498 GB
Notification: Performance of NFTyping.typeComponents: time 0.03369/2.805, allocations: 9.283 MB / 1.959 GB, free: 97.08 MB / 1.498 GB
Notification: Performance of NFTyping.typeBindings: time 0.07347/2.879, allocations: 21.07 MB / 1.98 GB, free: 96.3 MB / 1.498 GB
Notification: Performance of NFTyping.typeClassSections: time 0.04206/2.921, allocations: 14.94 MB / 1.994 GB, free: 92.35 MB / 1.498 GB
Notification: Performance of NFFlatten.flatten: time 0.1038/3.025, allocations: 66.07 MB / 2.059 GB, free: 58.51 MB / 1.498 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.03709/3.062, allocations: 15.64 MB / 2.074 GB, free: 42.99 MB / 1.498 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.04843/3.11, allocations: 19.48 MB / 2.093 GB, free: 24.58 MB / 1.498 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02709/3.137, allocations: 14.23 MB / 2.107 GB, free: 11.35 MB / 1.498 GB
Notification: Performance of NFPackage.collectConstants: time 0.009339/3.147, allocations: 2.281 MB / 2.109 GB, free: 9.07 MB / 1.498 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02979/3.176, allocations: 8.849 MB / 2.118 GB, free: 0.6055 MB / 1.498 GB
Notification: Performance of NFScalarize.scalarize: time 0.01241/3.189, allocations: 5.776 MB / 2.124 GB, free: 10.91 MB / 1.514 GB
Notification: Performance of NFVerifyModel.verify: time 0.03042/3.219, allocations: 11.3 MB / 2.135 GB, free: 15.67 MB / 1.529 GB
Notification: Performance of NFConvertDAE.convert: time 0.07525/3.294, allocations: 36.41 MB / 2.17 GB, free: 12.25 MB / 1.561 GB
Notification: Performance of FrontEnd - DAE generated: time 7.144e-06/3.294, allocations: 0 / 2.17 GB, free: 12.25 MB / 1.561 GB
Notification: Performance of FrontEnd: time 2.014e-06/3.294, allocations: 0 / 2.17 GB, free: 12.25 MB / 1.561 GB
Notification: Performance of Transformations before backend: time 0.001228/3.296, allocations: 0 / 2.17 GB, free: 12.25 MB / 1.561 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2854
 * Number of variables: 2854
Notification: Performance of Generate backend data structure: time 0.06003/3.356, allocations: 21.32 MB / 2.191 GB, free: 7.008 MB / 1.576 GB
Notification: Performance of prepare preOptimizeDAE: time 4.984e-05/3.356, allocations: 12.03 kB / 2.191 GB, free: 6.996 MB / 1.576 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0003336/3.356, allocations: 290.1 kB / 2.191 GB, free: 6.711 MB / 1.576 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.004814/3.361, allocations: 1.451 MB / 2.193 GB, free: 5.254 MB / 1.576 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01422/3.375, allocations: 2.717 MB / 2.195 GB, free: 2.523 MB / 1.576 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06661/3.442, allocations: 28.99 MB / 2.224 GB, free: 6.105 MB / 1.607 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001144/3.443, allocations: 0.8775 MB / 2.224 GB, free: 5.23 MB / 1.607 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004583/3.447, allocations: 1.155 MB / 2.226 GB, free: 4.078 MB / 1.607 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04131/3.489, allocations: 14.22 MB / 2.239 GB, free: 5.375 MB / 1.623 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003984/3.489, allocations: 31.75 kB / 2.24 GB, free: 5.344 MB / 1.623 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003303/3.493, allocations: 471.1 kB / 2.24 GB, free: 4.887 MB / 1.623 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004482/3.493, allocations: 436.9 kB / 2.24 GB, free: 4.457 MB / 1.623 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02949/3.522, allocations: 10.78 MB / 2.251 GB, free: 9.668 MB / 1.639 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.1242/3.647, allocations: 73.3 MB / 2.322 GB, free: 12.19 MB / 1.717 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01306/3.66, allocations: 5.47 MB / 2.328 GB, free: 6.652 MB / 1.717 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007456/3.667, allocations: 3.494 MB / 2.331 GB, free: 3.113 MB / 1.717 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.04074/3.708, allocations: 17.22 MB / 2.348 GB, free: 1.371 MB / 1.732 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.009015/3.717, allocations: 4.032 MB / 2.352 GB, free: 13.2 MB / 1.748 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.005731/3.723, allocations: 0.6231 MB / 2.353 GB, free: 12.58 MB / 1.748 GB
Notification: Performance of pre-optimization done (n=547): time 2.214e-05/3.723, allocations: 0 / 2.353 GB, free: 12.58 MB / 1.748 GB
Notification: Performance of matching and sorting (n=547): time 0.04102/3.764, allocations: 12.22 MB / 2.365 GB, free: 216 kB / 1.748 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004772/3.764, allocations: 0.6202 MB / 2.365 GB, free: 15.46 MB / 1.764 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03298/3.797, allocations: 14.41 MB / 2.379 GB, free: 1.121 MB / 1.764 GB
Notification: Performance of collectPreVariables (initialization): time 0.001603/3.799, allocations: 213.7 kB / 2.379 GB, free: 0.9062 MB / 1.764 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01216/3.811, allocations: 8.036 MB / 2.387 GB, free: 8.91 MB / 1.779 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003472/3.814, allocations: 1.897 MB / 2.389 GB, free: 7.043 MB / 1.779 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004793/3.819, allocations: 2.04 MB / 2.391 GB, free: 4.988 MB / 1.779 GB
Notification: Performance of setup shared object (initialization): time 0.0001326/3.819, allocations: 305.1 kB / 2.391 GB, free: 4.688 MB / 1.779 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008842/3.828, allocations: 4.387 MB / 2.396 GB, free: 300 kB / 1.779 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01386/3.842, allocations: 7.392 MB / 2.403 GB, free: 7.602 MB / 1.795 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02131/3.863, allocations: 11.38 MB / 2.414 GB, free: 11.13 MB / 1.811 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.072e-05/3.863, allocations: 20 kB / 2.414 GB, free: 11.11 MB / 1.811 GB
Notification: Performance of matching and sorting (n=1209) (initialization): time 0.03184/3.895, allocations: 13.63 MB / 2.427 GB, free: 13.43 MB / 1.826 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001596/3.895, allocations: 133.1 kB / 2.427 GB, free: 13.28 MB / 1.826 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001174/3.896, allocations: 76 kB / 2.428 GB, free: 13.21 MB / 1.826 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003636/3.899, allocations: 1.181 MB / 2.429 GB, free: 12.02 MB / 1.826 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008141/3.907, allocations: 1.708 MB / 2.43 GB, free: 10.31 MB / 1.826 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006939/3.914, allocations: 4.964 MB / 2.435 GB, free: 5.164 MB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01147/3.926, allocations: 483.8 kB / 2.436 GB, free: 4.695 MB / 1.826 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001857/3.928, allocations: 467.8 kB / 2.436 GB, free: 4.238 MB / 1.826 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01828/3.946, allocations: 7.78 MB / 2.444 GB, free: 12.46 MB / 1.842 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01259/3.958, allocations: 7.044 MB / 2.451 GB, free: 4.109 MB / 1.842 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02018/3.979, allocations: 10.64 MB / 2.461 GB, free: 8.371 MB / 1.857 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.792e-05/3.979, allocations: 28 kB / 2.461 GB, free: 8.344 MB / 1.857 GB
Notification: Performance of matching and sorting (n=1209) (initialization_lambda0): time 0.03518/4.014, allocations: 12.99 MB / 2.474 GB, free: 11.3 MB / 1.873 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001627/4.014, allocations: 130.8 kB / 2.474 GB, free: 11.16 MB / 1.873 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001116/4.014, allocations: 80.16 kB / 2.474 GB, free: 11.08 MB / 1.873 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002657/4.017, allocations: 0.942 MB / 2.475 GB, free: 10.13 MB / 1.873 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007947/4.025, allocations: 1.712 MB / 2.476 GB, free: 8.414 MB / 1.873 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01785/4.043, allocations: 10.98 MB / 2.487 GB, free: 13.07 MB / 1.889 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01299/4.056, allocations: 0.5124 MB / 2.488 GB, free: 12.56 MB / 1.889 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002789/4.058, allocations: 431.9 kB / 2.488 GB, free: 12.14 MB / 1.889 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 345
 * Number of states: 0 ()
 * Number of discrete variables: 171 ($PRE.stoPla.floCon.traChaLoc.enableFire,$PRE.stoPla.floCon.traRes1.enableFire,$PRE.stoPla.floCon.traChaRem.enableFire,$PRE.stoPla.floCon.traRes2.enableFire,$PRE.stoPla.floCon.traProChi.enableFire,$PRE.stoPla.floCon.traRes4.enableFire,$PRE.stoPla.floCon.traProTan.enableFire,$PRE.stoPla.floCon.traRes3.enableFire,$PRE.stoPla.floCon.traPro.enableFire,$PRE.stoPla.floCon.traTanToChi.enableFire,$PRE.stoPla.floCon.traRou.enableFire,$PRE.stoPla.floCon.traChiToTan.enableFire,stoPla.floCon.traChiToTan.t_start,$PRE.stoPla.floCon.traChiToTan.t_start,stoPla.floCon.traRou.t_start,$PRE.stoPla.floCon.traRou.t_start,stoPla.floCon.traTanToChi.t_start,$PRE.stoPla.floCon.traTanToChi.t_start,stoPla.floCon.traPro.t_start,$PRE.stoPla.floCon.traPro.t_start,stoPla.floCon.traRes3.t_start,$PRE.stoPla.floCon.traRes3.t_start,stoPla.floCon.traProTan.t_start,$PRE.stoPla.floCon.traProTan.t_start,stoPla.floCon.traRes4.t_start,$PRE.stoPla.floCon.traRes4.t_start,stoPla.floCon.traProChi.t_start,$PRE.stoPla.floCon.traProChi.t_start,stoPla.floCon.traRes2.t_start,$PRE.stoPla.floCon.traRes2.t_start,stoPla.floCon.traChaRem.t_start,$PRE.stoPla.floCon.traChaRem.t_start,stoPla.floCon.traRes1.t_start,$PRE.stoPla.floCon.traRes1.t_start,stoPla.floCon.traChaLoc.t_start,$PRE.stoPla.floCon.traChaLoc.t_start,$PRE.chiEnaSta.combiTimeTable.nextTimeEvent,$whenCondition2,$PRE.com.combiTimeTable.nextTimeEvent,$whenCondition1,stoPla.floCon.traRou.condition,stoPla.floCon.stateGraphRoot.resume,stoPla.floCon.stateGraphRoot.suspend,hys_yVal_actual.y,$PRE.hys_yVal_actual.y,chiEnaSta.combiTimeTable.nextTimeEventScaled,$PRE.chiEnaSta.combiTimeTable.nextTimeEventScaled,chiEnaSta.combiTimeTable.nextTimeEvent,com.combiTimeTable.nextTimeEventScaled,$PRE.com.combiTimeTable.nextTimeEventScaled,com.combiTimeTable.nextTimeEvent,com.y,mLoa3_flow.nextEventScaled,mLoa3_flow.nextEvent,$PRE.mLoa3_flow.nextEvent,mLoa3_flow.last,mLoa3_flow.b,mLoa3_flow.a,mLoa2_flow.nextEventScaled,mLoa2_flow.nextEvent,$PRE.mLoa2_flow.nextEvent,mLoa2_flow.last,mLoa2_flow.b,mLoa2_flow.a,mLoa1_flow.nextEventScaled,mLoa1_flow.nextEvent,$PRE.mLoa1_flow.nextEvent,mLoa1_flow.last,mLoa1_flow.b,mLoa1_flow.a,stoPla.chiEnaSta,stoPla.tanSta.hysEmp.y,$PRE.stoPla.tanSta.hysEmp.y,stoPla.tanSta.hysCha.y,$PRE.stoPla.tanSta.hysCha.y,stoPla.floCon.traChiToTan.enableFire,stoPla.floCon.traChiToTan.localCondition,stoPla.floCon.traRou.enableFire,stoPla.floCon.steRou2.oldActive,$PRE.stoPla.floCon.steRou2.oldActive,stoPla.floCon.steRou2.newActive,$PRE.stoPla.floCon.steRou2.newActive,stoPla.floCon.steRou2.inPort[1].set,stoPla.floCon.steRou2.localActive,$PRE.stoPla.floCon.steRou2.localActive,stoPla.floCon.steRou2.active,stoPla.floCon.steRou1.oldActive,$PRE.stoPla.floCon.steRou1.oldActive,stoPla.floCon.steRou1.newActive,$PRE.stoPla.floCon.steRou1.newActive,stoPla.floCon.steRou1.outPort[1].reset,stoPla.floCon.steRou1.localActive,$PRE.stoPla.floCon.steRou1.localActive,stoPla.floCon.steRou1.active,stoPla.floCon.altTanCha1.split[2].available,stoPla.floCon.traTanToChi.enableFire,stoPla.floCon.traTanToChi.localCondition,stoPla.floCon.parallel.outPort.available,stoPla.floCon.traPro.outPort.occupied,stoPla.floCon.traPro.enableFire,stoPla.floCon.traPro.localCondition,stoPla.floCon.stePumSecOn.oldActive,$PRE.stoPla.floCon.stePumSecOn.oldActive,stoPla.floCon.stePumSecOn.newActive,$PRE.stoPla.floCon.stePumSecOn.newActive,stoPla.floCon.stePumSecOn.localActive,$PRE.stoPla.floCon.stePumSecOn.localActive,stoPla.floCon.stePumSecOn.active,stoPla.floCon.alt.split[3].available,stoPla.floCon.alt.split[2].available,stoPla.floCon.expPriPumFlo.y,stoPla.floCon.traRes3.enableFire,stoPla.floCon.steProTan.oldActive,$PRE.stoPla.floCon.steProTan.oldActive,stoPla.floCon.steProTan.newActive,$PRE.stoPla.floCon.steProTan.newActive,stoPla.floCon.steProTan.outPort[2].available,stoPla.floCon.steProTan.inPort[2].occupied,stoPla.floCon.steProTan.localActive,$PRE.stoPla.floCon.steProTan.localActive,stoPla.floCon.steProTan.active,stoPla.floCon.traProTan.enableFire,stoPla.floCon.traRes4.enableFire,stoPla.floCon.steProChi.oldActive,$PRE.stoPla.floCon.steProChi.oldActive,stoPla.floCon.steProChi.newActive,$PRE.stoPla.floCon.steProChi.newActive,stoPla.floCon.steProChi.outPort[2].available,stoPla.floCon.steProChi.inPort[2].occupied,stoPla.floCon.steProChi.localActive,$PRE.stoPla.floCon.steProChi.localActive,stoPla.floCon.steProChi.active,stoPla.floCon.traProChi.enableFire,stoPla.floCon.traRes2.enableFire,stoPla.floCon.traRes2.localCondition,stoPla.floCon.steChaRem.oldActive,$PRE.stoPla.floCon.steChaRem.oldActive,stoPla.floCon.steChaRem.newActive,$PRE.stoPla.floCon.steChaRem.newActive,stoPla.floCon.steChaRem.localActive,$PRE.stoPla.floCon.steChaRem.localActive,stoPla.floCon.steChaRem.active,stoPla.floCon.traChaRem.enableFire,stoPla.floCon.traRes1.enableFire,stoPla.floCon.traRes1.localCondition,stoPla.floCon.steChaLoc.oldActive,$PRE.stoPla.floCon.steChaLoc.oldActive,stoPla.floCon.steChaLoc.newActive,$PRE.stoPla.floCon.steChaLoc.newActive,stoPla.floCon.steChaLoc.localActive,$PRE.stoPla.floCon.steChaLoc.localActive,stoPla.floCon.steChaLoc.active,stoPla.floCon.traChaLoc.enableFire,stoPla.floCon.allOff.oldActive,$PRE.stoPla.floCon.allOff.oldActive,stoPla.floCon.allOff.newActive,$PRE.stoPla.floCon.allOff.newActive,stoPla.floCon.allOff.outPort[1].reset,stoPla.floCon.allOff.inPort[1].set,stoPla.floCon.allOff.localActive,$PRE.stoPla.floCon.allOff.localActive,stoPla.floCon.allOff.active,stoPla.floCon.stateGraphRoot.activeSteps,$whenCondition5,$whenCondition4,$whenCondition3,ideUse3.conPI.I.trigger_internal,ideUse2.conPI.I.trigger_internal,ideUse1.conPI.I.trigger_internal,stoPla.tanSta.gre.y,conPI_pumChi1.I.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1036):
 * Single equations (assignments): 1024
 * Array equations: 3
 * Algorithm blocks: 3
 * Record equations: 3
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,10,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(4,40)}
Notification: Performance of prepare postOptimizeDAE: time 0.003471/4.062, allocations: 0.9463 MB / 2.489 GB, free: 11.16 MB / 1.889 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.005252/4.067, allocations: 1.451 MB / 2.49 GB, free: 9.695 MB / 1.889 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01976/4.087, allocations: 8.162 MB / 2.498 GB, free: 1.434 MB / 1.889 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.475e-05/4.087, allocations: 56 kB / 2.498 GB, free: 1.379 MB / 1.889 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.968e-05/4.087, allocations: 15.92 kB / 2.499 GB, free: 1.363 MB / 1.889 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01327/4.1, allocations: 6.414 MB / 2.505 GB, free: 10.77 MB / 1.904 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.7938/4.894, allocations: 21.33 MB / 2.526 GB, free: 0.8303 GB / 1.904 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.48e-05/4.894, allocations: 4.281 kB / 2.526 GB, free: 0.8303 GB / 1.904 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004442/4.899, allocations: 0.9042 MB / 2.526 GB, free: 0.8303 GB / 1.904 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001895/4.9, allocations: 0.7265 MB / 2.527 GB, free: 0.8301 GB / 1.904 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0007782/4.901, allocations: 172.5 kB / 2.527 GB, free: 0.83 GB / 1.904 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.004281/4.905, allocations: 4.732 MB / 2.532 GB, free: 0.8265 GB / 1.904 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.799e-06/4.905, allocations: 2.75 kB / 2.532 GB, free: 0.8265 GB / 1.904 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02514/4.931, allocations: 12.02 MB / 2.544 GB, free: 0.8219 GB / 1.904 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.008095/4.939, allocations: 2.468 MB / 2.546 GB, free: 0.8211 GB / 1.904 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004639/4.943, allocations: 146.6 kB / 2.546 GB, free: 0.821 GB / 1.904 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.009086/4.952, allocations: 307.9 kB / 2.547 GB, free: 0.8209 GB / 1.904 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001603/4.954, allocations: 455.2 kB / 2.547 GB, free: 0.8208 GB / 1.904 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000953/4.955, allocations: 267.7 kB / 2.547 GB, free: 0.8208 GB / 1.904 GB
Notification: Performance of sorting global known variables: time 0.02879/4.984, allocations: 9.67 MB / 2.557 GB, free: 0.8173 GB / 1.904 GB
Notification: Performance of sort global known variables: time 3.21e-07/4.984, allocations: 0 / 2.557 GB, free: 0.8173 GB / 1.904 GB
Notification: Performance of remove unused functions: time 0.03554/5.019, allocations: 7.984 MB / 2.564 GB, free: 0.8166 GB / 1.904 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 15
 * Number of states: 36 (pumSup1.vol.dynBal.U,pumSup1.filter.s[1],pumSup1.filter.s[2],conPI_pumChi1.I.y,stoPla.pumPri.vol.dynBal.U,stoPla.pumPri.filter.s[1],stoPla.pumPri.filter.s[2],stoPla.tanBra.tan.vol[1].dynBal.U,stoPla.tanBra.tan.vol[2].dynBal.U,stoPla.tanBra.tan.vol[3].dynBal.U,stoPla.tanBra.tan.vol[4].dynBal.U,stoPla.tanBra.tan.vol[5].dynBal.U,stoPla.tanBra.junSup.vol.dynBal.U,stoPla.tanBra.junRet.vol.dynBal.U,stoPla.revCon.pum.vol.dynBal.U,stoPla.revCon.pum.filter.s[1],stoPla.revCon.pum.filter.s[2],stoPla.revCon.val.filter.s[1],stoPla.revCon.val.filter.s[2],stoPla.revCon.jun1.vol.dynBal.U,stoPla.revCon.jun2.vol.dynBal.U,ideUse1.val.filter.s[1],ideUse1.val.filter.s[2],ideUse1.conPI.I.y,ideUse2.val.filter.s[1],ideUse2.val.filter.s[2],ideUse2.conPI.I.y,ideUse3.val.filter.s[1],ideUse3.val.filter.s[2],ideUse3.conPI.I.y,parJunPla1.jun1.vol.dynBal.U,parJunPla1.jun2.vol.dynBal.U,parJunUse2.jun1.vol.dynBal.U,parJunUse2.jun2.vol.dynBal.U,parJunPla2.jun1.vol.dynBal.U,parJunPla2.jun2.vol.dynBal.U)
 * Number of discrete variables: 105 (stoPla.floCon.steRou2.localActive,stoPla.floCon.steRou1.localActive,stoPla.floCon.stePumSecOn.localActive,stoPla.floCon.steProTan.localActive,stoPla.floCon.steProChi.localActive,stoPla.floCon.steChaRem.active,stoPla.floCon.steChaLoc.localActive,stoPla.floCon.allOff.localActive,$whenCondition2,$whenCondition1,$whenCondition3,$whenCondition4,$whenCondition5,stoPla.floCon.stateGraphRoot.activeSteps,stoPla.floCon.allOff.active,stoPla.floCon.allOff.inPort[1].set,stoPla.floCon.allOff.outPort[1].reset,stoPla.floCon.allOff.newActive,stoPla.floCon.allOff.oldActive,stoPla.floCon.traChaLoc.enableFire,stoPla.floCon.steChaLoc.active,stoPla.floCon.steChaLoc.newActive,stoPla.floCon.steChaLoc.oldActive,stoPla.floCon.traRes1.localCondition,stoPla.floCon.traRes1.enableFire,stoPla.floCon.traChaRem.enableFire,stoPla.floCon.steChaRem.localActive,stoPla.floCon.steChaRem.newActive,stoPla.floCon.steChaRem.oldActive,stoPla.floCon.traRes2.localCondition,stoPla.floCon.traRes2.enableFire,stoPla.floCon.traProChi.enableFire,stoPla.floCon.steProChi.active,stoPla.floCon.steProChi.inPort[2].occupied,stoPla.floCon.steProChi.outPort[2].available,stoPla.floCon.steProChi.newActive,stoPla.floCon.steProChi.oldActive,stoPla.floCon.traRes4.enableFire,stoPla.floCon.traProTan.enableFire,stoPla.floCon.steProTan.active,stoPla.floCon.steProTan.inPort[2].occupied,stoPla.floCon.steProTan.outPort[2].available,stoPla.floCon.steProTan.newActive,stoPla.floCon.steProTan.oldActive,stoPla.floCon.traRes3.enableFire,stoPla.floCon.expPriPumFlo.y,stoPla.floCon.alt.split[2].available,stoPla.floCon.alt.split[3].available,stoPla.floCon.stePumSecOn.active,stoPla.floCon.stePumSecOn.newActive,stoPla.floCon.stePumSecOn.oldActive,stoPla.floCon.traPro.localCondition,stoPla.floCon.traPro.enableFire,stoPla.floCon.traPro.outPort.occupied,stoPla.floCon.parallel.outPort.available,stoPla.floCon.traTanToChi.localCondition,stoPla.floCon.traTanToChi.enableFire,stoPla.floCon.altTanCha1.split[2].available,stoPla.floCon.steRou1.active,stoPla.floCon.steRou1.outPort[1].reset,stoPla.floCon.steRou1.newActive,stoPla.floCon.steRou1.oldActive,stoPla.floCon.steRou2.active,stoPla.floCon.steRou2.inPort[1].set,stoPla.floCon.steRou2.newActive,stoPla.floCon.steRou2.oldActive,stoPla.floCon.traRou.enableFire,stoPla.floCon.traChiToTan.localCondition,stoPla.floCon.traChiToTan.enableFire,stoPla.tanSta.hysCha.y,stoPla.tanSta.hysEmp.y,stoPla.chiEnaSta,mLoa1_flow.a,mLoa1_flow.b,mLoa1_flow.last,mLoa1_flow.nextEvent,mLoa1_flow.nextEventScaled,mLoa2_flow.a,mLoa2_flow.b,mLoa2_flow.last,mLoa2_flow.nextEvent,mLoa2_flow.nextEventScaled,mLoa3_flow.a,mLoa3_flow.b,mLoa3_flow.last,mLoa3_flow.nextEvent,mLoa3_flow.nextEventScaled,com.y,com.combiTimeTable.nextTimeEvent,com.combiTimeTable.nextTimeEventScaled,chiEnaSta.combiTimeTable.nextTimeEvent,chiEnaSta.combiTimeTable.nextTimeEventScaled,hys_yVal_actual.y,stoPla.floCon.traChaLoc.t_start,stoPla.floCon.traRes1.t_start,stoPla.floCon.traChaRem.t_start,stoPla.floCon.traRes2.t_start,stoPla.floCon.traProChi.t_start,stoPla.floCon.traRes4.t_start,stoPla.floCon.traProTan.t_start,stoPla.floCon.traRes3.t_start,stoPla.floCon.traPro.t_start,stoPla.floCon.traTanToChi.t_start,stoPla.floCon.traRou.t_start,stoPla.floCon.traChiToTan.t_start)
 * Number of discrete states: 45 (stoPla.floCon.traChiToTan.t_start,stoPla.floCon.traRou.t_start,stoPla.floCon.traTanToChi.t_start,stoPla.floCon.traPro.t_start,stoPla.floCon.traRes3.t_start,stoPla.floCon.traProTan.t_start,stoPla.floCon.traRes4.t_start,stoPla.floCon.traProChi.t_start,stoPla.floCon.traRes2.t_start,stoPla.floCon.traChaRem.t_start,stoPla.floCon.traRes1.t_start,stoPla.floCon.traChaLoc.t_start,chiEnaSta.combiTimeTable.nextTimeEvent,com.combiTimeTable.nextTimeEvent,stoPla.floCon.allOff.newActive,stoPla.floCon.steChaLoc.newActive,stoPla.floCon.steChaRem.newActive,stoPla.floCon.steProChi.newActive,stoPla.floCon.steProTan.newActive,stoPla.floCon.stePumSecOn.newActive,stoPla.floCon.steRou1.newActive,stoPla.floCon.steRou2.newActive,stoPla.tanSta.hysCha.y,stoPla.tanSta.hysEmp.y,mLoa1_flow.nextEvent,mLoa1_flow.last,mLoa1_flow.nextEventScaled,mLoa1_flow.b,mLoa1_flow.a,$whenCondition5,mLoa2_flow.nextEvent,mLoa2_flow.last,mLoa2_flow.nextEventScaled,mLoa2_flow.b,mLoa2_flow.a,$whenCondition4,mLoa3_flow.nextEvent,mLoa3_flow.last,mLoa3_flow.nextEventScaled,mLoa3_flow.b,mLoa3_flow.a,$whenCondition3,hys_yVal_actual.y,chiEnaSta.combiTimeTable.nextTimeEventScaled,com.combiTimeTable.nextTimeEventScaled)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (509):
 * Single equations (assignments): 467
 * Array equations: 0
 * Algorithm blocks: 3
 * Record equations: 12
 * When equations: 24
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,10,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(4,42)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0305/5.05, allocations: 15.41 MB / 2.58 GB, free: 0.8105 GB / 1.904 GB
Notification: Performance of simCode: created initialization part: time 0.02767/5.077, allocations: 15.11 MB / 2.594 GB, free: 0.8046 GB / 1.904 GB
Notification: Performance of simCode: created event and clocks part: time 1.884e-05/5.077, allocations: 9.938 kB / 2.594 GB, free: 0.8046 GB / 1.904 GB
Notification: Performance of simCode: created simulation system equations: time 0.007265/5.085, allocations: 3.588 MB / 2.598 GB, free: 0.8032 GB / 1.904 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.03529/5.12, allocations: 7.333 MB / 2.605 GB, free: 0.8013 GB / 1.904 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2013/5.321, allocations: 111 MB / 2.713 GB, free: 0.7031 GB / 1.904 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01008/5.331, allocations: 7.745 MB / 2.721 GB, free: 0.6955 GB / 1.904 GB
Notification: Performance of simCode: alias equations: time 0.01735/5.349, allocations: 3.637 MB / 2.725 GB, free: 0.692 GB / 1.904 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01293/5.362, allocations: 7.536 MB / 2.732 GB, free: 0.6846 GB / 1.904 GB
Notification: Performance of SimCode: time 1.042e-06/5.362, allocations: 0 / 2.732 GB, free: 0.6846 GB / 1.904 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.5055/5.867, allocations: 401.3 MB / 3.124 GB, free: 301.7 MB / 1.904 GB
Notification: Performance of buildModelFMU: Generate platform static: time 61.39/67.26, allocations: 8 kB / 3.124 GB, free: 301.7 MB / 1.904 GB
"
[Timeout remaining time 232]
(rm -f Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe ; mkfifo Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe ; head -c 1048576 < Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe >> ../files/Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource_res.mat --tempDir=temp_Buildings_11_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource_fmu --startTime=0 --stopTime=9000 --stepSize=3.6 --timeout=400 --tolerance=1e-06 Buildings_11_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource.fmu > Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe 2>&1) [Timeout 420.0]
[Calling os._exit(0), Time elapsed: 74.33089702500729]
Failed to read output from testmodel.py, exit status != 0:
67.74557062296662 67.758744087 64.460855182
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7febc4aa33a0>