Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false)
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo)
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances,tolerance=1e-05,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|junction1.mC_scaled.1.|junction1.mC_scaled.2.|junction1.medium.T|junction1.medium.p|junction2.mC_scaled.1.|junction2.mC_scaled.2.|junction2.medium.T|junction2.medium.p|pipe1.mediums.1..T|pipe1.mediums.1..p|pipe1.mediums.2..T|pipe1.mediums.2..p|pipe1.mediums.3..T|pipe1.mediums.3..p|pipe1.mediums.4..T|pipe1.mediums.4..p|pipe1.mediums.5..T|pipe1.mediums.5..p|pipe2.mediums.1..T|pipe2.mediums.1..p|pipe2.mediums.2..T|pipe2.mediums.2..p|pipe2.mediums.3..T|pipe2.mediums.3..p|pipe2.mediums.4..T|pipe2.mediums.4..p|pipe2.mediums.5..T|pipe2.mediums.5..p|pipe3.mediums.1..T|pipe3.mediums.1..p|pipe3.mediums.2..T|pipe3.mediums.2..p|pipe3.mediums.3..T|pipe3.mediums.3..p|pipe3.mediums.4..T|pipe3.mediums.4..p|pipe3.mediums.5..T|pipe3.mediums.5..p|pipe4.mediums.1..T|pipe4.mediums.1..p|pipe4.mediums.2..T|pipe4.mediums.2..p|pipe4.mediums.3..T|pipe4.mediums.3..p|pipe4.mediums.4..T|pipe4.mediums.4..p|pipe4.mediums.5..T|pipe4.mediums.5..p|pipe5.mediums.1..T|pipe5.mediums.1..p|pipe5.mediums.2..T|pipe5.mediums.2..p|pipe5.mediums.3..T|pipe5.mediums.3..p|pipe5.mediums.4..T|pipe5.mediums.4..p|pipe5.mediums.5..T|pipe5.mediums.5..p|pipe6.mediums.1..T|pipe6.mediums.1..p|pipe6.mediums.2..T|pipe6.mediums.2..p|pipe6.mediums.3..T|pipe6.mediums.3..p|pipe6.mediums.4..T|pipe6.mediums.4..p|pipe6.mediums.5..T|pipe6.mediums.5..p|pipe7.mediums.1..T|pipe7.mediums.1..p|pipe7.mediums.2..T|pipe7.mediums.2..p|pipe7.mediums.3..T|pipe7.mediums.3..p|pipe7.mediums.4..T|pipe7.mediums.4..p|pipe7.mediums.5..T|pipe7.mediums.5..p|pipe8.mediums.1..T|pipe8.mediums.1..p|pipe8.mediums.2..T|pipe8.mediums.2..p|pipe8.mediums.3..T|pipe8.mediums.3..p|pipe8.mediums.4..T|pipe8.mediums.4..p|pipe8.mediums.5..T|pipe8.mediums.5..p",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances")
translateModel(ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances,tolerance=1e-05,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|junction1.mC_scaled.1.|junction1.mC_scaled.2.|junction1.medium.T|junction1.medium.p|junction2.mC_scaled.1.|junction2.mC_scaled.2.|junction2.medium.T|junction2.medium.p|pipe1.mediums.1..T|pipe1.mediums.1..p|pipe1.mediums.2..T|pipe1.mediums.2..p|pipe1.mediums.3..T|pipe1.mediums.3..p|pipe1.mediums.4..T|pipe1.mediums.4..p|pipe1.mediums.5..T|pipe1.mediums.5..p|pipe2.mediums.1..T|pipe2.mediums.1..p|pipe2.mediums.2..T|pipe2.mediums.2..p|pipe2.mediums.3..T|pipe2.mediums.3..p|pipe2.mediums.4..T|pipe2.mediums.4..p|pipe2.mediums.5..T|pipe2.mediums.5..p|pipe3.mediums.1..T|pipe3.mediums.1..p|pipe3.mediums.2..T|pipe3.mediums.2..p|pipe3.mediums.3..T|pipe3.mediums.3..p|pipe3.mediums.4..T|pipe3.mediums.4..p|pipe3.mediums.5..T|pipe3.mediums.5..p|pipe4.mediums.1..T|pipe4.mediums.1..p|pipe4.mediums.2..T|pipe4.mediums.2..p|pipe4.mediums.3..T|pipe4.mediums.3..p|pipe4.mediums.4..T|pipe4.mediums.4..p|pipe4.mediums.5..T|pipe4.mediums.5..p|pipe5.mediums.1..T|pipe5.mediums.1..p|pipe5.mediums.2..T|pipe5.mediums.2..p|pipe5.mediums.3..T|pipe5.mediums.3..p|pipe5.mediums.4..T|pipe5.mediums.4..p|pipe5.mediums.5..T|pipe5.mediums.5..p|pipe6.mediums.1..T|pipe6.mediums.1..p|pipe6.mediums.2..T|pipe6.mediums.2..p|pipe6.mediums.3..T|pipe6.mediums.3..p|pipe6.mediums.4..T|pipe6.mediums.4..p|pipe6.mediums.5..T|pipe6.mediums.5..p|pipe7.mediums.1..T|pipe7.mediums.1..p|pipe7.mediums.2..T|pipe7.mediums.2..p|pipe7.mediums.3..T|pipe7.mediums.3..p|pipe7.mediums.4..T|pipe7.mediums.4..p|pipe7.mediums.5..T|pipe7.mediums.5..p|pipe8.mediums.1..T|pipe8.mediums.1..p|pipe8.mediums.2..T|pipe8.mediums.2..p|pipe8.mediums.3..T|pipe8.mediums.3..p|pipe8.mediums.4..T|pipe8.mediums.4..p|pipe8.mediums.5..T|pipe8.mediums.5..p",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001564/0.001564, allocations: 104.5 kB / 17.85 MB, free: 5.656 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001868/0.001868, allocations: 205.8 kB / 18.79 MB, free: 4.727 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 2.253/2.253, allocations: 227.4 MB / 246.9 MB, free: 8.988 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.3587/0.3587, allocations: 46.09 MB / 344.3 MB, free: 11.57 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.002625/0.002626, allocations: 66.92 kB / 417.5 MB, free: 20.15 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1687/0.1714, allocations: 62.46 MB / 480 MB, free: 5.676 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances): time 1.309/1.48, allocations: 355.9 MB / 0.8162 GB, free: 15.21 MB / 0.545 GB
Notification: Performance of NFInst.instExpressions: time 0.06648/1.547, allocations: 36.63 MB / 0.852 GB, free: 13.31 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.009361/1.556, allocations: 227.9 kB / 0.8522 GB, free: 13.2 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.007914/1.564, allocations: 1.741 MB / 0.8539 GB, free: 12.01 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.02886/1.593, allocations: 8.241 MB / 0.862 GB, free: 5.781 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.04485/1.638, allocations: 11.74 MB / 0.8734 GB, free: 10.05 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.0414/1.679, allocations: 35.16 MB / 0.9078 GB, free: 6.809 MB / 0.6075 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01533/1.695, allocations: 5.999 MB / 0.9136 GB, free: 0.7539 MB / 0.6075 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02696/1.722, allocations: 10.01 MB / 0.9234 GB, free: 6.711 MB / 0.6231 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02813/1.75, allocations: 11.1 MB / 0.9342 GB, free: 11.59 MB / 0.6387 GB
Notification: Performance of NFPackage.collectConstants: time 0.008214/1.758, allocations: 0.6016 MB / 0.9348 GB, free: 10.98 MB / 0.6387 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0301/1.788, allocations: 6.473 MB / 0.9411 GB, free: 4.504 MB / 0.6387 GB
Notification: Performance of NFScalarize.scalarize: time 0.01371/1.802, allocations: 6.46 MB / 0.9475 GB, free: 14.03 MB / 0.6544 GB
Notification: Performance of NFVerifyModel.verify: time 0.02902/1.831, allocations: 7.411 MB / 0.9547 GB, free: 6.582 MB / 0.6544 GB
Notification: Performance of NFConvertDAE.convert: time 1.223/3.054, allocations: 18.86 MB / 0.9731 GB, free: 43.91 MB / 0.6544 GB
Notification: Performance of FrontEnd - DAE generated: time 1.001e-05/3.054, allocations: 0.6875 kB / 0.9731 GB, free: 43.91 MB / 0.6544 GB
Notification: Performance of FrontEnd: time 2.245e-06/3.054, allocations: 2.25 kB / 0.9731 GB, free: 43.91 MB / 0.6544 GB
Notification: Performance of Transformations before backend: time 0.001361/3.056, allocations: 2.156 kB / 0.9731 GB, free: 43.91 MB / 0.6544 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3585
 * Number of variables: 3585
Notification: Performance of Generate backend data structure: time 0.1413/3.197, allocations: 19.04 MB / 0.9917 GB, free: 38.87 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 5.481e-05/3.197, allocations: 12.83 kB / 0.9917 GB, free: 38.87 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0247/3.222, allocations: 2.173 MB / 0.9938 GB, free: 38.14 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02719/3.249, allocations: 7.044 MB / 1.001 GB, free: 36.86 MB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001786/3.251, allocations: 0.876 MB / 1.002 GB, free: 36.83 MB / 0.6544 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01071/3.262, allocations: 1.132 MB / 1.003 GB, free: 36.8 MB / 0.6544 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.07982/3.342, allocations: 22.84 MB / 1.025 GB, free: 35.18 MB / 0.6544 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0009719/3.343, allocations: 42.48 kB / 1.025 GB, free: 35.17 MB / 0.6544 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01041/3.353, allocations: 462.7 kB / 1.025 GB, free: 35.17 MB / 0.6544 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002705/3.356, allocations: 1.859 MB / 1.027 GB, free: 35.14 MB / 0.6544 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.06708/3.423, allocations: 22.78 MB / 1.05 GB, free: 33.6 MB / 0.6544 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.2391/3.662, allocations: 84.77 MB / 1.132 GB, free: 15.73 MB / 0.7169 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.05312/3.715, allocations: 21.64 MB / 1.153 GB, free: 9.625 MB / 0.7325 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.03189/3.747, allocations: 10.85 MB / 1.164 GB, free: 14.75 MB / 0.7482 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00351/3.751, allocations: 1.067 MB / 1.165 GB, free: 13.57 MB / 0.7482 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0004447/3.751, allocations: 212.4 kB / 1.165 GB, free: 13.36 MB / 0.7482 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01429/3.765, allocations: 1.433 MB / 1.167 GB, free: 11.91 MB / 0.7482 GB
Notification: Performance of pre-optimization done (n=1487): time 7.136e-05/3.766, allocations: 0 / 1.167 GB, free: 11.91 MB / 0.7482 GB
Warning: Some equations could not be differentiated for following variables having attribute stateSelect=StateSelect.prefer. They will be treated as if they had stateSelect=StateSelect.default
========================================
1: pipe2.mediums[3].T
2: pipe2.mediums[1].T
3: pipe2.mediums[5].T
4: pipe2.mediums[2].T
5: pipe2.mediums[4].T
6: pipe3.mediums[1].T
7: pipe3.mediums[3].T
8: pipe3.mediums[4].T
9: pipe3.mediums[2].T
10: pipe3.mediums[5].T
11: pipe1.mediums[2].T
12: pipe1.mediums[1].T
13: pipe1.mediums[3].T
14: pipe1.mediums[4].T
15: pipe1.mediums[5].T
16: pipe4.mediums[2].T
17: pipe4.mediums[4].T
18: pipe4.mediums[1].T
19: pipe4.mediums[3].T
20: pipe4.mediums[5].T
21: junction1.medium.T
22: junction2.medium.T
Please use -d=bltdump for more information.
Notification: Performance of matching and sorting (n=1737): time 1.487/5.253, allocations: 137.7 MB / 1.301 GB, free: 425.1 MB / 0.8263 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002759/5.253, allocations: 359.1 kB / 1.301 GB, free: 424.9 MB / 0.8263 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0281/5.281, allocations: 8.246 MB / 1.31 GB, free: 422.1 MB / 0.8263 GB
Notification: Performance of collectPreVariables (initialization): time 0.008709/5.29, allocations: 347.9 kB / 1.31 GB, free: 421.8 MB / 0.8263 GB
Notification: Performance of collectInitialEqns (initialization): time 0.008709/5.299, allocations: 4.957 MB / 1.315 GB, free: 418 MB / 0.8263 GB
Notification: Performance of collectInitialBindings (initialization): time 0.01682/5.316, allocations: 6.124 MB / 1.321 GB, free: 413.3 MB / 0.8263 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.04352/5.36, allocations: 3.916 MB / 1.324 GB, free: 411.8 MB / 0.8263 GB
Notification: Performance of setup shared object (initialization): time 0.0001147/5.36, allocations: 301.1 kB / 1.325 GB, free: 411.5 MB / 0.8263 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04451/5.404, allocations: 10.87 MB / 1.335 GB, free: 408 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0531/5.457, allocations: 14.64 MB / 1.35 GB, free: 396.6 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.09379/5.551, allocations: 26.65 MB / 1.376 GB, free: 371.6 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0004896/5.552, allocations: 28 kB / 1.376 GB, free: 371.5 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=2465) (initialization): time 0.1451/5.697, allocations: 30.04 MB / 1.405 GB, free: 341.6 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0005677/5.698, allocations: 159.4 kB / 1.405 GB, free: 341.5 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000412/5.698, allocations: 103.7 kB / 1.405 GB, free: 341.4 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0116/5.71, allocations: 2.513 MB / 1.408 GB, free: 338.7 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04957/5.759, allocations: 4.997 MB / 1.413 GB, free: 333.7 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03989/5.799, allocations: 39.47 MB / 1.451 GB, free: 291.8 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0399/5.839, allocations: 1.439 MB / 1.453 GB, free: 290.4 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0125/5.852, allocations: 1.296 MB / 1.454 GB, free: 289.1 MB / 0.8263 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0662/5.918, allocations: 13.53 MB / 1.467 GB, free: 275.5 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.05035/5.969, allocations: 14.01 MB / 1.481 GB, free: 260.1 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.08984/6.059, allocations: 25.35 MB / 1.506 GB, free: 233.3 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0003395/6.059, allocations: 32 kB / 1.506 GB, free: 233.3 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=2465) (initialization_lambda0): time 0.1303/6.189, allocations: 28.57 MB / 1.533 GB, free: 204.5 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004419/6.19, allocations: 163.6 kB / 1.534 GB, free: 204.3 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003359/6.19, allocations: 100 kB / 1.534 GB, free: 204.2 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009321/6.2, allocations: 2.233 MB / 1.536 GB, free: 201.8 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04537/6.245, allocations: 4.958 MB / 1.541 GB, free: 196.9 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03338/6.279, allocations: 38.62 MB / 1.578 GB, free: 155.8 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03266/6.311, allocations: 1.415 MB / 1.58 GB, free: 154.4 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01068/6.322, allocations: 1.269 MB / 1.581 GB, free: 153.2 MB / 0.8263 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 452
 * Number of states: 0 ()
 * Number of discrete variables: 4 (traceSubstance4.ind,traceSubstance3.ind,traceSubstance2.ind,traceSubstance1.ind)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2405):
 * Single equations (assignments): 2348
 * Array equations: 0
 * Algorithm blocks: 4
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 53
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 10 systems
   {(1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 43 systems
   {(1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (2,7), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1)}
Notification: Performance of prepare postOptimizeDAE: time 0.02202/6.344, allocations: 1.277 MB / 1.582 GB, free: 151.9 MB / 0.8263 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01559/6.36, allocations: 1.17 MB / 1.583 GB, free: 150.7 MB / 0.8263 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1288/6.489, allocations: 33.35 MB / 1.616 GB, free: 117.2 MB / 0.8263 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0003367/6.489, allocations: 172 kB / 1.616 GB, free: 117.1 MB / 0.8263 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0002002/6.489, allocations: 4 kB / 1.616 GB, free: 117.1 MB / 0.8263 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0936/6.583, allocations: 22.49 MB / 1.638 GB, free: 94.22 MB / 0.8263 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.1979/6.781, allocations: 48.12 MB / 1.685 GB, free: 45.58 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003724/6.781, allocations: 4 kB / 1.685 GB, free: 45.58 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.04137/6.823, allocations: 3.882 MB / 1.689 GB, free: 41.69 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01343/6.836, allocations: 2.869 MB / 1.692 GB, free: 38.66 MB / 0.8263 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.009202/6.845, allocations: 0.7494 MB / 1.692 GB, free: 37.91 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.6175/7.463, allocations: 40.04 MB / 1.732 GB, free: 398.4 MB / 0.8263 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.967e-06/7.463, allocations: 2.75 kB / 1.732 GB, free: 398.4 MB / 0.8263 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.1693/7.632, allocations: 37.75 MB / 1.768 GB, free: 392.1 MB / 0.8263 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.009134/7.641, allocations: 1.304 MB / 1.77 GB, free: 391.9 MB / 0.8263 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01304/7.655, allocations: 481 kB / 1.77 GB, free: 391.6 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02866/7.683, allocations: 1.007 MB / 1.771 GB, free: 391.4 MB / 0.8263 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01007/7.693, allocations: 1.199 MB / 1.772 GB, free: 391.2 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.009722/7.703, allocations: 1.051 MB / 1.773 GB, free: 391.1 MB / 0.8263 GB
Notification: Performance of sorting global known variables: time 0.01895/7.722, allocations: 5.554 MB / 1.779 GB, free: 389.2 MB / 0.8263 GB
Notification: Performance of sort global known variables: time 1.173e-06/7.722, allocations: 0 / 1.779 GB, free: 389.2 MB / 0.8263 GB
Notification: Performance of remove unused functions: time 0.07243/7.795, allocations: 9.014 MB / 1.788 GB, free: 388.3 MB / 0.8263 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 10
 * Number of states: 168 (pipe5.mCs_scaled[1,1],pipe5.mCs_scaled[1,2],pipe5.mCs_scaled[2,1],pipe5.mCs_scaled[2,2],pipe5.mCs_scaled[3,1],pipe5.mCs_scaled[3,2],pipe5.mCs_scaled[4,1],pipe5.mCs_scaled[4,2],pipe5.mCs_scaled[5,1],pipe5.mCs_scaled[5,2],pipe5.mediums[1].p,pipe5.mediums[1].T,pipe5.mediums[2].p,pipe5.mediums[2].T,pipe5.mediums[3].p,pipe5.mediums[3].T,pipe5.mediums[4].p,pipe5.mediums[4].T,pipe5.mediums[5].p,pipe5.mediums[5].T,pipe6.mCs_scaled[1,1],pipe6.mCs_scaled[1,2],pipe6.mCs_scaled[2,1],pipe6.mCs_scaled[2,2],pipe6.mCs_scaled[3,1],pipe6.mCs_scaled[3,2],pipe6.mCs_scaled[4,1],pipe6.mCs_scaled[4,2],pipe6.mCs_scaled[5,1],pipe6.mCs_scaled[5,2],pipe6.mediums[1].p,pipe6.mediums[1].T,pipe6.mediums[2].p,pipe6.mediums[2].T,pipe6.mediums[3].p,pipe6.mediums[3].T,pipe6.mediums[4].p,pipe6.mediums[4].T,pipe6.mediums[5].p,pipe6.mediums[5].T,pipe7.mCs_scaled[1,1],pipe7.mCs_scaled[1,2],pipe7.mCs_scaled[2,1],pipe7.mCs_scaled[2,2],pipe7.mCs_scaled[3,1],pipe7.mCs_scaled[3,2],pipe7.mCs_scaled[4,1],pipe7.mCs_scaled[4,2],pipe7.mCs_scaled[5,1],pipe7.mCs_scaled[5,2],pipe7.mediums[1].p,pipe7.mediums[1].T,pipe7.mediums[2].p,pipe7.mediums[2].T,pipe7.mediums[3].p,pipe7.mediums[3].T,pipe7.mediums[4].p,pipe7.mediums[4].T,pipe7.mediums[5].p,pipe7.mediums[5].T,pipe8.mCs_scaled[1,1],pipe8.mCs_scaled[1,2],pipe8.mCs_scaled[2,1],pipe8.mCs_scaled[2,2],pipe8.mCs_scaled[3,1],pipe8.mCs_scaled[3,2],pipe8.mCs_scaled[4,1],pipe8.mCs_scaled[4,2],pipe8.mCs_scaled[5,1],pipe8.mCs_scaled[5,2],pipe8.mediums[1].p,pipe8.mediums[1].T,pipe8.mediums[2].p,pipe8.mediums[2].T,pipe8.mediums[3].p,pipe8.mediums[3].T,pipe8.mediums[4].p,pipe8.mediums[4].T,pipe8.mediums[5].p,pipe8.mediums[5].T,pipe2.mCs_scaled[1,1],pipe2.mCs_scaled[1,2],pipe2.mCs_scaled[2,1],pipe2.mCs_scaled[2,2],pipe2.mCs_scaled[3,1],pipe2.mCs_scaled[3,2],pipe2.mCs_scaled[4,1],pipe2.mCs_scaled[4,2],pipe2.mCs_scaled[5,1],pipe2.mCs_scaled[5,2],pipe2.mediums[1].p,pipe2.mediums[1].T,pipe2.mediums[2].p,pipe2.mediums[2].T,pipe2.mediums[3].p,pipe2.mediums[3].T,pipe2.mediums[4].p,pipe2.mediums[4].T,pipe2.mediums[5].p,pipe2.mediums[5].T,pipe3.mCs_scaled[1,1],pipe3.mCs_scaled[1,2],pipe3.mCs_scaled[2,1],pipe3.mCs_scaled[2,2],pipe3.mCs_scaled[3,1],pipe3.mCs_scaled[3,2],pipe3.mCs_scaled[4,1],pipe3.mCs_scaled[4,2],pipe3.mCs_scaled[5,1],pipe3.mCs_scaled[5,2],pipe3.mediums[1].p,pipe3.mediums[1].T,pipe3.mediums[2].p,pipe3.mediums[2].T,pipe3.mediums[3].p,pipe3.mediums[3].T,pipe3.mediums[4].p,pipe3.mediums[4].T,pipe3.mediums[5].p,pipe3.mediums[5].T,pipe1.mCs_scaled[1,1],pipe1.mCs_scaled[1,2],pipe1.mCs_scaled[2,1],pipe1.mCs_scaled[2,2],pipe1.mCs_scaled[3,1],pipe1.mCs_scaled[3,2],pipe1.mCs_scaled[4,1],pipe1.mCs_scaled[4,2],pipe1.mCs_scaled[5,1],pipe1.mCs_scaled[5,2],pipe1.mediums[1].p,pipe1.mediums[1].T,pipe1.mediums[2].p,pipe1.mediums[2].T,pipe1.mediums[3].p,pipe1.mediums[3].T,pipe1.mediums[4].p,pipe1.mediums[4].T,pipe1.mediums[5].p,pipe1.mediums[5].T,pipe4.mCs_scaled[1,1],pipe4.mCs_scaled[1,2],pipe4.mCs_scaled[2,1],pipe4.mCs_scaled[2,2],pipe4.mCs_scaled[3,1],pipe4.mCs_scaled[3,2],pipe4.mCs_scaled[4,1],pipe4.mCs_scaled[4,2],pipe4.mCs_scaled[5,1],pipe4.mCs_scaled[5,2],pipe4.mediums[1].p,pipe4.mediums[1].T,pipe4.mediums[2].p,pipe4.mediums[2].T,pipe4.mediums[3].p,pipe4.mediums[3].T,pipe4.mediums[4].p,pipe4.mediums[4].T,pipe4.mediums[5].p,pipe4.mediums[5].T,junction1.medium.p,junction1.medium.T,junction1.mC_scaled[1],junction1.mC_scaled[2],junction2.medium.p,junction2.medium.T,junction2.mC_scaled[1],junction2.mC_scaled[2])
 * 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 (1723):
 * Single equations (assignments): 1670
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 53
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 11 systems
   {(1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 42 systems
   {(1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (2,12), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1), (1,1)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01731/7.812, allocations: 2.382 MB / 1.79 GB, free: 387.6 MB / 0.8263 GB
Notification: Performance of simCode: created initialization part: time 0.1397/7.952, allocations: 42.83 MB / 1.832 GB, free: 360.4 MB / 0.8263 GB
Notification: Performance of simCode: created event and clocks part: time 3.157e-05/7.952, allocations: 16.48 kB / 1.832 GB, free: 360.4 MB / 0.8263 GB
Notification: Performance of simCode: created simulation system equations: time 0.04835/8, allocations: 13.77 MB / 1.845 GB, free: 348.4 MB / 0.8263 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.06934/8.07, allocations: 5.348 MB / 1.85 GB, free: 344.7 MB / 0.8263 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1659/8.236, allocations: 49.97 MB / 1.899 GB, free: 300.2 MB / 0.8263 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.02287/8.259, allocations: 7.779 MB / 1.907 GB, free: 293.8 MB / 0.8263 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.02218/8.281, allocations: 2.049 MB / 1.909 GB, free: 291.7 MB / 0.8263 GB
Notification: Performance of SimCode: time 1.893e-06/8.281, allocations: 0 / 1.909 GB, free: 291.7 MB / 0.8263 GB
Notification: Performance of Templates: time 7.437/15.72, allocations: 2.539 GB / 4.447 GB, free: 218.3 MB / 0.8263 GB
make -j1 -f ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.makefile
(rm -f ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.sim & ./ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.pipe 2>&1)
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/ModelicaTest/Fluid/TestComponents/Pipes/DynamicPipesWithTraceSubstances/DynamicPipesWithTraceSubstances.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.DynamicPipesWithTraceSubstances.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
""

Variables in the reference:time,junction1.mC_scaled[1],junction1.mC_scaled[2],junction1.medium.T,junction1.medium.p,junction2.mC_scaled[1],junction2.mC_scaled[2],junction2.medium.T,junction2.medium.p,pipe1.mediums[1].T,pipe1.mediums[1].p,pipe1.mediums[2].T,pipe1.mediums[2].p,pipe1.mediums[3].T,pipe1.mediums[3].p,pipe1.mediums[4].T,pipe1.mediums[4].p,pipe1.mediums[5].T,pipe1.mediums[5].p,pipe2.mediums[1].T,pipe2.mediums[1].p,pipe2.mediums[2].T,pipe2.mediums[2].p,pipe2.mediums[3].T,pipe2.mediums[3].p,pipe2.mediums[4].T,pipe2.mediums[4].p,pipe2.mediums[5].T,pipe2.mediums[5].p,pipe3.mediums[1].T,pipe3.mediums[1].p,pipe3.mediums[2].T,pipe3.mediums[2].p,pipe3.mediums[3].T,pipe3.mediums[3].p,pipe3.mediums[4].T,pipe3.mediums[4].p,pipe3.mediums[5].T,pipe3.mediums[5].p,pipe4.mediums[1].T,pipe4.mediums[1].p,pipe4.mediums[2].T,pipe4.mediums[2].p,pipe4.mediums[3].T,pipe4.mediums[3].p,pipe4.mediums[4].T,pipe4.mediums[4].p,pipe4.mediums[5].T,pipe4.mediums[5].p,pipe5.mediums[1].T,pipe5.mediums[1].p,pipe5.mediums[2].T,pipe5.mediums[2].p,pipe5.mediums[3].T,pipe5.mediums[3].p,pipe5.mediums[4].T,pipe5.mediums[4].p,pipe5.mediums[5].T,pipe5.mediums[5].p,pipe6.mediums[1].T,pipe6.mediums[1].p,pipe6.mediums[2].T,pipe6.mediums[2].p,pipe6.mediums[3].T,pipe6.mediums[3].p,pipe6.mediums[4].T,pipe6.mediums[4].p,pipe6.mediums[5].T,pipe6.mediums[5].p,pipe7.mediums[1].T,pipe7.mediums[1].p,pipe7.mediums[2].T,pipe7.mediums[2].p,pipe7.mediums[3].T,pipe7.mediums[3].p,pipe7.mediums[4].T,pipe7.mediums[4].p,pipe7.mediums[5].T,pipe7.mediums[5].p,pipe8.mediums[1].T,pipe8.mediums[1].p,pipe8.mediums[2].T,pipe8.mediums[2].p,pipe8.mediums[3].T,pipe8.mediums[3].p,pipe8.mediums[4].T,pipe8.mediums[4].p,pipe8.mediums[5].T,pipe8.mediums[5].p

Variables in the result:junction1.mC_scaled[1],junction1.mC_scaled[2],junction1.medium.T,junction1.medium.p,junction2.mC_scaled[1],junction2.mC_scaled[2],junction2.medium.T,junction2.medium.p,pipe1.mediums[1].T,pipe1.mediums[1].p,pipe1.mediums[2].T,pipe1.mediums[2].p,pipe1.mediums[3].T,pipe1.mediums[3].p,pipe1.mediums[4].T,pipe1.mediums[4].p,pipe1.mediums[5].T,pipe1.mediums[5].p,pipe2.mediums[1].T,pipe2.mediums[1].p,pipe2.mediums[2].T,pipe2.mediums[2].p,pipe2.mediums[3].T,pipe2.mediums[3].p,pipe2.mediums[4].T,pipe2.mediums[4].p,pipe2.mediums[5].T,pipe2.mediums[5].p,pipe3.mediums[1].T,pipe3.mediums[1].p,pipe3.mediums[2].T,pipe3.mediums[2].p,pipe3.mediums[3].T,pipe3.mediums[3].p,pipe3.mediums[4].T,pipe3.mediums[4].p,pipe3.mediums[5].T,pipe3.mediums[5].p,pipe4.mediums[1].T,pipe4.mediums[1].p,pipe4.mediums[2].T,pipe4.mediums[2].p,pipe4.mediums[3].T,pipe4.mediums[3].p,pipe4.mediums[4].T,pipe4.mediums[4].p,pipe4.mediums[5].T,pipe4.mediums[5].p,pipe5.mediums[1].T,pipe5.mediums[1].p,pipe5.mediums[2].T,pipe5.mediums[2].p,pipe5.mediums[3].T,pipe5.mediums[3].p,pipe5.mediums[4].T,pipe5.mediums[4].p,pipe5.mediums[5].T,pipe5.mediums[5].p,pipe6.mediums[1].T,pipe6.mediums[1].p,pipe6.mediums[2].T,pipe6.mediums[2].p,pipe6.mediums[3].T,pipe6.mediums[3].p,pipe6.mediums[4].T,pipe6.mediums[4].p,pipe6.mediums[5].T,pipe6.mediums[5].p,pipe7.mediums[1].T,pipe7.mediums[1].p,pipe7.mediums[2].T,pipe7.mediums[2].p,pipe7.mediums[3].T,pipe7.mediums[3].p,pipe7.mediums[4].T,pipe7.mediums[4].p,pipe7.mediums[5].T,pipe7.mediums[5].p,pipe8.mediums[1].T,pipe8.mediums[1].p,pipe8.mediums[2].T,pipe8.mediums[2].p,pipe8.mediums[3].T,pipe8.mediums[3].p,pipe8.mediums[4].T,pipe8.mediums[4].p,pipe8.mediums[5].T,pipe8.mediums[5].p,time