[svn.haxx.se] · SVN Dev · SVN Users · SVN Org · TSVN Dev · TSVN Users · Subclipse Dev · Subclipse Users · this month's index

Re: [svnbench] Revision: 1234657 compiled Jan 23 2012, 00:21:27

From: Hyrum K Wright <hyrum.wright_at_wandisco.com>
Date: Mon, 23 Jan 2012 04:55:07 -0600

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

This is an archived mail posted to the Subversion Dev mailing list.