So, it looks like info is *significantly* faster on trunk than on the
1.7.x branch. Do we know why this is, and it is possible to backport
the responsible commits?
-Hyrum
On Sun, Jan 22, 2012 at 8:01 PM, <neels_at_apache.org> wrote:
> /home/neels/svnbench/20120123-002444
> Started at Mon Jan 23 00:24:44 UTC 2012
>
> *Disclaimer:* this tests only file://-URL access on a GNU/Linux VM.
> This is intended to measure changes in performance of the local working
> copy layer, *only*. These results are *not* generally true for everyone.
>
> Averaged-total results across all runs:
> ---------------------------------------
>
> COMPARE total_1.7.x to total_trunk
> TOTAL RUN timings: 184.9 seconds avg for total_1.7.x
> 135.3 seconds avg for total_trunk
> avg operation
> 0.73|-49.564 TOTAL RUN
> 0.90| -0.002 add
> 0.91| -0.100 checkout
> 0.84| -1.877 commit
> 0.96| -0.009 copy
> 0.83| -0.063 delete
> 0.12| -5.523 info
> 0.88| -0.607 merge
> 0.91| -0.001 mkdir
> 0.85| -0.002 prop mod
> 0.88| -0.001 propdel
> 0.86| -0.001 proplist
> 0.86| -0.001 propset
> 0.85| -0.001 resolve
> 0.91| -0.022 resolved
> 0.95| -0.011 status
> 0.86| -0.213 switch
> 0.90| -0.082 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means 'total_trunk' is faster than 'total_1.7.x')
>
>
> Above totals split into separate <dir-levels>x<dir-spread> runs:
> ----------------------------------------------------------------
>
> COMPARE 5x5_1.7.x to 5x5_trunk
> TOTAL RUN timings: 514.4 seconds avg for 5x5_1.7.x
> 369.8 seconds avg for 5x5_trunk
> avg operation
> 0.72|-144.614 TOTAL RUN
> 0.91| -0.002 add
> 0.90| -0.277 checkout
> 0.83| -5.469 commit
> 0.87| -0.038 copy
> 0.82| -0.173 delete
> 0.12|-16.430 info
> 0.88| -1.703 merge
> 0.91| -0.001 mkdir
> 0.85| -0.001 prop mod
> 0.88| -0.001 propdel
> 0.86| -0.001 proplist
> 0.86| -0.001 propset
> 0.87| -0.001 resolve
> 0.91| -0.058 resolved
> 0.96| -0.024 status
> 0.86| -0.577 switch
> 0.90| -0.209 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means '5x5_trunk' is faster than '5x5_1.7.x')
>
> COMPARE 100x1_1.7.x to 100x1_trunk
> TOTAL RUN timings: 28.9 seconds avg for 100x1_1.7.x
> 25.0 seconds avg for 100x1_trunk
> avg operation
> 0.87| -3.899 TOTAL RUN
> 0.86| -0.002 add
> 0.91| -0.018 checkout
> 0.89| -0.191 commit
> 0.95| -0.010 copy
> 0.89| -0.014 delete
> 0.50| -0.135 info
> 0.87| -0.106 merge
> 0.92| -0.001 mkdir
> 0.86| -0.002 prop mod
> 0.85| -0.002 propdel
> 0.85| -0.002 proplist
> 0.85| -0.002 propset
> 0.83| -0.001 resolve
> 0.91| -0.007 resolved
> 0.92| -0.006 status
> 0.88| -0.052 switch
> 0.89| -0.031 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means '100x1_trunk' is faster than '100x1_1.7.x')
>
> COMPARE 1x100_1.7.x to 1x100_trunk
> TOTAL RUN timings: 11.3 seconds avg for 1x100_1.7.x
> 11.1 seconds avg for 1x100_trunk
> avg operation
> 0.98| -0.180 TOTAL RUN
> 0.89| -0.005 add
> 0.94| -0.005 checkout
> 1.03| +0.028 commit
> 1.09| +0.021 copy
> 0.92| -0.002 delete
> 0.91| -0.004 info
> 0.96| -0.011 merge
> 0.85| -0.001 prop mod
> 0.86| -0.001 propdel
> 0.84| -0.001 proplist
> 0.85| -0.001 propset
> 0.83| -0.001 resolve
> 0.86| -0.002 resolved
> 0.90| -0.002 status
> 0.88| -0.010 switch
> 0.89| -0.005 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means '1x100_trunk' is faster than '1x100_1.7.x')
>
>
>
> More detail:
> ------------
>
> Timings for 5x5_1.7.x
> N min max avg operation (unit is seconds)
> 6 435.63 639.49 514.37 TOTAL RUN
> 887 0.01 1.85 0.02 add
> 12 0.02 6.73 2.90 checkout
> 48 1.61 176.26 32.65 commit
> 6 0.16 0.51 0.30 copy
> 6 0.88 1.20 0.98 delete
> 30 9.53 44.13 18.58 info
> 12 7.07 27.36 13.78 merge
> 960 0.01 0.37 0.01 mkdir
> 6407 0.01 0.52 0.01 prop mod
> 261 0.01 0.01 0.01 propdel
> 12550 0.01 0.02 0.01 proplist
> 25373 0.01 1.36 0.01 propset
> 12 0.01 0.01 0.01 resolve
> 12 0.52 0.74 0.63 resolved
> 84 0.21 1.42 0.55 status
> 6 3.85 4.53 4.06 switch
> 90 0.17 7.70 2.08 update
> ---
> Timings for 5x5_trunk
> N min max avg operation (unit is seconds)
> 6 349.11 407.43 369.75 TOTAL RUN
> 887 0.01 1.72 0.02 add
> 12 0.02 5.45 2.62 checkout
> 48 1.47 123.83 27.18 commit
> 6 0.21 0.37 0.26 copy
> 6 0.72 0.84 0.80 delete
> 30 0.73 3.66 2.15 info
> 12 6.02 18.91 12.07 merge
> 960 0.01 0.46 0.01 mkdir
> 6407 0.01 0.49 0.01 prop mod
> 261 0.01 0.01 0.01 propdel
> 12550 0.01 0.02 0.01 proplist
> 25373 0.01 0.51 0.01 propset
> 12 0.01 0.01 0.01 resolve
> 12 0.48 0.66 0.57 resolved
> 84 0.19 0.92 0.53 status
> 6 3.17 3.93 3.49 switch
> 90 0.16 5.75 1.87 update
> ---
> COMPARE 5x5_1.7.x to 5x5_trunk
> TOTAL RUN timings: 514.4 seconds avg for 5x5_1.7.x
> 369.8 seconds avg for 5x5_trunk
> min max avg operation
> 0.80|-86.523 0.64|-232.060 0.72|-144.614 TOTAL RUN
> 0.86| -0.001 0.93| -0.129 0.91| -0.002 add
> 0.97| -0.001 0.81| -1.278 0.90| -0.277 checkout
> 0.91| -0.149 0.70|-52.429 0.83| -5.469 commit
> 1.27| +0.044 0.72| -0.142 0.87| -0.038 copy
> 0.82| -0.162 0.70| -0.363 0.82| -0.173 delete
> 0.08| -8.802 0.08|-40.467 0.12|-16.430 info
> 0.85| -1.051 0.69| -8.441 0.88| -1.703 merge
> 0.86| -0.001 1.26| +0.096 0.91| -0.001 mkdir
> 0.86| -0.001 0.94| -0.032 0.85| -0.001 prop mod
> 0.85| -0.001 1.15| +0.002 0.88| -0.001 propdel
> 0.85| -0.001 0.88| -0.002 0.86| -0.001 proplist
> 0.84| -0.001 0.38| -0.851 0.86| -0.001 propset
> 0.87| -0.001 0.89| -0.001 0.87| -0.001 resolve
> 0.92| -0.039 0.90| -0.075 0.91| -0.058 resolved
> 0.94| -0.013 0.65| -0.503 0.96| -0.024 status
> 0.82| -0.678 0.87| -0.605 0.86| -0.577 switch
> 0.93| -0.013 0.75| -1.953 0.90| -0.209 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means '5x5_trunk' is faster than '5x5_1.7.x')
>
>
> Timings for 100x1_1.7.x
> N min max avg operation (unit is seconds)
> 6 27.33 30.34 28.94 TOTAL RUN
> 148 0.01 0.15 0.02 add
> 12 0.02 0.39 0.20 checkout
> 48 0.30 5.47 1.78 commit
> 6 0.16 0.23 0.18 copy
> 6 0.11 0.13 0.12 delete
> 30 0.14 0.50 0.27 info
> 12 0.61 1.12 0.81 merge
> 107 0.01 0.12 0.01 mkdir
> 361 0.01 0.02 0.01 prop mod
> 17 0.01 0.01 0.01 propdel
> 687 0.01 0.01 0.01 proplist
> 1113 0.01 0.02 0.01 propset
> 12 0.01 0.01 0.01 resolve
> 12 0.06 0.10 0.08 resolved
> 84 0.05 0.12 0.08 status
> 6 0.38 0.44 0.42 switch
> 90 0.04 0.59 0.27 update
> ---
> Timings for 100x1_trunk
> N min max avg operation (unit is seconds)
> 6 24.72 25.20 25.04 TOTAL RUN
> 148 0.01 0.13 0.01 add
> 12 0.02 0.37 0.19 checkout
> 48 0.30 3.75 1.59 commit
> 6 0.16 0.19 0.17 copy
> 6 0.10 0.11 0.11 delete
> 30 0.07 0.21 0.14 info
> 12 0.52 1.00 0.71 merge
> 107 0.01 0.19 0.01 mkdir
> 361 0.01 0.01 0.01 prop mod
> 17 0.01 0.01 0.01 propdel
> 687 0.01 0.01 0.01 proplist
> 1113 0.01 0.02 0.01 propset
> 12 0.01 0.01 0.01 resolve
> 12 0.05 0.09 0.07 resolved
> 84 0.04 0.11 0.07 status
> 6 0.33 0.39 0.37 switch
> 90 0.04 0.53 0.24 update
> ---
> COMPARE 100x1_1.7.x to 100x1_trunk
> TOTAL RUN timings: 28.9 seconds avg for 100x1_1.7.x
> 25.0 seconds avg for 100x1_trunk
> min max avg operation
> 0.90| -2.608 0.83| -5.141 0.87| -3.899 TOTAL RUN
> 0.85| -0.001 0.87| -0.020 0.86| -0.002 add
> 0.95| -0.001 0.94| -0.022 0.91| -0.018 checkout
> 1.00| +0.001 0.69| -1.721 0.89| -0.191 commit
> 1.03| +0.005 0.82| -0.042 0.95| -0.010 copy
> 0.90| -0.012 0.88| -0.016 0.89| -0.014 delete
> 0.47| -0.076 0.43| -0.283 0.50| -0.135 info
> 0.87| -0.082 0.90| -0.114 0.87| -0.106 merge
> 0.84| -0.002 1.61| +0.071 0.92| -0.001 mkdir
> 0.85| -0.001 0.84| -0.003 0.86| -0.002 prop mod
> 0.87| -0.001 0.87| -0.002 0.85| -0.002 propdel
> 0.83| -0.001 0.96| -0.001 0.85| -0.002 proplist
> 0.85| -0.001 0.83| -0.003 0.85| -0.002 propset
> 0.84| -0.001 0.84| -0.001 0.83| -0.001 resolve
> 0.92| -0.005 0.92| -0.008 0.91| -0.007 resolved
> 0.93| -0.003 0.93| -0.008 0.92| -0.006 status
> 0.87| -0.049 0.89| -0.051 0.88| -0.052 switch
> 0.89| -0.005 0.90| -0.062 0.89| -0.031 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means '100x1_trunk' is faster than '100x1_1.7.x')
>
>
> Timings for 1x100_1.7.x
> N min max avg operation (unit is seconds)
> 6 8.52 15.55 11.32 TOTAL RUN
> 6 0.04 0.04 0.04 add
> 12 0.02 0.13 0.08 checkout
> 48 0.21 4.06 0.89 commit
> 6 0.16 0.42 0.25 copy
> 6 0.02 0.02 0.02 delete
> 30 0.02 0.09 0.05 info
> 12 0.15 0.56 0.28 merge
> 186 0.01 0.01 0.01 prop mod
> 24 0.01 0.01 0.01 propdel
> 288 0.01 0.01 0.01 proplist
> 588 0.01 0.01 0.01 propset
> 12 0.01 0.01 0.01 resolve
> 12 0.01 0.02 0.02 resolved
> 84 0.01 0.02 0.02 status
> 6 0.08 0.08 0.08 switch
> 90 0.01 0.13 0.04 update
> ---
> Timings for 1x100_trunk
> N min max avg operation (unit is seconds)
> 6 8.23 13.59 11.14 TOTAL RUN
> 6 0.04 0.04 0.04 add
> 12 0.02 0.12 0.07 checkout
> 48 0.25 3.97 0.91 commit
> 6 0.15 0.44 0.27 copy
> 6 0.02 0.02 0.02 delete
> 30 0.02 0.08 0.04 info
> 12 0.12 0.60 0.26 merge
> 186 0.01 0.01 0.01 prop mod
> 24 0.01 0.01 0.01 propdel
> 288 0.01 0.01 0.01 proplist
> 588 0.01 0.01 0.01 propset
> 12 0.01 0.01 0.01 resolve
> 12 0.01 0.02 0.01 resolved
> 84 0.01 0.02 0.01 status
> 6 0.07 0.07 0.07 switch
> 90 0.01 0.12 0.04 update
> ---
> COMPARE 1x100_1.7.x to 1x100_trunk
> TOTAL RUN timings: 11.3 seconds avg for 1x100_1.7.x
> 11.1 seconds avg for 1x100_trunk
> min max avg operation
> 0.97| -0.290 0.87| -1.958 0.98| -0.180 TOTAL RUN
> 0.89| -0.004 0.89| -0.005 0.89| -0.005 add
> 0.95| -0.001 0.93| -0.009 0.94| -0.005 checkout
> 1.23| +0.047 0.98| -0.092 1.03| +0.028 commit
> 0.94| -0.009 1.03| +0.015 1.09| +0.021 copy
> 0.90| -0.002 0.91| -0.002 0.92| -0.002 delete
> 0.90| -0.002 0.90| -0.009 0.91| -0.004 info
> 0.80| -0.031 1.07| +0.039 0.96| -0.011 merge
> 0.85| -0.001 0.74| -0.003 0.85| -0.001 prop mod
> 0.86| -0.001 0.94| -0.001 0.86| -0.001 propdel
> 0.84| -0.001 0.93| -0.001 0.84| -0.001 proplist
> 0.85| -0.001 0.87| -0.001 0.85| -0.001 propset
> 0.84| -0.001 0.81| -0.002 0.83| -0.001 resolve
> 0.88| -0.002 0.75| -0.005 0.86| -0.002 resolved
> 0.89| -0.001 0.88| -0.003 0.90| -0.002 status
> 0.88| -0.010 0.88| -0.010 0.88| -0.010 switch
> 0.88| -0.002 0.90| -0.013 0.89| -0.005 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means '1x100_trunk' is faster than '1x100_1.7.x')
>
>
> Timings for total_1.7.x
> N min max avg operation (unit is seconds)
> 18 8.52 639.49 184.87 TOTAL RUN
> 1041 0.01 1.85 0.02 add
> 36 0.02 6.73 1.06 checkout
> 144 0.21 176.26 11.77 commit
> 18 0.16 0.51 0.24 copy
> 18 0.02 1.20 0.37 delete
> 90 0.02 44.13 6.30 info
> 36 0.15 27.36 4.96 merge
> 1067 0.01 0.37 0.01 mkdir
> 6954 0.01 0.52 0.01 prop mod
> 302 0.01 0.01 0.01 propdel
> 13525 0.01 0.02 0.01 proplist
> 27074 0.01 1.36 0.01 propset
> 36 0.01 0.01 0.01 resolve
> 36 0.01 0.74 0.24 resolved
> 252 0.01 1.42 0.22 status
> 18 0.08 4.53 1.52 switch
> 270 0.01 7.70 0.80 update
> ---
> Timings for total_trunk
> N min max avg operation (unit is seconds)
> 18 8.23 407.43 135.31 TOTAL RUN
> 1041 0.01 1.72 0.02 add
> 36 0.02 5.45 0.96 checkout
> 144 0.25 123.83 9.89 commit
> 18 0.15 0.44 0.23 copy
> 18 0.02 0.84 0.31 delete
> 90 0.02 3.66 0.78 info
> 36 0.12 18.91 4.35 merge
> 1067 0.01 0.46 0.01 mkdir
> 6954 0.01 0.49 0.01 prop mod
> 302 0.01 0.01 0.01 propdel
> 13525 0.01 0.02 0.01 proplist
> 27074 0.01 0.51 0.01 propset
> 36 0.01 0.01 0.01 resolve
> 36 0.01 0.66 0.22 resolved
> 252 0.01 0.92 0.21 status
> 18 0.07 3.93 1.31 switch
> 270 0.01 5.75 0.72 update
> ---
> COMPARE total_1.7.x to total_trunk
> TOTAL RUN timings: 184.9 seconds avg for total_1.7.x
> 135.3 seconds avg for total_trunk
> min max avg operation
> 0.97| -0.290 0.64|-232.060 0.73|-49.564 TOTAL RUN
> 0.86| -0.001 0.93| -0.129 0.90| -0.002 add
> 0.95| -0.001 0.81| -1.278 0.91| -0.100 checkout
> 1.23| +0.047 0.70|-52.429 0.84| -1.877 commit
> 0.95| -0.007 0.85| -0.075 0.96| -0.009 copy
> 0.90| -0.002 0.70| -0.363 0.83| -0.063 delete
> 0.90| -0.002 0.08|-40.467 0.12| -5.523 info
> 0.80| -0.031 0.69| -8.441 0.88| -0.607 merge
> 0.86| -0.001 1.26| +0.096 0.91| -0.001 mkdir
> 0.85| -0.001 0.94| -0.032 0.85| -0.002 prop mod
> 0.86| -0.001 1.00| -0.000 0.88| -0.001 propdel
> 0.84| -0.001 0.88| -0.002 0.86| -0.001 proplist
> 0.85| -0.001 0.38| -0.851 0.86| -0.001 propset
> 0.84| -0.001 0.89| -0.001 0.85| -0.001 resolve
> 0.88| -0.002 0.90| -0.075 0.91| -0.022 resolved
> 0.89| -0.001 0.65| -0.503 0.95| -0.011 status
> 0.88| -0.010 0.87| -0.605 0.86| -0.213 switch
> 0.88| -0.002 0.75| -1.953 0.90| -0.082 update
> (legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
> factor < 1 and difference < 0 means 'total_trunk' is faster than 'total_1.7.x')
>
>
>
> Had started at Mon Jan 23 00:24:44 UTC 2012,
> done at Mon Jan 23 02:01:00 UTC 2012
> /home/neels/svnbench/20120123-002444
> real 5775.97
> user 2271.55
> sys 1012.84
--
uberSVN: Apache Subversion Made Easy
http://www.uberSVN.com/
Received on 2012-01-23 11:55:42 CET