Skip to content

Instantly share code, notes, and snippets.

@rcrowley

rcrowley/gist:9192703

Last active August 29, 2015 13:56
Show Gist options
  • Save rcrowley/9192703 to your computer and use it in GitHub Desktop.
Save rcrowley/9192703 to your computer and use it in GitHub Desktop.
Download ZIP
Benchmarks for Go CL 67730046
Raw
gistfile1.md

Benchmarks before CL 67730046

./bench -bench=http

2014/02/24 10:21:20 Benchmarking 1 iterations
2014/02/24 10:21:20 Benchmarking 100 iterations
2014/02/24 10:21:20 Benchmarking 10000 iterations
2014/02/24 10:21:21 Benchmarking 100000 iterations
2014/02/24 10:21:30 Result: {N:100000 Duration:9.175569959s RunTime:91755 Metrics:map[time:91755 virtual-mem:434716672 cputime:77124 latency-50:183154 sys-total:
19519808 sys-other:4995392 gc-pause-one:1246836 latency-95:972536 latency-99:4263845 allocated:5381 rss:16367616 allocs:53 sys-heap:12189696 sys-stack:524288 gc-
pause-total:1009 sys-gc:1810432] Files:map[memprof0:/tmp/10.memprof cpuprof:/tmp/11.cpuprof memprof:/tmp/12.memprof]}
2014/02/24 10:21:30 Benchmarking 1 iterations
2014/02/24 10:21:30 Benchmarking 100 iterations
2014/02/24 10:21:31 Benchmarking 10000 iterations
2014/02/24 10:21:32 Benchmarking 100000 iterations
2014/02/24 10:21:41 Result: {N:100000 Duration:9.175507922s RunTime:91755 Metrics:map[sys-total:19650880 sys-heap:12189696 gc-pause-total:1050 sys-gc:1810432 sys
-other:4995392 allocs:53 latency-99:4056648 virtual-mem:434716672 cputime:74644 latency-95:966220 rss:17354752 sys-stack:655360 gc-pause-one:1312609 latency-50:2
14764 allocated:5380 time:91755] Files:map[memprof0:/tmp/22.memprof cpuprof:/tmp/23.cpuprof memprof:/tmp/24.memprof]}
2014/02/24 10:21:41 Benchmarking 1 iterations
2014/02/24 10:21:41 Benchmarking 100 iterations
2014/02/24 10:21:41 Benchmarking 10000 iterations
2014/02/24 10:21:42 Benchmarking 100000 iterations
2014/02/24 10:21:49 Result: {N:100000 Duration:7.719612253s RunTime:77196 Metrics:map[time:77196 virtual-mem:434716672 cputime:73764 latency-50:210364 sys-total:
19650880 sys-other:4995392 gc-pause-one:1112291 latency-95:909124 latency-99:2245557 allocated:5379 rss:17846272 allocs:53 sys-heap:12189696 sys-stack:655360 gc-
pause-total:889 sys-gc:1810432] Files:map[memprof0:/tmp/34.memprof cpuprof:/tmp/35.cpuprof memprof:/tmp/36.memprof]}
2014/02/24 10:21:49 Benchmarking 1 iterations
2014/02/24 10:21:49 Benchmarking 100 iterations
2014/02/24 10:21:49 Benchmarking 10000 iterations
2014/02/24 10:21:50 Benchmarking 100000 iterations
2014/02/24 10:21:59 Result: {N:100000 Duration:8.60835789s RunTime:86083 Metrics:map[allocs:53 sys-total:19650880 sys-heap:12189696 gc-pause-total:1066 sys-gc:18
10432 sys-other:4995392 latency-95:957440 latency-99:3823423 virtual-mem:434716672 cputime:76604 latency-50:182923 rss:17846272 sys-stack:655360 gc-pause-one:131
7251 time:86083 allocated:5380] Files:map[cpuprof:/tmp/47.cpuprof memprof:/tmp/48.memprof memprof0:/tmp/46.memprof]}
2014/02/24 10:21:59 Benchmarking 1 iterations
2014/02/24 10:21:59 Benchmarking 100 iterations
2014/02/24 10:21:59 Benchmarking 10000 iterations
2014/02/24 10:22:00 Benchmarking 200000 iterations
2014/02/24 10:22:17 Result: {N:200000 Duration:16.930113026s RunTime:84650 Metrics:map[sys-heap:13893632 gc-pause-total:808 sys-gc:1916928 sys-other:4995392 allo
cs:53 sys-total:21592384 virtual-mem:434716672 cputime:74984 latency-95:927370 latency-99:3657784 sys-stack:786432 gc-pause-one:1162958 latency-50:195949 rss:196
77184 time:84650 allocated:5378] Files:map[memprof0:/tmp/58.memprof cpuprof:/tmp/59.cpuprof memprof:/tmp/60.memprof]}
GOPERF-METRIC:allocated=5379
GOPERF-METRIC:allocs=53
GOPERF-METRIC:cputime=73764
GOPERF-METRIC:gc-pause-one=1112291
GOPERF-METRIC:gc-pause-total=889
GOPERF-METRIC:latency-50=210364
GOPERF-METRIC:latency-95=909124
GOPERF-METRIC:latency-99=2245557
GOPERF-METRIC:rss=19677184
GOPERF-METRIC:sys-gc=1916928
GOPERF-METRIC:sys-heap=13893632
GOPERF-METRIC:sys-other=4995392
GOPERF-METRIC:sys-stack=786432
GOPERF-METRIC:sys-total=21592384
GOPERF-METRIC:time=77196
GOPERF-METRIC:virtual-mem=434716672
GOPERF-FILE:cpuprof=/tmp/61.prof.txt
GOPERF-FILE:memprof=/tmp/62.prof.txt

GOMAXPROCS=2 ./bench -bench=http

2014/02/24 10:22:22 Benchmarking 1 iterations
2014/02/24 10:22:22 Benchmarking 100 iterations
2014/02/24 10:22:22 Benchmarking 10000 iterations
2014/02/24 10:22:22 Benchmarking 200000 iterations
2014/02/24 10:22:38 Result: {N:200000 Duration:15.700674654s RunTime:78503 Metrics:map[gc-pause-total:891 sys-gc:1941504 sys-other:4995392 allocs:53 sys-total:21
879104 sys-heap:14286848 cputime:75124 latency-95:1429149 latency-99:7961811 virtual-mem:434847744 gc-pause-one:1320362 latency-50:305109 rss:18812928 sys-stack:
655360 time:78503 allocated:5383] Files:map[cpuprof:/tmp/11.cpuprof memprof:/tmp/12.memprof memprof0:/tmp/10.memprof]}
2014/02/24 10:22:38 Benchmarking 1 iterations
2014/02/24 10:22:38 Benchmarking 100 iterations
2014/02/24 10:22:38 Benchmarking 10000 iterations
2014/02/24 10:22:39 Benchmarking 200000 iterations
2014/02/24 10:22:56 Result: {N:200000 Duration:17.26021104s RunTime:86301 Metrics:map[sys-gc:1941504 sys-other:4995392 allocs:53 sys-total:22272320 sys-heap:1428
6848 gc-pause-total:957 latency-95:1641449 latency-99:8435503 virtual-mem:434847744 cputime:80625 latency-50:316893 rss:19570688 sys-stack:1048576 gc-pause-one:1
439560 time:86301 allocated:5382] Files:map[memprof0:/tmp/22.memprof cpuprof:/tmp/23.cpuprof memprof:/tmp/24.memprof]}
2014/02/24 10:22:56 Benchmarking 1 iterations
2014/02/24 10:22:56 Benchmarking 100 iterations
2014/02/24 10:22:56 Benchmarking 10000 iterations
2014/02/24 10:22:57 Benchmarking 100000 iterations
2014/02/24 10:23:07 Result: {N:100000 Duration:9.909473456s RunTime:99094 Metrics:map[cputime:82965 time:99094 virtual-mem:434847744 sys-other:4995392 gc-pause-o
ne:1582139 latency-50:323675 sys-total:22534464 allocated:5383 latency-95:2226717 latency-99:10575962 sys-heap:14286848 sys-stack:1310720 gc-pause-total:1202 sys
-gc:1941504 rss:19828736 allocs:53] Files:map[memprof0:/tmp/34.memprof cpuprof:/tmp/35.cpuprof memprof:/tmp/36.memprof]}
2014/02/24 10:23:07 Benchmarking 1 iterations
2014/02/24 10:23:07 Benchmarking 100 iterations
2014/02/24 10:23:07 Benchmarking 10000 iterations
2014/02/24 10:23:08 Benchmarking 100000 iterations
2014/02/24 10:23:18 Result: {N:100000 Duration:10.192607311s RunTime:101926 Metrics:map[latency-95:2280476 latency-99:9739593 allocated:5383 sys-stack:1441792 gc
-pause-total:1264 sys-gc:1941504 rss:19828736 allocs:53 sys-heap:14286848 time:101926 virtual-mem:434847744 cputime:96646 gc-pause-one:1663270 latency-50:355158 
sys-total:22665536 sys-other:4995392] Files:map[memprof0:/tmp/46.memprof cpuprof:/tmp/47.cpuprof memprof:/tmp/48.memprof]}
2014/02/24 10:23:18 Benchmarking 1 iterations
2014/02/24 10:23:18 Benchmarking 100 iterations
2014/02/24 10:23:18 Benchmarking 10000 iterations
2014/02/24 10:23:19 Benchmarking 100000 iterations
2014/02/24 10:23:28 Result: {N:100000 Duration:8.516850452s RunTime:85168 Metrics:map[time:85168 virtual-mem:434847744 cputime:81885 latency-50:320322 sys-total:
22796608 sys-other:4995392 gc-pause-one:1426621 latency-95:1547696 latency-99:8385691 allocated:5383 rss:20025344 allocs:53 sys-heap:14286848 sys-stack:1572864 g
c-pause-total:1084 sys-gc:1941504] Files:map[memprof0:/tmp/58.memprof cpuprof:/tmp/59.cpuprof memprof:/tmp/60.memprof]}
GOPERF-METRIC:allocated=5383
GOPERF-METRIC:allocs=53
GOPERF-METRIC:cputime=75124
GOPERF-METRIC:gc-pause-one=1320362
GOPERF-METRIC:gc-pause-total=891
GOPERF-METRIC:latency-50=305109
GOPERF-METRIC:latency-95=1429149
GOPERF-METRIC:latency-99=7961811
GOPERF-METRIC:rss=20025344
GOPERF-METRIC:sys-gc=1941504
GOPERF-METRIC:sys-heap=14286848
GOPERF-METRIC:sys-other=4995392
GOPERF-METRIC:sys-stack=1572864
GOPERF-METRIC:sys-total=22796608
GOPERF-METRIC:time=78503
GOPERF-METRIC:virtual-mem=434847744
GOPERF-FILE:cpuprof=/tmp/61.prof.txt
GOPERF-FILE:memprof=/tmp/62.prof.txt

Benchmarks after CL 67730046

./bench -bench=http

2014/02/27 05:12:11 Benchmarking 1 iterations
2014/02/27 05:12:11 Benchmarking 100 iterations
2014/02/27 05:12:11 Benchmarking 10000 iterations
2014/02/27 05:12:12 Benchmarking 100000 iterations
2014/02/27 05:12:20 Result: {N:100000 Duration:8.549251731s RunTime:85492 Metrics:map[latency-50:214900 rss:16601088 sys-stack:524288 gc-pause-one:1249974 time:8
5492 allocated:5380 sys-other:4995392 allocs:53 sys-total:19519808 sys-heap:12189696 gc-pause-total:1012 sys-gc:1810432 latency-95:956572 latency-99:3213171 virt
ual-mem:349712384 cputime:80365] Files:map[memprof:/tmp/12.memprof memprof0:/tmp/10.memprof cpuprof:/tmp/11.cpuprof]}
2014/02/27 05:12:20 Benchmarking 1 iterations
2014/02/27 05:12:20 Benchmarking 100 iterations
2014/02/27 05:12:20 Benchmarking 10000 iterations
2014/02/27 05:12:21 Benchmarking 100000 iterations
2014/02/27 05:12:28 Result: {N:100000 Duration:7.220068432s RunTime:72200 Metrics:map[latency-99:1214908 allocated:5381 latency-95:725518 allocs:53 sys-heap:1218
9696 sys-stack:655360 gc-pause-total:989 sys-gc:1810432 rss:17620992 virtual-mem:349712384 cputime:70684 time:72200 sys-total:19650880 sys-other:4995392 gc-pause
-one:1221675 latency-50:224863] Files:map[memprof:/tmp/24.memprof memprof0:/tmp/22.memprof cpuprof:/tmp/23.cpuprof]}
2014/02/27 05:12:28 Benchmarking 1 iterations
2014/02/27 05:12:28 Benchmarking 100 iterations
2014/02/27 05:12:28 Benchmarking 10000 iterations
2014/02/27 05:12:29 Benchmarking 100000 iterations
2014/02/27 05:12:38 Result: {N:100000 Duration:8.84013448s RunTime:88401 Metrics:map[latency-50:213919 rss:17862656 sys-stack:655360 gc-pause-one:1247371 time:88
401 allocated:5380 sys-other:4995392 allocs:53 sys-total:19650880 sys-heap:12189696 gc-pause-total:1010 sys-gc:1810432 latency-95:982479 latency-99:3574781 virtu
al-mem:349712384 cputime:82725] Files:map[memprof:/tmp/36.memprof memprof0:/tmp/34.memprof cpuprof:/tmp/35.cpuprof]}
2014/02/27 05:12:38 Benchmarking 1 iterations
2014/02/27 05:12:38 Benchmarking 100 iterations
2014/02/27 05:12:38 Benchmarking 10000 iterations
2014/02/27 05:12:39 Benchmarking 200000 iterations
2014/02/27 05:12:55 Result: {N:200000 Duration:16.159612582s RunTime:80798 Metrics:map[sys-gc:1916928 sys-other:4995392 allocs:53 sys-total:21461312 sys-heap:138
93632 gc-pause-total:801 latency-95:919286 latency-99:3094886 virtual-mem:349712384 cputime:74784 latency-50:212129 rss:19955712 sys-stack:655360 gc-pause-one:11
37308 time:80798 allocated:5379] Files:map[memprof0:/tmp/46.memprof cpuprof:/tmp/47.cpuprof memprof:/tmp/48.memprof]}
2014/02/27 05:12:55 Benchmarking 1 iterations
2014/02/27 05:12:55 Benchmarking 100 iterations
2014/02/27 05:12:55 Benchmarking 10000 iterations
2014/02/27 05:12:56 Benchmarking 100000 iterations
2014/02/27 05:13:04 Result: {N:100000 Duration:8.427177715s RunTime:84271 Metrics:map[time:84271 virtual-mem:349712384 cputime:71444 latency-50:217005 sys-total:
21461312 sys-other:4995392 gc-pause-one:1281649 latency-95:884847 latency-99:2890076 allocated:5381 rss:19955712 allocs:53 sys-heap:13893632 sys-stack:655360 gc-
pause-total:1038 sys-gc:1916928] Files:map[memprof0:/tmp/58.memprof cpuprof:/tmp/59.cpuprof memprof:/tmp/60.memprof]}
GOPERF-METRIC:allocated=5381
GOPERF-METRIC:allocs=53
GOPERF-METRIC:cputime=70684
GOPERF-METRIC:gc-pause-one=1221675
GOPERF-METRIC:gc-pause-total=989
GOPERF-METRIC:latency-50=224863
GOPERF-METRIC:latency-95=725518
GOPERF-METRIC:latency-99=1214908
GOPERF-METRIC:rss=19955712
GOPERF-METRIC:sys-gc=1916928
GOPERF-METRIC:sys-heap=13893632
GOPERF-METRIC:sys-other=4995392
GOPERF-METRIC:sys-stack=655360
GOPERF-METRIC:sys-total=21461312
GOPERF-METRIC:time=72200
GOPERF-METRIC:virtual-mem=349712384
GOPERF-FILE:cpuprof=/tmp/61.prof.txt
GOPERF-FILE:memprof=/tmp/62.prof.txt

GOMAXPROCS=2 ./bench -bench=http

2014/02/27 05:16:37 Benchmarking 1 iterations
2014/02/27 05:16:37 Benchmarking 100 iterations
2014/02/27 05:16:37 Benchmarking 10000 iterations
2014/02/27 05:16:38 Benchmarking 100000 iterations2014/02/27 05:16:46 Result: {N:100000 Duration:8.188688738s RunTime:81886 Metrics:map[sys-other:4995392 allocs:53 sys-total:21027136 sys-heap:13238272 gc-pause-total:1042 sys-gc:1875968 latency-95:1634829 latency-99:7694963 virtual-mem:343973888 cputime:78324 latency-50:295879 rss:16752640 sys-stack:917504 gc-pause-one:1
354247 time:81886 allocated:5386] Files:map[memprof0:/tmp/10.memprof cpuprof:/tmp/11.cpuprof memprof:/tmp/12.memprof]}
2014/02/27 05:16:46 Benchmarking 1 iterations
2014/02/27 05:16:46 Benchmarking 100 iterations
2014/02/27 05:16:46 Benchmarking 10000 iterations
2014/02/27 05:16:47 Benchmarking 100000 iterations
2014/02/27 05:16:56 Result: {N:100000 Duration:9.602121153s RunTime:96021 Metrics:map[latency-50:318647 rss:18018304 sys-stack:1179648 gc-pause-one:1588231 time:
96021 allocated:5384 sys-other:4995392 allocs:53 sys-total:21289280 sys-heap:13238272 gc-pause-total:1207 sys-gc:1875968 latency-95:2481789 latency-99:8751212 vi
rtual-mem:344236032 cputime:87325] Files:map[memprof:/tmp/24.memprof memprof0:/tmp/22.memprof cpuprof:/tmp/23.cpuprof]}
2014/02/27 05:16:56 Benchmarking 1 iterations
2014/02/27 05:16:56 Benchmarking 100 iterations
2014/02/27 05:16:56 Benchmarking 10000 iterations
2014/02/27 05:16:57 Benchmarking 200000 iterations
2014/02/27 05:17:15 Result: {N:200000 Duration:17.755108524s RunTime:88775 Metrics:map[time:88775 virtual-mem:346177536 cputime:79544 latency-50:313499 sys-total
:23230784 sys-other:4995392 gc-pause-one:1567174 latency-95:1870346 latency-99:8263789 allocated:5382 rss:19759104 allocs:53 sys-heap:14942208 sys-stack:1310720 
gc-pause-total:1042 sys-gc:1982464] Files:map[memprof0:/tmp/34.memprof cpuprof:/tmp/35.cpuprof memprof:/tmp/36.memprof]}
2014/02/27 05:17:15 Benchmarking 1 iterations
2014/02/27 05:17:15 Benchmarking 100 iterations
2014/02/27 05:17:15 Benchmarking 10000 iterations
2014/02/27 05:17:16 Benchmarking 100000 iterations
2014/02/27 05:17:24 Result: {N:100000 Duration:8.118249459s RunTime:81182 Metrics:map[virtual-mem:346177536 cputime:77484 time:81182 sys-total:23230784 sys-other
:4995392 gc-pause-one:1392853 latency-50:297262 latency-99:7518119 allocated:5384 latency-95:1625711 allocs:53 sys-heap:14942208 sys-stack:1310720 gc-pause-total
:1058 sys-gc:1982464 rss:19759104] Files:map[memprof:/tmp/48.memprof memprof0:/tmp/46.memprof cpuprof:/tmp/47.cpuprof]}
2014/02/27 05:17:24 Benchmarking 1 iterations
2014/02/27 05:17:24 Benchmarking 100 iterations
2014/02/27 05:17:24 Benchmarking 10000 iterations
2014/02/27 05:17:25 Benchmarking 100000 iterations
2014/02/27 05:17:33 Result: {N:100000 Duration:8.321508216s RunTime:83215 Metrics:map[time:83215 virtual-mem:346177536 cputime:78364 latency-50:296660 sys-total:
23230784 sys-other:4995392 gc-pause-one:1428312 latency-95:1696405 latency-99:7817777 allocated:5384 rss:19759104 allocs:53 sys-heap:14942208 sys-stack:1310720 g
c-pause-total:1085 sys-gc:1982464] Files:map[memprof0:/tmp/58.memprof cpuprof:/tmp/59.cpuprof memprof:/tmp/60.memprof]}
GOPERF-METRIC:allocated=5384
GOPERF-METRIC:allocs=53
GOPERF-METRIC:cputime=77484
GOPERF-METRIC:gc-pause-one=1392853
GOPERF-METRIC:gc-pause-total=1058
GOPERF-METRIC:latency-50=297262
GOPERF-METRIC:latency-95=1625711
GOPERF-METRIC:latency-99=7518119
GOPERF-METRIC:rss=19759104
GOPERF-METRIC:sys-gc=1982464
GOPERF-METRIC:sys-heap=14942208
GOPERF-METRIC:sys-other=4995392
GOPERF-METRIC:sys-stack=1310720
GOPERF-METRIC:sys-total=23230784
GOPERF-METRIC:time=81182
GOPERF-METRIC:virtual-mem=346177536
GOPERF-FILE:cpuprof=/tmp/61.prof.txt
GOPERF-FILE:memprof=/tmp/62.prof.txt
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment