Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.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.Vessels.TestMixingVolumes,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|mixingVolume1.medium.T|mixingVolume2.medium.T",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes")
translateModel(ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|mixingVolume1.medium.T|mixingVolume2.medium.T",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001159/0.001158, allocations: 113 kB / 18.45 MB, free: 4.602 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001329/0.00133, allocations: 206.8 kB / 19.4 MB, free: 3.656 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.361/1.361, allocations: 227.4 MB / 247.6 MB, free: 10.42 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2112/0.2112, allocations: 46.09 MB / 344.9 MB, free: 11.47 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001596/0.001596, allocations: 71.88 kB / 482 MB, free: 4.062 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3722/0.3738, allocations: 62.45 MB / 0.5317 GB, free: 13.59 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes): time 0.01967/0.3936, allocations: 17.75 MB / 0.549 GB, free: 15.32 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.009917/0.4035, allocations: 7.013 MB / 0.5558 GB, free: 8.906 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001164/0.4047, allocations: 19.88 kB / 0.5559 GB, free: 8.887 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001228/0.406, allocations: 377.6 kB / 0.5562 GB, free: 8.516 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001929/0.408, allocations: 0.6722 MB / 0.5569 GB, free: 7.84 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004484/0.4125, allocations: 1.327 MB / 0.5582 GB, free: 6.52 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004109/0.4166, allocations: 2.517 MB / 0.5606 GB, free: 3.996 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001205/0.4179, allocations: 0.67 MB / 0.5613 GB, free: 3.312 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002249/0.4201, allocations: 1.055 MB / 0.5623 GB, free: 2.254 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001273/0.4214, allocations: 0.7473 MB / 0.563 GB, free: 1.504 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002135/0.4216, allocations: 88 kB / 0.5631 GB, free: 1.418 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001544/0.4232, allocations: 0.5421 MB / 0.5637 GB, free: 0.875 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003568/0.4236, allocations: 375 kB / 0.564 GB, free: 0.5078 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007133/0.4243, allocations: 0.5096 MB / 0.5645 GB, free: 15.99 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.002304/0.4266, allocations: 1.54 MB / 0.566 GB, free: 14.45 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.148e-06/0.4266, allocations: 0 / 0.566 GB, free: 14.45 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.403e-06/0.4266, allocations: 3.938 kB / 0.566 GB, free: 14.45 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 1.712e-05/0.4267, allocations: 0 / 0.566 GB, free: 14.45 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 206
 * Number of variables: 206
Notification: Performance of Generate backend data structure: time 0.002537/0.4292, allocations: 1.29 MB / 0.5673 GB, free: 13.11 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.81e-05/0.4292, allocations: 8.031 kB / 0.5673 GB, free: 13.1 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0004987/0.4298, allocations: 127.8 kB / 0.5674 GB, free: 12.97 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0009582/0.4307, allocations: 0.6222 MB / 0.568 GB, free: 12.31 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001993/0.4309, allocations: 244.8 kB / 0.5682 GB, free: 12.04 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001225/0.4311, allocations: 80.38 kB / 0.5683 GB, free: 11.96 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00193/0.433, allocations: 1.305 MB / 0.5696 GB, free: 10.52 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.882e-05/0.433, allocations: 7.938 kB / 0.5696 GB, free: 10.51 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001122/0.4332, allocations: 36 kB / 0.5696 GB, free: 10.47 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.641e-05/0.4332, allocations: 49.3 kB / 0.5697 GB, free: 10.43 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001539/0.4348, allocations: 0.961 MB / 0.5706 GB, free: 9.465 MB / 430.1 MB
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.006818/0.4416, allocations: 7.075 MB / 0.5775 GB, free: 1.922 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0005264/0.4422, allocations: 320.2 kB / 0.5778 GB, free: 1.602 MB / 430.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002568/0.4424, allocations: 173.6 kB / 0.578 GB, free: 1.43 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.002759/0.4452, allocations: 2.826 MB / 0.5808 GB, free: 14.1 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.61e-05/0.4453, allocations: 42.78 kB / 0.5808 GB, free: 14.05 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=28): time 3.416e-06/0.4453, allocations: 4 kB / 0.5808 GB, free: 14.04 MB / 446.1 MB
Notification: Performance of matching and sorting (n=34): time 0.002355/0.4476, allocations: 1.103 MB / 0.5819 GB, free: 12.89 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.851e-05/0.4477, allocations: 55.41 kB / 0.5819 GB, free: 12.82 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00102/0.4487, allocations: 0.868 MB / 0.5828 GB, free: 11.94 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 4.377e-05/0.4488, allocations: 41.69 kB / 0.5828 GB, free: 11.89 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.000363/0.4492, allocations: 0.5588 MB / 0.5834 GB, free: 11.32 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001006/0.4493, allocations: 138.3 kB / 0.5835 GB, free: 11.19 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002544/0.4495, allocations: 238.5 kB / 0.5837 GB, free: 10.95 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0002/0.4497, allocations: 402.7 kB / 0.5841 GB, free: 10.55 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003691/0.4501, allocations: 246.5 kB / 0.5844 GB, free: 10.31 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0008615/0.451, allocations: 0.9621 MB / 0.5853 GB, free: 8.984 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001367/0.4524, allocations: 1.316 MB / 0.5866 GB, free: 7.309 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.172e-05/0.4524, allocations: 12 kB / 0.5866 GB, free: 7.297 MB / 446.1 MB
Notification: Performance of matching and sorting (n=154) (initialization): time 0.001825/0.4542, allocations: 1.208 MB / 0.5878 GB, free: 6.082 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.886e-05/0.4543, allocations: 22.22 kB / 0.5878 GB, free: 6.062 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.606e-05/0.4543, allocations: 28 kB / 0.5878 GB, free: 6.035 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 4.324e-05/0.4543, allocations: 32 kB / 0.5878 GB, free: 6.004 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008263/0.4552, allocations: 283.4 kB / 0.5881 GB, free: 5.727 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.249e-05/0.4552, allocations: 51.84 kB / 0.5882 GB, free: 5.676 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006537/0.4559, allocations: 51.91 kB / 0.5882 GB, free: 5.625 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.37e-05/0.456, allocations: 72 kB / 0.5883 GB, free: 5.555 MB / 446.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 117
 * Number of states: 0 ()
 * Number of discrete variables: 12 (mixingVolume1.regularFlow[1],mixingVolume1.regularFlow[2],mixingVolume1.regularFlow[3],mixingVolume2.regularFlow[1],mixingVolume2.regularFlow[2],mixingVolume2.regularFlow[3],mixingVolume2.inFlow[3],mixingVolume2.inFlow[2],mixingVolume2.inFlow[1],mixingVolume1.inFlow[3],mixingVolume1.inFlow[2],mixingVolume1.inFlow[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (154):
 * Single equations (assignments): 154
 * 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.0004802/0.4565, allocations: 341 kB / 0.5886 GB, free: 5.211 MB / 446.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001323/0.4566, allocations: 86.97 kB / 0.5887 GB, free: 5.125 MB / 446.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008649/0.4575, allocations: 481.4 kB / 0.5892 GB, free: 4.648 MB / 446.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.947e-06/0.4575, allocations: 8 kB / 0.5892 GB, free: 4.641 MB / 446.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.701e-06/0.4575, allocations: 8 kB / 0.5892 GB, free: 4.633 MB / 446.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.973e-06/0.4575, allocations: 3.984 kB / 0.5892 GB, free: 4.629 MB / 446.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001637/0.4592, allocations: 1.239 MB / 0.5904 GB, free: 3.277 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.173e-06/0.4592, allocations: 4.188 kB / 0.5904 GB, free: 3.273 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003178/0.4595, allocations: 71.84 kB / 0.5905 GB, free: 3.203 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 1.061e-05/0.4595, allocations: 4 kB / 0.5905 GB, free: 3.199 MB / 446.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.282e-05/0.4596, allocations: 8 kB / 0.5905 GB, free: 3.191 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 5.78e-06/0.4596, allocations: 7.984 kB / 0.5905 GB, free: 3.184 MB / 446.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.813e-06/0.4596, allocations: 0 / 0.5905 GB, free: 3.184 MB / 446.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0009087/0.4605, allocations: 0.6445 MB / 0.5911 GB, free: 2.504 MB / 446.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002344/0.4607, allocations: 204 kB / 0.5913 GB, free: 2.285 MB / 446.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001065/0.4608, allocations: 8 kB / 0.5913 GB, free: 2.277 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004423/0.4613, allocations: 12 kB / 0.5913 GB, free: 2.266 MB / 446.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 4.2e-05/0.4613, allocations: 28.03 kB / 0.5913 GB, free: 2.238 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.899e-05/0.4614, allocations: 27.97 kB / 0.5914 GB, free: 2.211 MB / 446.1 MB
Notification: Performance of sorting global known variables: time 0.0006782/0.4621, allocations: 0.593 MB / 0.5919 GB, free: 1.605 MB / 446.1 MB
Notification: Performance of sort global known variables: time 1.6e-07/0.4621, allocations: 0 / 0.5919 GB, free: 1.605 MB / 446.1 MB
Notification: Performance of remove unused functions: time 0.001106/0.4632, allocations: 299.9 kB / 0.5922 GB, free: 1.312 MB / 446.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 7
 * Number of states: 2 (mixingVolume1.medium.T,mixingVolume2.medium.T)
 * 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 (34):
 * Single equations (assignments): 32
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 2
 * 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.000715/0.4639, allocations: 333.5 kB / 0.5926 GB, free: 0.9844 MB / 446.1 MB
Notification: Performance of simCode: created initialization part: time 0.001004/0.4649, allocations: 0.634 MB / 0.5932 GB, free: 364 kB / 446.1 MB
Notification: Performance of simCode: created event and clocks part: time 5.26e-06/0.465, allocations: 4.938 kB / 0.5932 GB, free: 360 kB / 446.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0002223/0.4652, allocations: 148 kB / 0.5933 GB, free: 212 kB / 446.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001908/0.4671, allocations: 428.6 kB / 0.5937 GB, free: 15.8 MB / 462.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00226/0.4694, allocations: 1.527 MB / 0.5952 GB, free: 14.22 MB / 462.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005385/0.4699, allocations: 0.9971 MB / 0.5962 GB, free: 13.19 MB / 462.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001768/0.4701, allocations: 91.42 kB / 0.5963 GB, free: 13.1 MB / 462.1 MB
Notification: Performance of SimCode: time 5.31e-07/0.4701, allocations: 0 / 0.5963 GB, free: 13.1 MB / 462.1 MB
Notification: Performance of Templates: time 0.02179/0.4919, allocations: 14.87 MB / 0.6108 GB, free: 14.3 MB / 478.1 MB
make -j1 -f ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.makefile
(rm -f ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.sim & ./ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.pipe 2>&1)
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/ModelicaTest/Fluid/TestComponents/Vessels/TestMixingVolumes/TestMixingVolumes.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches