debug/sorter/BENCH

   1 # 32-bit Athlon 64, gcc-4.1
   2 mj@albireo:~/src/sh/dev-sorter/run$ bin/retros
   3 D 2006-11-23 23:17:36 [retros] memcpy: 212
   4 D 2006-11-23 23:17:44 [retros] qsort: 6947
   5 D 2006-11-23 23:17:48 [retros] arraysort: 3183
   6 D 2006-11-23 23:18:02 [retros] indirect qsort: 13116
   7 D 2006-11-23 23:18:24 [retros] indirect arraysort: 19176
   8 D 2006-11-23 23:18:30 [retros] radix1: 3755
   9 D 2006-11-23 23:18:34 [retros] radix1b: 3100
  10 D 2006-11-23 23:18:39 [retros] radix1c: 2777
  11 D 2006-11-23 23:18:43 [retros] radix1c-sse: 2602
  12 D 2006-11-23 23:18:47 [retros] radix1d: 2728
  13 D 2006-11-23 23:18:53 [retros] radix2: 4249
  14 D 2006-11-23 23:18:57 [retros] radix3: 2577
  15 D 2006-11-23 23:19:09 [retros] mergesort: 10399
  16 D 2006-11-23 23:19:16 [retros] samplesort: 5698
  17 D 2006-11-23 23:19:23 [retros] samplesort2: 5016
  18
  19 # 32-bit P4 Xeon, gcc-3.4
  20 sherlock@sherlock3:~/sherlock-mj/run$ bin/retros
  21 D 2006-11-23 23:23:52 [retros] memcpy: 198
  22 D 2006-11-23 23:24:23 [retros] qsort: 30114
  23 D 2006-11-23 23:24:27 [retros] arraysort: 2882
  24 D 2006-11-23 23:24:43 [retros] indirect qsort: 15019
  25 D 2006-11-23 23:24:59 [retros] indirect arraysort: 13267
  26 D 2006-11-23 23:25:03 [retros] radix1: 1881
  27 D 2006-11-23 23:25:06 [retros] radix1b: 1442
  28 D 2006-11-23 23:25:08 [retros] radix1c: 1313
  29 D 2006-11-23 23:25:10 [retros] radix1c-sse: 1229
  30 D 2006-11-23 23:25:13 [retros] radix1d: 1324
  31 D 2006-11-23 23:25:17 [retros] radix2: 2598
  32 D 2006-11-23 23:25:19 [retros] radix3: 1419
  33 D 2006-11-23 23:25:25 [retros] mergesort: 4929
  34 D 2006-11-23 23:25:29 [retros] samplesort: 2742
  35 D 2006-11-23 23:25:33 [retros] samplesort2: 2350
  36
  37 # 64-bit P4 Xeon, gcc-3.4
  38 sherlock@sherlock4:~/sherlock-3.10/run$ bin/retros
  39 D 2006-11-23 23:44:31 [retros] memcpy: 132
  40 D 2006-11-23 23:44:58 [retros] qsort: 26469
  41 D 2006-11-23 23:45:01 [retros] arraysort: 2307
  42 D 2006-11-23 23:45:12 [retros] indirect qsort: 10971
  43 D 2006-11-23 23:45:24 [retros] indirect arraysort: 10350
  44 D 2006-11-23 23:45:26 [retros] radix1: 1099
  45 D 2006-11-23 23:45:27 [retros] radix1b: 1052
  46 D 2006-11-23 23:45:29 [retros] radix1c: 1017
  47 D 2006-11-23 23:45:30 [retros] radix1c-sse: 1017
  48 D 2006-11-23 23:45:32 [retros] radix1d: 1016
  49 D 2006-11-23 23:45:34 [retros] radix2: 1661
  50 D 2006-11-23 23:45:36 [retros] radix3: 955
  51 D 2006-11-23 23:45:39 [retros] mergesort: 3302
  52 D 2006-11-23 23:45:42 [retros] samplesort: 2376
  53 D 2006-11-23 23:45:45 [retros] samplesort2: 1870
  54
  55 # 64-bit Turion X2 TL52, gcc-4.1.1
  56 pchar@paja ~/prog/sherlock-dev-sorter/run $ bin/retros
  57 D 2006-11-24 00:32:38 [retros] memcpy: 93
  58 D 2006-11-24 00:32:46 [retros] qsort: 7530
  59 D 2006-11-24 00:32:50 [retros] arraysort: 2766
  60 D 2006-11-24 00:33:01 [retros] indirect qsort: 10543
  61 D 2006-11-24 00:33:13 [retros] indirect arraysort: 10169
  62 D 2006-11-24 00:33:16 [retros] radix1: 1319
  63 D 2006-11-24 00:33:18 [retros] radix1b: 1126
  64 D 2006-11-24 00:33:20 [retros] radix1c: 1084
  65 D 2006-11-24 00:33:22 [retros] radix1c-sse: 1126
  66 D 2006-11-24 00:33:24 [retros] radix1d: 1091
  67 D 2006-11-24 00:33:27 [retros] radix2: 2238
  68 D 2006-11-24 00:33:29 [retros] radix3: 1183
  69 D 2006-11-24 00:33:34 [retros] mergesort: 4036
  70 D 2006-11-24 00:33:37 [retros] samplesort: 2594
  71 D 2006-11-24 00:33:40 [retros] samplesort2: 2214
  72
  73 ### file-test: pure write ###
  74
  75 I 2006-11-24 10:52:50 Initializing
  76 I 2006-11-24 10:52:52 Writing 32768 MB to 1 files in parallel with 1048576 byte buffers
  77 I 2006-11-24 10:57:35 Syncing
  78 I 2006-11-24 10:57:36 Spent 284686ms (115 MB/sec)
  79 I 2006-11-24 10:57:36 Reading the files sequentially
  80 I 2006-11-24 11:00:45 Spent 188351ms (173 MB/sec)
  81 I 2006-11-24 11:00:45 Done
  82
  83 I 2006-11-24 11:02:46 Initializing
  84 I 2006-11-24 11:03:12 Writing 32768 MB to 1 files in parallel with 65536 byte buffers
  85 I 2006-11-24 11:07:29 Syncing
  86 I 2006-11-24 11:07:30 Spent 257520ms (127 MB/sec)
  87 I 2006-11-24 11:07:30 Reading the files sequentially
  88 I 2006-11-24 11:10:07 Spent 157423ms (208 MB/sec)
  89 I 2006-11-24 11:10:07 Done
  90
  91 I 2006-11-24 11:14:29 Initializing
  92 I 2006-11-24 11:15:05 Writing 32768 MB to 16 files in parallel with 65536 byte buffers
  93 I 2006-11-24 11:19:22 Syncing
  94 I 2006-11-24 11:19:23 Spent 257815ms (127 MB/sec)
  95 I 2006-11-24 11:19:23 Reading the files sequentially
  96 I 2006-11-24 11:22:31 Spent 188160ms (174 MB/sec)
  97 I 2006-11-24 11:22:31 Done
  98
  99 I 2006-11-24 11:23:01 Initializing
 100 I 2006-11-24 11:23:26 Writing 32768 MB to 256 files in parallel with 65536 byte buffers
 101 I 2006-11-24 11:27:43 Syncing
 102 I 2006-11-24 11:27:44 Spent 258366ms (126 MB/sec)
 103 I 2006-11-24 11:27:44 Reading the files sequentially
 104 I 2006-11-24 11:31:07 Spent 203234ms (161 MB/sec)
 105 I 2006-11-24 11:31:07 Done
 106
 107 I 2006-11-24 12:00:44 Initializing
 108 I 2006-11-24 12:01:16 Writing 65536 MB to 256 files in parallel with 65536 byte buffers
 109 I 2006-11-24 12:10:20 Syncing
 110 I 2006-11-24 12:10:22 Spent 546139ms (119 MB/sec)
 111 I 2006-11-24 12:10:22 Reading the files sequentially
 112 I 2006-11-24 12:17:03 Spent 401425ms (163 MB/sec)
 113 I 2006-11-24 12:17:03 Done
 114
 115 I 2006-11-24 12:17:03 Initializing
 116 I 2006-11-24 12:18:51 Writing 65536 MB to 512 files in parallel with 65536 byte buffers
 117 I 2006-11-24 12:27:36 Syncing
 118 I 2006-11-24 12:27:38 Spent 526981ms (124 MB/sec)
 119 I 2006-11-24 12:27:38 Reading the files sequentially
 120 I 2006-11-24 12:34:35 Spent 416636ms (157 MB/sec)
 121 I 2006-11-24 12:34:35 Done
 122
 123 I 2006-11-24 11:32:56 Initializing
 124 I 2006-11-24 11:33:27 Writing 32768 MB to 1024 files in parallel with 65536 byte buffers
 125 I 2006-11-24 11:39:09 Syncing
 126 I 2006-11-24 11:39:18 Spent 350706ms (93 MB/sec)
 127 I 2006-11-24 11:39:18 Reading the files sequentially
 128 I 2006-11-24 11:43:22 Spent 244076ms (134 MB/sec)
 129 I 2006-11-24 11:43:22 Done
 130
 131 ### file-test: copy ###
 132
 133 I 2006-11-24 15:09:42 Creating input file
 134 I 2006-11-24 15:13:41 Spent 238846ms (137 MB/sec)
 135 I 2006-11-24 15:13:41 Initializing output files
 136 I 2006-11-24 15:13:41 Writing 32768 MB to 16 files in parallel with 65536 byte buffers
 137 I 2006-11-24 15:22:09 Syncing
 138 I 2006-11-24 15:22:11 Spent 509946ms (64 MB/sec)
 139 I 2006-11-24 15:22:11 Reading the files sequentially
 140 I 2006-11-24 15:25:19 Spent 188915ms (173 MB/sec)
 141 I 2006-11-24 15:26:15 Done
 142
 143 I 2006-11-24 15:29:26 Creating input file
 144 I 2006-11-24 15:33:25 Spent 239255ms (136 MB/sec)
 145 I 2006-11-24 15:33:25 Initializing output files
 146 I 2006-11-24 15:33:25 Writing 32768 MB to 256 files in parallel with 65536 byte buffers
 147 I 2006-11-24 15:48:00 Syncing
 148 I 2006-11-24 15:48:01 Spent 876144ms (37 MB/sec)
 149 I 2006-11-24 15:48:01 Reading the files sequentially
 150 I 2006-11-24 15:51:33 Spent 211650ms (154 MB/sec)
 151 I 2006-11-24 15:52:32 Done
 152
 153 I 2006-11-24 15:54:38 Creating input file
 154 I 2006-11-24 15:58:53 Spent 255885ms (128 MB/sec)
 155 I 2006-11-24 15:58:53 Initializing output files
 156 I 2006-11-24 15:58:53 Writing 32768 MB to 256 files in parallel with 1048576 byte buffers
 157 I 2006-11-24 16:09:04 Syncing
 158 I 2006-11-24 16:09:05 Spent 611713ms (53 MB/sec)
 159 I 2006-11-24 16:09:05 Reading the files sequentially
 160 I 2006-11-24 16:12:44 Spent 219099ms (149 MB/sec)
 161 I 2006-11-24 16:13:42 Done
 162
 163 (with O_DIRECT on the input file)
 164 sherlock@sherlock3:~/run-3.11$ bin/file-test 256 64 32
 165 I 2006-11-24 16:43:52 Creating input file
 166 I 2006-11-24 16:47:48 Spent 236225ms (138 MB/sec)
 167 I 2006-11-24 16:47:48 Initializing output files
 168 I 2006-11-24 16:47:49 Writing 32768 MB to 256 files in parallel with 65536 byte buffers
 169 I 2006-11-24 17:06:24 Syncing
 170 I 2006-11-24 17:06:25 Spent 1116667ms (29 MB/sec)
 171
 172 (the following results are with full O_DIRECT)
 173
 174 sherlock@sherlock3:~/run-3.11$ bin/file-test 256 4096 32
 175 I 2006-11-24 17:20:11 Creating input file
 176 I 2006-11-24 17:24:26 Spent 255.051 sec (128.48 MB/sec)
 177 I 2006-11-24 17:24:26 Initializing output files
 178 I 2006-11-24 17:24:26 Writing 32768 MB to 256 files in parallel with 4194304 byte buffers
 179 I 2006-11-24 17:31:50 Syncing
 180 I 2006-11-24 17:31:50 Spent 443.999 sec (73.80 MB/sec)
 181 I 2006-11-24 17:31:50 Reading the files sequentially
 182 I 2006-11-24 17:34:38 Spent 167.203 sec (195.98 MB/sec)
 183 I 2006-11-24 17:34:43 Done
 184
 185 sherlock@sherlock3:~/run-3.11$ bin/file-test 1024 1024 16
 186 I 2006-11-24 18:23:29 Creating input file
 187 I 2006-11-24 18:25:36 Spent 127.604 sec (128.40 MB/sec)
 188 I 2006-11-24 18:25:36 Initializing output files
 189 I 2006-11-24 18:25:36 Writing 16384 MB to 1024 files in parallel with 1048576 byte buffers
 190 I 2006-11-24 18:31:38 Syncing
 191 I 2006-11-24 18:31:39 Spent 362.371 sec (45.21 MB/sec)
 192
 193 sherlock@sherlock3:~/run-3.11$ bin/file-test 512 4096 16
 194 I 2006-11-24 18:32:57 Creating input file
 195 I 2006-11-24 18:35:03 Spent 126.128 sec (129.90 MB/sec)
 196 I 2006-11-24 18:35:03 Initializing output files
 197 I 2006-11-24 18:35:03 Writing 16384 MB to 512 files in parallel with 4194304 byte buffers
 198 I 2006-11-24 18:38:30 Syncing
 199 I 2006-11-24 18:38:31 Spent 208.551 sec (78.56 MB/sec)
 200 I 2006-11-24 18:38:31 Reading the files sequentially
 201 I 2006-11-24 18:40:03 Spent 91.598 sec (178.87 MB/sec)
 202 I 2006-11-24 18:40:06 Done
 203
 204 sherlock@sherlock3:~/run-3.11$ bin/file-test 256 2048 16
 205 I 2006-11-24 18:45:45 Creating input file
 206 I 2006-11-24 18:47:49 Spent 123.534 sec (132.63 MB/sec)
 207 I 2006-11-24 18:47:49 Initializing output files
 208 I 2006-11-24 18:47:49 Writing 16384 MB to 256 files in parallel with 2097152 byte buffers
 209 I 2006-11-24 18:52:06 Syncing
 210 I 2006-11-24 18:52:07 Spent 257.988 sec (63.51 MB/sec)
 211 I 2006-11-24 18:52:07 Reading the files sequentially
 212 I 2006-11-24 18:53:36 Spent 89.451 sec (183.16 MB/sec)
 213 I 2006-11-24 18:53:39 Done
 214
 215 sherlock@sherlock3:~/run-3.11$ bin/file-test 16 16384 16
 216 I 2006-11-24 18:53:57 Creating input file
 217 I 2006-11-24 18:55:36 Spent 99.077 sec (165.37 MB/sec)
 218 I 2006-11-24 18:55:36 Initializing output files
 219 I 2006-11-24 18:55:36 Writing 16384 MB to 16 files in parallel with 16777216 byte buffers
 220 I 2006-11-24 18:58:09 Syncing
 221 I 2006-11-24 18:58:10 Spent 153.898 sec (106.46 MB/sec)
 222 I 2006-11-24 18:58:10 Reading the files sequentially
 223 I 2006-11-24 18:59:19 Spent 69.361 sec (236.21 MB/sec)
 224 I 2006-11-24 18:59:22 Done
 225
 226 sherlock@sherlock3:~/run-3.11$ bin/file-test 16 65536 16
 227 I 2006-11-24 19:00:52 Creating input file
 228 I 2006-11-24 19:02:31 Spent 99.330 sec (164.95 MB/sec)
 229 I 2006-11-24 19:02:31 Initializing output files
 230 I 2006-11-24 19:02:31 Writing 16384 MB to 16 files in parallel with 67108864 byte buffers
 231 I 2006-11-24 19:04:56 Syncing
 232 I 2006-11-24 19:04:56 Spent 144.390 sec (113.47 MB/sec)
 233 I 2006-11-24 19:04:56 Reading the files sequentially
 234 I 2006-11-24 19:06:01 Spent 65.553 sec (249.94 MB/sec)
 235 I 2006-11-24 19:06:04 Done
 236
 237 sherlock@sherlock3:~/run-3.11$ bin/file-test 16 65536 32
 238 I 2006-11-24 19:07:08 Creating input file
 239 I 2006-11-24 19:10:26 Spent 198.269 sec (165.27 MB/sec)
 240 I 2006-11-24 19:10:26 Initializing output files
 241 I 2006-11-24 19:10:26 Writing 32768 MB to 16 files in parallel with 67108864 byte buffers
 242 I 2006-11-24 19:15:18 Syncing
 243 I 2006-11-24 19:15:18 Spent 292.412 sec (112.06 MB/sec)
 244 I 2006-11-24 19:15:18 Reading the files sequentially
 245 I 2006-11-24 19:17:30 Spent 131.738 sec (248.74 MB/sec)
 246 I 2006-11-24 19:17:36 Done
 247
 248 ### asio-test (copy mode) ###
 249
 250 sherlock@sherlock3:~/run-3.11$ bin/asio-test 16 65536 32
 251 I 2006-12-01 22:09:44 Creating input file
 252 I 2006-12-01 22:12:07 Spent 143.221 sec (228.79 MB/sec)
 253 I 2006-12-01 22:12:07 Initializing output files
 254 I 2006-12-01 22:12:07 Writing 32768 MB to 16 files in parallel with 67108864 byte buffers
 255 I 2006-12-01 22:16:50 Syncingsec)
 256 I 2006-12-01 22:16:51 Spent 283.507 sec (115.58 MB/sec)
 257 I 2006-12-01 22:16:51 Reading the files sequentially
 258 I 2006-12-01 22:19:01 Spent 130.772 sec (250.57 MB/sec)
 259 I 2006-12-01 22:19:07 Done
 260
 261 sherlock@sherlock3:~/run-3.11$ bin/asio-test 256 4096 32
 262 I 2006-12-01 22:19:39 Creating input file
 263 I 2006-12-01 22:22:43 Spent 184.154 sec (177.94 MB/sec)
 264 I 2006-12-01 22:22:43 Initializing output files
 265 I 2006-12-01 22:22:43 Writing 32768 MB to 256 files in parallel with 4194304 byte buffers
 266 I 2006-12-01 22:29:58 Syncingec)
 267 I 2006-12-01 22:29:59 Spent 435.416 sec (75.26 MB/sec)
 268 I 2006-12-01 22:29:59 Reading the files sequentially
 269 I 2006-12-01 22:32:48 Spent 168.874 sec (194.04 MB/sec)
 270 I 2006-12-01 22:32:53 Done
 271
 272 sherlock@sherlock3:~/run-3.11$ bin/asio-test 1024 1024 16
 273 I 2006-12-01 22:37:16 Creating input file
 274 I 2006-12-01 22:38:52 Spent 96.078 sec (170.53 MB/sec)
 275 I 2006-12-01 22:38:52 Initializing output files
 276 I 2006-12-01 22:38:52 Writing 16384 MB to 1024 files in parallel with 1048576 byte buffers
 277 I 2006-12-01 22:44:28 Syncingec)
 278 I 2006-12-01 22:44:29 Spent 337.186 sec (48.59 MB/sec)
 279 I 2006-12-01 22:44:29 Reading the files sequentially
 280 I 2006-12-01 22:46:23 Spent 114.060 sec (143.64 MB/sec)
 281 I 2006-12-01 22:46:26 Done
 282
 283 ### asio-test (copy mode with memcpy)
 284
 285 sherlock@sherlock3:~/run-3.11$ bin/file-test 16 65536 32
 286 I 2006-12-01 23:47:41 Creating input file
 287 I 2006-12-01 23:50:57 Spent 196.321 sec (166.91 MB/sec)
 288 I 2006-12-01 23:50:57 Initializing output files
 289 I 2006-12-01 23:50:57 Writing 32768 MB to 16 files in parallel with 67108864 byte buffers
 290 I 2006-12-01 23:55:42 Syncingsec)
 291 I 2006-12-01 23:55:42 Spent 284.875 sec (115.03 MB/sec)
 292 I 2006-12-01 23:55:42 Reading the files sequentially
 293 I 2006-12-01 23:57:52 Spent 130.049 sec (251.97 MB/sec)
 294 I 2006-12-01 23:57:58 Done
 295
 296 sherlock@sherlock3:~/run-3.11$ bin/asio-test 256 4096 32
 297 I 2006-12-01 23:25:42 Creating input file
 298 I 2006-12-01 23:28:45 Spent 182.538 sec (179.51 MB/sec)
 299 I 2006-12-01 23:28:45 Initializing output files
 300 I 2006-12-01 23:28:45 Writing 32768 MB to 256 files in parallel with 4194304 byte buffers
 301 I 2006-12-01 23:36:45 Syncingec)
 302 I 2006-12-01 23:36:46 Spent 481.480 sec (68.06 MB/sec)
 303
 304 sherlock@sherlock3:~/run-3.11$ bin/file-test 1024 1024 16
 305 I 2006-12-01 23:38:03 Creating input file
 306 I 2006-12-01 23:40:07 Spent 124.668 sec (131.42 MB/sec)
 307 I 2006-12-01 23:40:07 Initializing output files
 308 I 2006-12-01 23:40:07 Writing 16384 MB to 1024 files in parallel with 1048576 byte buffers
 309 I 2006-12-01 23:45:43 Syncingec)
 310 I 2006-12-01 23:45:43 Spent 335.988 sec (48.76 MB/sec)
 311 I 2006-12-01 23:45:43 Reading the files sequentially
 312 I 2006-12-01 23:47:38 Spent 114.654 sec (142.90 MB/sec)
 313 I 2006-12-01 23:47:41 Done