Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_master_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.conf.json
Using package ModelicaServices with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Using package Buildings with version 9.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo)
Running command: translateModel(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow,tolerance=1e-08,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_master_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo): time 4.66/4.66, allocations: 0.5623 GB / 0.5769 GB, free: 3.324 MB / 474.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002466/0.002466, allocations: 362.8 kB / 0.644 GB, free: 185.6 MB / 474.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 3.806/3.806, allocations: 410.7 MB / 1.112 GB, free: 147.8 MB / 0.5886 GB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001735/0.001734, allocations: 180.1 kB / 1.225 GB, free: 35.88 MB / 0.5886 GB
Notification: Performance of FrontEnd - loaded program: time 0.00048/0.0004802, allocations: 16.55 kB / 1.348 GB, free: 121.7 MB / 0.6042 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1464/0.1469, allocations: 115.3 MB / 1.461 GB, free: 6.598 MB / 0.6042 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow): time 0.7899/0.9368, allocations: 171.2 MB / 1.628 GB, free: 6.055 MB / 0.698 GB
Notification: Performance of NFInst.instExpressions: time 0.01128/0.9482, allocations: 5.835 MB / 1.634 GB, free: 1.305 MB / 0.698 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001256/0.9494, allocations: 31.62 kB / 1.634 GB, free: 1.289 MB / 0.698 GB
Notification: Performance of NFTyping.typeComponents: time 0.001639/0.9511, allocations: 0.5023 MB / 1.634 GB, free: 0.8672 MB / 0.698 GB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:788:36-789:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:786:36-787:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:788:36-789:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:786:36-787:54:writable] Warning: 'each' used when modifying non-array element m_flow.
Notification: Performance of NFTyping.typeBindings: time 0.005615/0.9567, allocations: 1.785 MB / 1.636 GB, free: 15.48 MB / 0.7136 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004451/0.9612, allocations: 1.184 MB / 1.637 GB, free: 14.66 MB / 0.7136 GB
Notification: Performance of NFFlatten.flatten: time 0.004762/0.966, allocations: 3.422 MB / 1.64 GB, free: 12.66 MB / 0.7136 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001309/0.9673, allocations: 0.8006 MB / 1.641 GB, free: 12.2 MB / 0.7136 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001559/0.9689, allocations: 0.8393 MB / 1.642 GB, free: 11.75 MB / 0.7136 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00139/0.9703, allocations: 0.9079 MB / 1.643 GB, free: 11.27 MB / 0.7136 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001506/0.9705, allocations: 46.34 kB / 1.643 GB, free: 11.27 MB / 0.7136 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001832/0.9723, allocations: 0.5518 MB / 1.643 GB, free: 11 MB / 0.7136 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005968/0.9729, allocations: 492.4 kB / 1.644 GB, free: 10.71 MB / 0.7136 GB
Notification: Performance of NFVerifyModel.verify: time 0.0004019/0.9734, allocations: 279.4 kB / 1.644 GB, free: 10.56 MB / 0.7136 GB
Notification: Performance of NFConvertDAE.convert: time 0.002042/0.9754, allocations: 1.561 MB / 1.646 GB, free: 9.527 MB / 0.7136 GB
Notification: Performance of FrontEnd - DAE generated: time 4.334e-06/0.9754, allocations: 4 kB / 1.646 GB, free: 9.523 MB / 0.7136 GB
Notification: Performance of FrontEnd: time 1.779e-06/0.9754, allocations: 0 / 1.646 GB, free: 9.523 MB / 0.7136 GB
Notification: Performance of Transformations before backend: time 1.732e-05/0.9755, allocations: 0 / 1.646 GB, free: 9.523 MB / 0.7136 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 248
 * Number of variables: 248
Notification: Performance of Generate backend data structure: time 0.002688/0.9782, allocations: 1.346 MB / 1.647 GB, free: 8.508 MB / 0.7136 GB
Notification: Performance of prepare preOptimizeDAE: time 3.498e-05/0.9782, allocations: 10.91 kB / 1.647 GB, free: 8.508 MB / 0.7136 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002487/0.9807, allocations: 330.6 kB / 1.647 GB, free: 8.359 MB / 0.7136 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001796/0.9825, allocations: 0.8311 MB / 1.648 GB, free: 7.727 MB / 0.7136 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.698e-05/0.9826, allocations: 79.34 kB / 1.648 GB, free: 7.688 MB / 0.7136 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001897/0.9828, allocations: 101.7 kB / 1.648 GB, free: 7.652 MB / 0.7136 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002393/0.9852, allocations: 1.081 MB / 1.649 GB, free: 7.051 MB / 0.7136 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.573e-05/0.9853, allocations: 3.938 kB / 1.649 GB, free: 7.047 MB / 0.7136 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001416/0.9854, allocations: 43.41 kB / 1.649 GB, free: 7.016 MB / 0.7136 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.597e-05/0.9855, allocations: 38.67 kB / 1.649 GB, free: 7.004 MB / 0.7136 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002397/0.9879, allocations: 0.9993 MB / 1.65 GB, free: 6 MB / 0.7136 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.008449/0.9964, allocations: 4.235 MB / 1.655 GB, free: 1.469 MB / 0.7136 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001312/0.9977, allocations: 0.6021 MB / 1.655 GB, free: 0.8555 MB / 0.7136 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0007553/0.9985, allocations: 354.4 kB / 1.655 GB, free: 0.5078 MB / 0.7136 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002339/0.9988, allocations: 105.5 kB / 1.656 GB, free: 396 kB / 0.7136 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.425e-05/0.9988, allocations: 47 kB / 1.656 GB, free: 340 kB / 0.7136 GB
Notification: Performance of pre-optimization done (n=53): time 3.729e-06/0.9988, allocations: 4 kB / 1.656 GB, free: 336 kB / 0.7136 GB
Notification: Performance of matching and sorting (n=55): time 0.005603/1.004, allocations: 2.212 MB / 1.658 GB, free: 14 MB / 0.7292 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.071e-05/1.005, allocations: 59.97 kB / 1.658 GB, free: 13.93 MB / 0.7292 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001541/1.006, allocations: 0.8685 MB / 1.659 GB, free: 13.05 MB / 0.7292 GB
Notification: Performance of collectPreVariables (initialization): time 8.523e-05/1.006, allocations: 37.7 kB / 1.659 GB, free: 13.01 MB / 0.7292 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005259/1.007, allocations: 0.5146 MB / 1.659 GB, free: 12.49 MB / 0.7292 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002488/1.007, allocations: 197.1 kB / 1.659 GB, free: 12.29 MB / 0.7292 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 5.405e-05/1.007, allocations: 16 kB / 1.659 GB, free: 12.28 MB / 0.7292 GB
Notification: Performance of setup shared object (initialization): time 0.0001803/1.007, allocations: 330 kB / 1.66 GB, free: 11.95 MB / 0.7292 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007129/1.008, allocations: 293.8 kB / 1.66 GB, free: 11.66 MB / 0.7292 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.000868/1.009, allocations: 454.6 kB / 1.66 GB, free: 11.16 MB / 0.7292 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: volDyn.dynBal.medium.p = volDyn.dynBal.p_start (bou.p = volDyn.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.003925/1.013, allocations: 1.098 MB / 1.662 GB, free: 10.04 MB / 0.7292 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.357e-06/1.013, allocations: 0 / 1.662 GB, free: 10.04 MB / 0.7292 GB
Notification: Performance of matching and sorting (n=84) (initialization): time 0.002214/1.015, allocations: 0.9258 MB / 1.662 GB, free: 9.102 MB / 0.7292 GB
Notification: Performance of prepare postOptimizeDAE: time 2.539e-05/1.015, allocations: 4 kB / 1.662 GB, free: 9.098 MB / 0.7292 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.296e-05/1.015, allocations: 4 kB / 1.662 GB, free: 9.094 MB / 0.7292 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002946/1.015, allocations: 106.8 kB / 1.663 GB, free: 8.98 MB / 0.7292 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009818/1.016, allocations: 171.7 kB / 1.663 GB, free: 8.812 MB / 0.7292 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004861/1.021, allocations: 4.298 MB / 1.667 GB, free: 4.109 MB / 0.7292 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001236/1.023, allocations: 78 kB / 1.667 GB, free: 4.035 MB / 0.7292 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.888e-05/1.023, allocations: 24 kB / 1.667 GB, free: 4.012 MB / 0.7292 GB
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization sytem:
         volDyn.dynBal.medium.p = volDyn.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 17
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (80):
 * Single equations (assignments): 77
 * 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: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 3 {1 1,1 1,1 2}
Notification: Performance of prepare postOptimizeDAE: time 0.0007351/1.023, allocations: 337.6 kB / 1.667 GB, free: 3.672 MB / 0.7292 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002228/1.024, allocations: 98.83 kB / 1.667 GB, free: 3.574 MB / 0.7292 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0002284/1.024, allocations: 113.1 kB / 1.668 GB, free: 3.465 MB / 0.7292 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.34e-06/1.024, allocations: 8 kB / 1.668 GB, free: 3.457 MB / 0.7292 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.718e-06/1.024, allocations: 4 kB / 1.668 GB, free: 3.453 MB / 0.7292 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001544/1.025, allocations: 0.773 MB / 1.668 GB, free: 2.629 MB / 0.7292 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004362/1.03, allocations: 1.984 MB / 1.67 GB, free: 0.543 MB / 0.7292 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.928e-06/1.03, allocations: 4.031 kB / 1.67 GB, free: 0.5391 MB / 0.7292 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009403/1.031, allocations: 163.8 kB / 1.67 GB, free: 388 kB / 0.7292 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0001374/1.031, allocations: 40.83 kB / 1.67 GB, free: 344 kB / 0.7292 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.999e-05/1.031, allocations: 16 kB / 1.67 GB, free: 328 kB / 0.7292 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001715/1.033, allocations: 1.48 MB / 1.672 GB, free: 14.71 MB / 0.7448 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.803e-06/1.033, allocations: 7.938 kB / 1.672 GB, free: 14.7 MB / 0.7448 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002534/1.035, allocations: 1.25 MB / 1.673 GB, free: 13.39 MB / 0.7448 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006024/1.036, allocations: 256 kB / 1.673 GB, free: 13.12 MB / 0.7448 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001651/1.036, allocations: 16 kB / 1.673 GB, free: 13.11 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001044/1.037, allocations: 74.05 kB / 1.673 GB, free: 13.04 MB / 0.7448 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001421/1.037, allocations: 47.89 kB / 1.674 GB, free: 12.99 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 9.134e-05/1.037, allocations: 31.92 kB / 1.674 GB, free: 12.96 MB / 0.7448 GB
Notification: Performance of sorting global known variables: time 0.001092/1.039, allocations: 0.6005 MB / 1.674 GB, free: 12.36 MB / 0.7448 GB
Notification: Performance of sort global known variables: time 3.518e-06/1.039, allocations: 0 / 1.674 GB, free: 12.36 MB / 0.7448 GB
Notification: Performance of remove unused functions: time 0.001239/1.04, allocations: 311.9 kB / 1.674 GB, free: 12.05 MB / 0.7448 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * Number of states: 3 (volDyn.dynBal.U,volDyn.dynBal.mXi[1],volDyn.dynBal.mC[1])
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (53):
 * Single equations (assignments): 52
 * 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: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {1 2}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004908/1.04, allocations: 198.3 kB / 1.675 GB, free: 11.86 MB / 0.7448 GB
Notification: Performance of simCode: created initialization part: time 0.002023/1.042, allocations: 0.9993 MB / 1.676 GB, free: 10.82 MB / 0.7448 GB
Notification: Performance of simCode: created event and clocks part: time 7.734e-06/1.042, allocations: 4 kB / 1.676 GB, free: 10.81 MB / 0.7448 GB
Notification: Performance of simCode: created simulation system equations: time 0.0009077/1.043, allocations: 459.6 kB / 1.676 GB, free: 10.35 MB / 0.7448 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003538/1.047, allocations: 0.6206 MB / 1.677 GB, free: 9.742 MB / 0.7448 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004473/1.051, allocations: 2.48 MB / 1.679 GB, free: 7.195 MB / 0.7448 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001154/1.053, allocations: 1.19 MB / 1.68 GB, free: 5.977 MB / 0.7448 GB
Notification: Performance of simCode: alias equations: time 0.001374/1.054, allocations: 252.4 kB / 1.68 GB, free: 5.727 MB / 0.7448 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002862/1.054, allocations: 79.42 kB / 1.681 GB, free: 5.648 MB / 0.7448 GB
Notification: Performance of SimCode: time 1.603e-06/1.054, allocations: 0 / 1.681 GB, free: 5.648 MB / 0.7448 GB
Notification: Performance of Templates: time 0.04846/1.103, allocations: 20.59 MB / 1.701 GB, free: 1.363 MB / 0.7605 GB