Skip to content

Instantly share code, notes, and snippets.

@benesch
Created May 18, 2017 15:53
Show Gist options
  • Select an option

  • Save benesch/dd3f9c41aeaaed7e465ebb60a47bf6b6 to your computer and use it in GitHub Desktop.

Select an option

Save benesch/dd3f9c41aeaaed7e465ebb60a47bf6b6 to your computer and use it in GitHub Desktop.
W170518 11:25:57.176329 61232 server/status/runtime.go:111 Could not parse build timestamp: parsing time "" as "2006/01/02 15:04:05": cannot parse "" as "2006"
I170518 11:25:57.177280 61232 server/config.go:436 1 storage engine initialized
I170518 11:25:57.177483 61232 server/node.go:454 [n?] store [n0,s0] not bootstrapped
I170518 11:25:57.179298 61232 server/node.go:389 [n?] **** cluster 0e6c56b2-a561-40b0-97ba-6eedceee3e37 has been created
I170518 11:25:57.179309 61232 server/node.go:390 [n?] **** add additional nodes by specifying --join=127.0.0.1:59883
I170518 11:25:57.179678 61232 storage/store.go:1252 [n1] [n1,s1]: failed initial metrics computation: [n1,s1]: system config not yet available
I170518 11:25:57.179697 61232 server/node.go:467 [n1] initialized store [n1,s1]: {Capacity:536870912 Available:536870912 RangeCount:1 LeaseCount:0}
I170518 11:25:57.179717 61232 server/node.go:351 [n1] node ID 1 initialized
I170518 11:25:57.179752 61232 gossip/gossip.go:297 [n1] NodeDescriptor set to node_id:1 address:<network_field:"tcp" address_field:"127.0.0.1:59883" > attrs:<> locality:<>
I170518 11:25:57.179889 61232 storage/stores.go:296 [n1] read 0 node addresses from persistent storage
I170518 11:25:57.179928 61232 server/node.go:608 [n1] connecting to gossip network to verify cluster ID...
I170518 11:25:57.179945 61232 server/node.go:633 [n1] node connected via gossip and verified as part of cluster "0e6c56b2-a561-40b0-97ba-6eedceee3e37"
I170518 11:25:57.179965 61232 server/node.go:405 [n1] node=1: started with [[]=] engine(s) and attributes []
I170518 11:25:57.180014 61232 sql/executor.go:349 [n1] creating distSQLPlanner with address {tcp 127.0.0.1:59883}
I170518 11:25:57.181113 61214 storage/split_queue.go:92 [split,n1,s1,r1/1:/M{in-ax}] splitting at key /System/""
I170518 11:25:57.182065 61214 storage/replica_command.go:2633 [split,n1,s1,r1/1:/M{in-ax}] initiating a split of this range at key /System/"" [r2]
I170518 11:25:57.183348 61232 server/server.go:736 [n1] starting https server at 127.0.0.1:59884
I170518 11:25:57.183366 61232 server/server.go:737 [n1] starting grpc/postgres server at 127.0.0.1:59883
I170518 11:25:57.183379 61232 server/server.go:738 [n1] advertising CockroachDB node at 127.0.0.1:59883
I170518 11:25:57.195391 61214 storage/split_queue.go:92 [split,n1,s1,r2/1:/{System/-Max}] splitting at key /System/tsd
I170518 11:25:57.195449 61214 storage/replica_command.go:2633 [split,n1,s1,r2/1:/{System/-Max}] initiating a split of this range at key /System/tsd [r3]
E170518 11:25:57.199144 61560 storage/replica_proposal.go:458 [n1,s1,r2/1:/{System/-Max}] could not load SystemConfig span: must retry later due to intent on SystemConfigSpan
I170518 11:25:57.199506 61214 storage/split_queue.go:92 [split,n1,s1,r3/1:/{System/tsd-Max}] splitting at key /System/"tse"
I170518 11:25:57.199538 61214 storage/replica_command.go:2633 [split,n1,s1,r3/1:/{System/tsd-Max}] initiating a split of this range at key /System/"tse" [r4]
I170518 11:25:57.200301 61232 sql/event_log.go:101 [n1] Event: "alter_table", target: 12, info: {TableName:eventlog Statement:ALTER TABLE system.eventlog ALTER COLUMN uniqueid SET DEFAULT uuid_v4() User:node MutationID:0 CascadeDroppedViews:[]}
I170518 11:25:57.206378 61214 storage/split_queue.go:92 [split,n1,s1,r4/1:/{System/tse-Max}] splitting at key /Table/0/0
I170518 11:25:57.206416 61214 storage/replica_command.go:2633 [split,n1,s1,r4/1:/{System/tse-Max}] initiating a split of this range at key /Table/0 [r5]
I170518 11:25:57.206984 61232 sql/lease.go:372 [n1] publish: descID=12 (eventlog) version=2 mtime=2017-05-18 11:25:57.2069688 -0400 EDT
I170518 11:25:57.210456 61214 storage/split_queue.go:92 [split,n1,s1,r5/1:/{Table/0-Max}] splitting at key /Table/11/0
I170518 11:25:57.210489 61214 storage/replica_command.go:2633 [split,n1,s1,r5/1:/{Table/0-Max}] initiating a split of this range at key /Table/11 [r6]
I170518 11:25:57.214689 61214 storage/split_queue.go:92 [split,n1,s1,r6/1:/{Table/11-Max}] splitting at key /Table/12/0
I170518 11:25:57.214724 61214 storage/replica_command.go:2633 [split,n1,s1,r6/1:/{Table/11-Max}] initiating a split of this range at key /Table/12 [r7]
I170518 11:25:57.218519 61232 server/server.go:848 [n1] done ensuring all necessary migrations have run
I170518 11:25:57.218538 61232 server/server.go:850 [n1] serving sql connections
I170518 11:25:57.218902 61214 storage/split_queue.go:92 [split,n1,s1,r7/1:/{Table/12-Max}] splitting at key /Table/13/0
I170518 11:25:57.218933 61214 storage/replica_command.go:2633 [split,n1,s1,r7/1:/{Table/12-Max}] initiating a split of this range at key /Table/13 [r8]
I170518 11:25:57.220900 61647 sql/event_log.go:101 [n1] Event: "node_join", target: 1, info: {Descriptor:{NodeID:1 Address:{NetworkField:tcp AddressField:127.0.0.1:59883} Attrs: Locality:} ClusterID:0e6c56b2-a561-40b0-97ba-6eedceee3e37 StartedAt:1495121157179948884 LastUp:1495121157179948884}
I170518 11:25:57.225316 61214 storage/split_queue.go:92 [split,n1,s1,r8/1:/{Table/13-Max}] splitting at key /Table/14/0
I170518 11:25:57.225354 61214 storage/replica_command.go:2633 [split,n1,s1,r8/1:/{Table/13-Max}] initiating a split of this range at key /Table/14 [r9]
I170518 11:25:57.232251 61214 storage/split_queue.go:92 [split,n1,s1,r9/1:/{Table/14-Max}] splitting at key /Table/15/0
I170518 11:25:57.232291 61214 storage/replica_command.go:2633 [split,n1,s1,r9/1:/{Table/14-Max}] initiating a split of this range at key /Table/15 [r10]
I170518 11:25:57.250880 61785 sql/event_log.go:101 [client=127.0.0.1:59888,user=root,n1] Event: "create_database", target: 50, info: {DatabaseName:bench Statement:CREATE DATABASE bench User:root}
I170518 11:25:57.250955 61214 storage/split_queue.go:92 [split,n1,s1,r10/1:/{Table/15-Max}] splitting at key /Table/50/0
I170518 11:25:57.250993 61214 storage/replica_command.go:2633 [split,n1,s1,r10/1:/{Table/15-Max}] initiating a split of this range at key /Table/50 [r11]
I170518 11:25:57.254686 61785 sql/event_log.go:101 [client=127.0.0.1:59888,user=root,n1] Event: "create_table", target: 51, info: {TableName:bench.bank Statement:CREATE TABLE bench.bank (id INT PRIMARY KEY, balance INT, payload STRING, FAMILY (id, balance, payload)) User:root}
I170518 11:25:57.255393 61214 storage/split_queue.go:92 [split,n1,s1,r11/1:/{Table/50-Max}] splitting at key /Table/51/0
I170518 11:25:57.255424 61214 storage/replica_command.go:2633 [split,n1,s1,r11/1:/{Table/50-Max}] initiating a split of this range at key /Table/51 [r12]
I170518 11:25:57.283468 62149 ccl/storageccl/export.go:87 [n1,s1,r5/1:/Table/{0-11}] export [/Table/4/1,/Table/4/2)
I170518 11:25:57.283485 62089 ccl/storageccl/export.go:87 [n1,s1,r5/1:/Table/{0-11}] export [/Table/3/1,/Table/3/2)
I170518 11:25:57.283685 62196 ccl/storageccl/export.go:87 [n1,s1,r12/1:/{Table/51-Max}] export [/Table/51/1,/Table/51/2)
I170518 11:25:57.306880 61686 vendor/google.golang.org/grpc/transport/http2_server.go:392 transport: http2Server.HandleStreams failed to read frame: read tcp 127.0.0.1:59883->127.0.0.1:59886: use of closed network connection
--- FAIL: TestBackupRestorePermissions (5.17s)
leaktest.go:90: Leaked goroutine: goroutine 61442 [select]:
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc.(*addrConn).transportMonitor(0xc4268441a0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/clientconn.go:908 +0x694
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc.(*ClientConn).resetAddrConn.func1(0xc4268441a0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/clientconn.go:637 +0x1d9
created by github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc.(*ClientConn).resetAddrConn
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/clientconn.go:638 +0x786
leaktest.go:90: Leaked goroutine: goroutine 61443 [select]:
github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go.(*Recorder).reportLoop(0xc427174380, 0xc4285781e0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go/recorder.go:702 +0x270
created by github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go.NewRecorder
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go/recorder.go:378 +0xc99
leaktest.go:90: Leaked goroutine: goroutine 61463 [select]:
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc.(*addrConn).transportMonitor(0xc426845a00)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/clientconn.go:908 +0x694
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc.(*ClientConn).resetAddrConn.func1(0xc426845a00)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/clientconn.go:637 +0x1d9
created by github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc.(*ClientConn).resetAddrConn
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/clientconn.go:638 +0x786
leaktest.go:90: Leaked goroutine: goroutine 61464 [select]:
github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go.(*Recorder).reportLoop(0xc427174a80, 0xc421996fc0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go/recorder.go:702 +0x270
created by github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go.NewRecorder
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/github.com/lightstep/lightstep-tracer-go/recorder.go:378 +0xc99
leaktest.go:90: Leaked goroutine: goroutine 61965 [IO wait]:
net.runtime_pollWait(0x9c6f678, 0x72, 0x52)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/runtime/netpoll.go:164 +0x59
net.(*pollDesc).wait(0xc42298a228, 0x72, 0x5f4ec40, 0x5f43df0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/fd_poll_runtime.go:75 +0x38
net.(*pollDesc).waitRead(0xc42298a228, 0xc42634a000, 0x2000)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/fd_poll_runtime.go:80 +0x34
net.(*netFD).Read(0xc42298a1c0, 0xc42634a000, 0x2000, 0x2000, 0x0, 0x5f4ec40, 0x5f43df0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/fd_unix.go:250 +0x1b7
net.(*conn).Read(0xc423fb41d0, 0xc42634a000, 0x2000, 0x2000, 0x0, 0x0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/net.go:181 +0x70
crypto/tls.(*block).readFromUntil(0xc425d67380, 0x99203d8, 0xc423fb41d0, 0x5, 0xc423fb41d0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/crypto/tls/conn.go:488 +0x98
crypto/tls.(*Conn).readRecord(0xc42363a700, 0x5610e17, 0xc42363a820, 0xc4277fa7c0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/crypto/tls/conn.go:590 +0xc4
crypto/tls.(*Conn).Read(0xc42363a700, 0xc428236000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/crypto/tls/conn.go:1134 +0x11d
bufio.(*Reader).Read(0xc422e83380, 0xc423090f18, 0x9, 0x9, 0x0, 0x0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/bufio/bufio.go:213 +0x312
io.ReadAtLeast(0x5f45a80, 0xc422e83380, 0xc423090f18, 0x9, 0x9, 0x9, 0x6, 0xc42001c068, 0xc42001c000)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/io/io.go:307 +0xa9
io.ReadFull(0x5f45a80, 0xc422e83380, 0xc423090f18, 0x9, 0x9, 0x0, 0x0, 0xc4252e9e30)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/io/io.go:325 +0x58
github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2.readFrameHeader(0xc423090f18, 0x9, 0x9, 0x5f45a80, 0xc422e83380, 0x0, 0xc400000000, 0xc4252e9e75, 0xc422bb8270)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2/frame.go:237 +0x7b
github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2.(*Framer).ReadFrame(0xc423090ee0, 0x5f51180, 0xc422bb8270, 0x0, 0x0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2/frame.go:492 +0xa4
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.(*framer).readFrame(0xc4211d88d0, 0xc422bb8270, 0xc422bb8270, 0x0, 0x0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http_util.go:608 +0x2f
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.(*http2Client).reader(0xc427e6d680)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:1095 +0xcd
created by github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.newHTTP2Client
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:267 +0xce8
leaktest.go:90: Leaked goroutine: goroutine 61966 [select]:
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.(*http2Client).controller(0xc427e6d680)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:1168 +0x65a
created by github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.newHTTP2Client
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:297 +0xe2d
leaktest.go:90: Leaked goroutine: goroutine 62130 [IO wait]:
net.runtime_pollWait(0x9c6fa38, 0x72, 0x4a)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/runtime/netpoll.go:164 +0x59
net.(*pollDesc).wait(0xc4210b2148, 0x72, 0x5f4ec40, 0x5f43df0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/fd_poll_runtime.go:75 +0x38
net.(*pollDesc).waitRead(0xc4210b2148, 0xc4251c8000, 0x2000)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/fd_poll_runtime.go:80 +0x34
net.(*netFD).Read(0xc4210b20e0, 0xc4251c8000, 0x2000, 0x2000, 0x0, 0x5f4ec40, 0x5f43df0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/fd_unix.go:250 +0x1b7
net.(*conn).Read(0xc42029c1f0, 0xc4251c8000, 0x2000, 0x2000, 0x0, 0x0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/net/net.go:181 +0x70
crypto/tls.(*block).readFromUntil(0xc42253b950, 0x99203d8, 0xc42029c1f0, 0x5, 0xc42029c1f0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/crypto/tls/conn.go:488 +0x98
crypto/tls.(*Conn).readRecord(0xc424fe1c00, 0x5610e17, 0xc424fe1d20, 0xc4252e7c68)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/crypto/tls/conn.go:590 +0xc4
crypto/tls.(*Conn).Read(0xc424fe1c00, 0xc42a14a000, 0x8000, 0x8000, 0x0, 0x0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/crypto/tls/conn.go:1134 +0x11d
bufio.(*Reader).Read(0xc425cdbe60, 0xc42948a118, 0x9, 0x9, 0x0, 0x0, 0x0)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/bufio/bufio.go:213 +0x312
io.ReadAtLeast(0x5f45a80, 0xc425cdbe60, 0xc42948a118, 0x9, 0x9, 0x9, 0x6, 0xc42001d368, 0xc42001d300)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/io/io.go:307 +0xa9
io.ReadFull(0x5f45a80, 0xc425cdbe60, 0xc42948a118, 0x9, 0x9, 0x0, 0x0, 0xc4252e7e30)
/usr/local/Cellar/go/HEAD-a4c18f0/libexec/src/io/io.go:325 +0x58
github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2.readFrameHeader(0xc42948a118, 0x9, 0x9, 0x5f45a80, 0xc425cdbe60, 0x0, 0xc400000000, 0xc4252e7e75, 0xc423397500)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2/frame.go:237 +0x7b
github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2.(*Framer).ReadFrame(0xc42948a0e0, 0x5f51180, 0xc423397500, 0x0, 0x0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/golang.org/x/net/http2/frame.go:492 +0xa4
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.(*framer).readFrame(0xc423862de0, 0xc423397500, 0xc423397500, 0x0, 0x0)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http_util.go:608 +0x2f
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.(*http2Client).reader(0xc424c00300)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:1095 +0xcd
created by github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.newHTTP2Client
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:267 +0xce8
leaktest.go:90: Leaked goroutine: goroutine 62131 [select]:
github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.(*http2Client).controller(0xc424c00300)
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:1168 +0x65a
created by github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport.newHTTP2Client
/Users/benesch/go/src/github.com/cockroachdb/cockroach/vendor/google.golang.org/grpc/transport/http2_client.go:297 +0xe2d
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment