Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
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.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater")
translateModel(Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008573/0.0008573, allocations: 99.86 kB / 15.05 MB, free: 1.105 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0008513/0.0008513, allocations: 177.3 kB / 15.94 MB, free: 228 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.525/1.525, allocations: 205.4 MB / 222.1 MB, free: 5.504 MB / 174.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0005571/0.0005577, allocations: 8 kB / 278.9 MB, free: 12.73 MB / 238.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2446/0.2452, allocations: 47.59 MB / 326.4 MB, free: 1.434 MB / 254.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater): time 0.7825/1.028, allocations: 357.6 MB / 0.668 GB, free: 8.121 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.09055/1.118, allocations: 82.12 MB / 0.7482 GB, free: 15.4 MB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01314/1.132, allocations: 322.9 kB / 0.7485 GB, free: 15.24 MB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01477/1.146, allocations: 4.07 MB / 0.7525 GB, free: 12.63 MB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.05841/1.205, allocations: 16.89 MB / 0.769 GB, free: 1.266 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.03994/1.245, allocations: 13.74 MB / 0.7824 GB, free: 5.148 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.02358/1.268, allocations: 13.8 MB / 0.7959 GB, free: 7.336 MB / 0.5137 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01078/1.279, allocations: 5.866 MB / 0.8016 GB, free: 1.418 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01493/1.294, allocations: 7.026 MB / 0.8085 GB, free: 10.37 MB / 0.5294 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.4036/1.698, allocations: 56.29 MB / 0.8635 GB, free: 47.68 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 0.004268/1.702, allocations: 0.5117 MB / 0.864 GB, free: 47.68 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03856/1.741, allocations: 16.73 MB / 0.8803 GB, free: 47.65 MB / 0.5606 GB
Notification: Performance of NFScalarize.scalarize: time 0.005624/1.746, allocations: 2.588 MB / 0.8828 GB, free: 47.65 MB / 0.5606 GB
Notification: Performance of NFVerifyModel.verify: time 0.004518/1.751, allocations: 1.97 MB / 0.8848 GB, free: 47.62 MB / 0.5606 GB
Notification: Performance of NFConvertDAE.convert: time 0.03043/1.781, allocations: 17.77 MB / 0.9021 GB, free: 46.39 MB / 0.5606 GB
Notification: Performance of FrontEnd - DAE generated: time 3.246e-06/1.781, allocations: 0 / 0.9021 GB, free: 46.39 MB / 0.5606 GB
Notification: Performance of FrontEnd: time 2.285e-06/1.781, allocations: 0 / 0.9021 GB, free: 46.39 MB / 0.5606 GB
Notification: Performance of Transformations before backend: time 0.0004322/1.782, allocations: 0 / 0.9021 GB, free: 46.39 MB / 0.5606 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.03191/1.814, allocations: 12.59 MB / 0.9144 GB, free: 36.12 MB / 0.5606 GB
Notification: Performance of prepare preOptimizeDAE: time 3.901e-05/1.814, allocations: 12.02 kB / 0.9144 GB, free: 36.11 MB / 0.5606 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02245/1.836, allocations: 3.319 MB / 0.9177 GB, free: 33.04 MB / 0.5606 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01418/1.851, allocations: 5.339 MB / 0.9229 GB, free: 29.29 MB / 0.5606 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0009874/1.852, allocations: 1.341 MB / 0.9242 GB, free: 28.14 MB / 0.5606 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002529/1.854, allocations: 0.8691 MB / 0.925 GB, free: 27.56 MB / 0.5606 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02875/1.883, allocations: 14.08 MB / 0.9388 GB, free: 16.8 MB / 0.5606 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001829/1.883, allocations: 33.97 kB / 0.9388 GB, free: 16.79 MB / 0.5606 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0009694/1.884, allocations: 373.6 kB / 0.9392 GB, free: 16.7 MB / 0.5606 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004949/1.885, allocations: 0.5498 MB / 0.9397 GB, free: 16.19 MB / 0.5606 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03332/1.918, allocations: 13.08 MB / 0.9525 GB, free: 6.984 MB / 0.5606 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.09476/2.013, allocations: 60.82 MB / 1.012 GB, free: 0.6836 MB / 0.6075 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02483/2.038, allocations: 10.46 MB / 1.022 GB, free: 9.953 MB / 0.6231 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.129/2.167, allocations: 55.75 MB / 1.077 GB, free: 14.04 MB / 0.6856 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02193/2.189, allocations: 7.1 MB / 1.083 GB, free: 8.68 MB / 0.6856 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.005541/2.194, allocations: 0.7231 MB / 1.084 GB, free: 8.086 MB / 0.6856 GB
Notification: Performance of pre-optimization done (n=796): time 2.921e-05/2.194, allocations: 0 / 1.084 GB, free: 8.086 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=870): time 0.378/2.572, allocations: 48.28 MB / 1.131 GB, free: 370 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001479/2.572, allocations: 318.7 kB / 1.132 GB, free: 369.8 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007529/2.58, allocations: 5.037 MB / 1.137 GB, free: 368 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 0.002069/2.582, allocations: 259 kB / 1.137 GB, free: 367.8 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002808/2.585, allocations: 3.594 MB / 1.14 GB, free: 365.2 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003967/2.589, allocations: 2.85 MB / 1.143 GB, free: 363 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001299/2.59, allocations: 200.9 kB / 1.143 GB, free: 362.8 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 0.000597/2.591, allocations: 0.6893 MB / 1.144 GB, free: 362.3 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.009456/2.6, allocations: 4.508 MB / 1.148 GB, free: 360.8 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01139/2.612, allocations: 6.61 MB / 1.155 GB, free: 356.7 MB / 0.6856 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01022/2.622, allocations: 5.101 MB / 1.16 GB, free: 354.5 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.636e-05/2.622, allocations: 15.25 kB / 1.16 GB, free: 354.5 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=1295) (initialization): time 0.03565/2.658, allocations: 14.13 MB / 1.174 GB, free: 344 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 0.005532/2.663, allocations: 27.72 MB / 1.201 GB, free: 314.2 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000157/2.663, allocations: 56 kB / 1.201 GB, free: 314.1 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01178/2.675, allocations: 3.294 MB / 1.204 GB, free: 311.3 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00981/2.685, allocations: 2.133 MB / 1.206 GB, free: 309.1 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01871/2.704, allocations: 25.28 MB / 1.231 GB, free: 282.6 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01172/2.716, allocations: 1.005 MB / 1.232 GB, free: 281.6 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003028/2.719, allocations: 491.8 kB / 1.232 GB, free: 281.1 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 1.709e-05/2.719, allocations: 4 kB / 1.232 GB, free: 281.1 MB / 0.6856 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.03808/2.757, allocations: 13.31 MB / 1.245 GB, free: 267.6 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002265/2.757, allocations: 56 kB / 1.245 GB, free: 267.5 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008248/2.765, allocations: 2.548 MB / 1.248 GB, free: 264.9 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01033/2.776, allocations: 2.169 MB / 1.25 GB, free: 262.8 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02914/2.805, allocations: 34.29 MB / 1.283 GB, free: 226.4 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01042/2.815, allocations: 0.9971 MB / 1.284 GB, free: 225.4 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002532/2.818, allocations: 487.8 kB / 1.285 GB, free: 224.9 MB / 0.6856 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,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,$PRE.B5.InnerTankTop[1].m_flow_negative,B5.InnerTankTop[1].m_flow_negative,$PRE.B5.InnerTankSide[1].m_flow_negative,B5.InnerTankSide[1].m_flow_negative,$PRE.B5.InnerTankBottom[1].m_flow_negative,B5.InnerTankBottom[1].m_flow_negative,B5.heatTransfer.states[1].phase,controller.InitialStep1.active,$PRE.controller.InitialStep1.localActive,controller.InitialStep1.localActive,$PRE.controller.InitialStep1.newActive,controller.InitialStep1.newActive,$PRE.controller.InitialStep1.oldActive,controller.InitialStep1.oldActive,controller.Transition1.enableFire,controller.Transition1.fire,$PRE.controller.Transition1.t_start,controller.Transition1.t_start,controller.Step1.active,$PRE.controller.Step1.localActive,controller.Step1.localActive,$PRE.controller.Step1.newActive,controller.Step1.newActive,$PRE.controller.Step1.oldActive,controller.Step1.oldActive,controller.Transition2.condition,controller.Transition2.enableFire,controller.Step2.active,$PRE.controller.Step2.localActive,controller.Step2.localActive,$PRE.controller.Step2.newActive,controller.Step2.newActive,$PRE.controller.Step2.oldActive,controller.Step2.oldActive,controller.Transition3.enableFire,controller.Transition3.fire,$PRE.controller.Transition3.t_start,controller.Transition3.t_start,controller.Step3.active,$PRE.controller.Step3.localActive,controller.Step3.localActive,$PRE.controller.Step3.newActive,controller.Step3.newActive,$PRE.controller.Step3.oldActive,controller.Step3.oldActive,controller.Transition4.condition,controller.Transition4.enableFire,controller.Step4.active,$PRE.controller.Step4.localActive,controller.Step4.localActive,$PRE.controller.Step4.newActive,controller.Step4.newActive,$PRE.controller.Step4.oldActive,controller.Step4.oldActive,controller.Transition5.enableFire,controller.Step5.active,$PRE.controller.Step5.localActive,controller.Step5.localActive,$PRE.controller.Step5.newActive,controller.Step5.newActive,$PRE.controller.Step5.oldActive,controller.Step5.oldActive,controller.Transition6.condition,controller.Transition6.enableFire,controller.Step6.active,$PRE.controller.Step6.localActive,controller.Step6.localActive,$PRE.controller.Step6.newActive,controller.Step6.newActive,$PRE.controller.Step6.oldActive,controller.Step6.oldActive,controller.Transition7.enableFire,controller.Transition7.fire,controller.Transition7.outPort.occupied,$PRE.controller.Transition7.t_start,controller.Transition7.t_start,controller.Step7.active,$PRE.controller.Step7.localActive,controller.Step7.localActive,$PRE.controller.Step7.newActive,controller.Step7.newActive,$PRE.controller.Step7.oldActive,controller.Step7.oldActive,controller.Step8.active,$PRE.controller.Step8.localActive,controller.Step8.localActive,$PRE.controller.Step8.newActive,controller.Step8.newActive,$PRE.controller.Step8.oldActive,controller.Step8.oldActive,controller.Step9.active,$PRE.controller.Step9.localActive,controller.Step9.localActive,$PRE.controller.Step9.newActive,controller.Step9.newActive,$PRE.controller.Step9.oldActive,controller.Step9.oldActive,controller.Step10.active,$PRE.controller.Step10.localActive,controller.Step10.localActive,$PRE.controller.Step10.newActive,controller.Step10.newActive,$PRE.controller.Step10.oldActive,controller.Step10.oldActive,controller.Step11.active,$PRE.controller.Step11.localActive,controller.Step11.localActive,$PRE.controller.Step11.newActive,controller.Step11.newActive,$PRE.controller.Step11.oldActive,controller.Step11.oldActive,controller.Step12.active,$PRE.controller.Step12.localActive,controller.Step12.localActive,$PRE.controller.Step12.newActive,controller.Step12.newActive,$PRE.controller.Step12.oldActive,controller.Step12.oldActive,controller.Step13.active,$PRE.controller.Step13.localActive,controller.Step13.localActive,$PRE.controller.Step13.newActive,controller.Step13.newActive,$PRE.controller.Step13.oldActive,controller.Step13.oldActive,controller.Step14.active,$PRE.controller.Step14.localActive,controller.Step14.localActive,$PRE.controller.Step14.newActive,controller.Step14.newActive,$PRE.controller.Step14.oldActive,controller.Step14.oldActive,controller.Transition8.enableFire,controller.Transition9.condition,controller.Transition9.enableFire,controller.Transition10.condition,controller.Transition10.enableFire,controller.Transition11.condition,controller.Transition11.enableFire,controller.Transition12.condition,controller.Transition12.enableFire,controller.Transition13.condition,controller.Transition13.enableFire,controller.T5_idle,controller.T7_idle,controller.TransitionWithSignal1.condition,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,$PRE.B1.ports_m_flow_out[1],B1.ports_m_flow_out[1],$PRE.B1.aboveLevel[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,$PRE.B2.ports_m_flow_out[1],B2.ports_m_flow_out[1],$PRE.B2.aboveLevel[1],B2.aboveLevel[1],B3.heatTransfer.states[1].phase,$PRE.B3.ports_m_flow_out[1],B3.ports_m_flow_out[1],$PRE.B3.ports_m_flow_out[2],B3.ports_m_flow_out[2],$PRE.B3.aboveLevel[1],B3.aboveLevel[1],$PRE.B3.aboveLevel[2],B3.aboveLevel[2],B4.heatTransfer.states[1].phase,$PRE.B4.ports_m_flow_out[1],B4.ports_m_flow_out[1],$PRE.B4.aboveLevel[1],B4.aboveLevel[1],B7.heatTransfer.states[1].phase,$PRE.B7.ports_m_flow_out[1],B7.ports_m_flow_out[1],$PRE.B7.aboveLevel[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,$PRE.B6.ports_m_flow_out[1],B6.ports_m_flow_out[1],$PRE.B6.aboveLevel[1],B6.aboveLevel[1],volume4.medium.phase,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 (linear and non-linear blocks): 0
 * Torn equation systems: 30
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(1,100.0%) 6,(1,100.0%) 6}
 * Non-linear torn systems: 28 {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,1 6,1 1,2 14,2 10}
Notification: Performance of prepare postOptimizeDAE: time 0.008596/2.827, allocations: 2.172 MB / 1.287 GB, free: 222.8 MB / 0.6856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003467/2.83, allocations: 0.7411 MB / 1.288 GB, free: 222 MB / 0.6856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0578/2.888, allocations: 35.6 MB / 1.322 GB, free: 186.2 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.000353/2.888, allocations: 407.4 kB / 1.323 GB, free: 185.8 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.355e-05/2.888, allocations: 7.984 kB / 1.323 GB, free: 185.8 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.04941/2.938, allocations: 30.26 MB / 1.352 GB, free: 155.3 MB / 0.6856 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.08266/3.021, allocations: 49.9 MB / 1.401 GB, free: 105.3 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.812e-05/3.021, allocations: 4 kB / 1.401 GB, free: 105.3 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.007608/3.028, allocations: 1.614 MB / 1.403 GB, free: 103.7 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01384/3.042, allocations: 6.128 MB / 1.409 GB, free: 97.48 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.002213/3.044, allocations: 263.8 kB / 1.409 GB, free: 97.22 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01883/3.063, allocations: 27.31 MB / 1.436 GB, free: 68.63 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.489e-06/3.063, allocations: 4 kB / 1.436 GB, free: 68.63 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.05423/3.118, allocations: 33.97 MB / 1.469 GB, free: 34.29 MB / 0.6856 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.008057/3.126, allocations: 2.476 MB / 1.471 GB, free: 31.78 MB / 0.6856 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003311/3.129, allocations: 239.9 kB / 1.471 GB, free: 31.54 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.009423/3.138, allocations: 0.7836 MB / 1.472 GB, free: 30.76 MB / 0.6856 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002734/3.141, allocations: 0.8448 MB / 1.473 GB, free: 29.91 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001227/3.142, allocations: 323.8 kB / 1.473 GB, free: 29.59 MB / 0.6856 GB
Notification: Performance of sorting global known variables: time 0.004947/3.147, allocations: 3.214 MB / 1.476 GB, free: 26.39 MB / 0.6856 GB
Notification: Performance of sort global known variables: time 1.6e-07/3.147, allocations: 0 / 1.476 GB, free: 26.39 MB / 0.6856 GB
Notification: Performance of remove unused functions: time 0.02625/3.174, allocations: 7.098 MB / 1.483 GB, free: 19.7 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 9
 * 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,$cse10.phase,$cse10.region,$cse11.phase,$cse11.region,$cse12.phase,$cse12.region,$cse13.phase,$cse13.region,$cse14.phase,$cse14.region,$cse15,$cse16,$cse17,$cse18,$cse19,$cse20,$cse21,$cse22,$cse23,$cse24,$cse25,$cse26,$cse27,$cse28,$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,$cse53.phase,$cse53.region,$cse54.phase,$cse54.region,$cse57.phase,$cse57.region,$cse59.phase,$cse59.region,$cse60.phase,$cse60.region,$cse61.region3boundary,$cse62.region3boundary,$cse63.phase,$cse63.region,$cse64.phase,$cse64.region,$cse65.region3boundary,$cse66.region3boundary,$cse67.phase,$cse67.region,$cse68.phase,$cse68.region,$cse70.region3boundary,$cse71.region3boundary,$cse72.phase,$cse72.region,$cse73.phase,$cse73.region,$cse77.phase,$cse77.region,$cse78.phase,$cse78.region,$cse79.phase,$cse79.region,$cse80.phase,$cse80.region,$cse81.phase,$cse81.region,$cse82.region3boundary,$cse83.region3boundary,$cse84.phase,$cse84.region,$cse85.region3boundary,$cse86.region3boundary,$cse87.phase,$cse87.region,$cse89.phase,$cse89.region,$cse90.phase,$cse90.region,$cse91.phase,$cse91.region,$cse92.phase,$cse92.region,$cse94.phase,$cse94.region,$cse95.phase,$cse95.region,$cse97.phase,$cse97.region,$cse98.phase,$cse98.region,$cse100.phase,$cse100.region,$cse101.phase,$cse101.region,$cse103.phase,$cse103.region,$cse104.phase,$cse104.region,$cse106.region3boundary,$cse107.region3boundary,$cse108.phase,$cse108.region,$cse109.region3boundary,$cse110.region3boundary,$cse111.phase,$cse111.region,$cse113.phase,$cse113.region,$cse114.phase,$cse114.region,$cse115.region3boundary,$cse116.region3boundary,$cse117.phase,$cse117.region,$cse118.region3boundary,$cse119.region3boundary,$cse120.phase,$cse120.region,$cse122.phase,$cse122.region,$cse123.phase,$cse123.region,$cse124.phase,$cse124.region,$cse128.region3boundary,$cse129.region3boundary,$cse130.phase,$cse130.region,$cse132.phase,$cse132.region,$cse133.phase,$cse133.region,$cse137.phase,$cse137.region,$cse139.phase,$cse139.region,$cse141.phase,$cse141.region,$cse143.phase,$cse143.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,$cse159.phase,$cse159.region,$cse160.phase,$cse160.region,$cse162.phase,$cse162.region,$cse163.phase,$cse163.region,$cse165.phase,$cse165.region,$cse167.phase,$cse167.region,$cse168.phase,$cse168.region,$cse170.phase,$cse170.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.condition,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.condition,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.condition,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.condition,controller.Transition9.enableFire,controller.Transition10.condition,controller.Transition10.enableFire,controller.Transition11.condition,controller.Transition11.enableFire,controller.Transition12.condition,controller.Transition12.enableFire,controller.Transition13.condition,controller.Transition13.enableFire,controller.T5_idle,controller.T7_idle,controller.TransitionWithSignal1.condition,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 (895):
 * Single equations (assignments): 741
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 101
 * When equations: 24
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 29
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(1,100.0%) 6,(1,100.0%) 6}
 * Non-linear torn systems: 27 {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,1 5,1 5,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.01378/3.188, allocations: 4.053 MB / 1.487 GB, free: 16.19 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.2668/3.454, allocations: 23.86 MB / 1.511 GB, free: 357.8 MB / 0.6856 GB
Notification: Performance of simCode: created event and clocks part: time 1.593e-05/3.454, allocations: 4.625 kB / 1.511 GB, free: 357.8 MB / 0.6856 GB
Notification: Performance of simCode: created simulation system equations: time 0.01883/3.473, allocations: 11.59 MB / 1.522 GB, free: 357 MB / 0.6856 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02171/3.495, allocations: 3.634 MB / 1.525 GB, free: 356.4 MB / 0.6856 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.07099/3.566, allocations: 35.38 MB / 1.56 GB, free: 338.4 MB / 0.6856 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01606/3.582, allocations: 6.845 MB / 1.567 GB, free: 337 MB / 0.6856 GB
Notification: Performance of simCode: alias equations: time 0.02746/3.61, allocations: 9.568 MB / 1.576 GB, free: 336.2 MB / 0.6856 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.005906/3.616, allocations: 0.7451 MB / 1.577 GB, free: 336.2 MB / 0.6856 GB
Notification: Performance of SimCode: time 1.453e-06/3.616, allocations: 0 / 1.577 GB, free: 336.2 MB / 0.6856 GB
Notification: Performance of Templates: time 0.3914/4.007, allocations: 250.5 MB / 1.821 GB, free: 91.25 MB / 0.6856 GB
make -j1 -f Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.makefile
Environment - environmentSimulation:
(rm -f Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe ; mkfifo Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe ; head -c 1048576 < Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe >> ../files/Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.sim & ./Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Modelica_3.2.1_Modelica.Fluid.Examples.AST_BatchPlant.BatchPlant_StandardWater.pipe 2>&1)
Environment - environmentSimulation: