Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.2_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes.conf.json
Using package ModelicaTest with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo)
Using package ModelicaServices with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo)
Using package Modelica with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo)
Using package Complex with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/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_3.2.2_ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.004278/0.004277, allocations: 364.4 kB / 15.96 MB, free: 6.199 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo): time 4.072/4.072, allocations: 388.9 MB / 405.6 MB, free: 10.32 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.003514/0.003514, allocations: 228.1 kB / 451 MB, free: 145.1 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo): time 0.506/0.506, allocations: 80.03 MB / 0.5627 GB, free: 19.85 MB / 346.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0006161/0.0006164, allocations: 12 kB / 0.6265 GB, free: 96.54 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09302/0.09366, allocations: 54.86 MB / 0.6801 GB, free: 41.66 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Vessels.TestMixingVolumes): time 0.01927/0.113, allocations: 16.47 MB / 0.6962 GB, free: 25.13 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01015/0.1232, allocations: 6.611 MB / 0.7026 GB, free: 18.51 MB / 346.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009057/0.1242, allocations: 31.75 kB / 0.7027 GB, free: 18.48 MB / 346.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001059/0.1252, allocations: 294.2 kB / 0.7029 GB, free: 18.19 MB / 346.7 MB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:826:36-827:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:824:36-825:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:772:36-773:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:770:36-771:54:writable] Warning: 'each' used when modifying non-array element m_flow.
Notification: Performance of NFTyping.typeBindings: time 0.002161/0.1274, allocations: 0.6916 MB / 0.7036 GB, free: 17.49 MB / 346.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004198/0.1316, allocations: 1.049 MB / 0.7046 GB, free: 16.45 MB / 346.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003846/0.1355, allocations: 2.957 MB / 0.7075 GB, free: 13.48 MB / 346.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001095/0.1366, allocations: 0.5515 MB / 0.7081 GB, free: 12.92 MB / 346.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001935/0.1386, allocations: 0.8524 MB / 0.7089 GB, free: 12.07 MB / 346.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001572/0.1402, allocations: 0.7474 MB / 0.7096 GB, free: 11.32 MB / 346.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002584/0.1405, allocations: 48 kB / 0.7097 GB, free: 11.27 MB / 346.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00148/0.142, allocations: 299.6 kB / 0.71 GB, free: 10.98 MB / 346.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003876/0.1424, allocations: 327.1 kB / 0.7103 GB, free: 10.66 MB / 346.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003009/0.1427, allocations: 201.3 kB / 0.7105 GB, free: 10.45 MB / 346.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001522/0.1442, allocations: 1.072 MB / 0.7115 GB, free: 9.379 MB / 346.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.529e-06/0.1442, allocations: 4 kB / 0.7115 GB, free: 9.375 MB / 346.7 MB
Notification: Performance of FrontEnd: time 1.923e-06/0.1443, allocations: 0 / 0.7115 GB, free: 9.375 MB / 346.7 MB
Notification: Performance of Transformations before backend: time 2.331e-05/0.1443, allocations: 0 / 0.7115 GB, free: 9.375 MB / 346.7 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.002925/0.1472, allocations: 1.115 MB / 0.7126 GB, free: 8.211 MB / 346.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.683e-05/0.1473, allocations: 8.031 kB / 0.7126 GB, free: 8.203 MB / 346.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0005949/0.1479, allocations: 122.9 kB / 0.7127 GB, free: 8.082 MB / 346.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001212/0.1491, allocations: 0.59 MB / 0.7133 GB, free: 7.453 MB / 346.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002723/0.1494, allocations: 252.2 kB / 0.7135 GB, free: 7.168 MB / 346.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002846/0.1497, allocations: 76 kB / 0.7136 GB, free: 7.094 MB / 346.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004966/0.1547, allocations: 1.296 MB / 0.7149 GB, free: 5.68 MB / 346.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.081e-05/0.1548, allocations: 3.938 kB / 0.7149 GB, free: 5.676 MB / 346.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002741/0.1551, allocations: 44 kB / 0.7149 GB, free: 5.633 MB / 346.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001091/0.1552, allocations: 41.27 kB / 0.715 GB, free: 5.59 MB / 346.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00419/0.1594, allocations: 0.961 MB / 0.7159 GB, free: 4.629 MB / 346.7 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.3728/0.5323, allocations: 7.029 MB / 0.7228 GB, free: 43.12 MB / 346.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0008644/0.5332, allocations: 293.5 kB / 0.7231 GB, free: 43.09 MB / 346.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004905/0.5337, allocations: 156.7 kB / 0.7232 GB, free: 43.08 MB / 346.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.00372/0.5375, allocations: 3.032 MB / 0.7262 GB, free: 40.18 MB / 346.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.177e-05/0.5376, allocations: 38.62 kB / 0.7262 GB, free: 40.14 MB / 346.7 MB
Notification: Performance of pre-optimization done (n=19): time 3.547e-06/0.5376, allocations: 0 / 0.7262 GB, free: 40.14 MB / 346.7 MB
Notification: Performance of matching and sorting (n=25): time 0.00289/0.5405, allocations: 0.9113 MB / 0.7271 GB, free: 39.72 MB / 346.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.523e-05/0.5405, allocations: 52.72 kB / 0.7271 GB, free: 39.66 MB / 346.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001481/0.542, allocations: 0.7957 MB / 0.7279 GB, free: 39.41 MB / 346.7 MB
Notification: Performance of collectPreVariables (initialization): time 7.998e-05/0.5422, allocations: 32.05 kB / 0.7279 GB, free: 39.37 MB / 346.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004908/0.5427, allocations: 0.6314 MB / 0.7286 GB, free: 38.87 MB / 346.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001234/0.5428, allocations: 107 kB / 0.7287 GB, free: 38.79 MB / 346.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 5.871e-05/0.5429, allocations: 29.28 kB / 0.7287 GB, free: 38.77 MB / 346.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001633/0.543, allocations: 388.1 kB / 0.7291 GB, free: 38.43 MB / 346.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005693/0.5436, allocations: 228.9 kB / 0.7293 GB, free: 38.37 MB / 346.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009234/0.5446, allocations: 1.051 MB / 0.7303 GB, free: 37.2 MB / 346.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001084/0.5457, allocations: 446.4 kB / 0.7307 GB, free: 37.07 MB / 346.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.996e-05/0.5457, allocations: 15.47 kB / 0.7308 GB, free: 37.07 MB / 346.7 MB
Notification: Performance of matching and sorting (n=163) (initialization): time 0.002986/0.5487, allocations: 1.233 MB / 0.732 GB, free: 36.58 MB / 346.7 MB
Notification: Performance of prepare postOptimizeDAE: time 4.874e-05/0.5488, allocations: 21.27 kB / 0.732 GB, free: 36.58 MB / 346.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.586e-05/0.5489, allocations: 30.5 kB / 0.732 GB, free: 36.58 MB / 346.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 7.742e-05/0.549, allocations: 43.86 kB / 0.732 GB, free: 36.58 MB / 346.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002288/0.5513, allocations: 292.2 kB / 0.7323 GB, free: 36.54 MB / 346.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001252/0.5515, allocations: 56.75 kB / 0.7324 GB, free: 36.53 MB / 346.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002165/0.5536, allocations: 78.8 kB / 0.7325 GB, free: 36.5 MB / 346.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002864/0.554, allocations: 77.89 kB / 0.7325 GB, free: 36.49 MB / 346.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 135
 * 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 (163):
 * Single equations (assignments): 163
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001017/0.555, allocations: 337.8 kB / 0.7329 GB, free: 36.26 MB / 346.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003067/0.5553, allocations: 77.69 kB / 0.7329 GB, free: 36.22 MB / 346.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001423/0.5568, allocations: 350.1 kB / 0.7333 GB, free: 36.06 MB / 346.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.38e-05/0.5568, allocations: 5.312 kB / 0.7333 GB, free: 36.05 MB / 346.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.55e-06/0.5569, allocations: 0 / 0.7333 GB, free: 36.05 MB / 346.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005975/0.5575, allocations: 167.8 kB / 0.7334 GB, free: 35.91 MB / 346.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002462/0.5578, allocations: 7.75 kB / 0.7334 GB, free: 35.91 MB / 346.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008769/0.5586, allocations: 14.08 kB / 0.7334 GB, free: 35.9 MB / 346.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 9.348e-05/0.5588, allocations: 18.05 kB / 0.7335 GB, free: 35.9 MB / 346.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001242/0.56, allocations: 341 kB / 0.7338 GB, free: 35.71 MB / 346.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.00151/0.5616, allocations: 0.5634 MB / 0.7343 GB, free: 35.36 MB / 346.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0003415/0.562, allocations: 96.36 kB / 0.7344 GB, free: 35.32 MB / 346.7 MB
Notification: Performance of sorting global known variables: time 0.001526/0.5635, allocations: 0.4889 MB / 0.7349 GB, free: 35.12 MB / 346.7 MB
Notification: Performance of Backend: time 1.383e-06/0.5636, allocations: 0 / 0.7349 GB, free: 35.12 MB / 346.7 MB
Notification: Performance of simCode: created initialization part: time 0.004071/0.5677, allocations: 0.8493 MB / 0.7357 GB, free: 34.84 MB / 346.7 MB
Notification: Performance of SimCode: time 0.007043/0.5748, allocations: 2.321 MB / 0.738 GB, free: 33.39 MB / 346.7 MB
Notification: Performance of Templates: time 0.0401/0.6149, allocations: 11.13 MB / 0.7489 GB, free: 29.05 MB / 346.7 MB