You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@subversion.apache.org by ne...@apache.org on 2012/07/09 02:50:15 UTC

[svnbench] Revision: 1358887 compiled Jul 9 2012, 00:21:31

1.7.0@1181106 vs. trunk@1358779
Started at Mon Jul  9 00:25:08 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 1.7.0 to trunk@1358779
       N        avg         operation
      6/9    0.76|-36.256   TOTAL RUN
  372/530    0.79| -0.006   add
    12/18    0.96| -0.044   checkout
    48/72    0.98| -0.167   commit
      6/9    1.28| +0.062   copy
      6/9    0.93| -0.021   delete
    30/45    0.14| -5.096   info
    12/18    0.85| -0.594   merge
  312/516    0.82| -0.003   mkdir
    16/21    0.76| -0.003   propdel
    4K/6K    0.68| -0.004   proplist
    4K/6K    0.71| -0.004   propset
  370/591    0.71| -0.004   ps
    12/18    0.83| -0.002   resolve
    12/18    0.91| -0.021   resolved
   84/126    0.84| -0.034   status
      6/9    1.06| +0.089   switch
   84/126    0.87| -0.109   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779' is faster.
 "2/3" means: '1.7.0' has 2 timings on record, the other has 3.)


Above totals split into separate <dir-levels>x<dir-spread> runs:
----------------------------------------------------------------

Compare 1.7.0,5x5 to trunk@1358779,5x5
       N        avg         operation
      2/3    0.75|-102.669  TOTAL RUN
  314/456    0.79| -0.006   add
      4/6    0.96| -0.101   checkout
    16/24    0.99| -0.272   commit
      2/3    1.67| +0.171   copy
      2/3    0.94| -0.042   delete
    10/15    0.13|-15.155   info
      4/6    0.86| -1.442   merge
  284/470    0.81| -0.003   mkdir
    16/20    0.75| -0.003   propdel
    4K/6K    0.68| -0.004   proplist
    4K/6K    0.71| -0.004   propset
  352/552    0.71| -0.004   ps
      4/6    0.83| -0.002   resolve
      4/6    0.92| -0.049   resolved
    28/42    0.85| -0.078   status
      2/3    1.09| +0.354   switch
    28/42    0.88| -0.252   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779,5x5' is faster.
 "2/3" means: '1.7.0,5x5' has 2 timings on record, the other has 3.)

Compare 1.7.0,100x1 to trunk@1358779,100x1
       N        avg         operation
      2/3    0.82| -5.008   TOTAL RUN
    56/71    0.74| -0.005   add
      4/6    0.88| -0.024   checkout
    16/24    0.88| -0.214   commit
      2/3    1.13| +0.026   copy
      2/3    0.85| -0.016   delete
    10/15    0.54| -0.126   info
      4/6    0.72| -0.192   merge
    28/46    0.93| -0.001   mkdir
  218/337    0.68| -0.004   proplist
  176/273    0.70| -0.004   propset
    14/33    0.72| -0.004   ps
      4/6    0.82| -0.002   resolve
      4/6    0.90| -0.008   resolved
    28/42    0.75| -0.019   status
      2/3    0.82| -0.069   switch
    28/42    0.78| -0.066   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779,100x1' is faster.
 "2/3" means: '1.7.0,100x1' has 2 timings on record, the other has 3.)

Compare 1.7.0,1x100 to trunk@1358779,1x100
       N        avg         operation
      2/3    0.88| -1.091   TOTAL RUN
      2/3    0.85| -0.007   add
      4/6    0.90| -0.008   checkout
    16/24    0.98| -0.014   commit
      2/3    0.95| -0.010   copy
      2/3    0.86| -0.004   delete
    10/15    0.90| -0.006   info
      4/6    0.54| -0.148   merge
   74/111    0.65| -0.004   proplist
   84/126    0.67| -0.004   propset
      4/6    0.65| -0.004   ps
      4/6    0.84| -0.002   resolve
      4/6    0.71| -0.006   resolved
    28/42    0.73| -0.005   status
      2/3    0.79| -0.017   switch
    28/42    0.82| -0.009   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779,1x100' is faster.
 "2/3" means: '1.7.0,1x100' has 2 timings on record, the other has 3.)



More detail:
------------

Timings for 1.7.0,5x5
   N    min     max     avg   operation  (unit is seconds)
   2  415.62  418.75  417.19  TOTAL RUN
 314    0.01    2.29    0.03  add
   4    0.02    5.81    2.87  checkout
  16    1.63   85.70   24.58  commit
   2    0.25    0.26    0.26  copy
   2    0.74    0.80    0.77  delete
  10    9.43   43.37   17.43  info
   4    7.15   13.09   10.51  merge
 284    0.01    0.35    0.02  mkdir
  16    0.01    0.02    0.01  propdel
  4K    0.01    0.33    0.01  proplist
  4K    0.01    0.35    0.01  propset
 352    0.01    0.10    0.01  ps
   4    0.01    0.01    0.01  resolve
   4    0.49    0.71    0.60  resolved
  28    0.21    0.83    0.52  status
   2    3.62    4.56    4.09  switch
  28    0.27    6.66    2.17  update
--
Timings for trunk@1358779,5x5
   N    min     max     avg   operation  (unit is seconds)
   3  306.03  326.86  314.52  TOTAL RUN
 456    0.01    1.93    0.02  add
   6    0.02    5.70    2.77  checkout
  24    2.39   84.24   24.31  commit
   3    0.28    0.68    0.43  copy
   3    0.71    0.74    0.73  delete
  15    0.90    4.64    2.27  info
   6    4.77   14.57    9.07  merge
 470    0.01    0.41    0.01  mkdir
  20    0.01    0.02    0.01  propdel
  6K    0.01    0.22    0.01  proplist
  6K    0.01    0.73    0.01  propset
 552    0.01    0.17    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.33    0.88    0.55  resolved
  42    0.14    1.18    0.44  status
   3    3.35    5.76    4.44  switch
  42    0.22    5.47    1.91  update
--
Compare 1.7.0,5x5 to trunk@1358779,5x5
       N        min              max              avg         operation
      2/3    0.74|-109.585    0.78|-91.892     0.75|-102.669  TOTAL RUN
  314/456    0.68| -0.003     0.84| -0.364     0.79| -0.006   add
      4/6    0.76| -0.006     0.98| -0.109     0.96| -0.101   checkout
    16/24    1.47| +0.764     0.98| -1.461     0.99| -0.272   commit
      2/3    1.13| +0.032     2.58| +0.414     1.67| +0.171   copy
      2/3    0.96| -0.027     0.93| -0.057     0.94| -0.042   delete
    10/15    0.10| -8.522     0.11|-38.734     0.13|-15.155   info
      4/6    0.67| -2.379     1.11| +1.478     0.86| -1.442   merge
  284/470    0.68| -0.004     1.17| +0.062     0.81| -0.003   mkdir
    16/20    0.66| -0.004     0.98| -0.000     0.75| -0.003   propdel
    4K/6K    0.65| -0.003     0.67| -0.108     0.68| -0.004   proplist
    4K/6K    0.68| -0.003     2.08| +0.379     0.71| -0.004   propset
  352/552    0.68| -0.003     1.72| +0.073     0.71| -0.004   ps
      4/6    0.74| -0.003     0.89| -0.002     0.83| -0.002   resolve
      4/6    0.68| -0.158     1.25| +0.175     0.92| -0.049   resolved
    28/42    0.70| -0.063     1.43| +0.357     0.85| -0.078   status
      2/3    0.93| -0.269     1.26| +1.198     1.09| +0.354   switch
    28/42    0.83| -0.045     0.82| -1.181     0.88| -0.252   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779,5x5' is faster.
 "2/3" means: '1.7.0,5x5' has 2 timings on record, the other has 3.)


Timings for 1.7.0,100x1
   N    min     max     avg   operation  (unit is seconds)
   2   27.29   28.02   27.66  TOTAL RUN
  56    0.01    0.21    0.02  add
   4    0.03    0.38    0.20  checkout
  16    0.36    4.03    1.83  commit
   2    0.19    0.20    0.20  copy
   2    0.11    0.12    0.11  delete
  10    0.18    0.48    0.28  info
   4    0.60    0.83    0.69  merge
  28    0.01    0.02    0.01  mkdir
 218    0.01    0.02    0.01  proplist
 176    0.01    0.03    0.01  propset
  14    0.01    0.02    0.01  ps
   4    0.01    0.01    0.01  resolve
   4    0.05    0.10    0.07  resolved
  28    0.05    0.11    0.08  status
   2    0.38    0.40    0.39  switch
  28    0.05    0.60    0.30  update
--
Timings for trunk@1358779,100x1
   N    min     max     avg   operation  (unit is seconds)
   3   22.41   23.01   22.65  TOTAL RUN
  71    0.01    0.13    0.01  add
   6    0.02    0.34    0.18  checkout
  24    0.83    3.65    1.62  commit
   3    0.19    0.26    0.22  copy
   3    0.09    0.10    0.10  delete
  15    0.11    0.22    0.15  info
   6    0.42    0.67    0.49  merge
  46    0.01    0.15    0.01  mkdir
   1    0.01    0.01    0.01  propdel
 337    0.01    0.06    0.01  proplist
 273    0.01    0.01    0.01  propset
  33    0.01    0.01    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.06    0.07    0.07  resolved
  42    0.03    0.08    0.06  status
   3    0.31    0.33    0.32  switch
  42    0.04    0.45    0.23  update
--
Compare 1.7.0,100x1 to trunk@1358779,100x1
       N        min              max              avg         operation
      2/3    0.82| -4.880     0.82| -5.007     0.82| -5.008   TOTAL RUN
    56/71    0.71| -0.003     0.63| -0.077     0.74| -0.005   add
      4/6    0.68| -0.008     0.89| -0.040     0.88| -0.024   checkout
    16/24    2.33| +0.475     0.90| -0.384     0.88| -0.214   commit
      2/3    1.04| +0.008     1.25| +0.052     1.13| +0.026   copy
      2/3    0.89| -0.011     0.82| -0.022     0.85| -0.016   delete
    10/15    0.62| -0.069     0.47| -0.253     0.54| -0.126   info
      4/6    0.70| -0.177     0.80| -0.161     0.72| -0.192   merge
    28/46    0.70| -0.004     7.70| +0.130     0.93| -0.001   mkdir
  218/337    0.66| -0.003     2.97| +0.040     0.68| -0.004   proplist
  176/273    0.69| -0.003     0.58| -0.011     0.70| -0.004   propset
    14/33    0.68| -0.004     0.81| -0.003     0.72| -0.004   ps
      4/6    0.84| -0.002     0.79| -0.002     0.82| -0.002   resolve
      4/6    1.09| +0.005     0.79| -0.021     0.90| -0.008   resolved
    28/42    0.77| -0.010     0.74| -0.029     0.75| -0.019   status
      2/3    0.81| -0.074     0.84| -0.064     0.82| -0.069   switch
    28/42    0.87| -0.006     0.76| -0.143     0.78| -0.066   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779,100x1' is faster.
 "2/3" means: '1.7.0,100x1' has 2 timings on record, the other has 3.)


Timings for 1.7.0,1x100
   N    min     max     avg   operation  (unit is seconds)
   2    8.79    8.93    8.86  TOTAL RUN
   2    0.05    0.05    0.05  add
   4    0.02    0.15    0.08  checkout
  16    0.24    1.88    0.65  commit
   2    0.20    0.24    0.22  copy
   2    0.03    0.03    0.03  delete
  10    0.05    0.10    0.07  info
   4    0.16    0.61    0.32  merge
  74    0.01    0.02    0.01  proplist
  84    0.01    0.02    0.01  propset
   4    0.01    0.01    0.01  ps
   4    0.01    0.01    0.01  resolve
   4    0.02    0.02    0.02  resolved
  28    0.01    0.03    0.02  status
   2    0.08    0.08    0.08  switch
  28    0.02    0.14    0.05  update
--
Timings for trunk@1358779,1x100
   N    min     max     avg   operation  (unit is seconds)
   3    7.59    8.05    7.77  TOTAL RUN
   3    0.04    0.04    0.04  add
   6    0.02    0.13    0.07  checkout
  24    0.22    2.05    0.63  commit
   3    0.20    0.21    0.21  copy
   3    0.02    0.02    0.02  delete
  15    0.05    0.09    0.06  info
   6    0.10    0.30    0.17  merge
 111    0.01    0.01    0.01  proplist
 126    0.01    0.01    0.01  propset
   6    0.01    0.01    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.01    0.02    0.01  resolved
  42    0.01    0.02    0.01  status
   3    0.06    0.07    0.06  switch
  42    0.02    0.12    0.04  update
--
Compare 1.7.0,1x100 to trunk@1358779,1x100
       N        min              max              avg         operation
      2/3    0.86| -1.199     0.90| -0.883     0.88| -1.091   TOTAL RUN
      2/3    0.85| -0.007     0.86| -0.007     0.85| -0.007   add
      4/6    0.72| -0.007     0.90| -0.014     0.90| -0.008   checkout
    16/24    0.91| -0.021     1.09| +0.172     0.98| -0.014   commit
      2/3    1.01| +0.002     0.91| -0.022     0.95| -0.010   copy
      2/3    0.83| -0.005     0.91| -0.002     0.86| -0.004   delete
    10/15    0.92| -0.004     0.93| -0.007     0.90| -0.006   info
      4/6    0.62| -0.063     0.49| -0.312     0.54| -0.148   merge
   74/111    0.66| -0.003     0.76| -0.004     0.65| -0.004   proplist
   84/126    0.68| -0.004     0.61| -0.007     0.67| -0.004   propset
      4/6    0.69| -0.003     0.58| -0.006     0.65| -0.004   ps
      4/6    0.77| -0.002     0.92| -0.001     0.84| -0.002   resolve
      4/6    0.56| -0.008     0.82| -0.004     0.71| -0.006   resolved
    28/42    0.71| -0.004     0.77| -0.006     0.73| -0.005   status
      2/3    0.76| -0.018     0.82| -0.014     0.79| -0.017   switch
    28/42    0.80| -0.004     0.88| -0.017     0.82| -0.009   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779,1x100' is faster.
 "2/3" means: '1.7.0,1x100' has 2 timings on record, the other has 3.)


Timings for 1.7.0
   N    min     max     avg   operation  (unit is seconds)
   6    8.79  418.75  151.23  TOTAL RUN
 372    0.01    2.29    0.03  add
  12    0.02    5.81    1.05  checkout
  48    0.24   85.70    9.02  commit
   6    0.19    0.26    0.22  copy
   6    0.03    0.80    0.30  delete
  30    0.05   43.37    5.92  info
  12    0.16   13.09    3.84  merge
 312    0.01    0.35    0.02  mkdir
  16    0.01    0.02    0.01  propdel
  4K    0.01    0.33    0.01  proplist
  4K    0.01    0.35    0.01  propset
 370    0.01    0.10    0.01  ps
  12    0.01    0.01    0.01  resolve
  12    0.02    0.71    0.23  resolved
  84    0.01    0.83    0.21  status
   6    0.08    4.56    1.52  switch
  84    0.02    6.66    0.84  update
--
Timings for trunk@1358779
   N    min     max     avg   operation  (unit is seconds)
   9    7.59  326.86  114.98  TOTAL RUN
 530    0.01    1.93    0.02  add
  18    0.02    5.70    1.01  checkout
  72    0.22   84.24    8.85  commit
   9    0.19    0.68    0.29  copy
   9    0.02    0.74    0.28  delete
  45    0.05    4.64    0.83  info
  18    0.10   14.57    3.25  merge
 516    0.01    0.41    0.01  mkdir
  21    0.01    0.02    0.01  propdel
  6K    0.01    0.22    0.01  proplist
  6K    0.01    0.73    0.01  propset
 591    0.01    0.17    0.01  ps
  18    0.01    0.01    0.01  resolve
  18    0.01    0.88    0.21  resolved
 126    0.01    1.18    0.17  status
   9    0.06    5.76    1.61  switch
 126    0.02    5.47    0.73  update
--
Compare 1.7.0 to trunk@1358779
       N        min              max              avg         operation
      6/9    0.86| -1.199     0.78|-91.892     0.76|-36.256   TOTAL RUN
  372/530    0.68| -0.003     0.84| -0.364     0.79| -0.006   add
    12/18    0.75| -0.006     0.98| -0.109     0.96| -0.044   checkout
    48/72    0.91| -0.021     0.98| -1.461     0.98| -0.167   commit
      6/9    1.04| +0.008     2.58| +0.414     1.28| +0.062   copy
      6/9    0.83| -0.005     0.93| -0.057     0.93| -0.021   delete
    30/45    0.92| -0.004     0.11|-38.734     0.14| -5.096   info
    12/18    0.62| -0.063     1.11| +1.478     0.85| -0.594   merge
  312/516    0.68| -0.004     1.17| +0.062     0.82| -0.003   mkdir
    16/21    0.66| -0.004     0.98| -0.000     0.76| -0.003   propdel
    4K/6K    0.65| -0.003     0.67| -0.108     0.68| -0.004   proplist
    4K/6K    0.68| -0.003     2.08| +0.379     0.71| -0.004   propset
  370/591    0.68| -0.003     1.72| +0.073     0.71| -0.004   ps
    12/18    0.79| -0.002     0.89| -0.002     0.83| -0.002   resolve
    12/18    0.56| -0.008     1.25| +0.175     0.91| -0.021   resolved
   84/126    0.71| -0.004     1.43| +0.357     0.84| -0.034   status
      6/9    0.76| -0.018     1.26| +1.198     1.06| +0.089   switch
   84/126    0.80| -0.004     0.82| -1.181     0.87| -0.109   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1358779' is faster.
 "2/3" means: '1.7.0' has 2 timings on record, the other has 3.)



Had started at Mon Jul  9 00:25:08 UTC 2012,
       done at Mon Jul  9 00:50:15 UTC 2012
/home/neels/svnbench
real 1506.95
user 547.51
sys 305.83

Re: [svnbench] Revision: 1358887 compiled Jul 9 2012, 00:21:31

Posted by Neels J Hofmeyr <ne...@elego.de>.
/me notes: still unproportionally high N for proplist & propset.

On 2012-07-09 02:50, neels@apache.org wrote:
>     4K/6K    0.68| -0.004   proplist
>     4K/6K    0.71| -0.004   propset