Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.1_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.WaterIF97_pT.conf.json
Using package ModelicaTest with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.1+maint.om/package.mo)
Using package ModelicaServices with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.1+maint.om/package.mo)
Using package Modelica with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+maint.om/package.mo)
Using package Complex with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.1+maint.om/package.mo)
Running command: translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.WaterIF97_pT,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volume.medium.T|volume.medium.p",fileNamePrefix="ModelicaTest_3.2.1_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.WaterIF97_pT")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.1+maint.om/package.mo): time 0.003455/0.00346, allocations: 354.8 kB / 15.95 MB, free: 6.215 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+maint.om/package.mo): time 3.61/3.61, allocations: 341.5 MB / 358.2 MB, free: 12.55 MB / 298.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.1+maint.om/package.mo): time 0.003523/0.003523, allocations: 225.2 kB / 397.9 MB, free: 4.906 MB / 330.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.1+maint.om/package.mo): time 0.5031/0.5031, allocations: 76.09 MB / 0.5014 GB, free: 112.6 MB / 362.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.001018/0.001018, allocations: 12 kB / 0.5592 GB, free: 53.44 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08856/0.08963, allocations: 48.71 MB / 0.6068 GB, free: 4.625 MB / 362.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.WaterIF97_pT): time 0.416/0.5057, allocations: 36.21 MB / 0.6421 GB, free: 70.12 MB / 362.7 MB
Notification: Performance of NFInst.instExpressions: time 0.03019/0.536, allocations: 18.67 MB / 0.6604 GB, free: 55.82 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001405/0.5374, allocations: 27.81 kB / 0.6604 GB, free: 55.81 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001475/0.5389, allocations: 362.4 kB / 0.6607 GB, free: 55.51 MB / 362.7 MB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+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.1+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.1+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.1+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.0189/0.5579, allocations: 4.057 MB / 0.6647 GB, free: 52.48 MB / 362.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.008499/0.5664, allocations: 1.994 MB / 0.6666 GB, free: 51.04 MB / 362.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003822/0.5702, allocations: 2.321 MB / 0.6689 GB, free: 49.45 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001079/0.5714, allocations: 392.1 kB / 0.6693 GB, free: 49.07 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01453/0.586, allocations: 6.291 MB / 0.6754 GB, free: 42.77 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01932/0.6053, allocations: 8.996 MB / 0.6842 GB, free: 33.77 MB / 362.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.000258/0.6056, allocations: 32 kB / 0.6842 GB, free: 33.74 MB / 362.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01932/0.625, allocations: 6.711 MB / 0.6908 GB, free: 27.02 MB / 362.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0005294/0.6256, allocations: 231.2 kB / 0.691 GB, free: 26.79 MB / 362.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003926/0.626, allocations: 189.3 kB / 0.6912 GB, free: 26.6 MB / 362.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.007868/0.6339, allocations: 3.34 MB / 0.6945 GB, free: 23.25 MB / 362.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.783e-06/0.6339, allocations: 4 kB / 0.6945 GB, free: 23.25 MB / 362.7 MB
Notification: Performance of FrontEnd: time 2.775e-06/0.6339, allocations: 0 / 0.6945 GB, free: 23.25 MB / 362.7 MB
Notification: Performance of Transformations before backend: time 1.645e-05/0.634, allocations: 3.938 kB / 0.6945 GB, free: 23.25 MB / 362.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 160
 * Number of variables: 160
Notification: Performance of Generate backend data structure: time 0.005386/0.6393, allocations: 1.453 MB / 0.6959 GB, free: 21.75 MB / 362.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.341e-05/0.6394, allocations: 8.031 kB / 0.6959 GB, free: 21.74 MB / 362.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00205/0.6415, allocations: 206.8 kB / 0.6961 GB, free: 21.54 MB / 362.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001273/0.6428, allocations: 0.5239 MB / 0.6966 GB, free: 20.97 MB / 362.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001299/0.643, allocations: 116.6 kB / 0.6967 GB, free: 20.84 MB / 362.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001819/0.6431, allocations: 67.98 kB / 0.6968 GB, free: 20.78 MB / 362.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002756/0.6459, allocations: 1.068 MB / 0.6978 GB, free: 19.61 MB / 362.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.438e-05/0.646, allocations: 7.938 kB / 0.6978 GB, free: 19.6 MB / 362.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00014/0.6462, allocations: 28 kB / 0.6978 GB, free: 19.57 MB / 362.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001011/0.6463, allocations: 40.98 kB / 0.6979 GB, free: 19.53 MB / 362.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002888/0.6492, allocations: 0.8193 MB / 0.6987 GB, free: 18.71 MB / 362.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.008206/0.6575, allocations: 5.532 MB / 0.7041 GB, free: 12.83 MB / 362.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001667/0.6592, allocations: 0.5232 MB / 0.7046 GB, free: 12.28 MB / 362.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004742/0.6597, allocations: 202.5 kB / 0.7048 GB, free: 12.07 MB / 362.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.2017/0.8614, allocations: 53.94 MB / 0.7575 GB, free: 15.67 MB / 426.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.358e-05/0.8615, allocations: 42.5 kB / 0.7575 GB, free: 15.62 MB / 426.7 MB
Notification: Performance of pre-optimization done (n=39): time 5.29e-06/0.8616, allocations: 0 / 0.7575 GB, free: 15.62 MB / 426.7 MB
Notification: Performance of matching and sorting (n=44): time 0.01339/0.875, allocations: 2.108 MB / 0.7596 GB, free: 13.46 MB / 426.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001648/0.8752, allocations: 188 kB / 0.7597 GB, free: 13.23 MB / 426.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001561/0.8768, allocations: 0.6211 MB / 0.7604 GB, free: 12.6 MB / 426.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001005/0.8769, allocations: 33.7 kB / 0.7604 GB, free: 12.56 MB / 426.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005539/0.8774, allocations: 414.8 kB / 0.7608 GB, free: 12.15 MB / 426.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002686/0.8777, allocations: 161.8 kB / 0.7609 GB, free: 11.99 MB / 426.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 7.33e-05/0.8778, allocations: 12 kB / 0.7609 GB, free: 11.98 MB / 426.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002796/0.8781, allocations: 382.8 kB / 0.7613 GB, free: 11.59 MB / 426.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009564/0.8791, allocations: 233.8 kB / 0.7615 GB, free: 11.36 MB / 426.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001389/0.8805, allocations: 0.6082 MB / 0.7621 GB, free: 10.57 MB / 426.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001234/0.8818, allocations: 347.3 kB / 0.7625 GB, free: 10.23 MB / 426.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.073e-05/0.8818, allocations: 8 kB / 0.7625 GB, free: 10.23 MB / 426.7 MB
Notification: Performance of matching and sorting (n=100) (initialization): time 0.003467/0.8853, allocations: 0.9129 MB / 0.7634 GB, free: 9.305 MB / 426.7 MB
Notification: Performance of prepare postOptimizeDAE: time 4.412e-05/0.8854, allocations: 8 kB / 0.7634 GB, free: 9.297 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.955e-05/0.8854, allocations: 16 kB / 0.7634 GB, free: 9.281 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006203/0.8916, allocations: 269 kB / 0.7636 GB, free: 9.016 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002454/0.8941, allocations: 295.5 kB / 0.7639 GB, free: 8.727 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002514/0.8967, allocations: 2.024 MB / 0.7659 GB, free: 6.555 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001099/0.8979, allocations: 59.98 kB / 0.766 GB, free: 6.496 MB / 426.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001145/0.898, allocations: 47.98 kB / 0.766 GB, free: 6.449 MB / 426.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 57
 * Number of states: 0 ()
 * Number of discrete variables: 10 (volume.regularFlow[1],volume.regularFlow[2],volume.inFlow[2],volume.inFlow[1],volume.medium.phase,shortPipe.flowModel.states[2].phase,shortPipe.flowModel.states[1].phase,ambient.state.phase,ambient.medium.phase,fixedMassFlowRate.medium.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (98):
 * Single equations (assignments): 96
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 1
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 1 {1}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {2 1}
Notification: Performance of prepare postOptimizeDAE: time 0.000599/0.8986, allocations: 285.4 kB / 0.7663 GB, free: 6.16 MB / 426.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002112/0.8988, allocations: 67.16 kB / 0.7663 GB, free: 6.094 MB / 426.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003917/0.9028, allocations: 2.016 MB / 0.7683 GB, free: 4.066 MB / 426.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.043e-05/0.9028, allocations: 3.984 kB / 0.7683 GB, free: 4.062 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.021e-06/0.9029, allocations: 4 kB / 0.7683 GB, free: 4.059 MB / 426.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007098/0.9036, allocations: 279.3 kB / 0.7686 GB, free: 3.766 MB / 426.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002316/0.9038, allocations: 14.39 kB / 0.7686 GB, free: 3.754 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008404/0.9047, allocations: 35.89 kB / 0.7686 GB, free: 3.719 MB / 426.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001213/0.9048, allocations: 32.39 kB / 0.7687 GB, free: 3.688 MB / 426.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.003848/0.9087, allocations: 1.382 MB / 0.77 GB, free: 2.297 MB / 426.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.003565/0.9123, allocations: 1.863 MB / 0.7718 GB, free: 424 kB / 426.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.2788/1.191, allocations: 0.4899 MB / 0.7723 GB, free: 146.3 MB / 426.7 MB
Notification: Performance of sorting global known variables: time 0.001636/1.193, allocations: 0.5469 MB / 0.7728 GB, free: 146.2 MB / 426.7 MB
Notification: Performance of Backend: time 1.583e-06/1.193, allocations: 1.656 kB / 0.7728 GB, free: 146.2 MB / 426.7 MB
Notification: Performance of simCode: created initialization part: time 0.01653/1.209, allocations: 1.973 MB / 0.7748 GB, free: 146.2 MB / 426.7 MB
Notification: Performance of SimCode: time 0.008027/1.218, allocations: 2.675 MB / 0.7774 GB, free: 144.8 MB / 426.7 MB
Notification: Performance of Templates: time 0.1194/1.337, allocations: 48.12 MB / 0.8244 GB, free: 113 MB / 426.7 MB