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.00146/0.00146, allocations: 100.6 kB / 16.27 MB, free: 5.977 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.001611/0.001611, allocations: 188.5 kB / 17.19 MB, free: 5.605 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.359/1.359, allocations: 222.9 MB / 240.9 MB, free: 15.19 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.7819/0.7819, allocations: 126.1 MB / 417.1 MB, free: 12.86 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001355/0.001355, allocations: 59.91 kB / 0.495 GB, free: 3.523 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4048/0.4062, allocations: 78.7 MB / 0.5718 GB, free: 31.16 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Sensors.Examples.TraceSubstances): time 0.585/0.9913, allocations: 195.1 MB / 0.7623 GB, free: 1.77 MB / 0.5762 GB
Notification: Performance of NFInst.instExpressions: time 0.0174/1.009, allocations: 14.32 MB / 0.7763 GB, free: 1.617 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001791/1.011, allocations: 41.59 kB / 0.7763 GB, free: 1.613 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.001612/1.012, allocations: 0.4972 MB / 0.7768 GB, free: 1.609 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.004967/1.017, allocations: 1.886 MB / 0.7787 GB, free: 1.531 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002546/1.02, allocations: 0.8595 MB / 0.7795 GB, free: 1.512 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.003652/1.023, allocations: 2.58 MB / 0.782 GB, free: 1.504 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001651/1.025, allocations: 1.116 MB / 0.7831 GB, free: 1.383 MB / 0.5762 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004279/1.029, allocations: 1.595 MB / 0.7847 GB, free: 1.352 MB / 0.5762 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00191/1.031, allocations: 0.9198 MB / 0.7856 GB, free: 1.352 MB / 0.5762 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002557/1.032, allocations: 108.5 kB / 0.7857 GB, free: 1.352 MB / 0.5762 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002644/1.034, allocations: 1.091 MB / 0.7867 GB, free: 1.34 MB / 0.5762 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005445/1.035, allocations: 0.4998 MB / 0.7872 GB, free: 1.332 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 0.00103/1.036, allocations: 0.636 MB / 0.7878 GB, free: 1.332 MB / 0.5762 GB
Notification: Performance of NFConvertDAE.convert: time 0.003182/1.039, allocations: 1.879 MB / 0.7897 GB, free: 1.168 MB / 0.5762 GB
Notification: Performance of FrontEnd - DAE generated: time 4.899e-06/1.039, allocations: 0.9688 kB / 0.7897 GB, free: 1.168 MB / 0.5762 GB
Notification: Performance of FrontEnd: time 2.395e-06/1.039, allocations: 0 / 0.7897 GB, free: 1.168 MB / 0.5762 GB
Notification: Performance of Transformations before backend: time 2.478e-05/1.039, allocations: 0.8438 kB / 0.7897 GB, free: 1.168 MB / 0.5762 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.002482/1.042, allocations: 1.455 MB / 0.7911 GB, free: 16.39 MB / 0.5919 GB
Notification: Performance of prepare preOptimizeDAE: time 4.533e-05/1.042, allocations: 11.28 kB / 0.7911 GB, free: 16.39 MB / 0.5919 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001426/1.043, allocations: 252.2 kB / 0.7913 GB, free: 16.32 MB / 0.5919 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001261/1.044, allocations: 0.7559 MB / 0.7921 GB, free: 16.01 MB / 0.5919 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.781e-05/1.044, allocations: 70.28 kB / 0.7922 GB, free: 16 MB / 0.5919 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001387/1.045, allocations: 96.66 kB / 0.7922 GB, free: 16 MB / 0.5919 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001658/1.046, allocations: 1.17 MB / 0.7934 GB, free: 15.78 MB / 0.5919 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.598e-05/1.046, allocations: 5.75 kB / 0.7934 GB, free: 15.78 MB / 0.5919 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.39e-05/1.046, allocations: 37.38 kB / 0.7934 GB, free: 15.78 MB / 0.5919 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.404e-05/1.046, allocations: 32.81 kB / 0.7935 GB, free: 15.77 MB / 0.5919 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001252/1.048, allocations: 0.9008 MB / 0.7943 GB, free: 15.77 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.005869/1.054, allocations: 5.779 MB / 0.8 GB, free: 11.99 MB / 0.5919 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0008074/1.054, allocations: 0.5361 MB / 0.8005 GB, free: 11.96 MB / 0.5919 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003892/1.055, allocations: 319.7 kB / 0.8008 GB, free: 11.95 MB / 0.5919 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006298/1.055, allocations: 311.2 kB / 0.8011 GB, free: 11.67 MB / 0.5919 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.3e-05/1.055, allocations: 41.88 kB / 0.8011 GB, free: 11.63 MB / 0.5919 GB
Notification: Performance of pre-optimization done (n=49): time 3.266e-06/1.056, allocations: 0 / 0.8011 GB, free: 11.63 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=49): time 0.001558/1.057, allocations: 0.8024 MB / 0.8019 GB, free: 11.54 MB / 0.5919 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.707e-05/1.057, allocations: 57.41 kB / 0.802 GB, free: 11.48 MB / 0.5919 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009576/1.058, allocations: 0.752 MB / 0.8027 GB, free: 11.34 MB / 0.5919 GB
Notification: Performance of collectPreVariables (initialization): time 6.772e-05/1.058, allocations: 39.89 kB / 0.8028 GB, free: 11.31 MB / 0.5919 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003858/1.059, allocations: 0.5029 MB / 0.8032 GB, free: 11.07 MB / 0.5919 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001762/1.059, allocations: 174.5 kB / 0.8034 GB, free: 10.96 MB / 0.5919 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002116/1.059, allocations: 147.1 kB / 0.8036 GB, free: 10.94 MB / 0.5919 GB
Notification: Performance of setup shared object (initialization): time 0.0001335/1.059, allocations: 332 kB / 0.8039 GB, free: 10.64 MB / 0.5919 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003474/1.059, allocations: 245.8 kB / 0.8041 GB, free: 10.64 MB / 0.5919 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007154/1.06, allocations: 0.5525 MB / 0.8046 GB, free: 10.28 MB / 0.5919 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0009636/1.061, allocations: 0.807 MB / 0.8054 GB, free: 9.992 MB / 0.5919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.34e-06/1.061, allocations: 0.9688 kB / 0.8054 GB, free: 9.992 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=86) (initialization): time 0.001189/1.062, allocations: 0.852 MB / 0.8063 GB, free: 9.902 MB / 0.5919 GB
Notification: Performance of prepare postOptimizeDAE: time 2.186e-05/1.062, allocations: 11.12 kB / 0.8063 GB, free: 9.902 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.224e-05/1.062, allocations: 7.906 kB / 0.8063 GB, free: 9.902 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.494e-05/1.062, allocations: 10.66 kB / 0.8063 GB, free: 9.902 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005361/1.063, allocations: 151.6 kB / 0.8064 GB, free: 9.902 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.879e-05/1.063, allocations: 21.86 kB / 0.8065 GB, free: 9.902 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008054/1.064, allocations: 95.34 kB / 0.8066 GB, free: 9.855 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.907e-05/1.064, allocations: 31.78 kB / 0.8066 GB, free: 9.855 MB / 0.5919 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.0005143/1.064, allocations: 326.9 kB / 0.8069 GB, free: 9.738 MB / 0.5919 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001562/1.065, allocations: 93.02 kB / 0.807 GB, free: 9.691 MB / 0.5919 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001004/1.066, allocations: 0.6183 MB / 0.8076 GB, free: 9.629 MB / 0.5919 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.795e-06/1.066, allocations: 5.469 kB / 0.8076 GB, free: 9.629 MB / 0.5919 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.777e-06/1.066, allocations: 0.8125 kB / 0.8076 GB, free: 9.629 MB / 0.5919 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0006568/1.066, allocations: 0.5241 MB / 0.8081 GB, free: 9.551 MB / 0.5919 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002714/1.069, allocations: 1.667 MB / 0.8097 GB, free: 9.172 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.789e-06/1.069, allocations: 0.7812 kB / 0.8097 GB, free: 9.172 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004954/1.07, allocations: 115.1 kB / 0.8098 GB, free: 9.172 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (simulation): time 7.664e-06/1.07, allocations: 2.125 kB / 0.8098 GB, free: 9.172 MB / 0.5919 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.776e-05/1.07, allocations: 18.06 kB / 0.8099 GB, free: 9.172 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 4.238e-06/1.07, allocations: 2.844 kB / 0.8099 GB, free: 9.172 MB / 0.5919 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.685e-06/1.07, allocations: 2.406 kB / 0.8099 GB, free: 9.172 MB / 0.5919 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001255/1.071, allocations: 1.022 MB / 0.8109 GB, free: 8.926 MB / 0.5919 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003539/1.071, allocations: 224.2 kB / 0.8111 GB, free: 8.785 MB / 0.5919 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001074/1.071, allocations: 10.16 kB / 0.8111 GB, free: 8.785 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004527/1.072, allocations: 21.02 kB / 0.8111 GB, free: 8.781 MB / 0.5919 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.978e-05/1.072, allocations: 38.7 kB / 0.8111 GB, free: 8.777 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.683e-05/1.072, allocations: 19.17 kB / 0.8112 GB, free: 8.777 MB / 0.5919 GB
Notification: Performance of sorting global known variables: time 0.0006151/1.073, allocations: 0.5106 MB / 0.8117 GB, free: 8.684 MB / 0.5919 GB
Notification: Performance of sort global known variables: time 1.51e-07/1.073, allocations: 3.344 kB / 0.8117 GB, free: 8.684 MB / 0.5919 GB
Notification: Performance of remove unused functions: time 0.0009319/1.074, allocations: 302.3 kB / 0.812 GB, free: 8.641 MB / 0.5919 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.0004898/1.074, allocations: 211.5 kB / 0.8122 GB, free: 8.539 MB / 0.5919 GB
Notification: Performance of simCode: created initialization part: time 0.001107/1.075, allocations: 0.7091 MB / 0.8128 GB, free: 8.527 MB / 0.5919 GB
Notification: Performance of simCode: created event and clocks part: time 4.589e-06/1.075, allocations: 4.656 kB / 0.8129 GB, free: 8.523 MB / 0.5919 GB
Notification: Performance of simCode: created simulation system equations: time 0.0003419/1.075, allocations: 273.5 kB / 0.8131 GB, free: 8.523 MB / 0.5919 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001991/1.077, allocations: 439.1 kB / 0.8135 GB, free: 8.469 MB / 0.5919 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003206/1.081, allocations: 1.945 MB / 0.8154 GB, free: 7.594 MB / 0.5919 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007615/1.081, allocations: 1.12 MB / 0.8165 GB, free: 6.883 MB / 0.5919 GB
Notification: Performance of simCode: alias equations: time 0.0009214/1.082, allocations: 276.6 kB / 0.8168 GB, free: 6.828 MB / 0.5919 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002024/1.083, allocations: 76.33 kB / 0.8169 GB, free: 6.816 MB / 0.5919 GB
Notification: Performance of SimCode: time 6.71e-07/1.083, allocations: 0 / 0.8169 GB, free: 6.816 MB / 0.5919 GB
Notification: Performance of Templates: time 0.02878/1.111, allocations: 15.39 MB / 0.8319 GB, free: 13.45 MB / 0.6075 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)