Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.conf.json
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/ModelicaServices trunk/package.mo", uses=false)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 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)
Running command: translateModel(Modelica.Fluid.Examples.InverseParameterization,tolerance=1e-06,outputFormat="mat",numberOfIntervals=20000,variableFilter="time|sink.ports.1..m_flow|sink1.ports.1..m_flow|sink2.ports.1..m_flow|pipe1.flowModel.dps_fg_turbulent.1.|pipe1.flowModel.pathLengths_nominal.1.|pipe2.flowModel.pathLengths_nominal.1.",fileNamePrefix="Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization")
translateModel(Modelica.Fluid.Examples.InverseParameterization,tolerance=1e-06,outputFormat="mat",numberOfIntervals=20000,variableFilter="time|sink.ports.1..m_flow|sink1.ports.1..m_flow|sink2.ports.1..m_flow|pipe1.flowModel.dps_fg_turbulent.1.|pipe1.flowModel.pathLengths_nominal.1.|pipe2.flowModel.pathLengths_nominal.1.",fileNamePrefix="Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001123/0.001123, allocations: 201.5 kB / 17.08 MB, free: 5.062 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.26/1.26, allocations: 227.2 MB / 245 MB, free: 7.605 MB / 202.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.0009198/0.0009201, allocations: 98.02 kB / 297.3 MB, free: 6.195 MB / 234.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.000221/0.000221, allocations: 4 kB / 357.9 MB, free: 9.547 MB / 298.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2442/0.2444, allocations: 51.48 MB / 409.4 MB, free: 26.74 MB / 298.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 5.675/5.919, allocations: 2.814 GB / 3.214 GB, free: 89.56 MB / 458.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002529/5.92, allocations: 79.84 kB / 3.214 GB, free: 89.52 MB / 458.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/Fluid/Interfaces.mo:714:9-719:39:writable] Notification: From here:
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/Fluid/Interfaces.mo:306:3-308: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 2.425/8.344, allocations: 0.7584 GB / 3.972 GB, free: 24.23 MB / 474.7 MB
Notification: Performance of FrontEnd: time 2.034e-06/8.344, allocations: 3.969 kB / 3.972 GB, free: 24.23 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 0.0001475/8.344, allocations: 36.3 kB / 3.972 GB, free: 24.23 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: 287
 * Number of variables: 287
Notification: Performance of Generate backend data structure: time 0.01198/8.356, allocations: 2.884 MB / 3.975 GB, free: 22.49 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.634e-05/8.357, allocations: 8.031 kB / 3.975 GB, free: 22.48 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004973/8.362, allocations: 0.6083 MB / 3.976 GB, free: 22.01 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002763/8.364, allocations: 1.075 MB / 3.977 GB, free: 21.12 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001325/8.364, allocations: 92.48 kB / 3.977 GB, free: 21.04 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004064/8.365, allocations: 109.1 kB / 3.977 GB, free: 20.94 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005171/8.37, allocations: 2.277 MB / 3.979 GB, free: 19.05 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.657e-05/8.37, allocations: 4 kB / 3.979 GB, free: 19.05 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001663/8.37, allocations: 49.23 kB / 3.979 GB, free: 19.01 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.32e-05/8.37, allocations: 97.19 kB / 3.979 GB, free: 18.92 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004983/8.375, allocations: 2.398 MB / 3.982 GB, free: 16.95 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.0107/8.386, allocations: 9.198 MB / 3.991 GB, free: 7.902 MB / 474.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00449/8.391, allocations: 1.891 MB / 3.993 GB, free: 6.008 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.3285/8.719, allocations: 27 MB / 4.019 GB, free: 38.08 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.116e-05/8.719, allocations: 47.88 kB / 4.019 GB, free: 38.04 MB / 490.7 MB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001398/8.721, allocations: 209.9 kB / 4.019 GB, free: 38.04 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=109): time 3.968e-06/8.721, allocations: 0 / 4.019 GB, free: 38.04 MB / 490.7 MB
Notification: Performance of matching and sorting (n=109): time 0.01679/8.737, allocations: 4.453 MB / 4.024 GB, free: 37.88 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.738e-05/8.738, allocations: 224 kB / 4.024 GB, free: 37.63 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001071/8.739, allocations: 0.8398 MB / 4.025 GB, free: 37.48 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001206/8.739, allocations: 48.73 kB / 4.025 GB, free: 37.45 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003219/8.739, allocations: 0.5579 MB / 4.025 GB, free: 37.25 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002771/8.739, allocations: 375.1 kB / 4.026 GB, free: 37.07 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 7.525e-05/8.739, allocations: 23.19 kB / 4.026 GB, free: 37.07 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 7.576e-05/8.74, allocations: 355.7 kB / 4.026 GB, free: 36.76 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001185/8.741, allocations: 0.6488 MB / 4.027 GB, free: 36.76 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001285/8.742, allocations: 1.019 MB / 4.028 GB, free: 36.37 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001277/8.743, allocations: 0.7612 MB / 4.028 GB, free: 36.36 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.488e-06/8.743, allocations: 7.016 kB / 4.028 GB, free: 36.36 MB / 490.7 MB
Notification: Performance of matching and sorting (n=158) (initialization): time 0.00359/8.747, allocations: 2.081 MB / 4.03 GB, free: 36.23 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0005666/8.748, allocations: 3.741 MB / 4.034 GB, free: 32.34 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.381e-05/8.748, allocations: 11.12 kB / 4.034 GB, free: 32.34 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02787/8.775, allocations: 3.066 MB / 4.037 GB, free: 32.32 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001218/8.777, allocations: 280.7 kB / 4.037 GB, free: 32.32 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002411/8.779, allocations: 1.443 MB / 4.039 GB, free: 31.79 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001592/8.781, allocations: 139 kB / 4.039 GB, free: 31.79 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001279/8.781, allocations: 71.83 kB / 4.039 GB, free: 31.79 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 1.234e-05/8.781, allocations: 9.281 kB / 4.039 GB, free: 31.79 MB / 490.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.003721/8.785, allocations: 1.788 MB / 4.041 GB, free: 31.75 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.37e-05/8.785, allocations: 14.92 kB / 4.041 GB, free: 31.75 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007743/8.785, allocations: 194.9 kB / 4.041 GB, free: 31.74 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001356/8.787, allocations: 347.1 kB / 4.041 GB, free: 31.74 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0007348/8.787, allocations: 0.735 MB / 4.042 GB, free: 31.23 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001298/8.789, allocations: 147 kB / 4.042 GB, free: 31.22 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001161/8.789, allocations: 69.59 kB / 4.042 GB, free: 31.22 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 43
 * Number of states: 0 ()
 * Number of discrete variables: 12 (pipe2.flowModel.states[2].phase,pipe2.flowModel.states[1].phase,pipe1.flowModel.states[2].phase,pipe1.flowModel.states[1].phase,source.medium.phase,pump.heatTransfer.states[1].phase,sink.medium.phase,sink1.medium.phase,sink2.medium.phase,orifice.state_b.phase,orifice.state_a.phase,pump.monitoring.state_in.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (129):
 * Single equations (assignments): 128
 * 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: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {6 24}
Notification: Performance of prepare postOptimizeDAE: time 0.0006961/8.79, allocations: 439.6 kB / 4.043 GB, free: 31.02 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001876/8.79, allocations: 129 kB / 4.043 GB, free: 30.96 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006352/8.796, allocations: 5.668 MB / 4.048 GB, free: 30.48 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.272e-05/8.796, allocations: 57.52 kB / 4.048 GB, free: 30.48 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.582e-06/8.796, allocations: 6.25 kB / 4.048 GB, free: 30.48 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.294e-05/8.796, allocations: 6.094 kB / 4.048 GB, free: 30.48 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.008051/8.804, allocations: 7.705 MB / 4.056 GB, free: 29.9 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.042e-06/8.804, allocations: 0 / 4.056 GB, free: 29.9 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007335/8.805, allocations: 212.9 kB / 4.056 GB, free: 29.9 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02466/8.83, allocations: 4.036 MB / 4.06 GB, free: 29.82 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001526/8.83, allocations: 31.89 kB / 4.06 GB, free: 29.82 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0026/8.833, allocations: 2.378 MB / 4.062 GB, free: 29.25 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.465e-06/8.833, allocations: 1.125 kB / 4.062 GB, free: 29.25 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005074/8.838, allocations: 3.684 MB / 4.066 GB, free: 28.75 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001105/8.839, allocations: 0.6424 MB / 4.067 GB, free: 28.47 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002099/8.839, allocations: 26 kB / 4.067 GB, free: 28.47 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001024/8.84, allocations: 132.2 kB / 4.067 GB, free: 28.46 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00018/8.84, allocations: 103.9 kB / 4.067 GB, free: 28.46 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.283e-05/8.84, allocations: 47.34 kB / 4.067 GB, free: 28.46 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.001131/8.841, allocations: 1.06 MB / 4.068 GB, free: 28.35 MB / 490.7 MB
Notification: Performance of sort global known variables: time 7.51e-07/8.841, allocations: 0 / 4.068 GB, free: 28.35 MB / 490.7 MB
Notification: Performance of remove unused functions: time 0.006283/8.848, allocations: 1.978 MB / 4.07 GB, free: 28.24 MB / 490.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 0 ()
 * Number of discrete variables: 35 ($cse3.phase,$cse3.region,$cse5.region3boundary,$cse6.region3boundary,$cse7.phase,$cse7.region,$cse8.phase,$cse8.region,$cse11.phase,$cse11.region,$cse12.phase,$cse12.region,$cse17.phase,$cse17.region,$cse18.phase,$cse18.region,$cse21.phase,$cse21.region,$cse23.phase,$cse23.region,$cse25.phase,$cse25.region,$cse30.phase,$cse30.region,$cse32.phase,$cse32.region,$cse33.phase,$cse33.region,$cse36.phase,$cse36.region,source.medium.phase,pump.heatTransfer.states[1].phase,sink.medium.phase,sink1.medium.phase,sink2.medium.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (93):
 * Single equations (assignments): 80
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 12
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {6 35}
Notification: Performance of Backend phase and start with SimCode phase: time 0.006927/8.855, allocations: 2.206 MB / 4.072 GB, free: 28 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.005868/8.861, allocations: 3.022 MB / 4.075 GB, free: 27.95 MB / 490.7 MB
Notification: Performance of simCode: created event and clocks part: time 4.98e-06/8.861, allocations: 0 / 4.075 GB, free: 27.95 MB / 490.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.00211/8.863, allocations: 1.485 MB / 4.076 GB, free: 27.91 MB / 490.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003094/8.866, allocations: 0.6878 MB / 4.077 GB, free: 27.89 MB / 490.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005829/8.872, allocations: 5.275 MB / 4.082 GB, free: 25.69 MB / 490.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007656/8.872, allocations: 1.627 MB / 4.084 GB, free: 24.95 MB / 490.7 MB
Notification: Performance of simCode: alias equations: time 0.002038/8.874, allocations: 1.473 MB / 4.085 GB, free: 24.93 MB / 490.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002857/8.875, allocations: 117.2 kB / 4.085 GB, free: 24.93 MB / 490.7 MB
Notification: Performance of SimCode: time 1.492e-06/8.875, allocations: 0 / 4.085 GB, free: 24.93 MB / 490.7 MB
Notification: Performance of Templates: time 0.07756/8.952, allocations: 67.11 MB / 4.151 GB, free: 17.69 MB / 490.7 MB
make -j1 -f Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.makefile
(rm -f Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.pipe ; mkfifo Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.pipe ; head -c 1048576 < Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.pipe >> ../files/Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.sim & ./Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.pipe 2>&1)
diffSimulationResults("Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/Modelica/Fluid/Examples/InverseParameterization/InverseParameterization.csv","../files/Modelica_trunk_Modelica.Fluid.Examples.InverseParameterization.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches