Created
May 18, 2017 15:53
-
-
Save benesch/dd3f9c41aeaaed7e465ebb60a47bf6b6 to your computer and use it in GitHub Desktop.
This file contains hidden or bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| 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