Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource.conf.json
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/IBPSA 4.0.0-master/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 IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/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)
Running command: "" <> buildModelFMU(IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource,fileNamePrefix="IBPSA_latest_IBPSA_Fluid_Sources_Examples_TraceSubstancesFlowSource",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource,fileNamePrefix="IBPSA_latest_IBPSA_Fluid_Sources_Examples_TraceSubstancesFlowSource",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001235/0.001236, allocations: 183.5 kB / 15.76 MB, free: 6.363 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.5335/0.5335, allocations: 91.98 MB / 108.5 MB, free: 13.05 MB / 90.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.777/1.777, allocations: 223.2 MB / 354.3 MB, free: 12.11 MB / 282.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001264/0.001265, allocations: 100.2 kB / 427.4 MB, free: 3.832 MB / 346.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004265/0.0004265, allocations: 8 kB / 0.4983 GB, free: 42.14 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1899/0.1904, allocations: 72.3 MB / 0.5689 GB, free: 1.773 MB / 394.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 6.208/6.399, allocations: 1.933 GB / 2.502 GB, free: 149.6 MB / 0.6042 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002587/6.399, allocations: 91.45 kB / 2.502 GB, free: 149.6 MB / 0.6042 GB
Notification: Performance of FrontEnd - DAE generated: time 7.836/14.24, allocations: 2.073 GB / 4.575 GB, free: 36.03 MB / 0.6823 GB
Notification: Performance of FrontEnd: time 2.745e-06/14.24, allocations: 0 / 4.575 GB, free: 36.03 MB / 0.6823 GB
Notification: Performance of Transformations before backend: time 0.0005421/14.24, allocations: 91.97 kB / 4.575 GB, free: 35.94 MB / 0.6823 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 693
 * Number of variables: 693
Notification: Performance of Generate backend data structure: time 0.01683/14.25, allocations: 3.984 MB / 4.579 GB, free: 31.89 MB / 0.6823 GB
Notification: Performance of prepare preOptimizeDAE: time 7.989e-05/14.25, allocations: 8.031 kB / 4.579 GB, free: 31.89 MB / 0.6823 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002837/14.26, allocations: 400.1 kB / 4.58 GB, free: 31.49 MB / 0.6823 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008268/14.26, allocations: 0.9279 MB / 4.581 GB, free: 30.56 MB / 0.6823 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01096/14.27, allocations: 2.909 MB / 4.583 GB, free: 27.58 MB / 0.6823 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000476/14.28, allocations: 281.4 kB / 4.584 GB, free: 27.29 MB / 0.6823 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001612/14.28, allocations: 296 kB / 4.584 GB, free: 27 MB / 0.6823 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0118/14.29, allocations: 3.199 MB / 4.587 GB, free: 23.71 MB / 0.6823 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001808/14.29, allocations: 7.906 kB / 4.587 GB, free: 23.71 MB / 0.6823 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001158/14.29, allocations: 116 kB / 4.587 GB, free: 23.59 MB / 0.6823 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001544/14.29, allocations: 105.3 kB / 4.587 GB, free: 23.49 MB / 0.6823 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007069/14.3, allocations: 2.644 MB / 4.59 GB, free: 20.83 MB / 0.6823 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.02015/14.32, allocations: 13.22 MB / 4.603 GB, free: 6.469 MB / 0.6823 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002626/14.32, allocations: 1.673 MB / 4.604 GB, free: 4.805 MB / 0.6823 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005078/14.32, allocations: 329.2 kB / 4.605 GB, free: 4.43 MB / 0.6823 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.506e-05/14.32, allocations: 52.56 kB / 4.605 GB, free: 4.371 MB / 0.6823 GB
Notification: Performance of pre-optimization done (n=182): time 3.787e-06/14.32, allocations: 4 kB / 4.605 GB, free: 4.367 MB / 0.6823 GB
Notification: Performance of matching and sorting (n=182): time 0.005646/14.33, allocations: 2.7 MB / 4.607 GB, free: 1.664 MB / 0.6823 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.803e-05/14.33, allocations: 73.41 kB / 4.607 GB, free: 1.578 MB / 0.6823 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003668/14.33, allocations: 1.712 MB / 4.609 GB, free: 15.85 MB / 0.698 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003559/14.33, allocations: 65.7 kB / 4.609 GB, free: 15.78 MB / 0.698 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001561/14.33, allocations: 1.415 MB / 4.611 GB, free: 14.34 MB / 0.698 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000918/14.33, allocations: 0.6353 MB / 4.611 GB, free: 13.7 MB / 0.698 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001517/14.33, allocations: 44 kB / 4.611 GB, free: 13.66 MB / 0.698 GB
Notification: Performance of setup shared object (initialization): time 0.0002307/14.33, allocations: 385.9 kB / 4.612 GB, free: 13.27 MB / 0.698 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001571/14.34, allocations: 0.7808 MB / 4.612 GB, free: 12.49 MB / 0.698 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002174/14.34, allocations: 1.436 MB / 4.614 GB, free: 10.77 MB / 0.698 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002088/14.34, allocations: 1.048 MB / 4.615 GB, free: 9.719 MB / 0.698 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.747e-05/14.34, allocations: 11.94 kB / 4.615 GB, free: 9.707 MB / 0.698 GB
Notification: Performance of matching and sorting (n=257) (initialization): time 0.005019/14.34, allocations: 2.454 MB / 4.617 GB, free: 7.242 MB / 0.698 GB
Notification: Performance of prepare postOptimizeDAE: time 0.002577/14.35, allocations: 5.523 MB / 4.623 GB, free: 1.211 MB / 0.698 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.978e-05/14.35, allocations: 16 kB / 4.623 GB, free: 1.195 MB / 0.698 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.999e-05/14.35, allocations: 24 kB / 4.623 GB, free: 1.172 MB / 0.698 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002485/14.35, allocations: 0.511 MB / 4.623 GB, free: 0.6602 MB / 0.698 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.659/15.01, allocations: 4.144 MB / 4.627 GB, free: 57.36 MB / 0.698 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005603/15.01, allocations: 405.4 kB / 4.628 GB, free: 57.36 MB / 0.698 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005687/15.01, allocations: 89.55 kB / 4.628 GB, free: 57.36 MB / 0.698 GB
Notification: Performance of prepare postOptimizeDAE: time 2.314e-05/15.02, allocations: 5.109 kB / 4.628 GB, free: 57.36 MB / 0.698 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.005316/15.02, allocations: 2.196 MB / 4.63 GB, free: 57.32 MB / 0.698 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.561e-05/15.02, allocations: 13.38 kB / 4.63 GB, free: 57.32 MB / 0.698 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.036e-05/15.02, allocations: 25.59 kB / 4.63 GB, free: 57.32 MB / 0.698 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002134/15.02, allocations: 477.5 kB / 4.63 GB, free: 57.32 MB / 0.698 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 5.325e-05/15.02, allocations: 31.23 kB / 4.63 GB, free: 57.32 MB / 0.698 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004121/15.03, allocations: 387.7 kB / 4.631 GB, free: 57.32 MB / 0.698 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002052/15.03, allocations: 90.06 kB / 4.631 GB, free: 57.32 MB / 0.698 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 59
 * 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 (257):
 * Single equations (assignments): 250
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 7
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 7 {1,1,1,1,1,1,1}
 * Without analytic Jacobian: 0
Notification: Performance of prepare postOptimizeDAE: time 0.002651/15.03, allocations: 0.8625 MB / 4.632 GB, free: 57.14 MB / 0.698 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008847/15.03, allocations: 270.1 kB / 4.632 GB, free: 57.14 MB / 0.698 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004385/15.03, allocations: 2.097 MB / 4.634 GB, free: 57.01 MB / 0.698 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.696e-05/15.03, allocations: 16.47 kB / 4.634 GB, free: 57.01 MB / 0.698 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.042e-05/15.04, allocations: 1.156 kB / 4.634 GB, free: 57.01 MB / 0.698 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003027/15.04, allocations: 1.878 MB / 4.636 GB, free: 56.71 MB / 0.698 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01071/15.05, allocations: 5.142 MB / 4.641 GB, free: 56.23 MB / 0.698 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.165e-05/15.05, allocations: 5.656 kB / 4.641 GB, free: 56.23 MB / 0.698 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002374/15.05, allocations: 0.4933 MB / 4.641 GB, free: 56.23 MB / 0.698 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.843e-05/15.05, allocations: 10.88 kB / 4.641 GB, free: 56.23 MB / 0.698 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001402/15.05, allocations: 51.42 kB / 4.641 GB, free: 56.23 MB / 0.698 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001408/15.05, allocations: 4.127 MB / 4.645 GB, free: 52.14 MB / 0.698 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.539e-06/15.05, allocations: 8.375 kB / 4.645 GB, free: 52.14 MB / 0.698 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005986/15.06, allocations: 3.412 MB / 4.649 GB, free: 51.41 MB / 0.698 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002281/15.06, allocations: 0.5734 MB / 4.649 GB, free: 51.18 MB / 0.698 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003731/15.06, allocations: 42.84 kB / 4.649 GB, free: 51.18 MB / 0.698 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00229/15.06, allocations: 253.6 kB / 4.65 GB, free: 51.17 MB / 0.698 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002497/15.06, allocations: 113 kB / 4.65 GB, free: 51.17 MB / 0.698 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001646/15.06, allocations: 72.48 kB / 4.65 GB, free: 51.17 MB / 0.698 GB
Notification: Performance of sorting global known variables: time 0.002809/15.07, allocations: 1.312 MB / 4.651 GB, free: 51.03 MB / 0.698 GB
Notification: Performance of sort global known variables: time 8.12e-07/15.07, allocations: 0 / 4.651 GB, free: 51.03 MB / 0.698 GB
Notification: Performance of remove unused functions: time 0.00852/15.08, allocations: 2.143 MB / 4.653 GB, free: 50.9 MB / 0.698 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 13
 * Number of states: 20 (vol.dynBal.medium.Xi[1],vol.dynBal.U,vol.dynBal.m,vol.dynBal.mC[1],vol1.dynBal.medium.Xi[1],vol1.dynBal.U,vol1.dynBal.m,vol1.dynBal.mC[1],vol2.dynBal.medium.Xi[1],vol2.dynBal.U,vol2.dynBal.m,vol2.dynBal.mC[1],vol3.dynBal.medium.Xi[1],vol3.dynBal.U,vol3.dynBal.m,vol3.dynBal.mC[1],vol4.dynBal.medium.Xi[1],vol4.dynBal.U,vol4.dynBal.m,vol4.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 (182):
 * Single equations (assignments): 175
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 7
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 7 {1,1,1,1,1,1,1}
 * Without analytic Jacobian: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.01454/15.09, allocations: 9.364 MB / 4.662 GB, free: 46.79 MB / 0.698 GB
Notification: Performance of simCode: created initialization part: time 0.006562/15.1, allocations: 3.203 MB / 4.665 GB, free: 46.74 MB / 0.698 GB
Notification: Performance of simCode: created event and clocks part: time 1.362e-05/15.1, allocations: 5.281 kB / 4.665 GB, free: 46.74 MB / 0.698 GB
Notification: Performance of simCode: created simulation system equations: time 0.001801/15.1, allocations: 1.001 MB / 4.666 GB, free: 46.73 MB / 0.698 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.007931/15.11, allocations: 1.577 MB / 4.668 GB, free: 46.69 MB / 0.698 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01749/15.12, allocations: 19.34 MB / 4.687 GB, free: 32.7 MB / 0.698 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001873/15.13, allocations: 2.257 MB / 4.689 GB, free: 31.95 MB / 0.698 GB
Notification: Performance of simCode: alias equations: time 0.004493/15.13, allocations: 1.097 MB / 4.69 GB, free: 31.92 MB / 0.698 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001677/15.13, allocations: 1.267 MB / 4.691 GB, free: 31.91 MB / 0.698 GB
Notification: Performance of SimCode: time 1.132e-06/15.13, allocations: 0 / 4.691 GB, free: 31.91 MB / 0.698 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.2375/15.37, allocations: 135.7 MB / 4.824 GB, free: 20.46 MB / 0.698 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001904/15.37, allocations: 255.2 kB / 4.824 GB, free: 20.13 MB / 0.698 GB
Notification: Performance of buildModelFMU: Generate platform static: time 6.908/22.28, allocations: 0 / 4.824 GB, free: 20.13 MB / 0.698 GB
(rm -f IBPSA_latest_IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator  --tempDir=temp_IBPSA_latest_IBPSA_Fluid_Sources_Examples_TraceSubstancesFlowSource_fmu --startTime=0 --stopTime=600 --timeout=50 --tolerance=1e-06 IBPSA_latest_IBPSA_Fluid_Sources_Examples_TraceSubstancesFlowSource.fmu > IBPSA_latest_IBPSA.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe 2>&1)