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: "" <> buildModelFMU(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor,fileNamePrefix="Buildings_9_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeMFactor",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor,fileNamePrefix="Buildings_9_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeMFactor",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.1-maint.9.1.x/package.mo): time 2.333/2.333, allocations: 390.3 MB / 405.3 MB, free: 13.42 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.00132/0.00132, allocations: 189.5 kB / 498.1 MB, free: 5.691 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.675/1.675, allocations: 223.1 MB / 0.7948 GB, free: 17.86 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.0009313/0.0009312, allocations: 96.77 kB / 0.9346 GB, free: 6.172 MB / 0.6823 GB
Notification: Performance of FrontEnd - loaded program: time 0.0003963/0.0003959, allocations: 7.844 kB / 1.084 GB, free: 221.4 MB / 0.823 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3092/0.3097, allocations: 142.3 MB / 1.223 GB, free: 114.1 MB / 0.823 GB
Notification: Performance of FrontEnd - scodeFlatten: time 4.138/4.448, allocations: 1.923 GB / 3.145 GB, free: 64.84 MB / 1.167 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0003954/4.448, allocations: 91.61 kB / 3.146 GB, free: 64.8 MB / 1.167 GB
Notification: Performance of FrontEnd - DAE generated: time 2.946/7.394, allocations: 0.7652 GB / 3.911 GB, free: 210 MB / 1.167 GB
Notification: Performance of FrontEnd: time 2.084e-06/7.394, allocations: 0 / 3.911 GB, free: 210 MB / 1.167 GB
Notification: Performance of Transformations before backend: time 9.414e-05/7.394, allocations: 43.94 kB / 3.911 GB, free: 210 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.003565/7.397, allocations: 1.741 MB / 3.912 GB, free: 208.2 MB / 1.167 GB
Notification: Performance of prepare preOptimizeDAE: time 5.342e-05/7.398, allocations: 12.03 kB / 3.912 GB, free: 208.2 MB / 1.167 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0002478/7.398, allocations: 168.7 kB / 3.913 GB, free: 208 MB / 1.167 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003132/7.401, allocations: 489.6 kB / 3.913 GB, free: 207.5 MB / 1.167 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002236/7.403, allocations: 1.105 MB / 3.914 GB, free: 206.4 MB / 1.167 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.904e-05/7.403, allocations: 82.14 kB / 3.914 GB, free: 206.3 MB / 1.167 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001402/7.403, allocations: 112 kB / 3.914 GB, free: 206.2 MB / 1.167 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001766/7.405, allocations: 1.225 MB / 3.916 GB, free: 204.9 MB / 1.167 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.957e-05/7.405, allocations: 3.906 kB / 3.916 GB, free: 204.9 MB / 1.167 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.735e-05/7.405, allocations: 44 kB / 3.916 GB, free: 204.9 MB / 1.167 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.641e-05/7.405, allocations: 42.14 kB / 3.916 GB, free: 204.8 MB / 1.167 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001361/7.407, allocations: 1.027 MB / 3.917 GB, free: 203.8 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.005157/7.412, allocations: 4.203 MB / 3.921 GB, free: 199.3 MB / 1.167 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0009487/7.413, allocations: 0.776 MB / 3.921 GB, free: 198.5 MB / 1.167 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003528/7.413, allocations: 296.3 kB / 3.922 GB, free: 198.2 MB / 1.167 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.52e-05/7.413, allocations: 51.52 kB / 3.922 GB, free: 198.1 MB / 1.167 GB
Notification: Performance of pre-optimization done (n=83): time 3.697e-06/7.413, allocations: 3.984 kB / 3.922 GB, free: 198.1 MB / 1.167 GB
Notification: Performance of matching and sorting (n=89): time 0.004799/7.418, allocations: 2.803 MB / 3.925 GB, free: 195.2 MB / 1.167 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.765e-05/7.418, allocations: 64.34 kB / 3.925 GB, free: 195.1 MB / 1.167 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009198/7.419, allocations: 0.8488 MB / 3.925 GB, free: 194.3 MB / 1.167 GB
Notification: Performance of collectPreVariables (initialization): time 6.952e-05/7.419, allocations: 45.7 kB / 3.925 GB, free: 194.2 MB / 1.167 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002715/7.419, allocations: 0.5435 MB / 3.926 GB, free: 193.7 MB / 1.167 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000175/7.42, allocations: 315.4 kB / 3.926 GB, free: 193.4 MB / 1.167 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 3.698e-05/7.42, allocations: 16 kB / 3.926 GB, free: 193.4 MB / 1.167 GB
Notification: Performance of setup shared object (initialization): time 6.335e-05/7.42, allocations: 341.9 kB / 3.927 GB, free: 193 MB / 1.167 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004081/7.42, allocations: 329.8 kB / 3.927 GB, free: 192.7 MB / 1.167 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004819/7.421, allocations: 492.5 kB / 3.927 GB, free: 192.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.004235/7.425, allocations: 2.235 MB / 3.93 GB, free: 189.9 MB / 1.167 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.098e-06/7.425, allocations: 0 / 3.93 GB, free: 189.9 MB / 1.167 GB
Notification: Performance of matching and sorting (n=108) (initialization): time 0.00135/7.426, allocations: 0.9996 MB / 3.931 GB, free: 188.9 MB / 1.167 GB
Notification: Performance of prepare postOptimizeDAE: time 1.924e-05/7.426, allocations: 4.375 kB / 3.931 GB, free: 188.9 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.788e-06/7.426, allocations: 15.88 kB / 3.931 GB, free: 188.9 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.45e-05/7.426, allocations: 8 kB / 3.931 GB, free: 188.9 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006961/7.427, allocations: 175.8 kB / 3.931 GB, free: 188.7 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.233e-05/7.427, allocations: 15.88 kB / 3.931 GB, free: 188.7 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001301/7.428, allocations: 83.7 kB / 3.931 GB, free: 188.6 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.168e-05/7.428, allocations: 35.94 kB / 3.931 GB, free: 188.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.0006775/7.429, allocations: 387.4 kB / 3.931 GB, free: 188.2 MB / 1.167 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001699/7.429, allocations: 122.6 kB / 3.931 GB, free: 188 MB / 1.167 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0002664/7.43, allocations: 148.3 kB / 3.932 GB, free: 187.9 MB / 1.167 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.983e-06/7.43, allocations: 8 kB / 3.932 GB, free: 187.9 MB / 1.167 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.534e-05/7.43, allocations: 0 / 3.932 GB, free: 187.9 MB / 1.167 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001202/7.431, allocations: 1.086 MB / 3.933 GB, free: 186.7 MB / 1.167 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004068/7.435, allocations: 3.044 MB / 3.936 GB, free: 183.5 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.566e-06/7.435, allocations: 8 kB / 3.936 GB, free: 183.5 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006548/7.436, allocations: 167.7 kB / 3.936 GB, free: 183.3 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0002944/7.436, allocations: 93.66 kB / 3.936 GB, free: 183.2 MB / 1.167 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.448e-05/7.436, allocations: 32 kB / 3.936 GB, free: 183.2 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001708/7.438, allocations: 2.9 MB / 3.939 GB, free: 180 MB / 1.167 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.552e-06/7.438, allocations: 11.94 kB / 3.939 GB, free: 180 MB / 1.167 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002047/7.44, allocations: 2.239 MB / 3.941 GB, free: 177.5 MB / 1.167 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006892/7.44, allocations: 311.5 kB / 3.941 GB, free: 177.2 MB / 1.167 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001386/7.441, allocations: 31.98 kB / 3.941 GB, free: 177.1 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009931/7.442, allocations: 71.8 kB / 3.941 GB, free: 177.1 MB / 1.167 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001097/7.442, allocations: 88.14 kB / 3.941 GB, free: 177 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.861e-05/7.442, allocations: 43.98 kB / 3.941 GB, free: 176.9 MB / 1.167 GB
Notification: Performance of sorting global known variables: time 0.0008386/7.443, allocations: 0.6916 MB / 3.942 GB, free: 176.2 MB / 1.167 GB
Notification: Performance of sort global known variables: time 1.41e-07/7.443, allocations: 0 / 3.942 GB, free: 176.2 MB / 1.167 GB
Notification: Performance of remove unused functions: time 0.001723/7.444, allocations: 0.5195 MB / 3.943 GB, free: 175.7 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.004657/7.449, allocations: 6.91 MB / 3.949 GB, free: 168.4 MB / 1.167 GB
Notification: Performance of simCode: created initialization part: time 0.001211/7.45, allocations: 0.8129 MB / 3.95 GB, free: 167.6 MB / 1.167 GB
Notification: Performance of simCode: created event and clocks part: time 8.366e-06/7.45, allocations: 4 kB / 3.95 GB, free: 167.6 MB / 1.167 GB
Notification: Performance of simCode: created simulation system equations: time 0.000771/7.451, allocations: 0.6618 MB / 3.951 GB, free: 166.9 MB / 1.167 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003756/7.455, allocations: 0.8689 MB / 3.952 GB, free: 166.1 MB / 1.167 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.009711/7.465, allocations: 9.625 MB / 3.961 GB, free: 156 MB / 1.167 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006306/7.465, allocations: 1.28 MB / 3.962 GB, free: 154.7 MB / 1.167 GB
Notification: Performance of simCode: alias equations: time 0.001385/7.467, allocations: 343 kB / 3.963 GB, free: 154.3 MB / 1.167 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005338/7.467, allocations: 479.2 kB / 3.963 GB, free: 153.9 MB / 1.167 GB
Notification: Performance of SimCode: time 6.91e-07/7.467, allocations: 0 / 3.963 GB, free: 153.9 MB / 1.167 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.08282/7.55, allocations: 62.75 MB / 4.024 GB, free: 91.48 MB / 1.167 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001425/7.55, allocations: 246.6 kB / 4.025 GB, free: 91.15 MB / 1.167 GB
Notification: Performance of buildModelFMU: Generate platform static: time 4.149/11.7, allocations: 8 kB / 4.025 GB, free: 91.14 MB / 1.167 GB
(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 & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator -r=Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor_res.mat --tempDir=temp_Buildings_9_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeMFactor_fmu --startTime=0 --stopTime=100 --timeout=50 --tolerance=1e-06 Buildings_9_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeMFactor.fmu > Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe 2>&1)
TODO: How the !@#!# did the simulation report success but simulation result Buildings_9_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeMFactor_res.mat does not exist to compare? outputFormat=mat