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/23 02:54:47 UTC

[svnbench] Revision: 1364467 compiled Jul 23 2012, 00:21:30

1.7.0@1181106 vs. trunk@1364434
Started at Mon Jul 23 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@1364434
       N        avg         operation
     12/9    0.87|-20.852   TOTAL RUN
  744/530    0.78| -0.005   add
    24/18    0.88| -0.128   checkout
    96/72    1.18| +1.744   commit
     12/9    1.04| +0.013   copy
     12/9    0.82| -0.064   delete
    60/45    0.15| -5.044   info
    24/18    0.93| -0.274   merge
  624/516    0.72| -0.005   mkdir
    32/21    0.76| -0.003   propdel
    8K/6K    0.67| -0.004   proplist
    9K/6K    0.71| -0.004   propset
  740/591    0.68| -0.004   ps
    24/18    0.80| -0.002   resolve
    24/18    0.75| -0.061   resolved
  168/126    0.92| -0.016   status
     12/9    0.66| -0.526   switch
  168/126    0.87| -0.116   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434' 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@1364434,5x5
       N        avg         operation
      4/3    0.85|-66.173   TOTAL RUN
  628/456    0.78| -0.006   add
      8/6    0.88| -0.346   checkout
    32/24    1.16| +4.307   commit
      4/3    1.33| +0.088   copy
      4/3    0.81| -0.175   delete
    20/15    0.14|-15.015   info
      8/6    0.95| -0.585   merge
  568/470    0.75| -0.004   mkdir
    32/20    0.75| -0.003   propdel
    8K/6K    0.67| -0.004   proplist
    8K/6K    0.70| -0.004   propset
  704/552    0.68| -0.004   ps
      8/6    0.73| -0.003   resolve
      8/6    0.73| -0.168   resolved
    56/42    0.95| -0.025   status
      4/3    0.64| -1.490   switch
    56/42    0.87| -0.292   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434,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@1364434,100x1
       N        avg         operation
      4/3    1.12| +3.540   TOTAL RUN
   112/71    0.79| -0.004   add
      8/6    0.85| -0.032   checkout
    32/24    1.40| +0.815   commit
      4/3    0.89| -0.050   copy
      4/3    0.91| -0.011   delete
    20/15    0.59| -0.113   info
      8/6    0.82| -0.125   merge
    56/46    0.50| -0.011   mkdir
  436/337    0.71| -0.004   proplist
  352/273    0.75| -0.004   propset
    28/33    0.78| -0.003   ps
      8/6    0.86| -0.001   resolve
      8/6    0.89| -0.008   resolved
    56/42    0.77| -0.018   status
      4/3    0.81| -0.075   switch
    56/42    0.83| -0.049   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434,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@1364434,1x100
       N        avg         operation
      4/3    1.01| +0.076   TOTAL RUN
      4/3    0.88| -0.006   add
      8/6    0.92| -0.007   checkout
    32/24    1.17| +0.110   commit
      4/3    1.00| +0.001   copy
      4/3    0.83| -0.005   delete
    20/15    0.93| -0.004   info
      8/6    0.61| -0.111   merge
  148/111    0.66| -0.004   proplist
  168/126    0.71| -0.003   propset
      8/6    0.70| -0.003   ps
      8/6    0.82| -0.002   resolve
      8/6    0.74| -0.005   resolved
    56/42    0.76| -0.004   status
      4/3    0.85| -0.012   switch
    56/42    0.86| -0.007   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434,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)
   4  412.54  483.55  432.61  TOTAL RUN
 628    0.01    2.29    0.03  add
   8    0.02    5.81    2.89  checkout
  32    1.63  115.37   26.30  commit
   4    0.25    0.31    0.27  copy
   4    0.74    1.24    0.92  delete
  20    8.63   43.37   17.36  info
   8    7.12   15.01   10.65  merge
 568    0.01    0.43    0.02  mkdir
  32    0.01    0.02    0.01  propdel
  8K    0.01    0.33    0.01  proplist
  8K    0.01    1.10    0.01  propset
 704    0.01    0.54    0.01  ps
   8    0.01    0.01    0.01  resolve
   8    0.49    0.89    0.63  resolved
  56    0.21    0.83    0.53  status
   4    3.62    4.68    4.19  switch
  56    0.26    6.91    2.24  update
--
Timings for trunk@1364434,5x5
   N    min     max     avg   operation  (unit is seconds)
   3  356.92  372.74  366.44  TOTAL RUN
 456    0.01    1.70    0.02  add
   6    0.02    5.15    2.54  checkout
  24    2.14  118.12   30.60  commit
   3    0.31    0.39    0.36  copy
   3    0.63    0.81    0.74  delete
  15    0.80    4.21    2.35  info
   6    4.96   17.93   10.06  merge
 470    0.01    0.50    0.01  mkdir
  20    0.01    0.04    0.01  propdel
  6K    0.01    0.32    0.01  proplist
  6K    0.01    1.01    0.01  propset
 552    0.01    0.28    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.33    0.65    0.46  resolved
  42    0.14    2.56    0.50  status
   3    2.58    2.80    2.70  switch
  42    0.22    6.37    1.95  update
--
Compare 1.7.0,5x5 to trunk@1364434,5x5
       N        min              max              avg         operation
      4/3    0.87|-55.623     0.77|-110.814    0.85|-66.173   TOTAL RUN
  628/456    0.70| -0.003     0.74| -0.594     0.78| -0.006   add
      8/6    0.79| -0.005     0.89| -0.658     0.88| -0.346   checkout
    32/24    1.31| +0.510     1.02| +2.750     1.16| +4.307   commit
      4/3    1.26| +0.064     1.26| +0.081     1.33| +0.088   copy
      4/3    0.86| -0.107     0.65| -0.432     0.81| -0.175   delete
    20/15    0.09| -7.831     0.10|-39.166     0.14|-15.015   info
      8/6    0.70| -2.163     1.20| +2.928     0.95| -0.585   merge
  568/470    0.70| -0.003     1.16| +0.068     0.75| -0.004   mkdir
    32/20    0.72| -0.003     1.67| +0.015     0.75| -0.003   propdel
    8K/6K    0.67| -0.003     0.96| -0.014     0.67| -0.004   proplist
    8K/6K    0.70| -0.003     0.92| -0.085     0.70| -0.004   propset
  704/552    0.70| -0.003     0.52| -0.260     0.68| -0.004   ps
      8/6    0.77| -0.002     0.64| -0.005     0.73| -0.003   resolve
      8/6    0.67| -0.162     0.73| -0.242     0.73| -0.168   resolved
    56/42    0.68| -0.067     3.08| +1.727     0.95| -0.025   status
      4/3    0.71| -1.045     0.60| -1.881     0.64| -1.490   switch
    56/42    0.83| -0.044     0.92| -0.536     0.87| -0.292   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434,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)
   4   26.80   37.00   29.78  TOTAL RUN
 112    0.01    0.21    0.02  add
   8    0.02    0.43    0.21  checkout
  32    0.35   10.98    2.04  commit
   4    0.19    1.29    0.47  copy
   4    0.11    0.13    0.12  delete
  20    0.18    0.48    0.27  info
   8    0.60    0.83    0.69  merge
  56    0.01    0.47    0.02  mkdir
 436    0.01    0.06    0.01  proplist
 352    0.01    0.04    0.01  propset
  28    0.01    0.02    0.01  ps
   8    0.01    0.01    0.01  resolve
   8    0.05    0.10    0.08  resolved
  56    0.05    0.13    0.08  status
   4    0.38    0.41    0.40  switch
  56    0.05    0.60    0.29  update
--
Timings for trunk@1364434,100x1
   N    min     max     avg   operation  (unit is seconds)
   3   28.70   37.96   33.32  TOTAL RUN
  71    0.01    0.13    0.02  add
   6    0.02    0.33    0.18  checkout
  24    1.01    9.00    2.86  commit
   3    0.26    0.68    0.42  copy
   3    0.10    0.12    0.11  delete
  15    0.11    0.23    0.16  info
   6    0.43    0.76    0.56  merge
  46    0.01    0.02    0.01  mkdir
   1    0.01    0.01    0.01  propdel
 337    0.01    0.02    0.01  proplist
 273    0.01    0.02    0.01  propset
  33    0.01    0.02    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.06    0.08    0.07  resolved
  42    0.04    0.09    0.06  status
   3    0.32    0.33    0.32  switch
  42    0.04    0.49    0.24  update
--
Compare 1.7.0,100x1 to trunk@1364434,100x1
       N        min              max              avg         operation
      4/3    1.07| +1.900     1.03| +0.959     1.12| +3.540   TOTAL RUN
   112/71    0.72| -0.003     0.61| -0.080     0.79| -0.004   add
      8/6    0.75| -0.006     0.78| -0.096     0.85| -0.032   checkout
    32/24    2.91| +0.665     0.82| -1.973     1.40| +0.815   commit
      4/3    1.40| +0.074     0.52| -0.618     0.89| -0.050   copy
      4/3    0.94| -0.006     0.93| -0.009     0.91| -0.011   delete
    20/15    0.65| -0.062     0.48| -0.252     0.59| -0.113   info
      8/6    0.72| -0.166     0.91| -0.070     0.82| -0.125   merge
    56/46    0.74| -0.003     0.04| -0.450     0.50| -0.011   mkdir
  436/337    0.69| -0.003     0.25| -0.046     0.71| -0.004   proplist
  352/273    0.71| -0.003     0.48| -0.022     0.75| -0.004   propset
    28/33    0.71| -0.003     0.92| -0.002     0.78| -0.003   ps
      8/6    0.88| -0.001     0.90| -0.001     0.86| -0.001   resolve
      8/6    1.10| +0.005     0.78| -0.021     0.89| -0.008   resolved
    56/42    0.79| -0.009     0.72| -0.036     0.77| -0.018   status
      4/3    0.83| -0.067     0.81| -0.077     0.81| -0.075   switch
    56/42    0.86| -0.007     0.82| -0.109     0.83| -0.049   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434,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)
   4    7.87    9.12    8.68  TOTAL RUN
   4    0.05    0.05    0.05  add
   8    0.02    0.15    0.08  checkout
  32    0.19    2.05    0.65  commit
   4    0.18    0.24    0.21  copy
   4    0.03    0.03    0.03  delete
  20    0.05    0.10    0.06  info
   8    0.16    0.61    0.28  merge
 148    0.01    0.02    0.01  proplist
 168    0.01    0.02    0.01  propset
   8    0.01    0.01    0.01  ps
   8    0.01    0.01    0.01  resolve
   8    0.02    0.02    0.02  resolved
  56    0.01    0.03    0.02  status
   4    0.08    0.08    0.08  switch
  56    0.02    0.14    0.05  update
--
Timings for trunk@1364434,1x100
   N    min     max     avg   operation  (unit is seconds)
   3    7.23    9.93    8.75  TOTAL RUN
   3    0.04    0.04    0.04  add
   6    0.02    0.14    0.08  checkout
  24    0.19    3.23    0.76  commit
   3    0.18    0.27    0.21  copy
   3    0.02    0.02    0.02  delete
  15    0.05    0.10    0.06  info
   6    0.11    0.28    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.07  switch
  42    0.02    0.13    0.04  update
--
Compare 1.7.0,1x100 to trunk@1364434,1x100
       N        min              max              avg         operation
      4/3    0.92| -0.636     1.09| +0.807     1.01| +0.076   TOTAL RUN
      4/3    0.87| -0.006     0.90| -0.005     0.88| -0.006   add
      8/6    0.81| -0.004     0.91| -0.013     0.92| -0.007   checkout
    32/24    1.00| +0.001     1.58| +1.182     1.17| +0.110   commit
      4/3    1.01| +0.001     1.12| +0.029     1.00| +0.001   copy
      4/3    0.87| -0.003     0.76| -0.007     0.83| -0.005   delete
    20/15    0.94| -0.003     0.98| -0.002     0.93| -0.004   info
      8/6    0.67| -0.052     0.46| -0.326     0.61| -0.111   merge
  148/111    0.70| -0.003     0.63| -0.006     0.66| -0.004   proplist
  168/126    0.73| -0.003     0.73| -0.005     0.71| -0.003   propset
      8/6    0.72| -0.003     0.73| -0.004     0.70| -0.003   ps
      8/6    0.83| -0.002     0.84| -0.002     0.82| -0.002   resolve
      8/6    0.60| -0.007     0.80| -0.004     0.74| -0.005   resolved
    56/42    0.75| -0.003     0.77| -0.006     0.76| -0.004   status
      4/3    0.79| -0.016     0.92| -0.007     0.85| -0.012   switch
    56/42    0.85| -0.003     0.90| -0.014     0.86| -0.007   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434,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)
  12    7.87  483.55  157.02  TOTAL RUN
 744    0.01    2.29    0.02  add
  24    0.02    5.81    1.06  checkout
  96    0.19  115.37    9.66  commit
  12    0.18    1.29    0.32  copy
  12    0.03    1.24    0.35  delete
  60    0.05   43.37    5.90  info
  24    0.16   15.01    3.87  merge
 624    0.01    0.47    0.02  mkdir
  32    0.01    0.02    0.01  propdel
  8K    0.01    0.33    0.01  proplist
  9K    0.01    1.10    0.01  propset
 740    0.01    0.54    0.01  ps
  24    0.01    0.01    0.01  resolve
  24    0.02    0.89    0.24  resolved
 168    0.01    0.83    0.21  status
  12    0.08    4.68    1.55  switch
 168    0.02    6.91    0.86  update
--
Timings for trunk@1364434
   N    min     max     avg   operation  (unit is seconds)
   9    7.23  372.74  136.17  TOTAL RUN
 530    0.01    1.70    0.02  add
  18    0.02    5.15    0.93  checkout
  72    0.19  118.12   11.41  commit
   9    0.18    0.68    0.33  copy
   9    0.02    0.81    0.29  delete
  45    0.05    4.21    0.86  info
  18    0.11   17.93    3.60  merge
 516    0.01    0.50    0.01  mkdir
  21    0.01    0.04    0.01  propdel
  6K    0.01    0.32    0.01  proplist
  6K    0.01    1.01    0.01  propset
 591    0.01    0.28    0.01  ps
  18    0.01    0.01    0.01  resolve
  18    0.01    0.65    0.18  resolved
 126    0.01    2.56    0.19  status
   9    0.06    2.80    1.03  switch
 126    0.02    6.37    0.74  update
--
Compare 1.7.0 to trunk@1364434
       N        min              max              avg         operation
     12/9    0.92| -0.636     0.77|-110.814    0.87|-20.852   TOTAL RUN
  744/530    0.70| -0.003     0.74| -0.594     0.78| -0.005   add
    24/18    0.79| -0.004     0.89| -0.658     0.88| -0.128   checkout
    96/72    1.00| +0.001     1.02| +2.750     1.18| +1.744   commit
     12/9    1.01| +0.001     0.52| -0.618     1.04| +0.013   copy
     12/9    0.87| -0.003     0.65| -0.432     0.82| -0.064   delete
    60/45    0.94| -0.003     0.10|-39.166     0.15| -5.044   info
    24/18    0.67| -0.052     1.20| +2.928     0.93| -0.274   merge
  624/516    0.70| -0.003     1.06| +0.030     0.72| -0.005   mkdir
    32/21    0.72| -0.003     1.67| +0.015     0.76| -0.003   propdel
    8K/6K    0.67| -0.003     0.96| -0.014     0.67| -0.004   proplist
    9K/6K    0.70| -0.003     0.92| -0.085     0.71| -0.004   propset
  740/591    0.70| -0.003     0.52| -0.260     0.68| -0.004   ps
    24/18    0.83| -0.002     0.72| -0.004     0.80| -0.002   resolve
    24/18    0.60| -0.007     0.73| -0.242     0.75| -0.061   resolved
  168/126    0.75| -0.003     3.08| +1.727     0.92| -0.016   status
     12/9    0.79| -0.016     0.60| -1.881     0.66| -0.526   switch
  168/126    0.85| -0.003     0.92| -0.536     0.87| -0.116   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1364434' is faster.
 "2/3" means: '1.7.0' has 2 timings on record, the other has 3.)



Had started at Mon Jul 23 00:25:08 UTC 2012,
       done at Mon Jul 23 00:54:47 UTC 2012
/home/neels/svnbench
real 1778.55
user 559.22
sys 311.93