Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.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.0.0-master/package.mo", uses=false)
Using package Buildings with version 10.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.0.0-master/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: "" <> buildModelFMU(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,fileNamePrefix="Buildings_latest_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeTraceSubstance",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,fileNamePrefix="Buildings_latest_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeTraceSubstance",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001056/0.001056, allocations: 99.23 kB / 15.05 MB, free: 1.105 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001331/0.001331, allocations: 177.3 kB / 15.94 MB, free: 228 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.536/1.536, allocations: 223.2 MB / 239.9 MB, free: 5.344 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.0.0-master/package.mo): time 2.717/2.717, allocations: 409.2 MB / 0.6838 GB, free: 0.9766 MB / 0.5294 GB
Notification: Performance of FrontEnd - loaded program: time 0.0005507/0.0005508, allocations: 16 kB / 0.8375 GB, free: 6.602 MB / 0.6856 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.9584/0.959, allocations: 146.8 MB / 0.9809 GB, free: 6.367 MB / 0.7325 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance): time 0.2053/1.164, allocations: 158.1 MB / 1.135 GB, free: 7.887 MB / 0.8887 GB
Notification: Performance of NFInst.instExpressions: time 0.009781/1.174, allocations: 5.621 MB / 1.141 GB, free: 2.25 MB / 0.8887 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00162/1.176, allocations: 35.75 kB / 1.141 GB, free: 2.215 MB / 0.8887 GB
Notification: Performance of NFTyping.typeComponents: time 0.002195/1.178, allocations: 0.5599 MB / 1.141 GB, free: 1.652 MB / 0.8887 GB
Notification: Performance of NFTyping.typeBindings: time 0.005971/1.184, allocations: 1.658 MB / 1.143 GB, free: 15.99 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004116/1.188, allocations: 1.191 MB / 1.144 GB, free: 14.81 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.003241/1.191, allocations: 1.465 MB / 1.146 GB, free: 13.35 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001464/1.193, allocations: 0.7076 MB / 1.146 GB, free: 12.63 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002466/1.195, allocations: 0.7397 MB / 1.147 GB, free: 11.89 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001468/1.197, allocations: 0.5058 MB / 1.147 GB, free: 11.38 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003408/1.197, allocations: 43.94 kB / 1.147 GB, free: 11.34 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002898/1.2, allocations: 0.8814 MB / 1.148 GB, free: 10.45 MB / 0.9044 GB
Notification: Performance of NFScalarize.scalarize: time 0.0007728/1.201, allocations: 399 kB / 1.149 GB, free: 10.06 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007977/1.202, allocations: 249.1 kB / 1.149 GB, free: 9.812 MB / 0.9044 GB
Notification: Performance of NFConvertDAE.convert: time 0.002456/1.204, allocations: 1.442 MB / 1.15 GB, free: 8.367 MB / 0.9044 GB
Notification: Performance of FrontEnd - DAE generated: time 3.516e-06/1.204, allocations: 4 kB / 1.15 GB, free: 8.363 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 2.345e-06/1.204, allocations: 0 / 1.15 GB, free: 8.363 MB / 0.9044 GB
Notification: Performance of Transformations before backend: time 6.036e-05/1.204, allocations: 0 / 1.15 GB, free: 8.363 MB / 0.9044 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.002836/1.207, allocations: 1.244 MB / 1.152 GB, free: 7.066 MB / 0.9044 GB
Notification: Performance of prepare preOptimizeDAE: time 5.504e-05/1.207, allocations: 12.03 kB / 1.152 GB, free: 7.055 MB / 0.9044 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0004004/1.208, allocations: 143.2 kB / 1.152 GB, free: 6.906 MB / 0.9044 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002251/1.21, allocations: 342.3 kB / 1.152 GB, free: 6.57 MB / 0.9044 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00174/1.212, allocations: 0.7459 MB / 1.153 GB, free: 5.785 MB / 0.9044 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003757/1.212, allocations: 125.9 kB / 1.153 GB, free: 5.66 MB / 0.9044 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001868/1.212, allocations: 83.95 kB / 1.153 GB, free: 5.578 MB / 0.9044 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001978/1.214, allocations: 1.07 MB / 1.154 GB, free: 4.48 MB / 0.9044 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.657e-05/1.214, allocations: 3.938 kB / 1.154 GB, free: 4.477 MB / 0.9044 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.828e-05/1.214, allocations: 40 kB / 1.154 GB, free: 4.438 MB / 0.9044 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.583e-05/1.214, allocations: 30 kB / 1.154 GB, free: 4.406 MB / 0.9044 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001679/1.216, allocations: 0.847 MB / 1.155 GB, free: 3.562 MB / 0.9044 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.005907/1.222, allocations: 4.062 MB / 1.159 GB, free: 15.23 MB / 0.92 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0005691/1.223, allocations: 370.6 kB / 1.159 GB, free: 14.86 MB / 0.92 GB
Notification: Performance of preOpt evalFunc (simulation): time 5.757e-05/1.223, allocations: 8.578 kB / 1.159 GB, free: 14.86 MB / 0.92 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.435e-05/1.223, allocations: 42.56 kB / 1.159 GB, free: 14.8 MB / 0.92 GB
Notification: Performance of pre-optimization done (n=36): time 5.08e-06/1.223, allocations: 7.984 kB / 1.159 GB, free: 14.8 MB / 0.92 GB
Notification: Performance of matching and sorting (n=38): time 0.002614/1.225, allocations: 1.331 MB / 1.161 GB, free: 13.41 MB / 0.92 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.755e-05/1.225, allocations: 55.41 kB / 1.161 GB, free: 13.34 MB / 0.92 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001306/1.227, allocations: 0.9842 MB / 1.162 GB, free: 12.34 MB / 0.92 GB
Notification: Performance of collectPreVariables (initialization): time 5.387e-05/1.227, allocations: 37.7 kB / 1.162 GB, free: 12.29 MB / 0.92 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003777/1.227, allocations: 491.3 kB / 1.162 GB, free: 11.81 MB / 0.92 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001469/1.227, allocations: 142 kB / 1.162 GB, free: 11.67 MB / 0.92 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 3.155e-05/1.227, allocations: 16 kB / 1.162 GB, free: 11.65 MB / 0.92 GB
Notification: Performance of setup shared object (initialization): time 0.0001306/1.228, allocations: 330 kB / 1.163 GB, free: 11.32 MB / 0.92 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004615/1.228, allocations: 241.2 kB / 1.163 GB, free: 11.09 MB / 0.92 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005646/1.229, allocations: 401.2 kB / 1.163 GB, free: 10.63 MB / 0.92 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.001866/1.23, allocations: 0.7349 MB / 1.164 GB, free: 9.875 MB / 0.92 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.596e-06/1.23, allocations: 0 / 1.164 GB, free: 9.875 MB / 0.92 GB
Notification: Performance of matching and sorting (n=71) (initialization): time 0.001474/1.232, allocations: 0.7576 MB / 1.165 GB, free: 9.109 MB / 0.92 GB
Notification: Performance of prepare postOptimizeDAE: time 1.94e-05/1.232, allocations: 8.25 kB / 1.165 GB, free: 9.102 MB / 0.92 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.356e-05/1.232, allocations: 11.95 kB / 1.165 GB, free: 9.09 MB / 0.92 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.287e-05/1.232, allocations: 11.94 kB / 1.165 GB, free: 9.078 MB / 0.92 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005706/1.233, allocations: 139.8 kB / 1.165 GB, free: 8.941 MB / 0.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.637e-05/1.233, allocations: 11.94 kB / 1.165 GB, free: 8.93 MB / 0.92 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001122/1.234, allocations: 99.77 kB / 1.165 GB, free: 8.832 MB / 0.92 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.956e-05/1.234, allocations: 24 kB / 1.165 GB, free: 8.809 MB / 0.92 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: 22
 * 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 (71):
 * Single equations (assignments): 71
 * 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.0005451/1.234, allocations: 313.5 kB / 1.165 GB, free: 8.492 MB / 0.92 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000152/1.235, allocations: 102.8 kB / 1.165 GB, free: 8.391 MB / 0.92 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0001648/1.235, allocations: 101 kB / 1.165 GB, free: 8.293 MB / 0.92 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.957e-06/1.235, allocations: 11.95 kB / 1.165 GB, free: 8.281 MB / 0.92 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.197e-06/1.235, allocations: 0 / 1.165 GB, free: 8.281 MB / 0.92 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0007013/1.235, allocations: 0.6752 MB / 1.166 GB, free: 7.52 MB / 0.92 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00263/1.238, allocations: 1.578 MB / 1.168 GB, free: 5.836 MB / 0.92 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.784e-06/1.238, allocations: 4 kB / 1.168 GB, free: 5.832 MB / 0.92 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004081/1.239, allocations: 87.83 kB / 1.168 GB, free: 5.746 MB / 0.92 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.093e-05/1.239, allocations: 4 kB / 1.168 GB, free: 5.742 MB / 0.92 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.256e-05/1.239, allocations: 16 kB / 1.168 GB, free: 5.727 MB / 0.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 7.113e-06/1.239, allocations: 4 kB / 1.168 GB, free: 5.723 MB / 0.92 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.135e-06/1.239, allocations: 12.44 kB / 1.168 GB, free: 5.711 MB / 0.92 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001225/1.24, allocations: 1.033 MB / 1.169 GB, free: 4.531 MB / 0.92 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.000478/1.24, allocations: 235.9 kB / 1.169 GB, free: 4.281 MB / 0.92 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001299/1.24, allocations: 19.98 kB / 1.169 GB, free: 4.262 MB / 0.92 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007147/1.241, allocations: 55.81 kB / 1.169 GB, free: 4.207 MB / 0.92 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 5.527e-05/1.241, allocations: 39.8 kB / 1.169 GB, free: 4.168 MB / 0.92 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.746e-05/1.241, allocations: 27.98 kB / 1.169 GB, free: 4.141 MB / 0.92 GB
Notification: Performance of sorting global known variables: time 0.0009673/1.242, allocations: 0.7034 MB / 1.17 GB, free: 3.426 MB / 0.92 GB
Notification: Performance of sort global known variables: time 2.4e-07/1.242, allocations: 0 / 1.17 GB, free: 3.426 MB / 0.92 GB
Notification: Performance of remove unused functions: time 0.0008529/1.243, allocations: 256 kB / 1.17 GB, free: 3.176 MB / 0.92 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 17
 * Number of states: 3 (volDyn.dynBal.medium.Xi[1],volDyn.dynBal.U,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.002101/1.245, allocations: 2.747 MB / 1.173 GB, free: 308 kB / 0.92 GB
Notification: Performance of simCode: created initialization part: time 0.0009742/1.246, allocations: 0.492 MB / 1.173 GB, free: 15.81 MB / 0.9356 GB
Notification: Performance of simCode: created event and clocks part: time 5.53e-06/1.246, allocations: 3.938 kB / 1.173 GB, free: 15.8 MB / 0.9356 GB
Notification: Performance of simCode: created simulation system equations: time 0.0002467/1.247, allocations: 188.8 kB / 1.173 GB, free: 15.62 MB / 0.9356 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002679/1.249, allocations: 0.593 MB / 1.174 GB, free: 15.04 MB / 0.9356 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.009801/1.259, allocations: 8.934 MB / 1.183 GB, free: 5.773 MB / 0.9356 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006924/1.26, allocations: 1.115 MB / 1.184 GB, free: 4.625 MB / 0.9356 GB
Notification: Performance of simCode: alias equations: time 0.0009955/1.261, allocations: 244.5 kB / 1.184 GB, free: 4.383 MB / 0.9356 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004232/1.261, allocations: 339.3 kB / 1.184 GB, free: 4.055 MB / 0.9356 GB
Notification: Performance of SimCode: time 8.92e-07/1.261, allocations: 4 kB / 1.184 GB, free: 4.051 MB / 0.9356 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.05054/1.312, allocations: 27.16 MB / 1.211 GB, free: 9.332 MB / 0.9669 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0002472/1.312, allocations: 240.4 kB / 1.211 GB, free: 9.059 MB / 0.9669 GB
Notification: Performance of buildModelFMU: Generate platform static: time 2.911/4.223, allocations: 64 / 1.211 GB, free: 9.059 MB / 0.9669 GB
(rm -f Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe ; mkfifo Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe ; head -c 1048576 < Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe >> ../files/Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator -r=Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance_res.mat --tempDir=temp_Buildings_latest_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeTraceSubstance_fmu --startTime=0 --stopTime=10 --timeout=50 --tolerance=1e-06 Buildings_latest_Buildings_Fluid_MixingVolumes_Validation_MixingVolumeTraceSubstance.fmu > Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.pipe 2>&1)
Environment - environmentSimulation:
TODO: How the !@#!# did the simulation report success but simulation result Buildings_latest_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance_res.mat does not exist to compare? outputFormat=mat