Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_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.001818/0.001818, allocations: 117.4 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.002282/0.002282, allocations: 214.3 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.471/1.471, allocations: 230.6 MB / 253.5 MB, free: 15.11 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.025/3.025, allocations: 0.49 GB / 0.7923 GB, free: 1.355 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: "" <> buildModelFMU(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource,fileNamePrefix="Buildings_12_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource,fileNamePrefix="Buildings_12_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.002/0.002, allocations: 63.91 kB / 1.15 GB, free: 15.35 MB / 0.9356 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.006/1.008, allocations: 176.1 MB / 1.321 GB, free: 24.73 MB / 1.061 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource): time 1.761/2.768, allocations: 0.5569 GB / 1.878 GB, free: 97 MB / 1.545 GB
Notification: Performance of NFInst.instExpressions: time 0.1878/2.956, allocations: 116.8 MB / 1.993 GB, free: 95.7 MB / 1.545 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.03418/2.99, allocations: 0.847 MB / 1.993 GB, free: 95.7 MB / 1.545 GB
Notification: Performance of NFTyping.typeComponents: time 0.03166/3.022, allocations: 9.272 MB / 2.002 GB, free: 95.55 MB / 1.545 GB
Notification: Performance of NFTyping.typeBindings: time 0.06637/3.088, allocations: 21.19 MB / 2.023 GB, free: 94.78 MB / 1.545 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0368/3.125, allocations: 14.75 MB / 2.037 GB, free: 94.41 MB / 1.545 GB
Notification: Performance of NFFlatten.flatten: time 0.08413/3.209, allocations: 66.06 MB / 2.102 GB, free: 66.29 MB / 1.545 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0261/3.235, allocations: 15.65 MB / 2.117 GB, free: 55.31 MB / 1.545 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.04347/3.279, allocations: 20.06 MB / 2.137 GB, free: 36.35 MB / 1.545 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0267/3.306, allocations: 13.94 MB / 2.15 GB, free: 23.35 MB / 1.545 GB
Notification: Performance of NFPackage.collectConstants: time 0.009172/3.315, allocations: 2.273 MB / 2.153 GB, free: 21.07 MB / 1.545 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03063/3.345, allocations: 8.818 MB / 2.161 GB, free: 12.64 MB / 1.545 GB
Notification: Performance of NFScalarize.scalarize: time 0.009916/3.355, allocations: 5.718 MB / 2.167 GB, free: 7 MB / 1.545 GB
Notification: Performance of NFVerifyModel.verify: time 0.02651/3.382, allocations: 11.29 MB / 2.178 GB, free: 11.75 MB / 1.561 GB
Notification: Performance of NFConvertDAE.convert: time 0.06924/3.451, allocations: 36.32 MB / 2.213 GB, free: 8.426 MB / 1.592 GB
Notification: Performance of FrontEnd - DAE generated: time 6.512e-06/3.451, allocations: 0 / 2.213 GB, free: 8.426 MB / 1.592 GB
Notification: Performance of FrontEnd: time 1.833e-06/3.451, allocations: 0 / 2.213 GB, free: 8.426 MB / 1.592 GB
Notification: Performance of Transformations before backend: time 0.00104/3.452, allocations: 4 kB / 2.213 GB, free: 8.422 MB / 1.592 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2841
 * Number of variables: 2841
Notification: Performance of Generate backend data structure: time 0.06331/3.515, allocations: 21.28 MB / 2.234 GB, free: 3.227 MB / 1.607 GB
Notification: Performance of prepare preOptimizeDAE: time 6.126e-05/3.516, allocations: 12.02 kB / 2.234 GB, free: 3.215 MB / 1.607 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.000398/3.516, allocations: 293.9 kB / 2.234 GB, free: 2.93 MB / 1.607 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.006405/3.522, allocations: 1.435 MB / 2.236 GB, free: 1.488 MB / 1.607 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01552/3.538, allocations: 2.711 MB / 2.239 GB, free: 14.77 MB / 1.623 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06522/3.603, allocations: 29.2 MB / 2.267 GB, free: 2.125 MB / 1.639 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00123/3.604, allocations: 0.8647 MB / 2.268 GB, free: 1.262 MB / 1.639 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004606/3.609, allocations: 1.16 MB / 2.269 GB, free: 108 kB / 1.639 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04189/3.651, allocations: 14.19 MB / 2.283 GB, free: 1.438 MB / 1.654 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005333/3.651, allocations: 27.75 kB / 2.283 GB, free: 1.41 MB / 1.654 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003513/3.655, allocations: 474.2 kB / 2.283 GB, free: 0.9492 MB / 1.654 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003753/3.655, allocations: 311.9 kB / 2.284 GB, free: 0.6445 MB / 1.654 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03133/3.687, allocations: 10.68 MB / 2.294 GB, free: 5.949 MB / 1.67 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.1224/3.809, allocations: 74.18 MB / 2.367 GB, free: 7.34 MB / 1.748 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01224/3.821, allocations: 5.465 MB / 2.372 GB, free: 1.816 MB / 1.748 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006882/3.828, allocations: 3.491 MB / 2.375 GB, free: 14.28 MB / 1.764 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.0347/3.863, allocations: 16.96 MB / 2.392 GB, free: 12.8 MB / 1.779 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.008572/3.871, allocations: 4.018 MB / 2.396 GB, free: 8.664 MB / 1.779 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.005226/3.877, allocations: 0.6163 MB / 2.396 GB, free: 8.047 MB / 1.779 GB
Notification: Performance of pre-optimization done (n=533): time 2.003e-05/3.877, allocations: 0 / 2.396 GB, free: 8.047 MB / 1.779 GB
Notification: Performance of matching and sorting (n=533): time 0.03403/3.911, allocations: 11.81 MB / 2.408 GB, free: 12.11 MB / 1.795 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004158/3.911, allocations: 0.6169 MB / 2.408 GB, free: 11.36 MB / 1.795 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02959/3.941, allocations: 14.4 MB / 2.423 GB, free: 13.03 MB / 1.811 GB
Notification: Performance of collectPreVariables (initialization): time 0.001532/3.942, allocations: 209.7 kB / 2.423 GB, free: 12.82 MB / 1.811 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01068/3.953, allocations: 8.017 MB / 2.431 GB, free: 4.848 MB / 1.811 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003178/3.956, allocations: 1.835 MB / 2.432 GB, free: 3.039 MB / 1.811 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004378/3.96, allocations: 2.031 MB / 2.434 GB, free: 0.9961 MB / 1.811 GB
Notification: Performance of setup shared object (initialization): time 0.0001597/3.961, allocations: 309.1 kB / 2.435 GB, free: 0.6914 MB / 1.811 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008644/3.969, allocations: 4.366 MB / 2.439 GB, free: 12.32 MB / 1.826 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.012/3.981, allocations: 7.384 MB / 2.446 GB, free: 3.633 MB / 1.826 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02001/4.001, allocations: 11.28 MB / 2.457 GB, free: 7.262 MB / 1.842 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.161e-05/4.001, allocations: 20 kB / 2.457 GB, free: 7.242 MB / 1.842 GB
Notification: Performance of matching and sorting (n=1195) (initialization): time 0.03329/4.035, allocations: 13.57 MB / 2.47 GB, free: 9.617 MB / 1.857 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001605/4.035, allocations: 133.1 kB / 2.471 GB, free: 9.469 MB / 1.857 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001112/4.035, allocations: 79.94 kB / 2.471 GB, free: 9.391 MB / 1.857 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003484/4.038, allocations: 1.179 MB / 2.472 GB, free: 8.207 MB / 1.857 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007616/4.046, allocations: 1.677 MB / 2.473 GB, free: 6.527 MB / 1.857 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006516/4.053, allocations: 4.966 MB / 2.478 GB, free: 1.379 MB / 1.857 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01105/4.064, allocations: 0.4963 MB / 2.479 GB, free: 0.8867 MB / 1.857 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002059/4.066, allocations: 467.9 kB / 2.479 GB, free: 440 kB / 1.857 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01893/4.085, allocations: 7.74 MB / 2.487 GB, free: 8.688 MB / 1.873 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01176/4.096, allocations: 7.03 MB / 2.494 GB, free: 360 kB / 1.873 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01994/4.116, allocations: 10.56 MB / 2.504 GB, free: 4.699 MB / 1.889 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001036/4.116, allocations: 24.11 kB / 2.504 GB, free: 4.676 MB / 1.889 GB
Notification: Performance of matching and sorting (n=1195) (initialization_lambda0): time 0.03207/4.148, allocations: 12.94 MB / 2.517 GB, free: 7.672 MB / 1.904 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001534/4.149, allocations: 129.1 kB / 2.517 GB, free: 7.527 MB / 1.904 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001051/4.149, allocations: 76 kB / 2.517 GB, free: 7.453 MB / 1.904 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002654/4.151, allocations: 0.935 MB / 2.518 GB, free: 6.512 MB / 1.904 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007539/4.159, allocations: 1.681 MB / 2.519 GB, free: 4.828 MB / 1.904 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01732/4.176, allocations: 10.99 MB / 2.53 GB, free: 9.473 MB / 1.92 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01193/4.188, allocations: 0.5241 MB / 2.531 GB, free: 8.949 MB / 1.92 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00214/4.19, allocations: 436 kB / 2.531 GB, free: 8.523 MB / 1.92 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 (1024):
 * Single equations (assignments): 1012
 * 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,38)}
Notification: Performance of prepare postOptimizeDAE: time 0.002905/4.193, allocations: 0.9292 MB / 2.532 GB, free: 7.559 MB / 1.92 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.005409/4.199, allocations: 1.451 MB / 2.533 GB, free: 6.098 MB / 1.92 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0203/4.219, allocations: 8.146 MB / 2.541 GB, free: 13.83 MB / 1.936 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.197e-05/4.219, allocations: 63.98 kB / 2.541 GB, free: 13.77 MB / 1.936 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.47e-05/4.219, allocations: 4 kB / 2.541 GB, free: 13.77 MB / 1.936 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01393/4.233, allocations: 6.421 MB / 2.548 GB, free: 7.156 MB / 1.936 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05374/4.287, allocations: 21.09 MB / 2.568 GB, free: 1.934 MB / 1.951 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.839e-05/4.287, allocations: 4 kB / 2.568 GB, free: 1.93 MB / 1.951 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004781/4.291, allocations: 0.9167 MB / 2.569 GB, free: 1.012 MB / 1.951 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002066/4.294, allocations: 0.7336 MB / 2.57 GB, free: 280 kB / 1.951 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001026/4.295, allocations: 167.8 kB / 2.57 GB, free: 112 kB / 1.951 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006034/4.301, allocations: 4.732 MB / 2.575 GB, free: 11.2 MB / 1.967 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.534e-06/4.301, allocations: 4 kB / 2.575 GB, free: 11.2 MB / 1.967 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.7888/5.089, allocations: 11.94 MB / 2.586 GB, free: 0.8454 GB / 1.967 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.009535/5.099, allocations: 2.422 MB / 2.589 GB, free: 0.8453 GB / 1.967 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.005923/5.105, allocations: 134.6 kB / 2.589 GB, free: 0.8453 GB / 1.967 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01069/5.116, allocations: 333.1 kB / 2.589 GB, free: 0.8453 GB / 1.967 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00162/5.117, allocations: 448.2 kB / 2.589 GB, free: 0.8453 GB / 1.967 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0009525/5.118, allocations: 271.6 kB / 2.59 GB, free: 0.8453 GB / 1.967 GB
Notification: Performance of sorting global known variables: time 0.0256/5.144, allocations: 9.647 MB / 2.599 GB, free: 0.8426 GB / 1.967 GB
Notification: Performance of sort global known variables: time 2.41e-07/5.144, allocations: 0 / 2.599 GB, free: 0.8426 GB / 1.967 GB
Notification: Performance of remove unused functions: time 0.03299/5.177, allocations: 7.941 MB / 2.607 GB, free: 0.8421 GB / 1.967 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 15
 * Number of states: 29 (pumSup1.vol.dynBal.U,pumSup1.motSpe.y,conPI_pumChi1.I.y,stoPla.pumPri.vol.dynBal.U,stoPla.pumPri.motSpe.y,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.motSpe.y,stoPla.revCon.val.actPos.y,stoPla.revCon.jun1.vol.dynBal.U,stoPla.revCon.jun2.vol.dynBal.U,ideUse1.val.actPos.y,ideUse1.conPI.I.y,ideUse2.val.actPos.y,ideUse2.conPI.I.y,ideUse3.val.actPos.y,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 (498):
 * Single equations (assignments): 456
 * 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,39)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.03548/5.212, allocations: 15.31 MB / 2.622 GB, free: 0.8369 GB / 1.967 GB
Notification: Performance of simCode: created initialization part: time 0.03254/5.245, allocations: 15.07 MB / 2.637 GB, free: 0.831 GB / 1.967 GB
Notification: Performance of simCode: created event and clocks part: time 1.828e-05/5.245, allocations: 6.969 kB / 2.637 GB, free: 0.831 GB / 1.967 GB
Notification: Performance of simCode: created simulation system equations: time 0.007969/5.253, allocations: 3.592 MB / 2.64 GB, free: 0.8296 GB / 1.967 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.03855/5.291, allocations: 7.368 MB / 2.647 GB, free: 0.8278 GB / 1.967 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2353/5.527, allocations: 110.7 MB / 2.755 GB, free: 0.7603 GB / 1.967 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01086/5.537, allocations: 7.698 MB / 2.763 GB, free: 0.7548 GB / 1.967 GB
Notification: Performance of simCode: alias equations: time 0.01535/5.553, allocations: 3.55 MB / 2.766 GB, free: 0.7518 GB / 1.967 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0134/5.566, allocations: 7.517 MB / 2.774 GB, free: 0.7444 GB / 1.967 GB
Notification: Performance of SimCode: time 2.975e-06/5.566, allocations: 0 / 2.774 GB, free: 0.7444 GB / 1.967 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.5092/6.075, allocations: 399.8 MB / 3.164 GB, free: 364.5 MB / 1.967 GB
Notification: Performance of buildModelFMU: Generate platform static: time 58.17/64.24, allocations: 4.062 kB / 3.164 GB, free: 364.5 MB / 1.967 GB
"
[Timeout remaining time 235]
(rm -f Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe ; mkfifo Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe ; head -c 1048576 < Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe >> ../files/Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource_res.mat --tempDir=temp_Buildings_12_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource_fmu --startTime=0 --stopTime=9000 --stepSize=3.6 --timeout=400 --tolerance=1e-06 Buildings_12_Buildings_DHC_Plants_Cooling_Examples_StoragePlantDualSource.fmu > Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe 2>&1) [Timeout 420.0]
[Calling os._exit(0), Time elapsed: 71.799040192971]
Failed to read output from testmodel.py, exit status != 0:
64.71493456803728 64.727895524 61.273520151
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd9008233a0>