Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo", uses=false)
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)
Using package Annex60 with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo)
Running command: translateModel(Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness")
translateModel(Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo): time 0.3126/0.3126, allocations: 55.02 MB / 69.96 MB, free: 8.672 MB / 58.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.001098/0.001098, allocations: 187.5 kB / 83.98 MB, free: 10.86 MB / 74.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo): time 1.595/1.595, allocations: 194.4 MB / 292.3 MB, free: 3.605 MB / 218.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.001672/0.001672, allocations: 121.2 kB / 350.7 MB, free: 9.102 MB / 282.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003818/0.0003818, allocations: 12 kB / 418.9 MB, free: 37.02 MB / 298.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1212/0.1216, allocations: 57.65 MB / 476.5 MB, free: 11.3 MB / 330.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 6.02/6.142, allocations: 2.145 GB / 2.611 GB, free: 147 MB / 0.4948 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0003812/6.142, allocations: 89.88 kB / 2.611 GB, free: 147 MB / 0.4948 GB
Notification: Performance of FrontEnd - DAE generated: time 4.076/10.22, allocations: 0.9662 GB / 3.577 GB, free: 53.68 MB / 0.5261 GB
Notification: Performance of FrontEnd: time 2.284e-06/10.22, allocations: 1.312 kB / 3.577 GB, free: 53.68 MB / 0.5261 GB
Notification: Performance of Transformations before backend: time 0.0001452/10.22, allocations: 37.89 kB / 3.577 GB, free: 53.68 MB / 0.5261 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 276
 * Number of variables: 276
Notification: Performance of Generate backend data structure: time 0.00817/10.23, allocations: 2.056 MB / 3.579 GB, free: 53.16 MB / 0.5261 GB
Notification: Performance of prepare preOptimizeDAE: time 6.584e-05/10.23, allocations: 12.58 kB / 3.579 GB, free: 53.16 MB / 0.5261 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003801/10.23, allocations: 0.5385 MB / 3.58 GB, free: 53.08 MB / 0.5261 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003431/10.23, allocations: 1.091 MB / 3.581 GB, free: 52.8 MB / 0.5261 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006868/10.24, allocations: 216.9 kB / 3.581 GB, free: 52.8 MB / 0.5261 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002828/10.24, allocations: 107.6 kB / 3.581 GB, free: 52.8 MB / 0.5261 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004115/10.24, allocations: 1.699 MB / 3.583 GB, free: 52.69 MB / 0.5261 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.935e-05/10.24, allocations: 0 / 3.583 GB, free: 52.69 MB / 0.5261 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001479/10.24, allocations: 54.12 kB / 3.583 GB, free: 52.69 MB / 0.5261 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.532e-05/10.24, allocations: 53.95 kB / 3.583 GB, free: 52.68 MB / 0.5261 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004467/10.24, allocations: 1.879 MB / 3.584 GB, free: 52.67 MB / 0.5261 GB
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.009799/10.25, allocations: 4.969 MB / 3.589 GB, free: 50.71 MB / 0.5261 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003958/10.26, allocations: 1.212 MB / 3.591 GB, free: 50.68 MB / 0.5261 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006139/10.26, allocations: 249.4 kB / 3.591 GB, free: 50.49 MB / 0.5261 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.541e-05/10.26, allocations: 50.72 kB / 3.591 GB, free: 50.45 MB / 0.5261 GB
Notification: Performance of pre-optimization done (n=79): time 4.087e-06/10.26, allocations: 0 / 3.591 GB, free: 50.45 MB / 0.5261 GB
Notification: Performance of matching and sorting (n=79): time 0.006857/10.27, allocations: 2.073 MB / 3.593 GB, free: 50.34 MB / 0.5261 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.205e-05/10.27, allocations: 65.31 kB / 3.593 GB, free: 50.28 MB / 0.5261 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001663/10.27, allocations: 0.8772 MB / 3.594 GB, free: 50.14 MB / 0.5261 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002101/10.27, allocations: 50.58 kB / 3.594 GB, free: 50.11 MB / 0.5261 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004329/10.27, allocations: 0.5308 MB / 3.594 GB, free: 49.93 MB / 0.5261 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002825/10.27, allocations: 279 kB / 3.595 GB, free: 49.8 MB / 0.5261 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 6.531e-05/10.27, allocations: 18.22 kB / 3.595 GB, free: 49.8 MB / 0.5261 GB
Notification: Performance of setup shared object (initialization): time 9.008e-05/10.27, allocations: 341.4 kB / 3.595 GB, free: 49.49 MB / 0.5261 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00178/10.27, allocations: 0.6355 MB / 3.596 GB, free: 49.49 MB / 0.5261 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001656/10.27, allocations: 0.7908 MB / 3.596 GB, free: 49.34 MB / 0.5261 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001611/10.27, allocations: 0.7013 MB / 3.597 GB, free: 49.3 MB / 0.5261 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.041e-06/10.27, allocations: 0 / 3.597 GB, free: 49.3 MB / 0.5261 GB
Notification: Performance of matching and sorting (n=105) (initialization): time 0.004/10.28, allocations: 1.632 MB / 3.599 GB, free: 49.16 MB / 0.5261 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003088/10.28, allocations: 1.089 MB / 3.6 GB, free: 48.04 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.196e-05/10.28, allocations: 3.875 kB / 3.6 GB, free: 48.04 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.92e-05/10.28, allocations: 9.875 kB / 3.6 GB, free: 48.04 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004103/10.28, allocations: 0.6974 MB / 3.6 GB, free: 48.04 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00175/10.28, allocations: 1.415 MB / 3.602 GB, free: 46.87 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002703/10.29, allocations: 249.2 kB / 3.602 GB, free: 46.84 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002221/10.29, allocations: 39.77 kB / 3.602 GB, free: 46.84 MB / 0.5261 GB
Notification: Performance of prepare postOptimizeDAE: time 2.534e-05/10.29, allocations: 3.781 kB / 3.602 GB, free: 46.84 MB / 0.5261 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.003556/10.29, allocations: 1.441 MB / 3.603 GB, free: 46.79 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.539e-05/10.29, allocations: 4.406 kB / 3.603 GB, free: 46.79 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.589e-05/10.29, allocations: 10.73 kB / 3.603 GB, free: 46.78 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001494/10.29, allocations: 301.4 kB / 3.604 GB, free: 46.78 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.336e-05/10.29, allocations: 9.219 kB / 3.604 GB, free: 46.78 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001921/10.29, allocations: 223.5 kB / 3.604 GB, free: 46.76 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.971e-05/10.29, allocations: 35.17 kB / 3.604 GB, free: 46.76 MB / 0.5261 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 12
 * Number of states: 0 ()
 * Number of discrete variables: 2 (hex.i1_w,hex.i2_w)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (104):
 * Single equations (assignments): 101
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 2
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 2 {1,1}
 * Without analytic Jacobian: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001001/10.3, allocations: 417.5 kB / 3.604 GB, free: 46.57 MB / 0.5261 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003506/10.3, allocations: 125.8 kB / 3.604 GB, free: 46.51 MB / 0.5261 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003714/10.3, allocations: 1.524 MB / 3.606 GB, free: 46.44 MB / 0.5261 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.241e-05/10.3, allocations: 15.55 kB / 3.606 GB, free: 46.43 MB / 0.5261 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.246e-06/10.3, allocations: 0 / 3.606 GB, free: 46.43 MB / 0.5261 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.075e-05/10.3, allocations: 3.469 kB / 3.606 GB, free: 46.43 MB / 0.5261 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005719/10.31, allocations: 2.896 MB / 3.609 GB, free: 46.02 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.055e-06/10.31, allocations: 2.938 kB / 3.609 GB, free: 46.02 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003771/10.31, allocations: 0.6782 MB / 3.609 GB, free: 46.02 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.815e-05/10.31, allocations: 6.656 kB / 3.61 GB, free: 46.02 MB / 0.5261 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 8.462e-05/10.31, allocations: 31.72 kB / 3.61 GB, free: 46.01 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.004305/10.31, allocations: 3.323 MB / 3.613 GB, free: 43.37 MB / 0.5261 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.502e-06/10.31, allocations: 11.59 kB / 3.613 GB, free: 43.37 MB / 0.5261 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.004073/10.32, allocations: 1.793 MB / 3.615 GB, free: 42.83 MB / 0.5261 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001381/10.32, allocations: 347.4 kB / 3.615 GB, free: 42.68 MB / 0.5261 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004494/10.32, allocations: 17.44 kB / 3.615 GB, free: 42.68 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00195/10.32, allocations: 182.9 kB / 3.615 GB, free: 42.68 MB / 0.5261 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001982/10.32, allocations: 69.69 kB / 3.615 GB, free: 42.67 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002149/10.32, allocations: 46.98 kB / 3.615 GB, free: 42.67 MB / 0.5261 GB
Notification: Performance of sorting global known variables: time 0.001246/10.32, allocations: 0.6821 MB / 3.616 GB, free: 42.56 MB / 0.5261 GB
Notification: Performance of sort global known variables: time 3.81e-07/10.32, allocations: 3.594 kB / 3.616 GB, free: 42.56 MB / 0.5261 GB
Notification: Performance of remove unused functions: time 0.004023/10.33, allocations: 0.883 MB / 3.617 GB, free: 42.52 MB / 0.5261 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (79):
 * Single equations (assignments): 77
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 2
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 2 {1,1}
 * Without analytic Jacobian: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.004449/10.33, allocations: 2.36 MB / 3.619 GB, free: 41.67 MB / 0.5261 GB
Notification: Performance of simCode: created initialization part: time 0.008226/10.34, allocations: 2.681 MB / 3.622 GB, free: 41.54 MB / 0.5261 GB
Notification: Performance of simCode: created event and clocks part: time 1.831e-05/10.34, allocations: 6.594 kB / 3.622 GB, free: 41.54 MB / 0.5261 GB
Notification: Performance of simCode: created simulation system equations: time 0.006559/10.35, allocations: 1.435 MB / 3.623 GB, free: 41.54 MB / 0.5261 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003823/10.35, allocations: 0.6297 MB / 3.624 GB, free: 41.52 MB / 0.5261 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006215/10.36, allocations: 2.761 MB / 3.626 GB, free: 40.33 MB / 0.5261 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000741/10.36, allocations: 1.182 MB / 3.628 GB, free: 39.62 MB / 0.5261 GB
Notification: Performance of simCode: alias equations: time 0.002321/10.36, allocations: 424.4 kB / 3.628 GB, free: 39.61 MB / 0.5261 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003419/10.36, allocations: 98.84 kB / 3.628 GB, free: 39.6 MB / 0.5261 GB
Notification: Performance of SimCode: time 1.743e-06/10.36, allocations: 0 / 3.628 GB, free: 39.6 MB / 0.5261 GB
Notification: Performance of Templates: time 0.07343/10.43, allocations: 50.04 MB / 3.677 GB, free: 36.99 MB / 0.5261 GB
make -j1 -f Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness.makefile
(rm -f Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe ; mkfifo Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe ; head -c 1048576 < Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe >> ../files/Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness.sim & ./Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Annex60_1.0.0_Annex60.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe 2>&1)