Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.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 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.0-maint.10.0.x/package.mo", uses=false)
Using package Buildings with version 10.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.0-maint.10.0.x/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 ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/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_10_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_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.00107/0.00107, allocations: 99.58 kB / 16.02 MB, free: 76 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001269/0.001269, allocations: 182.4 kB / 16.92 MB, free: 6.918 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.439/1.439, allocations: 223.3 MB / 241 MB, free: 5.352 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.0-maint.10.0.x/package.mo): time 2.643/2.643, allocations: 437.8 MB / 0.7128 GB, free: 6.992 MB / 0.5606 GB
Notification: Performance of FrontEnd - loaded program: time 0.0004434/0.0004437, allocations: 12 kB / 0.8735 GB, free: 3.332 MB / 0.7169 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7328/0.7332, allocations: 153.8 MB / 1.024 GB, free: 4 kB / 0.7637 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor): time 0.2319/0.9652, allocations: 204.9 MB / 1.224 GB, free: 2.383 MB / 0.9669 GB
Notification: Performance of NFInst.instExpressions: time 0.01697/0.9822, allocations: 9.855 MB / 1.233 GB, free: 8.504 MB / 0.9825 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002682/0.9849, allocations: 51.62 kB / 1.233 GB, free: 8.453 MB / 0.9825 GB
Notification: Performance of NFTyping.typeComponents: time 0.002497/0.9875, allocations: 0.6606 MB / 1.234 GB, free: 7.789 MB / 0.9825 GB
Notification: Performance of NFTyping.typeBindings: time 0.008269/0.9958, allocations: 2.65 MB / 1.237 GB, free: 5.129 MB / 0.9825 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004149/0.9999, allocations: 1.26 MB / 1.238 GB, free: 3.875 MB / 0.9825 GB
Notification: Performance of NFFlatten.flatten: time 0.7311/1.731, allocations: 5.632 MB / 1.243 GB, free: 4.879 MB / 0.9825 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002538/1.734, allocations: 1.083 MB / 1.244 GB, free: 4.75 MB / 0.9825 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003293/1.737, allocations: 1.124 MB / 1.246 GB, free: 4.746 MB / 0.9825 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00403/1.741, allocations: 1.227 MB / 1.247 GB, free: 4.742 MB / 0.9825 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003181/1.741, allocations: 56.16 kB / 1.247 GB, free: 4.742 MB / 0.9825 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006942/1.748, allocations: 2.741 MB / 1.249 GB, free: 4.742 MB / 0.9825 GB
Notification: Performance of NFScalarize.scalarize: time 0.001114/1.749, allocations: 0.5192 MB / 1.25 GB, free: 4.738 MB / 0.9825 GB
Notification: Performance of NFVerifyModel.verify: time 0.001667/1.751, allocations: 0.772 MB / 1.251 GB, free: 4.707 MB / 0.9825 GB
Notification: Performance of NFConvertDAE.convert: time 0.00469/1.756, allocations: 2.198 MB / 1.253 GB, free: 4.641 MB / 0.9825 GB
Notification: Performance of FrontEnd - DAE generated: time 7.334e-06/1.756, allocations: 5.125 kB / 1.253 GB, free: 4.641 MB / 0.9825 GB
Notification: Performance of FrontEnd: time 2.735e-06/1.756, allocations: 0.5312 kB / 1.253 GB, free: 4.641 MB / 0.9825 GB
Notification: Performance of Transformations before backend: time 5.198e-05/1.756, allocations: 0 / 1.253 GB, free: 4.641 MB / 0.9825 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.004507/1.76, allocations: 1.588 MB / 1.254 GB, free: 19.79 MB / 0.9981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.637e-05/1.76, allocations: 13.77 kB / 1.254 GB, free: 19.78 MB / 0.9981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003493/1.764, allocations: 496 kB / 1.255 GB, free: 19.69 MB / 0.9981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002113/1.766, allocations: 1.061 MB / 1.256 GB, free: 19.33 MB / 0.9981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.104e-05/1.766, allocations: 82.7 kB / 1.256 GB, free: 19.32 MB / 0.9981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001919/1.766, allocations: 114.9 kB / 1.256 GB, free: 19.32 MB / 0.9981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002409/1.769, allocations: 1.157 MB / 1.257 GB, free: 19.24 MB / 0.9981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.072e-05/1.769, allocations: 5.766 kB / 1.257 GB, free: 19.24 MB / 0.9981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001163/1.769, allocations: 45.86 kB / 1.257 GB, free: 19.24 MB / 0.9981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.729e-05/1.769, allocations: 39.86 kB / 1.257 GB, free: 19.24 MB / 0.9981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001909/1.771, allocations: 1.075 MB / 1.258 GB, free: 19.22 MB / 0.9981 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.006484/1.777, allocations: 4.217 MB / 1.263 GB, free: 17.59 MB / 0.9981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00122/1.779, allocations: 0.788 MB / 1.263 GB, free: 17.56 MB / 0.9981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006846/1.779, allocations: 484.2 kB / 1.264 GB, free: 17.54 MB / 0.9981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004263/1.78, allocations: 295.8 kB / 1.264 GB, free: 17.25 MB / 0.9981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.185e-05/1.78, allocations: 44.22 kB / 1.264 GB, free: 17.21 MB / 0.9981 GB
Notification: Performance of pre-optimization done (n=83): time 2.986e-06/1.78, allocations: 1.281 kB / 1.264 GB, free: 17.21 MB / 0.9981 GB
Notification: Performance of matching and sorting (n=89): time 0.006177/1.786, allocations: 2.845 MB / 1.267 GB, free: 16.48 MB / 0.9981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.026e-05/1.786, allocations: 66.03 kB / 1.267 GB, free: 16.42 MB / 0.9981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001372/1.787, allocations: 0.9805 MB / 1.268 GB, free: 16.27 MB / 0.9981 GB
Notification: Performance of collectPreVariables (initialization): time 9.925e-05/1.788, allocations: 46.95 kB / 1.268 GB, free: 16.23 MB / 0.9981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003713/1.788, allocations: 0.5377 MB / 1.268 GB, free: 16.04 MB / 0.9981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002799/1.788, allocations: 314.6 kB / 1.269 GB, free: 15.84 MB / 0.9981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001031/1.788, allocations: 77.66 kB / 1.269 GB, free: 15.82 MB / 0.9981 GB
Notification: Performance of setup shared object (initialization): time 0.0001377/1.788, allocations: 336.2 kB / 1.269 GB, free: 15.52 MB / 0.9981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005139/1.789, allocations: 340.5 kB / 1.269 GB, free: 15.52 MB / 0.9981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0006222/1.79, allocations: 484.2 kB / 1.27 GB, free: 15.41 MB / 0.9981 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 (bou.p = vol.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: vol1.dynBal.medium.p = vol1.dynBal.p_start (bou.p = vol1.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.005571/1.795, allocations: 3.058 MB / 1.273 GB, free: 14.94 MB / 0.9981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.274e-06/1.795, allocations: 0 / 1.273 GB, free: 14.94 MB / 0.9981 GB
Notification: Performance of matching and sorting (n=108) (initialization): time 0.001667/1.797, allocations: 1.013 MB / 1.274 GB, free: 14.82 MB / 0.9981 GB
Notification: Performance of prepare postOptimizeDAE: time 2.135e-05/1.797, allocations: 6.125 kB / 1.274 GB, free: 14.82 MB / 0.9981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.092e-05/1.797, allocations: 3 kB / 1.274 GB, free: 14.82 MB / 0.9981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.287e-05/1.797, allocations: 9.438 kB / 1.274 GB, free: 14.82 MB / 0.9981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006957/1.798, allocations: 180.1 kB / 1.274 GB, free: 14.81 MB / 0.9981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.54e-05/1.798, allocations: 16.52 kB / 1.274 GB, free: 14.81 MB / 0.9981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001447/1.799, allocations: 77.86 kB / 1.274 GB, free: 14.8 MB / 0.9981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.666e-05/1.799, allocations: 33.41 kB / 1.274 GB, free: 14.8 MB / 0.9981 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization sytem:
         vol.dynBal.medium.p = vol.dynBal.p_start
         volMFactor.dynBal.medium.p = volMFactor.dynBal.p_start
         vol1.dynBal.medium.p = vol1.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 (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0007673/1.8, allocations: 383.2 kB / 1.275 GB, free: 14.68 MB / 0.9981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002053/1.8, allocations: 130.1 kB / 1.275 GB, free: 14.61 MB / 0.9981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.000295/1.801, allocations: 144.8 kB / 1.275 GB, free: 14.6 MB / 0.9981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.824e-06/1.801, allocations: 8.781 kB / 1.275 GB, free: 14.6 MB / 0.9981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.306e-05/1.801, allocations: 4.578 kB / 1.275 GB, free: 14.6 MB / 0.9981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001378/1.802, allocations: 1.093 MB / 1.276 GB, free: 14.21 MB / 0.9981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004406/1.806, allocations: 3.046 MB / 1.279 GB, free: 13.54 MB / 0.9981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.061e-05/1.806, allocations: 11.09 kB / 1.279 GB, free: 13.54 MB / 0.9981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006486/1.807, allocations: 167.1 kB / 1.279 GB, free: 13.54 MB / 0.9981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0003191/1.807, allocations: 96.03 kB / 1.279 GB, free: 13.52 MB / 0.9981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.217e-05/1.807, allocations: 34.92 kB / 1.279 GB, free: 13.52 MB / 0.9981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002182/1.81, allocations: 2.873 MB / 1.282 GB, free: 11 MB / 0.9981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.388e-06/1.81, allocations: 11.56 kB / 1.282 GB, free: 11 MB / 0.9981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002239/1.812, allocations: 1.749 MB / 1.284 GB, free: 10.43 MB / 0.9981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007448/1.813, allocations: 303.9 kB / 1.284 GB, free: 10.27 MB / 0.9981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001631/1.813, allocations: 32.64 kB / 1.284 GB, free: 10.27 MB / 0.9981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001034/1.814, allocations: 77.59 kB / 1.284 GB, free: 10.26 MB / 0.9981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00012/1.814, allocations: 89.34 kB / 1.284 GB, free: 10.25 MB / 0.9981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.06e-05/1.814, allocations: 44.39 kB / 1.284 GB, free: 10.25 MB / 0.9981 GB
Notification: Performance of sorting global known variables: time 0.0008848/1.815, allocations: 0.6963 MB / 1.285 GB, free: 10.14 MB / 0.9981 GB
Notification: Performance of sort global known variables: time 4e-07/1.815, allocations: 0 / 1.285 GB, free: 10.14 MB / 0.9981 GB
Notification: Performance of remove unused functions: time 0.00127/1.816, allocations: 374.8 kB / 1.285 GB, free: 10.14 MB / 0.9981 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 (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0005251/1.817, allocations: 241 kB / 1.286 GB, free: 10.02 MB / 0.9981 GB
Notification: Performance of simCode: created initialization part: time 0.00122/1.818, allocations: 0.8267 MB / 1.286 GB, free: 10 MB / 0.9981 GB
Notification: Performance of simCode: created event and clocks part: time 5.66e-06/1.818, allocations: 4.797 kB / 1.286 GB, free: 10 MB / 0.9981 GB
Notification: Performance of simCode: created simulation system equations: time 0.0008169/1.819, allocations: 0.6732 MB / 1.287 GB, free: 9.766 MB / 0.9981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005548/1.824, allocations: 0.8909 MB / 1.288 GB, free: 9.688 MB / 0.9981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004711/1.829, allocations: 3.447 MB / 1.291 GB, free: 7.781 MB / 0.9981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008202/1.83, allocations: 1.25 MB / 1.292 GB, free: 7.051 MB / 0.9981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003836/1.83, allocations: 107.2 kB / 1.293 GB, free: 7.043 MB / 0.9981 GB
Notification: Performance of SimCode: time 5.51e-07/1.83, allocations: 0 / 1.293 GB, free: 7.043 MB / 0.9981 GB
Notification: Performance of Templates: time 0.04006/1.871, allocations: 26.9 MB / 1.319 GB, free: 15.63 MB / 1.014 GB
make -j1 -f Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.makefile
(rm -f Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe ; mkfifo Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe ; head -c 1048576 < Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe >> ../files/Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.sim & ./Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe 2>&1)
diffSimulationResults("Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_10.0.x/Buildings_Fluid_MixingVolumes_Validation_MixingVolumeMFactor.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_10_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches