Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.1_ModelicaTest.Fluid.TestPipesAndValves.BranchingPipes4.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.Fluid.TestPipesAndValves.BranchingPipes4,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|pipe1.mediums.1..h|pipe1.mediums.1..p|pipe2.mediums.1..h|pipe2.mediums.1..p|pipe3.mediums.1..h|pipe3.mediums.1..p|splitter.medium.h|splitter.medium.p|valveOpening1.a|valveOpening1.b|valveOpening1.last|valveOpening1.nextEvent|valveOpening2.a|valveOpening2.b|valveOpening2.last|valveOpening2.nextEvent",fileNamePrefix="ModelicaTest_3.2.1_ModelicaTest.Fluid.TestPipesAndValves.BranchingPipes4")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.1+maint.om/package.mo): time 0.003632/0.003632, allocations: 348.7 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.37/3.37, allocations: 341.5 MB / 358.2 MB, free: 12.64 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.003521/0.003521, allocations: 229.1 kB / 397.9 MB, free: 4.98 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.475/0.475, allocations: 76.1 MB / 0.5014 GB, free: 112.6 MB / 362.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004845/0.0004847, allocations: 12 kB / 0.5592 GB, free: 53.48 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08373/0.08424, allocations: 48.71 MB / 0.6067 GB, free: 4.66 MB / 362.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestPipesAndValves.BranchingPipes4): time 0.3729/0.4572, allocations: 95.21 MB / 0.6997 GB, free: 17.8 MB / 362.7 MB
Notification: Performance of NFInst.instExpressions: time 0.06563/0.5229, allocations: 36.59 MB / 0.7354 GB, free: 13.18 MB / 394.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003549/0.5265, allocations: 103.2 kB / 0.7355 GB, free: 13.08 MB / 394.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.004965/0.5315, allocations: 1.08 MB / 0.7366 GB, free: 12 MB / 394.7 MB
[/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.
[/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.02857/0.5601, allocations: 6.878 MB / 0.7433 GB, free: 5.121 MB / 394.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.08426/0.6444, allocations: 7.534 MB / 0.7507 GB, free: 13.64 MB / 410.7 MB
Notification: Performance of NFFlatten.flatten: time 0.0142/0.6586, allocations: 8.192 MB / 0.7587 GB, free: 5.426 MB / 410.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004308/0.663, allocations: 1.26 MB / 0.7599 GB, free: 4.18 MB / 410.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.006739/0.6698, allocations: 2.23 MB / 0.7621 GB, free: 1.941 MB / 410.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.3669/1.037, allocations: 19.3 MB / 0.7809 GB, free: 18.66 MB / 410.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.001056/1.038, allocations: 114.9 kB / 0.781 GB, free: 18.66 MB / 410.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.02748/1.065, allocations: 9.51 MB / 0.7903 GB, free: 18.64 MB / 410.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.002031/1.067, allocations: 0.8125 MB / 0.7911 GB, free: 18.64 MB / 410.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001573/1.069, allocations: 0.5297 MB / 0.7916 GB, free: 18.61 MB / 410.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.01498/1.084, allocations: 6.528 MB / 0.798 GB, free: 18.38 MB / 410.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.533e-06/1.084, allocations: 1.172 kB / 0.798 GB, free: 18.38 MB / 410.7 MB
Notification: Performance of FrontEnd: time 2.855e-06/1.084, allocations: 4 kB / 0.798 GB, free: 18.38 MB / 410.7 MB
Notification: Performance of Transformations before backend: time 0.0001276/1.084, allocations: 0 / 0.798 GB, free: 18.38 MB / 410.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 532
 * Number of variables: 532
Notification: Performance of Generate backend data structure: time 0.01382/1.098, allocations: 3.654 MB / 0.8016 GB, free: 16.64 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.143e-05/1.098, allocations: 10.11 kB / 0.8016 GB, free: 16.64 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008165/1.106, allocations: 0.8378 MB / 0.8024 GB, free: 16.5 MB / 410.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01705/1.123, allocations: 6.416 MB / 0.8087 GB, free: 14.01 MB / 410.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005966/1.124, allocations: 182.9 kB / 0.8088 GB, free: 13.91 MB / 410.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001349/1.126, allocations: 192.1 kB / 0.809 GB, free: 13.79 MB / 410.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01508/1.141, allocations: 3.595 MB / 0.8125 GB, free: 11.43 MB / 410.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 8.089e-05/1.141, allocations: 10.91 kB / 0.8126 GB, free: 11.42 MB / 410.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003491/1.141, allocations: 86.55 kB / 0.8126 GB, free: 11.4 MB / 410.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003743/1.142, allocations: 201.1 kB / 0.8128 GB, free: 11.23 MB / 410.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01262/1.154, allocations: 3.407 MB / 0.8162 GB, free: 8.797 MB / 410.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.02912/1.183, allocations: 15.23 MB / 0.831 GB, free: 10.72 MB / 426.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.009349/1.193, allocations: 2.259 MB / 0.8332 GB, free: 9.109 MB / 426.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003542/1.196, allocations: 1.103 MB / 0.8343 GB, free: 8.383 MB / 426.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.06658/1.263, allocations: 19.09 MB / 0.853 GB, free: 3.422 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.008932/1.272, allocations: 1.658 MB / 0.8546 GB, free: 2.125 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=159): time 7.444e-06/1.272, allocations: 0 / 0.8546 GB, free: 2.125 MB / 442.7 MB
Notification: Performance of matching and sorting (n=175): time 0.04276/1.315, allocations: 8.742 MB / 0.8631 GB, free: 11.41 MB / 458.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001426/1.315, allocations: 182.6 kB / 0.8633 GB, free: 11.21 MB / 458.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003632/1.319, allocations: 1.564 MB / 0.8648 GB, free: 10.03 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0005139/1.319, allocations: 59.64 kB / 0.8649 GB, free: 9.992 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001405/1.321, allocations: 1.023 MB / 0.8659 GB, free: 9.328 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001175/1.322, allocations: 0.5878 MB / 0.8664 GB, free: 8.828 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002572/1.322, allocations: 41.56 kB / 0.8665 GB, free: 8.82 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002588/1.322, allocations: 390.1 kB / 0.8669 GB, free: 8.465 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003931/1.326, allocations: 1.096 MB / 0.8679 GB, free: 7.723 MB / 458.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005824/1.332, allocations: 1.609 MB / 0.8695 GB, free: 6.301 MB / 458.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004551/1.337, allocations: 1.345 MB / 0.8708 GB, free: 5.426 MB / 458.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.372e-05/1.337, allocations: 4 kB / 0.8708 GB, free: 5.422 MB / 458.7 MB
Notification: Performance of matching and sorting (n=268) (initialization): time 0.01152/1.348, allocations: 3.286 MB / 0.874 GB, free: 3.105 MB / 458.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0005905/1.349, allocations: 0.5838 MB / 0.8746 GB, free: 2.363 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.518e-05/1.349, allocations: 16 kB / 0.8746 GB, free: 2.348 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001542/1.351, allocations: 251.6 kB / 0.8749 GB, free: 2.141 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004483/1.355, allocations: 0.5343 MB / 0.8754 GB, free: 1.66 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002432/1.358, allocations: 2.438 MB / 0.8778 GB, free: 15.28 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003939/1.362, allocations: 271.8 kB / 0.878 GB, free: 15.05 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005225/1.362, allocations: 108.2 kB / 0.8781 GB, free: 14.96 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 1.403e-05/1.362, allocations: 4 kB / 0.8781 GB, free: 14.96 MB / 474.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.01005/1.372, allocations: 3.026 MB / 0.8811 GB, free: 12.9 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.899e-05/1.372, allocations: 16 kB / 0.8811 GB, free: 12.89 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001421/1.374, allocations: 252.9 kB / 0.8813 GB, free: 12.68 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004563/1.378, allocations: 0.529 MB / 0.8818 GB, free: 12.2 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002907/1.381, allocations: 2.433 MB / 0.8842 GB, free: 9.828 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00475/1.386, allocations: 263.1 kB / 0.8845 GB, free: 9.602 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000448/1.387, allocations: 104.4 kB / 0.8846 GB, free: 9.52 MB / 474.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 66
 * Number of states: 0 ()
 * Number of discrete variables: 28 (pipe3.state_a.phase,pipe2.state_a.phase,pipe1.state_b.phase,pipe1.state_a.phase,valveIncompressible1.state_b.phase,valveIncompressible1.state_a.phase,valveIncompressible.state_b.phase,valveIncompressible.state_a.phase,$whenCondition1,$whenCondition2,source.medium.phase,pipe1.statesFM[2].phase,sink.medium.phase,pipe2.statesFM[2].phase,pipe3.statesFM[2].phase,valveOpening1.a,valveOpening1.b,valveOpening1.last,$PRE.valveOpening1.nextEvent,valveOpening1.nextEvent,valveOpening2.a,valveOpening2.b,valveOpening2.last,$PRE.valveOpening2.nextEvent,valveOpening2.nextEvent,splitter.medium.phase,pipe3.state_b.phase,pipe2.state_b.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (256):
 * Single equations (assignments): 250
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 4 {1 1,1 1,1 1,1 1}
Notification: Performance of prepare postOptimizeDAE: time 0.002664/1.389, allocations: 0.76 MB / 0.8853 GB, free: 8.973 MB / 474.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008465/1.39, allocations: 201.5 kB / 0.8855 GB, free: 8.773 MB / 474.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02439/1.415, allocations: 7.576 MB / 0.8929 GB, free: 3.637 MB / 474.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01992/1.435, allocations: 6.604 MB / 0.8994 GB, free: 15.05 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.733e-05/1.435, allocations: 0 / 0.8994 GB, free: 15.05 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.002817/1.437, allocations: 0.7187 MB / 0.9001 GB, free: 14.43 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009528/1.438, allocations: 46.06 kB / 0.9001 GB, free: 14.41 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004231/1.443, allocations: 259.1 kB / 0.9003 GB, free: 14.19 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006114/1.443, allocations: 144.6 kB / 0.9005 GB, free: 14.07 MB / 490.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0165/1.46, allocations: 4.754 MB / 0.9051 GB, free: 10.6 MB / 490.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.01586/1.476, allocations: 6.736 MB / 0.9117 GB, free: 6.004 MB / 490.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004924/1.481, allocations: 2.145 MB / 0.9138 GB, free: 4.77 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.002565/1.483, allocations: 1.161 MB / 0.9149 GB, free: 3.891 MB / 490.7 MB
Notification: Performance of Backend: time 8.42e-07/1.483, allocations: 0 / 0.9149 GB, free: 3.891 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.02575/1.509, allocations: 6.07 MB / 0.9209 GB, free: 15.2 MB / 0.4948 GB
Notification: Performance of SimCode: time 0.0179/1.527, allocations: 7.214 MB / 0.9279 GB, free: 8.5 MB / 0.4948 GB
Notification: Performance of Templates: time 0.6185/2.146, allocations: 94.91 MB / 1.021 GB, free: 182.2 MB / 0.5105 GB