C:\dev\ETSGO\etsgo>c:\Gotip\go\bin\go version
go version devel +6632631 Fri May 27 21:27:35 2016 -0400 windows/amd64

C:\dev\ETSGO\etsgo>c:\Gotip\go\bin\go run main.go
gc 1 @0.141s 0%: 0+0.50+0.50 ms clock, 0+0/0/0.50+2.0 ms cpu, 4->4->0 MB, 5 MB goal, 4 P
gc 2 @0.175s 0%: 0+0+0 ms clock, 0+0/0/0+0 ms cpu, 4->4->0 MB, 5 MB goal, 4 P
gc 3 @0.217s 0%: 0+0+0 ms clock, 0+0/0/0+0 ms cpu, 4->4->0 MB, 5 MB goal, 4 P
gc 4 @0.262s 0%: 1.0+1.0+0 ms clock, 3.0+1.0/1.0/0+0 ms cpu, 4->4->1 MB, 5 MB goal, 4 P
# text/tabwriter
gc 1 @0.028s 9%: 1.0+6.0+4.0 ms clock, 2.0+0/5.0/0+8.0 ms cpu, 4->4->2 MB, 5 MB goal, 4 P
gc 2 @0.048s 18%: 0+7.0+6.0 ms clock, 0+3.0/4.0/0+24 ms cpu, 4->5->3 MB, 5 MB goal, 4 P
gc 3 @0.075s 24%: 0+13+11 ms clock, 0+5.0/3.0/1.0+44 ms cpu, 5->6->3 MB, 6 MB goal, 4 P
# database/sql/driver
gc 1 @0.029s 9%: 0+13+4.0 ms clock, 0+4.0/1.0/3.0+12 ms cpu, 4->4->2 MB, 5 MB goal, 4 P
gc 2 @0.053s 11%: 0+4.0+1.5 ms clock, 0+1.0/3.0/4.0+6.0 ms cpu, 4->4->3 MB, 5 MB goal, 4 P
gc 3 @0.075s 11%: 0+14+1.0 ms clock, 0+1.0/10/6.0+4.0 ms cpu, 6->7->5 MB, 7 MB goal, 4 P
gc 4 @0.099s 15%: 4.0+13+2.0 ms clock, 16+0/5.0/6.0+8.0 ms cpu, 7->8->5 MB, 8 MB goal, 4 P
# net/url
gc 1 @0.039s 4%: 0+4.0+1.0 ms clock, 0+1.0/4.0/3.0+3.0 ms cpu, 4->4->2 MB, 5 MB goal, 4 P
gc 2 @0.056s 9%: 0+12+2.5 ms clock, 0+5.0/4.5/0+10 ms cpu, 4->6->4 MB, 5 MB goal, 4 P
gc 3 @0.075s 11%: 0+11+1.0 ms clock, 0+9.0/1.0/1.0+4.0 ms cpu, 5->5->4 MB, 7 MB goal, 4 P
gc 4 @0.105s 12%: 0+9.0+2.0 ms clock, 0+7.0/2.0/8.0+8.0 ms cpu, 9->9->5 MB, 10 MB goal, 4 P
# runtime/pprof
gc 1 @0.042s 10%: 0+14+5.5 ms clock, 0+1.0/4.5/1.0+22 ms cpu, 4->4->3 MB, 5 MB goal, 4 P
gc 2 @0.074s 11%: 0+7.0+1.5 ms clock, 0+1.0/4.5/5.5+6.0 ms cpu, 5->5->4 MB, 6 MB goal, 4 P
gc 3 @0.104s 17%: 0+23+11 ms clock, 0+11/2.0/1.0+44 ms cpu, 7->8->6 MB, 8 MB goal, 4 P
gc 4 @0.160s 18%: 0+25+3.5 ms clock, 0+12/15/0.50+14 ms cpu, 10->11->8 MB, 11 MB goal, 4 P
gc 5 @0.231s 18%: 0+15+10 ms clock, 0+0/12/9.5+40 ms cpu, 15->15->10 MB, 16 MB goal, 4 P
# database/sql
gc 1 @0.043s 4%: 0+8.5+1.5 ms clock, 0+3.0/3.5/0.50+3.0 ms cpu, 4->4->3 MB, 5 MB goal, 4 P
gc 2 @0.065s 8%: 0+4.5+2.0 ms clock, 0+3.5/3.0/2.5+8.0 ms cpu, 5->5->3 MB, 6 MB goal, 4 P
gc 3 @0.094s 22%: 0+24+24 ms clock, 0+2.5/6.0/6.5+96 ms cpu, 7->8->6 MB, 8 MB goal, 4 P
gc 4 @0.174s 18%: 0+20+1.0 ms clock, 0+3.5/7.0/7.5+4.0 ms cpu, 11->12->9 MB, 12 MB goal, 4 P
gc 5 @0.221s 21%: 0+19+14 ms clock, 0+8.0/7.5/8.0+58 ms cpu, 16->16->11 MB, 17 MB goal, 4 P
gc 6 @0.298s 18%: 0+11+1.0 ms clock, 0+0/10/18+4.0 ms cpu, 22->22->14 MB, 23 MB goal, 4 P
gc 5 @0.902s 0%: 0+1.0+0 ms clock, 0+0/0.50/1.5+0 ms cpu, 4->4->1 MB, 5 MB goal, 4 P
gc 6 @1.972s 0%: 0+1.0+1.1 ms clock, 0+0/1.0/3.0+4.7 ms cpu, 4->4->1 MB, 5 MB goal, 4 P
# github.com/mattn/go-sqlite3
gc 1 @0.170s 1%: 1.0+0.94+1.0 ms clock, 3.1+0/0.94/0.94+3.0 ms cpu, 4->4->0 MB, 5 MB goal, 4 P
gc 2 @0.441s 0%: 0+0.49+0.50 ms clock, 0+0.49/0.49/0.99+2.0 ms cpu, 4->4->0 MB, 5 MB goal, 4 P
gc 3 @0.677s 0%: 0+2.0+0 ms clock, 0+0/0.97/2.9+0 ms cpu, 4->4->0 MB, 5 MB goal, 4 P
gc 7 @3.523s 0%: 0+1.0+0 ms clock, 0+0/1.0/2.0+0 ms cpu, 4->4->1 MB, 5 MB goal, 4 P
# github.com/mattn/go-sqlite3
gc 1 @0.014s 13%: 2.0+2.0+1.0 ms clock, 4.0+2.0/2.0/2.0+2.0 ms cpu, 4->4->2 MB, 5 MB goal, 4 P
gc 2 @0.025s 14%: 0+4.0+1.0 ms clock, 0+0/3.0/6.0+4.0 ms cpu, 4->4->3 MB, 5 MB goal, 4 P
gc 3 @0.039s 15%: 0+5.0+0.50 ms clock, 0+3.5/5.0/6.5+2.0 ms cpu, 6->7->5 MB, 7 MB goal, 4 P
gc 4 @0.061s 15%: 0+6.0+1.0 ms clock, 0+4.0/6.0/8.0+4.0 ms cpu, 10->10->7 MB, 11 MB goal, 4 P
gc 5 @0.093s 12%: 0+8.0+1.0 ms clock, 0+0/7.0/15+4.0 ms cpu, 14->14->9 MB, 15 MB goal, 4 P
gc 6 @0.130s 12%: 0+9.0+1.0 ms clock, 0+8.0/8.0/12+4.0 ms cpu, 19->19->13 MB, 20 MB goal, 4 P
gc 7 @0.182s 13%: 0+11+2.0 ms clock, 0+10/11/16+8.0 ms cpu, 25->25->17 MB, 26 MB goal, 4 P
gc 8 @0.242s 13%: 0+16+1.0 ms clock, 0+14/16/30+4.0 ms cpu, 34->34->24 MB, 35 MB goal, 4 P
# command-line-arguments
gc 1 @0.016s 7%: 1.0+2.0+1.0 ms clock, 2.0+0/2.0/4.0+2.0 ms cpu, 4->4->2 MB, 5 MB goal, 4 P
gc 2 @0.026s 10%: 0+3.0+1.0 ms clock, 0+0/3.0/6.0+4.0 ms cpu, 4->4->4 MB, 5 MB goal, 4 P
gc 3 @0.042s 11%: 0+4.0+1.0 ms clock, 0+0/4.0/10+4.0 ms cpu, 7->7->6 MB, 8 MB goal, 4 P
# command-line-arguments
gc 1 @0.025s 5%: 1.0+0.94+1.0 ms clock, 2.1+0.94/0.94/1.8+2.0 ms cpu, 4->4->2 MB, 5 MB goal, 4 P
gc 2 @0.035s 8%: 0+4.0+0.50 ms clock, 0+3.0/2.0/4.0+2.0 ms cpu, 5->5->4 MB, 6 MB goal, 4 P
gc 3 @0.054s 9%: 0+4.0+1.0 ms clock, 0+3.0/3.0/7.0+4.0 ms cpu, 8->8->6 MB, 9 MB goal, 4 P
gc 4 @0.080s 11%: 0+7.0+1.0 ms clock, 0+5.0/7.0/10+4.2 ms cpu, 12->13->10 MB, 13 MB goal, 4 P
gc 5 @0.119s 12%: 0+12+1.0 ms clock, 0+8.0/12/16+4.2 ms cpu, 20->21->18 MB, 21 MB goal, 4 P
gc 6 @0.202s 10%: 0+24+2.0 ms clock, 0+12/14/36+8.0 ms cpu, 34->35->29 MB, 37 MB goal, 4 P
gc 7 @0.331s 11%: 0+36+6.0 ms clock, 0+23/33/48+24 ms cpu, 53->53->41 MB, 56 MB goal, 4 P
gc 1 @0.043s 2%: 1.0+1.0+1.0 ms clock, 2.0+1.0/0/1.0+2.0 ms cpu, 4->4->2 MB, 5 MB goal, 4 P
gc 2 @0.083s 3%: 0+3.0+0.99 ms clock, 0+2.0/1.0/3.0+3.9 ms cpu, 5->6->4 MB, 6 MB goal, 4 P
gc 3 @0.115s 3%: 0+2.0+1.0 ms clock, 0+0/2.0/0+4.0 ms cpu, 5->5->3 MB, 6 MB goal, 4 P
gc 4 @0.146s 4%: 0+3.0+1.0 ms clock, 0+0/3.0/2.0+4.0 ms cpu, 4->4->3 MB, 6 MB goal, 4 P
gc 5 @0.174s 5%: 0+8.0+1.0 ms clock, 0+7.0/3.0/5.0+4.0 ms cpu, 7->9->9 MB, 8 MB goal, 4 P
gc 6 @0.223s 5%: 0+3.0+0.50 ms clock, 0+1.5/3.0/1.0+2.0 ms cpu, 10->10->5 MB, 12 MB goal, 4 P
gc 7 @0.279s 4%: 0+7.0+1.0 ms clock, 0+0/2.0/6.0+4.0 ms cpu, 8->8->6 MB, 11 MB goal, 4 P
gc 8 @0.337s 5%: 0+12+1.0 ms clock, 0+12/4.0/9.0+4.0 ms cpu, 14->14->12 MB, 15 MB goal, 4 P
gc 9 @0.351s 6%: 0+10+2.0 ms clock, 0+4.0/10/4.0+8.0 ms cpu, 18->18->18 MB, 25 MB goal, 4 P
gc 10 @0.578s 4%: 0+7.0+1.0 ms clock, 0+0/6.0/3.0+4.1 ms cpu, 28->28->13 MB, 37 MB goal, 4 P
gc 11 @0.630s 5%: 0+22+1.0 ms clock, 0+22/8.0/13+4.0 ms cpu, 27->27->25 MB, 28 MB goal, 4 P
gc 12 @0.798s 4%: 0+19+1.0 ms clock, 0+0/13/5.9+4.0 ms cpu, 41->41->23 MB, 51 MB goal, 4 P
gc 13 @1.204s 3%: 0+22+0.50 ms clock, 0+0/14/15+2.0 ms cpu, 42->42->28 MB, 47 MB goal, 4 P
gc 14 @1.249s 4%: 0+52+1.0 ms clock, 0+22/50/2.0+4.0 ms cpu, 74->74->74 MB, 75 MB goal, 4 P
gc 15 @2.024s 3%: 0+18+0.50 ms clock, 0+13/18/0+2.0 ms cpu, 104->105->51 MB, 147 MB goal, 4 P
gc 16 @2.333s 3%: 0+54+0.50 ms clock, 0+54/19/35+2.0 ms cpu, 113->113->102 MB, 114 MB goal, 4 P
gc 17 @2.393s 4%: 0+111+0.57 ms clock, 0+47/64/48+2.3 ms cpu, 148->149->148 MB, 204 MB goal, 4 P
gc 18 @4.325s 3%: 0+51+0.55 ms clock, 0+32/51/1.4+2.2 ms cpu, 236->237->110 MB, 294 MB goal, 4 P
gc 19 @4.472s 4%: 0+139+1.0 ms clock, 0+139/49/91+4.2 ms cpu, 208->208->204 MB, 220 MB goal, 4 P
Ready. 
Loaded 1002662 serials
gc 20 @5.471s 5%: 0+0+80 ms clock, 0+139/49/91+322 ms cpu, 326->326->187 MB, 326 MB goal, 4 P (forced)
GC took 83.5405ms

===== TEST CLIENT STARTED AT THIS POINT =====

gc 21 @28.678s 1%: 1.0+108+0 ms clock, 4.0+0/53/54+0 ms cpu, 344->344->187 MB, 374 MB goal, 4 P
exit status 2