Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false)
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(IDEAS.Fluid.Examples.Performance.Example1v2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2")
translateModel(IDEAS.Fluid.Examples.Performance.Example1v2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008138/0.0008139, allocations: 99.5 kB / 16.02 MB, free: 76 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0008928/0.0008928, allocations: 187.9 kB / 16.92 MB, free: 6.75 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.38/1.38, allocations: 223.3 MB / 241 MB, free: 5.344 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 1.019/1.019, allocations: 135.3 MB / 427.4 MB, free: 14.3 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.000344/0.000344, allocations: 12.75 kB / 0.5082 GB, free: 4.785 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1716/0.172, allocations: 82.31 MB / 0.5886 GB, free: 2.648 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Examples.Performance.Example1v2): time 0.4484/0.6205, allocations: 78.57 MB / 0.6653 GB, free: 3.625 MB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.034/0.6545, allocations: 19.12 MB / 0.684 GB, free: 5.957 MB / 0.5137 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.006147/0.6607, allocations: 92.81 kB / 0.6841 GB, free: 5.918 MB / 0.5137 GB
Notification: Performance of NFTyping.typeComponents: time 0.005554/0.6663, allocations: 0.971 MB / 0.6851 GB, free: 5.395 MB / 0.5137 GB
Notification: Performance of NFTyping.typeBindings: time 0.01179/0.6781, allocations: 2.578 MB / 0.6876 GB, free: 3.914 MB / 0.5137 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007992/0.6861, allocations: 2.146 MB / 0.6897 GB, free: 2.727 MB / 0.5137 GB
Notification: Performance of NFFlatten.flatten: time 0.04877/0.7349, allocations: 35.94 MB / 0.7248 GB, free: 0.8594 MB / 0.5294 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02258/0.7576, allocations: 14.96 MB / 0.7394 GB, free: 1.797 MB / 0.545 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02326/0.7809, allocations: 9.163 MB / 0.7483 GB, free: 8.602 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0238/0.8047, allocations: 11.31 MB / 0.7594 GB, free: 13.25 MB / 0.5762 GB
Notification: Performance of NFPackage.collectConstants: time 0.005147/0.8099, allocations: 0.8047 MB / 0.7602 GB, free: 12.45 MB / 0.5762 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01181/0.8218, allocations: 2.056 MB / 0.7622 GB, free: 10.39 MB / 0.5762 GB
Notification: Performance of NFScalarize.scalarize: time 0.007895/0.8297, allocations: 3.029 MB / 0.7651 GB, free: 7.348 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 0.01627/0.846, allocations: 5.796 MB / 0.7708 GB, free: 1.52 MB / 0.5762 GB
Notification: Performance of NFConvertDAE.convert: time 0.02051/0.8666, allocations: 13.94 MB / 0.7844 GB, free: 3.547 MB / 0.5919 GB
Notification: Performance of FrontEnd - DAE generated: time 5.971e-06/0.8666, allocations: 0 / 0.7844 GB, free: 3.547 MB / 0.5919 GB
Notification: Performance of FrontEnd: time 2.635e-06/0.8666, allocations: 0 / 0.7844 GB, free: 3.547 MB / 0.5919 GB
Notification: Performance of Transformations before backend: time 0.0005568/0.8672, allocations: 0 / 0.7844 GB, free: 3.547 MB / 0.5919 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1418
 * Number of variables: 1418
Notification: Performance of Generate backend data structure: time 0.03657/0.9038, allocations: 12.97 MB / 0.7971 GB, free: 6.473 MB / 0.6075 GB
Notification: Performance of prepare preOptimizeDAE: time 5.635e-05/0.9038, allocations: 8.031 kB / 0.7971 GB, free: 6.465 MB / 0.6075 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008592/0.9124, allocations: 1.285 MB / 0.7983 GB, free: 5.168 MB / 0.6075 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02362/0.9361, allocations: 10.01 MB / 0.8081 GB, free: 11.07 MB / 0.6231 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005421/0.9367, allocations: 444 kB / 0.8085 GB, free: 10.64 MB / 0.6231 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003593/0.9403, allocations: 0.5818 MB / 0.8091 GB, free: 10.05 MB / 0.6231 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03451/0.9748, allocations: 14.62 MB / 0.8234 GB, free: 11.17 MB / 0.6387 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002397/0.9751, allocations: 27.78 kB / 0.8234 GB, free: 11.14 MB / 0.6387 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002968/0.9781, allocations: 236 kB / 0.8236 GB, free: 10.91 MB / 0.6387 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001223/0.9782, allocations: 143.9 kB / 0.8238 GB, free: 10.77 MB / 0.6387 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03632/1.015, allocations: 19.79 MB / 0.8431 GB, free: 6.957 MB / 0.6544 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.7208/1.735, allocations: 66.12 MB / 0.9077 GB, free: 177.3 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01573/1.751, allocations: 9.198 MB / 0.9166 GB, free: 173.6 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01318/1.764, allocations: 8.617 MB / 0.9251 GB, free: 170 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.05688/1.821, allocations: 30.63 MB / 0.955 GB, free: 150.7 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01034/1.832, allocations: 7.665 MB / 0.9624 GB, free: 146 MB / 0.67 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02639/1.858, allocations: 11.76 MB / 0.9739 GB, free: 136.1 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=300): time 2.964e-05/1.858, allocations: 0 / 0.9739 GB, free: 136.1 MB / 0.67 GB
Notification: Performance of matching and sorting (n=300): time 0.04747/1.906, allocations: 23.89 MB / 0.9973 GB, free: 112.3 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.345e-05/1.906, allocations: 88.58 kB / 0.9973 GB, free: 112.2 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01184/1.918, allocations: 8.038 MB / 1.005 GB, free: 104.2 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.002198/1.92, allocations: 97.7 kB / 1.005 GB, free: 104.1 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003771/1.924, allocations: 4.069 MB / 1.009 GB, free: 99.98 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00308/1.927, allocations: 1.083 MB / 1.01 GB, free: 98.91 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00189/1.929, allocations: 375.5 kB / 1.011 GB, free: 98.53 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 4.335e-05/1.929, allocations: 309.1 kB / 1.011 GB, free: 98.23 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008723/1.937, allocations: 6.539 MB / 1.017 GB, free: 91.68 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00994/1.947, allocations: 8.334 MB / 1.025 GB, free: 82.52 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01997/1.967, allocations: 15.26 MB / 1.04 GB, free: 66.63 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.705e-05/1.968, allocations: 19.94 kB / 1.04 GB, free: 66.61 MB / 0.67 GB
Notification: Performance of matching and sorting (n=579) (initialization): time 0.03304/2.001, allocations: 17.7 MB / 1.058 GB, free: 48.86 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0007273/2.001, allocations: 1.564 MB / 1.059 GB, free: 46.61 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.055e-05/2.001, allocations: 48 kB / 1.059 GB, free: 46.57 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.06572/2.067, allocations: 11.38 MB / 1.07 GB, free: 35.22 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008217/2.075, allocations: 1.561 MB / 1.072 GB, free: 33.65 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007257/2.083, allocations: 6.473 MB / 1.078 GB, free: 26.96 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01128/2.094, allocations: 0.5191 MB / 1.079 GB, free: 26.45 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002788/2.097, allocations: 0.5382 MB / 1.079 GB, free: 25.91 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 1.246e-05/2.097, allocations: 5.25 kB / 1.079 GB, free: 25.9 MB / 0.67 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.02765/2.125, allocations: 16.83 MB / 1.096 GB, free: 8.996 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001039/2.125, allocations: 48 kB / 1.096 GB, free: 8.949 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.06583/2.191, allocations: 10.93 MB / 1.106 GB, free: 14.05 MB / 0.6857 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009088/2.2, allocations: 1.561 MB / 1.108 GB, free: 12.48 MB / 0.6857 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01342/2.213, allocations: 10.38 MB / 1.118 GB, free: 1.664 MB / 0.6857 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01107/2.224, allocations: 0.5064 MB / 1.119 GB, free: 1.16 MB / 0.6857 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003088/2.227, allocations: 0.5343 MB / 1.119 GB, free: 0.625 MB / 0.6857 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 217
 * Number of states: 0 ()
 * Number of discrete variables: 8 (pulse.count,$PRE.pulse.count,pulse.T_start,$PRE.pulse.T_start,$whenCondition1,from_dp.y,nRes.y,allowFlowReversal.y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (527):
 * Single equations (assignments): 521
 * Array equations: 1
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(1,3), (20,21), (2,5)}
Notification: Performance of prepare postOptimizeDAE: time 0.3737/2.601, allocations: 2.662 MB / 1.122 GB, free: 197.6 MB / 0.6857 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003296/2.604, allocations: 0.7661 MB / 1.122 GB, free: 197.6 MB / 0.6857 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02796/2.632, allocations: 14.16 MB / 1.136 GB, free: 195.2 MB / 0.6857 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.666e-05/2.632, allocations: 36.84 kB / 1.136 GB, free: 195.2 MB / 0.6857 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.787e-06/2.632, allocations: 3.328 kB / 1.136 GB, free: 195.2 MB / 0.6857 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01501/2.648, allocations: 10.77 MB / 1.147 GB, free: 190.4 MB / 0.6857 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 postOpt removeSimpleEquations (simulation): time 0.0439/2.691, allocations: 24.54 MB / 1.171 GB, free: 179.1 MB / 0.6857 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.64e-05/2.692, allocations: 0.7031 kB / 1.171 GB, free: 179.1 MB / 0.6857 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.005236/2.697, allocations: 0.6967 MB / 1.171 GB, free: 179 MB / 0.6857 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.06767/2.764, allocations: 10.27 MB / 1.182 GB, free: 178.5 MB / 0.6857 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001485/2.766, allocations: 103.8 kB / 1.182 GB, free: 178.4 MB / 0.6857 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.004881/2.771, allocations: 4.707 MB / 1.186 GB, free: 175.1 MB / 0.6857 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.405e-06/2.771, allocations: 1.375 kB / 1.186 GB, free: 175.1 MB / 0.6857 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02814/2.799, allocations: 19.3 MB / 1.205 GB, free: 166.8 MB / 0.6857 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006318/2.805, allocations: 1.947 MB / 1.207 GB, free: 165.9 MB / 0.6857 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002866/2.808, allocations: 70.91 kB / 1.207 GB, free: 165.9 MB / 0.6857 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.007741/2.816, allocations: 385 kB / 1.207 GB, free: 165.8 MB / 0.6857 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001271/2.817, allocations: 224.2 kB / 1.208 GB, free: 165.8 MB / 0.6857 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0006475/2.818, allocations: 158.5 kB / 1.208 GB, free: 165.7 MB / 0.6857 GB
Notification: Performance of sorting global known variables: time 0.01008/2.828, allocations: 5.533 MB / 1.213 GB, free: 162.9 MB / 0.6857 GB
Notification: Performance of sort global known variables: time 9.12e-07/2.828, allocations: 0 / 1.213 GB, free: 162.9 MB / 0.6857 GB
Notification: Performance of remove unused functions: time 0.02208/2.85, allocations: 4.846 MB / 1.218 GB, free: 158.3 MB / 0.6857 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 22 (pump.vol.dynBal.U,hea.outCon.T,vol[1].dynBal.U,vol[2].dynBal.U,vol[3].dynBal.U,vol[4].dynBal.U,vol[5].dynBal.U,vol[6].dynBal.U,vol[7].dynBal.U,vol[8].dynBal.U,vol[9].dynBal.U,vol[10].dynBal.U,vol[11].dynBal.U,vol[12].dynBal.U,vol[13].dynBal.U,vol[14].dynBal.U,vol[15].dynBal.U,vol[16].dynBal.U,vol[17].dynBal.U,vol[18].dynBal.U,vol[19].dynBal.U,vol[20].dynBal.U)
 * Number of discrete variables: 3 ($whenCondition1,pulse.T_start,pulse.count)
 * Number of discrete states: 1 (pulse.count)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (304):
 * Single equations (assignments): 294
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 4
 * When equations: 2
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(1,3), (2,5), (20,21)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.002655/2.853, allocations: 0.5939 MB / 1.218 GB, free: 157.8 MB / 0.6857 GB
Notification: Performance of simCode: created initialization part: time 0.03645/2.889, allocations: 24 MB / 1.242 GB, free: 133.7 MB / 0.6857 GB
Notification: Performance of simCode: created event and clocks part: time 1.014e-05/2.889, allocations: 0 / 1.242 GB, free: 133.7 MB / 0.6857 GB
Notification: Performance of simCode: created simulation system equations: time 0.009653/2.899, allocations: 6.402 MB / 1.248 GB, free: 127.3 MB / 0.6857 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02944/2.929, allocations: 4.664 MB / 1.253 GB, free: 122.7 MB / 0.6857 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03719/2.966, allocations: 24 MB / 1.276 GB, free: 98.7 MB / 0.6857 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004775/2.971, allocations: 5.243 MB / 1.281 GB, free: 93.42 MB / 0.6857 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.005902/2.977, allocations: 0.9359 MB / 1.282 GB, free: 92.49 MB / 0.6857 GB
Notification: Performance of SimCode: time 1.122e-06/2.977, allocations: 4 kB / 1.282 GB, free: 92.48 MB / 0.6857 GB
Notification: Performance of Templates: time 0.706/3.683, allocations: 350.5 MB / 1.624 GB, free: 10.63 MB / 0.7169 GB
make -j1 -f IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.makefile
(rm -f IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.sim & ./IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe 2>&1)