Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.1-maint.9.1.x/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 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+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 Buildings with version 9.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.1-maint.9.1.x/package.mo)
Running command: translateModel(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volMFactor.T|vol1.T|vol1.Xi.1.|volMFactor.Xi.1.|vol.T",fileNamePrefix="Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor")
translateModel(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volMFactor.T|vol1.T|vol1.Xi.1.|volMFactor.Xi.1.|vol.T",fileNamePrefix="Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.1-maint.9.1.x/package.mo): time 2.516/2.516, allocations: 390.3 MB / 405.3 MB, free: 13.51 MB / 314.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001235/0.001235, allocations: 177 kB / 498.1 MB, free: 5.75 MB / 394.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.729/1.729, allocations: 223.2 MB / 0.7948 GB, free: 17.93 MB / 0.5573 GB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001036/0.001036, allocations: 94.77 kB / 0.9346 GB, free: 6.199 MB / 0.6823 GB
Notification: Performance of FrontEnd - loaded program: time 0.0004637/0.0004643, allocations: 10.5 kB / 1.084 GB, free: 221.3 MB / 0.823 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3475/0.3479, allocations: 142.3 MB / 1.223 GB, free: 114.2 MB / 0.823 GB
Notification: Performance of FrontEnd - scodeFlatten: time 4.371/4.719, allocations: 1.923 GB / 3.145 GB, free: 90.7 MB / 1.167 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.000287/4.719, allocations: 92.66 kB / 3.145 GB, free: 90.61 MB / 1.167 GB
Notification: Performance of FrontEnd - DAE generated: time 3.024/7.743, allocations: 0.7652 GB / 3.911 GB, free: 227.9 MB / 1.167 GB
Notification: Performance of FrontEnd: time 1.483e-06/7.743, allocations: 0 / 3.911 GB, free: 227.9 MB / 1.167 GB
Notification: Performance of Transformations before backend: time 0.0001606/7.743, allocations: 44.11 kB / 3.911 GB, free: 227.8 MB / 1.167 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 272
 * Number of variables: 272
Notification: Performance of Generate backend data structure: time 0.004864/7.748, allocations: 1.737 MB / 3.912 GB, free: 226.1 MB / 1.167 GB
Notification: Performance of prepare preOptimizeDAE: time 3.997e-05/7.748, allocations: 12.03 kB / 3.912 GB, free: 226.1 MB / 1.167 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003684/7.752, allocations: 477.7 kB / 3.913 GB, free: 225.6 MB / 1.167 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003134/7.755, allocations: 1.114 MB / 3.914 GB, free: 224.4 MB / 1.167 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.132e-05/7.755, allocations: 86.14 kB / 3.914 GB, free: 224.3 MB / 1.167 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003977/7.755, allocations: 111.9 kB / 3.914 GB, free: 224.2 MB / 1.167 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002644/7.758, allocations: 1.219 MB / 3.915 GB, free: 223 MB / 1.167 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.563e-05/7.758, allocations: 0 / 3.915 GB, free: 223 MB / 1.167 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001746/7.758, allocations: 48 kB / 3.915 GB, free: 222.9 MB / 1.167 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.87e-05/7.758, allocations: 42.08 kB / 3.915 GB, free: 222.9 MB / 1.167 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001781/7.76, allocations: 1.023 MB / 3.916 GB, free: 221.9 MB / 1.167 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.006984/7.767, allocations: 4.212 MB / 3.921 GB, free: 217.4 MB / 1.167 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001056/7.768, allocations: 0.7681 MB / 3.921 GB, free: 216.6 MB / 1.167 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000368/7.769, allocations: 292.3 kB / 3.922 GB, free: 216.2 MB / 1.167 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.41e-05/7.769, allocations: 51.78 kB / 3.922 GB, free: 216.2 MB / 1.167 GB
Notification: Performance of pre-optimization done (n=83): time 3.296e-06/7.769, allocations: 0 / 3.922 GB, free: 216.2 MB / 1.167 GB
Notification: Performance of matching and sorting (n=89): time 0.005545/7.774, allocations: 2.807 MB / 3.924 GB, free: 213.3 MB / 1.167 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.142e-05/7.774, allocations: 68.36 kB / 3.924 GB, free: 213.2 MB / 1.167 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001233/7.775, allocations: 0.8565 MB / 3.925 GB, free: 212.3 MB / 1.167 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001175/7.776, allocations: 41.7 kB / 3.925 GB, free: 212.3 MB / 1.167 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003285/7.776, allocations: 0.5396 MB / 3.926 GB, free: 211.8 MB / 1.167 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002438/7.776, allocations: 319.3 kB / 3.926 GB, free: 211.4 MB / 1.167 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.351e-05/7.776, allocations: 23.94 kB / 3.926 GB, free: 211.4 MB / 1.167 GB
Notification: Performance of setup shared object (initialization): time 7.821e-05/7.776, allocations: 341.9 kB / 3.926 GB, free: 211.1 MB / 1.167 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004375/7.777, allocations: 333.8 kB / 3.927 GB, free: 210.7 MB / 1.167 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005112/7.777, allocations: 492.5 kB / 3.927 GB, free: 210.2 MB / 1.167 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: vol1.dynBal.medium.p = vol1.dynBal.p_start (bou.p = vol1.dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: volMFactor.dynBal.medium.p = volMFactor.dynBal.p_start (bou.p = volMFactor.dynBal.p_start)
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 (bou.p = vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.004317/7.782, allocations: 2.229 MB / 3.929 GB, free: 207.9 MB / 1.167 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.085e-06/7.782, allocations: 7.938 kB / 3.929 GB, free: 207.9 MB / 1.167 GB
Notification: Performance of matching and sorting (n=108) (initialization): time 0.001473/7.783, allocations: 0.9997 MB / 3.93 GB, free: 206.9 MB / 1.167 GB
Notification: Performance of prepare postOptimizeDAE: time 2.136e-05/7.783, allocations: 4 kB / 3.93 GB, free: 206.9 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.134e-05/7.783, allocations: 4 kB / 3.93 GB, free: 206.9 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.673e-05/7.783, allocations: 8 kB / 3.93 GB, free: 206.9 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007201/7.784, allocations: 184.1 kB / 3.931 GB, free: 206.7 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.944e-05/7.784, allocations: 8 kB / 3.931 GB, free: 206.7 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001485/7.785, allocations: 83.66 kB / 3.931 GB, free: 206.6 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001033/7.786, allocations: 35.98 kB / 3.931 GB, free: 206.6 MB / 1.167 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization sytem:
         vol1.dynBal.medium.p = vol1.dynBal.p_start
         volMFactor.dynBal.medium.p = volMFactor.dynBal.p_start
         vol.dynBal.medium.p = vol.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 13
 * 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 (108):
 * Single equations (assignments): 108
 * 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.0007564/7.786, allocations: 373.5 kB / 3.931 GB, free: 206.2 MB / 1.167 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003128/7.787, allocations: 130.6 kB / 3.931 GB, free: 206.1 MB / 1.167 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0003683/7.787, allocations: 144.3 kB / 3.931 GB, free: 206 MB / 1.167 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.083e-06/7.787, allocations: 8 kB / 3.931 GB, free: 206 MB / 1.167 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.4e-05/7.787, allocations: 4 kB / 3.931 GB, free: 205.9 MB / 1.167 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001259/7.788, allocations: 1.086 MB / 3.932 GB, free: 204.8 MB / 1.167 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004824/7.793, allocations: 3.036 MB / 3.935 GB, free: 201.6 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.559e-05/7.793, allocations: 12 kB / 3.935 GB, free: 201.6 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006745/7.794, allocations: 171.9 kB / 3.936 GB, free: 201.4 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0003356/7.794, allocations: 97.66 kB / 3.936 GB, free: 201.3 MB / 1.167 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.242e-05/7.794, allocations: 31.98 kB / 3.936 GB, free: 201.3 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001798/7.796, allocations: 2.9 MB / 3.939 GB, free: 198.1 MB / 1.167 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.253e-06/7.796, allocations: 15.92 kB / 3.939 GB, free: 198.1 MB / 1.167 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002282/7.798, allocations: 2.231 MB / 3.941 GB, free: 195.6 MB / 1.167 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001093/7.8, allocations: 307.5 kB / 3.941 GB, free: 195.2 MB / 1.167 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004259/7.8, allocations: 31.98 kB / 3.941 GB, free: 195.2 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001494/7.801, allocations: 71.8 kB / 3.941 GB, free: 195.1 MB / 1.167 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001316/7.802, allocations: 92.14 kB / 3.941 GB, free: 195 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001111/7.802, allocations: 52.3 kB / 3.941 GB, free: 195 MB / 1.167 GB
Notification: Performance of sorting global known variables: time 0.001184/7.803, allocations: 0.6839 MB / 3.942 GB, free: 194.3 MB / 1.167 GB
Notification: Performance of sort global known variables: time 1.91e-07/7.803, allocations: 0 / 3.942 GB, free: 194.3 MB / 1.167 GB
Notification: Performance of remove unused functions: time 0.00226/7.805, allocations: 0.5233 MB / 3.942 GB, free: 193.8 MB / 1.167 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 39
 * Number of states: 6 (vol.dynBal.medium.Xi[1],vol.dynBal.U,volMFactor.dynBal.medium.Xi[1],volMFactor.dynBal.U,vol1.dynBal.medium.Xi[1],vol1.dynBal.U)
 * 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 (85):
 * Single equations (assignments): 83
 * 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: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(1,100.0%) 2,(1,100.0%) 2}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.002564/7.808, allocations: 1.676 MB / 3.944 GB, free: 192 MB / 1.167 GB
Notification: Performance of simCode: created initialization part: time 0.001367/7.809, allocations: 0.8161 MB / 3.945 GB, free: 191.2 MB / 1.167 GB
Notification: Performance of simCode: created event and clocks part: time 7.334e-06/7.809, allocations: 0 / 3.945 GB, free: 191.2 MB / 1.167 GB
Notification: Performance of simCode: created simulation system equations: time 0.0008778/7.81, allocations: 0.669 MB / 3.945 GB, free: 190.5 MB / 1.167 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004893/7.815, allocations: 0.8733 MB / 3.946 GB, free: 189.7 MB / 1.167 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004481/7.819, allocations: 3.244 MB / 3.95 GB, free: 186.3 MB / 1.167 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007583/7.82, allocations: 1.28 MB / 3.951 GB, free: 184.9 MB / 1.167 GB
Notification: Performance of simCode: alias equations: time 0.00175/7.822, allocations: 331 kB / 3.951 GB, free: 184.6 MB / 1.167 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003154/7.822, allocations: 107.4 kB / 3.951 GB, free: 184.5 MB / 1.167 GB
Notification: Performance of SimCode: time 8.62e-07/7.822, allocations: 0 / 3.951 GB, free: 184.5 MB / 1.167 GB
Notification: Performance of Templates: time 0.05756/7.88, allocations: 45.61 MB / 3.996 GB, free: 139.2 MB / 1.167 GB
make -j1 -f Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.makefile
(rm -f Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe ; mkfifo Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe ; head -c 1048576 < Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe >> ../files/Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.sim & ./Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe 2>&1)
diffSimulationResults("Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_9.1.x/Buildings_Fluid_MixingVolumes_Validation_MixingVolumeMFactor.csv","../files/Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches