{"action":"created","issue":{"url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27678","repository_url":"https://api.github.com/repos/cockroachdb/cockroach","labels_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27678/labels{/name}","comments_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27678/comments","events_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27678/events","html_url":"https://github.com/cockroachdb/cockroach/issues/27678","id":342186589,"node_id":"MDU6SXNzdWUzNDIxODY1ODk=","number":27678,"title":"github.com/cockroachdb/cockroach/pkg/ccl/importccl: TestImportCSVStmt/schema-in-file-auto-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":570,"closed_issues":964,"state":"open","created_at":"2017-09-05T15:30:49Z","updated_at":"2018-07-20T05:40:59Z","due_on":null,"closed_at":null},"comments":4,"created_at":"2018-07-18T05:55:39Z","updated_at":"2018-07-20T06:57:24Z","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-auto-gzip\nI180718 05:31:49.783184 34175 storage/replica_range_lease.go:554  [replicate,n1,s1,r216/1:/Table/63/2/{NULL/4…-\"D\"/23…}] transferring lease to s3\nI180718 05:31:49.807317 34175 storage/replica_range_lease.go:617  [replicate,n1,s1,r216/1:/Table/63/2/{NULL/4…-\"D\"/23…}] done transferring lease to s3: <nil>\nI180718 05:31:49.817944 5637 storage/replica_proposal.go:222  [n3,s3,r216/2:/Table/63/2/{NULL/4…-\"D\"/23…}] new range lease repl=(n3,s3):2 seq=4 start=1531891909.783381711,0 epo=1 pro=1531891909.783425337,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:50.419556 6220 sql/event_log.go:126  [n1,client=127.0.0.1:54394,user=root] Event: \"create_database\", target: 74, info: {DatabaseName:csv11 Statement:CREATE DATABASE csv11 User:root}\nI180718 05:31:50.867356 34259 storage/replica_range_lease.go:554  [replicate,n1,s1,r152/1:/Table/59/2/\"{S\"/2827-X\"/1402}] transferring lease to s3\nI180718 05:31:50.900232 34224 storage/replica_command.go:279  [split,n1,s1,r333/1:/Table/7{2-3/1/741}] initiating a split of this range at key /Table/73 [r361]\nI180718 05:31:51.059457 34259 storage/replica_range_lease.go:617  [replicate,n1,s1,r152/1:/Table/59/2/\"{S\"/2827-X\"/1402}] done transferring lease to s3: <nil>\nI180718 05:31:51.075447 5514 storage/replica_proposal.go:222  [n3,s3,r152/2:/Table/59/2/\"{S\"/2827-X\"/1402}] new range lease repl=(n3,s3):2 seq=4 start=1531891910.946054592,0 epo=1 pro=1531891910.946123795,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:31:51.694897 4681 storage/replica_proposal.go:222  [n1,s1,r333/1:/Table/7{2-3/1/741}] 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:53.397657 4527 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n3 established\nI180718 05:31:53.417832 34453 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:31:53.447831 4528 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n2 established\nI180718 05:31:53.458529 34428 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:31:54.064389 4724 server/status/runtime.go:255  [n1] runtime stats: 1.7 GiB RSS, 649 goroutines, 73 MiB/35 MiB/135 MiB GO alloc/idle/total, 289 MiB/329 MiB CGO alloc/total, 2295.55cgo/sec, 0.84/0.06 %(u/s)time, 0.02 %gc (5x)\nI180718 05:31:57.593713 5245 server/status/runtime.go:255  [n2] runtime stats: 1.7 GiB RSS, 594 goroutines, 70 MiB/42 MiB/135 MiB GO alloc/idle/total, 284 MiB/329 MiB CGO alloc/total, 1763.99cgo/sec, 0.82/0.05 %(u/s)time, 0.02 %gc (5x)\nI180718 05:31:58.117074 4531 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n2 established\nI180718 05:31:58.117847 4530 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n3 established\nI180718 05:31:58.133010 34825 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:31:58.133421 34826 rpc/nodedialer/nodedialer.go:90  [n2] connection to n3 established\nI180718 05:31:58.193396 34886 rpc/nodedialer/nodedialer.go:90  [n3] connection to n2 established\nI180718 05:31:58.193862 34885 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:31:58.274506 34872 rpc/nodedialer/nodedialer.go:90  [import-distsql,n1] connection to n2 established\nI180718 05:31:58.294931 34873 rpc/nodedialer/nodedialer.go:90  [import-distsql,n1] connection to n3 established\nI180718 05:31:58.540500 5496 server/status/runtime.go:255  [n3] runtime stats: 1.7 GiB RSS, 698 goroutines, 62 MiB/44 MiB/135 MiB GO alloc/idle/total, 292 MiB/332 MiB CGO alloc/total, 1594.50cgo/sec, 0.81/0.05 %(u/s)time, 0.02 %gc (4x)\nI180718 05:32:02.561191 4529 rpc/nodedialer/nodedialer.go:90  [intExec=adopt-job] connection to n3 established\nI180718 05:32:02.621154 35158 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:32:04.097790 4724 server/status/runtime.go:255  [n1] runtime stats: 1.7 GiB RSS, 638 goroutines, 68 MiB/39 MiB/135 MiB GO alloc/idle/total, 288 MiB/332 MiB CGO alloc/total, 581.16cgo/sec, 0.87/0.03 %(u/s)time, 0.04 %gc (5x)\nI180718 05:32:04.171804 35254 storage/replica_range_lease.go:554  [replicate,n1,s1,r235/1:/Table/63/3/4{256/NU…-957/\"R…}] transferring lease to s3\nI180718 05:32:04.209452 35254 storage/replica_range_lease.go:617  [replicate,n1,s1,r235/1:/Table/63/3/4{256/NU…-957/\"R…}] done transferring lease to s3: <nil>\nI180718 05:32:04.213553 5612 storage/replica_proposal.go:222  [n3,s3,r235/2:/Table/63/3/4{256/NU…-957/\"R…}] new range lease repl=(n3,s3):2 seq=4 start=1531891924.198785694,0 epo=1 pro=1531891924.198832095,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:05.352485 35261 storage/replica_command.go:279  [n1,s1,r360/1:/{Table/74-Max}] initiating a split of this range at key /Table/75/1/741 [r362]\nI180718 05:32:05.415506 5173 rpc/nodedialer/nodedialer.go:90  [intExec=adopt-job] connection to n3 established\nI180718 05:32:05.427072 35278 rpc/nodedialer/nodedialer.go:90  [n3] connection to n2 established\nI180718 05:32:05.468749 35287 storage/replica_command.go:279  [n1,s1,r360/1:/{Table/74-Max}] initiating a split of this range at key /Table/75/1/4357 [r363]\nI180718 05:32:05.470354 35286 storage/replica_command.go:279  [n1,s1,r360/1:/{Table/74-Max}] initiating a split of this range at key /Table/75/2/\"U\"/3401 [r364]\nI180718 05:32:05.803698 4660 storage/replica_proposal.go:222  [n1,s1,r360/1:/{Table/74-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:32:05.828704 35261 storage/replica_range_lease.go:554  [n1,s1,r360/1:/Table/7{4-5/1/741}] transferring lease to s3\nI180718 05:32:05.899821 35261 storage/replica_range_lease.go:617  [n1,s1,r360/1:/Table/7{4-5/1/741}] done transferring lease to s3: <nil>\nI180718 05:32:05.907047 5636 storage/replica_proposal.go:222  [n3,s3,r360/2:/Table/7{4-5/1/741}] new range lease repl=(n3,s3):2 seq=4 start=1531891925.828834746,0 epo=1 pro=1531891925.828847940,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:06.039267 35261 storage/replica_command.go:279  [n1,s1,r362/1:/{Table/75/1/7…-Max}] initiating a split of this range at key /Table/75/1/1456 [r365]\nI180718 05:32:06.052234 35405 storage/replica_command.go:279  [n1,s1,r362/1:/{Table/75/1/7…-Max}] initiating a split of this range at key /Table/75/1/4357 [r366]\nI180718 05:32:06.224406 35370 storage/replica_command.go:279  [n1,s1,r362/1:/{Table/75/1/7…-Max}] initiating a split of this range at key /Table/75/2/\"U\"/3401 [r367]\nI180718 05:32:06.398832 4678 storage/replica_proposal.go:222  [n1,s1,r362/1:/{Table/75/1/7…-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:32:06.491044 35261 storage/replica_range_lease.go:554  [n1,s1,r362/1:/Table/75/1/{741-1456}] transferring lease to s2\nI180718 05:32:06.667501 5321 storage/replica_proposal.go:222  [n2,s2,r362/3:/Table/75/1/{741-1456}] new range lease repl=(n2,s2):3 seq=4 start=1531891926.636026458,0 epo=1 pro=1531891926.636069645,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:06.745348 35261 storage/replica_range_lease.go:617  [n1,s1,r362/1:/Table/75/1/{741-1456}] done transferring lease to s2: <nil>\nI180718 05:32:06.928841 35361 storage/replica_command.go:279  [n1,s1,r365/1:/{Table/75/1/1…-Max}] initiating a split of this range at key /Table/75/1/4357 [r368]\nI180718 05:32:07.061173 35261 storage/replica_command.go:279  [n1,s1,r365/1:/{Table/75/1/1…-Max}] initiating a split of this range at key /Table/75/1/2171 [r369]\nI180718 05:32:07.222286 35507 storage/replica_command.go:279  [n1,s1,r365/1:/{Table/75/1/1…-Max}] initiating a split of this range at key /Table/75/2/\"U\"/3401 [r370]\nI180718 05:32:07.533307 4670 storage/replica_proposal.go:222  [n1,s1,r365/1:/{Table/75/1/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:32:07.562491 35491 storage/replica_range_lease.go:554  [n1,s1,r365/1:/Table/75/1/{1456-4357}] transferring lease to s2\nI180718 05:32:07.589035 5245 server/status/runtime.go:255  [n2] runtime stats: 1.7 GiB RSS, 628 goroutines, 64 MiB/41 MiB/135 MiB GO alloc/idle/total, 287 MiB/332 MiB CGO alloc/total, 1701.29cgo/sec, 0.93/0.03 %(u/s)time, 0.05 %gc (5x)\nI180718 05:32:07.700762 35491 storage/replica_range_lease.go:617  [n1,s1,r365/1:/Table/75/1/{1456-4357}] done transferring lease to s2: <nil>\nI180718 05:32:07.730487 5264 storage/replica_proposal.go:222  [n2,s2,r365/3:/Table/75/1/{1456-4357}] new range lease repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:07.878228 35495 storage/replica_command.go:279  [n2,s2,r365/3:/Table/75/1/{1456-4357}] initiating a split of this range at key /Table/75/1/2171 [r264]\nI180718 05:32:07.959399 35522 storage/replica_command.go:279  [n1,s1,r368/1:/{Table/75/1/4…-Max}] initiating a split of this range at key /Table/75/2/\"A\"/1743 [r371]\nI180718 05:32:08.230057 35500 storage/replica_command.go:279  [n1,s1,r368/1:/{Table/75/1/4…-Max}] initiating a split of this range at key /Table/75/2/\"U\"/3401 [r372]\nI180718 05:32:08.478101 5251 storage/replica_proposal.go:222  [n2,s2,r365/3:/Table/75/1/{1456-4357}] new range lease repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0 following repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0\nI180718 05:32:08.537855 5496 server/status/runtime.go:255  [n3] runtime stats: 1.7 GiB RSS, 633 goroutines, 87 MiB/20 MiB/135 MiB GO alloc/idle/total, 288 MiB/332 MiB CGO alloc/total, 1944.91cgo/sec, 0.94/0.04 %(u/s)time, 0.05 %gc (5x)\nI180718 05:32:08.999486 4688 storage/replica_proposal.go:222  [n1,s1,r368/1:/{Table/75/1/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:32:09.266014 35521 storage/replica_range_lease.go:554  [replicate,n1,s1,r19/1:/Table/2{2-3}] transferring lease to s3\nI180718 05:32:09.338585 35521 storage/replica_range_lease.go:617  [replicate,n1,s1,r19/1:/Table/2{2-3}] done transferring lease to s3: <nil>\nI180718 05:32:09.411100 5602 storage/replica_proposal.go:222  [n3,s3,r19/2:/Table/2{2-3}] new range lease repl=(n3,s3):2 seq=4 start=1531891929.266186772,0 epo=1 pro=1531891929.266220377,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891533.899323095,0\nI180718 05:32:09.436159 35574 storage/replica_command.go:279  [n2,s2,r264/3:/Table/75/1/{2171-4357}] initiating a split of this range at key /Table/75/1/2886 [r265]\nI180718 05:32:09.624660 35624 storage/replica_command.go:279  [n1,s1,r371/1:/{Table/75/2/\"…-Max}] initiating a split of this range at key /Table/75/2/\"D\"/4112 [r373]\nI180718 05:32:09.863983 35638 storage/replica_command.go:279  [n1,s1,r371/1:/{Table/75/2/\"…-Max}] initiating a split of this range at key /Table/75/2/\"U\"/3401 [r374]\nI180718 05:32:10.218992 5255 storage/replica_proposal.go:222  [n2,s2,r264/3:/Table/75/1/{2171-4357}] new range lease repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0 following repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0\nI180718 05:32:10.734876 35550 storage/replica_command.go:279  [n2,s2,r265/3:/Table/75/1/{2886-4357}] initiating a split of this range at key /Table/75/1/3601 [r266]\nI180718 05:32:11.203302 4654 storage/replica_proposal.go:222  [n1,s1,r371/1:/{Table/75/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:32:11.563344 35656 storage/replica_command.go:279  [n1,s1,r373/1:/{Table/75/2/\"…-Max}] initiating a split of this range at key /Table/75/2/\"U\"/3401 [r375]\nI180718 05:32:11.645946 35702 storage/replica_command.go:279  [n1,s1,r373/1:/{Table/75/2/\"…-Max}] initiating a split of this range at key /Table/75/2/\"H\"/1490 [r376]\nI180718 05:32:11.663642 5313 storage/replica_proposal.go:222  [n2,s2,r265/3:/Table/75/1/{2886-4357}] new range lease repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0 following repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0\nI180718 05:32:12.143699 35709 storage/replica_command.go:279  [n2,s2,r266/3:/Table/75/1/{3601-4357}] initiating a split of this range at key /Table/75/1/3642 [r267]\nI180718 05:32:12.208058 4653 storage/replica_proposal.go:222  [n1,s1,r373/1:/{Table/75/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:32:12.414230 35702 storage/replica_command.go:279  [n1,s1,r373/1:/Table/75/2/\"{D\"/4112-U\"/3401}] initiating a split of this range at key /Table/75/2/\"H\"/1490 [r377]\nI180718 05:32:12.485024 35729 storage/replica_command.go:279  [n1,s1,r375/1:/{Table/75/2/\"…-Max}] initiating a split of this range at key /Table/75/2/\"Y\"/883 [r378]\nI180718 05:32:12.758960 5312 storage/replica_proposal.go:222  [n2,s2,r266/3:/Table/75/1/{3601-4357}] new range lease repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0 following repl=(n2,s2):3 seq=4 start=1531891927.562677632,0 epo=1 pro=1531891927.562692715,0\nI180718 05:32:13.475891 4651 storage/replica_proposal.go:222  [n1,s1,r373/1:/Table/75/2/\"{D\"/4112-U\"/3401}] 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:32:13.714090 4703 storage/replica_proposal.go:222  [n1,s1,r375/1:/{Table/75/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:32:13.815010 35821 storage/replica_command.go:279  [n1,s1,r377/1:/Table/75/2/\"{H\"/1490-U\"/3401}] initiating a split of this range at key /Table/75/2/\"K\"/3911 [r379]\nI180718 05:32:14.062347 4724 server/status/runtime.go:255  [n1] runtime stats: 1.7 GiB RSS, 627 goroutines, 84 MiB/22 MiB/135 MiB GO alloc/idle/total, 288 MiB/332 MiB CGO alloc/total, 3659.27cgo/sec, 0.95/0.06 %(u/s)time, 0.04 %gc (5x)\nI180718 05:32:14.077029 35906 storage/replica_command.go:279  [n1,s1,r378/1:/{Table/75/2/\"…-Max}] initiating a split of this range at key /Table/75/3/341/\"D\"/PrefixEnd [r380]\nI180718 05:32:14.656564 35917 storage/replica_command.go:279  [n1,s1,r378/1:/{Table/75/2/\"…-Max}] initiating a split of this range at key /Table/75/3/4783/\"Z\"/PrefixEnd [r391]\nI180718 05:32:14.793441 4651 storage/replica_proposal.go:222  [n1,s1,r377/1:/Table/75/2/\"{H\"/1490-U\"/3401}] 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:32:14.852000 4685 storage/replica_proposal.go:222  [n1,s1,r378/1:/{Table/75/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:32:14.957101 36009 storage/replica_range_lease.go:554  [n1,s1,r378/1:/Table/75/{2/\"Y\"/8…-3/341/\"…}] transferring lease to s2\nI180718 05:32:15.046112 35929 storage/replica_command.go:279  [n1,s1,r379/1:/Table/75/2/\"{K\"/3911-U\"/3401}] initiating a split of this range at key /Table/75/2/\"O\"/1393 [r392]\nI180718 05:32:15.052401 36009 storage/replica_range_lease.go:617  [n1,s1,r378/1:/Table/75/{2/\"Y\"/8…-3/341/\"…}] done transferring lease to s2: <nil>\nI180718 05:32:15.058574 5298 storage/replica_proposal.go:222  [n2,s2,r378/3:/Table/75/{2/\"Y\"/8…-3/341/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891934.958122273,0 epo=1 pro=1531891934.958160252,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:15.239034 35917 storage/replica_command.go:279  [n1,s1,r380/1:/{Table/75/3/3…-Max}] initiating a split of this range at key /Table/75/3/4783/\"Z\"/PrefixEnd [r393]\nI180718 05:32:15.282445 35936 storage/replica_command.go:279  [n1,s1,r380/1:/{Table/75/3/3…-Max}] initiating a split of this range at key /Table/75/3/1008/\"U\"/PrefixEnd [r394]\nI180718 05:32:15.670182 4691 storage/replica_proposal.go:222  [n1,s1,r380/1:/{Table/75/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:32:15.701824 4678 storage/replica_proposal.go:222  [n1,s1,r379/1:/Table/75/2/\"{K\"/3911-U\"/3401}] 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:32:15.724800 35917 storage/replica_range_lease.go:554  [n1,s1,r380/1:/Table/75/3/{341/\"D…-4783/\"…}] transferring lease to s2\nI180718 05:32:15.774469 35968 storage/replica_range_lease.go:554  [n1,s1,r379/1:/Table/75/2/\"{K\"/3911-O\"/1393}] transferring lease to s3\nI180718 05:32:15.839405 35917 storage/replica_range_lease.go:617  [n1,s1,r380/1:/Table/75/3/{341/\"D…-4783/\"…}] done transferring lease to s2: <nil>\nI180718 05:32:15.847179 35968 storage/replica_range_lease.go:617  [n1,s1,r379/1:/Table/75/2/\"{K\"/3911-O\"/1393}] done transferring lease to s3: <nil>\nI180718 05:32:15.867071 5602 storage/replica_proposal.go:222  [n3,s3,r379/2:/Table/75/2/\"{K\"/3911-O\"/1393}] new range lease repl=(n3,s3):2 seq=4 start=1531891935.783098186,0 epo=1 pro=1531891935.783145818,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:15.904811 5305 storage/replica_proposal.go:222  [n2,s2,r380/3:/Table/75/3/{341/\"D…-4783/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:15.943027 36039 storage/replica_command.go:279  [n2,s2,r380/3:/Table/75/3/{341/\"D…-4783/\"…}] initiating a split of this range at key /Table/75/3/1008/\"U\"/PrefixEnd [r268]\nI180718 05:32:16.085988 36027 storage/replica_command.go:279  [n1,s1,r392/1:/Table/75/2/\"{O\"/1393-U\"/3401}] initiating a split of this range at key /Table/75/2/\"R\"/979 [r395]\nI180718 05:32:16.409106 35917 storage/replica_command.go:279  [n1,s1,r393/1:/{Table/75/3/4…-Max}] initiating a split of this range at key /Table/76 [r396]\nI180718 05:32:16.595163 5300 storage/replica_proposal.go:222  [n2,s2,r380/3:/Table/75/3/{341/\"D…-4783/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0 following repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0\nI180718 05:32:17.160008 36121 storage/replica_command.go:279  [n2,s2,r268/3:/Table/75/3/{1008/\"…-4783/\"…}] initiating a split of this range at key /Table/75/3/1675/\"L\"/PrefixEnd [r269]\nI180718 05:32:17.430242 4711 storage/replica_proposal.go:222  [n1,s1,r392/1:/Table/75/2/\"{O\"/1393-U\"/3401}] 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:32:17.445510 4700 storage/replica_proposal.go:222  [n1,s1,r393/1:/{Table/75/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:32:17.471518 36188 storage/replica_range_lease.go:554  [replicate,n1,s1,r392/1:/Table/75/2/\"{O\"/1393-R\"/979}] transferring lease to s2\nI180718 05:32:17.486767 36144 storage/replica_range_lease.go:554  [n1,s1,r392/1:/Table/75/2/\"{O\"/1393-R\"/979}] transferring lease to s3\nI180718 05:32:17.610412 5245 server/status/runtime.go:255  [n2] runtime stats: 1.8 GiB RSS, 629 goroutines, 86 MiB/20 MiB/135 MiB GO alloc/idle/total, 289 MiB/333 MiB CGO alloc/total, 3908.65cgo/sec, 1.04/0.08 %(u/s)time, 0.03 %gc (5x)\nI180718 05:32:17.651884 36188 storage/replica_range_lease.go:617  [replicate,n1,s1,r392/1:/Table/75/2/\"{O\"/1393-R\"/979}] done transferring lease to s2: <nil>\nI180718 05:32:17.679023 35917 storage/replica_range_lease.go:554  [n1,s1,r393/1:/Table/7{5/3/4783…-6}] transferring lease to s3\nI180718 05:32:17.710723 5153 storage/replica_proposal.go:222  [n2,s2,r392/3:/Table/75/2/\"{O\"/1393-R\"/979}] new range lease repl=(n2,s2):3 seq=4 start=1531891937.510519513,0 epo=1 pro=1531891937.510564673,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:17.730756 5265 storage/replica_proposal.go:222  [n2,s2,r268/3:/Table/75/3/{1008/\"…-4783/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0 following repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0\nI180718 05:32:17.963221 35917 storage/replica_range_lease.go:617  [n1,s1,r393/1:/Table/7{5/3/4783…-6}] done transferring lease to s3: <nil>\nI180718 05:32:18.042112 5635 storage/replica_proposal.go:222  [n3,s3,r393/2:/Table/7{5/3/4783…-6}] new range lease repl=(n3,s3):2 seq=4 start=1531891937.679210687,0 epo=1 pro=1531891937.679229436,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:18.432659 36208 storage/replica_command.go:279  [n2,s2,r269/3:/Table/75/3/{1675/\"…-4783/\"…}] initiating a split of this range at key /Table/75/3/2342/\"C\"/PrefixEnd [r270]\nI180718 05:32:18.562050 5496 server/status/runtime.go:255  [n3] runtime stats: 1.8 GiB RSS, 624 goroutines, 60 MiB/44 MiB/135 MiB GO alloc/idle/total, 288 MiB/333 MiB CGO alloc/total, 3865.85cgo/sec, 1.05/0.07 %(u/s)time, 0.04 %gc (6x)\nI180718 05:32:19.372638 5257 storage/replica_proposal.go:222  [n2,s2,r269/3:/Table/75/3/{1675/\"…-4783/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0 following repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0\nI180718 05:32:19.769323 36283 storage/replica_command.go:279  [n2,s2,r270/3:/Table/75/3/{2342/\"…-4783/\"…}] initiating a split of this range at key /Table/75/3/3009/\"T\"/PrefixEnd [r381]\nI180718 05:32:20.504653 36303 storage/replica_range_lease.go:554  [replicate,n1,s1,r150/1:/Table/59/1/2{293-958}] transferring lease to s3\nI180718 05:32:20.587932 36303 storage/replica_range_lease.go:617  [replicate,n1,s1,r150/1:/Table/59/1/2{293-958}] done transferring lease to s3: <nil>\nI180718 05:32:20.594126 5250 storage/replica_proposal.go:222  [n2,s2,r270/3:/Table/75/3/{2342/\"…-4783/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0 following repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0\nI180718 05:32:20.605262 5520 storage/replica_proposal.go:222  [n3,s3,r150/2:/Table/59/1/2{293-958}] new range lease repl=(n3,s3):2 seq=4 start=1531891940.504884083,0 epo=1 pro=1531891940.504917356,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:32:21.218062 36436 storage/replica_command.go:279  [n2,s2,r381/3:/Table/75/3/{3009/\"…-4783/\"…}] initiating a split of this range at key /Table/75/3/3676/\"K\"/PrefixEnd [r382]\nI180718 05:32:21.755856 5264 storage/replica_proposal.go:222  [n2,s2,r381/3:/Table/75/3/{3009/\"…-4783/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0 following repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0\nI180718 05:32:22.295506 36500 storage/replica_command.go:279  [n2,s2,r382/3:/Table/75/3/{3676/\"…-4783/\"…}] initiating a split of this range at key /Table/75/3/4117/\"J\" [r383]\nI180718 05:32:22.754912 5308 storage/replica_proposal.go:222  [n2,s2,r382/3:/Table/75/3/{3676/\"…-4783/\"…}] new range lease repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0 following repl=(n2,s2):3 seq=4 start=1531891935.776152134,0 epo=1 pro=1531891935.776207662,0\nI180718 05:32:24.035892 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 3643/925 sent/received, bytes 1361686B/351115B sent/received)\n  2: 127.0.0.1:45835 (6m56s)\n  3: 127.0.0.1:40869 (6m56s)\nI180718 05:32:24.048476 4724 server/status/runtime.go:255  [n1] runtime stats: 1.8 GiB RSS, 598 goroutines, 85 MiB/22 MiB/135 MiB GO alloc/idle/total, 287 MiB/331 MiB CGO alloc/total, 3374.88cgo/sec, 0.99/0.07 %(u/s)time, 0.03 %gc (5x)\nI180718 05:32:24.696308 4532 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:32:24.709142 36542 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:32:25.521740 4533 rpc/nodedialer/nodedialer.go:90  [intExec=get-table-statistics] connection to n2 established\nI180718 05:32:25.560530 36698 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:32:25.572874 4534 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root] connection to n3 established\nI180718 05:32:25.574197 4536 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root] connection to n2 established\nI180718 05:32:25.579311 36724 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:32:25.614452 36670 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\n\n```"},"comment":{"url":"https://api.github.com/repos/cockroachdb/cockroach/issues/comments/406507100","html_url":"https://github.com/cockroachdb/cockroach/issues/27678#issuecomment-406507100","issue_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27678","id":406507100,"node_id":"MDEyOklzc3VlQ29tbWVudDQwNjUwNzEwMA==","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-20T06:57:24Z","updated_at":"2018-07-20T06:57:24Z","author_association":"MEMBER","body":"SHA: https://github.com/cockroachdb/cockroach/commits/f38e4c7a2bb3f0e697b25d21bdd8ea67b163c32f\n\nParameters:\n```\nTAGS=\nGOFLAGS=-race\n```\n\nFailed test: https://teamcity.cockroachdb.com/viewLog.html?buildId=788155&tab=buildLog"}}
{"actor":{"display_login":"cockroach-teamcity"}}