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.001365/0.001365, allocations: 104.4 kB / 19.7 MB, free: 2.125 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.001491/0.001491, allocations: 215.9 kB / 22.99 MB, free: 4.945 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.439/1.439, allocations: 230.6 MB / 256.8 MB, free: 7.805 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.192/3.192, allocations: 0.4892 GB / 0.7951 GB, free: 35.69 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: translateModel(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pumSup1.m_flow|stoPla.revCon.m_flow|stoPla.pumPri.m_flow|stoPla.tanBra.mTan_flow|stoPla.revCon.pum.m_flow|stoPla.revCon.val.m_flow|ideUse1.yVal_actual|ideUse2.yVal_actual|ideUse3.yVal_actual|ideUse1.dp|ideUse2.dp|ideUse3.dp|stoPla.tanBra.heaPorTop.T|stoPla.tanBra.heaPorBot.T|stoPla.tanSta.y.1.|stoPla.tanSta.y.2.",fileNamePrefix="Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource")
translateModel(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pumSup1.m_flow|stoPla.revCon.m_flow|stoPla.pumPri.m_flow|stoPla.tanBra.mTan_flow|stoPla.revCon.pum.m_flow|stoPla.revCon.val.m_flow|ideUse1.yVal_actual|ideUse2.yVal_actual|ideUse3.yVal_actual|ideUse1.dp|ideUse2.dp|ideUse3.dp|stoPla.tanBra.heaPorTop.T|stoPla.tanBra.heaPorBot.T|stoPla.tanSta.y.1.|stoPla.tanSta.y.2.",fileNamePrefix="Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource") [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.003108/0.003108, allocations: 70.58 kB / 1.154 GB, free: 25.52 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3536/0.3567, allocations: 176.1 MB / 1.326 GB, free: 1.871 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource): time 1.488/1.845, allocations: 0.5073 GB / 1.834 GB, free: 384 kB / 1.311 GB
Notification: Performance of NFInst.instExpressions: time 0.1781/2.023, allocations: 109.2 MB / 1.94 GB, free: 2.938 MB / 1.42 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02255/2.046, allocations: 0.8484 MB / 1.941 GB, free: 2.082 MB / 1.42 GB
Notification: Performance of NFTyping.typeComponents: time 0.03076/2.077, allocations: 8.944 MB / 1.95 GB, free: 9.094 MB / 1.436 GB
Notification: Performance of NFTyping.typeBindings: time 0.08094/2.158, allocations: 21.55 MB / 1.971 GB, free: 3.469 MB / 1.451 GB
Notification: Performance of NFTyping.typeClassSections: time 0.04666/2.204, allocations: 14.9 MB / 1.985 GB, free: 4.672 MB / 1.467 GB
Notification: Performance of NFFlatten.flatten: time 0.09824/2.302, allocations: 67.47 MB / 2.051 GB, free: 1.055 MB / 1.529 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.03878/2.341, allocations: 14.49 MB / 2.065 GB, free: 2.406 MB / 1.545 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.05236/2.394, allocations: 20.49 MB / 2.085 GB, free: 13.9 MB / 1.576 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02923/2.423, allocations: 13.37 MB / 2.098 GB, free: 484 kB / 1.576 GB
Notification: Performance of NFPackage.collectConstants: time 0.00867/2.431, allocations: 2.215 MB / 2.101 GB, free: 14.25 MB / 1.592 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02526/2.457, allocations: 7.291 MB / 2.108 GB, free: 6.957 MB / 1.592 GB
Notification: Performance of NFScalarize.scalarize: time 0.01017/2.467, allocations: 5.625 MB / 2.113 GB, free: 1.312 MB / 1.592 GB
Notification: Performance of NFVerifyModel.verify: time 0.02371/2.491, allocations: 8.673 MB / 2.122 GB, free: 8.594 MB / 1.607 GB
Notification: Performance of NFConvertDAE.convert: time 0.06486/2.555, allocations: 35.68 MB / 2.157 GB, free: 4.797 MB / 1.639 GB
Notification: Performance of FrontEnd - DAE generated: time 5.39e-06/2.555, allocations: 0 / 2.157 GB, free: 4.797 MB / 1.639 GB
Notification: Performance of FrontEnd: time 2.034e-06/2.555, allocations: 0 / 2.157 GB, free: 4.797 MB / 1.639 GB
Notification: Performance of Transformations before backend: time 0.001147/2.557, allocations: 0 / 2.157 GB, free: 4.797 MB / 1.639 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.05584/2.612, allocations: 20.97 MB / 2.177 GB, free: 15.57 MB / 1.67 GB
Notification: Performance of prepare preOptimizeDAE: time 4.377e-05/2.613, allocations: 8.031 kB / 2.177 GB, free: 15.56 MB / 1.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01409/2.627, allocations: 2.705 MB / 2.18 GB, free: 12.84 MB / 1.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.05203/2.679, allocations: 20.64 MB / 2.2 GB, free: 8.043 MB / 1.686 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001113/2.68, allocations: 0.8614 MB / 2.201 GB, free: 7.18 MB / 1.686 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004515/2.684, allocations: 1.148 MB / 2.202 GB, free: 6.031 MB / 1.686 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0433/2.728, allocations: 15.83 MB / 2.217 GB, free: 5.301 MB / 1.701 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000547/2.728, allocations: 47.62 kB / 2.217 GB, free: 5.254 MB / 1.701 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003295/2.731, allocations: 476 kB / 2.218 GB, free: 4.789 MB / 1.701 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003914/2.732, allocations: 311.9 kB / 2.218 GB, free: 4.484 MB / 1.701 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03005/2.762, allocations: 10.76 MB / 2.229 GB, free: 9.684 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 removeSimpleEquations (simulation): time 0.8145/3.576, allocations: 51 MB / 2.278 GB, free: 0.6554 GB / 1.748 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01265/3.589, allocations: 5.479 MB / 2.284 GB, free: 0.6549 GB / 1.748 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008846/3.598, allocations: 3.592 MB / 2.287 GB, free: 0.6547 GB / 1.748 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.03353/3.631, allocations: 12.36 MB / 2.299 GB, free: 0.6489 GB / 1.748 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.008956/3.64, allocations: 5.537 MB / 2.305 GB, free: 0.6454 GB / 1.748 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.005747/3.646, allocations: 0.5432 MB / 2.305 GB, free: 0.6453 GB / 1.748 GB
Notification: Performance of pre-optimization done (n=533): time 3.227e-05/3.646, allocations: 1.406 kB / 2.305 GB, free: 0.6453 GB / 1.748 GB
Notification: Performance of matching and sorting (n=533): time 0.03454/3.681, allocations: 11.78 MB / 2.317 GB, free: 0.642 GB / 1.748 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001727/3.681, allocations: 0.561 MB / 2.317 GB, free: 0.6414 GB / 1.748 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03066/3.711, allocations: 13.78 MB / 2.331 GB, free: 0.6367 GB / 1.748 GB
Notification: Performance of collectPreVariables (initialization): time 0.001729/3.713, allocations: 202.8 kB / 2.331 GB, free: 0.6365 GB / 1.748 GB
Notification: Performance of collectInitialEqns (initialization): time 0.009574/3.723, allocations: 7.994 MB / 2.339 GB, free: 0.6313 GB / 1.748 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003248/3.726, allocations: 1.842 MB / 2.341 GB, free: 0.6299 GB / 1.748 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.006293/3.732, allocations: 2.02 MB / 2.343 GB, free: 0.629 GB / 1.748 GB
Notification: Performance of setup shared object (initialization): time 5.804e-05/3.732, allocations: 304.6 kB / 2.343 GB, free: 0.6287 GB / 1.748 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007412/3.74, allocations: 4.511 MB / 2.347 GB, free: 0.6266 GB / 1.748 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01186/3.752, allocations: 9.392 MB / 2.356 GB, free: 0.6183 GB / 1.748 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01897/3.771, allocations: 11.39 MB / 2.368 GB, free: 0.6089 GB / 1.748 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001183/3.771, allocations: 24 kB / 2.368 GB, free: 0.6088 GB / 1.748 GB
Notification: Performance of matching and sorting (n=1193) (initialization): time 0.03076/3.802, allocations: 13.71 MB / 2.381 GB, free: 0.5954 GB / 1.748 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001912/3.802, allocations: 133 kB / 2.381 GB, free: 0.5953 GB / 1.748 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001444/3.802, allocations: 81.06 kB / 2.381 GB, free: 0.5952 GB / 1.748 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003132/3.805, allocations: 1.186 MB / 2.382 GB, free: 0.5941 GB / 1.748 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007324/3.812, allocations: 1.677 MB / 2.384 GB, free: 0.5924 GB / 1.748 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005326/3.818, allocations: 4.805 MB / 2.389 GB, free: 0.5876 GB / 1.748 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01497/3.833, allocations: 495.5 kB / 2.389 GB, free: 0.5872 GB / 1.748 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002513/3.835, allocations: 468.5 kB / 2.39 GB, free: 0.5867 GB / 1.748 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01877/3.854, allocations: 7.859 MB / 2.397 GB, free: 0.579 GB / 1.748 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0105/3.864, allocations: 9.015 MB / 2.406 GB, free: 0.5685 GB / 1.748 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01659/3.881, allocations: 10.65 MB / 2.416 GB, free: 0.5571 GB / 1.748 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.823e-05/3.881, allocations: 27.12 kB / 2.416 GB, free: 0.5571 GB / 1.748 GB
Notification: Performance of matching and sorting (n=1193) (initialization_lambda0): time 0.03283/3.914, allocations: 13.06 MB / 2.429 GB, free: 0.5443 GB / 1.748 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001542/3.914, allocations: 133.1 kB / 2.429 GB, free: 0.5442 GB / 1.748 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001174/3.914, allocations: 76.08 kB / 2.429 GB, free: 0.5441 GB / 1.748 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002268/3.916, allocations: 0.9424 MB / 2.43 GB, free: 0.5432 GB / 1.748 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006791/3.923, allocations: 1.681 MB / 2.432 GB, free: 0.5415 GB / 1.748 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01251/3.936, allocations: 10.62 MB / 2.442 GB, free: 0.531 GB / 1.748 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01129/3.947, allocations: 0.5244 MB / 2.443 GB, free: 0.5305 GB / 1.748 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001813/3.949, allocations: 439.9 kB / 2.443 GB, free: 0.5301 GB / 1.748 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 (1022):
 * Single equations (assignments): 1010
 * 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.002623/3.951, allocations: 0.9266 MB / 2.444 GB, free: 0.5291 GB / 1.748 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004592/3.956, allocations: 1.382 MB / 2.446 GB, free: 0.5278 GB / 1.748 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01755/3.974, allocations: 8.24 MB / 2.454 GB, free: 0.5197 GB / 1.748 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.593e-05/3.974, allocations: 52 kB / 2.454 GB, free: 0.5196 GB / 1.748 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.451e-05/3.974, allocations: 11.84 kB / 2.454 GB, free: 0.5196 GB / 1.748 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01136/3.985, allocations: 6.535 MB / 2.46 GB, free: 0.5131 GB / 1.748 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04059/4.026, allocations: 17.89 MB / 2.478 GB, free: 0.4956 GB / 1.748 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.621e-05/4.026, allocations: 10.06 kB / 2.478 GB, free: 0.4956 GB / 1.748 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004566/4.03, allocations: 0.9219 MB / 2.478 GB, free: 0.4947 GB / 1.748 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001711/4.032, allocations: 0.7315 MB / 2.479 GB, free: 0.494 GB / 1.748 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0009855/4.033, allocations: 167.9 kB / 2.479 GB, free: 0.4938 GB / 1.748 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00382/4.037, allocations: 4.58 MB / 2.484 GB, free: 0.4892 GB / 1.748 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.188e-06/4.037, allocations: 7.938 kB / 2.484 GB, free: 0.4892 GB / 1.748 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02626/4.063, allocations: 12.13 MB / 2.496 GB, free: 488.6 MB / 1.748 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.009458/4.072, allocations: 2.279 MB / 2.498 GB, free: 486.3 MB / 1.748 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004925/4.077, allocations: 140 kB / 2.498 GB, free: 486.1 MB / 1.748 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01109/4.088, allocations: 336.8 kB / 2.498 GB, free: 485.8 MB / 1.748 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001659/4.09, allocations: 467.3 kB / 2.499 GB, free: 485.3 MB / 1.748 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001428/4.092, allocations: 271.8 kB / 2.499 GB, free: 485.1 MB / 1.748 GB
Notification: Performance of sorting global known variables: time 0.02373/4.115, allocations: 9.083 MB / 2.508 GB, free: 476.1 MB / 1.748 GB
Notification: Performance of sort global known variables: time 1.81e-07/4.115, allocations: 0 / 2.508 GB, free: 476.1 MB / 1.748 GB
Notification: Performance of remove unused functions: time 0.03414/4.149, allocations: 8.019 MB / 2.516 GB, free: 468 MB / 1.748 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 (500):
 * Single equations (assignments): 458
 * 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.009765/4.159, allocations: 2.961 MB / 2.519 GB, free: 465.8 MB / 1.748 GB
Notification: Performance of simCode: created initialization part: time 0.04027/4.199, allocations: 15.4 MB / 2.534 GB, free: 450.3 MB / 1.748 GB
Notification: Performance of simCode: created event and clocks part: time 1.814e-05/4.199, allocations: 9.812 kB / 2.534 GB, free: 450.3 MB / 1.748 GB
Notification: Performance of simCode: created simulation system equations: time 0.01037/4.21, allocations: 3.661 MB / 2.537 GB, free: 446.5 MB / 1.748 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.03846/4.248, allocations: 6.789 MB / 2.544 GB, free: 440.1 MB / 1.748 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.05096/4.299, allocations: 28.65 MB / 2.572 GB, free: 411.3 MB / 1.748 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00988/4.309, allocations: 7.356 MB / 2.579 GB, free: 403.9 MB / 1.748 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003724/4.313, allocations: 1.042 MB / 2.58 GB, free: 402.8 MB / 1.748 GB
Notification: Performance of SimCode: time 1.082e-06/4.313, allocations: 0 / 2.58 GB, free: 402.8 MB / 1.748 GB
Notification: Performance of Templates: time 1.866/6.179, allocations: 0.5176 GB / 3.098 GB, free: 0.5655 GB / 1.748 GB
"
[Timeout remaining time 294]
make -j1 -f Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.makefile [Timeout 300]
(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 & ./Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.DHC.Plants.Cooling.Examples.StoragePlantDualSource.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 78.31485803890973]
Failed to read output from testmodel.py, exit status != 0:
6.191212446894497 6.198510489 3.640371467
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f118929efe0>