{"action":"created","issue":{"url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27677","repository_url":"https://api.github.com/repos/cockroachdb/cockroach","labels_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27677/labels{/name}","comments_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27677/comments","events_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27677/events","html_url":"https://github.com/cockroachdb/cockroach/issues/27677","id":342186586,"node_id":"MDU6SXNzdWUzNDIxODY1ODY=","number":27677,"title":"github.com/cockroachdb/cockroach/pkg/ccl/importccl: TestImportCSVStmt/schema-in-file-explicit-gzip failed under stress","user":{"login":"cockroach-teamcity","id":20559710,"node_id":"MDQ6VXNlcjIwNTU5NzEw","avatar_url":"https://avatars0.githubusercontent.com/u/20559710?v=4","gravatar_id":"","url":"https://api.github.com/users/cockroach-teamcity","html_url":"https://github.com/cockroach-teamcity","followers_url":"https://api.github.com/users/cockroach-teamcity/followers","following_url":"https://api.github.com/users/cockroach-teamcity/following{/other_user}","gists_url":"https://api.github.com/users/cockroach-teamcity/gists{/gist_id}","starred_url":"https://api.github.com/users/cockroach-teamcity/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/cockroach-teamcity/subscriptions","organizations_url":"https://api.github.com/users/cockroach-teamcity/orgs","repos_url":"https://api.github.com/users/cockroach-teamcity/repos","events_url":"https://api.github.com/users/cockroach-teamcity/events{/privacy}","received_events_url":"https://api.github.com/users/cockroach-teamcity/received_events","type":"User","site_admin":false},"labels":[{"id":191587409,"node_id":"MDU6TGFiZWwxOTE1ODc0MDk=","url":"https://api.github.com/repos/cockroachdb/cockroach/labels/C-test-failure","name":"C-test-failure","color":"c5def5","default":false},{"id":335217860,"node_id":"MDU6TGFiZWwzMzUyMTc4NjA=","url":"https://api.github.com/repos/cockroachdb/cockroach/labels/O-robot","name":"O-robot","color":"666ad1","default":false}],"state":"open","locked":false,"assignee":{"login":"dt","id":15615,"node_id":"MDQ6VXNlcjE1NjE1","avatar_url":"https://avatars2.githubusercontent.com/u/15615?v=4","gravatar_id":"","url":"https://api.github.com/users/dt","html_url":"https://github.com/dt","followers_url":"https://api.github.com/users/dt/followers","following_url":"https://api.github.com/users/dt/following{/other_user}","gists_url":"https://api.github.com/users/dt/gists{/gist_id}","starred_url":"https://api.github.com/users/dt/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/dt/subscriptions","organizations_url":"https://api.github.com/users/dt/orgs","repos_url":"https://api.github.com/users/dt/repos","events_url":"https://api.github.com/users/dt/events{/privacy}","received_events_url":"https://api.github.com/users/dt/received_events","type":"User","site_admin":false},"assignees":[{"login":"dt","id":15615,"node_id":"MDQ6VXNlcjE1NjE1","avatar_url":"https://avatars2.githubusercontent.com/u/15615?v=4","gravatar_id":"","url":"https://api.github.com/users/dt","html_url":"https://github.com/dt","followers_url":"https://api.github.com/users/dt/followers","following_url":"https://api.github.com/users/dt/following{/other_user}","gists_url":"https://api.github.com/users/dt/gists{/gist_id}","starred_url":"https://api.github.com/users/dt/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/dt/subscriptions","organizations_url":"https://api.github.com/users/dt/orgs","repos_url":"https://api.github.com/users/dt/repos","events_url":"https://api.github.com/users/dt/events{/privacy}","received_events_url":"https://api.github.com/users/dt/received_events","type":"User","site_admin":false}],"milestone":{"url":"https://api.github.com/repos/cockroachdb/cockroach/milestones/13","html_url":"https://github.com/cockroachdb/cockroach/milestone/13","labels_url":"https://api.github.com/repos/cockroachdb/cockroach/milestones/13/labels","id":2745639,"node_id":"MDk6TWlsZXN0b25lMjc0NTYzOQ==","number":13,"title":"2.1","description":"","creator":{"login":"knz","id":642886,"node_id":"MDQ6VXNlcjY0Mjg4Ng==","avatar_url":"https://avatars3.githubusercontent.com/u/642886?v=4","gravatar_id":"","url":"https://api.github.com/users/knz","html_url":"https://github.com/knz","followers_url":"https://api.github.com/users/knz/followers","following_url":"https://api.github.com/users/knz/following{/other_user}","gists_url":"https://api.github.com/users/knz/gists{/gist_id}","starred_url":"https://api.github.com/users/knz/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/knz/subscriptions","organizations_url":"https://api.github.com/users/knz/orgs","repos_url":"https://api.github.com/users/knz/repos","events_url":"https://api.github.com/users/knz/events{/privacy}","received_events_url":"https://api.github.com/users/knz/received_events","type":"User","site_admin":false},"open_issues":591,"closed_issues":949,"state":"open","created_at":"2017-09-05T15:30:49Z","updated_at":"2018-07-19T06:42:09Z","due_on":null,"closed_at":null},"comments":2,"created_at":"2018-07-18T05:55:38Z","updated_at":"2018-07-19T06:46:29Z","closed_at":null,"author_association":"MEMBER","body":"SHA: https://github.com/cockroachdb/cockroach/commits/eed443af32474fd76d3b860b44ebfa339b249703\n\nParameters:\n```\nTAGS=\nGOFLAGS=-race\n```\n\nFailed test: https://teamcity.cockroachdb.com/viewLog.html?buildId=783812&tab=buildLog\n\n```\n=== RUN   TestImportCSVStmt/schema-in-file-explicit-gzip\nI180718 05:31:13.105780 6220 sql/event_log.go:126  [n1,client=127.0.0.1:54394,user=root] Event: \"create_database\", target: 72, info: {DatabaseName:csv10 Statement:CREATE DATABASE csv10 User:root}\nI180718 05:31:13.168574 31473 storage/replica_command.go:279  [split,n3,s3,r297/2:/Table/7{0-1/1/741}] initiating a split of this range at key /Table/71 [r78]\nI180718 05:31:13.279487 31524 storage/replica_range_lease.go:554  [replicate,n1,s1,r151/1:/Table/59/2/{NULL/4…-\"D\"/40…}] transferring lease to s3\nI180718 05:31:13.346374 31524 storage/replica_range_lease.go:617  [replicate,n1,s1,r151/1:/Table/59/2/{NULL/4…-\"D\"/40…}] done transferring lease to s3: <nil>\nI180718 05:31:13.417295 5537 storage/replica_proposal.go:222  [n3,s3,r151/2:/Table/59/2/{NULL/4…-\"D\"/40…}] new range lease repl=(n3,s3):2 seq=4 start=1531891873.280095043,0 epo=1 pro=1531891873.280140316,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:14.067470 4724 server/status/runtime.go:255  [n1] runtime stats: 1.6 GiB RSS, 608 goroutines, 80 MiB/22 MiB/128 MiB GO alloc/idle/total, 284 MiB/327 MiB CGO alloc/total, 2444.08cgo/sec, 0.86/0.05 %(u/s)time, 0.04 %gc (5x)\nI180718 05:31:14.281448 5604 storage/replica_proposal.go:222  [n3,s3,r297/2:/Table/7{0-1/1/741}] new range lease repl=(n3,s3):2 seq=4 start=1531891852.828414871,0 epo=1 pro=1531891852.828431262,0 following repl=(n3,s3):2 seq=4 start=1531891852.828414871,0 epo=1 pro=1531891852.828431262,0\nI180718 05:31:15.834455 4533 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n2 established\nI180718 05:31:15.836177 4534 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n3 established\nI180718 05:31:15.855857 31757 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:31:15.902418 31742 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:31:17.586935 5245 server/status/runtime.go:255  [n2] runtime stats: 1.6 GiB RSS, 651 goroutines, 63 MiB/38 MiB/128 MiB GO alloc/idle/total, 288 MiB/328 MiB CGO alloc/total, 1592.39cgo/sec, 0.86/0.05 %(u/s)time, 0.03 %gc (5x)\nI180718 05:31:18.576657 5496 server/status/runtime.go:255  [n3] runtime stats: 1.6 GiB RSS, 650 goroutines, 83 MiB/21 MiB/128 MiB GO alloc/idle/total, 288 MiB/328 MiB CGO alloc/total, 1418.99cgo/sec, 0.84/0.05 %(u/s)time, 0.03 %gc (4x)\nI180718 05:31:21.571199 4535 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n2 established\nI180718 05:31:21.573135 4536 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n3 established\nI180718 05:31:21.578811 32197 rpc/nodedialer/nodedialer.go:90  [n3] connection to n2 established\nI180718 05:31:21.583956 32198 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:31:21.624667 32170 rpc/nodedialer/nodedialer.go:90  [n2] connection to n3 established\nI180718 05:31:21.626188 32171 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:31:21.657030 32258 rpc/nodedialer/nodedialer.go:90  [import-distsql,n1] connection to n2 established\nI180718 05:31:21.660578 32259 rpc/nodedialer/nodedialer.go:90  [import-distsql,n1] connection to n3 established\nI180718 05:31:24.055391 4724 server/status/runtime.go:255  [n1] runtime stats: 1.7 GiB RSS, 686 goroutines, 72 MiB/35 MiB/135 MiB GO alloc/idle/total, 290 MiB/330 MiB CGO alloc/total, 892.48cgo/sec, 0.88/0.05 %(u/s)time, 0.04 %gc (5x)\nI180718 05:31:24.056396 4497 gossip/gossip.go:518  [n1] gossip status (ok, 3 nodes)\ngossip client (0/3 cur/max conns)\ngossip server (2/3 cur/max conns, infos 3320/795 sent/received, bytes 1276337B/307637B sent/received)\n  3: 127.0.0.1:40869 (5m56s)\n  2: 127.0.0.1:45835 (5m57s)\nI180718 05:31:26.511718 32589 storage/replica_range_lease.go:554  [replicate,n1,s1,r228/1:/Table/63/3{-/95/\"R\"…}] transferring lease to s2\nI180718 05:31:26.528130 5266 storage/replica_proposal.go:222  [n2,s2,r228/3:/Table/63/3{-/95/\"R\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891886.511954447,0 epo=1 pro=1531891886.511987044,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:26.574466 32589 storage/replica_range_lease.go:617  [replicate,n1,s1,r228/1:/Table/63/3{-/95/\"R\"…}] done transferring lease to s2: <nil>\nI180718 05:31:27.554234 5242 gossip/gossip.go:518  [n2] gossip status (ok, 3 nodes)\ngossip client (1/3 cur/max conns)\n  1: 127.0.0.1:40367 (6m0s: infos 506/1317 sent/received, bytes 210889B/392336B sent/received)\ngossip server (0/3 cur/max conns, infos 506/1317 sent/received, bytes 210889B/392336B sent/received)\nI180718 05:31:27.586991 5245 server/status/runtime.go:255  [n2] runtime stats: 1.7 GiB RSS, 641 goroutines, 57 MiB/48 MiB/135 MiB GO alloc/idle/total, 287 MiB/330 MiB CGO alloc/total, 683.30cgo/sec, 0.88/0.04 %(u/s)time, 0.04 %gc (5x)\nI180718 05:31:28.136117 32738 storage/replica_command.go:279  [n1,s1,r333/1:/{Table/72-Max}] initiating a split of this range at key /Table/73/2/\"E\"/655 [r334]\nI180718 05:31:28.202166 32727 storage/replica_command.go:279  [n1,s1,r333/1:/{Table/72-Max}] initiating a split of this range at key /Table/73/3/1579/\"T\"/PrefixEnd [r335]\nI180718 05:31:28.250169 32741 storage/replica_command.go:279  [n1,s1,r333/1:/{Table/72-Max}] initiating a split of this range at key /Table/73/1/741 [r336]\nI180718 05:31:28.508575 5493 gossip/gossip.go:518  [n3] gossip status (ok, 3 nodes)\ngossip client (1/3 cur/max conns)\n  1: 127.0.0.1:40367 (6m0s: infos 294/2054 sent/received, bytes 97917B/891147B sent/received)\ngossip server (0/3 cur/max conns, infos 294/2054 sent/received, bytes 97917B/891147B sent/received)\nI180718 05:31:28.516440 32620 storage/replica_range_lease.go:554  [replicate,n1,s1,r32/1:/Table/53/{1/4783-2/\"A\"/1…}] transferring lease to s2\nI180718 05:31:28.547891 5496 server/status/runtime.go:255  [n3] runtime stats: 1.7 GiB RSS, 628 goroutines, 79 MiB/27 MiB/135 MiB GO alloc/idle/total, 286 MiB/330 MiB CGO alloc/total, 1465.01cgo/sec, 0.88/0.04 %(u/s)time, 0.04 %gc (5x)\nI180718 05:31:28.615842 32620 storage/replica_range_lease.go:617  [replicate,n1,s1,r32/1:/Table/53/{1/4783-2/\"A\"/1…}] done transferring lease to s2: <nil>\nI180718 05:31:28.677040 5265 storage/replica_proposal.go:222  [n2,s2,r32/3:/Table/53/{1/4783-2/\"A\"/1…}] new range lease repl=(n2,s2):3 seq=4 start=1531891888.549627402,0 epo=1 pro=1531891888.549672226,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:29.090882 4686 storage/replica_proposal.go:222  [n1,s1,r333/1:/{Table/72-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:29.456269 32741 storage/replica_command.go:279  [n1,s1,r333/1:/Table/7{2-3/2/\"E\"/…}] initiating a split of this range at key /Table/73/1/741 [r337]\nI180718 05:31:29.685025 32727 storage/replica_command.go:279  [n1,s1,r334/1:/{Table/73/2/\"…-Max}] initiating a split of this range at key /Table/73/3/1579/\"T\"/PrefixEnd [r338]\nI180718 05:31:29.691904 32799 storage/replica_command.go:279  [n1,s1,r334/1:/{Table/73/2/\"…-Max}] initiating a split of this range at key /Table/73/2/\"H\"/3024 [r339]\nI180718 05:31:30.364778 4676 storage/replica_proposal.go:222  [n1,s1,r333/1:/Table/7{2-3/2/\"E\"/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:30.675746 4697 storage/replica_proposal.go:222  [n1,s1,r334/1:/{Table/73/2/\"…-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:30.833197 32899 storage/replica_command.go:279  [n1,s1,r337/1:/Table/73/{1/741-2/\"E\"/6…}] initiating a split of this range at key /Table/73/1/1456 [r340]\nI180718 05:31:31.065617 32799 storage/replica_command.go:279  [n1,s1,r334/1:/Table/73/{2/\"E\"/6…-3/1579/…}] initiating a split of this range at key /Table/73/2/\"H\"/3024 [r341]\nI180718 05:31:31.171900 32727 storage/replica_command.go:279  [n1,s1,r338/1:/{Table/73/3/1…-Max}] initiating a split of this range at key /Table/73/3/2067/\"N\" [r342]\nI180718 05:31:31.732060 4537 rpc/nodedialer/nodedialer.go:90  [intExec=adopt-job] connection to n3 established\nI180718 05:31:31.737362 32926 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:31:31.860116 4655 storage/replica_proposal.go:222  [n1,s1,r337/1:/Table/73/{1/741-2/\"E\"/6…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:32.206312 32997 storage/replica_command.go:279  [n1,s1,r340/1:/Table/73/{1/1456-2/\"E\"/6…}] initiating a split of this range at key /Table/73/1/2171 [r343]\nI180718 05:31:32.562561 4681 storage/replica_proposal.go:222  [n1,s1,r334/1:/Table/73/{2/\"E\"/6…-3/1579/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:32.711842 4694 storage/replica_proposal.go:222  [n1,s1,r338/1:/{Table/73/3/1…-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:32.925782 32969 storage/replica_command.go:279  [n1,s1,r341/1:/Table/73/{2/\"H\"/3…-3/1579/…}] initiating a split of this range at key /Table/73/2/\"L\"/480 [r344]\nI180718 05:31:33.637099 4674 storage/replica_proposal.go:222  [n1,s1,r340/1:/Table/73/{1/1456-2/\"E\"/6…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:34.056289 4724 server/status/runtime.go:255  [n1] runtime stats: 1.7 GiB RSS, 623 goroutines, 63 MiB/42 MiB/135 MiB GO alloc/idle/total, 286 MiB/329 MiB CGO alloc/total, 2718.86cgo/sec, 0.91/0.07 %(u/s)time, 0.04 %gc (5x)\nI180718 05:31:34.184311 33082 storage/replica_command.go:279  [n1,s1,r343/1:/Table/73/{1/2171-2/\"E\"/6…}] initiating a split of this range at key /Table/73/1/2886 [r345]\nI180718 05:31:34.230805 4692 storage/replica_proposal.go:222  [n1,s1,r341/1:/Table/73/{2/\"H\"/3…-3/1579/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:34.666364 33139 storage/replica_command.go:279  [n1,s1,r344/1:/Table/73/{2/\"L\"/4…-3/1579/…}] initiating a split of this range at key /Table/73/2/\"O\"/2927 [r346]\nI180718 05:31:34.927022 4707 storage/replica_proposal.go:222  [n1,s1,r343/1:/Table/73/{1/2171-2/\"E\"/6…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:35.067353 5172 rpc/nodedialer/nodedialer.go:90  [intExec=adopt-job] connection to n3 established\nI180718 05:31:35.088336 33211 rpc/nodedialer/nodedialer.go:90  [n3] connection to n2 established\nI180718 05:31:35.109785 33121 storage/replica_range_lease.go:554  [n1,s1,r343/1:/Table/73/1/2{171-886}] transferring lease to s2\nI180718 05:31:35.288887 33121 storage/replica_range_lease.go:617  [n1,s1,r343/1:/Table/73/1/2{171-886}] done transferring lease to s2: <nil>\nI180718 05:31:35.303577 5144 storage/replica_proposal.go:222  [n2,s2,r343/3:/Table/73/1/2{171-886}] new range lease repl=(n2,s2):3 seq=4 start=1531891895.168502810,0 epo=1 pro=1531891895.168551704,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:35.475235 33129 storage/replica_command.go:279  [n1,s1,r345/1:/Table/73/{1/2886-2/\"E\"/6…}] initiating a split of this range at key /Table/73/1/3601 [r347]\nI180718 05:31:35.613835 4712 storage/replica_proposal.go:222  [n1,s1,r344/1:/Table/73/{2/\"L\"/4…-3/1579/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:35.629845 33226 storage/replica_range_lease.go:554  [n1,s1,r344/1:/Table/73/2/\"{L\"/480-O\"/2927}] transferring lease to s2\nI180718 05:31:35.690980 33226 storage/replica_range_lease.go:617  [n1,s1,r344/1:/Table/73/2/\"{L\"/480-O\"/2927}] done transferring lease to s2: <nil>\nI180718 05:31:35.794778 5303 storage/replica_proposal.go:222  [n2,s2,r344/3:/Table/73/2/\"{L\"/480-O\"/2927}] new range lease repl=(n2,s2):3 seq=4 start=1531891895.630825202,0 epo=1 pro=1531891895.630844519,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:35.984771 33269 storage/replica_command.go:279  [n1,s1,r346/1:/Table/73/{2/\"O\"/2…-3/1579/…}] initiating a split of this range at key /Table/73/2/\"S\"/409 [r348]\nI180718 05:31:35.992586 4664 storage/replica_proposal.go:222  [n1,s1,r345/1:/Table/73/{1/2886-2/\"E\"/6…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:36.065537 33301 storage/replica_range_lease.go:554  [n1,s1,r345/1:/Table/73/1/{2886-3601}] transferring lease to s3\nI180718 05:31:36.196493 5614 storage/replica_proposal.go:222  [n3,s3,r345/2:/Table/73/1/{2886-3601}] new range lease repl=(n3,s3):2 seq=4 start=1531891896.065690924,0 epo=1 pro=1531891896.065728585,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:36.218993 33301 storage/replica_range_lease.go:617  [n1,s1,r345/1:/Table/73/1/{2886-3601}] done transferring lease to s3: <nil>\nI180718 05:31:36.473816 4660 storage/replica_proposal.go:222  [n1,s1,r346/1:/Table/73/{2/\"O\"/2…-3/1579/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:36.713010 33264 storage/replica_range_lease.go:554  [n1,s1,r346/1:/Table/73/2/\"{O\"/2927-S\"/409}] transferring lease to s3\nI180718 05:31:36.743313 33264 storage/replica_range_lease.go:617  [n1,s1,r346/1:/Table/73/2/\"{O\"/2927-S\"/409}] done transferring lease to s3: <nil>\nI180718 05:31:36.762981 5638 storage/replica_proposal.go:222  [n3,s3,r346/2:/Table/73/2/\"{O\"/2927-S\"/409}] new range lease repl=(n3,s3):2 seq=4 start=1531891896.713282999,0 epo=1 pro=1531891896.713321590,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:36.781045 33319 storage/replica_command.go:279  [n1,s1,r347/1:/Table/73/{1/3601-2/\"E\"/6…}] initiating a split of this range at key /Table/73/1/4316 [r349]\nI180718 05:31:37.348491 33355 storage/replica_command.go:279  [n1,s1,r348/1:/Table/73/{2/\"S\"/4…-3/1579/…}] initiating a split of this range at key /Table/73/2/\"V\"/2856 [r350]\nI180718 05:31:37.573330 5245 server/status/runtime.go:255  [n2] runtime stats: 1.7 GiB RSS, 625 goroutines, 64 MiB/40 MiB/135 MiB GO alloc/idle/total, 287 MiB/329 MiB CGO alloc/total, 4150.77cgo/sec, 0.98/0.08 %(u/s)time, 0.03 %gc (5x)\nI180718 05:31:37.757491 4652 storage/replica_proposal.go:222  [n1,s1,r347/1:/Table/73/{1/3601-2/\"E\"/6…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:37.902230 33398 storage/replica_range_lease.go:554  [replicate,n1,s1,r347/1:/Table/73/1/{3601-4316}] transferring lease to s3\nI180718 05:31:37.984975 33398 storage/replica_range_lease.go:617  [replicate,n1,s1,r347/1:/Table/73/1/{3601-4316}] done transferring lease to s3: <nil>\nI180718 05:31:38.013711 5606 storage/replica_proposal.go:222  [n3,s3,r347/2:/Table/73/1/{3601-4316}] new range lease repl=(n3,s3):2 seq=4 start=1531891897.916244981,0 epo=1 pro=1531891897.916286178,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:38.171747 4700 storage/replica_proposal.go:222  [n1,s1,r348/1:/Table/73/{2/\"S\"/4…-3/1579/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:38.274796 33432 storage/replica_range_lease.go:554  [n1,s1,r348/1:/Table/73/2/\"{S\"/409-V\"/2856}] transferring lease to s2\nI180718 05:31:38.442460 33432 storage/replica_range_lease.go:617  [n1,s1,r348/1:/Table/73/2/\"{S\"/409-V\"/2856}] done transferring lease to s2: <nil>\nI180718 05:31:38.465158 33375 storage/replica_command.go:279  [n1,s1,r349/1:/Table/73/{1/4316-2/\"E\"/6…}] initiating a split of this range at key /Table/73/2/\"A\"/755 [r351]\nI180718 05:31:38.532899 5200 storage/replica_proposal.go:222  [n2,s2,r348/3:/Table/73/2/\"{S\"/409-V\"/2856}] new range lease repl=(n2,s2):3 seq=4 start=1531891898.274962923,0 epo=1 pro=1531891898.274993915,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:38.828538 5496 server/status/runtime.go:255  [n3] runtime stats: 1.7 GiB RSS, 619 goroutines, 98 MiB/12 MiB/135 MiB GO alloc/idle/total, 287 MiB/329 MiB CGO alloc/total, 3427.21cgo/sec, 1.00/0.08 %(u/s)time, 0.04 %gc (6x)\nI180718 05:31:39.068123 33506 storage/replica_command.go:279  [n1,s1,r350/1:/Table/73/{2/\"V\"/2…-3/1579/…}] initiating a split of this range at key /Table/73/2/\"Z\"/338 [r352]\nI180718 05:31:39.256139 4652 storage/replica_proposal.go:222  [n1,s1,r349/1:/Table/73/{1/4316-2/\"E\"/6…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:39.399202 33463 storage/replica_range_lease.go:554  [n1,s1,r349/1:/Table/73/{1/4316-2/\"A\"/7…}] transferring lease to s2\nI180718 05:31:39.545342 33463 storage/replica_range_lease.go:617  [n1,s1,r349/1:/Table/73/{1/4316-2/\"A\"/7…}] done transferring lease to s2: <nil>\nI180718 05:31:39.555908 5328 storage/replica_proposal.go:222  [n2,s2,r349/3:/Table/73/{1/4316-2/\"A\"/7…}] new range lease repl=(n2,s2):3 seq=4 start=1531891899.399455853,0 epo=1 pro=1531891899.399503040,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:39.837000 4673 storage/replica_proposal.go:222  [n1,s1,r350/1:/Table/73/{2/\"V\"/2…-3/1579/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:39.902399 33469 storage/replica_range_lease.go:554  [n1,s1,r350/1:/Table/73/2/\"{V\"/2856-Z\"/338}] transferring lease to s3\nI180718 05:31:40.048386 33469 storage/replica_range_lease.go:617  [n1,s1,r350/1:/Table/73/2/\"{V\"/2856-Z\"/338}] done transferring lease to s3: <nil>\nI180718 05:31:40.085690 5602 storage/replica_proposal.go:222  [n3,s3,r350/2:/Table/73/2/\"{V\"/2856-Z\"/338}] new range lease repl=(n3,s3):2 seq=4 start=1531891899.926049371,0 epo=1 pro=1531891899.926092248,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:40.171816 33484 storage/replica_command.go:279  [n1,s1,r351/1:/Table/73/2/\"{A\"/755-E\"/655}] initiating a split of this range at key /Table/73/2/\"A\"/3302 [r353]\nI180718 05:31:40.838957 33533 storage/replica_command.go:279  [n1,s1,r352/1:/Table/73/{2/\"Z\"/3…-3/1579/…}] initiating a split of this range at key /Table/73/3/512/\"S\"/PrefixEnd [r354]\nI180718 05:31:41.165718 4681 storage/replica_proposal.go:222  [n1,s1,r351/1:/Table/73/2/\"{A\"/755-E\"/655}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:41.530400 4711 storage/replica_proposal.go:222  [n1,s1,r352/1:/Table/73/{2/\"Z\"/3…-3/1579/…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:41.867762 33667 storage/replica_command.go:279  [n1,s1,r342/1:/{Table/73/3/2…-Max}] initiating a split of this range at key /Table/73/3/2733/\"D\"/PrefixEnd [r355]\nI180718 05:31:41.899404 33623 storage/replica_command.go:279  [n1,s1,r354/1:/Table/73/3/{512/\"S…-1579/\"…}] initiating a split of this range at key /Table/73/3/913/\"D\" [r356]\nI180718 05:31:42.793316 4665 storage/replica_proposal.go:222  [n1,s1,r354/1:/Table/73/3/{512/\"S…-1579/\"…}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:42.826571 4705 storage/replica_proposal.go:222  [n1,s1,r342/1:/{Table/73/3/2…-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:43.245218 33778 storage/replica_command.go:279  [n1,s1,r355/1:/{Table/73/3/2…-Max}] initiating a split of this range at key /Table/73/3/3400/\"U\"/PrefixEnd [r357]\nI180718 05:31:43.673433 4673 storage/replica_proposal.go:222  [n1,s1,r355/1:/{Table/73/3/2…-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:44.079411 4724 server/status/runtime.go:255  [n1] runtime stats: 1.7 GiB RSS, 611 goroutines, 80 MiB/26 MiB/135 MiB GO alloc/idle/total, 286 MiB/329 MiB CGO alloc/total, 3939.28cgo/sec, 1.01/0.08 %(u/s)time, 0.03 %gc (5x)\nI180718 05:31:44.175198 33754 storage/replica_command.go:279  [n1,s1,r357/1:/{Table/73/3/3…-Max}] initiating a split of this range at key /Table/73/3/4067/\"L\"/PrefixEnd [r358]\nI180718 05:31:44.690982 4668 storage/replica_proposal.go:222  [n1,s1,r357/1:/{Table/73/3/3…-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:44.779854 33809 storage/replica_range_lease.go:554  [replicate,n1,s1,r357/1:/Table/73/3/{3400/\"…-4067/\"…}] transferring lease to s3\nI180718 05:31:44.883685 33809 storage/replica_range_lease.go:617  [replicate,n1,s1,r357/1:/Table/73/3/{3400/\"…-4067/\"…}] done transferring lease to s3: <nil>\nI180718 05:31:44.892825 5521 storage/replica_proposal.go:222  [n3,s3,r357/2:/Table/73/3/{3400/\"…-4067/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891904.870792543,0 epo=1 pro=1531891904.870835593,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:45.181936 33923 storage/replica_command.go:279  [n1,s1,r358/1:/{Table/73/3/4…-Max}] initiating a split of this range at key /Table/73/3/4734/\"C\"/PrefixEnd [r359]\nI180718 05:31:45.727650 4697 storage/replica_proposal.go:222  [n1,s1,r358/1:/{Table/73/3/4…-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:45.809859 33849 storage/replica_range_lease.go:554  [n1,s1,r358/1:/Table/73/3/4{067/\"L…-734/\"C…}] transferring lease to s3\nI180718 05:31:45.841488 33849 storage/replica_range_lease.go:617  [n1,s1,r358/1:/Table/73/3/4{067/\"L…-734/\"C…}] done transferring lease to s3: <nil>\nI180718 05:31:45.873838 5610 storage/replica_proposal.go:222  [n3,s3,r358/2:/Table/73/3/4{067/\"L…-734/\"C…}] new range lease repl=(n3,s3):2 seq=4 start=1531891905.829803884,0 epo=1 pro=1531891905.829845383,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:46.161072 33943 storage/replica_command.go:279  [n1,s1,r359/1:/{Table/73/3/4…-Max}] initiating a split of this range at key /Table/74 [r360]\nI180718 05:31:46.851336 4703 storage/replica_proposal.go:222  [n1,s1,r359/1:/{Table/73/3/4…-Max}] new range lease repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:46.872808 34002 storage/replica_range_lease.go:554  [replicate,n1,s1,r359/1:/Table/7{3/3/4734…-4}] transferring lease to s3\nI180718 05:31:46.970485 5521 storage/replica_proposal.go:222  [n3,s3,r359/2:/Table/7{3/3/4734…-4}] new range lease repl=(n3,s3):2 seq=4 start=1531891906.873063114,0 epo=1 pro=1531891906.873099668,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:47.033188 34002 storage/replica_range_lease.go:617  [replicate,n1,s1,r359/1:/Table/7{3/3/4734…-4}] done transferring lease to s3: <nil>\nI180718 05:31:47.583403 5245 server/status/runtime.go:255  [n2] runtime stats: 1.7 GiB RSS, 594 goroutines, 64 MiB/41 MiB/135 MiB GO alloc/idle/total, 284 MiB/329 MiB CGO alloc/total, 3027.15cgo/sec, 0.89/0.08 %(u/s)time, 0.03 %gc (5x)\nI180718 05:31:48.368050 4523 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root,intExec=crdb-internal-jobs-table] connection to n3 established\nI180718 05:31:48.372127 34118 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:31:48.760589 5496 server/status/runtime.go:255  [n3] runtime stats: 1.7 GiB RSS, 591 goroutines, 86 MiB/25 MiB/135 MiB GO alloc/idle/total, 284 MiB/329 MiB CGO alloc/total, 3093.04cgo/sec, 0.87/0.07 %(u/s)time, 0.03 %gc (5x)\nI180718 05:31:49.312791 4522 rpc/nodedialer/nodedialer.go:90  [intExec=get-table-statistics] connection to n2 established\nI180718 05:31:49.324393 34056 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:31:49.358869 4524 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root] connection to n3 established\nI180718 05:31:49.360140 4526 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root] connection to n2 established\nI180718 05:31:49.364109 34168 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:31:49.372616 34031 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:31:49.542395 4525 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root] connection to n2 established\nI180718 05:31:49.549121 34197 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\n\n```"},"comment":{"url":"https://api.github.com/repos/cockroachdb/cockroach/issues/comments/406172239","html_url":"https://github.com/cockroachdb/cockroach/issues/27677#issuecomment-406172239","issue_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27677","id":406172239,"node_id":"MDEyOklzc3VlQ29tbWVudDQwNjE3MjIzOQ==","user":{"login":"cockroach-teamcity","id":20559710,"node_id":"MDQ6VXNlcjIwNTU5NzEw","avatar_url":"https://avatars0.githubusercontent.com/u/20559710?v=4","gravatar_id":"","url":"https://api.github.com/users/cockroach-teamcity","html_url":"https://github.com/cockroach-teamcity","followers_url":"https://api.github.com/users/cockroach-teamcity/followers","following_url":"https://api.github.com/users/cockroach-teamcity/following{/other_user}","gists_url":"https://api.github.com/users/cockroach-teamcity/gists{/gist_id}","starred_url":"https://api.github.com/users/cockroach-teamcity/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/cockroach-teamcity/subscriptions","organizations_url":"https://api.github.com/users/cockroach-teamcity/orgs","repos_url":"https://api.github.com/users/cockroach-teamcity/repos","events_url":"https://api.github.com/users/cockroach-teamcity/events{/privacy}","received_events_url":"https://api.github.com/users/cockroach-teamcity/received_events","type":"User","site_admin":false},"created_at":"2018-07-19T06:46:29Z","updated_at":"2018-07-19T06:46:29Z","author_association":"MEMBER","body":"SHA: https://github.com/cockroachdb/cockroach/commits/6b49d8cf1b8130a21698c74f541964dfd6a4e3c8\n\nParameters:\n```\nTAGS=\nGOFLAGS=-race\n```\n\nFailed test: https://teamcity.cockroachdb.com/viewLog.html?buildId=786444&tab=buildLog"}}
{"actor":{"display_login":"cockroach-teamcity"}}