Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.MixingVolumes.Validation.TraceSubstanceConservationDynamicBalance.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 IBPSA with version 3.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/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)
Running command: translateModel(IBPSA.Fluid.MixingVolumes.Validation.TraceSubstanceConservationDynamicBalance,tolerance=1e-08,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.MixingVolumes.Validation.TraceSubstanceConservationDynamicBalance")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002605/0.002611, allocations: 356.9 kB / 16.58 MB, free: 5.566 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo): time 1.473/1.473, allocations: 165.8 MB / 183.1 MB, free: 5.473 MB / 138.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 3.69/3.69, allocations: 410.8 MB / 0.6001 GB, free: 15.14 MB / 298.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001792/0.001791, allocations: 189.1 kB / 0.6668 GB, free: 6.91 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0005015/0.0005016, allocations: 15.98 kB / 0.7429 GB, free: 8.941 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4187/0.4193, allocations: 67.43 MB / 0.8087 GB, free: 28.78 MB / 378.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 5.993/6.412, allocations: 2.046 GB / 2.855 GB, free: 48.22 MB / 0.573 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002802/6.412, allocations: 97.7 kB / 2.855 GB, free: 48.17 MB / 0.573 GB
Notification: Performance of FrontEnd - DAE generated: time 3.147/9.56, allocations: 0.5864 GB / 3.442 GB, free: 101.7 MB / 0.573 GB
Notification: Performance of FrontEnd: time 1.933e-06/9.56, allocations: 0 / 3.442 GB, free: 101.7 MB / 0.573 GB
Notification: Performance of Transformations before backend: time 8.487e-05/9.56, allocations: 28 kB / 3.442 GB, free: 101.7 MB / 0.573 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 186
 * Number of variables: 186
Notification: Performance of Generate backend data structure: time 0.003622/9.563, allocations: 1.247 MB / 3.443 GB, free: 101 MB / 0.573 GB
Notification: Performance of prepare preOptimizeDAE: time 4.218e-05/9.564, allocations: 13.94 kB / 3.443 GB, free: 101 MB / 0.573 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001896/9.565, allocations: 202.8 kB / 3.443 GB, free: 101 MB / 0.573 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001939/9.567, allocations: 0.6632 MB / 3.444 GB, free: 100.7 MB / 0.573 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 6.083e-05/9.568, allocations: 58.44 kB / 3.444 GB, free: 100.7 MB / 0.573 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001642/9.568, allocations: 76.62 kB / 3.444 GB, free: 100.7 MB / 0.573 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001894/9.57, allocations: 0.909 MB / 3.445 GB, free: 100.5 MB / 0.573 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.289e-05/9.57, allocations: 6.375 kB / 3.445 GB, free: 100.5 MB / 0.573 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001003/9.57, allocations: 35.38 kB / 3.445 GB, free: 100.5 MB / 0.573 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.995e-05/9.57, allocations: 27.59 kB / 3.445 GB, free: 100.5 MB / 0.573 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00148/9.571, allocations: 0.5898 MB / 3.445 GB, free: 100.5 MB / 0.573 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.005853/9.577, allocations: 4.565 MB / 3.45 GB, free: 97.29 MB / 0.573 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0009241/9.578, allocations: 445.6 kB / 3.45 GB, free: 97.21 MB / 0.573 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004644/9.579, allocations: 232.8 kB / 3.45 GB, free: 97.18 MB / 0.573 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002422/9.579, allocations: 108.1 kB / 3.451 GB, free: 97.08 MB / 0.573 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.874e-05/9.579, allocations: 44.72 kB / 3.451 GB, free: 97.04 MB / 0.573 GB
Notification: Performance of pre-optimization done (n=43): time 3.306e-06/9.579, allocations: 0 / 3.451 GB, free: 97.04 MB / 0.573 GB
Notification: Performance of matching and sorting (n=45): time 0.004103/9.583, allocations: 1.55 MB / 3.452 GB, free: 96.41 MB / 0.573 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.686e-05/9.583, allocations: 110.2 kB / 3.452 GB, free: 96.29 MB / 0.573 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001158/9.584, allocations: 0.7046 MB / 3.453 GB, free: 96.16 MB / 0.573 GB
Notification: Performance of collectPreVariables (initialization): time 9.893e-05/9.585, allocations: 35.14 kB / 3.453 GB, free: 96.12 MB / 0.573 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003198/9.585, allocations: 426.3 kB / 3.453 GB, free: 95.93 MB / 0.573 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001769/9.585, allocations: 161.6 kB / 3.454 GB, free: 95.84 MB / 0.573 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 3.867e-05/9.585, allocations: 14.38 kB / 3.454 GB, free: 95.84 MB / 0.573 GB
Notification: Performance of setup shared object (initialization): time 0.0001319/9.585, allocations: 383.9 kB / 3.454 GB, free: 95.52 MB / 0.573 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004382/9.586, allocations: 178.5 kB / 3.454 GB, free: 95.52 MB / 0.573 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005902/9.586, allocations: 419.1 kB / 3.454 GB, free: 95.25 MB / 0.573 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: vol.dynBal.medium.p = vol.dynBal.p_start (sin.p = vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.002606/9.589, allocations: 0.6651 MB / 3.455 GB, free: 95.14 MB / 0.573 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.28e-06/9.589, allocations: 0 / 3.455 GB, free: 95.14 MB / 0.573 GB
Notification: Performance of matching and sorting (n=74) (initialization): time 0.001521/9.59, allocations: 0.6593 MB / 3.456 GB, free: 95.02 MB / 0.573 GB
Notification: Performance of prepare postOptimizeDAE: time 2.592e-05/9.59, allocations: 8.812 kB / 3.456 GB, free: 95.02 MB / 0.573 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.402e-05/9.591, allocations: 7.406 kB / 3.456 GB, free: 95.02 MB / 0.573 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.588e-05/9.591, allocations: 15.42 kB / 3.456 GB, free: 95.02 MB / 0.573 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008737/9.591, allocations: 128.5 kB / 3.456 GB, free: 95 MB / 0.573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.651e-05/9.591, allocations: 11.03 kB / 3.456 GB, free: 95 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001209/9.593, allocations: 54.83 kB / 3.456 GB, free: 94.99 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.454e-05/9.593, allocations: 32.5 kB / 3.456 GB, free: 94.99 MB / 0.573 GB
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization sytem:
         vol.dynBal.medium.p = vol.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 28
 * Number of states: 0 ()
 * Number of discrete variables: 2 (intTraSubOut.local_reset,intTraSubIn.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (74):
 * Single equations (assignments): 74
 * 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: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0006196/9.593, allocations: 274 kB / 3.456 GB, free: 94.86 MB / 0.573 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002302/9.594, allocations: 82.12 kB / 3.456 GB, free: 94.82 MB / 0.573 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0002075/9.594, allocations: 89 kB / 3.456 GB, free: 94.8 MB / 0.573 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.923e-06/9.594, allocations: 3 kB / 3.456 GB, free: 94.8 MB / 0.573 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.03e-05/9.594, allocations: 3.953 kB / 3.456 GB, free: 94.8 MB / 0.573 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0009321/9.595, allocations: 0.6885 MB / 3.457 GB, free: 94.36 MB / 0.573 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003347/9.598, allocations: 1.611 MB / 3.459 GB, free: 93.49 MB / 0.573 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.095e-06/9.598, allocations: 7.031 kB / 3.459 GB, free: 93.49 MB / 0.573 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0008891/9.599, allocations: 96.05 kB / 3.459 GB, free: 93.48 MB / 0.573 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.146e-05/9.599, allocations: 7.5 kB / 3.459 GB, free: 93.48 MB / 0.573 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.622e-05/9.599, allocations: 15.56 kB / 3.459 GB, free: 93.48 MB / 0.573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.017e-05/9.599, allocations: 15.38 kB / 3.459 GB, free: 93.47 MB / 0.573 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.969e-06/9.599, allocations: 3.5 kB / 3.459 GB, free: 93.47 MB / 0.573 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001651/9.601, allocations: 0.8742 MB / 3.46 GB, free: 92.94 MB / 0.573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005479/9.602, allocations: 204.9 kB / 3.46 GB, free: 92.78 MB / 0.573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001633/9.602, allocations: 12.38 kB / 3.46 GB, free: 92.78 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008672/9.603, allocations: 48.12 kB / 3.46 GB, free: 92.76 MB / 0.573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 7.085e-05/9.603, allocations: 36.56 kB / 3.46 GB, free: 92.75 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.943e-05/9.603, allocations: 18.47 kB / 3.46 GB, free: 92.75 MB / 0.573 GB
Notification: Performance of sorting global known variables: time 0.0009258/9.604, allocations: 0.5001 MB / 3.46 GB, free: 92.54 MB / 0.573 GB
Notification: Performance of sort global known variables: time 3.41e-07/9.604, allocations: 0 / 3.46 GB, free: 92.54 MB / 0.573 GB
Notification: Performance of remove unused functions: time 0.001739/9.605, allocations: 372.7 kB / 3.461 GB, free: 92.43 MB / 0.573 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * Number of states: 5 (vol.dynBal.U,vol.dynBal.mXi[1],vol.dynBal.mC[1],intTraSubIn.y,intTraSubOut.y)
 * 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 (45):
 * Single equations (assignments): 45
 * 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: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.002853/9.608, allocations: 1.666 MB / 3.462 GB, free: 91.89 MB / 0.573 GB
Notification: Performance of simCode: created initialization part: time 0.001298/9.61, allocations: 452.2 kB / 3.463 GB, free: 91.75 MB / 0.573 GB
Notification: Performance of simCode: created event and clocks part: time 5.801e-06/9.61, allocations: 3.375 kB / 3.463 GB, free: 91.75 MB / 0.573 GB
Notification: Performance of simCode: created simulation system equations: time 0.0004298/9.61, allocations: 209.1 kB / 3.463 GB, free: 91.68 MB / 0.573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003983/9.614, allocations: 461 kB / 3.464 GB, free: 91.59 MB / 0.573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003615/9.618, allocations: 1.973 MB / 3.465 GB, free: 90.5 MB / 0.573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006108/9.618, allocations: 1.048 MB / 3.466 GB, free: 89.77 MB / 0.573 GB
Notification: Performance of simCode: alias equations: time 0.001222/9.62, allocations: 197 kB / 3.467 GB, free: 89.76 MB / 0.573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002594/9.62, allocations: 61.89 kB / 3.467 GB, free: 89.75 MB / 0.573 GB
Notification: Performance of SimCode: time 1.102e-06/9.62, allocations: 0 / 3.467 GB, free: 89.75 MB / 0.573 GB
Notification: Performance of Templates: time 0.07311/9.693, allocations: 33.78 MB / 3.5 GB, free: 61.88 MB / 0.573 GB