Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3.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 3.2.3+maint.om/package.mo", uses=false) loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo", uses=false) Using package Annex60 with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo) Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(Annex60.Fluid.Examples.Performance.Example3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3") translateModel(Annex60.Fluid.Examples.Performance.Example3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.009629/0.009629, allocations: 98.08 kB / 15.73 MB, free: 6.055 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.001831/0.001832, allocations: 199.3 kB / 16.68 MB, free: 5.977 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.622/1.622, allocations: 205.1 MB / 222.5 MB, free: 12.24 MB / 190.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo): time 0.3091/0.3091, allocations: 54.97 MB / 324.9 MB, free: 4.855 MB / 270.1 MB Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed. Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed. Notification: Annex60 requested package Modelica of version 3.2.2. Modelica 3.2.3 is used instead which states that it is fully compatible without conversion script needed. Notification: Performance of FrontEnd - loaded program: time 0.001421/0.001421, allocations: 71.89 kB / 456.2 MB, free: 2.656 MB / 334.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1507/0.1522, allocations: 59.6 MB / 0.5037 GB, free: 7.102 MB / 398.1 MB Notification: Performance of NFInst.instantiate(Annex60.Fluid.Examples.Performance.Example3): time 0.3573/0.5095, allocations: 32.63 MB / 0.5356 GB, free: 6.844 MB / 414.1 MB Notification: Performance of NFInst.instExpressions: time 0.02412/0.5336, allocations: 12.57 MB / 0.5478 GB, free: 3.676 MB / 414.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003804/0.5375, allocations: 52.12 kB / 0.5479 GB, free: 3.656 MB / 414.1 MB Notification: Performance of NFTyping.typeComponents: time 0.003496/0.5411, allocations: 0.7433 MB / 0.5486 GB, free: 3.246 MB / 414.1 MB Notification: Performance of NFTyping.typeBindings: time 0.008026/0.5491, allocations: 1.513 MB / 0.5501 GB, free: 2.379 MB / 414.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.006711/0.5558, allocations: 1.407 MB / 0.5515 GB, free: 1.66 MB / 414.1 MB Notification: Performance of NFFlatten.flatten: time 0.008333/0.5642, allocations: 4.744 MB / 0.5561 GB, free: 15.48 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.003708/0.5679, allocations: 2.001 MB / 0.5581 GB, free: 14.64 MB / 430.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.004893/0.5728, allocations: 1.844 MB / 0.5599 GB, free: 13.86 MB / 430.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.00326/0.5761, allocations: 1.366 MB / 0.5612 GB, free: 13.25 MB / 430.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0003794/0.5765, allocations: 148.5 kB / 0.5613 GB, free: 13.24 MB / 430.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.00406/0.5806, allocations: 1.18 MB / 0.5625 GB, free: 12.93 MB / 430.1 MB Notification: Performance of NFScalarize.scalarize: time 0.0007499/0.5814, allocations: 476.6 kB / 0.5629 GB, free: 12.67 MB / 430.1 MB Notification: Performance of NFVerifyModel.verify: time 0.001881/0.5832, allocations: 1.021 MB / 0.5639 GB, free: 12.06 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.005633/0.5889, allocations: 2.949 MB / 0.5668 GB, free: 10.38 MB / 430.1 MB Notification: Performance of FrontEnd - DAE generated: time 5.911e-06/0.5889, allocations: 1.094 kB / 0.5668 GB, free: 10.38 MB / 430.1 MB Notification: Performance of FrontEnd: time 2.174e-06/0.5889, allocations: 0 / 0.5668 GB, free: 10.38 MB / 430.1 MB Notification: Performance of Transformations before backend: time 4.858e-05/0.589, allocations: 0 / 0.5668 GB, free: 10.38 MB / 430.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 242 * Number of variables: 242 Notification: Performance of Generate backend data structure: time 0.005226/0.5942, allocations: 2.431 MB / 0.5692 GB, free: 8.562 MB / 430.1 MB Notification: Performance of prepare preOptimizeDAE: time 5.279e-05/0.5943, allocations: 10 kB / 0.5692 GB, free: 8.562 MB / 430.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001533/0.5958, allocations: 310 kB / 0.5695 GB, free: 8.41 MB / 430.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.003544/0.5994, allocations: 1.688 MB / 0.5711 GB, free: 7.207 MB / 430.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001022/0.5995, allocations: 79.12 kB / 0.5712 GB, free: 7.176 MB / 430.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001699/0.5997, allocations: 109.5 kB / 0.5713 GB, free: 7.141 MB / 430.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.003768/0.6034, allocations: 1.519 MB / 0.5728 GB, free: 6.129 MB / 430.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 3.172e-05/0.6035, allocations: 2.094 kB / 0.5728 GB, free: 6.129 MB / 430.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001111/0.6036, allocations: 43.5 kB / 0.5729 GB, free: 6.098 MB / 430.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.019e-05/0.6037, allocations: 25.12 kB / 0.5729 GB, free: 6.098 MB / 430.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003187/0.6069, allocations: 1.181 MB / 0.574 GB, free: 5.504 MB / 430.1 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.009668/0.6165, allocations: 6.896 MB / 0.5808 GB, free: 15.39 MB / 446.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.002546/0.6191, allocations: 0.7608 MB / 0.5815 GB, free: 14.98 MB / 446.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.0007632/0.6199, allocations: 390.8 kB / 0.5819 GB, free: 14.79 MB / 446.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.003068/0.623, allocations: 1.437 MB / 0.5833 GB, free: 13.54 MB / 446.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001962/0.625, allocations: 0.5665 MB / 0.5838 GB, free: 13.19 MB / 446.1 MB Notification: Performance of preOpt simplifyInStream (simulation): time 0.0007125/0.6257, allocations: 90.42 kB / 0.5839 GB, free: 13.13 MB / 446.1 MB Notification: Performance of pre-optimization done (n=45): time 3.026e-06/0.6257, allocations: 0 / 0.5839 GB, free: 13.13 MB / 446.1 MB Notification: Performance of matching and sorting (n=50): time 0.011/0.6367, allocations: 3.103 MB / 0.587 GB, free: 10.98 MB / 446.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001345/0.6369, allocations: 137 kB / 0.5871 GB, free: 10.82 MB / 446.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003157/0.6401, allocations: 1.787 MB / 0.5888 GB, free: 8.988 MB / 446.1 MB Notification: Performance of collectPreVariables (initialization): time 0.0001046/0.6402, allocations: 41.64 kB / 0.5889 GB, free: 8.941 MB / 446.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.0009096/0.6411, allocations: 0.8824 MB / 0.5897 GB, free: 8.051 MB / 446.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.0002017/0.6413, allocations: 178.5 kB / 0.5899 GB, free: 7.875 MB / 446.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003356/0.6416, allocations: 214.5 kB / 0.5901 GB, free: 7.656 MB / 446.1 MB Notification: Performance of setup shared object (initialization): time 0.0001585/0.6418, allocations: 341.9 kB / 0.5904 GB, free: 7.316 MB / 446.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009168/0.6427, allocations: 395.2 kB / 0.5908 GB, free: 6.93 MB / 446.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.001304/0.644, allocations: 0.8557 MB / 0.5916 GB, free: 5.848 MB / 446.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.002093/0.6461, allocations: 1.131 MB / 0.5927 GB, free: 4.559 MB / 446.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.755e-06/0.6462, allocations: 8 kB / 0.5928 GB, free: 4.551 MB / 446.1 MB Notification: Performance of matching and sorting (n=108) (initialization): time 0.004889/0.6511, allocations: 1.629 MB / 0.5943 GB, free: 2.902 MB / 446.1 MB Notification: Performance of prepare postOptimizeDAE: time 6.452e-05/0.6511, allocations: 37.7 kB / 0.5944 GB, free: 2.859 MB / 446.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.253e-05/0.6512, allocations: 12 kB / 0.5944 GB, free: 2.848 MB / 446.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.0009322/0.6521, allocations: 307.9 kB / 0.5947 GB, free: 2.547 MB / 446.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007071/0.6528, allocations: 191.7 kB / 0.5949 GB, free: 2.359 MB / 446.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008426/0.6613, allocations: 3.894 MB / 0.5987 GB, free: 14.34 MB / 462.1 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001992/0.6633, allocations: 64.78 kB / 0.5987 GB, free: 14.28 MB / 462.1 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002153/0.6635, allocations: 55.97 kB / 0.5988 GB, free: 14.22 MB / 462.1 MB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001765/0.6653, allocations: 0.7204 MB / 0.5995 GB, free: 13.5 MB / 462.1 MB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00109/0.6664, allocations: 0.7699 MB / 0.6002 GB, free: 12.5 MB / 462.1 MB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00155/0.668, allocations: 1.003 MB / 0.6012 GB, free: 11.34 MB / 462.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.888e-06/0.668, allocations: 4 kB / 0.6012 GB, free: 11.34 MB / 462.1 MB Notification: Performance of matching and sorting (n=108) (initialization_lambda0): time 0.002818/0.6708, allocations: 1.35 MB / 0.6025 GB, free: 9.949 MB / 462.1 MB Notification: Performance of prepare postOptimizeDAE: time 9.597e-05/0.6709, allocations: 34.58 kB / 0.6026 GB, free: 9.91 MB / 462.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.508e-05/0.671, allocations: 15.98 kB / 0.6026 GB, free: 9.895 MB / 462.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.0002612/0.6712, allocations: 124.8 kB / 0.6027 GB, free: 9.77 MB / 462.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006883/0.6719, allocations: 180.3 kB / 0.6029 GB, free: 9.594 MB / 462.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002498/0.6745, allocations: 2.315 MB / 0.6051 GB, free: 7.145 MB / 462.1 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001539/0.676, allocations: 60.34 kB / 0.6052 GB, free: 7.086 MB / 462.1 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001049/0.6761, allocations: 48 kB / 0.6052 GB, free: 7.039 MB / 462.1 MB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 50 * Number of states: 0 () * Number of discrete variables: 8 (pulse.count,$PRE.pulse.count,pulse.T_start,$PRE.pulse.T_start,$whenCondition1,nRes.y,from_dp.y,mergeDp.y) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (94): * Single equations (assignments): 91 * Array equations: 1 * Algorithm blocks: 1 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * 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): 1 system {(1,12)} Notification: Performance of prepare postOptimizeDAE: time 0.0004263/0.6766, allocations: 251.9 kB / 0.6055 GB, free: 6.785 MB / 462.1 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005336/0.6771, allocations: 190.7 kB / 0.6057 GB, free: 6.598 MB / 462.1 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004173/0.6813, allocations: 1.262 MB / 0.6069 GB, free: 5.32 MB / 462.1 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.688e-05/0.6813, allocations: 7.938 kB / 0.6069 GB, free: 5.312 MB / 462.1 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 3.937e-06/0.6813, allocations: 0 / 0.6069 GB, free: 5.312 MB / 462.1 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003359/0.6847, allocations: 1.219 MB / 0.6081 GB, free: 4.02 MB / 462.1 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.007622/0.6923, allocations: 2.507 MB / 0.6105 GB, free: 1.438 MB / 462.1 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.115e-06/0.6924, allocations: 0 / 0.6105 GB, free: 1.438 MB / 462.1 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00049/0.6929, allocations: 99.86 kB / 0.6106 GB, free: 1.34 MB / 462.1 MB Notification: Performance of postOpt tearingSystem (simulation): time 0.0008007/0.6937, allocations: 280 kB / 0.6109 GB, free: 1.066 MB / 462.1 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.032e-05/0.6938, allocations: 23.97 kB / 0.6109 GB, free: 1.043 MB / 462.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.008153/0.7019, allocations: 3.66 MB / 0.6145 GB, free: 13.24 MB / 478.1 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.026e-06/0.702, allocations: 0 / 0.6145 GB, free: 13.24 MB / 478.1 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005264/0.7073, allocations: 1.725 MB / 0.6162 GB, free: 11.48 MB / 478.1 MB Notification: Performance of postOpt removeConstants (simulation): time 0.001113/0.7084, allocations: 315.8 kB / 0.6165 GB, free: 11.16 MB / 478.1 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006996/0.7091, allocations: 12 kB / 0.6165 GB, free: 11.14 MB / 478.1 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001373/0.7105, allocations: 43.91 kB / 0.6165 GB, free: 11.1 MB / 478.1 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002129/0.7108, allocations: 60.41 kB / 0.6166 GB, free: 11.04 MB / 478.1 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.584e-05/0.7109, allocations: 27.97 kB / 0.6166 GB, free: 11.02 MB / 478.1 MB Notification: Performance of sorting global known variables: time 0.002425/0.7133, allocations: 1.23 MB / 0.6178 GB, free: 9.762 MB / 478.1 MB Notification: Performance of sort global known variables: time 1.8e-07/0.7133, allocations: 4 kB / 0.6178 GB, free: 9.758 MB / 478.1 MB Notification: Performance of remove unused functions: time 0.004247/0.7176, allocations: 0.7574 MB / 0.6186 GB, free: 9 MB / 478.1 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 3 * Number of states: 1 (pump.vol.dynBal.medium.T) * 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 (45): * Single equations (assignments): 39 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 3 * When equations: 2 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * 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): 1 system {(1,12)} Notification: Performance of Backend phase and start with SimCode phase: time 0.001361/0.7189, allocations: 379.2 kB / 0.6189 GB, free: 8.625 MB / 478.1 MB Notification: Performance of simCode: created initialization part: time 0.004229/0.7232, allocations: 1.846 MB / 0.6207 GB, free: 6.73 MB / 478.1 MB Notification: Performance of simCode: created event and clocks part: time 7.764e-06/0.7232, allocations: 4.625 kB / 0.6207 GB, free: 6.727 MB / 478.1 MB Notification: Performance of simCode: created simulation system equations: time 0.001468/0.7247, allocations: 0.5876 MB / 0.6213 GB, free: 6.129 MB / 478.1 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004801/0.7295, allocations: 0.7953 MB / 0.6221 GB, free: 5.355 MB / 478.1 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006935/0.7365, allocations: 3.03 MB / 0.6251 GB, free: 2.285 MB / 478.1 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001593/0.7381, allocations: 1.496 MB / 0.6265 GB, free: 0.7578 MB / 478.1 MB Notification: Performance of simCode: alias equations: time 0.001907/0.74, allocations: 450.6 kB / 0.6269 GB, free: 320 kB / 478.1 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004248/0.7405, allocations: 131.4 kB / 0.6271 GB, free: 188 kB / 478.1 MB Notification: Performance of SimCode: time 4.799e-06/0.7405, allocations: 0 / 0.6271 GB, free: 188 kB / 478.1 MB Notification: Performance of Templates: time 0.0685/0.809, allocations: 37.16 MB / 0.6634 GB, free: 11.27 MB / 0.5137 GB make -j1 -f Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3.makefile (rm -f Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3.pipe ; mkfifo Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3.pipe ; head -c 1048576 < Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3.pipe >> ../files/Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3.sim & ./Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3 -abortSlowSimulation -alarm=480 -lv LOG_STATS > Annex60_1.0.0_Annex60.Fluid.Examples.Performance.Example3.pipe 2>&1)