Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 3.2.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo)
Using package Modelica with version 3.2.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo)
Using package Complex with version 3.2.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo)
Running command: translateModel(Modelica.Fluid.Examples.Explanatory.MeasuringTemperature,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature")
translateModel(Modelica.Fluid.Examples.Explanatory.MeasuringTemperature,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.001173/0.001173, allocations: 178.9 kB / 16.38 MB, free: 5.766 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo): time 1.425/1.425, allocations: 194.5 MB / 211.6 MB, free: 3.031 MB / 170.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.001641/0.001641, allocations: 118.6 kB / 256.9 MB, free: 4.352 MB / 202.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003079/0.0003079, allocations: 8 kB / 310.6 MB, free: 14.94 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09704/0.09736, allocations: 44.5 MB / 355.1 MB, free: 2.34 MB / 298.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 4.64/4.738, allocations: 2.588 GB / 2.935 GB, free: 34.63 MB / 410.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002529/4.738, allocations: 87.97 kB / 2.935 GB, free: 34.54 MB / 410.7 MB
Notification: Performance of FrontEnd - DAE generated: time 2.815/7.553, allocations: 0.963 GB / 3.898 GB, free: 17.34 MB / 442.7 MB
Notification: Performance of FrontEnd: time 2.224e-06/7.553, allocations: 0 / 3.898 GB, free: 17.34 MB / 442.7 MB
Notification: Performance of Transformations before backend: time 0.0001377/7.553, allocations: 62.91 kB / 3.898 GB, free: 17.32 MB / 442.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 462
 * Number of variables: 462
Notification: Performance of Generate backend data structure: time 0.009108/7.562, allocations: 3.573 MB / 3.901 GB, free: 15.49 MB / 442.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.435e-05/7.562, allocations: 11.44 kB / 3.901 GB, free: 15.49 MB / 442.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01204/7.574, allocations: 1.213 MB / 3.903 GB, free: 15.37 MB / 442.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003176/7.577, allocations: 1.542 MB / 3.904 GB, free: 14.94 MB / 442.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002144/7.578, allocations: 346.6 kB / 3.904 GB, free: 14.75 MB / 442.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003182/7.578, allocations: 188.2 kB / 3.905 GB, free: 14.75 MB / 442.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00705/7.585, allocations: 3.514 MB / 3.908 GB, free: 14.33 MB / 442.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.53e-05/7.585, allocations: 14.61 kB / 3.908 GB, free: 14.32 MB / 442.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002509/7.585, allocations: 89.22 kB / 3.908 GB, free: 14.32 MB / 442.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001065/7.585, allocations: 104.6 kB / 3.908 GB, free: 14.32 MB / 442.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007095/7.593, allocations: 3.512 MB / 3.912 GB, free: 14.28 MB / 442.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.01785/7.61, allocations: 14.47 MB / 3.926 GB, free: 5.883 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.005986/7.616, allocations: 2.859 MB / 3.929 GB, free: 4.934 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.006015/7.622, allocations: 3.755 MB / 3.932 GB, free: 18.09 MB / 458.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.603e-05/7.622, allocations: 52.91 kB / 3.932 GB, free: 18.04 MB / 458.7 MB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003337/7.626, allocations: 0.567 MB / 3.933 GB, free: 17.88 MB / 458.7 MB
Notification: Performance of pre-optimization done (n=175): time 7.594e-06/7.626, allocations: 0 / 3.933 GB, free: 17.88 MB / 458.7 MB
Notification: Performance of matching and sorting (n=205): time 0.03422/7.66, allocations: 12.29 MB / 3.945 GB, free: 11.76 MB / 458.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.687e-05/7.66, allocations: 150.5 kB / 3.945 GB, free: 11.58 MB / 458.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001786/7.662, allocations: 1.225 MB / 3.946 GB, free: 10.54 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002431/7.662, allocations: 65.7 kB / 3.946 GB, free: 10.47 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005956/7.663, allocations: 0.8019 MB / 3.947 GB, free: 9.734 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0006231/7.664, allocations: 0.6758 MB / 3.948 GB, free: 9.121 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001499/7.664, allocations: 44 kB / 3.948 GB, free: 9.078 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002009/7.664, allocations: 388.8 kB / 3.948 GB, free: 8.719 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002483/7.666, allocations: 1.278 MB / 3.949 GB, free: 7.652 MB / 458.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002753/7.669, allocations: 1.735 MB / 3.951 GB, free: 5.969 MB / 458.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002542/7.672, allocations: 1.378 MB / 3.952 GB, free: 4.742 MB / 458.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.434e-06/7.672, allocations: 4 kB / 3.952 GB, free: 4.738 MB / 458.7 MB
Notification: Performance of matching and sorting (n=278) (initialization): time 0.00756/7.679, allocations: 3.989 MB / 3.956 GB, free: 1.262 MB / 458.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.2324/7.912, allocations: 4.974 MB / 3.961 GB, free: 29.38 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.105e-05/7.912, allocations: 12.25 kB / 3.961 GB, free: 29.38 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00468/7.916, allocations: 1.56 MB / 3.963 GB, free: 29.31 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002231/7.919, allocations: 478.6 kB / 3.963 GB, free: 29.31 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004462/7.923, allocations: 6.592 MB / 3.97 GB, free: 24.66 MB / 458.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002414/7.926, allocations: 225.2 kB / 3.97 GB, free: 24.66 MB / 458.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002115/7.926, allocations: 119.2 kB / 3.97 GB, free: 24.66 MB / 458.7 MB
Notification: Performance of prepare postOptimizeDAE: time 9.668e-06/7.926, allocations: 4.719 kB / 3.97 GB, free: 24.66 MB / 458.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.005995/7.932, allocations: 3.497 MB / 3.973 GB, free: 24.54 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.738e-05/7.932, allocations: 11.75 kB / 3.973 GB, free: 24.54 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002234/7.934, allocations: 1.041 MB / 3.974 GB, free: 24.46 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001921/7.936, allocations: 0.4909 MB / 3.975 GB, free: 24.46 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00687/7.943, allocations: 10.19 MB / 3.985 GB, free: 17.4 MB / 458.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002411/7.945, allocations: 215.4 kB / 3.985 GB, free: 17.4 MB / 458.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002162/7.946, allocations: 111.5 kB / 3.985 GB, free: 17.4 MB / 458.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 56
 * Number of states: 0 ()
 * Number of discrete variables: 21 (openTankCold2.heatTransfer.states[1].phase,openTankCold2.regularFlow[1],openTankCold2.inFlow[1],openTankCold1.heatTransfer.states[1].phase,openTankCold1.regularFlow[1],openTankCold1.inFlow[1],openTankHot1.heatTransfer.states[1].phase,openTankHot1.regularFlow[1],openTankHot1.inFlow[1],openTankHot2.heatTransfer.states[1].phase,openTankHot2.regularFlow[1],openTankHot2.inFlow[1],mFlow1.medium.phase,mFlow2.medium.phase,openTankCold3.heatTransfer.states[1].phase,openTankCold3.regularFlow[1],openTankCold3.inFlow[1],openTankHot3.heatTransfer.states[1].phase,openTankHot3.regularFlow[1],openTankHot3.inFlow[1],mFlow3.medium.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (219):
 * Single equations (assignments): 210
 * 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: 9
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 9 {1 5,1 5,4 6,1 5,1 5,4 6,1 5,1 5,6 6}
Notification: Performance of prepare postOptimizeDAE: time 0.001371/7.947, allocations: 0.5867 MB / 3.986 GB, free: 17.21 MB / 458.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003872/7.947, allocations: 201.7 kB / 3.986 GB, free: 17.21 MB / 458.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01099/7.958, allocations: 9.858 MB / 3.995 GB, free: 16.45 MB / 458.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8e-05/7.958, allocations: 103.9 kB / 3.996 GB, free: 16.45 MB / 458.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.141e-06/7.958, allocations: 0.625 kB / 3.996 GB, free: 16.45 MB / 458.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.152e-05/7.959, allocations: 13.22 kB / 3.996 GB, free: 16.45 MB / 458.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01647/7.975, allocations: 13.37 MB / 4.009 GB, free: 15.61 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.125e-05/7.975, allocations: 5.656 kB / 4.009 GB, free: 15.61 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001756/7.977, allocations: 403 kB / 4.009 GB, free: 15.61 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.006202/7.983, allocations: 4.135 MB / 4.013 GB, free: 15.4 MB / 458.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003035/7.983, allocations: 65.89 kB / 4.013 GB, free: 15.4 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006487/7.99, allocations: 8.766 MB / 4.022 GB, free: 10.64 MB / 458.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.868e-06/7.99, allocations: 7.766 kB / 4.022 GB, free: 10.64 MB / 458.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01335/8.003, allocations: 10.71 MB / 4.032 GB, free: 9.891 MB / 458.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001636/8.005, allocations: 0.9091 MB / 4.033 GB, free: 9.543 MB / 458.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003345/8.005, allocations: 49.95 kB / 4.033 GB, free: 9.539 MB / 458.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001852/8.007, allocations: 228 kB / 4.033 GB, free: 9.527 MB / 458.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004103/8.008, allocations: 232.5 kB / 4.034 GB, free: 9.523 MB / 458.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001714/8.008, allocations: 87.69 kB / 4.034 GB, free: 9.523 MB / 458.7 MB
Notification: Performance of sorting global known variables: time 0.001446/8.009, allocations: 1.248 MB / 4.035 GB, free: 9.379 MB / 458.7 MB
Notification: Performance of sort global known variables: time 1.4e-07/8.009, allocations: 0 / 4.035 GB, free: 9.379 MB / 458.7 MB
Notification: Performance of remove unused functions: time 0.007635/8.017, allocations: 2.382 MB / 4.037 GB, free: 9.312 MB / 458.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 12 (openTankCold2.medium.h,openTankCold2.level,openTankCold1.medium.h,openTankCold1.level,openTankHot1.medium.h,openTankHot1.level,openTankHot2.medium.h,openTankHot2.level,openTankCold3.medium.h,openTankCold3.level,openTankHot3.medium.h,openTankHot3.level)
 * Number of discrete variables: 71 ($cse1.phase,$cse1.region,$cse5.phase,$cse5.region,$cse6.phase,$cse6.region,$cse11.phase,$cse11.region,$cse13.phase,$cse13.region,$cse16.phase,$cse16.region,$cse18.phase,$cse18.region,$cse21.phase,$cse21.region,$cse23.phase,$cse23.region,$cse26.phase,$cse26.region,$cse28.phase,$cse28.region,$cse29.region3boundary,$cse30.region3boundary,$cse31.phase,$cse31.region,$cse32.phase,$cse32.region,$cse33.region3boundary,$cse34.region3boundary,$cse35.phase,$cse35.region,$cse36.phase,$cse36.region,$cse37.phase,$cse37.region,$cse41.phase,$cse41.region,$cse43.phase,$cse43.region,$cse46.phase,$cse46.region,$cse48.phase,$cse48.region,$cse49.region3boundary,$cse50.region3boundary,$cse51.phase,$cse51.region,$cse52.phase,$cse52.region,openTankCold2.heatTransfer.states[1].phase,openTankCold2.regularFlow[1],openTankCold2.inFlow[1],openTankCold1.heatTransfer.states[1].phase,openTankCold1.regularFlow[1],openTankCold1.inFlow[1],openTankHot1.heatTransfer.states[1].phase,openTankHot1.regularFlow[1],openTankHot1.inFlow[1],openTankHot2.heatTransfer.states[1].phase,openTankHot2.regularFlow[1],openTankHot2.inFlow[1],mFlow1.medium.phase,mFlow2.medium.phase,openTankCold3.heatTransfer.states[1].phase,openTankCold3.regularFlow[1],openTankCold3.inFlow[1],openTankHot3.heatTransfer.states[1].phase,openTankHot3.regularFlow[1],openTankHot3.inFlow[1],mFlow3.medium.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (173):
 * Single equations (assignments): 145
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 19
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 9
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 9 {2 4,2 4,6 9,2 4,2 4,6 9,2 4,2 4,6 11}
Notification: Performance of Backend phase and start with SimCode phase: time 0.00684/8.024, allocations: 2.594 MB / 4.04 GB, free: 9.016 MB / 458.7 MB
Notification: Performance of simCode: created initialization part: time 0.01169/8.035, allocations: 6.463 MB / 4.046 GB, free: 24.54 MB / 474.7 MB
Notification: Performance of simCode: created event and clocks part: time 7.855e-06/8.035, allocations: 0.5625 kB / 4.046 GB, free: 24.54 MB / 474.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.005499/8.041, allocations: 3.148 MB / 4.049 GB, free: 24.47 MB / 474.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004347/8.045, allocations: 1.05 MB / 4.05 GB, free: 24.45 MB / 474.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01025/8.056, allocations: 9.492 MB / 4.059 GB, free: 21.02 MB / 474.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001434/8.057, allocations: 2.244 MB / 4.062 GB, free: 20.24 MB / 474.7 MB
Notification: Performance of simCode: alias equations: time 0.004946/8.062, allocations: 3.453 MB / 4.065 GB, free: 20.19 MB / 474.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005238/8.062, allocations: 188.2 kB / 4.065 GB, free: 20.18 MB / 474.7 MB
Notification: Performance of SimCode: time 1.423e-06/8.062, allocations: 0 / 4.065 GB, free: 20.18 MB / 474.7 MB
Notification: Performance of Templates: time 0.3349/8.397, allocations: 98.51 MB / 4.161 GB, free: 44.6 MB / 474.7 MB
make -j1 -f Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature.makefile
(rm -f Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature.pipe ; mkfifo Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature.pipe ; head -c 1048576 < Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature.pipe >> ../files/Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature.sim & ./Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Modelica_3.2.2_Modelica.Fluid.Examples.Explanatory.MeasuringTemperature.pipe 2>&1)