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/09/17 02:49:32 UTC

[svnbench] Revision: 1385398 compiled Sep 17 2012, 00:21:37 on x86_64-unknown-linux-gnu

1.7.0@1181106 vs. trunk@1385395
Started at Mon Sep 17 00:24:54 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@1385395
       N        avg         operation
     36/9    0.71|-45.126   TOTAL RUN
   2K/530    0.78| -0.005   add
    72/18    0.91| -0.094   checkout
   288/72    0.89| -1.059   commit
     36/9    0.78| -0.065   copy
     36/9    0.77| -0.078   delete
   180/45    0.12| -5.040   info
    72/18    0.79| -0.835   merge
   1K/516    0.63| -0.006   mkdir
    96/21    0.57| -0.005   propdel
   26K/6K    0.63| -0.004   proplist
   27K/6K    0.66| -0.004   propset
   2K/591    0.64| -0.004   ps
    72/18    0.76| -0.002   resolve
    72/18    0.75| -0.058   resolved
  504/126    0.74| -0.053   status
     36/9    1.13| +0.187   switch
  504/126    0.84| -0.136   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395' 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@1385395,5x5
       N        avg         operation
     12/3    0.71|-126.662  TOTAL RUN
   1K/456    0.78| -0.005   add
     24/6    0.91| -0.230   checkout
    96/24    0.90| -2.672   commit
     12/3    0.94| -0.016   copy
     12/3    0.77| -0.205   delete
    60/15    0.11|-14.986   info
     24/6    0.80| -2.216   merge
   1K/470    0.64| -0.006   mkdir
    96/20    0.56| -0.006   propdel
   25K/6K    0.63| -0.004   proplist
   25K/6K    0.67| -0.004   propset
   2K/552    0.63| -0.005   ps
     24/6    0.75| -0.003   resolve
     24/6    0.75| -0.155   resolved
   168/42    0.75| -0.131   status
     12/3    1.18| +0.679   switch
   168/42    0.85| -0.329   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395,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@1385395,100x1
       N        avg         operation
     12/3    0.76| -7.048   TOTAL RUN
   336/71    0.70| -0.005   add
     24/6    0.80| -0.038   checkout
    96/24    0.80| -0.406   commit
     12/3    0.60| -0.131   copy
     12/3    0.78| -0.024   delete
    60/15    0.51| -0.128   info
     24/6    0.71| -0.188   merge
   168/46    0.54| -0.008   mkdir
   1K/337    0.62| -0.004   proplist
   1K/273    0.65| -0.005   propset
    84/33    0.68| -0.004   ps
     24/6    0.74| -0.003   resolve
     24/6    0.81| -0.014   resolved
   168/42    0.69| -0.023   status
     12/3    0.74| -0.099   switch
   168/42    0.76| -0.068   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395,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@1385395,1x100
       N        avg         operation
     12/3    0.82| -1.668   TOTAL RUN
     12/3    0.80| -0.009   add
     24/6    0.83| -0.013   checkout
    96/24    0.87| -0.099   commit
     12/3    0.81| -0.047   copy
     12/3    0.81| -0.005   delete
    60/15    0.89| -0.007   info
     24/6    0.57| -0.101   merge
  444/111    0.63| -0.003   proplist
  504/126    0.66| -0.004   propset
     24/6    0.66| -0.004   ps
     24/6    0.79| -0.002   resolve
     24/6    0.71| -0.005   resolved
   168/42    0.72| -0.005   status
     12/3    0.74| -0.019   switch
   168/42    0.79| -0.010   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395,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)
  12  395.33  561.33  430.44  TOTAL RUN
  1K    0.01    2.29    0.02  add
  24    0.02    5.81    2.71  checkout
  96    1.53  160.96   27.18  commit
  12    0.19    0.49    0.29  copy
  12    0.74    1.24    0.88  delete
  60    8.63   43.37   16.85  info
  24    6.81   21.02   11.11  merge
  1K    0.01    0.66    0.02  mkdir
  96    0.01    0.09    0.01  propdel
 25K    0.01    0.69    0.01  proplist
 25K    0.01    1.10    0.01  propset
  2K    0.01    0.54    0.01  ps
  24    0.01    0.01    0.01  resolve
  24    0.47    0.96    0.62  resolved
 168    0.20    1.08    0.52  status
  12    3.24    4.68    3.86  switch
 168    0.26    7.02    2.16  update
--
Timings for trunk@1385395,5x5
   N    min     max     avg   operation  (unit is seconds)
   3  296.81  310.89  303.78  TOTAL RUN
 456    0.01    1.90    0.02  add
   6    0.02    5.62    2.48  checkout
  24    2.23   85.37   24.51  commit
   3    0.24    0.32    0.28  copy
   3    0.62    0.71    0.67  delete
  15    0.75    3.54    1.86  info
   6    4.38   14.40    8.90  merge
 470    0.01    0.69    0.01  mkdir
  20    0.01    0.01    0.01  propdel
  6K    0.01    0.15    0.01  proplist
  6K    0.01    0.39    0.01  propset
 552    0.01    0.13    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.31    0.76    0.46  resolved
  42    0.13    0.65    0.39  status
   3    2.53    7.03    4.54  switch
  42    0.21    6.29    1.84  update
--
Compare 1.7.0,5x5 to trunk@1385395,5x5
       N        min              max              avg         operation
     12/3    0.75|-98.522     0.55|-250.445    0.71|-126.662  TOTAL RUN
   1K/456    0.71| -0.003     0.83| -0.392     0.78| -0.005   add
     24/6    0.80| -0.004     0.97| -0.189     0.91| -0.230   checkout
    96/24    1.46| +0.702     0.53|-75.594     0.90| -2.672   commit
     12/3    1.29| +0.053     0.65| -0.174     0.94| -0.016   copy
     12/3    0.84| -0.116     0.57| -0.534     0.77| -0.205   delete
    60/15    0.09| -7.881     0.08|-39.832     0.11|-14.986   info
     24/6    0.64| -2.431     0.68| -6.622     0.80| -2.216   merge
   1K/470    0.71| -0.003     1.05| +0.033     0.64| -0.006   mkdir
    96/20    0.70| -0.003     0.09| -0.078     0.56| -0.006   propdel
   25K/6K    0.67| -0.003     0.22| -0.536     0.63| -0.004   proplist
   25K/6K    0.70| -0.003     0.35| -0.710     0.67| -0.004   propset
   2K/552    0.70| -0.003     0.23| -0.419     0.63| -0.005   ps
     24/6    0.79| -0.002     0.58| -0.006     0.75| -0.003   resolve
     24/6    0.66| -0.160     0.79| -0.198     0.75| -0.155   resolved
   168/42    0.67| -0.065     0.61| -0.426     0.75| -0.131   status
     12/3    0.78| -0.712     1.50| +2.349     1.18| +0.679   switch
   168/42    0.83| -0.043     0.90| -0.728     0.85| -0.329   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395,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)
  12   24.71   43.91   28.93  TOTAL RUN
 336    0.01    0.21    0.02  add
  24    0.02    0.43    0.19  checkout
  96    0.31   10.98    2.06  commit
  12    0.19    1.29    0.33  copy
  12    0.11    0.13    0.11  delete
  60    0.17    0.48    0.26  info
  24    0.59    0.83    0.65  merge
 168    0.01    0.47    0.02  mkdir
  1K    0.01    0.06    0.01  proplist
  1K    0.01    0.04    0.01  propset
  84    0.01    0.02    0.01  ps
  24    0.01    0.01    0.01  resolve
  24    0.05    0.10    0.07  resolved
 168    0.04    0.13    0.08  status
  12    0.37    0.41    0.39  switch
 168    0.05    1.02    0.28  update
--
Timings for trunk@1385395,100x1
   N    min     max     avg   operation  (unit is seconds)
   3   21.19   22.39   21.88  TOTAL RUN
  71    0.01    0.11    0.01  add
   6    0.02    0.30    0.16  checkout
  24    0.83    3.89    1.66  commit
   3    0.18    0.22    0.20  copy
   3    0.09    0.09    0.09  delete
  15    0.10    0.20    0.13  info
   6    0.40    0.63    0.46  merge
  46    0.01    0.01    0.01  mkdir
   1    0.01    0.01    0.01  propdel
 337    0.01    0.08    0.01  proplist
 273    0.01    0.04    0.01  propset
  33    0.01    0.02    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.05    0.07    0.06  resolved
  42    0.03    0.08    0.05  status
   3    0.28    0.29    0.29  switch
  42    0.04    0.41    0.21  update
--
Compare 1.7.0,100x1 to trunk@1385395,100x1
       N        min              max              avg         operation
     12/3    0.86| -3.517     0.51|-21.520     0.76| -7.048   TOTAL RUN
   336/71    0.69| -0.003     0.55| -0.094     0.70| -0.005   add
     24/6    0.79| -0.004     0.70| -0.129     0.80| -0.038   checkout
    96/24    2.68| +0.521     0.35| -7.090     0.80| -0.406   commit
     12/3    0.96| -0.008     0.17| -1.074     0.60| -0.131   copy
     12/3    0.83| -0.018     0.70| -0.037     0.78| -0.024   delete
    60/15    0.60| -0.066     0.41| -0.288     0.51| -0.128   info
     24/6    0.67| -0.193     0.76| -0.201     0.71| -0.188   merge
   168/46    0.70| -0.003     0.02| -0.456     0.54| -0.008   mkdir
   1K/337    0.66| -0.003     1.33| +0.020     0.62| -0.004   proplist
   1K/273    0.69| -0.003     0.94| -0.003     0.65| -0.005   propset
    84/33    0.65| -0.004     0.88| -0.002     0.68| -0.004   ps
     24/6    0.79| -0.002     0.64| -0.004     0.74| -0.003   resolve
     24/6    0.94| -0.003     0.69| -0.030     0.81| -0.014   resolved
   168/42    0.70| -0.013     0.59| -0.053     0.69| -0.023   status
     12/3    0.76| -0.090     0.72| -0.113     0.74| -0.099   switch
   168/42    0.83| -0.008     0.40| -0.614     0.76| -0.068   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395,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)
  12    7.69   17.23    9.13  TOTAL RUN
  12    0.04    0.05    0.04  add
  24    0.02    0.15    0.08  checkout
  96    0.18    5.67    0.74  commit
  12    0.18    0.66    0.25  copy
  12    0.02    0.03    0.02  delete
  60    0.05    0.10    0.06  info
  24    0.14    0.61    0.23  merge
 444    0.01    0.03    0.01  proplist
 504    0.01    0.05    0.01  propset
  24    0.01    0.01    0.01  ps
  24    0.01    0.01    0.01  resolve
  24    0.02    0.02    0.02  resolved
 168    0.01    0.03    0.02  status
  12    0.07    0.08    0.07  switch
 168    0.02    0.14    0.05  update
--
Timings for trunk@1385395,1x100
   N    min     max     avg   operation  (unit is seconds)
   3    7.01    7.77    7.46  TOTAL RUN
   3    0.03    0.04    0.04  add
   6    0.02    0.11    0.06  checkout
  24    0.19    1.89    0.64  commit
   3    0.18    0.22    0.20  copy
   3    0.02    0.02    0.02  delete
  15    0.04    0.09    0.05  info
   6    0.09    0.18    0.13  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.05    0.06    0.05  switch
  42    0.01    0.11    0.04  update
--
Compare 1.7.0,1x100 to trunk@1385395,1x100
       N        min              max              avg         operation
     12/3    0.91| -0.680     0.45| -9.454     0.82| -1.668   TOTAL RUN
     12/3    0.84| -0.007     0.74| -0.013     0.80| -0.009   add
     24/6    0.81| -0.004     0.76| -0.035     0.83| -0.013   checkout
    96/24    1.08| +0.014     0.33| -3.772     0.87| -0.099   commit
     12/3    1.00| +0.000     0.33| -0.440     0.81| -0.047   copy
     12/3    0.86| -0.003     0.70| -0.009     0.81| -0.005   delete
    60/15    0.94| -0.003     0.85| -0.015     0.89| -0.007   info
     24/6    0.65| -0.051     0.29| -0.431     0.57| -0.101   merge
  444/111    0.67| -0.003     0.26| -0.021     0.63| -0.003   proplist
  504/126    0.70| -0.003     0.20| -0.037     0.66| -0.004   propset
     24/6    0.69| -0.003     0.57| -0.006     0.66| -0.004   ps
     24/6    0.77| -0.002     0.80| -0.002     0.79| -0.002   resolve
     24/6    0.59| -0.006     0.72| -0.006     0.71| -0.005   resolved
   168/42    0.72| -0.003     0.67| -0.009     0.72| -0.005   status
     12/3    0.76| -0.016     0.72| -0.022     0.74| -0.019   switch
   168/42    0.84| -0.003     0.76| -0.034     0.79| -0.010   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395,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)
  36    7.69  561.33  156.16  TOTAL RUN
  2K    0.01    2.29    0.02  add
  72    0.02    5.81    0.99  checkout
 288    0.18  160.96    9.99  commit
  36    0.18    1.29    0.29  copy
  36    0.02    1.24    0.34  delete
 180    0.05   43.37    5.72  info
  72    0.14   21.02    4.00  merge
  1K    0.01    0.66    0.02  mkdir
  96    0.01    0.09    0.01  propdel
 26K    0.01    0.69    0.01  proplist
 27K    0.01    1.10    0.01  propset
  2K    0.01    0.54    0.01  ps
  72    0.01    0.01    0.01  resolve
  72    0.02    0.96    0.24  resolved
 504    0.01    1.08    0.20  status
  36    0.07    4.68    1.44  switch
 504    0.02    7.02    0.83  update
--
Timings for trunk@1385395
   N    min     max     avg   operation  (unit is seconds)
   9    7.01  310.89  111.04  TOTAL RUN
 530    0.01    1.90    0.02  add
  18    0.02    5.62    0.90  checkout
  72    0.19   85.37    8.93  commit
   9    0.18    0.32    0.23  copy
   9    0.02    0.71    0.26  delete
  45    0.04    3.54    0.68  info
  18    0.09   14.40    3.16  merge
 516    0.01    0.69    0.01  mkdir
  21    0.01    0.01    0.01  propdel
  6K    0.01    0.15    0.01  proplist
  6K    0.01    0.39    0.01  propset
 591    0.01    0.13    0.01  ps
  18    0.01    0.01    0.01  resolve
  18    0.01    0.76    0.18  resolved
 126    0.01    0.65    0.15  status
   9    0.05    7.03    1.63  switch
 126    0.01    6.29    0.69  update
--
Compare 1.7.0 to trunk@1385395
       N        min              max              avg         operation
     36/9    0.91| -0.680     0.55|-250.445    0.71|-45.126   TOTAL RUN
   2K/530    0.71| -0.003     0.83| -0.392     0.78| -0.005   add
    72/18    0.80| -0.004     0.97| -0.189     0.91| -0.094   checkout
   288/72    1.08| +0.014     0.53|-75.594     0.89| -1.059   commit
     36/9    1.00| +0.000     0.25| -0.978     0.78| -0.065   copy
     36/9    0.86| -0.003     0.57| -0.534     0.77| -0.078   delete
   180/45    0.94| -0.003     0.08|-39.832     0.12| -5.040   info
    72/18    0.65| -0.051     0.68| -6.622     0.79| -0.835   merge
   1K/516    0.71| -0.003     1.05| +0.033     0.63| -0.006   mkdir
    96/21    0.70| -0.003     0.12| -0.075     0.57| -0.005   propdel
   26K/6K    0.67| -0.003     0.22| -0.536     0.63| -0.004   proplist
   27K/6K    0.70| -0.003     0.35| -0.710     0.66| -0.004   propset
   2K/591    0.70| -0.003     0.23| -0.419     0.64| -0.004   ps
    72/18    0.77| -0.002     0.62| -0.005     0.76| -0.002   resolve
    72/18    0.59| -0.006     0.79| -0.198     0.75| -0.058   resolved
  504/126    0.72| -0.003     0.61| -0.426     0.74| -0.053   status
     36/9    0.76| -0.016     1.50| +2.349     1.13| +0.187   switch
  504/126    0.84| -0.003     0.90| -0.728     0.84| -0.136   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1385395' is faster.
 "2/3" means: '1.7.0' has 2 timings on record, the other has 3.)



Had started at Mon Sep 17 00:24:54 UTC 2012,
       done at Mon Sep 17 00:49:32 UTC 2012
/home/neels/svnbench
real 1478.08
user 545.42
sys 246.91