Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.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.002322/0.002322, allocations: 95.66 kB / 21.73 MB, free: 2.684 MB / 18.57 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.002562/0.002562, allocations: 163.4 kB / 25.03 MB, free: 7.902 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.307/1.307, allocations: 158.6 MB / 186.8 MB, free: 2.898 MB / 170.7 MB
"
[Timeout remaining time 179]
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5082,variableFilter="time|B1.level|B1.medium.h|B2.level|B2.medium.h|B3.level|B3.medium.h|B4.level|B4.medium.h|B5.level|B5.medium.h|B6.level|B6.medium.h|B7.level|B7.medium.h|P1.medium.h|P1.medium.p|P2.medium.h|P2.medium.p|pipeB1B2.mediums.1..h|pipeB1B2.mediums.1..p|pipeB1B2.mediums.2..h|pipeB1B2.mediums.2..p|pipePump1B1.mediums.1..h|pipePump1B1.mediums.1..p|pipePump1B1.mediums.2..h|pipePump1B1.mediums.2..p|pipePump2B2.mediums.1..h|pipePump2B2.mediums.1..p|pipePump2B2.mediums.2..h|pipePump2B2.mediums.2..p|volume4.medium.h|volume4.medium.p|volume5.medium.h|volume5.medium.p|B5.InnerTankBottom.1..m_flow_negative|B5.InnerTankSide.1..m_flow_negative|B5.InnerTankTop.1..m_flow_negative|P1_on.y|P2_on.y|controller.Transition1.enableFire|controller.Transition10.enableFire|controller.Transition11.enableFire|controller.Transition12.enableFire|controller.Transition13.enableFire|controller.Transition2.enableFire|controller.Transition3.enableFire|controller.Transition4.enableFire|controller.Transition5.enableFire|controller.Transition6.enableFire|controller.Transition7.enableFire|controller.Transition8.enableFire|controller.Transition9.enableFire|controller.TransitionWithSignal1.enableFire",fileNamePrefix="Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater")
translateModel(Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5082,variableFilter="time|B1.level|B1.medium.h|B2.level|B2.medium.h|B3.level|B3.medium.h|B4.level|B4.medium.h|B5.level|B5.medium.h|B6.level|B6.medium.h|B7.level|B7.medium.h|P1.medium.h|P1.medium.p|P2.medium.h|P2.medium.p|pipeB1B2.mediums.1..h|pipeB1B2.mediums.1..p|pipeB1B2.mediums.2..h|pipeB1B2.mediums.2..p|pipePump1B1.mediums.1..h|pipePump1B1.mediums.1..p|pipePump1B1.mediums.2..h|pipePump1B1.mediums.2..p|pipePump2B2.mediums.1..h|pipePump2B2.mediums.1..p|pipePump2B2.mediums.2..h|pipePump2B2.mediums.2..p|volume4.medium.h|volume4.medium.p|volume5.medium.h|volume5.medium.p|B5.InnerTankBottom.1..m_flow_negative|B5.InnerTankSide.1..m_flow_negative|B5.InnerTankTop.1..m_flow_negative|P1_on.y|P2_on.y|controller.Transition1.enableFire|controller.Transition10.enableFire|controller.Transition11.enableFire|controller.Transition12.enableFire|controller.Transition13.enableFire|controller.Transition2.enableFire|controller.Transition3.enableFire|controller.Transition4.enableFire|controller.Transition5.enableFire|controller.Transition6.enableFire|controller.Transition7.enableFire|controller.Transition8.enableFire|controller.Transition9.enableFire|controller.TransitionWithSignal1.enableFire",fileNamePrefix="Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.462e-06/1.462e-06, allocations: 3.938 kB / 250.4 MB, free: 3.906 MB / 234.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.943e-05/3.089e-05, allocations: 6.25 kB / 250.4 MB, free: 3.898 MB / 234.7 MB
Notification: Performance of NFInst.instantiate(Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater): time 1.038/1.038, allocations: 438.8 MB / 0.6731 GB, free: 1.418 MB / 458.7 MB
Notification: Performance of NFInst.instExpressions: time 0.1238/1.162, allocations: 94.32 MB / 0.7652 GB, free: 12.48 MB / 0.4948 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.009743/1.172, allocations: 309.4 kB / 0.7655 GB, free: 12.18 MB / 0.4948 GB
Notification: Performance of NFTyping.typeComponents: time 0.0143/1.186, allocations: 4.33 MB / 0.7697 GB, free: 7.82 MB / 0.4948 GB
Notification: Performance of NFTyping.typeBindings: time 0.06187/1.248, allocations: 21.01 MB / 0.7902 GB, free: 2.695 MB / 0.5105 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05082/1.299, allocations: 18.8 MB / 0.8086 GB, free: 15.84 MB / 0.5417 GB
Notification: Performance of NFFlatten.flatten: time 0.3352/1.634, allocations: 24.48 MB / 0.8325 GB, free: 11.22 MB / 0.5417 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01249/1.647, allocations: 6.454 MB / 0.8388 GB, free: 10.89 MB / 0.5417 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.03209/1.679, allocations: 12.11 MB / 0.8506 GB, free: 10.89 MB / 0.5417 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.07618/1.755, allocations: 37.28 MB / 0.887 GB, free: 2.336 MB / 0.5417 GB
Notification: Performance of NFPackage.collectConstants: time 0.004172/1.759, allocations: 1.028 MB / 0.888 GB, free: 2.336 MB / 0.5417 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03291/1.792, allocations: 15.33 MB / 0.903 GB, free: 2.316 MB / 0.5417 GB
Notification: Performance of NFScalarize.scalarize: time 0.00551/1.797, allocations: 3.59 MB / 0.9065 GB, free: 1.871 MB / 0.5417 GB
Notification: Performance of NFVerifyModel.verify: time 0.01006/1.808, allocations: 4.151 MB / 0.9106 GB, free: 1.445 MB / 0.5417 GB
Notification: Performance of NFConvertDAE.convert: time 0.05552/1.863, allocations: 27.43 MB / 0.9374 GB, free: 4.656 MB / 0.5573 GB
Notification: Performance of FrontEnd - DAE generated: time 7.875e-06/1.863, allocations: 0 / 0.9374 GB, free: 4.656 MB / 0.5573 GB
Notification: Performance of FrontEnd: time 1.843e-06/1.863, allocations: 0 / 0.9374 GB, free: 4.656 MB / 0.5573 GB
Notification: Performance of Transformations before backend: time 0.0005029/1.864, allocations: 0 / 0.9374 GB, free: 4.656 MB / 0.5573 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2241
 * Number of variables: 2241
Notification: Performance of Generate backend data structure: time 0.04833/1.912, allocations: 15.44 MB / 0.9524 GB, free: 7.352 MB / 0.573 GB
Notification: Performance of prepare preOptimizeDAE: time 4.444e-05/1.912, allocations: 10.28 kB / 0.9524 GB, free: 7.344 MB / 0.573 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02932/1.941, allocations: 4.849 MB / 0.9572 GB, free: 3.199 MB / 0.573 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02579/1.967, allocations: 10.7 MB / 0.9676 GB, free: 9.777 MB / 0.5886 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002019/1.969, allocations: 1.625 MB / 0.9692 GB, free: 7.973 MB / 0.5886 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004568/1.974, allocations: 0.8846 MB / 0.9701 GB, free: 7.109 MB / 0.5886 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04447/2.018, allocations: 14.79 MB / 0.9845 GB, free: 7.355 MB / 0.6042 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002611/2.018, allocations: 36.17 kB / 0.9845 GB, free: 7.336 MB / 0.6042 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001699/2.02, allocations: 374.9 kB / 0.9849 GB, free: 6.992 MB / 0.6042 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007921/2.021, allocations: 0.5524 MB / 0.9854 GB, free: 6.441 MB / 0.6042 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03698/2.058, allocations: 10.92 MB / 0.9961 GB, free: 11.66 MB / 0.6198 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.1033/2.161, allocations: 52.18 MB / 1.047 GB, free: 7.348 MB / 0.6667 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02399/2.185, allocations: 8.684 MB / 1.056 GB, free: 14.65 MB / 0.6823 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.009063/2.194, allocations: 3.704 MB / 1.059 GB, free: 10.94 MB / 0.6823 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.3515/2.546, allocations: 34.1 MB / 1.092 GB, free: 415.3 MB / 0.6824 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0179/2.563, allocations: 7.092 MB / 1.099 GB, free: 414.2 MB / 0.6824 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.00369/2.567, allocations: 0.6192 MB / 1.1 GB, free: 414.2 MB / 0.6824 GB
Notification: Performance of pre-optimization done (n=796): time 4.504e-05/2.567, allocations: 3.609 kB / 1.1 GB, free: 414.2 MB / 0.6824 GB
Notification: Performance of matching and sorting (n=870): time 0.09137/2.659, allocations: 31.97 MB / 1.131 GB, free: 393.3 MB / 0.6824 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001284/2.659, allocations: 311.4 kB / 1.132 GB, free: 393 MB / 0.6824 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006789/2.666, allocations: 5.757 MB / 1.137 GB, free: 387.3 MB / 0.6824 GB
Notification: Performance of collectPreVariables (initialization): time 0.001872/2.667, allocations: 245.7 kB / 1.137 GB, free: 387.1 MB / 0.6824 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002934/2.67, allocations: 4.025 MB / 1.141 GB, free: 383.5 MB / 0.6824 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00375/2.674, allocations: 3.209 MB / 1.144 GB, free: 380.7 MB / 0.6824 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004347/2.678, allocations: 2.593 MB / 1.147 GB, free: 378.1 MB / 0.6824 GB
Notification: Performance of setup shared object (initialization): time 6.455e-05/2.678, allocations: 476.8 kB / 1.147 GB, free: 377.6 MB / 0.6824 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007264/2.686, allocations: 3.452 MB / 1.151 GB, free: 374.1 MB / 0.6824 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.009217/2.695, allocations: 6.778 MB / 1.157 GB, free: 366.2 MB / 0.6824 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01613/2.711, allocations: 10.3 MB / 1.167 GB, free: 354.9 MB / 0.6824 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.208e-05/2.711, allocations: 16 kB / 1.167 GB, free: 354.9 MB / 0.6824 GB
Notification: Performance of matching and sorting (n=1295) (initialization): time 0.02597/2.737, allocations: 12.37 MB / 1.18 GB, free: 342.3 MB / 0.6824 GB
Notification: Performance of prepare postOptimizeDAE: time 6.658e-05/2.737, allocations: 28 kB / 1.18 GB, free: 342.3 MB / 0.6824 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.449e-05/2.737, allocations: 63.94 kB / 1.18 GB, free: 342.2 MB / 0.6824 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01118/2.748, allocations: 3.264 MB / 1.183 GB, free: 338.9 MB / 0.6824 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008533/2.757, allocations: 1.962 MB / 1.185 GB, free: 336.9 MB / 0.6824 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01759/2.775, allocations: 33.99 MB / 1.218 GB, free: 301.5 MB / 0.6824 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008053/2.783, allocations: 423.8 kB / 1.218 GB, free: 301.1 MB / 0.6824 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001836/2.784, allocations: 479.8 kB / 1.219 GB, free: 300.7 MB / 0.6824 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.009929/2.794, allocations: 4.52 MB / 1.223 GB, free: 296.1 MB / 0.6824 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.009026/2.803, allocations: 6.769 MB / 1.23 GB, free: 288.2 MB / 0.6824 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0162/2.82, allocations: 10.18 MB / 1.24 GB, free: 276.8 MB / 0.6824 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.173e-05/2.82, allocations: 16 kB / 1.24 GB, free: 276.8 MB / 0.6824 GB
Notification: Performance of matching and sorting (n=1295) (initialization_lambda0): time 0.02793/2.848, allocations: 12.29 MB / 1.252 GB, free: 264.4 MB / 0.6824 GB
Notification: Performance of prepare postOptimizeDAE: time 5.285e-05/2.848, allocations: 32 kB / 1.252 GB, free: 264.4 MB / 0.6824 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.372e-05/2.848, allocations: 67.92 kB / 1.252 GB, free: 264.3 MB / 0.6824 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01025/2.858, allocations: 2.746 MB / 1.255 GB, free: 261.4 MB / 0.6824 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008925/2.867, allocations: 2.012 MB / 1.257 GB, free: 259.4 MB / 0.6824 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02729/2.894, allocations: 44.44 MB / 1.3 GB, free: 213 MB / 0.6824 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007615/2.902, allocations: 418.4 kB / 1.3 GB, free: 212.6 MB / 0.6824 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001901/2.904, allocations: 483.8 kB / 1.301 GB, free: 212.1 MB / 0.6824 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 263
 * Number of states: 0 ()
 * Number of discrete variables: 288 ($PRE.controller.Transition1.enableFire,$PRE.controller.Transition2.enableFire,$PRE.controller.Transition3.enableFire,$PRE.controller.Transition4.enableFire,$PRE.controller.Transition5.enableFire,$PRE.controller.Transition6.enableFire,$PRE.controller.Transition7.enableFire,$PRE.controller.Transition8.enableFire,$PRE.controller.Transition9.enableFire,$PRE.controller.Transition10.enableFire,$PRE.controller.Transition11.enableFire,$PRE.controller.Transition12.enableFire,$PRE.controller.Transition13.enableFire,$PRE.controller.TransitionWithSignal1.enableFire,controller.stateGraphRoot.resume,controller.stateGraphRoot.suspend,controller.Transition7.condition,controller.Transition3.condition,controller.Transition1.condition,controller.actuators.V21,controller.actuators.V19,controller.actuators.V10,controller.actuators.V4,controller.actuators.V2,pipeB7Pump.flowModel.states[2].phase,pipeB7Pump.flowModel.states[1].phase,pipeB6Pump.flowModel.states[2].phase,pipeB6Pump.flowModel.states[1].phase,pipeB1B1.flowModel.states[2].phase,pipeB1B1.flowModel.states[1].phase,pipeB2B3.flowModel.states[2].phase,pipeB2B3.flowModel.states[1].phase,pipeB1B3.flowModel.states[2].phase,pipeB1B3.flowModel.states[1].phase,volume4.medium.phase,B6.aboveLevel[1],$PRE.B6.aboveLevel[1],B6.ports_m_flow_out[1],$PRE.B6.ports_m_flow_out[1],B6.heatTransfer.states[1].phase,pipePump2B2.statesFM[2].phase,pipePump2B2.statesFM[1].phase,pipePump1B1.statesFM[2].phase,pipePump1B1.statesFM[1].phase,pipeB1B2.statesFM[2].phase,pipeB1B2.statesFM[1].phase,B7.aboveLevel[1],$PRE.B7.aboveLevel[1],B7.ports_m_flow_out[1],$PRE.B7.ports_m_flow_out[1],B7.heatTransfer.states[1].phase,B4.aboveLevel[1],$PRE.B4.aboveLevel[1],B4.ports_m_flow_out[1],$PRE.B4.ports_m_flow_out[1],B4.heatTransfer.states[1].phase,B3.aboveLevel[2],$PRE.B3.aboveLevel[2],B3.aboveLevel[1],$PRE.B3.aboveLevel[1],B3.ports_m_flow_out[2],$PRE.B3.ports_m_flow_out[2],B3.ports_m_flow_out[1],$PRE.B3.ports_m_flow_out[1],B3.heatTransfer.states[1].phase,B2.aboveLevel[1],$PRE.B2.aboveLevel[1],B2.ports_m_flow_out[1],$PRE.B2.ports_m_flow_out[1],B2.heatTransfer.states[1].phase,P2_on.T,P2_on.rate,P2_on.endValue,P1_on.T,P1_on.rate,P1_on.endValue,B1.aboveLevel[1],$PRE.B1.aboveLevel[1],B1.ports_m_flow_out[1],$PRE.B1.ports_m_flow_out[1],B1.heatTransfer.states[1].phase,P2.heatTransfer.states[1].phase,P1.heatTransfer.states[1].phase,volume5.medium.phase,controller.stateGraphRoot.activeSteps,controller.TransitionWithSignal1.inPort.available,controller.TransitionWithSignal1.enableFire,controller.TransitionWithSignal1.localCondition,controller.T7_idle,controller.T5_idle,controller.Transition13.enableFire,controller.Transition13.localCondition,controller.Transition12.enableFire,controller.Transition12.localCondition,controller.Transition11.enableFire,controller.Transition11.localCondition,controller.Transition10.enableFire,controller.Transition10.localCondition,controller.Transition9.enableFire,controller.Transition9.localCondition,controller.Transition8.enableFire,controller.Step14.oldActive,$PRE.controller.Step14.oldActive,controller.Step14.newActive,$PRE.controller.Step14.newActive,controller.Step14.localActive,$PRE.controller.Step14.localActive,controller.Step14.active,controller.Step13.oldActive,$PRE.controller.Step13.oldActive,controller.Step13.newActive,$PRE.controller.Step13.newActive,controller.Step13.localActive,$PRE.controller.Step13.localActive,controller.Step13.active,controller.Step12.oldActive,$PRE.controller.Step12.oldActive,controller.Step12.newActive,$PRE.controller.Step12.newActive,controller.Step12.localActive,$PRE.controller.Step12.localActive,controller.Step12.active,controller.Step11.oldActive,$PRE.controller.Step11.oldActive,controller.Step11.newActive,$PRE.controller.Step11.newActive,controller.Step11.localActive,$PRE.controller.Step11.localActive,controller.Step11.active,controller.Step10.oldActive,$PRE.controller.Step10.oldActive,controller.Step10.newActive,$PRE.controller.Step10.newActive,controller.Step10.localActive,$PRE.controller.Step10.localActive,controller.Step10.active,controller.Step9.oldActive,$PRE.controller.Step9.oldActive,controller.Step9.newActive,$PRE.controller.Step9.newActive,controller.Step9.localActive,$PRE.controller.Step9.localActive,controller.Step9.active,controller.Step8.oldActive,$PRE.controller.Step8.oldActive,controller.Step8.newActive,$PRE.controller.Step8.newActive,controller.Step8.localActive,$PRE.controller.Step8.localActive,controller.Step8.active,controller.Step7.oldActive,$PRE.controller.Step7.oldActive,controller.Step7.newActive,$PRE.controller.Step7.newActive,controller.Step7.localActive,$PRE.controller.Step7.localActive,controller.Step7.active,controller.Transition7.t_start,$PRE.controller.Transition7.t_start,controller.Transition7.outPort.occupied,controller.Transition7.fire,controller.Transition7.enableFire,controller.Step6.oldActive,$PRE.controller.Step6.oldActive,controller.Step6.newActive,$PRE.controller.Step6.newActive,controller.Step6.localActive,$PRE.controller.Step6.localActive,controller.Step6.active,controller.Transition6.enableFire,controller.Transition6.localCondition,controller.Step5.oldActive,$PRE.controller.Step5.oldActive,controller.Step5.newActive,$PRE.controller.Step5.newActive,controller.Step5.localActive,$PRE.controller.Step5.localActive,controller.Step5.active,controller.Transition5.enableFire,controller.Step4.oldActive,$PRE.controller.Step4.oldActive,controller.Step4.newActive,$PRE.controller.Step4.newActive,controller.Step4.localActive,$PRE.controller.Step4.localActive,controller.Step4.active,controller.Transition4.enableFire,controller.Transition4.localCondition,controller.Step3.oldActive,$PRE.controller.Step3.oldActive,controller.Step3.newActive,$PRE.controller.Step3.newActive,controller.Step3.localActive,$PRE.controller.Step3.localActive,controller.Step3.active,controller.Transition3.t_start,$PRE.controller.Transition3.t_start,controller.Transition3.fire,controller.Transition3.enableFire,controller.Step2.oldActive,$PRE.controller.Step2.oldActive,controller.Step2.newActive,$PRE.controller.Step2.newActive,controller.Step2.localActive,$PRE.controller.Step2.localActive,controller.Step2.active,controller.Transition2.enableFire,controller.Transition2.localCondition,controller.Step1.oldActive,$PRE.controller.Step1.oldActive,controller.Step1.newActive,$PRE.controller.Step1.newActive,controller.Step1.localActive,$PRE.controller.Step1.localActive,controller.Step1.active,controller.Transition1.t_start,$PRE.controller.Transition1.t_start,controller.Transition1.fire,controller.Transition1.enableFire,controller.InitialStep1.oldActive,$PRE.controller.InitialStep1.oldActive,controller.InitialStep1.newActive,$PRE.controller.InitialStep1.newActive,controller.InitialStep1.localActive,$PRE.controller.InitialStep1.localActive,controller.InitialStep1.active,B5.heatTransfer.states[1].phase,B5.InnerTankBottom[1].m_flow_negative,$PRE.B5.InnerTankBottom[1].m_flow_negative,B5.InnerTankSide[1].m_flow_negative,$PRE.B5.InnerTankSide[1].m_flow_negative,B5.InnerTankTop[1].m_flow_negative,$PRE.B5.InnerTankTop[1].m_flow_negative,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,pipePump2B2.state_b.phase,pipePump2B2.state_a.phase,pipePump1B1.state_b.phase,pipePump1B1.state_a.phase,pipeB1B2.state_b.phase,pipeB1B2.state_a.phase,P2.monitoring.state_in.phase,P1.monitoring.state_in.phase,V18.state_b.phase,V18.state_a.phase,V21.state_b.phase,V21.state_a.phase,V10.state_b.phase,V10.state_a.phase,V19.state_b.phase,V19.state_a.phase,V20.state_b.phase,V20.state_a.phase,V25.state_b.phase,V25.state_a.phase,V24.state_b.phase,V24.state_a.phase,V5.state_b.phase,V5.state_a.phase,V22.state_b.phase,V22.state_a.phase,V1.state_b.phase,V1.state_a.phase,V23.state_b.phase,V23.state_a.phase,V6.state_b.phase,V6.state_a.phase,V3.state_b.phase,V3.state_a.phase,V4.state_b.phase,V4.state_a.phase,V2.state_b.phase,V2.state_a.phase,V9.state_b.phase,V9.state_a.phase,V8.state_b.phase,V8.state_a.phase,V11.state_b.phase,V11.state_a.phase,V15.state_b.phase,V15.state_a.phase,V12.state_b.phase,V12.state_a.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1167):
 * Single equations (assignments): 1137
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 30
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,6,100.0%), (1,6,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 28 systems
   {(2,2), (1,5), (1,5), (2,2), (1,5), (1,2), (1,1), (1,5), (1,5), (2,7), (1,5), (2,7), (1,2), (1,1), (1,1), (1,1), (1,1), (2,5), (1,1), (2,10), (1,1), (1,1), (1,1), (1,1), (2,5), (1,1), (2,14), (2,10)}
Notification: Performance of prepare postOptimizeDAE: time 0.002657/2.906, allocations: 1.008 MB / 1.302 GB, free: 211.1 MB / 0.6824 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002539/2.909, allocations: 1.015 MB / 1.303 GB, free: 210.1 MB / 0.6824 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03489/2.944, allocations: 24.13 MB / 1.326 GB, free: 186 MB / 0.6824 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.000313/2.944, allocations: 415.3 kB / 1.327 GB, free: 185.6 MB / 0.6824 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.17e-05/2.944, allocations: 4 kB / 1.327 GB, free: 185.6 MB / 0.6824 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02756/2.972, allocations: 18.41 MB / 1.345 GB, free: 167 MB / 0.6824 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05318/3.025, allocations: 33.34 MB / 1.377 GB, free: 133.8 MB / 0.6824 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.783e-05/3.025, allocations: 4 kB / 1.377 GB, free: 133.8 MB / 0.6824 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.006237/3.031, allocations: 1.377 MB / 1.379 GB, free: 132.4 MB / 0.6824 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01152/3.043, allocations: 4.192 MB / 1.383 GB, free: 128.1 MB / 0.6824 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001723/3.044, allocations: 263.8 kB / 1.383 GB, free: 127.8 MB / 0.6824 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01687/3.061, allocations: 33.74 MB / 1.416 GB, free: 92.73 MB / 0.6824 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.118e-06/3.061, allocations: 4 kB / 1.416 GB, free: 92.73 MB / 0.6824 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.05306/3.114, allocations: 22.67 MB / 1.438 GB, free: 69.7 MB / 0.6824 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006022/3.12, allocations: 2.214 MB / 1.44 GB, free: 67.65 MB / 0.6824 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002081/3.122, allocations: 259.7 kB / 1.44 GB, free: 67.4 MB / 0.6824 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005411/3.128, allocations: 339.7 kB / 1.441 GB, free: 67.07 MB / 0.6824 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002017/3.13, allocations: 0.9006 MB / 1.442 GB, free: 66.16 MB / 0.6824 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0008595/3.131, allocations: 327.8 kB / 1.442 GB, free: 65.84 MB / 0.6824 GB
Notification: Performance of sorting global known variables: time 0.004107/3.135, allocations: 2.996 MB / 1.445 GB, free: 62.88 MB / 0.6824 GB
Notification: Performance of sort global known variables: time 1.11e-07/3.135, allocations: 0 / 1.445 GB, free: 62.88 MB / 0.6824 GB
Notification: Performance of remove unused functions: time 0.02283/3.158, allocations: 6.823 MB / 1.452 GB, free: 56.04 MB / 0.6824 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 13
 * Number of states: 34 (B5.medium.h,B5.level,volume5.medium.p,volume5.medium.h,P1.medium.p,P1.medium.h,P2.medium.p,P2.medium.h,B1.level,B1.medium.h,B2.level,B2.medium.h,B3.level,B3.medium.h,B4.level,B4.medium.h,B7.level,B7.medium.h,pipeB1B2.mediums[1].p,pipeB1B2.mediums[1].h,pipeB1B2.mediums[2].p,pipeB1B2.mediums[2].h,pipePump1B1.mediums[1].p,pipePump1B1.mediums[1].h,pipePump1B1.mediums[2].p,pipePump1B1.mediums[2].h,pipePump2B2.mediums[1].p,pipePump2B2.mediums[1].h,pipePump2B2.mediums[2].p,pipePump2B2.mediums[2].h,B6.level,B6.medium.h,volume4.medium.p,volume4.medium.h)
 * Number of discrete variables: 353 (controller.InitialStep1.localActive,controller.Transition1.enableFire,controller.Step1.localActive,controller.Step2.localActive,controller.Transition3.enableFire,controller.Step3.localActive,controller.Step4.localActive,controller.Step5.localActive,controller.Step6.localActive,controller.Transition7.enableFire,controller.Step7.localActive,controller.Step8.localActive,controller.Step9.localActive,controller.Step10.active,controller.Step10.localActive,controller.Step11.localActive,controller.Step12.localActive,controller.Step13.active,controller.Step13.localActive,controller.Step14.localActive,$cse8.phase,$cse8.region,$cse9.phase,$cse9.region,$cse10.phase,$cse10.region,$cse11.phase,$cse11.region,$cse12.phase,$cse12.region,$cse13,$cse14,$cse15,$cse16,$cse17,$cse18,$cse19,$cse20,$cse21,$cse22,$cse23,$cse24,$cse25,$cse26,$cse27.phase,$cse27.region,$cse28.phase,$cse28.region,$cse29.phase,$cse29.region,$cse30.phase,$cse30.region,$cse31.phase,$cse31.region,$cse32.phase,$cse32.region,$cse33.phase,$cse33.region,$cse34.phase,$cse34.region,$cse35.phase,$cse35.region,$cse36.phase,$cse36.region,$cse37.phase,$cse37.region,$cse38.phase,$cse38.region,$cse39.phase,$cse39.region,$cse40.phase,$cse40.region,$cse41.phase,$cse41.region,$cse42.phase,$cse42.region,$cse43.phase,$cse43.region,$cse44.phase,$cse44.region,$cse45.phase,$cse45.region,$cse46.phase,$cse46.region,$cse47.phase,$cse47.region,$cse48.phase,$cse48.region,$cse49.phase,$cse49.region,$cse50.phase,$cse50.region,$cse51.phase,$cse51.region,$cse52.phase,$cse52.region,$cse55.phase,$cse55.region,$cse57.phase,$cse57.region,$cse58.phase,$cse58.region,$cse59.region3boundary,$cse60.region3boundary,$cse61.phase,$cse61.region,$cse62.phase,$cse62.region,$cse63.region3boundary,$cse64.region3boundary,$cse65.phase,$cse65.region,$cse66.phase,$cse66.region,$cse68.region3boundary,$cse69.region3boundary,$cse70.phase,$cse70.region,$cse71.phase,$cse71.region,$cse75.phase,$cse75.region,$cse76.phase,$cse76.region,$cse77.phase,$cse77.region,$cse78.phase,$cse78.region,$cse79.phase,$cse79.region,$cse80.region3boundary,$cse81.region3boundary,$cse82.phase,$cse82.region,$cse83.region3boundary,$cse84.region3boundary,$cse85.phase,$cse85.region,$cse87.phase,$cse87.region,$cse88.phase,$cse88.region,$cse89.phase,$cse89.region,$cse90.phase,$cse90.region,$cse92.phase,$cse92.region,$cse93.phase,$cse93.region,$cse95.phase,$cse95.region,$cse96.phase,$cse96.region,$cse98.phase,$cse98.region,$cse99.phase,$cse99.region,$cse101.phase,$cse101.region,$cse102.phase,$cse102.region,$cse104.region3boundary,$cse105.region3boundary,$cse106.phase,$cse106.region,$cse107.region3boundary,$cse108.region3boundary,$cse109.phase,$cse109.region,$cse111.phase,$cse111.region,$cse112.phase,$cse112.region,$cse113.region3boundary,$cse114.region3boundary,$cse115.phase,$cse115.region,$cse116.region3boundary,$cse117.region3boundary,$cse118.phase,$cse118.region,$cse120.phase,$cse120.region,$cse121.phase,$cse121.region,$cse122.phase,$cse122.region,$cse126.region3boundary,$cse127.region3boundary,$cse128.phase,$cse128.region,$cse130.phase,$cse130.region,$cse131.phase,$cse131.region,$cse135.phase,$cse135.region,$cse137.phase,$cse137.region,$cse139.phase,$cse139.region,$cse141.phase,$cse141.region,$cse145.phase,$cse145.region,$cse147.phase,$cse147.region,$cse149.phase,$cse149.region,$cse151.phase,$cse151.region,$cse153.phase,$cse153.region,$cse155.phase,$cse155.region,$cse157.phase,$cse157.region,$cse158.phase,$cse158.region,$cse160.phase,$cse160.region,$cse161.phase,$cse161.region,$cse163.phase,$cse163.region,$cse165.phase,$cse165.region,$cse166.phase,$cse166.region,$cse168.phase,$cse168.region,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,B5.InnerTankTop[1].m_flow_negative,B5.InnerTankSide[1].m_flow_negative,B5.InnerTankBottom[1].m_flow_negative,B5.heatTransfer.states[1].phase,controller.InitialStep1.active,controller.InitialStep1.newActive,controller.InitialStep1.oldActive,controller.Transition1.fire,controller.Transition1.t_start,controller.Step1.active,controller.Step1.newActive,controller.Step1.oldActive,controller.Transition2.localCondition,controller.Transition2.enableFire,controller.Step2.active,controller.Step2.newActive,controller.Step2.oldActive,controller.Transition3.fire,controller.Transition3.t_start,controller.Step3.active,controller.Step3.newActive,controller.Step3.oldActive,controller.Transition4.localCondition,controller.Transition4.enableFire,controller.Step4.active,controller.Step4.newActive,controller.Step4.oldActive,controller.Transition5.enableFire,controller.Step5.active,controller.Step5.newActive,controller.Step5.oldActive,controller.Transition6.localCondition,controller.Transition6.enableFire,controller.Step6.active,controller.Step6.newActive,controller.Step6.oldActive,controller.Transition7.fire,controller.Transition7.outPort.occupied,controller.Transition7.t_start,controller.Step7.active,controller.Step7.newActive,controller.Step7.oldActive,controller.Step8.active,controller.Step8.newActive,controller.Step8.oldActive,controller.Step9.active,controller.Step9.newActive,controller.Step9.oldActive,controller.Step10.newActive,controller.Step10.oldActive,controller.Step11.active,controller.Step11.newActive,controller.Step11.oldActive,controller.Step12.active,controller.Step12.newActive,controller.Step12.oldActive,controller.Step13.newActive,controller.Step13.oldActive,controller.Step14.active,controller.Step14.newActive,controller.Step14.oldActive,controller.Transition8.enableFire,controller.Transition9.localCondition,controller.Transition9.enableFire,controller.Transition10.localCondition,controller.Transition10.enableFire,controller.Transition11.localCondition,controller.Transition11.enableFire,controller.Transition12.localCondition,controller.Transition12.enableFire,controller.Transition13.localCondition,controller.Transition13.enableFire,controller.T5_idle,controller.T7_idle,controller.TransitionWithSignal1.localCondition,controller.TransitionWithSignal1.enableFire,controller.TransitionWithSignal1.inPort.available,controller.stateGraphRoot.activeSteps,volume5.medium.phase,P1.heatTransfer.states[1].phase,P2.heatTransfer.states[1].phase,B1.heatTransfer.states[1].phase,B1.ports_m_flow_out[1],B1.aboveLevel[1],P1_on.endValue,P1_on.rate,P1_on.T,P2_on.endValue,P2_on.rate,P2_on.T,B2.heatTransfer.states[1].phase,B2.ports_m_flow_out[1],B2.aboveLevel[1],B3.heatTransfer.states[1].phase,B3.ports_m_flow_out[1],B3.ports_m_flow_out[2],B3.aboveLevel[1],B3.aboveLevel[2],B4.heatTransfer.states[1].phase,B4.ports_m_flow_out[1],B4.aboveLevel[1],B7.heatTransfer.states[1].phase,B7.ports_m_flow_out[1],B7.aboveLevel[1],pipeB1B2.statesFM[1].phase,pipeB1B2.statesFM[2].phase,pipePump1B1.statesFM[1].phase,pipePump1B1.statesFM[2].phase,pipePump2B2.statesFM[1].phase,pipePump2B2.statesFM[2].phase,B6.heatTransfer.states[1].phase,B6.ports_m_flow_out[1],B6.aboveLevel[1],volume4.medium.phase)
 * Number of discrete states: 34 (B6.aboveLevel[1],B7.aboveLevel[1],B4.aboveLevel[1],B3.aboveLevel[2],B3.aboveLevel[1],B2.aboveLevel[1],B1.aboveLevel[1],B3.ports_m_flow_out[2],controller.Step14.newActive,controller.Step13.newActive,P2_on.y,controller.Step12.newActive,controller.Step11.newActive,controller.Step10.newActive,B7.ports_m_flow_out[1],P1_on.y,controller.Step9.newActive,controller.Step8.newActive,controller.Step7.newActive,controller.Step6.newActive,controller.Step5.newActive,controller.Step4.newActive,controller.Step3.newActive,B3.ports_m_flow_out[1],controller.Step2.newActive,B2.ports_m_flow_out[1],controller.Step1.newActive,B1.ports_m_flow_out[1],controller.InitialStep1.newActive,B5.InnerTankTop[1].m_flow_negative,B4.ports_m_flow_out[1],B5.InnerTankSide[1].m_flow_negative,B5.InnerTankBottom[1].m_flow_negative,B6.ports_m_flow_out[1])
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (893):
 * Single equations (assignments): 739
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 101
 * When equations: 24
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 29
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,6,100.0%), (1,6,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 27 systems
   {(1,1), (1,1), (1,1), (2,6), (1,1), (1,7), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (2,2), (2,4), (2,4), (2,4), (2,4), (2,4), (2,4), (1,2), (2,2), (2,19), (2,9), (2,9), (2,13), (2,13), (1,2)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01257/3.17, allocations: 4.217 MB / 1.456 GB, free: 52.07 MB / 0.6824 GB
Notification: Performance of simCode: created initialization part: time 0.04051/3.211, allocations: 20.34 MB / 1.476 GB, free: 31.38 MB / 0.6824 GB
Notification: Performance of simCode: created event and clocks part: time 1.582e-05/3.211, allocations: 6.594 kB / 1.476 GB, free: 31.38 MB / 0.6824 GB
Notification: Performance of simCode: created simulation system equations: time 0.01538/3.226, allocations: 8.007 MB / 1.483 GB, free: 23.26 MB / 0.6824 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02089/3.247, allocations: 3.805 MB / 1.487 GB, free: 19.45 MB / 0.6824 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2281/3.475, allocations: 50.57 MB / 1.536 GB, free: 361 MB / 0.6824 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007226/3.482, allocations: 6.097 MB / 1.542 GB, free: 360.2 MB / 0.6824 GB
Notification: Performance of simCode: alias equations: time 0.01579/3.498, allocations: 9.154 MB / 1.551 GB, free: 359.8 MB / 0.6824 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003095/3.501, allocations: 0.7389 MB / 1.552 GB, free: 359.8 MB / 0.6824 GB
Notification: Performance of SimCode: time 1.673e-06/3.501, allocations: 4 kB / 1.552 GB, free: 359.8 MB / 0.6824 GB
Notification: Performance of Templates: time 0.3228/3.824, allocations: 253.8 MB / 1.8 GB, free: 125.7 MB / 0.6824 GB
"
[Timeout remaining time 656]
make -j1 -f Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.makefile [Timeout 660]
(rm -f Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe ; mkfifo Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe ; head -c 1048576 < Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe >> ../files/Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.sim & ./Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater -abortSlowSimulation -alarm=240  -emit_protected -lv LOG_STATS > Modelica_3.2.3_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe 2>&1) [Timeout 240]
[Calling os._exit(0), Time elapsed: 31.294793394976296]