Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems 2.0.0-master/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 2.5.0/package.mo", uses=false)
Using package NcDataReader2 with version 2.5.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 2.5.0/package.mo)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
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 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems 2.0.0-master/package.mo)
Running command: translateModel(BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance")
translateModel(BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems 2.0.0-master/package.mo): time 0.8991/0.8991, allocations: 140.1 MB / 156 MB, free: 8.398 MB / 122.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.00154/0.00154, allocations: 184.5 kB / 188.5 MB, free: 8.992 MB / 154.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 2.016/2.016, allocations: 205.4 MB / 426.3 MB, free: 8.137 MB / 314.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001022/0.001022, allocations: 88.89 kB / 0.4945 GB, free: 8.059 MB / 394.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 2.5.0/package.mo): time 0.001792/0.001792, allocations: 273.2 kB / 0.5728 GB, free: 66.13 MB / 410.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004979/0.0004981, allocations: 20 kB / 0.662 GB, free: 6.727 MB / 442.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4994/0.4999, allocations: 79.29 MB / 0.7394 GB, free: 46.3 MB / 458.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 4.637/5.137, allocations: 2.044 GB / 2.784 GB, free: 245.2 MB / 0.6823 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002427/5.137, allocations: 94.36 kB / 2.784 GB, free: 245.1 MB / 0.6823 GB
Notification: Performance of FrontEnd - DAE generated: time 1.642/6.779, allocations: 0.5638 GB / 3.348 GB, free: 139.6 MB / 0.6823 GB
Notification: Performance of FrontEnd: time 1.513e-06/6.779, allocations: 3.031 kB / 3.348 GB, free: 139.6 MB / 0.6823 GB
Notification: Performance of Transformations before backend: time 0.0001231/6.779, allocations: 24.91 kB / 3.348 GB, free: 139.6 MB / 0.6823 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 212
 * Number of variables: 212
Notification: Performance of Generate backend data structure: time 0.003697/6.783, allocations: 1.365 MB / 3.349 GB, free: 138.9 MB / 0.6823 GB
Notification: Performance of prepare preOptimizeDAE: time 4.565e-05/6.783, allocations: 14.38 kB / 3.349 GB, free: 138.9 MB / 0.6823 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002288/6.785, allocations: 321 kB / 3.349 GB, free: 138.8 MB / 0.6823 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001838/6.787, allocations: 0.8209 MB / 3.35 GB, free: 138.6 MB / 0.6823 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003321/6.787, allocations: 133.7 kB / 3.35 GB, free: 138.6 MB / 0.6823 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002777/6.788, allocations: 91.39 kB / 3.35 GB, free: 138.6 MB / 0.6823 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00184/6.79, allocations: 1.052 MB / 3.351 GB, free: 138.4 MB / 0.6823 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.226e-05/6.79, allocations: 0 / 3.351 GB, free: 138.4 MB / 0.6823 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001076/6.79, allocations: 36.08 kB / 3.352 GB, free: 138.4 MB / 0.6823 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.836e-05/6.79, allocations: 39.36 kB / 3.352 GB, free: 138.4 MB / 0.6823 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001451/6.791, allocations: 0.8054 MB / 3.352 GB, free: 138.4 MB / 0.6823 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.005042/6.796, allocations: 4.057 MB / 3.356 GB, free: 136.5 MB / 0.6823 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0004819/6.797, allocations: 382 kB / 3.357 GB, free: 136.5 MB / 0.6823 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.000248/6.797, allocations: 209 kB / 3.357 GB, free: 136.5 MB / 0.6823 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001548/6.797, allocations: 95.97 kB / 3.357 GB, free: 136.4 MB / 0.6823 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.23e-05/6.797, allocations: 47.02 kB / 3.357 GB, free: 136.3 MB / 0.6823 GB
Notification: Performance of pre-optimization done (n=36): time 2.084e-06/6.797, allocations: 0 / 3.357 GB, free: 136.3 MB / 0.6823 GB
Notification: Performance of matching and sorting (n=38): time 0.002337/6.8, allocations: 1.301 MB / 3.358 GB, free: 135.9 MB / 0.6823 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.206e-05/6.8, allocations: 65.5 kB / 3.358 GB, free: 135.8 MB / 0.6823 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001225/6.801, allocations: 0.9807 MB / 3.359 GB, free: 135.7 MB / 0.6823 GB
Notification: Performance of collectPreVariables (initialization): time 5.803e-05/6.801, allocations: 38.61 kB / 3.359 GB, free: 135.7 MB / 0.6823 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002632/6.801, allocations: 497.3 kB / 3.36 GB, free: 135.5 MB / 0.6823 GB
Notification: Performance of collectInitialBindings (initialization): time 9.869e-05/6.801, allocations: 140.4 kB / 3.36 GB, free: 135.4 MB / 0.6823 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 2.832e-05/6.801, allocations: 11.56 kB / 3.36 GB, free: 135.4 MB / 0.6823 GB
Notification: Performance of setup shared object (initialization): time 5.364e-05/6.801, allocations: 332.1 kB / 3.36 GB, free: 135.1 MB / 0.6823 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004172/6.802, allocations: 231 kB / 3.36 GB, free: 135.1 MB / 0.6823 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004938/6.802, allocations: 432.1 kB / 3.361 GB, free: 134.9 MB / 0.6823 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.002041/6.804, allocations: 1.193 MB / 3.362 GB, free: 134.6 MB / 0.6823 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.258e-06/6.804, allocations: 3.062 kB / 3.362 GB, free: 134.6 MB / 0.6823 GB
Notification: Performance of matching and sorting (n=72) (initialization): time 0.001208/6.806, allocations: 0.7587 MB / 3.363 GB, free: 134.5 MB / 0.6823 GB
Notification: Performance of prepare postOptimizeDAE: time 1.668e-05/6.806, allocations: 7.531 kB / 3.363 GB, free: 134.5 MB / 0.6823 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.117e-06/6.806, allocations: 3.562 kB / 3.363 GB, free: 134.5 MB / 0.6823 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.805e-05/6.806, allocations: 7.312 kB / 3.363 GB, free: 134.5 MB / 0.6823 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005466/6.806, allocations: 151 kB / 3.363 GB, free: 134.5 MB / 0.6823 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.342e-05/6.806, allocations: 11.34 kB / 3.363 GB, free: 134.5 MB / 0.6823 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001071/6.807, allocations: 96.31 kB / 3.363 GB, free: 134.5 MB / 0.6823 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.001e-05/6.807, allocations: 23.09 kB / 3.363 GB, free: 134.5 MB / 0.6823 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: 23
 * 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 (72):
 * Single equations (assignments): 72
 * 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.0004905/6.808, allocations: 315 kB / 3.363 GB, free: 134.4 MB / 0.6823 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001584/6.808, allocations: 102.5 kB / 3.363 GB, free: 134.4 MB / 0.6823 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0001934/6.808, allocations: 98.67 kB / 3.364 GB, free: 134.3 MB / 0.6823 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.621e-06/6.808, allocations: 11.09 kB / 3.364 GB, free: 134.3 MB / 0.6823 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.113e-06/6.808, allocations: 0 / 3.364 GB, free: 134.3 MB / 0.6823 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.000555/6.809, allocations: 0.6751 MB / 3.364 GB, free: 133.9 MB / 0.6823 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002177/6.811, allocations: 1.577 MB / 3.366 GB, free: 133.4 MB / 0.6823 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.308e-06/6.811, allocations: 6.594 kB / 3.366 GB, free: 133.4 MB / 0.6823 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003759/6.811, allocations: 87 kB / 3.366 GB, free: 133.4 MB / 0.6823 GB
Notification: Performance of postOpt tearingSystem (simulation): time 7.454e-06/6.811, allocations: 7.438 kB / 3.366 GB, free: 133.4 MB / 0.6823 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.89e-05/6.811, allocations: 11.47 kB / 3.366 GB, free: 133.4 MB / 0.6823 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.843e-06/6.812, allocations: 8.344 kB / 3.366 GB, free: 133.4 MB / 0.6823 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.292e-06/6.812, allocations: 11.77 kB / 3.366 GB, free: 133.4 MB / 0.6823 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0008847/6.812, allocations: 0.8369 MB / 3.367 GB, free: 133.1 MB / 0.6823 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003753/6.813, allocations: 242.4 kB / 3.367 GB, free: 133 MB / 0.6823 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000154/6.813, allocations: 19.36 kB / 3.367 GB, free: 133 MB / 0.6823 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007211/6.814, allocations: 57.44 kB / 3.367 GB, free: 133 MB / 0.6823 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 5.615e-05/6.814, allocations: 35.33 kB / 3.367 GB, free: 133 MB / 0.6823 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.956e-05/6.814, allocations: 31.95 kB / 3.367 GB, free: 133 MB / 0.6823 GB
Notification: Performance of sorting global known variables: time 0.0009266/6.815, allocations: 0.6982 MB / 3.368 GB, free: 132.9 MB / 0.6823 GB
Notification: Performance of sort global known variables: time 2.1e-07/6.815, allocations: 0 / 3.368 GB, free: 132.9 MB / 0.6823 GB
Notification: Performance of remove unused functions: time 0.001336/6.816, allocations: 388.6 kB / 3.368 GB, free: 132.8 MB / 0.6823 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 17
 * 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 (38):
 * Single equations (assignments): 38
 * 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.002002/6.818, allocations: 1.368 MB / 3.369 GB, free: 132.5 MB / 0.6823 GB
Notification: Performance of simCode: created initialization part: time 0.001035/6.819, allocations: 493.8 kB / 3.37 GB, free: 132.5 MB / 0.6823 GB
Notification: Performance of simCode: created event and clocks part: time 7.624e-06/6.819, allocations: 0 / 3.37 GB, free: 132.5 MB / 0.6823 GB
Notification: Performance of simCode: created simulation system equations: time 0.0002743/6.819, allocations: 192 kB / 3.37 GB, free: 132.5 MB / 0.6823 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003276/6.823, allocations: 0.6025 MB / 3.371 GB, free: 132.5 MB / 0.6823 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003175/6.826, allocations: 2.153 MB / 3.373 GB, free: 131.5 MB / 0.6823 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007193/6.827, allocations: 1.095 MB / 3.374 GB, free: 130.8 MB / 0.6823 GB
Notification: Performance of simCode: alias equations: time 0.001074/6.828, allocations: 244.2 kB / 3.374 GB, free: 130.8 MB / 0.6823 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002282/6.828, allocations: 74.41 kB / 3.374 GB, free: 130.8 MB / 0.6823 GB
Notification: Performance of SimCode: time 6.21e-07/6.828, allocations: 3.828 kB / 3.374 GB, free: 130.8 MB / 0.6823 GB
Notification: Performance of Templates: time 0.04553/6.874, allocations: 35.11 MB / 3.408 GB, free: 127.2 MB / 0.6823 GB
make -j1 -f BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.makefile
(rm -f BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe ; mkfifo BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe >> ../files/BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.sim & ./BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe 2>&1)