Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001003/0.001003, allocations: 87.58 kB / 20.45 MB, free: 4.121 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001533/0.001533, allocations: 161.4 kB / 23.82 MB, free: 0.7539 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 0.9344/0.9344, allocations: 177.1 MB / 204.2 MB, free: 5.645 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo): time 1.818/1.818, allocations: 359 MB / 0.6052 GB, free: 10.98 MB / 0.573 GB
"
[Timeout remaining time 178]
Using package Buildings with version 11.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|volDyn.ports.[0-9,]+..m_flow|gain.y|volDyn.C.[0-9,]+.|volSte.C.[0-9,]+.",fileNamePrefix="Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate")
translateModel(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|volDyn.ports.[0-9,]+..m_flow|gain.y|volDyn.C.[0-9,]+.|volSte.C.[0-9,]+.",fileNamePrefix="Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.753e-06/1.753e-06, allocations: 0 / 0.7871 GB, free: 12.45 MB / 0.7448 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.077e-05/2.252e-05, allocations: 2.312 kB / 0.7871 GB, free: 12.45 MB / 0.7448 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate): time 0.1427/0.1427, allocations: 151 MB / 0.9346 GB, free: 4.965 MB / 0.8855 GB
Notification: Performance of NFInst.instExpressions: time 0.004749/0.1474, allocations: 3.831 MB / 0.9383 GB, free: 1.129 MB / 0.8855 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004909/0.1479, allocations: 55.5 kB / 0.9384 GB, free: 1.074 MB / 0.8855 GB
Notification: Performance of NFTyping.typeComponents: time 0.000549/0.1485, allocations: 277.9 kB / 0.9386 GB, free: 0.8008 MB / 0.8855 GB
Notification: Performance of NFTyping.typeBindings: time 0.001519/0.15, allocations: 1.072 MB / 0.9397 GB, free: 15.72 MB / 0.9011 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001341/0.1513, allocations: 0.9897 MB / 0.9407 GB, free: 14.73 MB / 0.9011 GB
Notification: Performance of NFFlatten.flatten: time 0.00191/0.1533, allocations: 2.584 MB / 0.9432 GB, free: 12.14 MB / 0.9011 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007434/0.154, allocations: 0.775 MB / 0.9439 GB, free: 11.34 MB / 0.9011 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001652/0.1557, allocations: 1.432 MB / 0.9453 GB, free: 9.938 MB / 0.9011 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0008372/0.1565, allocations: 0.8063 MB / 0.9461 GB, free: 9.129 MB / 0.9011 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001174/0.1566, allocations: 104 kB / 0.9462 GB, free: 9.027 MB / 0.9011 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.000758/0.1574, allocations: 0.6509 MB / 0.9469 GB, free: 8.375 MB / 0.9011 GB
Notification: Performance of NFScalarize.scalarize: time 0.0003262/0.1577, allocations: 0.5376 MB / 0.9474 GB, free: 7.836 MB / 0.9011 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003445/0.158, allocations: 463 kB / 0.9478 GB, free: 7.383 MB / 0.9011 GB
Notification: Performance of NFConvertDAE.convert: time 0.001271/0.1593, allocations: 1.39 MB / 0.9492 GB, free: 5.988 MB / 0.9011 GB
Notification: Performance of FrontEnd - DAE generated: time 4.749e-06/0.1593, allocations: 4 kB / 0.9492 GB, free: 5.984 MB / 0.9011 GB
Notification: Performance of FrontEnd: time 1.452e-06/0.1593, allocations: 0 / 0.9492 GB, free: 5.984 MB / 0.9011 GB
Notification: Performance of Transformations before backend: time 1.208e-05/0.1593, allocations: 0 / 0.9492 GB, free: 5.984 MB / 0.9011 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 216
 * Number of variables: 216
Notification: Performance of Generate backend data structure: time 0.001487/0.1608, allocations: 1.534 MB / 0.9507 GB, free: 4.41 MB / 0.9011 GB
Notification: Performance of prepare preOptimizeDAE: time 3.269e-05/0.1608, allocations: 12.03 kB / 0.9507 GB, free: 4.398 MB / 0.9011 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00135/0.1622, allocations: 0.4915 MB / 0.9512 GB, free: 3.906 MB / 0.9011 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007826/0.163, allocations: 0.7657 MB / 0.9519 GB, free: 3.113 MB / 0.9011 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.127e-05/0.163, allocations: 70.12 kB / 0.952 GB, free: 3.047 MB / 0.9011 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 9.937e-05/0.1631, allocations: 95.94 kB / 0.9521 GB, free: 2.953 MB / 0.9011 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001085/0.1642, allocations: 1.135 MB / 0.9532 GB, free: 1.695 MB / 0.9011 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.79e-05/0.1642, allocations: 0 / 0.9532 GB, free: 1.695 MB / 0.9011 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 6.326e-05/0.1643, allocations: 40 kB / 0.9532 GB, free: 1.656 MB / 0.9011 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.604e-05/0.1643, allocations: 33.98 kB / 0.9533 GB, free: 1.625 MB / 0.9011 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0006152/0.1649, allocations: 395.6 kB / 0.9536 GB, free: 1.234 MB / 0.9011 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.00323/0.1682, allocations: 2.96 MB / 0.9565 GB, free: 14.19 MB / 0.9167 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0004822/0.1686, allocations: 327 kB / 0.9568 GB, free: 13.86 MB / 0.9167 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001804/0.1688, allocations: 135.5 kB / 0.957 GB, free: 13.73 MB / 0.9167 GB
Notification: Performance of preOpt evalFunc (simulation): time 9.627e-05/0.1689, allocations: 23.98 kB / 0.957 GB, free: 13.7 MB / 0.9167 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.705e-05/0.169, allocations: 53.38 kB / 0.957 GB, free: 13.64 MB / 0.9167 GB
Notification: Performance of pre-optimization done (n=43): time 1.723e-06/0.169, allocations: 0 / 0.957 GB, free: 13.64 MB / 0.9167 GB
Notification: Performance of matching and sorting (n=45): time 0.001607/0.1706, allocations: 1.018 MB / 0.958 GB, free: 12.59 MB / 0.9167 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.375e-05/0.1706, allocations: 55.41 kB / 0.9581 GB, free: 12.52 MB / 0.9167 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0004625/0.171, allocations: 0.64 MB / 0.9587 GB, free: 11.87 MB / 0.9167 GB
Notification: Performance of collectPreVariables (initialization): time 4.757e-05/0.1711, allocations: 37.7 kB / 0.9587 GB, free: 11.83 MB / 0.9167 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002434/0.1713, allocations: 0.5573 MB / 0.9593 GB, free: 11.26 MB / 0.9167 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001026/0.1714, allocations: 170.5 kB / 0.9595 GB, free: 11.09 MB / 0.9167 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001407/0.1716, allocations: 123.9 kB / 0.9596 GB, free: 10.96 MB / 0.9167 GB
Notification: Performance of setup shared object (initialization): time 0.0002276/0.1718, allocations: 0.5625 MB / 0.9601 GB, free: 10.39 MB / 0.9167 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001766/0.172, allocations: 93.25 kB / 0.9602 GB, free: 10.3 MB / 0.9167 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002799/0.1723, allocations: 345 kB / 0.9605 GB, free: 9.879 MB / 0.9167 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.0008113/0.1731, allocations: 0.6258 MB / 0.9612 GB, free: 9.18 MB / 0.9167 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.214e-06/0.1731, allocations: 0 / 0.9612 GB, free: 9.18 MB / 0.9167 GB
Notification: Performance of matching and sorting (n=73) (initialization): time 0.0006133/0.1737, allocations: 492.7 kB / 0.9616 GB, free: 8.695 MB / 0.9167 GB
Notification: Performance of prepare postOptimizeDAE: time 1.523e-05/0.1737, allocations: 8 kB / 0.9616 GB, free: 8.688 MB / 0.9167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.034e-06/0.1737, allocations: 12.38 kB / 0.9616 GB, free: 8.676 MB / 0.9167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.395e-05/0.1737, allocations: 8 kB / 0.9616 GB, free: 8.668 MB / 0.9167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004207/0.1741, allocations: 163.8 kB / 0.9618 GB, free: 8.508 MB / 0.9167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.194e-05/0.1742, allocations: 11.98 kB / 0.9618 GB, free: 8.496 MB / 0.9167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003397/0.1745, allocations: 43.97 kB / 0.9619 GB, free: 8.453 MB / 0.9167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 3.959e-05/0.1745, allocations: 27.98 kB / 0.9619 GB, free: 8.426 MB / 0.9167 GB
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization system:
         volDyn.dynBal.medium.p = volDyn.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 17
 * 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 (73):
 * Single equations (assignments): 73
 * 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.0001738/0.1747, allocations: 198.4 kB / 0.9621 GB, free: 8.223 MB / 0.9167 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001056/0.1748, allocations: 95.48 kB / 0.9622 GB, free: 8.129 MB / 0.9167 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0001004/0.1749, allocations: 91.06 kB / 0.9623 GB, free: 8.031 MB / 0.9167 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.727e-06/0.1749, allocations: 4 kB / 0.9623 GB, free: 8.027 MB / 0.9167 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.817e-06/0.1749, allocations: 4 kB / 0.9623 GB, free: 8.023 MB / 0.9167 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0004579/0.1754, allocations: 0.5734 MB / 0.9628 GB, free: 7.363 MB / 0.9167 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001693/0.1771, allocations: 1.391 MB / 0.9642 GB, free: 5.875 MB / 0.9167 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.707e-06/0.1771, allocations: 4 kB / 0.9642 GB, free: 5.871 MB / 0.9167 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003634/0.1774, allocations: 140.2 kB / 0.9643 GB, free: 5.734 MB / 0.9167 GB
Notification: Performance of postOpt tearingSystem (simulation): time 5.119e-06/0.1774, allocations: 4 kB / 0.9643 GB, free: 5.73 MB / 0.9167 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.121e-05/0.1775, allocations: 19.97 kB / 0.9643 GB, free: 5.711 MB / 0.9167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 3.677e-06/0.1775, allocations: 4 kB / 0.9643 GB, free: 5.707 MB / 0.9167 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.287e-06/0.1775, allocations: 11.88 kB / 0.9644 GB, free: 5.695 MB / 0.9167 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0007342/0.1782, allocations: 0.7141 MB / 0.9651 GB, free: 4.902 MB / 0.9167 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001964/0.1784, allocations: 159.4 kB / 0.9652 GB, free: 4.746 MB / 0.9167 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.101e-05/0.1785, allocations: 12 kB / 0.9652 GB, free: 4.734 MB / 0.9167 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003275/0.1788, allocations: 43.95 kB / 0.9653 GB, free: 4.691 MB / 0.9167 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.477e-05/0.1789, allocations: 43.81 kB / 0.9653 GB, free: 4.648 MB / 0.9167 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.275e-05/0.1789, allocations: 31.97 kB / 0.9653 GB, free: 4.617 MB / 0.9167 GB
Notification: Performance of sorting global known variables: time 0.000294/0.1792, allocations: 415 kB / 0.9657 GB, free: 4.219 MB / 0.9167 GB
Notification: Performance of sort global known variables: time 7e-08/0.1792, allocations: 0 / 0.9657 GB, free: 4.219 MB / 0.9167 GB
Notification: Performance of remove unused functions: time 0.0004728/0.1797, allocations: 271.9 kB / 0.966 GB, free: 3.953 MB / 0.9167 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * 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 (45):
 * Single equations (assignments): 45
 * 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.0002509/0.1799, allocations: 155.1 kB / 0.9661 GB, free: 3.793 MB / 0.9167 GB
Notification: Performance of simCode: created initialization part: time 0.0004844/0.1804, allocations: 335.3 kB / 0.9665 GB, free: 3.461 MB / 0.9167 GB
Notification: Performance of simCode: created event and clocks part: time 2.535e-06/0.1804, allocations: 0 / 0.9665 GB, free: 3.461 MB / 0.9167 GB
Notification: Performance of simCode: created simulation system equations: time 0.0002616/0.1807, allocations: 158 kB / 0.9666 GB, free: 3.305 MB / 0.9167 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001072/0.1818, allocations: 486.2 kB / 0.9671 GB, free: 2.84 MB / 0.9167 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002344/0.1841, allocations: 1.812 MB / 0.9688 GB, free: 0.9336 MB / 0.9167 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0001513/0.1842, allocations: 243.1 kB / 0.9691 GB, free: 0.6758 MB / 0.9167 GB
Notification: Performance of simCode: alias equations: time 0.0004116/0.1847, allocations: 190.3 kB / 0.9692 GB, free: 496 kB / 0.9167 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001755/0.1848, allocations: 72.47 kB / 0.9693 GB, free: 424 kB / 0.9167 GB
Notification: Performance of SimCode: time 8.22e-07/0.1848, allocations: 0 / 0.9693 GB, free: 424 kB / 0.9167 GB
Notification: Performance of Templates: time 0.01948/0.2043, allocations: 19.29 MB / 0.9882 GB, free: 13.58 MB / 0.948 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.makefile [Timeout 300]
(rm -f Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.pipe ; mkfifo Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.pipe ; head -c 1048576 < Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.pipe >> ../files/Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.sim & ./Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate -abortSlowSimulation -alarm=240  -emit_protected -lv LOG_STATS > Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.pipe 2>&1) [Timeout 240]
diffSimulationResults("Buildings_11_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_11.x/Buildings_Fluid_MixingVolumes_Validation_MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.csv","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 5.5876996400766075]