Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

teamcity: failed tests on master: testrace/TestChangefeedPauseUnpause #26661

Closed
cockroach-teamcity opened this issue Jun 12, 2018 · 1 comment
Closed

teamcity: failed tests on master: testrace/TestChangefeedPauseUnpause #26661

cockroach-teamcity opened this issue Jun 12, 2018 · 1 comment
Assignees
@danhhz
Labels
C-test-failure Broken test (automatically or manually discovered). O-robot Originated from a bot.
Milestone
2.1

Comments

@cockroach-teamcity
Copy link
Member

The following tests appear to have failed:

#714262:

--- FAIL: testrace/TestChangefeedPauseUnpause (0.000s)
Test ended in panic.

------- Stdout: -------
W180612 21:02:46.668023 2718 server/status/runtime.go:109  Could not parse build timestamp: parsing time "" as "2006/01/02 15:04:05": cannot parse "" as "2006"
I180612 21:02:46.721010 2718 server/server.go:785  [n?] monitoring forward clock jumps based on server.clock.forward_jump_check_enabled
I180612 21:02:46.779659 2718 server/config.go:539  [n?] 1 storage engine initialized
I180612 21:02:46.779827 2718 server/config.go:542  [n?] RocksDB cache size: 128 MiB
I180612 21:02:46.779914 2718 server/config.go:542  [n?] store 0: in-memory, size 0 B
I180612 21:02:46.827103 2718 util/stop/stopper.go:471  [n?] quiescing; tasks left:
1      [async] storage.pendingLeaseRequest: requesting lease
I180612 21:02:46.831453 2718 server/node.go:376  [n?] **** cluster 56b913da-5cd5-4176-9598-a58c8f069940 has been created
I180612 21:02:46.831557 2718 server/server.go:1360  [n?] **** add additional nodes by specifying --join=127.0.0.1:37819
I180612 21:02:46.850223 2718 storage/store.go:1454  [n1,s1] [n1,s1]: failed initial metrics computation: [n1,s1]: system config not yet available
I180612 21:02:46.850907 2718 server/node.go:506  [n1] initialized store [n1,s1]: disk (capacity=512 MiB, available=512 MiB, used=0 B, logicalBytes=6.9 KiB), ranges=1, leases=0, writes=0.00, bytesPerReplica={p10=7043.00 p25=7043.00 p50=7043.00 p75=7043.00 p90=7043.00 pMax=7043.00}, writesPerReplica={p10=0.00 p25=0.00 p50=0.00 p75=0.00 p90=0.00 pMax=0.00}
I180612 21:02:46.851159 2718 server/node.go:354  [n1] node ID 1 initialized
I180612 21:02:46.851474 2718 gossip/gossip.go:333  [n1] NodeDescriptor set to node_id:1 address:<network_field:"tcp" address_field:"127.0.0.1:37819" > attrs:<> locality:<> ServerVersion:<major_val:2 minor_val:0 patch:0 unstable:7 > 
I180612 21:02:46.856996 2718 storage/stores.go:222  [n1] read 0 node addresses from persistent storage
I180612 21:02:46.857638 2718 server/node.go:647  [n1] connecting to gossip network to verify cluster ID...
I180612 21:02:46.867434 2718 server/node.go:672  [n1] node connected via gossip and verified as part of cluster "56b913da-5cd5-4176-9598-a58c8f069940"
I180612 21:02:46.867914 2718 server/node.go:440  [n1] node=1: started with [<no-attributes>=<in-mem>] engine(s) and attributes []
I180612 21:02:46.878670 2718 server/server.go:1488  [n1] starting https server at 127.0.0.1:40717
I180612 21:02:46.878790 2718 server/server.go:1489  [n1] starting grpc/postgres server at 127.0.0.1:37819
I180612 21:02:46.878839 2718 server/server.go:1490  [n1] advertising CockroachDB node at 127.0.0.1:37819
W180612 21:02:46.879067 2718 sql/jobs/registry.go:287  [n1] unable to get node liveness: node not in the liveness table
I180612 21:02:46.972402 3028 storage/replica_command.go:863  [split,n1,s1,r1/1:/M{in-ax}] initiating a split of this range at key /System/"" [r2]
I180612 21:02:47.278654 2799 storage/replica_command.go:863  [split,n1,s1,r2/1:/{System/-Max}] initiating a split of this range at key /System/NodeLiveness [r3]
W180612 21:02:47.306512 3033 storage/intent_resolver.go:638  [n1,s1] failed to push during intent resolution: failed to push "sql txn" id=0afd2429 key=/Table/SystemConfigSpan/Start rw=true pri=0.02660039 iso=SERIALIZABLE stat=PENDING epo=0 ts=1528837366.968569153,0 orig=1528837366.968569153,0 max=1528837366.968569153,0 wto=false rop=false seq=6
I180612 21:02:47.376809 3021 sql/event_log.go:126  [n1,intExec=optInToDiagnosticsStatReporting] Event: "set_cluster_setting", target: 0, info: {SettingName:diagnostics.reporting.enabled Value:true User:root}
I180612 21:02:47.399223 2936 storage/replica_consistency.go:129  [replica consistency checker,n1,s1,r1/1:/{Min-System/}] triggering stats recomputation to resolve delta of {ContainsEstimates:true LastUpdateNanos:1528837367028550254 IntentAge:0 GCBytesAge:0 LiveBytes:-20381 LiveCount:-429 KeyBytes:-19907 KeyCount:-429 ValBytes:-474 ValCount:-429 IntentBytes:0 IntentCount:0 SysBytes:0 SysCount:0}
I180612 21:02:47.420219 3066 storage/replica_command.go:863  [split,n1,s1,r3/1:/{System/NodeL…-Max}] initiating a split of this range at key /System/NodeLivenessMax [r4]
I180612 21:02:47.495675 3108 storage/replica_command.go:863  [split,n1,s1,r4/1:/{System/NodeL…-Max}] initiating a split of this range at key /System/tsd [r5]
I180612 21:02:47.636152 3097 storage/replica_command.go:863  [split,n1,s1,r5/1:/{System/tsd-Max}] initiating a split of this range at key /System/"tse" [r6]
I180612 21:02:47.675988 3077 sql/event_log.go:126  [n1,intExec=set-setting] Event: "set_cluster_setting", target: 0, info: {SettingName:version Value:$1 User:root}
I180612 21:02:47.752843 3128 storage/replica_command.go:863  [split,n1,s1,r6/1:/{System/tse-Max}] initiating a split of this range at key /Table/SystemConfigSpan/Start [r7]
I180612 21:02:47.862879 3115 sql/event_log.go:126  [n1,intExec=disableNetTrace] Event: "set_cluster_setting", target: 0, info: {SettingName:trace.debug.enable Value:false User:root}
I180612 21:02:47.880361 3157 storage/replica_command.go:863  [split,n1,s1,r7/1:/{Table/System…-Max}] initiating a split of this range at key /Table/11 [r8]
I180612 21:02:47.998403 3190 storage/replica_command.go:863  [split,n1,s1,r8/1:/{Table/11-Max}] initiating a split of this range at key /Table/12 [r9]
I180612 21:02:48.136636 3180 storage/replica_command.go:863  [split,n1,s1,r9/1:/{Table/12-Max}] initiating a split of this range at key /Table/13 [r10]
I180612 21:02:48.221617 3100 storage/replica_command.go:863  [split,n1,s1,r10/1:/{Table/13-Max}] initiating a split of this range at key /Table/14 [r11]
I180612 21:02:48.251463 3167 sql/event_log.go:126  [n1,intExec=initializeClusterSecret] Event: "set_cluster_setting", target: 0, info: {SettingName:cluster.secret Value:gen_random_uuid()::STRING User:root}
I180612 21:02:48.349062 3104 sql/event_log.go:126  [n1,intExec=create-default-db] Event: "create_database", target: 50, info: {DatabaseName:defaultdb Statement:CREATE DATABASE IF NOT EXISTS defaultdb User:root}
I180612 21:02:48.413614 3237 sql/event_log.go:126  [n1,intExec=create-default-db] Event: "create_database", target: 51, info: {DatabaseName:postgres Statement:CREATE DATABASE IF NOT EXISTS postgres User:root}
I180612 21:02:48.418032 3287 storage/replica_command.go:863  [split,n1,s1,r11/1:/{Table/14-Max}] initiating a split of this range at key /Table/15 [r12]
I180612 21:02:48.468546 2718 server/server.go:1567  [n1] done ensuring all necessary migrations have run
I180612 21:02:48.468675 2718 server/server.go:1570  [n1] serving sql connections
I180612 21:02:48.494839 3088 server/server_update.go:66  [n1] no need to upgrade, cluster already at the newest version
I180612 21:02:48.525418 3314 sql/event_log.go:126  [n1] Event: "node_join", target: 1, info: {Descriptor:{NodeID:1 Address:{NetworkField:tcp AddressField:127.0.0.1:37819} Attrs: Locality: ServerVersion:2.0-7} ClusterID:56b913da-5cd5-4176-9598-a58c8f069940 StartedAt:1528837366867515809 LastUp:1528837366867515809}
I180612 21:02:48.529672 3334 storage/replica_command.go:863  [split,n1,s1,r12/1:/{Table/15-Max}] initiating a split of this range at key /Table/16 [r13]
I180612 21:02:48.598136 3305 storage/replica_command.go:863  [split,n1,s1,r13/1:/{Table/16-Max}] initiating a split of this range at key /Table/17 [r14]
I180612 21:02:48.677970 3278 storage/replica_command.go:863  [split,n1,s1,r14/1:/{Table/17-Max}] initiating a split of this range at key /Table/18 [r15]
W180612 21:02:48.777640 3389 storage/intent_resolver.go:638  [n1,s1] failed to push during intent resolution: failed to push "split" id=9e4ba5da key=/Local/Range/Table/17/RangeDescriptor rw=true pri=0.00779335 iso=SERIALIZABLE stat=PENDING epo=0 ts=1528837368.678203434,0 orig=1528837368.678203434,0 max=1528837368.678203434,0 wto=false rop=false seq=1
I180612 21:02:48.826238 3344 storage/replica_command.go:863  [split,n1,s1,r15/1:/{Table/18-Max}] initiating a split of this range at key /Table/19 [r16]
I180612 21:02:48.949835 3446 storage/replica_command.go:863  [split,n1,s1,r16/1:/{Table/19-Max}] initiating a split of this range at key /Table/20 [r17]
I180612 21:02:49.081292 3431 storage/replica_command.go:863  [split,n1,s1,r17/1:/{Table/20-Max}] initiating a split of this range at key /Table/21 [r18]
I180612 21:02:49.205303 3507 storage/replica_command.go:863  [split,n1,s1,r18/1:/{Table/21-Max}] initiating a split of this range at key /Table/22 [r19]
I180612 21:02:49.342592 3470 storage/replica_command.go:863  [split,n1,s1,r19/1:/{Table/22-Max}] initiating a split of this range at key /Table/23 [r20]
I180612 21:02:49.428595 3326 storage/replica_command.go:863  [split,n1,s1,r20/1:/{Table/23-Max}] initiating a split of this range at key /Table/50 [r21]
I180612 21:02:49.539548 3472 storage/replica_command.go:863  [split,n1,s1,r21/1:/{Table/50-Max}] initiating a split of this range at key /Table/51 [r22]
I180612 21:02:50.083869 3409 sql/event_log.go:126  [n1,client=127.0.0.1:55828,user=root] Event: "set_cluster_setting", target: 0, info: {SettingName:changefeed.experimental_poll_interval Value:0s User:root}
I180612 21:02:50.134438 3409 sql/event_log.go:126  [n1,client=127.0.0.1:55828,user=root] Event: "create_database", target: 52, info: {DatabaseName:d Statement:CREATE DATABASE d User:root}
I180612 21:02:50.138220 3654 storage/replica_command.go:863  [split,n1,s1,r22/1:/{Table/51-Max}] initiating a split of this range at key /Table/52 [r23]
I180612 21:02:50.210550 3409 sql/event_log.go:126  [n1,client=127.0.0.1:55828,user=root] Event: "create_table", target: 53, info: {TableName:d.public.foo Statement:CREATE TABLE foo (a INT PRIMARY KEY, b STRING) User:root}
I180612 21:02:50.233838 3632 storage/replica_command.go:863  [split,n1,s1,r23/1:/{Table/52-Max}] initiating a split of this range at key /Table/53 [r24]
I180612 21:02:55.379200 4388 util/protoutil/randnullability.go:94  inserting null for (roachpb.Lease).Expiration: false
I180612 21:02:55.379464 4388 util/protoutil/randnullability.go:94  inserting null for (roachpb.Lease).DeprecatedStartStasis: true
I180612 21:02:55.382707 2973 storage/replica_proposal.go:202  [n1,s1,r7/1:/Table/{SystemCon…-11}] new range lease repl=(n1,s1):1 seq=3 start=1528837366.834764889,0 epo=1 pro=1528837375.378497407,0 following repl=(n1,s1):1 seq=2 start=1528837366.834764889,0 exp=1528837375.866938381,0 pro=1528837366.866986282,0
I180612 21:02:55.414503 2959 storage/replica_proposal.go:202  [n1,s1,r12/1:/Table/1{5-6}] new range lease repl=(n1,s1):1 seq=3 start=1528837366.834764889,0 epo=1 pro=1528837375.398637492,0 following repl=(n1,s1):1 seq=2 start=1528837366.834764889,0 exp=1528837375.866938381,0 pro=1528837366.866986282,0
I180612 21:02:55.796689 2996 storage/replica_proposal.go:202  [n1,s1,r18/1:/Table/2{1-2}] new range lease repl=(n1,s1):1 seq=3 start=1528837366.834764889,0 epo=1 pro=1528837375.793916501,0 following repl=(n1,s1):1 seq=2 start=1528837366.834764889,0 exp=1528837375.866938381,0 pro=1528837366.866986282,0
I180612 21:02:55.997973 2769 storage/replica_proposal.go:202  [n1,s1,r24/1:/{Table/53-Max}] new range lease repl=(n1,s1):1 seq=3 start=1528837366.834764889,0 epo=1 pro=1528837375.995147543,0 following repl=(n1,s1):1 seq=2 start=1528837366.834764889,0 exp=1528837375.866938381,0 pro=1528837366.866986282,0
I180612 21:02:56.400070 2973 storage/replica_proposal.go:202  [n1,s1,r21/1:/Table/5{0-1}] new range lease repl=(n1,s1):1 seq=3 start=1528837366.834764889,0 epo=1 pro=1528837376.397585108,0 following repl=(n1,s1):1 seq=2 start=1528837366.834764889,0 exp=1528837375.866938381,0 pro=1528837366.866986282,0
I180612 21:02:56.878358 2684 server/status/runtime.go:219  [n1] runtime stats: 424 MiB RSS, 176 goroutines, 18 MiB/8.2 MiB/40 MiB GO alloc/idle/total, 32 MiB/62 MiB CGO alloc/total, 0.00cgo/sec, 0.00/0.00 %(u/s)time, 0.00 %gc (42x)
I180612 21:02:57.433584 2768 storage/replica_proposal.go:202  [n1,s1,r4/1:/System/{NodeLive…-tsd}] new range lease repl=(n1,s1):1 seq=3 start=1528837366.834764889,0 epo=1 pro=1528837377.430847715,0 following repl=(n1,s1):1 seq=2 start=1528837366.834764889,0 exp=1528837375.866938381,0 pro=1528837366.866986282,0
I180612 21:02:57.747049 2950 storage/replica_proposal.go:202  [n1,s1,r5/1:/System/ts{d-e}] new range lease repl=(n1,s1):1 seq=3 start=1528837366.834764889,0 epo=1 pro=1528837377.743847088,0 following repl=(n1,s1):1 seq=2 start=1528837366.834764889,0 exp=1528837375.866938381,0 pro=1528837366.866986282,0
I180612 21:03:06.876009 2684 server/status/runtime.go:219  [n1] runtime stats: 426 MiB RSS, 176 goroutines, 19 MiB/7.7 MiB/40 MiB GO alloc/idle/total, 32 MiB/61 MiB CGO alloc/total, 1187.13cgo/sec, 0.72/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:03:16.883361 2684 server/status/runtime.go:219  [n1] runtime stats: 427 MiB RSS, 175 goroutines, 22 MiB/5.8 MiB/40 MiB GO alloc/idle/total, 32 MiB/61 MiB CGO alloc/total, 879.86cgo/sec, 0.54/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:03:26.899396 2684 server/status/runtime.go:219  [n1] runtime stats: 434 MiB RSS, 176 goroutines, 16 MiB/10 MiB/42 MiB GO alloc/idle/total, 31 MiB/61 MiB CGO alloc/total, 934.70cgo/sec, 0.55/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:03:36.882027 2684 server/status/runtime.go:219  [n1] runtime stats: 438 MiB RSS, 176 goroutines, 15 MiB/11 MiB/42 MiB GO alloc/idle/total, 32 MiB/62 MiB CGO alloc/total, 989.82cgo/sec, 0.58/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:03:46.879451 2684 server/status/runtime.go:219  [n1] runtime stats: 439 MiB RSS, 177 goroutines, 16 MiB/11 MiB/42 MiB GO alloc/idle/total, 32 MiB/63 MiB CGO alloc/total, 1003.26cgo/sec, 0.62/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:03:46.880441 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:03:56.879356 2684 server/status/runtime.go:219  [n1] runtime stats: 440 MiB RSS, 176 goroutines, 22 MiB/7.4 MiB/42 MiB GO alloc/idle/total, 32 MiB/63 MiB CGO alloc/total, 1086.50cgo/sec, 0.63/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:04:06.872478 2684 server/status/runtime.go:219  [n1] runtime stats: 440 MiB RSS, 175 goroutines, 25 MiB/6.3 MiB/42 MiB GO alloc/idle/total, 32 MiB/63 MiB CGO alloc/total, 1028.61cgo/sec, 0.61/0.01 %(u/s)time, 0.00 %gc (6x)
I180612 21:04:16.873879 2684 server/status/runtime.go:219  [n1] runtime stats: 444 MiB RSS, 175 goroutines, 15 MiB/11 MiB/42 MiB GO alloc/idle/total, 32 MiB/63 MiB CGO alloc/total, 1039.76cgo/sec, 0.63/0.01 %(u/s)time, 0.01 %gc (7x)
I180612 21:04:26.895368 2684 server/status/runtime.go:219  [n1] runtime stats: 444 MiB RSS, 176 goroutines, 17 MiB/9.9 MiB/42 MiB GO alloc/idle/total, 32 MiB/63 MiB CGO alloc/total, 1025.60cgo/sec, 0.60/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:04:36.895289 2684 server/status/runtime.go:219  [n1] runtime stats: 445 MiB RSS, 176 goroutines, 15 MiB/11 MiB/42 MiB GO alloc/idle/total, 32 MiB/63 MiB CGO alloc/total, 960.91cgo/sec, 0.55/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:04:46.918616 2684 server/status/runtime.go:219  [n1] runtime stats: 445 MiB RSS, 176 goroutines, 15 MiB/11 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 807.72cgo/sec, 0.49/0.01 %(u/s)time, 0.01 %gc (5x)
I180612 21:04:46.922360 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:04:56.882389 2684 server/status/runtime.go:219  [n1] runtime stats: 445 MiB RSS, 176 goroutines, 20 MiB/8.7 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 895.35cgo/sec, 0.53/0.01 %(u/s)time, 0.01 %gc (5x)
I180612 21:05:06.872811 2684 server/status/runtime.go:219  [n1] runtime stats: 446 MiB RSS, 175 goroutines, 22 MiB/7.5 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 857.22cgo/sec, 0.52/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:05:16.877249 2684 server/status/runtime.go:219  [n1] runtime stats: 446 MiB RSS, 176 goroutines, 23 MiB/7.2 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 839.72cgo/sec, 0.51/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:05:26.882705 2684 server/status/runtime.go:219  [n1] runtime stats: 446 MiB RSS, 175 goroutines, 19 MiB/9.3 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 772.08cgo/sec, 0.47/0.01 %(u/s)time, 0.00 %gc (5x)
I180612 21:05:36.877441 2684 server/status/runtime.go:219  [n1] runtime stats: 447 MiB RSS, 176 goroutines, 16 MiB/10 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 769.60cgo/sec, 0.43/0.02 %(u/s)time, 0.00 %gc (5x)
I180612 21:05:46.895977 2684 server/status/runtime.go:219  [n1] runtime stats: 447 MiB RSS, 176 goroutines, 14 MiB/11 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 821.68cgo/sec, 0.49/0.01 %(u/s)time, 0.01 %gc (5x)
I180612 21:05:46.911184 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:05:56.873346 2684 server/status/runtime.go:219  [n1] runtime stats: 448 MiB RSS, 175 goroutines, 16 MiB/10 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 847.72cgo/sec, 0.49/0.02 %(u/s)time, 0.00 %gc (5x)
I180612 21:06:06.943710 2684 server/status/runtime.go:219  [n1] runtime stats: 448 MiB RSS, 176 goroutines, 22 MiB/7.8 MiB/42 MiB GO alloc/idle/total, 33 MiB/64 MiB CGO alloc/total, 902.15cgo/sec, 0.55/0.02 %(u/s)time, 0.00 %gc (5x)
I180612 21:06:16.883153 2684 server/status/runtime.go:219  [n1] runtime stats: 450 MiB RSS, 175 goroutines, 18 MiB/10 MiB/42 MiB GO alloc/idle/total, 34 MiB/65 MiB CGO alloc/total, 939.49cgo/sec, 0.53/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:06:26.879242 2684 server/status/runtime.go:219  [n1] runtime stats: 451 MiB RSS, 176 goroutines, 16 MiB/10 MiB/42 MiB GO alloc/idle/total, 34 MiB/65 MiB CGO alloc/total, 963.38cgo/sec, 0.54/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:06:36.874513 2684 server/status/runtime.go:219  [n1] runtime stats: 452 MiB RSS, 176 goroutines, 18 MiB/10 MiB/42 MiB GO alloc/idle/total, 33 MiB/65 MiB CGO alloc/total, 863.00cgo/sec, 0.51/0.02 %(u/s)time, 0.00 %gc (5x)
I180612 21:06:46.880602 2684 server/status/runtime.go:219  [n1] runtime stats: 452 MiB RSS, 176 goroutines, 24 MiB/6.1 MiB/42 MiB GO alloc/idle/total, 33 MiB/65 MiB CGO alloc/total, 924.74cgo/sec, 0.55/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:06:46.881137 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:06:56.888929 2684 server/status/runtime.go:219  [n1] runtime stats: 455 MiB RSS, 176 goroutines, 26 MiB/6.3 MiB/43 MiB GO alloc/idle/total, 33 MiB/65 MiB CGO alloc/total, 1027.14cgo/sec, 0.60/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:07:06.874594 2684 server/status/runtime.go:219  [n1] runtime stats: 458 MiB RSS, 175 goroutines, 15 MiB/12 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 1028.17cgo/sec, 0.63/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:07:16.877906 2684 server/status/runtime.go:219  [n1] runtime stats: 459 MiB RSS, 176 goroutines, 16 MiB/12 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 1007.87cgo/sec, 0.58/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:07:26.880118 2684 server/status/runtime.go:219  [n1] runtime stats: 460 MiB RSS, 177 goroutines, 16 MiB/11 MiB/43 MiB GO alloc/idle/total, 38 MiB/68 MiB CGO alloc/total, 987.38cgo/sec, 0.59/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:07:36.876081 2684 server/status/runtime.go:219  [n1] runtime stats: 460 MiB RSS, 176 goroutines, 21 MiB/9.6 MiB/43 MiB GO alloc/idle/total, 38 MiB/68 MiB CGO alloc/total, 1045.32cgo/sec, 0.65/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:07:46.875123 2684 server/status/runtime.go:219  [n1] runtime stats: 459 MiB RSS, 175 goroutines, 22 MiB/8.7 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 998.40cgo/sec, 0.61/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:07:46.881491 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:07:56.893060 2684 server/status/runtime.go:219  [n1] runtime stats: 460 MiB RSS, 177 goroutines, 20 MiB/9.9 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 975.45cgo/sec, 0.59/0.01 %(u/s)time, 0.00 %gc (6x)
I180612 21:08:06.894361 2684 server/status/runtime.go:219  [n1] runtime stats: 460 MiB RSS, 176 goroutines, 20 MiB/10 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 988.77cgo/sec, 0.57/0.01 %(u/s)time, 0.00 %gc (6x)
I180612 21:08:16.877074 2684 server/status/runtime.go:219  [n1] runtime stats: 460 MiB RSS, 175 goroutines, 18 MiB/11 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 981.19cgo/sec, 0.56/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:08:26.891261 2684 server/status/runtime.go:219  [n1] runtime stats: 460 MiB RSS, 176 goroutines, 24 MiB/7.6 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 928.58cgo/sec, 0.55/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:08:36.897327 2684 server/status/runtime.go:219  [n1] runtime stats: 461 MiB RSS, 176 goroutines, 14 MiB/12 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 849.79cgo/sec, 0.52/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:08:46.880302 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:08:46.881685 2684 server/status/runtime.go:219  [n1] runtime stats: 461 MiB RSS, 180 goroutines, 18 MiB/11 MiB/43 MiB GO alloc/idle/total, 38 MiB/68 MiB CGO alloc/total, 867.55cgo/sec, 0.52/0.01 %(u/s)time, 0.01 %gc (5x)
I180612 21:08:56.878674 2684 server/status/runtime.go:219  [n1] runtime stats: 462 MiB RSS, 176 goroutines, 24 MiB/7.6 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 935.09cgo/sec, 0.55/0.01 %(u/s)time, 0.00 %gc (5x)
I180612 21:09:06.875467 2684 server/status/runtime.go:219  [n1] runtime stats: 461 MiB RSS, 175 goroutines, 25 MiB/6.4 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 853.97cgo/sec, 0.51/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:09:16.876608 2684 server/status/runtime.go:219  [n1] runtime stats: 462 MiB RSS, 176 goroutines, 16 MiB/12 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 879.30cgo/sec, 0.52/0.01 %(u/s)time, 0.01 %gc (6x)
I180612 21:09:26.872440 2684 server/status/runtime.go:219  [n1] runtime stats: 463 MiB RSS, 179 goroutines, 14 MiB/12 MiB/43 MiB GO alloc/idle/total, 38 MiB/68 MiB CGO alloc/total, 977.81cgo/sec, 0.57/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:09:36.882593 2684 server/status/runtime.go:219  [n1] runtime stats: 463 MiB RSS, 176 goroutines, 17 MiB/10 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 1023.26cgo/sec, 0.57/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:09:46.879052 2684 server/status/runtime.go:219  [n1] runtime stats: 463 MiB RSS, 176 goroutines, 24 MiB/7.4 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 940.43cgo/sec, 0.54/0.02 %(u/s)time, 0.00 %gc (5x)
I180612 21:09:46.882344 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:09:56.875271 2684 server/status/runtime.go:219  [n1] runtime stats: 464 MiB RSS, 176 goroutines, 16 MiB/11 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 913.85cgo/sec, 0.53/0.01 %(u/s)time, 0.02 %gc (6x)
I180612 21:10:06.874580 2684 server/status/runtime.go:219  [n1] runtime stats: 464 MiB RSS, 176 goroutines, 16 MiB/12 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 1013.27cgo/sec, 0.57/0.01 %(u/s)time, 0.00 %gc (6x)
I180612 21:10:16.872413 2684 server/status/runtime.go:219  [n1] runtime stats: 464 MiB RSS, 175 goroutines, 23 MiB/7.6 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 927.40cgo/sec, 0.53/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:10:26.899342 2684 server/status/runtime.go:219  [n1] runtime stats: 465 MiB RSS, 176 goroutines, 17 MiB/11 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 936.88cgo/sec, 0.55/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:10:36.884139 2684 server/status/runtime.go:219  [n1] runtime stats: 465 MiB RSS, 175 goroutines, 18 MiB/11 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 818.43cgo/sec, 0.49/0.01 %(u/s)time, 0.00 %gc (5x)
I180612 21:10:46.875480 2684 server/status/runtime.go:219  [n1] runtime stats: 466 MiB RSS, 179 goroutines, 24 MiB/7.5 MiB/43 MiB GO alloc/idle/total, 38 MiB/68 MiB CGO alloc/total, 910.48cgo/sec, 0.51/0.01 %(u/s)time, 0.01 %gc (5x)
I180612 21:10:46.880267 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:10:56.873686 2684 server/status/runtime.go:219  [n1] runtime stats: 466 MiB RSS, 177 goroutines, 18 MiB/11 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 922.77cgo/sec, 0.53/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:11:06.885761 2684 server/status/runtime.go:219  [n1] runtime stats: 468 MiB RSS, 176 goroutines, 26 MiB/6.5 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 1099.08cgo/sec, 0.61/0.01 %(u/s)time, 0.01 %gc (6x)
I180612 21:11:16.879981 2684 server/status/runtime.go:219  [n1] runtime stats: 469 MiB RSS, 176 goroutines, 16 MiB/11 MiB/43 MiB GO alloc/idle/total, 38 MiB/68 MiB CGO alloc/total, 1046.64cgo/sec, 0.58/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:11:26.874525 2684 server/status/runtime.go:219  [n1] runtime stats: 470 MiB RSS, 175 goroutines, 16 MiB/12 MiB/43 MiB GO alloc/idle/total, 37 MiB/68 MiB CGO alloc/total, 1033.06cgo/sec, 0.56/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:11:36.873635 2684 server/status/runtime.go:219  [n1] runtime stats: 470 MiB RSS, 176 goroutines, 16 MiB/11 MiB/43 MiB GO alloc/idle/total, 45 MiB/74 MiB CGO alloc/total, 983.78cgo/sec, 0.54/0.01 %(u/s)time, 0.01 %gc (6x)
I180612 21:11:46.874272 2684 server/status/runtime.go:219  [n1] runtime stats: 472 MiB RSS, 177 goroutines, 18 MiB/11 MiB/43 MiB GO alloc/idle/total, 54 MiB/83 MiB CGO alloc/total, 852.45cgo/sec, 0.52/0.01 %(u/s)time, 0.00 %gc (5x)
I180612 21:11:46.880309 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:11:56.872333 2684 server/status/runtime.go:219  [n1] runtime stats: 474 MiB RSS, 175 goroutines, 15 MiB/12 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 945.58cgo/sec, 0.53/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:12:06.872372 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 176 goroutines, 22 MiB/8.7 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 893.19cgo/sec, 0.52/0.02 %(u/s)time, 0.00 %gc (5x)
I180612 21:12:16.887827 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 176 goroutines, 16 MiB/12 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 907.80cgo/sec, 0.52/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:12:26.872402 2684 server/status/runtime.go:219  [n1] runtime stats: 474 MiB RSS, 175 goroutines, 15 MiB/12 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 984.92cgo/sec, 0.56/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:12:36.874614 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 175 goroutines, 20 MiB/9.6 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1063.57cgo/sec, 0.58/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:12:46.873499 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 176 goroutines, 20 MiB/9.7 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 998.21cgo/sec, 0.58/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:12:46.880295 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:12:56.874958 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 176 goroutines, 17 MiB/11 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1119.94cgo/sec, 0.65/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:13:06.887581 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 176 goroutines, 14 MiB/11 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1091.82cgo/sec, 0.65/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:13:16.887958 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 175 goroutines, 21 MiB/9.5 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1083.36cgo/sec, 0.66/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:13:26.873869 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 175 goroutines, 19 MiB/10 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1152.72cgo/sec, 0.69/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:13:36.872455 2684 server/status/runtime.go:219  [n1] runtime stats: 475 MiB RSS, 176 goroutines, 20 MiB/9.6 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1014.04cgo/sec, 0.61/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:13:46.886253 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:13:46.890557 2684 server/status/runtime.go:219  [n1] runtime stats: 476 MiB RSS, 179 goroutines, 23 MiB/7.8 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 879.81cgo/sec, 0.52/0.02 %(u/s)time, 0.01 %gc (5x)
I180612 21:13:56.878824 2684 server/status/runtime.go:219  [n1] runtime stats: 477 MiB RSS, 176 goroutines, 17 MiB/11 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 942.00cgo/sec, 0.54/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:14:06.892597 2684 server/status/runtime.go:219  [n1] runtime stats: 477 MiB RSS, 176 goroutines, 25 MiB/6.7 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1119.03cgo/sec, 0.67/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:14:16.875326 2684 server/status/runtime.go:219  [n1] runtime stats: 478 MiB RSS, 179 goroutines, 22 MiB/8.2 MiB/43 MiB GO alloc/idle/total, 54 MiB/83 MiB CGO alloc/total, 1118.66cgo/sec, 0.68/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:14:26.873114 2684 server/status/runtime.go:219  [n1] runtime stats: 477 MiB RSS, 176 goroutines, 22 MiB/8.6 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1152.25cgo/sec, 0.75/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:14:36.874400 2684 server/status/runtime.go:219  [n1] runtime stats: 478 MiB RSS, 176 goroutines, 16 MiB/11 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1068.76cgo/sec, 0.64/0.01 %(u/s)time, 0.01 %gc (7x)
I180612 21:14:46.872398 2684 server/status/runtime.go:219  [n1] runtime stats: 478 MiB RSS, 175 goroutines, 16 MiB/11 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1173.93cgo/sec, 0.75/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:14:46.880249 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:14:56.872437 2684 server/status/runtime.go:219  [n1] runtime stats: 478 MiB RSS, 176 goroutines, 16 MiB/11 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1183.40cgo/sec, 0.73/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:15:06.874235 2684 server/status/runtime.go:219  [n1] runtime stats: 480 MiB RSS, 175 goroutines, 24 MiB/7.2 MiB/43 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1307.17cgo/sec, 0.81/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:15:16.877380 2684 server/status/runtime.go:219  [n1] runtime stats: 483 MiB RSS, 175 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1226.32cgo/sec, 0.73/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:15:26.874498 2684 server/status/runtime.go:219  [n1] runtime stats: 483 MiB RSS, 176 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1177.83cgo/sec, 0.75/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:15:36.882888 2684 server/status/runtime.go:219  [n1] runtime stats: 484 MiB RSS, 175 goroutines, 24 MiB/8.5 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1124.06cgo/sec, 0.68/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:15:46.872451 2684 server/status/runtime.go:219  [n1] runtime stats: 486 MiB RSS, 176 goroutines, 19 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1283.54cgo/sec, 0.75/0.03 %(u/s)time, 0.00 %gc (8x)
I180612 21:15:46.880273 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:15:56.872403 2684 server/status/runtime.go:219  [n1] runtime stats: 486 MiB RSS, 176 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1134.81cgo/sec, 0.64/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:16:06.879393 2684 server/status/runtime.go:219  [n1] runtime stats: 486 MiB RSS, 177 goroutines, 24 MiB/8.3 MiB/44 MiB GO alloc/idle/total, 54 MiB/83 MiB CGO alloc/total, 1143.40cgo/sec, 0.74/0.02 %(u/s)time, 0.01 %gc (6x)
I180612 21:16:16.873570 2684 server/status/runtime.go:219  [n1] runtime stats: 486 MiB RSS, 175 goroutines, 23 MiB/9.0 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1151.97cgo/sec, 0.67/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:16:26.878457 2684 server/status/runtime.go:219  [n1] runtime stats: 489 MiB RSS, 176 goroutines, 23 MiB/9.2 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1181.33cgo/sec, 0.75/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:16:36.876327 2684 server/status/runtime.go:219  [n1] runtime stats: 489 MiB RSS, 176 goroutines, 22 MiB/10 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1140.64cgo/sec, 0.73/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:16:46.872899 2684 server/status/runtime.go:219  [n1] runtime stats: 489 MiB RSS, 176 goroutines, 15 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1223.22cgo/sec, 0.80/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:16:46.880317 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:16:56.875039 2684 server/status/runtime.go:219  [n1] runtime stats: 488 MiB RSS, 176 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1226.04cgo/sec, 0.73/0.03 %(u/s)time, 0.01 %gc (7x)
I180612 21:17:06.872494 2684 server/status/runtime.go:219  [n1] runtime stats: 488 MiB RSS, 175 goroutines, 15 MiB/13 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1295.93cgo/sec, 0.84/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:17:16.889060 2684 server/status/runtime.go:219  [n1] runtime stats: 489 MiB RSS, 175 goroutines, 18 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1233.96cgo/sec, 0.74/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:17:26.894769 2684 server/status/runtime.go:219  [n1] runtime stats: 489 MiB RSS, 176 goroutines, 25 MiB/7.8 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1122.06cgo/sec, 0.66/0.02 %(u/s)time, 0.00 %gc (6x)
I180612 21:17:36.872377 2684 server/status/runtime.go:219  [n1] runtime stats: 491 MiB RSS, 176 goroutines, 18 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1266.83cgo/sec, 0.77/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:17:46.899325 2684 server/status/runtime.go:219  [n1] runtime stats: 491 MiB RSS, 177 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1301.80cgo/sec, 0.82/0.03 %(u/s)time, 0.00 %gc (8x)
I180612 21:17:46.906288 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:17:56.873027 2684 server/status/runtime.go:219  [n1] runtime stats: 491 MiB RSS, 176 goroutines, 22 MiB/9.5 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1269.74cgo/sec, 0.85/0.03 %(u/s)time, 0.01 %gc (7x)
I180612 21:18:06.873185 2684 server/status/runtime.go:219  [n1] runtime stats: 491 MiB RSS, 175 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1299.38cgo/sec, 0.85/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:18:16.878974 2684 server/status/runtime.go:219  [n1] runtime stats: 490 MiB RSS, 175 goroutines, 25 MiB/7.5 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1255.59cgo/sec, 0.82/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:18:26.874656 2684 server/status/runtime.go:219  [n1] runtime stats: 491 MiB RSS, 175 goroutines, 22 MiB/9.8 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1311.23cgo/sec, 0.84/0.03 %(u/s)time, 0.00 %gc (8x)
I180612 21:18:36.879276 2684 server/status/runtime.go:219  [n1] runtime stats: 491 MiB RSS, 176 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1260.92cgo/sec, 0.81/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:18:46.881255 2684 server/status/runtime.go:219  [n1] runtime stats: 493 MiB RSS, 176 goroutines, 18 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1219.86cgo/sec, 0.72/0.03 %(u/s)time, 0.01 %gc (7x)
I180612 21:18:46.883050 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:18:56.872513 2684 server/status/runtime.go:219  [n1] runtime stats: 493 MiB RSS, 176 goroutines, 22 MiB/9.4 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1269.71cgo/sec, 0.73/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:19:06.876366 2684 server/status/runtime.go:219  [n1] runtime stats: 493 MiB RSS, 175 goroutines, 21 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1176.35cgo/sec, 0.68/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:19:16.872492 2684 server/status/runtime.go:219  [n1] runtime stats: 493 MiB RSS, 175 goroutines, 15 MiB/13 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1243.09cgo/sec, 0.80/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:19:26.883360 2684 server/status/runtime.go:219  [n1] runtime stats: 494 MiB RSS, 176 goroutines, 18 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1209.69cgo/sec, 0.77/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:19:36.875503 2684 server/status/runtime.go:219  [n1] runtime stats: 493 MiB RSS, 175 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1199.34cgo/sec, 0.77/0.03 %(u/s)time, 0.00 %gc (7x)
I180612 21:19:46.872195 2684 server/status/runtime.go:219  [n1] runtime stats: 493 MiB RSS, 176 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1267.31cgo/sec, 0.81/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:19:46.880326 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:19:56.872460 2684 server/status/runtime.go:219  [n1] runtime stats: 494 MiB RSS, 175 goroutines, 22 MiB/9.8 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1260.28cgo/sec, 0.85/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:20:06.886862 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 175 goroutines, 14 MiB/13 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1250.91cgo/sec, 0.78/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:20:16.881348 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 176 goroutines, 18 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1239.38cgo/sec, 0.73/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:20:26.907526 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 176 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 53 MiB/83 MiB CGO alloc/total, 1221.30cgo/sec, 0.77/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:20:36.874704 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 175 goroutines, 24 MiB/8.9 MiB/44 MiB GO alloc/idle/total, 53 MiB/82 MiB CGO alloc/total, 1221.31cgo/sec, 0.76/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:20:46.873631 2684 server/status/runtime.go:219  [n1] runtime stats: 493 MiB RSS, 176 goroutines, 23 MiB/8.9 MiB/44 MiB GO alloc/idle/total, 69 MiB/98 MiB CGO alloc/total, 1171.94cgo/sec, 0.72/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:20:46.880274 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:20:56.872502 2684 server/status/runtime.go:219  [n1] runtime stats: 494 MiB RSS, 175 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 69 MiB/98 MiB CGO alloc/total, 1277.33cgo/sec, 0.83/0.03 %(u/s)time, 0.00 %gc (8x)
I180612 21:21:06.879298 2684 server/status/runtime.go:219  [n1] runtime stats: 495 MiB RSS, 176 goroutines, 23 MiB/8.9 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1232.76cgo/sec, 0.80/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:21:16.881279 2684 server/status/runtime.go:219  [n1] runtime stats: 495 MiB RSS, 176 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1142.17cgo/sec, 0.69/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:21:26.879314 2684 server/status/runtime.go:219  [n1] runtime stats: 495 MiB RSS, 176 goroutines, 25 MiB/7.6 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1237.54cgo/sec, 0.77/0.02 %(u/s)time, 0.01 %gc (7x)
I180612 21:21:36.879129 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 176 goroutines, 21 MiB/10 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1292.43cgo/sec, 0.83/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:21:46.873025 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 175 goroutines, 18 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1307.40cgo/sec, 0.85/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:21:46.880323 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:21:56.872489 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 176 goroutines, 14 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1294.27cgo/sec, 0.84/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:22:06.894605 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 177 goroutines, 23 MiB/8.9 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1289.35cgo/sec, 0.83/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:22:16.874762 2684 server/status/runtime.go:219  [n1] runtime stats: 496 MiB RSS, 175 goroutines, 14 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1216.01cgo/sec, 0.77/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:22:26.874651 2684 server/status/runtime.go:219  [n1] runtime stats: 498 MiB RSS, 175 goroutines, 22 MiB/9.8 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1290.41cgo/sec, 0.87/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:22:36.874203 2684 server/status/runtime.go:219  [n1] runtime stats: 500 MiB RSS, 176 goroutines, 24 MiB/8.8 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1365.17cgo/sec, 0.89/0.03 %(u/s)time, 0.00 %gc (8x)
I180612 21:22:46.872422 2684 server/status/runtime.go:219  [n1] runtime stats: 500 MiB RSS, 176 goroutines, 21 MiB/10 MiB/44 MiB GO alloc/idle/total, 77 MiB/106 MiB CGO alloc/total, 1148.90cgo/sec, 0.66/0.02 %(u/s)time, 0.00 %gc (7x)
I180612 21:22:46.880981 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:22:56.875190 2684 server/status/runtime.go:219  [n1] runtime stats: 500 MiB RSS, 175 goroutines, 15 MiB/13 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1280.44cgo/sec, 0.78/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:23:06.872533 2684 server/status/runtime.go:219  [n1] runtime stats: 500 MiB RSS, 175 goroutines, 21 MiB/10 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1402.47cgo/sec, 0.97/0.02 %(u/s)time, 0.00 %gc (8x)
I180612 21:23:16.875515 2684 server/status/runtime.go:219  [n1] runtime stats: 501 MiB RSS, 176 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1437.57cgo/sec, 0.98/0.02 %(u/s)time, 0.00 %gc (9x)
I180612 21:23:26.883034 2684 server/status/runtime.go:219  [n1] runtime stats: 503 MiB RSS, 178 goroutines, 22 MiB/10 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1412.34cgo/sec, 0.97/0.03 %(u/s)time, 0.00 %gc (8x)
I180612 21:23:36.873109 2684 server/status/runtime.go:219  [n1] runtime stats: 502 MiB RSS, 176 goroutines, 24 MiB/8.2 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1376.06cgo/sec, 0.93/0.03 %(u/s)time, 0.00 %gc (8x)
I180612 21:23:46.872521 2684 server/status/runtime.go:219  [n1] runtime stats: 503 MiB RSS, 175 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1524.99cgo/sec, 1.10/0.04 %(u/s)time, 0.00 %gc (10x)
I180612 21:23:46.880321 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:23:56.872533 2684 server/status/runtime.go:219  [n1] runtime stats: 503 MiB RSS, 176 goroutines, 19 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1490.41cgo/sec, 1.07/0.05 %(u/s)time, 0.00 %gc (9x)
I180612 21:24:06.872397 2684 server/status/runtime.go:219  [n1] runtime stats: 503 MiB RSS, 176 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1458.01cgo/sec, 1.04/0.03 %(u/s)time, 0.00 %gc (9x)
I180612 21:24:16.872516 2684 server/status/runtime.go:219  [n1] runtime stats: 503 MiB RSS, 175 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1504.48cgo/sec, 1.06/0.03 %(u/s)time, 0.00 %gc (9x)
I180612 21:24:26.872426 2684 server/status/runtime.go:219  [n1] runtime stats: 503 MiB RSS, 175 goroutines, 15 MiB/13 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1550.82cgo/sec, 1.10/0.03 %(u/s)time, 0.00 %gc (10x)
I180612 21:24:36.873377 2684 server/status/runtime.go:219  [n1] runtime stats: 502 MiB RSS, 175 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1490.46cgo/sec, 1.03/0.04 %(u/s)time, 0.00 %gc (9x)
I180612 21:24:46.872582 2684 server/status/runtime.go:219  [n1] runtime stats: 503 MiB RSS, 175 goroutines, 18 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1507.12cgo/sec, 1.06/0.04 %(u/s)time, 0.00 %gc (9x)
I180612 21:24:46.880316 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:24:56.872522 2684 server/status/runtime.go:219  [n1] runtime stats: 502 MiB RSS, 175 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1471.41cgo/sec, 1.03/0.03 %(u/s)time, 0.00 %gc (9x)
I180612 21:25:06.872491 2684 server/status/runtime.go:219  [n1] runtime stats: 505 MiB RSS, 176 goroutines, 21 MiB/9.9 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1543.40cgo/sec, 1.06/0.05 %(u/s)time, 0.00 %gc (9x)
I180612 21:25:16.872235 2684 server/status/runtime.go:219  [n1] runtime stats: 505 MiB RSS, 175 goroutines, 16 MiB/13 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1544.73cgo/sec, 1.10/0.04 %(u/s)time, 0.00 %gc (10x)
I180612 21:25:26.872590 2684 server/status/runtime.go:219  [n1] runtime stats: 505 MiB RSS, 176 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1665.65cgo/sec, 1.12/0.06 %(u/s)time, 0.00 %gc (10x)
I180612 21:25:36.872477 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 176 goroutines, 14 MiB/13 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1691.02cgo/sec, 1.09/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:25:46.879605 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 177 goroutines, 23 MiB/9.3 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1740.57cgo/sec, 1.08/0.07 %(u/s)time, 0.00 %gc (10x)
I180612 21:25:46.880448 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:25:56.872163 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1690.82cgo/sec, 1.08/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:26:06.872132 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 19 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1656.31cgo/sec, 1.15/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:26:16.874405 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 21 MiB/10 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1634.26cgo/sec, 1.13/0.06 %(u/s)time, 0.00 %gc (10x)
I180612 21:26:26.872204 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 22 MiB/9.3 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1641.64cgo/sec, 1.10/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:26:36.872457 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 24 MiB/8.6 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1650.47cgo/sec, 1.10/0.06 %(u/s)time, 0.00 %gc (10x)
I180612 21:26:46.872402 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 177 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1671.91cgo/sec, 1.11/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:26:46.880283 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:26:56.872265 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 176 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1668.91cgo/sec, 1.10/0.07 %(u/s)time, 0.00 %gc (10x)
I180612 21:27:06.875101 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 15 MiB/13 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1689.64cgo/sec, 1.11/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:27:16.872115 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1654.57cgo/sec, 1.10/0.07 %(u/s)time, 0.00 %gc (10x)
I180612 21:27:26.872459 2684 server/status/runtime.go:219  [n1] runtime stats: 507 MiB RSS, 175 goroutines, 21 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1665.16cgo/sec, 1.13/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:27:36.872621 2684 server/status/runtime.go:219  [n1] runtime stats: 509 MiB RSS, 177 goroutines, 14 MiB/13 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1680.95cgo/sec, 1.09/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:27:46.872131 2684 server/status/runtime.go:219  [n1] runtime stats: 509 MiB RSS, 179 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 78 MiB/107 MiB CGO alloc/total, 1659.69cgo/sec, 1.10/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:27:46.880358 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:27:56.872167 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 18 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1659.99cgo/sec, 1.10/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:28:06.872578 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 21 MiB/10 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1663.76cgo/sec, 1.09/0.04 %(u/s)time, 0.00 %gc (10x)
I180612 21:28:16.872529 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1672.01cgo/sec, 1.15/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:28:26.873183 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 176 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1666.78cgo/sec, 1.15/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:28:36.872500 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1624.11cgo/sec, 1.09/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:28:46.874264 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 176 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1664.30cgo/sec, 1.09/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:28:46.880251 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:28:56.872461 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 22 MiB/9.3 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1628.10cgo/sec, 1.11/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:29:06.872666 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 176 goroutines, 23 MiB/8.8 MiB/44 MiB GO alloc/idle/total, 78 MiB/107 MiB CGO alloc/total, 1656.77cgo/sec, 1.10/0.04 %(u/s)time, 0.00 %gc (10x)
I180612 21:29:16.872549 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 176 goroutines, 24 MiB/8.5 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1656.92cgo/sec, 1.09/0.06 %(u/s)time, 0.00 %gc (10x)
I180612 21:29:26.872443 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 176 goroutines, 14 MiB/13 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1645.32cgo/sec, 1.09/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:29:36.872495 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 18 MiB/12 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1682.70cgo/sec, 1.14/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:29:46.872382 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 19 MiB/11 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1647.31cgo/sec, 1.10/0.06 %(u/s)time, 0.00 %gc (10x)
I180612 21:29:46.880314 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:29:56.872099 2684 server/status/runtime.go:219  [n1] runtime stats: 511 MiB RSS, 175 goroutines, 24 MiB/8.2 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1664.43cgo/sec, 1.12/0.05 %(u/s)time, 0.00 %gc (10x)
I180612 21:30:06.872185 2684 server/status/runtime.go:219  [n1] runtime stats: 513 MiB RSS, 175 goroutines, 24 MiB/8.7 MiB/44 MiB GO alloc/idle/total, 77 MiB/107 MiB CGO alloc/total, 1647.99cgo/sec, 1.10/0.07 %(u/s)time, 0.00 %gc (10x)
I180612 21:30:16.872550 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 176 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1734.55cgo/sec, 1.08/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:30:26.872162 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 175 goroutines, 19 MiB/11 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1757.06cgo/sec, 1.07/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:30:36.872191 2684 server/status/runtime.go:219  [n1] runtime stats: 516 MiB RSS, 179 goroutines, 21 MiB/9.9 MiB/44 MiB GO alloc/idle/total, 86 MiB/115 MiB CGO alloc/total, 1802.10cgo/sec, 1.08/0.04 %(u/s)time, 0.00 %gc (11x)
I180612 21:30:46.872119 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 175 goroutines, 18 MiB/12 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1825.41cgo/sec, 1.07/0.05 %(u/s)time, 0.00 %gc (12x)
I180612 21:30:46.880265 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:30:56.872099 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 175 goroutines, 15 MiB/13 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1794.40cgo/sec, 1.09/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:31:06.872304 2684 server/status/runtime.go:219  [n1] runtime stats: 516 MiB RSS, 179 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 86 MiB/115 MiB CGO alloc/total, 1792.86cgo/sec, 1.10/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:31:16.872182 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 176 goroutines, 17 MiB/12 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1770.72cgo/sec, 1.06/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:31:26.872616 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 175 goroutines, 19 MiB/11 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1800.74cgo/sec, 1.07/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:31:36.872150 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 175 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1793.06cgo/sec, 1.07/0.06 %(u/s)time, 0.00 %gc (11x)
I180612 21:31:46.872412 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 176 goroutines, 22 MiB/9.9 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1792.47cgo/sec, 1.07/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:31:46.880371 2681 gossip/gossip.go:488  [n1] gossip status (ok, 1 node)
gossip client (0/3 cur/max conns)
gossip server (0/3 cur/max conns, infos 0/0 sent/received, bytes 0B/0B sent/received)
I180612 21:31:56.872189 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 176 goroutines, 24 MiB/8.5 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1796.03cgo/sec, 1.09/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:32:06.872133 2684 server/status/runtime.go:219  [n1] runtime stats: 516 MiB RSS, 179 goroutines, 16 MiB/12 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1824.10cgo/sec, 1.08/0.05 %(u/s)time, 0.00 %gc (12x)
I180612 21:32:16.872086 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 175 goroutines, 20 MiB/11 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO alloc/total, 1843.21cgo/sec, 1.07/0.05 %(u/s)time, 0.00 %gc (11x)
I180612 21:32:26.872197 2684 server/status/runtime.go:219  [n1] runtime stats: 515 MiB RSS, 176 goroutines, 25 MiB/7.8 MiB/44 MiB GO alloc/idle/total, 85 MiB/115 MiB CGO a

Please assign, take a look and update the issue accordingly.
@cockroach-teamcity cockroach-teamcity added this to the 2.1 milestone Jun 12, 2018
@cockroach-teamcity cockroach-teamcity added C-test-failure Broken test (automatically or manually discovered). O-robot Originated from a bot. labels Jun 12, 2018
@danhhz
Copy link
Contributor

danhhz commented Jun 12, 2018

Test is timing out, nothing jumps out at me so skipping for now #26663

This was referenced Jun 12, 2018
Closed
Closed
Closed
danhhz added a commit to danhhz/cockroach that referenced this issue Jun 14, 2018
@danhhz
changefeedccl: support inline results over pgwire
5c2e370 
    CREATE CHANGEFEED FOR <target> WITH <...>

Notably the `INTO <sink>` clause is omitted. This triggers a fairly
radical change in behavior. Instead of setting up a system.job to emit
to a sink in the background and returning immediately with the job ID,
the `CREATE CHANGEFEED` blocks forever and returns all changes as rows
directly over pgwire. The types of these rows are `(topic STRING, key
BYTES, value BYTES)` and they correspond exactly to what would be
emitted to a sink.

Because everything is funneled through the sql gateway and the client
connection dies if it dies, there's no reason to run this as a system
job. So we don't. This means the client is responsible for keeping track
of the latest resolved timestamp emitted and, on errors, reconnecting
with the `cursor=<timestamp>` option.

This also "happens" to be exactly the interface we need for most of the
unit tests, so switch them over to it. This allows the removal of the
timing based aspects of the tests, which should reduce some the flake
problems these tests have been having.

NB: The system still internally buffers results, so changes are not
returned immediately. Which means this is currently quite awkward and
surprising to use as a client of CockroachDB. The fix initially seems
like a decent amount of work so it'll wait for a followup PR. (The tests
work around it with a hack to set `ConnResultsBufferBytes` to a very
small value in the test servers.)

Closes cockroachdb#26661
Closes cockroachdb#26682

Release note: None
@craig craig bot closed this as completed in 923ee38 Jun 18, 2018
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@danhhz danhhz

Labels
C-test-failure Broken test (automatically or manually discovered). O-robot Originated from a bot.
Projects
None yet
Milestone
2.1
Development

No branches or pull requests
2 participants
@danhhz @cockroach-teamcity