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/16 02:49:38 UTC

[svnbench] Revision: 1361847 compiled Jul 16 2012, 00:21:29

1.7.0@1181106 vs. trunk@1361648
Started at Mon Jul 16 00:25:00 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@1361648
       N        avg         operation
      9/9    0.75|-38.210   TOTAL RUN
  558/530    0.76| -0.006   add
    18/18    0.85| -0.156   checkout
    72/72    0.97| -0.247   commit
      9/9    1.12| +0.028   copy
      9/9    0.77| -0.082   delete
    45/45    0.14| -5.090   info
    18/18    0.85| -0.580   merge
  468/516    0.76| -0.004   mkdir
    24/21    1.07| +0.001   propdel
    6K/6K    0.66| -0.004   proplist
    6K/6K    0.68| -0.004   propset
  555/591    0.65| -0.005   ps
    18/18    0.80| -0.002   resolve
    18/18    0.74| -0.062   resolved
  126/126    0.80| -0.042   status
      9/9    0.84| -0.246   switch
  126/126    0.86| -0.115   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1361648' 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@1361648,5x5
       N        avg         operation
      3/3    0.74|-108.702  TOTAL RUN
  471/456    0.76| -0.006   add
      6/6    0.85| -0.423   checkout
    24/24    0.98| -0.569   commit
      3/3    1.23| +0.062   copy
      3/3    0.76| -0.220   delete
    15/15    0.13|-15.137   info
      6/6    0.86| -1.490   merge
  426/470    0.82| -0.003   mkdir
    24/20    1.07| +0.001   propdel
    6K/6K    0.67| -0.004   proplist
    6K/6K    0.68| -0.004   propset
  528/552    0.64| -0.005   ps
      6/6    0.79| -0.002   resolve
      6/6    0.73| -0.173   resolved
    42/42    0.81| -0.100   status
      3/3    0.84| -0.634   switch
    42/42    0.88| -0.271   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1361648,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@1361648,100x1
       N        avg         operation
      3/3    0.82| -4.916   TOTAL RUN
    84/71    0.71| -0.006   add
      6/6    0.84| -0.032   checkout
    24/24    0.91| -0.163   commit
      3/3    1.13| +0.025   copy
      3/3    0.82| -0.020   delete
    15/15    0.54| -0.126   info
      6/6    0.80| -0.138   merge
    42/46    0.38| -0.016   mkdir
  327/337    0.62| -0.005   proplist
  264/273    0.65| -0.005   propset
    21/33    0.68| -0.004   ps
      6/6    0.83| -0.002   resolve
      6/6    0.88| -0.009   resolved
    42/42    0.75| -0.020   status
      3/3    0.78| -0.086   switch
    42/42    0.78| -0.063   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1361648,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@1361648,1x100
       N        avg         operation
      3/3    0.88| -1.013   TOTAL RUN
      3/3    0.81| -0.009   add
      6/6    0.85| -0.012   checkout
    24/24    0.99| -0.009   commit
      3/3    0.98| -0.004   copy
      3/3    0.81| -0.005   delete
    15/15    0.87| -0.008   info
      6/6    0.60| -0.113   merge
  111/111    0.61| -0.004   proplist
  126/126    0.64| -0.004   propset
      6/6    0.64| -0.004   ps
      6/6    0.78| -0.002   resolve
      6/6    0.72| -0.006   resolved
    42/42    0.70| -0.006   status
      3/3    0.77| -0.018   switch
    42/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@1361648,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)
   3  412.54  418.75  415.64  TOTAL RUN
 471    0.01    2.29    0.03  add
   6    0.02    5.81    2.88  checkout
  24    1.63   85.70   24.55  commit
   3    0.25    0.31    0.27  copy
   3    0.74    1.24    0.93  delete
  15    9.25   43.37   17.36  info
   6    7.12   13.49   10.44  merge
 426    0.01    0.35    0.01  mkdir
  24    0.01    0.02    0.01  propdel
  6K    0.01    0.33    0.01  proplist
  6K    0.01    0.35    0.01  propset
 528    0.01    0.54    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.49    0.89    0.63  resolved
  42    0.21    0.83    0.52  status
   3    3.62    4.56    4.03  switch
  42    0.26    6.90    2.19  update
--
Timings for trunk@1361648,5x5
   N    min     max     avg   operation  (unit is seconds)
   3  302.31  313.47  306.93  TOTAL RUN
 456    0.01    1.75    0.02  add
   6    0.02    4.91    2.46  checkout
  24    2.02   85.08   23.98  commit
   3    0.19    0.50    0.34  copy
   3    0.64    0.74    0.71  delete
  15    0.80    3.98    2.22  info
   6    4.71   14.44    8.95  merge
 470    0.01    0.46    0.01  mkdir
  20    0.01    0.10    0.01  propdel
  6K    0.01    0.28    0.01  proplist
  6K    0.01    0.30    0.01  propset
 552    0.01    0.12    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.33    0.61    0.46  resolved
  42    0.14    1.10    0.42  status
   3    2.81    4.34    3.39  switch
  42    0.22    5.77    1.91  update
--
Compare 1.7.0,5x5 to trunk@1361648,5x5
       N        min              max              avg         operation
      3/3    0.73|-110.226    0.75|-105.285    0.74|-108.702  TOTAL RUN
  471/456    0.71| -0.003     0.77| -0.537     0.76| -0.006   add
      6/6    0.74| -0.006     0.85| -0.897     0.85| -0.423   checkout
    24/24    1.24| +0.385     0.99| -0.623     0.98| -0.569   commit
      3/3    0.78| -0.056     1.63| +0.192     1.23| +0.062   copy
      3/3    0.86| -0.100     0.60| -0.502     0.76| -0.220   delete
    15/15    0.09| -8.448     0.09|-39.393     0.13|-15.137   info
      6/6    0.66| -2.410     1.07| +0.949     0.86| -1.490   merge
  426/470    0.71| -0.003     1.31| +0.109     0.82| -0.003   mkdir
    24/20    0.72| -0.003     5.66| +0.079     1.07| +0.001   propdel
    6K/6K    0.67| -0.003     0.84| -0.054     0.67| -0.004   proplist
    6K/6K    0.70| -0.003     0.85| -0.053     0.68| -0.004   propset
  528/552    0.70| -0.003     0.21| -0.428     0.64| -0.005   ps
      6/6    0.73| -0.003     0.86| -0.002     0.79| -0.002   resolve
      6/6    0.68| -0.156     0.69| -0.278     0.73| -0.173   resolved
    42/42    0.68| -0.065     1.33| +0.271     0.81| -0.100   status
      3/3    0.78| -0.815     0.95| -0.214     0.84| -0.634   switch
    42/42    0.83| -0.045     0.84| -1.132     0.88| -0.271   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1361648,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)
   3   26.80   28.02   27.37  TOTAL RUN
  84    0.01    0.21    0.02  add
   6    0.02    0.38    0.20  checkout
  24    0.35    4.03    1.78  commit
   3    0.19    0.20    0.19  copy
   3    0.11    0.12    0.11  delete
  15    0.18    0.48    0.27  info
   6    0.60    0.83    0.68  merge
  42    0.01    0.47    0.03  mkdir
 327    0.01    0.06    0.01  proplist
 264    0.01    0.04    0.01  propset
  21    0.01    0.02    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.05    0.10    0.08  resolved
  42    0.05    0.11    0.08  status
   3    0.38    0.41    0.40  switch
  42    0.05    0.60    0.29  update
--
Timings for trunk@1361648,100x1
   N    min     max     avg   operation  (unit is seconds)
   3   22.07   23.04   22.46  TOTAL RUN
  71    0.01    0.12    0.01  add
   6    0.02    0.32    0.17  checkout
  24    0.88    3.67    1.62  commit
   3    0.17    0.27    0.22  copy
   3    0.09    0.10    0.09  delete
  15    0.11    0.22    0.15  info
   6    0.42    0.71    0.54  merge
  46    0.01    0.01    0.01  mkdir
   1    0.01    0.01    0.01  propdel
 337    0.01    0.01    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.10    0.06  status
   3    0.30    0.32    0.31  switch
  42    0.04    0.43    0.23  update
--
Compare 1.7.0,100x1 to trunk@1361648,100x1
       N        min              max              avg         operation
      3/3    0.82| -4.724     0.82| -4.985     0.82| -4.916   TOTAL RUN
    84/71    0.66| -0.004     0.60| -0.084     0.71| -0.006   add
      6/6    0.73| -0.006     0.84| -0.059     0.84| -0.032   checkout
    24/24    2.53| +0.534     0.91| -0.362     0.91| -0.163   commit
      3/3    0.94| -0.011     1.32| +0.065     1.13| +0.025   copy
      3/3    0.87| -0.013     0.80| -0.023     0.82| -0.020   delete
    15/15    0.63| -0.065     0.44| -0.269     0.54| -0.126   info
      6/6    0.70| -0.180     0.86| -0.115     0.80| -0.138   merge
    42/46    0.67| -0.004     0.03| -0.455     0.38| -0.016   mkdir
  327/337    0.63| -0.004     0.17| -0.051     0.62| -0.005   proplist
  264/273    0.66| -0.004     0.31| -0.029     0.65| -0.005   propset
    21/33    0.63| -0.004     0.73| -0.004     0.68| -0.004   ps
      6/6    0.78| -0.002     1.05| +0.001     0.83| -0.002   resolve
      6/6    1.07| +0.004     0.76| -0.023     0.88| -0.009   resolved
    42/42    0.75| -0.011     0.84| -0.019     0.75| -0.020   status
      3/3    0.79| -0.079     0.78| -0.089     0.78| -0.086   switch
    42/42    0.84| -0.008     0.73| -0.163     0.78| -0.063   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1361648,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)
   3    7.87    8.93    8.53  TOTAL RUN
   3    0.05    0.05    0.05  add
   6    0.02    0.15    0.08  checkout
  24    0.19    1.88    0.63  commit
   3    0.18    0.24    0.20  copy
   3    0.03    0.03    0.03  delete
  15    0.05    0.10    0.06  info
   6    0.16    0.61    0.28  merge
 111    0.01    0.02    0.01  proplist
 126    0.01    0.02    0.01  propset
   6    0.01    0.01    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.02    0.02    0.02  resolved
  42    0.01    0.03    0.02  status
   3    0.08    0.08    0.08  switch
  42    0.02    0.14    0.05  update
--
Timings for trunk@1361648,1x100
   N    min     max     avg   operation  (unit is seconds)
   3    7.34    7.69    7.52  TOTAL RUN
   3    0.04    0.04    0.04  add
   6    0.02    0.13    0.07  checkout
  24    0.20    2.00    0.62  commit
   3    0.19    0.21    0.20  copy
   3    0.02    0.02    0.02  delete
  15    0.05    0.09    0.06  info
   6    0.10    0.26    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.06    0.06  switch
  42    0.02    0.12    0.04  update
--
Compare 1.7.0,1x100 to trunk@1361648,1x100
       N        min              max              avg         operation
      3/3    0.93| -0.525     0.86| -1.240     0.88| -1.013   TOTAL RUN
      3/3    0.83| -0.008     0.80| -0.010     0.81| -0.009   add
      6/6    0.77| -0.005     0.87| -0.020     0.85| -0.012   checkout
    24/24    1.08| +0.016     1.06| +0.119     0.99| -0.009   commit
      3/3    1.09| +0.015     0.87| -0.030     0.98| -0.004   copy
      3/3    0.83| -0.004     0.80| -0.005     0.81| -0.005   delete
    15/15    0.90| -0.005     0.88| -0.012     0.87| -0.008   info
      6/6    0.64| -0.058     0.43| -0.345     0.60| -0.113   merge
  111/111    0.66| -0.003     0.45| -0.009     0.61| -0.004   proplist
  126/126    0.69| -0.003     0.47| -0.010     0.64| -0.004   propset
      6/6    0.69| -0.003     0.56| -0.006     0.64| -0.004   ps
      6/6    0.77| -0.002     0.84| -0.002     0.78| -0.002   resolve
      6/6    0.57| -0.007     0.80| -0.004     0.72| -0.006   resolved
    42/42    0.72| -0.003     0.76| -0.006     0.70| -0.006   status
      3/3    0.76| -0.018     0.77| -0.018     0.77| -0.018   switch
    42/42    0.81| -0.004     0.81| -0.026     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@1361648,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)
   9    7.87  418.75  150.51  TOTAL RUN
 558    0.01    2.29    0.02  add
  18    0.02    5.81    1.05  checkout
  72    0.19   85.70    8.99  commit
   9    0.18    0.31    0.22  copy
   9    0.03    1.24    0.36  delete
  45    0.05   43.37    5.90  info
  18    0.16   13.49    3.80  merge
 468    0.01    0.47    0.02  mkdir
  24    0.01    0.02    0.01  propdel
  6K    0.01    0.33    0.01  proplist
  6K    0.01    0.35    0.01  propset
 555    0.01    0.54    0.01  ps
  18    0.01    0.01    0.01  resolve
  18    0.02    0.89    0.24  resolved
 126    0.01    0.83    0.21  status
   9    0.08    4.56    1.50  switch
 126    0.02    6.90    0.84  update
--
Timings for trunk@1361648
   N    min     max     avg   operation  (unit is seconds)
   9    7.34  313.47  112.30  TOTAL RUN
 530    0.01    1.75    0.02  add
  18    0.02    4.91    0.90  checkout
  72    0.20   85.08    8.74  commit
   9    0.17    0.50    0.25  copy
   9    0.02    0.74    0.27  delete
  45    0.05    3.98    0.81  info
  18    0.10   14.44    3.22  merge
 516    0.01    0.46    0.01  mkdir
  21    0.01    0.10    0.01  propdel
  6K    0.01    0.28    0.01  proplist
  6K    0.01    0.30    0.01  propset
 591    0.01    0.12    0.01  ps
  18    0.01    0.01    0.01  resolve
  18    0.01    0.61    0.18  resolved
 126    0.01    1.10    0.17  status
   9    0.06    4.34    1.25  switch
 126    0.02    5.77    0.73  update
--
Compare 1.7.0 to trunk@1361648
       N        min              max              avg         operation
      9/9    0.93| -0.525     0.75|-105.285    0.75|-38.210   TOTAL RUN
  558/530    0.71| -0.003     0.77| -0.537     0.76| -0.006   add
    18/18    0.76| -0.005     0.85| -0.897     0.85| -0.156   checkout
    72/72    1.08| +0.016     0.99| -0.623     0.97| -0.247   commit
      9/9    0.99| -0.001     1.63| +0.192     1.12| +0.028   copy
      9/9    0.83| -0.004     0.60| -0.502     0.77| -0.082   delete
    45/45    0.90| -0.005     0.09|-39.393     0.14| -5.090   info
    18/18    0.64| -0.058     1.07| +0.949     0.85| -0.580   merge
  468/516    0.71| -0.003     0.99| -0.006     0.76| -0.004   mkdir
    24/21    0.72| -0.003     5.66| +0.079     1.07| +0.001   propdel
    6K/6K    0.67| -0.003     0.84| -0.054     0.66| -0.004   proplist
    6K/6K    0.70| -0.003     0.85| -0.053     0.68| -0.004   propset
  555/591    0.70| -0.003     0.21| -0.428     0.65| -0.005   ps
    18/18    0.77| -0.002     0.86| -0.002     0.80| -0.002   resolve
    18/18    0.57| -0.007     0.69| -0.278     0.74| -0.062   resolved
  126/126    0.72| -0.003     1.33| +0.271     0.80| -0.042   status
      9/9    0.76| -0.018     0.95| -0.214     0.84| -0.246   switch
  126/126    0.81| -0.004     0.84| -1.132     0.86| -0.115   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1361648' is faster.
 "2/3" means: '1.7.0' has 2 timings on record, the other has 3.)



Had started at Mon Jul 16 00:25:00 UTC 2012,
       done at Mon Jul 16 00:49:38 UTC 2012
/home/neels/svnbench
real 1477.40
user 534.26
sys 283.62