Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances.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/IBPSA 4.0.0-master/package.mo", uses=false)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.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: translateModel(IBPSA.Fluid.Sensors.Examples.TraceSubstances,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances")
translateModel(IBPSA.Fluid.Sensors.Examples.TraceSubstances,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001092/0.001092, allocations: 92.95 kB / 16.02 MB, free: 84 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.001508/0.001508, allocations: 177.4 kB / 16.92 MB, free: 6.645 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.372/1.372, allocations: 223.3 MB / 241 MB, free: 5.324 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.6942/0.6942, allocations: 115.5 MB / 407.6 MB, free: 2.684 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003316/0.0003316, allocations: 12.5 kB / 495.9 MB, free: 6.34 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.149/0.1493, allocations: 77.53 MB / 0.56 GB, free: 9.02 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Sensors.Examples.TraceSubstances): time 0.503/0.6523, allocations: 190.3 MB / 0.7458 GB, free: 8.434 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.01874/0.6711, allocations: 14.2 MB / 0.7597 GB, free: 10.19 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001296/0.6725, allocations: 39.75 kB / 0.7597 GB, free: 10.15 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.001424/0.6739, allocations: 409.7 kB / 0.7601 GB, free: 9.746 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.0051/0.679, allocations: 1.774 MB / 0.7618 GB, free: 7.965 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00225/0.6813, allocations: 0.7672 MB / 0.7626 GB, free: 7.203 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.003994/0.6853, allocations: 3.626 MB / 0.7661 GB, free: 3.566 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001249/0.6865, allocations: 0.9156 MB / 0.767 GB, free: 2.633 MB / 0.5762 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004407/0.691, allocations: 1.409 MB / 0.7684 GB, free: 1.234 MB / 0.5762 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001645/0.6926, allocations: 0.9164 MB / 0.7693 GB, free: 328 kB / 0.5762 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001157/0.6927, allocations: 48 kB / 0.7693 GB, free: 280 kB / 0.5762 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002349/0.6951, allocations: 1.247 MB / 0.7705 GB, free: 15.02 MB / 0.5919 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004305/0.6955, allocations: 430.8 kB / 0.771 GB, free: 14.6 MB / 0.5919 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006855/0.6962, allocations: 0.5886 MB / 0.7715 GB, free: 14 MB / 0.5919 GB
Notification: Performance of NFConvertDAE.convert: time 0.002066/0.6983, allocations: 1.892 MB / 0.7734 GB, free: 12.11 MB / 0.5919 GB
Notification: Performance of FrontEnd - DAE generated: time 3.817e-06/0.6983, allocations: 0 / 0.7734 GB, free: 12.11 MB / 0.5919 GB
Notification: Performance of FrontEnd: time 1.944e-06/0.6983, allocations: 0 / 0.7734 GB, free: 12.11 MB / 0.5919 GB
Notification: Performance of Transformations before backend: time 1.278e-05/0.6984, allocations: 0 / 0.7734 GB, free: 12.11 MB / 0.5919 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 226
 * Number of variables: 226
Notification: Performance of Generate backend data structure: time 0.001883/0.7002, allocations: 1.233 MB / 0.7746 GB, free: 10.82 MB / 0.5919 GB
Notification: Performance of prepare preOptimizeDAE: time 3.848e-05/0.7003, allocations: 12.03 kB / 0.7746 GB, free: 10.81 MB / 0.5919 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001321/0.7016, allocations: 246.3 kB / 0.7748 GB, free: 10.57 MB / 0.5919 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001164/0.7028, allocations: 0.7569 MB / 0.7756 GB, free: 9.766 MB / 0.5919 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 6.507e-05/0.7029, allocations: 73.98 kB / 0.7756 GB, free: 9.691 MB / 0.5919 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001457/0.703, allocations: 95.98 kB / 0.7757 GB, free: 9.598 MB / 0.5919 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001695/0.7047, allocations: 1.173 MB / 0.7769 GB, free: 8.355 MB / 0.5919 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.731e-05/0.7048, allocations: 3.938 kB / 0.7769 GB, free: 8.352 MB / 0.5919 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.589e-05/0.7049, allocations: 36 kB / 0.7769 GB, free: 8.316 MB / 0.5919 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.744e-05/0.7049, allocations: 41.92 kB / 0.777 GB, free: 8.273 MB / 0.5919 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00139/0.7063, allocations: 0.9016 MB / 0.7778 GB, free: 7.375 MB / 0.5919 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.005531/0.7119, allocations: 5.735 MB / 0.7834 GB, free: 1.184 MB / 0.5919 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0008266/0.7127, allocations: 0.5315 MB / 0.784 GB, free: 0.6445 MB / 0.5919 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004232/0.7131, allocations: 322.6 kB / 0.7843 GB, free: 336 kB / 0.5919 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000616/0.7138, allocations: 331.7 kB / 0.7846 GB, free: 15.95 MB / 0.6075 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.859e-05/0.7138, allocations: 39 kB / 0.7846 GB, free: 15.9 MB / 0.6075 GB
Notification: Performance of pre-optimization done (n=49): time 2.675e-06/0.7138, allocations: 0 / 0.7846 GB, free: 15.9 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=49): time 0.001477/0.7153, allocations: 0.8025 MB / 0.7854 GB, free: 15.09 MB / 0.6075 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.813e-05/0.7153, allocations: 59.84 kB / 0.7855 GB, free: 15.02 MB / 0.6075 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008833/0.7162, allocations: 0.751 MB / 0.7862 GB, free: 14.26 MB / 0.6075 GB
Notification: Performance of collectPreVariables (initialization): time 5.181e-05/0.7163, allocations: 37.7 kB / 0.7862 GB, free: 14.22 MB / 0.6075 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003689/0.7167, allocations: 0.5026 MB / 0.7867 GB, free: 13.71 MB / 0.6075 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000145/0.7168, allocations: 181.4 kB / 0.7869 GB, free: 13.53 MB / 0.6075 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 8.68e-05/0.7169, allocations: 61.69 kB / 0.7869 GB, free: 13.46 MB / 0.6075 GB
Notification: Performance of setup shared object (initialization): time 0.000114/0.717, allocations: 333.9 kB / 0.7873 GB, free: 13.13 MB / 0.6075 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003665/0.7174, allocations: 253.8 kB / 0.7875 GB, free: 12.88 MB / 0.6075 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0006092/0.718, allocations: 0.5546 MB / 0.788 GB, free: 12.2 MB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0009989/0.719, allocations: 0.7956 MB / 0.7888 GB, free: 11.29 MB / 0.6075 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.713e-06/0.719, allocations: 7.938 kB / 0.7888 GB, free: 11.29 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=86) (initialization): time 0.001239/0.7203, allocations: 0.8428 MB / 0.7897 GB, free: 10.43 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 2.473e-05/0.7203, allocations: 10.22 kB / 0.7897 GB, free: 10.43 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.371e-05/0.7203, allocations: 12 kB / 0.7897 GB, free: 10.41 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.387e-05/0.7204, allocations: 12 kB / 0.7897 GB, free: 10.4 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005465/0.7209, allocations: 155.7 kB / 0.7898 GB, free: 10.25 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.118e-05/0.721, allocations: 15.94 kB / 0.7899 GB, free: 10.23 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009136/0.7219, allocations: 147 kB / 0.79 GB, free: 10.09 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.385e-05/0.7219, allocations: 35.98 kB / 0.79 GB, free: 10.05 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 36
 * 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 (86):
 * Single equations (assignments): 86
 * 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.0004363/0.7224, allocations: 333.4 kB / 0.7903 GB, free: 9.719 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001182/0.7225, allocations: 82.97 kB / 0.7904 GB, free: 9.637 MB / 0.6075 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0009935/0.7235, allocations: 0.6219 MB / 0.791 GB, free: 9.004 MB / 0.6075 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.921e-06/0.7235, allocations: 8 kB / 0.791 GB, free: 8.996 MB / 0.6075 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.32e-06/0.7235, allocations: 4 kB / 0.791 GB, free: 8.992 MB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0007068/0.7243, allocations: 0.5172 MB / 0.7915 GB, free: 8.461 MB / 0.6075 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002351/0.7266, allocations: 1.671 MB / 0.7932 GB, free: 6.711 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.659e-06/0.7266, allocations: 4 kB / 0.7932 GB, free: 6.707 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004963/0.7271, allocations: 111.8 kB / 0.7933 GB, free: 6.598 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (simulation): time 6.772e-06/0.7271, allocations: 4 kB / 0.7933 GB, free: 6.594 MB / 0.6075 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.825e-05/0.7272, allocations: 19.98 kB / 0.7933 GB, free: 6.574 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.082e-06/0.7272, allocations: 7.984 kB / 0.7933 GB, free: 6.566 MB / 0.6075 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.054e-06/0.7272, allocations: 0 / 0.7933 GB, free: 6.566 MB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001304/0.7285, allocations: 1.015 MB / 0.7943 GB, free: 5.512 MB / 0.6075 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003045/0.7288, allocations: 227.9 kB / 0.7945 GB, free: 5.27 MB / 0.6075 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.307e-05/0.7289, allocations: 16 kB / 0.7945 GB, free: 5.254 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005563/0.7295, allocations: 63.88 kB / 0.7946 GB, free: 5.191 MB / 0.6075 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.327e-05/0.7296, allocations: 27.97 kB / 0.7946 GB, free: 5.164 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.573e-05/0.7296, allocations: 23.98 kB / 0.7947 GB, free: 5.141 MB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.000589/0.7302, allocations: 0.5107 MB / 0.7952 GB, free: 4.633 MB / 0.6075 GB
Notification: Performance of sort global known variables: time 1.512e-06/0.7302, allocations: 0 / 0.7952 GB, free: 4.633 MB / 0.6075 GB
Notification: Performance of remove unused functions: time 0.0008822/0.7311, allocations: 307.2 kB / 0.7954 GB, free: 4.332 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 8
 * Number of states: 4 (vol.dynBal.medium.Xi[1],vol.dynBal.U,vol.dynBal.m,vol.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 (49):
 * Single equations (assignments): 49
 * 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.0005694/0.7317, allocations: 206.5 kB / 0.7956 GB, free: 4.141 MB / 0.6075 GB
Notification: Performance of simCode: created initialization part: time 0.001081/0.7328, allocations: 0.612 MB / 0.7962 GB, free: 3.531 MB / 0.6075 GB
Notification: Performance of simCode: created event and clocks part: time 7.545e-06/0.7328, allocations: 0 / 0.7962 GB, free: 3.531 MB / 0.6075 GB
Notification: Performance of simCode: created simulation system equations: time 0.0003647/0.7332, allocations: 267.7 kB / 0.7965 GB, free: 3.27 MB / 0.6075 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002482/0.7356, allocations: 443.3 kB / 0.7969 GB, free: 2.848 MB / 0.6075 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003807/0.7395, allocations: 2.403 MB / 0.7993 GB, free: 400 kB / 0.6075 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007778/0.7403, allocations: 1.118 MB / 0.8004 GB, free: 15.25 MB / 0.6231 GB
Notification: Performance of simCode: alias equations: time 0.0008451/0.7411, allocations: 275.1 kB / 0.8006 GB, free: 14.97 MB / 0.6231 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001774/0.7413, allocations: 71.42 kB / 0.8007 GB, free: 14.9 MB / 0.6231 GB
Notification: Performance of SimCode: time 1.172e-06/0.7413, allocations: 0 / 0.8007 GB, free: 14.9 MB / 0.6231 GB
Notification: Performance of Templates: time 0.02835/0.7697, allocations: 14.85 MB / 0.8152 GB, free: 288 kB / 0.6231 GB
make -j1 -f IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances.makefile
(rm -f IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances.sim & ./IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.Sensors.Examples.TraceSubstances.pipe 2>&1)