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.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0015/0.0015, allocations: 114 kB / 17.6 MB, free: 4.699 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001674/0.001674, allocations: 186.7 kB / 20.78 MB, free: 1.535 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.42/1.42, allocations: 225.6 MB / 249.4 MB, free: 3.305 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.0-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.0-maint.11.x/package.mo): time 2.729/2.729, allocations: 476.7 MB / 0.7629 GB, free: 15.33 MB / 0.6075 GB
"
[Timeout remaining time 177]
Using package Buildings with version 11.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.0-maint.11.x/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: "" <> buildModelFMU(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource,fileNamePrefix="Buildings_11_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource,fileNamePrefix="Buildings_11_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource",fmuType="me",version="2.0",platforms={"static"}) [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.002533/0.002533, allocations: 67.89 kB / 1.107 GB, free: 5.141 MB / 0.8419 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.037/1.04, allocations: 168.7 MB / 1.272 GB, free: 7.715 MB / 0.9981 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource): time 0.6146/1.654, allocations: 0.5598 GB / 1.831 GB, free: 6.242 MB / 1.514 GB
Notification: Performance of NFInst.instExpressions: time 1.03/2.685, allocations: 117.1 MB / 1.946 GB, free: 97.57 MB / 1.529 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02478/2.709, allocations: 0.6013 MB / 1.946 GB, free: 97.57 MB / 1.529 GB
Notification: Performance of NFTyping.typeComponents: time 0.0278/2.737, allocations: 9.292 MB / 1.955 GB, free: 97.41 MB / 1.529 GB
Notification: Performance of NFTyping.typeBindings: time 0.06435/2.802, allocations: 21.04 MB / 1.976 GB, free: 96.63 MB / 1.529 GB
Notification: Performance of NFTyping.typeClassSections: time 0.03606/2.838, allocations: 15.1 MB / 1.991 GB, free: 96.13 MB / 1.529 GB
Notification: Performance of NFFlatten.flatten: time 0.08347/2.921, allocations: 66.43 MB / 2.055 GB, free: 83.48 MB / 1.529 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02999/2.951, allocations: 14.63 MB / 2.07 GB, free: 73.51 MB / 1.529 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.04394/2.995, allocations: 19.28 MB / 2.089 GB, free: 62.43 MB / 1.529 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02434/3.019, allocations: 14.25 MB / 2.103 GB, free: 49.17 MB / 1.529 GB
Notification: Performance of NFPackage.collectConstants: time 0.007508/3.027, allocations: 2.281 MB / 2.105 GB, free: 46.89 MB / 1.529 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02653/3.053, allocations: 8.629 MB / 2.113 GB, free: 38.66 MB / 1.529 GB
Notification: Performance of NFScalarize.scalarize: time 0.008752/3.062, allocations: 5.772 MB / 2.119 GB, free: 32.96 MB / 1.529 GB
Notification: Performance of NFVerifyModel.verify: time 0.02247/3.085, allocations: 11.29 MB / 2.13 GB, free: 21.71 MB / 1.529 GB
Notification: Performance of NFConvertDAE.convert: time 0.05617/3.141, allocations: 36.06 MB / 2.165 GB, free: 2.594 MB / 1.545 GB
Notification: Performance of FrontEnd - DAE generated: time 5.701e-06/3.141, allocations: 0 / 2.165 GB, free: 2.594 MB / 1.545 GB
Notification: Performance of FrontEnd: time 1.523e-06/3.141, allocations: 4 kB / 2.165 GB, free: 2.59 MB / 1.545 GB
Notification: Performance of Transformations before backend: time 0.0009531/3.142, allocations: 0 / 2.165 GB, free: 2.59 MB / 1.545 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.0551/3.197, allocations: 21.3 MB / 2.186 GB, free: 13.39 MB / 1.576 GB
Notification: Performance of prepare preOptimizeDAE: time 4.431e-05/3.197, allocations: 8.031 kB / 2.186 GB, free: 13.39 MB / 1.576 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.003966/3.201, allocations: 1.456 MB / 2.187 GB, free: 11.93 MB / 1.576 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01156/3.212, allocations: 2.615 MB / 2.19 GB, free: 9.297 MB / 1.576 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06015/3.273, allocations: 28.99 MB / 2.218 GB, free: 12.88 MB / 1.607 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0009514/3.274, allocations: 0.8761 MB / 2.219 GB, free: 12.01 MB / 1.607 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00378/3.277, allocations: 1.166 MB / 2.22 GB, free: 10.85 MB / 1.607 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03708/3.314, allocations: 14.22 MB / 2.234 GB, free: 12.13 MB / 1.623 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003384/3.315, allocations: 35.69 kB / 2.234 GB, free: 12.1 MB / 1.623 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002559/3.317, allocations: 471.2 kB / 2.235 GB, free: 11.64 MB / 1.623 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004389/3.318, allocations: 432.9 kB / 2.235 GB, free: 11.21 MB / 1.623 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02828/3.346, allocations: 10.79 MB / 2.245 GB, free: 420 kB / 1.623 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.1295/3.475, allocations: 73.37 MB / 2.317 GB, free: 2.891 MB / 1.701 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01846/3.494, allocations: 5.472 MB / 2.322 GB, free: 13.34 MB / 1.717 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007463/3.501, allocations: 3.513 MB / 2.326 GB, free: 9.781 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.03939/3.541, allocations: 17.27 MB / 2.343 GB, free: 7.988 MB / 1.732 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.009328/3.55, allocations: 4.038 MB / 2.347 GB, free: 3.816 MB / 1.732 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.006215/3.556, allocations: 0.6243 MB / 2.347 GB, free: 3.188 MB / 1.732 GB
Notification: Performance of pre-optimization done (n=547): time 2.594e-05/3.556, allocations: 0 / 2.347 GB, free: 3.188 MB / 1.732 GB
Notification: Performance of matching and sorting (n=547): time 0.05115/3.607, allocations: 12.24 MB / 2.359 GB, free: 6.797 MB / 1.748 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003929/3.608, allocations: 0.6092 MB / 2.36 GB, free: 6.055 MB / 1.748 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.04121/3.649, allocations: 14.4 MB / 2.374 GB, free: 7.727 MB / 1.764 GB
Notification: Performance of collectPreVariables (initialization): time 0.00204/3.651, allocations: 213.7 kB / 2.374 GB, free: 7.512 MB / 1.764 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01475/3.666, allocations: 8.029 MB / 2.382 GB, free: 15.52 MB / 1.779 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003627/3.67, allocations: 1.896 MB / 2.384 GB, free: 13.65 MB / 1.779 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.007053/3.677, allocations: 2.048 MB / 2.386 GB, free: 11.59 MB / 1.779 GB
Notification: Performance of setup shared object (initialization): time 0.0001832/3.677, allocations: 301.1 kB / 2.386 GB, free: 11.29 MB / 1.779 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.009075/3.686, allocations: 4.387 MB / 2.39 GB, free: 6.898 MB / 1.779 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01278/3.699, allocations: 7.408 MB / 2.398 GB, free: 14.19 MB / 1.795 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0202/3.719, allocations: 11.39 MB / 2.409 GB, free: 1.703 MB / 1.795 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.967e-05/3.719, allocations: 20 kB / 2.409 GB, free: 1.684 MB / 1.795 GB
Notification: Performance of matching and sorting (n=1209) (initialization): time 0.03149/3.75, allocations: 13.66 MB / 2.422 GB, free: 3.98 MB / 1.811 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001765/3.751, allocations: 141.2 kB / 2.422 GB, free: 3.824 MB / 1.811 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000134/3.751, allocations: 76 kB / 2.422 GB, free: 3.75 MB / 1.811 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003511/3.754, allocations: 1.176 MB / 2.423 GB, free: 2.57 MB / 1.811 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007796/3.762, allocations: 1.716 MB / 2.425 GB, free: 0.8516 MB / 1.811 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006585/3.769, allocations: 4.97 MB / 2.43 GB, free: 11.7 MB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01293/3.781, allocations: 484.8 kB / 2.43 GB, free: 11.23 MB / 1.826 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002183/3.784, allocations: 471.9 kB / 2.431 GB, free: 10.77 MB / 1.826 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0188/3.802, allocations: 7.785 MB / 2.439 GB, free: 2.98 MB / 1.826 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01232/3.815, allocations: 7.041 MB / 2.445 GB, free: 10.64 MB / 1.842 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01969/3.834, allocations: 10.68 MB / 2.456 GB, free: 14.86 MB / 1.857 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.651e-05/3.835, allocations: 20 kB / 2.456 GB, free: 14.84 MB / 1.857 GB
Notification: Performance of matching and sorting (n=1209) (initialization_lambda0): time 0.03412/3.869, allocations: 13.02 MB / 2.469 GB, free: 1.77 MB / 1.857 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002175/3.869, allocations: 133.1 kB / 2.469 GB, free: 1.621 MB / 1.857 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.00013/3.869, allocations: 72 kB / 2.469 GB, free: 1.551 MB / 1.857 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002691/3.872, allocations: 0.9423 MB / 2.47 GB, free: 0.6016 MB / 1.857 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007903/3.88, allocations: 1.72 MB / 2.471 GB, free: 14.88 MB / 1.873 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0177/3.897, allocations: 10.98 MB / 2.482 GB, free: 3.539 MB / 1.873 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01176/3.909, allocations: 0.5073 MB / 2.483 GB, free: 3.035 MB / 1.873 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001994/3.911, allocations: 447.9 kB / 2.483 GB, free: 2.598 MB / 1.873 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.003826/3.915, allocations: 0.9338 MB / 2.484 GB, free: 1.629 MB / 1.873 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.006437/3.921, allocations: 1.455 MB / 2.485 GB, free: 168 kB / 1.873 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02606/3.947, allocations: 8.303 MB / 2.493 GB, free: 7.773 MB / 1.889 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.554e-05/3.947, allocations: 63.98 kB / 2.493 GB, free: 7.711 MB / 1.889 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.85e-05/3.948, allocations: 8 kB / 2.493 GB, free: 7.703 MB / 1.889 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01605/3.964, allocations: 6.518 MB / 2.5 GB, free: 1.012 MB / 1.889 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05725/4.021, allocations: 21.48 MB / 2.521 GB, free: 11.41 MB / 1.92 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.18e-05/4.021, allocations: 4 kB / 2.521 GB, free: 11.41 MB / 1.92 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.005001/4.026, allocations: 0.9166 MB / 2.522 GB, free: 10.49 MB / 1.92 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002134/4.028, allocations: 0.738 MB / 2.522 GB, free: 9.75 MB / 1.92 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001109/4.029, allocations: 179.9 kB / 2.523 GB, free: 9.574 MB / 1.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.005812/4.035, allocations: 4.737 MB / 2.527 GB, free: 4.652 MB / 1.92 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.174e-06/4.035, allocations: 7.938 kB / 2.527 GB, free: 4.645 MB / 1.92 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02855/4.063, allocations: 12.12 MB / 2.539 GB, free: 8.281 MB / 1.936 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.008848/4.072, allocations: 2.467 MB / 2.541 GB, free: 5.785 MB / 1.936 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00421/4.077, allocations: 159.9 kB / 2.542 GB, free: 5.629 MB / 1.936 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.009756/4.086, allocations: 327.6 kB / 2.542 GB, free: 5.309 MB / 1.936 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001505/4.088, allocations: 435.8 kB / 2.542 GB, free: 4.879 MB / 1.936 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001387/4.089, allocations: 279.8 kB / 2.543 GB, free: 4.605 MB / 1.936 GB
Notification: Performance of sorting global known variables: time 0.7758/4.865, allocations: 9.651 MB / 2.552 GB, free: 0.8558 GB / 1.936 GB
Notification: Performance of sort global known variables: time 2.7e-07/4.865, allocations: 0.6875 kB / 2.552 GB, free: 0.8558 GB / 1.936 GB
Notification: Performance of remove unused functions: time 0.03482/4.9, allocations: 7.985 MB / 2.56 GB, free: 0.8558 GB / 1.936 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: 119 (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,$cse37,$cse38,$cse39,$cse40,$cse41,$cse42,$cse43,$cse44,$cse45,$cse46,$cse47,$cse48,$cse49,$cse50,$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 (523):
 * Single equations (assignments): 481
 * 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.03789/4.938, allocations: 15.6 MB / 2.575 GB, free: 0.8506 GB / 1.936 GB
Notification: Performance of simCode: created initialization part: time 0.0346/4.972, allocations: 15.17 MB / 2.59 GB, free: 0.8448 GB / 1.936 GB
Notification: Performance of simCode: created event and clocks part: time 2.182e-05/4.972, allocations: 6.875 kB / 2.59 GB, free: 0.8448 GB / 1.936 GB
Notification: Performance of simCode: created simulation system equations: time 0.009226/4.981, allocations: 3.621 MB / 2.593 GB, free: 0.8433 GB / 1.936 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.04473/5.026, allocations: 7.34 MB / 2.601 GB, free: 0.8415 GB / 1.936 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2748/5.301, allocations: 111.2 MB / 2.709 GB, free: 0.7749 GB / 1.936 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01789/5.319, allocations: 7.766 MB / 2.717 GB, free: 0.7692 GB / 1.936 GB
Notification: Performance of simCode: alias equations: time 0.02126/5.34, allocations: 3.658 MB / 2.72 GB, free: 0.7657 GB / 1.936 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01483/5.355, allocations: 7.548 MB / 2.728 GB, free: 0.7583 GB / 1.936 GB
Notification: Performance of SimCode: time 2.194e-06/5.355, allocations: 0 / 2.728 GB, free: 0.7583 GB / 1.936 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.5288/5.884, allocations: 403.2 MB / 3.122 GB, free: 375.1 MB / 1.936 GB
Notification: Performance of buildModelFMU: Generate platform static: time 56.21/62.09, allocations: 0 / 3.122 GB, free: 375.1 MB / 1.936 GB
"
[Timeout remaining time 238]
(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 & python3 -m fmpy simulate  --output-file Buildings_11_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource_res.csv --start-time 0 --stop-time 9000 --timeout 400 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 3.6 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: 72.33023785601836]
Failed to read output from testmodel.py, exit status != 0:
62.48994369301363 62.498218987 59.354947987
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe8fdb20a90>