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.001507/0.001507, allocations: 115.3 kB / 15.34 MB, free: 0.5156 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001498/0.001498, allocations: 190.4 kB / 16.25 MB, free: 6.645 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.332/1.332, allocations: 222.9 MB / 239.9 MB, free: 4.531 MB / 190.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.9138/0.9138, allocations: 135.6 MB / 425.6 MB, free: 12.01 MB / 350.1 MB Notification: Performance of FrontEnd - loaded program: time 0.001926/0.001926, allocations: 60.39 kB / 0.5054 GB, free: 5.168 MB / 414.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1693/0.1713, allocations: 81.02 MB / 0.5845 GB, free: 4.281 MB / 494.1 MB Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Examples.Performance.Example1v2): time 0.2938/0.4651, allocations: 79.88 MB / 0.6625 GB, free: 3.191 MB / 0.4981 GB Notification: Performance of NFInst.instExpressions: time 0.02628/0.4914, allocations: 19.12 MB / 0.6812 GB, free: 5.547 MB / 0.5137 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.003351/0.4948, allocations: 98.28 kB / 0.6813 GB, free: 5.508 MB / 0.5137 GB Notification: Performance of NFTyping.typeComponents: time 0.00315/0.498, allocations: 1.007 MB / 0.6822 GB, free: 4.953 MB / 0.5137 GB Notification: Performance of NFTyping.typeBindings: time 0.007591/0.5056, allocations: 2.568 MB / 0.6848 GB, free: 3.477 MB / 0.5137 GB Notification: Performance of NFTyping.typeClassSections: time 0.005979/0.5116, allocations: 2.191 MB / 0.6869 GB, free: 2.266 MB / 0.5137 GB Notification: Performance of NFFlatten.flatten: time 0.03017/0.5418, allocations: 26.97 MB / 0.7132 GB, free: 5.992 MB / 0.5294 GB Notification: Performance of NFFlatten.resolveConnections: time 0.02024/0.5621, allocations: 17 MB / 0.7298 GB, free: 7.531 MB / 0.545 GB Notification: Performance of NFEvalConstants.evaluate: time 0.02841/0.5905, allocations: 11.39 MB / 0.741 GB, free: 12.19 MB / 0.5606 GB Notification: Performance of NFSimplifyModel.simplify: time 0.0205/0.611, allocations: 11.93 MB / 0.7526 GB, free: 220 kB / 0.5606 GB Notification: Performance of NFPackage.collectConstants: time 0.004546/0.6156, allocations: 1.273 MB / 0.7539 GB, free: 14.94 MB / 0.5762 GB Notification: Performance of NFFlatten.collectFunctions: time 0.009838/0.6255, allocations: 2.572 MB / 0.7564 GB, free: 12.37 MB / 0.5762 GB Notification: Performance of NFScalarize.scalarize: time 0.006843/0.6324, allocations: 3.638 MB / 0.7599 GB, free: 8.719 MB / 0.5762 GB Notification: Performance of NFVerifyModel.verify: time 0.01365/0.646, allocations: 6.489 MB / 0.7663 GB, free: 2.203 MB / 0.5762 GB Notification: Performance of NFConvertDAE.convert: time 0.02601/0.6721, allocations: 18.31 MB / 0.7841 GB, free: 15.84 MB / 0.6075 GB Notification: Performance of FrontEnd - DAE generated: time 6.653e-06/0.6721, allocations: 0 / 0.7841 GB, free: 15.84 MB / 0.6075 GB Notification: Performance of FrontEnd: time 1.813e-06/0.6721, allocations: 0 / 0.7841 GB, free: 15.84 MB / 0.6075 GB Notification: Performance of Transformations before backend: time 0.0004926/0.6726, allocations: 0 / 0.7841 GB, free: 15.84 MB / 0.6075 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.03586/0.7085, allocations: 12.22 MB / 0.7961 GB, free: 3.52 MB / 0.6075 GB Notification: Performance of prepare preOptimizeDAE: time 4.528e-05/0.7085, allocations: 8.031 kB / 0.7961 GB, free: 3.512 MB / 0.6075 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008/0.7165, allocations: 1.257 MB / 0.7973 GB, free: 2.242 MB / 0.6075 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.02318/0.7398, allocations: 10.02 MB / 0.8071 GB, free: 8.133 MB / 0.6231 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005275/0.7403, allocations: 440.1 kB / 0.8075 GB, free: 7.703 MB / 0.6231 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.003624/0.744, allocations: 0.5818 MB / 0.8081 GB, free: 7.121 MB / 0.6231 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.03411/0.7781, allocations: 14.62 MB / 0.8224 GB, free: 8.238 MB / 0.6387 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0002597/0.7784, allocations: 19.86 kB / 0.8224 GB, free: 8.219 MB / 0.6387 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002895/0.7813, allocations: 240 kB / 0.8226 GB, free: 7.984 MB / 0.6387 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001391/0.7815, allocations: 140 kB / 0.8227 GB, free: 7.848 MB / 0.6387 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03563/0.8171, allocations: 19.77 MB / 0.842 GB, free: 4.059 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.5579/1.375, allocations: 59.9 MB / 0.9005 GB, free: 177.5 MB / 0.67 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01495/1.39, allocations: 9.184 MB / 0.9095 GB, free: 173.8 MB / 0.67 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0113/1.401, allocations: 8.601 MB / 0.9179 GB, free: 170.3 MB / 0.67 GB Notification: Performance of preOpt evalFunc (simulation): time 0.04587/1.447, allocations: 27.37 MB / 0.9446 GB, free: 155.1 MB / 0.67 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.009545/1.457, allocations: 7.645 MB / 0.9521 GB, free: 150.3 MB / 0.67 GB Notification: Performance of preOpt simplifyInStream (simulation): time 0.01766/1.474, allocations: 6.862 MB / 0.9588 GB, free: 147.5 MB / 0.67 GB Notification: Performance of pre-optimization done (n=300): time 2.219e-05/1.474, allocations: 0 / 0.9588 GB, free: 147.5 MB / 0.67 GB Notification: Performance of matching and sorting (n=300): time 0.04189/1.516, allocations: 23.53 MB / 0.9818 GB, free: 126 MB / 0.67 GB Notification: Performance of inlineWhenForInitialization (initialization): time 3.938e-05/1.516, allocations: 91.92 kB / 0.9819 GB, free: 125.9 MB / 0.67 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01089/1.527, allocations: 8.03 MB / 0.9897 GB, free: 117.9 MB / 0.67 GB Notification: Performance of collectPreVariables (initialization): time 0.001633/1.529, allocations: 98.83 kB / 0.9898 GB, free: 117.8 MB / 0.67 GB Notification: Performance of collectInitialEqns (initialization): time 0.002688/1.532, allocations: 4.069 MB / 0.9938 GB, free: 113.8 MB / 0.67 GB Notification: Performance of collectInitialBindings (initialization): time 0.001867/1.534, allocations: 1.071 MB / 0.9948 GB, free: 112.7 MB / 0.67 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002465/1.536, allocations: 1.669 MB / 0.9965 GB, free: 111 MB / 0.67 GB Notification: Performance of setup shared object (initialization): time 3.474e-05/1.536, allocations: 301.1 kB / 0.9967 GB, free: 110.7 MB / 0.67 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.006603/1.543, allocations: 6.552 MB / 1.003 GB, free: 104.2 MB / 0.67 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.007621/1.55, allocations: 8.324 MB / 1.011 GB, free: 95.02 MB / 0.67 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01594/1.566, allocations: 15.25 MB / 1.026 GB, free: 79.18 MB / 0.67 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.636e-05/1.566, allocations: 16 kB / 1.026 GB, free: 79.17 MB / 0.67 GB Notification: Performance of matching and sorting (n=579) (initialization): time 0.02947/1.596, allocations: 17.59 MB / 1.043 GB, free: 61.53 MB / 0.67 GB Notification: Performance of prepare postOptimizeDAE: time 6.419e-05/1.596, allocations: 50.64 kB / 1.043 GB, free: 61.47 MB / 0.67 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.933e-05/1.596, allocations: 48 kB / 1.043 GB, free: 61.43 MB / 0.67 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.06269/1.659, allocations: 11.58 MB / 1.055 GB, free: 49.82 MB / 0.67 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007337/1.666, allocations: 1.549 MB / 1.056 GB, free: 48.27 MB / 0.67 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004437/1.67, allocations: 3.778 MB / 1.06 GB, free: 44.41 MB / 0.67 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009408/1.68, allocations: 0.519 MB / 1.06 GB, free: 43.89 MB / 0.67 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002126/1.682, allocations: 0.546 MB / 1.061 GB, free: 43.34 MB / 0.67 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0129/1.695, allocations: 8.818 MB / 1.07 GB, free: 34.51 MB / 0.67 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.008753/1.704, allocations: 8.28 MB / 1.078 GB, free: 25.38 MB / 0.67 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01739/1.721, allocations: 15.17 MB / 1.093 GB, free: 9.496 MB / 0.67 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.516e-05/1.721, allocations: 16 kB / 1.093 GB, free: 9.48 MB / 0.67 GB Notification: Performance of matching and sorting (n=579) (initialization_lambda0): time 0.02952/1.751, allocations: 17.04 MB / 1.109 GB, free: 8.355 MB / 0.6857 GB Notification: Performance of prepare postOptimizeDAE: time 8.028e-05/1.751, allocations: 57.64 kB / 1.109 GB, free: 8.293 MB / 0.6857 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.66e-05/1.751, allocations: 48 kB / 1.109 GB, free: 8.246 MB / 0.6857 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.3171/2.068, allocations: 11.13 MB / 1.12 GB, free: 203.5 MB / 0.6857 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00721/2.075, allocations: 1.547 MB / 1.122 GB, free: 203.5 MB / 0.6857 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008186/2.084, allocations: 8.169 MB / 1.13 GB, free: 200 MB / 0.6857 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009152/2.093, allocations: 0.5105 MB / 1.13 GB, free: 200 MB / 0.6857 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001916/2.095, allocations: 0.5448 MB / 1.131 GB, free: 200 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 (525): * Single equations (assignments): 520 * Array equations: 1 * Algorithm blocks: 1 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 3 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 3 systems {(2,5), (20,21), (2,5)} Notification: Performance of prepare postOptimizeDAE: time 0.002087/2.097, allocations: 491.6 kB / 1.131 GB, free: 199.8 MB / 0.6857 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002668/2.099, allocations: 0.7613 MB / 1.132 GB, free: 199.8 MB / 0.6857 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02151/2.121, allocations: 14.19 MB / 1.146 GB, free: 194.8 MB / 0.6857 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.077e-05/2.121, allocations: 36.45 kB / 1.146 GB, free: 194.8 MB / 0.6857 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 8.726e-06/2.121, allocations: 0 / 1.146 GB, free: 194.8 MB / 0.6857 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01891/2.14, allocations: 10.77 MB / 1.156 GB, free: 190 MB / 0.6857 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04857/2.189, allocations: 24.52 MB / 1.18 GB, free: 178.6 MB / 0.6857 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.415e-05/2.189, allocations: 1.062 kB / 1.18 GB, free: 178.6 MB / 0.6857 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.005004/2.194, allocations: 0.6901 MB / 1.181 GB, free: 178.6 MB / 0.6857 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0646/2.258, allocations: 10.75 MB / 1.191 GB, free: 177.4 MB / 0.6857 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001372/2.26, allocations: 104.9 kB / 1.191 GB, free: 177.3 MB / 0.6857 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.003393/2.263, allocations: 3.11 MB / 1.195 GB, free: 175.4 MB / 0.6857 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.436e-06/2.263, allocations: 4 kB / 1.195 GB, free: 175.4 MB / 0.6857 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02501/2.288, allocations: 19.26 MB / 1.213 GB, free: 162.4 MB / 0.6857 GB Notification: Performance of postOpt removeConstants (simulation): time 0.004885/2.293, allocations: 1.808 MB / 1.215 GB, free: 160.5 MB / 0.6857 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002415/2.295, allocations: 76.11 kB / 1.215 GB, free: 160.5 MB / 0.6857 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.006475/2.302, allocations: 375.6 kB / 1.216 GB, free: 160.1 MB / 0.6857 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001055/2.303, allocations: 214.4 kB / 1.216 GB, free: 159.9 MB / 0.6857 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0008031/2.304, allocations: 159.9 kB / 1.216 GB, free: 159.8 MB / 0.6857 GB Notification: Performance of sorting global known variables: time 0.00861/2.312, allocations: 5.531 MB / 1.221 GB, free: 154.2 MB / 0.6857 GB Notification: Performance of sort global known variables: time 4.61e-07/2.312, allocations: 4 kB / 1.221 GB, free: 154.2 MB / 0.6857 GB Notification: Performance of remove unused functions: time 0.01779/2.33, allocations: 4.817 MB / 1.226 GB, free: 149.6 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 (302): * Single equations (assignments): 293 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 4 * When equations: 2 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 3 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 3 systems {(3,4), (2,5), (20,21)} Notification: Performance of Backend phase and start with SimCode phase: time 0.002391/2.333, allocations: 0.5769 MB / 1.227 GB, free: 149 MB / 0.6857 GB Notification: Performance of simCode: created initialization part: time 0.04212/2.375, allocations: 29.2 MB / 1.255 GB, free: 119.8 MB / 0.6857 GB Notification: Performance of simCode: created event and clocks part: time 8.816e-06/2.375, allocations: 4 kB / 1.255 GB, free: 119.8 MB / 0.6857 GB Notification: Performance of simCode: created simulation system equations: time 0.008258/2.383, allocations: 6.35 MB / 1.261 GB, free: 113.5 MB / 0.6857 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02316/2.406, allocations: 4.918 MB / 1.266 GB, free: 108.8 MB / 0.6857 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02926/2.436, allocations: 23.51 MB / 1.289 GB, free: 85.27 MB / 0.6857 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00437/2.44, allocations: 5.238 MB / 1.294 GB, free: 80 MB / 0.6857 GB Notification: Performance of simCode: alias equations: time 0.01811/2.458, allocations: 4.995 MB / 1.299 GB, free: 74.99 MB / 0.6857 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.005266/2.463, allocations: 0.9008 MB / 1.3 GB, free: 74.09 MB / 0.6857 GB Notification: Performance of SimCode: time 1.533e-06/2.463, allocations: 0 / 1.3 GB, free: 74.09 MB / 0.6857 GB Notification: Performance of Templates: time 0.5671/3.031, allocations: 227.7 MB / 1.522 GB, free: 126.6 MB / 0.7013 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)