Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.1_ModelicaTest.Fluid.TestPipesAndValves.SeriesPipes1.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: "" <> buildModelFMU(ModelicaTest.Fluid.TestPipesAndValves.SeriesPipes1,fileNamePrefix="ModelicaTest_3_2_1_ModelicaTest_Fluid_TestPipesAndValves_SeriesPipes1",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.1+maint.om/package.mo): time 0.01015/0.01016, allocations: 166.2 kB / 15.11 MB, free: 5.93 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 1.729/1.729, allocations: 170.8 MB / 186.6 MB, free: 1.133 MB / 154.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.1+maint.om/package.mo): time 0.001993/0.001993, allocations: 106.5 kB / 226.2 MB, free: 10.11 MB / 186.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.1+maint.om/package.mo): time 0.4427/0.4427, allocations: 38.05 MB / 303.7 MB, free: 42.41 MB / 250.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004858/0.0004857, allocations: 12 kB / 363 MB, free: 5.496 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1073/0.1078, allocations: 48.71 MB / 411.7 MB, free: 4.711 MB / 314.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 8.203/8.311, allocations: 2.547 GB / 2.95 GB, free: 62.39 MB / 442.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0003153/8.311, allocations: 78.88 kB / 2.95 GB, free: 62.36 MB / 442.7 MB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+maint.om/Fluid/Interfaces.mo:734:9-739:39:writable] Notification: From here:
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+maint.om/Fluid/Interfaces.mo:327:3-329:69:writable] Warning: Inherited elements are not identical: bug: https://trac.modelica.org/Modelica/ticket/627
        first:  Medium.MassFlowRate m_flow(min = if allowFlowReversal then -Modelica.Constants.inf else 0, start = m_flow_start, stateSelect = if momentumDynamics == Modelica.Fluid.Types.Dynamics.SteadyState then StateSelect.default else StateSelect.prefer) "mass flow rates between states"
        second: Medium.MassFlowRate m_flow(min = if allowFlowReversal then -Modelica.Constants.inf else 0, start = m_flow_start) "Mass flow rate in design flow direction"
Continue ....
Notification: Performance of FrontEnd - DAE generated: time 4.578/12.89, allocations: 0.7799 GB / 3.73 GB, free: 29.31 MB / 474.7 MB
Notification: Performance of FrontEnd: time 2.825e-06/12.89, allocations: 0 / 3.73 GB, free: 29.31 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 0.0003074/12.89, allocations: 64.97 kB / 3.73 GB, free: 29.3 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 510
 * Number of variables: 510
Notification: Performance of Generate backend data structure: time 0.01932/12.91, allocations: 4.018 MB / 3.733 GB, free: 27.51 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.914e-05/12.91, allocations: 9.594 kB / 3.733 GB, free: 27.51 MB / 474.7 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001497/12.91, allocations: 299.2 kB / 3.734 GB, free: 27.46 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009019/12.92, allocations: 0.8215 MB / 3.735 GB, free: 27.33 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00836/12.93, allocations: 2.102 MB / 3.737 GB, free: 26.89 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002957/12.93, allocations: 179.2 kB / 3.737 GB, free: 26.88 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008583/12.93, allocations: 195 kB / 3.737 GB, free: 26.88 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01217/12.94, allocations: 3.579 MB / 3.74 GB, free: 26.36 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 9.099e-05/12.94, allocations: 12.62 kB / 3.741 GB, free: 26.35 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004076/12.94, allocations: 86 kB / 3.741 GB, free: 26.35 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002966/12.94, allocations: 165.4 kB / 3.741 GB, free: 26.35 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01316/12.96, allocations: 3.285 MB / 3.744 GB, free: 26.33 MB / 474.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.03046/12.99, allocations: 15.04 MB / 3.759 GB, free: 17.34 MB / 474.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.009387/13, allocations: 2.306 MB / 3.761 GB, free: 17.3 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.06007/13.06, allocations: 18.79 MB / 3.779 GB, free: 21.56 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007984/13.06, allocations: 1.639 MB / 3.781 GB, free: 21.47 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=153): time 5.871e-06/13.06, allocations: 1.906 kB / 3.781 GB, free: 21.47 MB / 490.7 MB
Notification: Performance of matching and sorting (n=169): time 0.04276/13.11, allocations: 8.846 MB / 3.789 GB, free: 20.45 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002113/13.11, allocations: 282.3 kB / 3.79 GB, free: 20.14 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003353/13.11, allocations: 1.418 MB / 3.791 GB, free: 19.96 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0006115/13.11, allocations: 53.39 kB / 3.791 GB, free: 19.93 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001117/13.11, allocations: 0.9849 MB / 3.792 GB, free: 19.52 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.00098/13.11, allocations: 0.5703 MB / 3.793 GB, free: 19.15 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002785/13.11, allocations: 46.5 kB / 3.793 GB, free: 19.15 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.000231/13.11, allocations: 387.9 kB / 3.793 GB, free: 18.84 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003668/13.12, allocations: 1.151 MB / 3.794 GB, free: 18.83 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004235/13.12, allocations: 1.644 MB / 3.796 GB, free: 18.28 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004348/13.13, allocations: 1.375 MB / 3.797 GB, free: 18.23 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.086e-05/13.13, allocations: 3.312 kB / 3.797 GB, free: 18.23 MB / 490.7 MB
Notification: Performance of matching and sorting (n=261) (initialization): time 0.01117/13.14, allocations: 3.333 MB / 3.8 GB, free: 17.98 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.002582/13.14, allocations: 5.325 MB / 3.806 GB, free: 12.37 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001083/13.14, allocations: 17.84 kB / 3.806 GB, free: 12.37 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001688/13.14, allocations: 280.3 kB / 3.806 GB, free: 12.34 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004537/13.15, allocations: 0.5135 MB / 3.806 GB, free: 12.34 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002368/13.15, allocations: 2.493 MB / 3.809 GB, free: 10.27 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004183/13.15, allocations: 264.6 kB / 3.809 GB, free: 10.25 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007379/13.15, allocations: 102.9 kB / 3.809 GB, free: 10.25 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 1.878e-05/13.15, allocations: 7.406 kB / 3.809 GB, free: 10.25 MB / 490.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.01073/13.16, allocations: 3.065 MB / 3.812 GB, free: 10.14 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.558e-05/13.16, allocations: 20.11 kB / 3.812 GB, free: 10.14 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001487/13.17, allocations: 279.1 kB / 3.813 GB, free: 10.11 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004362/13.17, allocations: 0.5096 MB / 3.813 GB, free: 10.11 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002415/13.17, allocations: 2.5 MB / 3.815 GB, free: 8.039 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004044/13.18, allocations: 264.2 kB / 3.816 GB, free: 8.023 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00065/13.18, allocations: 102.1 kB / 3.816 GB, free: 8.023 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 59
 * Number of states: 0 ()
 * Number of discrete variables: 22 (pipe3.state_a.phase,simpleGenericOrifice.state_b.phase,simpleGenericOrifice.state_a.phase,valve.state_b.phase,valve.state_a.phase,pipe1.state_a.phase,$whenCondition1,source.medium.phase,pipe1.statesFM[2].phase,sink.medium.phase,pipe2.statesFM[1].phase,pipe2.statesFM[2].phase,valveOpening1.a,valveOpening1.b,valveOpening1.last,$PRE.valveOpening1.nextEvent,valveOpening1.nextEvent,pipe3.statesFM[2].phase,pipe3.state_b.phase,pipe2.state_b.phase,pipe2.state_a.phase,pipe1.state_b.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (251):
 * Single equations (assignments): 244
 * Array equations: 1
 * Algorithm blocks: 1
 * Record equations: 1
 * 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.002287/13.18, allocations: 0.7222 MB / 3.816 GB, free: 7.789 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006423/13.18, allocations: 206.4 kB / 3.817 GB, free: 7.688 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01719/13.2, allocations: 7.771 MB / 3.824 GB, free: 6.922 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.568e-05/13.2, allocations: 88.83 kB / 3.824 GB, free: 6.922 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.12e-05/13.2, allocations: 0 / 3.824 GB, free: 6.922 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0134/13.21, allocations: 6.678 MB / 3.831 GB, free: 6.098 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02282/13.23, allocations: 10.91 MB / 3.842 GB, free: 5.344 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.314e-05/13.23, allocations: 1.156 kB / 3.842 GB, free: 5.344 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003116/13.24, allocations: 408.1 kB / 3.842 GB, free: 5.344 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002328/13.24, allocations: 380 kB / 3.842 GB, free: 5.305 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002414/13.24, allocations: 63.48 kB / 3.842 GB, free: 5.305 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002364/13.24, allocations: 2.57 MB / 3.845 GB, free: 19.23 MB / 0.4948 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.879e-06/13.24, allocations: 7.453 kB / 3.845 GB, free: 19.23 MB / 0.4948 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01616/13.26, allocations: 7.225 MB / 3.852 GB, free: 18.59 MB / 0.4948 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002137/13.26, allocations: 0.7404 MB / 3.853 GB, free: 18.26 MB / 0.4948 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005317/13.26, allocations: 53.14 kB / 3.853 GB, free: 18.26 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002468/13.26, allocations: 209.1 kB / 3.853 GB, free: 18.24 MB / 0.4948 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004195/13.26, allocations: 145.9 kB / 3.853 GB, free: 18.24 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002046/13.26, allocations: 67.55 kB / 3.853 GB, free: 18.24 MB / 0.4948 GB
Notification: Performance of sorting global known variables: time 0.002371/13.27, allocations: 1.186 MB / 3.854 GB, free: 18.09 MB / 0.4948 GB
Notification: Performance of sort global known variables: time 4.9e-07/13.27, allocations: 0 / 3.854 GB, free: 18.09 MB / 0.4948 GB
Notification: Performance of remove unused functions: time 0.01151/13.28, allocations: 2.742 MB / 3.857 GB, free: 17.7 MB / 0.4948 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 10 (pipe1.mediums[1].p,pipe1.mediums[1].h,valve.filter.x[1],valve.filter.x[2],pipe2.mediums[1].p,pipe2.mediums[1].h,pipe2.mediums[2].p,pipe2.mediums[2].h,pipe3.mediums[1].p,pipe3.mediums[1].h)
 * Number of discrete variables: 53 ($cse3.phase,$cse3.region,$cse5.region3boundary,$cse6.region3boundary,$cse7.phase,$cse7.region,$cse9.phase,$cse9.region,$cse10.phase,$cse10.region,$cse15.phase,$cse15.region,$cse16.phase,$cse16.region,$cse18.phase,$cse18.region,$cse19.phase,$cse19.region,$cse22.phase,$cse22.region,$cse24.region3boundary,$cse25.region3boundary,$cse26.phase,$cse26.region,$cse27.region3boundary,$cse28.region3boundary,$cse29.phase,$cse29.region,$cse31.phase,$cse31.region,$cse32.phase,$cse32.region,$cse37.phase,$cse37.region,$cse38.phase,$cse38.region,$cse39.region3boundary,$cse40.region3boundary,$cse41.phase,$cse41.region,$cse43.phase,$cse43.region,$whenCondition1,source.medium.phase,pipe1.statesFM[2].phase,sink.medium.phase,pipe2.statesFM[1].phase,pipe2.statesFM[2].phase,valveOpening1.a,valveOpening1.b,valveOpening1.last,valveOpening1.nextEvent,pipe3.statesFM[2].phase)
 * Number of discrete states: 5 (valveOpening1.last,valveOpening1.nextEvent,valveOpening1.b,valveOpening1.a,$whenCondition1)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (197):
 * Single equations (assignments): 167
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 25
 * 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 Backend phase and start with SimCode phase: time 0.03016/13.31, allocations: 10.46 MB / 3.867 GB, free: 14.44 MB / 0.4948 GB
Notification: Performance of simCode: created initialization part: time 0.01546/13.32, allocations: 5.23 MB / 3.872 GB, free: 12.6 MB / 0.4948 GB
Notification: Performance of simCode: created event and clocks part: time 1.125e-05/13.32, allocations: 3 kB / 3.872 GB, free: 12.6 MB / 0.4948 GB
Notification: Performance of simCode: created simulation system equations: time 0.006294/13.33, allocations: 2.418 MB / 3.875 GB, free: 11.58 MB / 0.4948 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.008017/13.34, allocations: 0.8743 MB / 3.875 GB, free: 11.35 MB / 0.4948 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.8657/14.2, allocations: 140 MB / 4.012 GB, free: 35.75 MB / 0.4948 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002993/14.21, allocations: 2.306 MB / 4.014 GB, free: 34.94 MB / 0.4948 GB
Notification: Performance of simCode: alias equations: time 0.007743/14.22, allocations: 2.642 MB / 4.017 GB, free: 34.85 MB / 0.4948 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00308/14.22, allocations: 1.321 MB / 4.018 GB, free: 34.84 MB / 0.4948 GB
Notification: Performance of SimCode: time 1.423e-06/14.22, allocations: 0 / 4.018 GB, free: 34.84 MB / 0.4948 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.6871/14.91, allocations: 110.9 MB / 4.127 GB, free: 51.26 MB / 0.4948 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0002589/14.91, allocations: 255.8 kB / 4.127 GB, free: 50.93 MB / 0.4948 GB
Notification: Performance of buildModelFMU: Generate platform static: time 8.718/23.62, allocations: 1.094 kB / 4.127 GB, free: 50.93 MB / 0.4948 GB
TODO: How the !@#!# did the simulation report success but simulation result ModelicaTest_3.2.1_ModelicaTest.Fluid.TestPipesAndValves.SeriesPipes1_res.mat does not exist to compare? outputFormat=mat