Skip to content

Instantly share code, notes, and snippets.

@hcliff

hcliff/gist:8275313fe7951c3df883

Created June 10, 2015 19:09
Show Gist options
  • Save hcliff/8275313fe7951c3df883 to your computer and use it in GitHub Desktop.
Save hcliff/8275313fe7951c3df883 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
Before
[negroni] Started GET /browse
2015/06/10 15:09:25 Memstats param parsing alloc: 704B totalalloc: 704B sys: 0 pause: 0ms numGc: 0, heapAlloc: 704B heapSys: -16K heapObjects: 15 heapInuse: 0 elapsed: 169.577µs
2015/06/10 15:09:25 Memstats s2-covering alloc: 16.6K totalalloc: 16.6K sys: 0 pause: 0ms numGc: 0, heapAlloc: 16.6K heapSys: 0 heapObjects: 147 heapInuse: 16K elapsed: 294.735µs
2015/06/10 15:09:25 Memstats create-venue-event-query alloc: 192B totalalloc: 192B sys: 0 pause: 0ms numGc: 0, heapAlloc: 192B heapSys: 0 heapObjects: 2 heapInuse: 0 elapsed: 16.89µs
gc10(1): 1+0+1024+1 us, 1 -> 2 MB, 25383 (46628-21245) objects, 15 goroutines, 239/0/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc11(1): 1+0+1282+2 us, 1 -> 3 MB, 45529 (106116-60587) objects, 15 goroutines, 344/52/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc12(1): 1+0+1506+0 us, 2 -> 4 MB, 68582 (185793-117211) objects, 15 goroutines, 472/319/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc13(1): 1+0+1881+0 us, 2 -> 5 MB, 104066 (301373-197307) objects, 15 goroutines, 658/400/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc14(1): 1+0+2265+0 us, 3 -> 7 MB, 138640 (438349-299709) objects, 15 goroutines, 851/337/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc15(1): 1+0+3334+0 us, 4 -> 9 MB, 194679 (627016-432337) objects, 15 goroutines, 1149/417/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc16(1): 1+0+4925+0 us, 6 -> 13 MB, 255647 (857436-601789) objects, 15 goroutines, 1477/577/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc17(1): 1+0+6314+1 us, 8 -> 17 MB, 355513 (1176283-820770) objects, 15 goroutines, 1999/656/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:09:26 Memstats venueevent-fetch alloc: 20.8M totalalloc: 42.4M sys: 22.6M pause: 22ms numGc: 8, heapAlloc: 20.8M heapSys: 21M heapObjects: 460457 heapInuse: 21.2M elapsed: 1.037364953s
2015/06/10 15:09:26 Memstats gather-venue-event-ids alloc: 0 totalalloc: 0 sys: 0 pause: 0ms numGc: 0, heapAlloc: 0 heapSys: 0 heapObjects: 0 heapInuse: 0 elapsed: 338µs
gc18(1): 2+0+8275+1 us, 11 -> 22 MB, 473063 (1591739-1118676) objects, 15 goroutines, 2626/1062/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc19(1): 2+0+14617+2 us, 14 -> 30 MB, 649164 (2130186-1481022) objects, 15 goroutines, 3385/1472/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc20(1): 1+0+19369+1 us, 19 -> 39 MB, 821695 (2796105-1974410) objects, 15 goroutines, 4311/1906/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc21(1): 1+0+20916+1 us, 25 -> 50 MB, 1034257 (3587684-2553427) objects, 15 goroutines, 5406/2051/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc22(1): 1+0+32648+3 us, 32 -> 65 MB, 1354661 (4657737-3303076) objects, 16 goroutines, 7016/2414/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc23(1): 1+11+43754+2 us, 42 -> 85 MB, 1731913 (6006672-4274759) objects, 16 goroutines, 8872/2798/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc24(1): 2+0+60542+1 us, 55 -> 110 MB, 2400177 (8001337-5601160) objects, 16 goroutines, 12167/3428/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc25(1): 1+19+81958+1 us, 72 -> 144 MB, 2891227 (10174688-7283461) objects, 16 goroutines, 14777/3279/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:09:32 Memstats venue-fetch alloc: 117.5M totalalloc: 309.7M sys: 148M pause: 282ms numGc: 8, heapAlloc: 117.5M heapSys: 136.9M heapObjects: 2322323 heapInuse: 121.3M elapsed: 5.613742918s
2015/06/10 15:09:32 Memstats events-fetch alloc: 46.2M totalalloc: 46.2M sys: 31.2M pause: 0ms numGc: 0, heapAlloc: 46.2M heapSys: 28.7M heapObjects: 594117 heapInuse: 43.9M elapsed: 365.35545ms
gc26(1): 1+9+82095+1 us, 93 -> 186 MB, 3384233 (12654941-9270708) objects, 16 goroutines, 19296/5178/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:09:32 Memstats events-time-filter alloc: -23.7M totalalloc: 38.8M sys: 140.6K pause: 82ms numGc: 1, heapAlloc: -23.7M heapSys: 0 heapObjects: -997967 heapInuse: 336K elapsed: 210.918006ms
2015/06/10 15:09:32 Memstats events-grouping alloc: 1.2M totalalloc: 1.2M sys: 1.1M pause: 0ms numGc: 0, heapAlloc: 1.2M heapSys: 1M heapObjects: 8833 heapInuse: 960K elapsed: 1.738112ms
2015/06/10 15:09:32 Memstats events-sorting alloc: 2.6M totalalloc: 2.6M sys: 2.4M pause: 0ms numGc: 0, heapAlloc: 2.6M heapSys: 2M heapObjects: 6559 heapInuse: 2.5M elapsed: 2.919308ms
2015/06/10 15:09:32 Memstats event-denormalizing alloc: 1.4M totalalloc: 1.4M sys: 0 pause: 0ms numGc: 0, heapAlloc: 1.4M heapSys: 0 heapObjects: 56982 heapInuse: 0 elapsed: 9.036521ms
2015/06/10 15:09:32 Memstats venue-geo-filter alloc: 55.8M totalalloc: 55.8M sys: 59.6M pause: 0ms numGc: 0, heapAlloc: 55.8M heapSys: 56M heapObjects: 24 heapInuse: 55.8M elapsed: 58.365442ms
gc27(1): 2+0+98700+2 us, 124 -> 250 MB, 2466077 (12796530-10330453) objects, 16 goroutines, 20714/4/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:09:32 Memstats collect-tags alloc: -66.6M totalalloc: 53.1M sys: 29.4M pause: 98ms numGc: 1, heapAlloc: -66.6M heapSys: 27.7M heapObjects: -267184 heapInuse: -52.7M elapsed: 163.745436ms
2015/06/10 15:09:33 Memstats countBy-tags alloc: 20.1M totalalloc: 21.8M sys: 0 pause: 0ms numGc: 0, heapAlloc: 20.1M heapSys: 0 heapObjects: 391576 heapInuse: 10.2M elapsed: 112.365703ms
2015/06/10 15:09:33 Memstats sort-tags alloc: 71.5K totalalloc: 71.5K sys: 0 pause: 0ms numGc: 0, heapAlloc: 71.5K heapSys: 0 heapObjects: 25 heapInuse: 136K elapsed: 1.216763ms
2015/06/10 15:09:33 Memstats active-ordering-fetch alloc: 8.8K totalalloc: 8.8K sys: 0 pause: 0ms numGc: 0, heapAlloc: 8.8K heapSys: 0 heapObjects: 193 heapInuse: 0 elapsed: 27.546525ms
2015/06/10 15:09:33 Memstats annotations-classes-fetch alloc: 13.8K totalalloc: 13.8K sys: 0 pause: 0ms numGc: 0, heapAlloc: 13.8K heapSys: 0 heapObjects: 244 heapInuse: 0 elapsed: 26.277984ms
2015/06/10 15:09:33 Memstats create-tag-groups alloc: 55.1K totalalloc: 55.1K sys: 0 pause: 0ms numGc: 0, heapAlloc: 55.1K heapSys: 0 heapObjects: 612 heapInuse: 48K elapsed: 219.593µs
2015/06/10 15:09:33 Memstats marshaling-response alloc: 437.4K totalalloc: 437.4K sys: 0 pause: 0ms numGc: 0, heapAlloc: 437.4K heapSys: 0 heapObjects: 8242 heapInuse: 280K elapsed: 2.015538ms
[negroni] Completed 200 OK in 7.639375109s
After
2015/06/10 15:05:39 Memstats param parsing alloc: 704B totalalloc: 704B sys: 0 pause: 0ms numGc: 0, heapAlloc: 704B heapSys: -16K heapObjects: 15 heapInuse: 0 elapsed: 162.713µs
2015/06/10 15:05:39 Memstats s2-covering alloc: 16K totalalloc: 16K sys: 0 pause: 0ms numGc: 0, heapAlloc: 16K heapSys: 0 heapObjects: 141 heapInuse: 16K elapsed: 311.608µs
2015/06/10 15:05:39 Memstats create-venue-event-query alloc: 192B totalalloc: 192B sys: 0 pause: 0ms numGc: 0, heapAlloc: 192B heapSys: 0 heapObjects: 2 heapInuse: 0 elapsed: 16.912µs
gc10(1): 1+0+741+1 us, 1 -> 2 MB, 30328 (54082-23754) objects, 15 goroutines, 216/0/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:05:39 Memstats venueevent-fetch alloc: 1.1M totalalloc: 1.9M sys: 1.1M pause: 0ms numGc: 1, heapAlloc: 1.1M heapSys: 1M heapObjects: 29952 heapInuse: 1.2M elapsed: 178.20705ms
2015/06/10 15:05:39 Memstats gather-venue-event-ids alloc: 0 totalalloc: 0 sys: 0 pause: 0ms numGc: 0, heapAlloc: 0 heapSys: 0 heapObjects: 0 heapInuse: 0 elapsed: 82.011µs
gc11(1): 1+0+1057+1 us, 1 -> 2 MB, 42561 (108512-65951) objects, 15 goroutines, 248/199/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:05:40 Memstats venue-fetch alloc: 1.1M totalalloc: 1.1M sys: 1.1M pause: 0ms numGc: 0, heapAlloc: 1.1M heapSys: 992K heapObjects: 25505 heapInuse: 1.1M elapsed: 368.538162ms
gc12(1): 1+0+920+0 us, 1 -> 3 MB, 55104 (165080-109976) objects, 15 goroutines, 274/201/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc13(1): 2+0+1868+1 us, 1 -> 3 MB, 48094 (215260-167166) objects, 15 goroutines, 305/230/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc14(1): 1+0+1422+0 us, 2 -> 4 MB, 45710 (268382-222672) objects, 15 goroutines, 448/286/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc15(1): 1+0+1786+0 us, 3 -> 6 MB, 65431 (347638-282207) objects, 15 goroutines, 671/83/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc16(1): 1+0+2590+0 us, 4 -> 9 MB, 91795 (461466-369671) objects, 15 goroutines, 1025/173/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc17(1): 2+0+4133+1 us, 7 -> 14 MB, 133558 (633936-500378) objects, 15 goroutines, 1438/251/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc18(1): 2+0+5109+1 us, 9 -> 19 MB, 170404 (839331-668927) objects, 15 goroutines, 1964/0/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc19(1): 1+0+6401+0 us, 13 -> 27 MB, 234401 (1127317-892916) objects, 15 goroutines, 2693/260/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:05:41 Memstats events-fetch alloc: 18.3M totalalloc: 46.2M sys: 25.7M pause: 24ms numGc: 8, heapAlloc: 18.3M heapSys: 23.7M heapObjects: 144050 heapInuse: 19.3M elapsed: 641.490944ms
gc20(1): 2+0+8927+1 us, 18 -> 37 MB, 221286 (1311908-1090622) objects, 15 goroutines, 3836/507/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
gc21(1): 1+0+9730+1 us, 21 -> 43 MB, 160620 (1339393-1178773) objects, 15 goroutines, 3836/1/0 sweeps, 0(0) handoff, 0(0) steal, 0/0/0 yields
2015/06/10 15:05:41 Memstats events-time-filter alloc: 12M totalalloc: 38.8M sys: 20.2M pause: 18ms numGc: 2, heapAlloc: 12M heapSys: 19M heapObjects: -33253 heapInuse: 13.5M elapsed: 133.597327ms
2015/06/10 15:05:41 Memstats events-grouping alloc: -806.8K totalalloc: 1.2M sys: 0 pause: 0ms numGc: 0, heapAlloc: -806.8K heapSys: 0 heapObjects: 780 heapInuse: -208K elapsed: 1.856973ms
2015/06/10 15:05:41 Memstats events-sorting alloc: -2.3M totalalloc: 2.6M sys: 0 pause: 0ms numGc: 0, heapAlloc: -2.3M heapSys: 0 heapObjects: -1432 heapInuse: -640K elapsed: 1.8687ms
2015/06/10 15:05:41 Memstats collect-tags alloc: 2.4M totalalloc: 2.8M sys: 0 pause: 0ms numGc: 0, heapAlloc: 2.4M heapSys: 0 heapObjects: -418 heapInuse: 2.5M elapsed: 1.447009ms
2015/06/10 15:05:41 Memstats countBy-tags alloc: 1.4M totalalloc: 1.4M sys: 0 pause: 0ms numGc: 0, heapAlloc: 1.4M heapSys: 0 heapObjects: 25340 heapInuse: 704K elapsed: 4.717567ms
2015/06/10 15:05:41 Memstats sort-tags alloc: 70.8K totalalloc: 70.8K sys: 0 pause: 0ms numGc: 0, heapAlloc: 70.8K heapSys: 0 heapObjects: 25 heapInuse: 64K elapsed: 177.722µs
2015/06/10 15:05:41 Memstats active-ordering-fetch alloc: 4.9K totalalloc: 4.9K sys: 0 pause: 0ms numGc: 0, heapAlloc: 4.9K heapSys: 0 heapObjects: 98 heapInuse: 8K elapsed: 16.078834ms
2015/06/10 15:05:41 Memstats annotations-classes-fetch alloc: 11.1K totalalloc: 11.1K sys: 0 pause: 0ms numGc: 0, heapAlloc: 11.1K heapSys: 0 heapObjects: 244 heapInuse: 0 elapsed: 28.055479ms
2015/06/10 15:05:41 Memstats create-tag-groups alloc: 54.9K totalalloc: 54.9K sys: 0 pause: 0ms numGc: 0, heapAlloc: 54.9K heapSys: 0 heapObjects: 611 heapInuse: 64K elapsed: 124.684µs
2015/06/10 15:05:41 Memstats marshaling-response alloc: 428.2K totalalloc: 428.2K sys: 0 pause: 0ms numGc: 0, heapAlloc: 428.2K heapSys: 0 heapObjects: 8229 heapInuse: 296K elapsed: 1.789553ms
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment