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/30 02:48:31 UTC

[svnbench] Revision: 1366967 compiled Jul 30 2012, 00:21:28

1.7.0@1181106 vs. trunk@1366368
Started at Mon Jul 30 00:24:49 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@1366368
       N        avg         operation
     15/9    0.69|-47.410   TOTAL RUN
  930/530    0.74| -0.006   add
    30/18    0.87| -0.139   checkout
   120/72    0.90| -0.914   commit
     15/9    0.78| -0.064   copy
     15/9    0.80| -0.069   delete
    75/45    0.10| -5.235   info
    30/18    0.79| -0.807   merge
  780/516    0.66| -0.005   mkdir
    40/21    0.59| -0.005   propdel
   11K/6K    0.59| -0.005   proplist
   11K/6K    0.61| -0.005   propset
  925/591    0.57| -0.006   ps
    30/18    0.71| -0.003   resolve
    30/18    0.75| -0.059   resolved
  210/126    0.73| -0.055   status
     15/9    0.88| -0.175   switch
  210/126    0.80| -0.174   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368' 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@1366368,5x5
       N        avg         operation
      5/3    0.69|-133.834  TOTAL RUN
  785/456    0.75| -0.006   add
     10/6    0.87| -0.357   checkout
    40/24    0.91| -2.334   commit
      5/3    1.10| +0.025   copy
      5/3    0.81| -0.174   delete
    25/15    0.10|-15.560   info
     10/6    0.80| -2.113   merge
  710/470    0.69| -0.005   mkdir
    40/20    0.57| -0.005   propdel
   10K/6K    0.59| -0.004   proplist
   10K/6K    0.61| -0.005   propset
  880/552    0.56| -0.006   ps
     10/6    0.69| -0.003   resolve
     10/6    0.74| -0.158   resolved
    70/42    0.74| -0.135   status
      5/3    0.87| -0.506   switch
    70/42    0.80| -0.445   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368,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@1366368,100x1
       N        avg         operation
      5/3    0.76| -7.108   TOTAL RUN
   140/71    0.68| -0.006   add
     10/6    0.79| -0.044   checkout
    40/24    0.81| -0.382   commit
      5/3    0.56| -0.185   copy
      5/3    0.77| -0.026   delete
    25/15    0.50| -0.134   info
     10/6    0.72| -0.189   merge
    70/46    0.45| -0.011   mkdir
  545/337    0.59| -0.005   proplist
  440/273    0.62| -0.005   propset
    35/33    0.65| -0.005   ps
     10/6    0.71| -0.003   resolve
     10/6    0.82| -0.014   resolved
    70/42    0.69| -0.024   status
      5/3    1.01| +0.002   switch
    70/42    0.78| -0.064   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368,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@1366368,1x100
       N        avg         operation
      5/3    0.85| -1.289   TOTAL RUN
      5/3    0.76| -0.011   add
     10/6    0.79| -0.017   checkout
    40/24    0.96| -0.028   commit
      5/3    0.85| -0.031   copy
      5/3    0.76| -0.006   delete
    25/15    0.82| -0.011   info
     10/6    0.55| -0.118   merge
  185/111    0.58| -0.004   proplist
  210/126    0.60| -0.004   propset
     10/6    0.61| -0.004   ps
     10/6    0.74| -0.003   resolve
     10/6    0.66| -0.006   resolved
    70/42    0.67| -0.006   status
      5/3    0.71| -0.022   switch
    70/42    0.75| -0.013   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368,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)
   5  399.25  483.55  425.94  TOTAL RUN
 785    0.01    2.29    0.02  add
  10    0.02    5.81    2.85  checkout
  40    1.59  115.37   25.92  commit
   5    0.19    0.31    0.25  copy
   5    0.74    1.24    0.90  delete
  25    8.63   43.37   17.19  info
  10    6.81   15.01   10.47  merge
 710    0.01    0.43    0.02  mkdir
  40    0.01    0.02    0.01  propdel
 10K    0.01    0.33    0.01  proplist
 10K    0.01    1.10    0.01  propset
 880    0.01    0.54    0.01  ps
  10    0.01    0.01    0.01  resolve
  10    0.48    0.89    0.62  resolved
  70    0.20    0.83    0.52  status
   5    3.39    4.68    4.03  switch
  70    0.26    6.98    2.23  update
--
Timings for trunk@1366368,5x5
   N    min     max     avg   operation  (unit is seconds)
   3  290.50  293.26  292.11  TOTAL RUN
 456    0.01    1.62    0.02  add
   6    0.02    5.16    2.49  checkout
  24    2.22   83.42   23.59  commit
   3    0.26    0.30    0.28  copy
   3    0.62    0.84    0.73  delete
  15    0.71    3.51    1.63  info
   6    4.73   11.71    8.36  merge
 470    0.01    0.55    0.01  mkdir
  20    0.01    0.01    0.01  propdel
  6K    0.01    0.25    0.01  proplist
  6K    0.01    0.27    0.01  propset
 552    0.01    0.04    0.01  ps
   6    0.01    0.01    0.01  resolve
   6    0.31    0.71    0.46  resolved
  42    0.14    1.24    0.39  status
   3    2.45    5.59    3.52  switch
  42    0.22    5.29    1.78  update
--
Compare 1.7.0,5x5 to trunk@1366368,5x5
       N        min              max              avg         operation
      5/3    0.73|-108.743    0.61|-190.293    0.69|-133.834  TOTAL RUN
  785/456    0.67| -0.003     0.71| -0.674     0.75| -0.006   add
     10/6    0.77| -0.005     0.89| -0.652     0.87| -0.357   checkout
    40/24    1.40| +0.629     0.72|-31.948     0.91| -2.334   commit
      5/3    1.41| +0.076     1.00| -0.001     1.10| +0.025   copy
      5/3    0.84| -0.117     0.67| -0.407     0.81| -0.174   delete
    25/15    0.08| -7.915     0.08|-39.861     0.10|-15.560   info
     10/6    0.70| -2.075     0.78| -3.300     0.80| -2.113   merge
  710/470    0.68| -0.003     1.28| +0.120     0.69| -0.005   mkdir
    40/20    0.68| -0.003     0.32| -0.016     0.57| -0.005   propdel
   10K/6K    0.65| -0.003     0.77| -0.078     0.59| -0.004   proplist
   10K/6K    0.67| -0.003     0.25| -0.823     0.61| -0.005   propset
  880/552    0.68| -0.003     0.07| -0.507     0.56| -0.006   ps
     10/6    0.78| -0.002     0.61| -0.006     0.69| -0.003   resolve
     10/6    0.65| -0.166     0.79| -0.185     0.74| -0.158   resolved
    70/42    0.66| -0.069     1.49| +0.407     0.74| -0.135   status
      5/3    0.72| -0.937     1.20| +0.914     0.87| -0.506   switch
    70/42    0.83| -0.044     0.76| -1.691     0.80| -0.445   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368,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)
   5   26.14   37.00   29.05  TOTAL RUN
 140    0.01    0.21    0.02  add
  10    0.02    0.43    0.20  checkout
  40    0.35   10.98    1.99  commit
   5    0.19    1.29    0.42  copy
   5    0.11    0.13    0.12  delete
  25    0.17    0.48    0.27  info
  10    0.60    0.83    0.67  merge
  70    0.01    0.47    0.02  mkdir
 545    0.01    0.06    0.01  proplist
 440    0.01    0.04    0.01  propset
  35    0.01    0.02    0.01  ps
  10    0.01    0.01    0.01  resolve
  10    0.05    0.10    0.08  resolved
  70    0.05    0.13    0.08  status
   5    0.38    0.41    0.39  switch
  70    0.05    0.60    0.29  update
--
Timings for trunk@1366368,100x1
   N    min     max     avg   operation  (unit is seconds)
   3   21.05   22.66   21.94  TOTAL RUN
  71    0.01    0.12    0.01  add
   6    0.02    0.31    0.16  checkout
  24    0.89    3.53    1.61  commit
   3    0.22    0.26    0.24  copy
   3    0.09    0.09    0.09  delete
  15    0.10    0.21    0.14  info
   6    0.40    0.64    0.48  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.01    0.01  propset
  33    0.01    0.01    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.29    0.60    0.39  switch
  42    0.04    0.67    0.22  update
--
Compare 1.7.0,100x1 to trunk@1366368,100x1
       N        min              max              avg         operation
      5/3    0.81| -5.086     0.61|-14.341     0.76| -7.108   TOTAL RUN
   140/71    0.68| -0.003     0.56| -0.091     0.68| -0.006   add
     10/6    0.76| -0.005     0.72| -0.120     0.79| -0.044   checkout
    40/24    2.56| +0.543     0.32| -7.440     0.81| -0.382   commit
      5/3    1.16| +0.030     0.20| -1.039     0.56| -0.185   copy
      5/3    0.83| -0.018     0.72| -0.035     0.77| -0.026   delete
    25/15    0.60| -0.068     0.42| -0.280     0.50| -0.134   info
     10/6    0.67| -0.195     0.77| -0.191     0.72| -0.189   merge
    70/46    0.69| -0.003     0.04| -0.447     0.45| -0.011   mkdir
  545/337    0.65| -0.003     0.33| -0.041     0.59| -0.005   proplist
  440/273    0.68| -0.003     0.30| -0.029     0.62| -0.005   propset
    35/33    0.64| -0.004     0.66| -0.006     0.65| -0.005   ps
     10/6    0.78| -0.002     0.67| -0.004     0.71| -0.003   resolve
     10/6    0.99| -0.000     0.72| -0.027     0.82| -0.014   resolved
    70/42    0.71| -0.013     0.61| -0.050     0.69| -0.024   status
      5/3    0.76| -0.092     1.49| +0.197     1.01| +0.002   switch
    70/42    0.80| -0.010     1.11| +0.068     0.78| -0.064   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368,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)
   5    7.87    9.12    8.56  TOTAL RUN
   5    0.04    0.05    0.05  add
  10    0.02    0.15    0.08  checkout
  40    0.18    2.05    0.65  commit
   5    0.18    0.24    0.21  copy
   5    0.02    0.03    0.03  delete
  25    0.05    0.10    0.06  info
  10    0.15    0.61    0.26  merge
 185    0.01    0.02    0.01  proplist
 210    0.01    0.02    0.01  propset
  10    0.01    0.01    0.01  ps
  10    0.01    0.01    0.01  resolve
  10    0.02    0.02    0.02  resolved
  70    0.01    0.03    0.02  status
   5    0.07    0.08    0.08  switch
  70    0.02    0.14    0.05  update
--
Timings for trunk@1366368,1x100
   N    min     max     avg   operation  (unit is seconds)
   3    6.93    7.70    7.28  TOTAL RUN
   3    0.04    0.04    0.04  add
   6    0.02    0.11    0.06  checkout
  24    0.20    1.91    0.62  commit
   3    0.16    0.19    0.18  copy
   3    0.02    0.02    0.02  delete
  15    0.04    0.08    0.05  info
   6    0.10    0.24    0.14  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.05    0.05  switch
  42    0.01    0.11    0.04  update
--
Compare 1.7.0,1x100 to trunk@1366368,1x100
       N        min              max              avg         operation
      5/3    0.88| -0.932     0.84| -1.424     0.85| -1.289   TOTAL RUN
      5/3    0.83| -0.007     0.74| -0.013     0.76| -0.011   add
     10/6    0.78| -0.004     0.76| -0.035     0.79| -0.017   checkout
    40/24    1.11| +0.020     0.93| -0.141     0.96| -0.028   commit
      5/3    0.92| -0.014     0.82| -0.042     0.85| -0.031   copy
      5/3    0.84| -0.004     0.67| -0.010     0.76| -0.006   delete
    25/15    0.90| -0.005     0.80| -0.020     0.82| -0.011   info
     10/6    0.64| -0.053     0.39| -0.374     0.55| -0.118   merge
  185/111    0.66| -0.003     0.38| -0.011     0.58| -0.004   proplist
  210/126    0.68| -0.003     0.39| -0.012     0.60| -0.004   propset
     10/6    0.68| -0.003     0.50| -0.007     0.61| -0.004   ps
     10/6    0.75| -0.002     0.75| -0.003     0.74| -0.003   resolve
     10/6    0.59| -0.006     0.69| -0.007     0.66| -0.006   resolved
    70/42    0.71| -0.003     0.66| -0.009     0.67| -0.006   status
      5/3    0.76| -0.017     0.69| -0.025     0.71| -0.022   switch
    70/42    0.81| -0.003     0.75| -0.036     0.75| -0.013   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368,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)
  15    7.87  483.55  154.52  TOTAL RUN
 930    0.01    2.29    0.02  add
  30    0.02    5.81    1.04  checkout
 120    0.18  115.37    9.52  commit
  15    0.18    1.29    0.30  copy
  15    0.02    1.24    0.35  delete
  75    0.05   43.37    5.84  info
  30    0.15   15.01    3.80  merge
 780    0.01    0.47    0.02  mkdir
  40    0.01    0.02    0.01  propdel
 11K    0.01    0.33    0.01  proplist
 11K    0.01    1.10    0.01  propset
 925    0.01    0.54    0.01  ps
  30    0.01    0.01    0.01  resolve
  30    0.02    0.89    0.24  resolved
 210    0.01    0.83    0.21  status
  15    0.07    4.68    1.50  switch
 210    0.02    6.98    0.85  update
--
Timings for trunk@1366368
   N    min     max     avg   operation  (unit is seconds)
   9    6.93  293.26  107.11  TOTAL RUN
 530    0.01    1.62    0.02  add
  18    0.02    5.16    0.90  checkout
  72    0.20   83.42    8.61  commit
   9    0.16    0.30    0.23  copy
   9    0.02    0.84    0.28  delete
  45    0.04    3.51    0.61  info
  18    0.10   11.71    2.99  merge
 516    0.01    0.55    0.01  mkdir
  21    0.01    0.01    0.01  propdel
  6K    0.01    0.25    0.01  proplist
  6K    0.01    0.27    0.01  propset
 591    0.01    0.04    0.01  ps
  18    0.01    0.01    0.01  resolve
  18    0.01    0.71    0.18  resolved
 126    0.01    1.24    0.15  status
   9    0.05    5.59    1.32  switch
 126    0.01    5.29    0.68  update
--
Compare 1.7.0 to trunk@1366368
       N        min              max              avg         operation
     15/9    0.88| -0.932     0.61|-190.293    0.69|-47.410   TOTAL RUN
  930/530    0.67| -0.003     0.71| -0.674     0.74| -0.006   add
    30/18    0.77| -0.005     0.89| -0.652     0.87| -0.139   checkout
   120/72    1.11| +0.020     0.72|-31.948     0.90| -0.914   commit
     15/9    0.92| -0.014     0.24| -0.990     0.78| -0.064   copy
     15/9    0.84| -0.004     0.67| -0.407     0.80| -0.069   delete
    75/45    0.90| -0.005     0.08|-39.861     0.10| -5.235   info
    30/18    0.64| -0.053     0.78| -3.300     0.79| -0.807   merge
  780/516    0.68| -0.003     1.18| +0.082     0.66| -0.005   mkdir
    40/21    0.68| -0.003     0.48| -0.012     0.59| -0.005   propdel
   11K/6K    0.65| -0.003     0.77| -0.078     0.59| -0.005   proplist
   11K/6K    0.68| -0.003     0.25| -0.823     0.61| -0.005   propset
  925/591    0.68| -0.003     0.07| -0.507     0.57| -0.006   ps
    30/18    0.75| -0.002     0.61| -0.006     0.71| -0.003   resolve
    30/18    0.59| -0.006     0.79| -0.185     0.75| -0.059   resolved
  210/126    0.71| -0.003     1.49| +0.407     0.73| -0.055   status
     15/9    0.76| -0.017     1.20| +0.914     0.88| -0.175   switch
  210/126    0.81| -0.003     0.76| -1.691     0.80| -0.174   update
(legend: "1.23|+0.45" means: slower by factor 1.23 and by 0.45 seconds;
 factor < 1 and seconds < 0 means 'trunk@1366368' is faster.
 "2/3" means: '1.7.0' has 2 timings on record, the other has 3.)



Had started at Mon Jul 30 00:24:49 UTC 2012,
       done at Mon Jul 30 00:48:31 UTC 2012
/home/neels/svnbench
real 1421.85
user 517.99
sys 246.20