Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.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 9.1.3-maint.9.1.x/package.mo", uses=false)
Using package Buildings with version 9.1.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.x/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(Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|C.C|C1.C|vol4.C.1.|C2.C|C3.C",fileNamePrefix="Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource")
translateModel(Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|C.C|C1.C|vol4.C.1.|C2.C|C3.C",fileNamePrefix="Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001487/0.001487, allocations: 111.2 kB / 16.42 MB, free: 6.465 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.001399/0.001399, allocations: 190.7 kB / 17.35 MB, free: 5.715 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.266/1.266, allocations: 222.9 MB / 241.1 MB, free: 15.13 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.x/package.mo): time 2.152/2.152, allocations: 390.3 MB / 0.6655 GB, free: 0.6094 MB / 0.5294 GB
Notification: Performance of FrontEnd - loaded program: time 0.001272/0.001272, allocations: 67.89 kB / 0.9516 GB, free: 7.637 MB / 0.7325 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7022/0.7034, allocations: 140.4 MB / 1.089 GB, free: 5.516 MB / 0.8575 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource): time 0.3093/1.013, allocations: 305.1 MB / 1.387 GB, free: 12.09 MB / 1.123 GB
Notification: Performance of NFInst.instExpressions: time 0.03185/1.045, allocations: 25.83 MB / 1.412 GB, free: 2.191 MB / 1.139 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004475/1.049, allocations: 127.2 kB / 1.412 GB, free: 2.066 MB / 1.139 GB
Notification: Performance of NFTyping.typeComponents: time 0.004776/1.054, allocations: 1.86 MB / 1.414 GB, free: 200 kB / 1.139 GB
Notification: Performance of NFTyping.typeBindings: time 0.0151/1.069, allocations: 6.192 MB / 1.42 GB, free: 9.98 MB / 1.154 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006831/1.076, allocations: 2.643 MB / 1.422 GB, free: 7.352 MB / 1.154 GB
Notification: Performance of NFFlatten.flatten: time 0.01086/1.087, allocations: 8.927 MB / 1.431 GB, free: 14.4 MB / 1.17 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.005294/1.092, allocations: 3.624 MB / 1.435 GB, free: 10.7 MB / 1.17 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01012/1.102, allocations: 4.274 MB / 1.439 GB, free: 6.449 MB / 1.17 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005158/1.108, allocations: 2.964 MB / 1.442 GB, free: 3.488 MB / 1.17 GB
Notification: Performance of NFPackage.collectConstants: time 0.001227/1.109, allocations: 360 kB / 1.442 GB, free: 3.137 MB / 1.17 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.009274/1.118, allocations: 4.618 MB / 1.447 GB, free: 14.51 MB / 1.186 GB
Notification: Performance of NFScalarize.scalarize: time 0.002307/1.12, allocations: 1.644 MB / 1.448 GB, free: 12.86 MB / 1.186 GB
Notification: Performance of NFVerifyModel.verify: time 0.004029/1.124, allocations: 2.135 MB / 1.45 GB, free: 10.71 MB / 1.186 GB
Notification: Performance of NFConvertDAE.convert: time 0.01086/1.135, allocations: 6.739 MB / 1.457 GB, free: 3.957 MB / 1.186 GB
Notification: Performance of FrontEnd - DAE generated: time 3.827e-06/1.135, allocations: 0 / 1.457 GB, free: 3.957 MB / 1.186 GB
Notification: Performance of FrontEnd: time 1.162e-06/1.135, allocations: 0 / 1.457 GB, free: 3.957 MB / 1.186 GB
Notification: Performance of Transformations before backend: time 0.000191/1.135, allocations: 0 / 1.457 GB, free: 3.957 MB / 1.186 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.009177/1.145, allocations: 4.18 MB / 1.461 GB, free: 15.7 MB / 1.201 GB
Notification: Performance of prepare preOptimizeDAE: time 4.122e-05/1.145, allocations: 8.031 kB / 1.461 GB, free: 15.7 MB / 1.201 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005999/1.151, allocations: 0.9283 MB / 1.462 GB, free: 14.76 MB / 1.201 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004961/1.156, allocations: 2.396 MB / 1.464 GB, free: 12.29 MB / 1.201 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002479/1.156, allocations: 277.2 kB / 1.464 GB, free: 12 MB / 1.201 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005937/1.157, allocations: 295.9 kB / 1.465 GB, free: 11.71 MB / 1.201 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006017/1.163, allocations: 3.359 MB / 1.468 GB, free: 8.27 MB / 1.201 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.455e-05/1.163, allocations: 0 / 1.468 GB, free: 8.27 MB / 1.201 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003033/1.163, allocations: 116 kB / 1.468 GB, free: 8.156 MB / 1.201 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001/1.163, allocations: 105.3 kB / 1.468 GB, free: 8.051 MB / 1.201 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004595/1.168, allocations: 2.8 MB / 1.471 GB, free: 5.238 MB / 1.201 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.01558/1.183, allocations: 13.26 MB / 1.484 GB, free: 6.793 MB / 1.217 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002568/1.186, allocations: 1.693 MB / 1.486 GB, free: 5.082 MB / 1.217 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001485/1.187, allocations: 1.106 MB / 1.487 GB, free: 3.984 MB / 1.217 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004419/1.188, allocations: 329.2 kB / 1.487 GB, free: 3.609 MB / 1.217 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.623e-05/1.188, allocations: 56.56 kB / 1.487 GB, free: 3.543 MB / 1.217 GB
Notification: Performance of pre-optimization done (n=182): time 3.597e-06/1.188, allocations: 0 / 1.487 GB, free: 3.543 MB / 1.217 GB
Notification: Performance of matching and sorting (n=182): time 0.004784/1.193, allocations: 2.731 MB / 1.49 GB, free: 0.8008 MB / 1.217 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.297e-05/1.193, allocations: 77.33 kB / 1.49 GB, free: 0.7148 MB / 1.217 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002658/1.195, allocations: 2.001 MB / 1.492 GB, free: 14.7 MB / 1.232 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001735/1.196, allocations: 69.64 kB / 1.492 GB, free: 14.62 MB / 1.232 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000973/1.197, allocations: 1.406 MB / 1.493 GB, free: 13.2 MB / 1.232 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0005892/1.197, allocations: 0.643 MB / 1.494 GB, free: 12.55 MB / 1.232 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005544/1.198, allocations: 387.3 kB / 1.494 GB, free: 12.16 MB / 1.232 GB
Notification: Performance of setup shared object (initialization): time 8.582e-05/1.198, allocations: 305.1 kB / 1.494 GB, free: 11.86 MB / 1.232 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001296/1.199, allocations: 0.813 MB / 1.495 GB, free: 11.04 MB / 1.232 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001715/1.201, allocations: 1.406 MB / 1.497 GB, free: 9.375 MB / 1.232 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002967/1.204, allocations: 2.252 MB / 1.499 GB, free: 6.941 MB / 1.232 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.448e-05/1.204, allocations: 7.984 kB / 1.499 GB, free: 6.934 MB / 1.232 GB
Notification: Performance of matching and sorting (n=257) (initialization): time 0.003984/1.208, allocations: 2.498 MB / 1.501 GB, free: 4.426 MB / 1.232 GB
Notification: Performance of prepare postOptimizeDAE: time 2.369e-05/1.208, allocations: 12 kB / 1.501 GB, free: 4.414 MB / 1.232 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.828e-05/1.208, allocations: 12 kB / 1.501 GB, free: 4.402 MB / 1.232 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.15e-05/1.208, allocations: 27.94 kB / 1.501 GB, free: 4.375 MB / 1.232 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002528/1.21, allocations: 0.5539 MB / 1.502 GB, free: 3.82 MB / 1.232 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00201/1.213, allocations: 4.153 MB / 1.506 GB, free: 15.5 MB / 1.248 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002451/1.215, allocations: 210 kB / 1.506 GB, free: 15.3 MB / 1.248 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001603/1.215, allocations: 87.98 kB / 1.506 GB, free: 15.21 MB / 1.248 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00228/1.217, allocations: 1.476 MB / 1.508 GB, free: 13.73 MB / 1.248 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00166/1.219, allocations: 1.391 MB / 1.509 GB, free: 12.08 MB / 1.248 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.002933/1.222, allocations: 2.236 MB / 1.511 GB, free: 9.668 MB / 1.248 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.312e-05/1.222, allocations: 7.984 kB / 1.511 GB, free: 9.66 MB / 1.248 GB
Notification: Performance of matching and sorting (n=257) (initialization_lambda0): time 0.003842/1.226, allocations: 2.475 MB / 1.514 GB, free: 7.176 MB / 1.248 GB
Notification: Performance of prepare postOptimizeDAE: time 2.06e-05/1.226, allocations: 15.94 kB / 1.514 GB, free: 7.16 MB / 1.248 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.518e-05/1.226, allocations: 16.16 kB / 1.514 GB, free: 7.145 MB / 1.248 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.391e-05/1.226, allocations: 27.98 kB / 1.514 GB, free: 7.117 MB / 1.248 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001712/1.228, allocations: 467.3 kB / 1.514 GB, free: 6.66 MB / 1.248 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.952e-05/1.228, allocations: 31.88 kB / 1.514 GB, free: 6.629 MB / 1.248 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002377/1.23, allocations: 190.5 kB / 1.514 GB, free: 6.441 MB / 1.248 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000145/1.23, allocations: 91.92 kB / 1.514 GB, free: 6.352 MB / 1.248 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 (not torn): 7
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 7 systems
   {1, 1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0004324/1.231, allocations: 307.6 kB / 1.515 GB, free: 6.043 MB / 1.248 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005646/1.231, allocations: 268.8 kB / 1.515 GB, free: 5.777 MB / 1.248 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003342/1.235, allocations: 2.115 MB / 1.517 GB, free: 3.645 MB / 1.248 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002473/1.237, allocations: 1.894 MB / 1.519 GB, free: 1.707 MB / 1.248 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.029e-06/1.237, allocations: 0 / 1.519 GB, free: 1.707 MB / 1.248 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001207/1.238, allocations: 0.556 MB / 1.519 GB, free: 1.129 MB / 1.248 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004147/1.239, allocations: 47.86 kB / 1.519 GB, free: 1.082 MB / 1.248 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00181/1.241, allocations: 90.98 kB / 1.52 GB, free: 0.9961 MB / 1.248 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002077/1.241, allocations: 105.8 kB / 1.52 GB, free: 0.8906 MB / 1.248 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004884/1.246, allocations: 2.347 MB / 1.522 GB, free: 14.52 MB / 1.264 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.004537/1.25, allocations: 3.302 MB / 1.525 GB, free: 11.2 MB / 1.264 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001142/1.251, allocations: 0.9212 MB / 1.526 GB, free: 10.28 MB / 1.264 GB
Notification: Performance of sorting global known variables: time 0.001842/1.253, allocations: 1.338 MB / 1.527 GB, free: 8.93 MB / 1.264 GB
Notification: Performance of Backend: time 5.51e-07/1.253, allocations: 3.938 kB / 1.527 GB, free: 8.926 MB / 1.264 GB
Notification: Performance of simCode: created initialization part: time 0.008271/1.262, allocations: 3.548 MB / 1.531 GB, free: 5.387 MB / 1.264 GB
Notification: Performance of SimCode: time 0.01292/1.275, allocations: 7.435 MB / 1.538 GB, free: 13.92 MB / 1.279 GB
Notification: Performance of Templates: time 0.6468/1.921, allocations: 69.25 MB / 1.606 GB, free: 429.3 MB / 1.311 GB
make -j1 -f Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.makefile
(rm -f Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe ; mkfifo Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe ; head -c 1048576 < Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe >> ../files/Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.sim & ./Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe 2>&1)
diffSimulationResults("Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_9.1.x/Buildings_Fluid_Sources_Examples_TraceSubstancesFlowSource.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_9_Buildings.Fluid.Sources.Examples.TraceSubstancesFlowSource.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches