Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.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 9.1.2-maint.9.1.x/package.mo", uses=false)
Using package Buildings with version 9.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.2-maint.9.1.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.MixingVolume,tolerance=1e-09,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|vol.medium.T|vol1.T|vol2.T|res1.m_flow|res2.m_flow|cheEqu1.y|cheEqu2.y",fileNamePrefix="Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume")
translateModel(Buildings.Fluid.MixingVolumes.Validation.MixingVolume,tolerance=1e-09,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|vol.medium.T|vol1.T|vol2.T|res1.m_flow|res2.m_flow|cheEqu1.y|cheEqu2.y",fileNamePrefix="Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume")
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: 92 kB / 16.02 MB, free: 80 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.001496/0.001496, allocations: 179.3 kB / 16.92 MB, free: 6.652 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.441/1.441, allocations: 223.3 MB / 241 MB, free: 5.277 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.2-maint.9.1.x/package.mo): time 2.456/2.456, allocations: 390.9 MB / 0.667 GB, free: 2.352 MB / 0.5137 GB
Notification: Performance of FrontEnd - loaded program: time 0.0003707/0.0003707, allocations: 12 kB / 0.8166 GB, free: 13.22 MB / 0.67 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7924/0.7928, allocations: 142.4 MB / 0.9557 GB, free: 7.605 MB / 0.7169 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MixingVolumes.Validation.MixingVolume): time 0.2856/1.078, allocations: 237.2 MB / 1.187 GB, free: 1.27 MB / 0.9356 GB
Notification: Performance of NFInst.instExpressions: time 0.02691/1.105, allocations: 15.9 MB / 1.203 GB, free: 1.324 MB / 0.9512 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003685/1.109, allocations: 71.5 kB / 1.203 GB, free: 1.254 MB / 0.9512 GB
Notification: Performance of NFTyping.typeComponents: time 0.003896/1.113, allocations: 1.022 MB / 1.204 GB, free: 232 kB / 0.9512 GB
Notification: Performance of NFTyping.typeBindings: time 0.01233/1.125, allocations: 3.598 MB / 1.207 GB, free: 12.62 MB / 0.9669 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005618/1.131, allocations: 1.623 MB / 1.209 GB, free: 11 MB / 0.9669 GB
Notification: Performance of NFFlatten.flatten: time 0.01077/1.142, allocations: 7.42 MB / 1.216 GB, free: 3.566 MB / 0.9669 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0036/1.145, allocations: 1.383 MB / 1.218 GB, free: 2.148 MB / 0.9669 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007333/1.153, allocations: 2.307 MB / 1.22 GB, free: 15.83 MB / 0.9825 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0047/1.158, allocations: 1.73 MB / 1.222 GB, free: 14.11 MB / 0.9825 GB
Notification: Performance of NFPackage.collectConstants: time 0.0006784/1.158, allocations: 84 kB / 1.222 GB, free: 14.02 MB / 0.9825 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006903/1.165, allocations: 3.133 MB / 1.225 GB, free: 10.88 MB / 0.9825 GB
Notification: Performance of NFScalarize.scalarize: time 0.001411/1.167, allocations: 0.6697 MB / 1.225 GB, free: 10.21 MB / 0.9825 GB
Notification: Performance of NFVerifyModel.verify: time 0.003023/1.17, allocations: 1.067 MB / 1.226 GB, free: 9.133 MB / 0.9825 GB
Notification: Performance of NFConvertDAE.convert: time 0.005158/1.175, allocations: 2.58 MB / 1.229 GB, free: 6.547 MB / 0.9825 GB
Notification: Performance of FrontEnd - DAE generated: time 5.651e-06/1.175, allocations: 0 / 1.229 GB, free: 6.547 MB / 0.9825 GB
Notification: Performance of FrontEnd: time 2.775e-06/1.175, allocations: 0 / 1.229 GB, free: 6.547 MB / 0.9825 GB
Notification: Performance of Transformations before backend: time 0.0001367/1.175, allocations: 0 / 1.229 GB, free: 6.547 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: 363
 * Number of variables: 363
Notification: Performance of Generate backend data structure: time 0.00539/1.18, allocations: 2.102 MB / 1.231 GB, free: 4.391 MB / 0.9825 GB
Notification: Performance of prepare preOptimizeDAE: time 5.012e-05/1.18, allocations: 16.02 kB / 1.231 GB, free: 4.375 MB / 0.9825 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003499/1.184, allocations: 0.5009 MB / 1.232 GB, free: 3.871 MB / 0.9825 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002619/1.187, allocations: 1.277 MB / 1.233 GB, free: 2.539 MB / 0.9825 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001743/1.187, allocations: 182.2 kB / 1.233 GB, free: 2.348 MB / 0.9825 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002762/1.187, allocations: 155.8 kB / 1.233 GB, free: 2.195 MB / 0.9825 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0031/1.19, allocations: 1.753 MB / 1.235 GB, free: 376 kB / 0.9825 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.879e-05/1.19, allocations: 4 kB / 1.235 GB, free: 372 kB / 0.9825 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001405/1.19, allocations: 56 kB / 1.235 GB, free: 316 kB / 0.9825 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.664e-05/1.19, allocations: 62.69 kB / 1.235 GB, free: 252 kB / 0.9825 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002582/1.193, allocations: 1.392 MB / 1.236 GB, free: 14.85 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.01001/1.203, allocations: 8.21 MB / 1.244 GB, free: 5.938 MB / 0.9981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001603/1.205, allocations: 0.9163 MB / 1.245 GB, free: 5.016 MB / 0.9981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009105/1.206, allocations: 0.5458 MB / 1.246 GB, free: 4.465 MB / 0.9981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006427/1.206, allocations: 332.4 kB / 1.246 GB, free: 4.086 MB / 0.9981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.233e-05/1.206, allocations: 51.5 kB / 1.246 GB, free: 4.027 MB / 0.9981 GB
Notification: Performance of pre-optimization done (n=96): time 2.685e-06/1.206, allocations: 4 kB / 1.246 GB, free: 4.023 MB / 0.9981 GB
Notification: Performance of matching and sorting (n=102): time 0.007412/1.214, allocations: 3.169 MB / 1.249 GB, free: 0.8008 MB / 0.9981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.679e-05/1.214, allocations: 72.34 kB / 1.249 GB, free: 0.7188 MB / 0.9981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.6407/1.855, allocations: 1.252 MB / 1.25 GB, free: 39.43 MB / 0.9981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002684/1.855, allocations: 50.05 kB / 1.251 GB, free: 39.4 MB / 0.9981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006268/1.855, allocations: 0.811 MB / 1.251 GB, free: 39.31 MB / 0.9981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003572/1.856, allocations: 368.8 kB / 1.252 GB, free: 39.21 MB / 0.9981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001482/1.856, allocations: 104.2 kB / 1.252 GB, free: 39.2 MB / 0.9981 GB
Notification: Performance of setup shared object (initialization): time 0.0001017/1.856, allocations: 352 kB / 1.252 GB, free: 38.89 MB / 0.9981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009788/1.857, allocations: 447.4 kB / 1.253 GB, free: 38.89 MB / 0.9981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00118/1.858, allocations: 0.8326 MB / 1.253 GB, free: 38.45 MB / 0.9981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002093/1.86, allocations: 1.27 MB / 1.255 GB, free: 38.12 MB / 0.9981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.897e-06/1.86, allocations: 3.406 kB / 1.255 GB, free: 38.12 MB / 0.9981 GB
Notification: Performance of matching and sorting (n=150) (initialization): time 0.002943/1.863, allocations: 1.424 MB / 1.256 GB, free: 38 MB / 0.9981 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001935/1.864, allocations: 370.1 kB / 1.256 GB, free: 37.6 MB / 0.9981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.158e-05/1.864, allocations: 10.77 kB / 1.256 GB, free: 37.6 MB / 0.9981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.514e-05/1.864, allocations: 16.09 kB / 1.256 GB, free: 37.6 MB / 0.9981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001193/1.865, allocations: 284.4 kB / 1.257 GB, free: 37.59 MB / 0.9981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.08e-05/1.865, allocations: 19.89 kB / 1.257 GB, free: 37.59 MB / 0.9981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001832/1.867, allocations: 173.4 kB / 1.257 GB, free: 37.57 MB / 0.9981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001171/1.867, allocations: 54.23 kB / 1.257 GB, free: 37.57 MB / 0.9981 GB
Notification: Performance of prepare postOptimizeDAE: time 1.306e-05/1.867, allocations: 4.578 kB / 1.257 GB, free: 37.57 MB / 0.9981 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.002713/1.87, allocations: 1.242 MB / 1.258 GB, free: 37.56 MB / 0.9981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.057e-05/1.87, allocations: 9.859 kB / 1.258 GB, free: 37.56 MB / 0.9981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.053e-05/1.87, allocations: 18.91 kB / 1.258 GB, free: 37.56 MB / 0.9981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001258/1.871, allocations: 278.6 kB / 1.258 GB, free: 37.56 MB / 0.9981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.291e-05/1.871, allocations: 20.69 kB / 1.258 GB, free: 37.56 MB / 0.9981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002093/1.873, allocations: 176.1 kB / 1.259 GB, free: 37.53 MB / 0.9981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001208/1.873, allocations: 57.88 kB / 1.259 GB, free: 37.53 MB / 0.9981 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 41
 * Number of states: 0 ()
 * Number of discrete variables: 4 (vol.regularFlow[1],vol.regularFlow[2],vol.inFlow[2],vol.inFlow[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (150):
 * Single equations (assignments): 150
 * 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.001259/1.874, allocations: 0.5011 MB / 1.259 GB, free: 37.34 MB / 0.9981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003879/1.875, allocations: 165.8 kB / 1.259 GB, free: 37.25 MB / 0.9981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002817/1.878, allocations: 1.182 MB / 1.26 GB, free: 37.18 MB / 0.9981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.237e-05/1.878, allocations: 10 kB / 1.26 GB, free: 37.18 MB / 0.9981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.237e-06/1.878, allocations: 0.5 kB / 1.26 GB, free: 37.18 MB / 0.9981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002256/1.88, allocations: 1.097 MB / 1.261 GB, free: 36.93 MB / 0.9981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005423/1.885, allocations: 2.811 MB / 1.264 GB, free: 36.54 MB / 0.9981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.192e-06/1.885, allocations: 2.688 kB / 1.264 GB, free: 36.54 MB / 0.9981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00122/1.887, allocations: 250.1 kB / 1.264 GB, free: 36.54 MB / 0.9981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.414e-05/1.887, allocations: 7.422 kB / 1.264 GB, free: 36.54 MB / 0.9981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.851e-05/1.887, allocations: 27 kB / 1.265 GB, free: 36.54 MB / 0.9981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.038e-05/1.887, allocations: 5.625 kB / 1.265 GB, free: 36.54 MB / 0.9981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.041e-06/1.887, allocations: 6.656 kB / 1.265 GB, free: 36.54 MB / 0.9981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003023/1.89, allocations: 1.802 MB / 1.266 GB, free: 36.21 MB / 0.9981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007053/1.891, allocations: 334 kB / 1.267 GB, free: 36.04 MB / 0.9981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001663/1.891, allocations: 28.86 kB / 1.267 GB, free: 36.04 MB / 0.9981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001267/1.892, allocations: 144.2 kB / 1.267 GB, free: 36.02 MB / 0.9981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000157/1.892, allocations: 62.95 kB / 1.267 GB, free: 36.02 MB / 0.9981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.583e-05/1.892, allocations: 45.66 kB / 1.267 GB, free: 36.02 MB / 0.9981 GB
Notification: Performance of sorting global known variables: time 0.00127/1.894, allocations: 0.842 MB / 1.268 GB, free: 35.91 MB / 0.9981 GB
Notification: Performance of sort global known variables: time 2.9e-07/1.894, allocations: 0 / 1.268 GB, free: 35.91 MB / 0.9981 GB
Notification: Performance of remove unused functions: time 0.002559/1.896, allocations: 0.7621 MB / 1.268 GB, free: 35.91 MB / 0.9981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 8
 * Number of states: 9 (vol1.dynBal.medium.Xi[1],vol1.dynBal.U,vol1.dynBal.m,vol.medium.Xi[1],vol.medium.T,vol.m,vol2.dynBal.medium.Xi[1],vol2.dynBal.U,vol2.dynBal.m)
 * 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 (102):
 * Single equations (assignments): 102
 * 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 Backend phase and start with SimCode phase: time 0.0006397/1.897, allocations: 289 kB / 1.269 GB, free: 35.79 MB / 0.9981 GB
Notification: Performance of simCode: created initialization part: time 0.003527/1.9, allocations: 1.758 MB / 1.27 GB, free: 35.75 MB / 0.9981 GB
Notification: Performance of simCode: created event and clocks part: time 6.682e-06/1.9, allocations: 1.75 kB / 1.27 GB, free: 35.75 MB / 0.9981 GB
Notification: Performance of simCode: created simulation system equations: time 0.0009675/1.901, allocations: 0.5311 MB / 1.271 GB, free: 35.75 MB / 0.9981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005227/1.907, allocations: 0.9521 MB / 1.272 GB, free: 35.69 MB / 0.9981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005647/1.912, allocations: 4.446 MB / 1.276 GB, free: 33.43 MB / 0.9981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000982/1.913, allocations: 1.449 MB / 1.278 GB, free: 32.66 MB / 0.9981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005368/1.914, allocations: 136.8 kB / 1.278 GB, free: 32.66 MB / 0.9981 GB
Notification: Performance of SimCode: time 1.162e-06/1.914, allocations: 0 / 1.278 GB, free: 32.66 MB / 0.9981 GB
Notification: Performance of Templates: time 0.04282/1.957, allocations: 26.53 MB / 1.304 GB, free: 30.36 MB / 0.9981 GB
make -j1 -f Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.makefile
(rm -f Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.pipe ; mkfifo Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.pipe ; head -c 1048576 < Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.pipe >> ../files/Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.sim & ./Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.pipe 2>&1)
diffSimulationResults("Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_9.1.x/Buildings_Fluid_MixingVolumes_Validation_MixingVolume.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolume.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
""

Variables in the reference:time,vol.medium.T,vol1.T,vol2.T,res1.m_flow,res2.m_flow,cheEqu1.y,cheEqu2.y

Variables in the result:cheEqu1.y,cheEqu2.y,res1.m_flow,res2.m_flow,time,vol.medium.T,vol1.T,vol2.T