{"action":"created","issue":{"url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27665","repository_url":"https://api.github.com/repos/cockroachdb/cockroach","labels_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27665/labels{/name}","comments_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27665/comments","events_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27665/events","html_url":"https://github.com/cockroachdb/cockroach/issues/27665","id":342186535,"node_id":"MDU6SXNzdWUzNDIxODY1MzU=","number":27665,"title":"github.com/cockroachdb/cockroach/pkg/ccl/importccl: TestImportCSVStmt/schema-in-query 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:25Z","updated_at":"2018-07-20T06:57:26Z","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-query\nI180718 05:26:38.629909 5496 server/status/runtime.go:255  [n3] runtime stats: 951 MiB RSS, 595 goroutines, 59 MiB/14 MiB/92 MiB GO alloc/idle/total, 135 MiB/170 MiB CGO alloc/total, 3001.37cgo/sec, 0.83/0.03 %(u/s)time, 0.05 %gc (7x)\nI180718 05:26:38.845232 6220 sql/event_log.go:126  [n1,client=127.0.0.1:54394,user=root] Event: \"create_database\", target: 56, info: {DatabaseName:csv2 Statement:CREATE DATABASE csv2 User:root}\nI180718 05:26:38.968144 11290 storage/replica_command.go:279  [split,n2,s2,r50/3:/Table/5{4-5/1/741}] initiating a split of this range at key /Table/55 [r97]\nI180718 05:26:39.653294 5151 storage/replica_proposal.go:222  [n2,s2,r50/3:/Table/5{4-5/1/741}] new range lease repl=(n2,s2):3 seq=4 start=1531891579.834851593,0 epo=1 pro=1531891579.834899691,0 following repl=(n2,s2):3 seq=4 start=1531891579.834851593,0 epo=1 pro=1531891579.834899691,0\nI180718 05:26:40.894355 4536 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n3 established\nI180718 05:26:40.895657 4537 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n2 established\nI180718 05:26:40.902461 11369 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:26:40.967046 11263 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:26:44.079414 4724 server/status/runtime.go:255  [n1] runtime stats: 967 MiB RSS, 612 goroutines, 63 MiB/7.9 MiB/92 MiB GO alloc/idle/total, 141 MiB/178 MiB CGO alloc/total, 2581.12cgo/sec, 0.86/0.05 %(u/s)time, 0.02 %gc (6x)\nI180718 05:26:44.099840 11698 storage/replica_range_lease.go:554  [replicate,n1,s1,r82/1:/Table/55/2/\"{K\"/1154-N\"/3576}] transferring lease to s2\nI180718 05:26:44.257516 11698 storage/replica_range_lease.go:617  [replicate,n1,s1,r82/1:/Table/55/2/\"{K\"/1154-N\"/3576}] done transferring lease to s2: <nil>\nI180718 05:26:44.270961 5259 storage/replica_proposal.go:222  [n2,s2,r82/3:/Table/55/2/\"{K\"/1154-N\"/3576}] new range lease repl=(n2,s2):3 seq=4 start=1531891604.100318918,0 epo=1 pro=1531891604.100346996,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:26:46.104381 11764 storage/replica_range_lease.go:554  [replicate,n1,s1,r9/1:/Table/1{2-3}] transferring lease to s2\nI180718 05:26:46.120156 5302 storage/replica_proposal.go:222  [n2,s2,r9/2:/Table/1{2-3}] new range lease repl=(n2,s2):2 seq=4 start=1531891606.104627072,0 epo=1 pro=1531891606.104661485,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891534.101279192,0\nI180718 05:26:46.148404 11764 storage/replica_range_lease.go:617  [replicate,n1,s1,r9/1:/Table/1{2-3}] done transferring lease to s2: <nil>\nI180718 05:26:46.263893 4523 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n3 established\nI180718 05:26:46.265720 4522 rpc/nodedialer/nodedialer.go:90  [n1,import-distsql] connection to n2 established\nI180718 05:26:46.274493 11842 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:26:46.275080 11808 rpc/nodedialer/nodedialer.go:90  [n3] connection to n2 established\nI180718 05:26:46.290499 11713 rpc/nodedialer/nodedialer.go:90  [n2] connection to n3 established\nI180718 05:26:46.350036 11753 rpc/nodedialer/nodedialer.go:90  [import-distsql,n1] connection to n3 established\nI180718 05:26:46.351529 11754 rpc/nodedialer/nodedialer.go:90  [import-distsql,n1] connection to n2 established\nI180718 05:26:46.357956 11861 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:26:46.354547 11809 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:26:47.612386 5245 server/status/runtime.go:255  [n2] runtime stats: 984 MiB RSS, 689 goroutines, 47 MiB/24 MiB/94 MiB GO alloc/idle/total, 147 MiB/182 MiB CGO alloc/total, 2339.87cgo/sec, 0.88/0.05 %(u/s)time, 0.02 %gc (7x)\nI180718 05:26:48.606875 5496 server/status/runtime.go:255  [n3] runtime stats: 989 MiB RSS, 678 goroutines, 71 MiB/3.4 MiB/94 MiB GO alloc/idle/total, 147 MiB/182 MiB CGO alloc/total, 2274.78cgo/sec, 0.91/0.06 %(u/s)time, 0.02 %gc (7x)\nI180718 05:26:53.075204 12193 storage/replica_command.go:279  [n1,s1,r111/1:/{Table/56-Max}] initiating a split of this range at key /Table/57/2/\"O\"/509 [r112]\nI180718 05:26:53.092734 12342 storage/replica_command.go:279  [n1,s1,r111/1:/{Table/56-Max}] initiating a split of this range at key /Table/57/1/4615 [r113]\nI180718 05:26:53.266468 12354 storage/replica_command.go:279  [n1,s1,r111/1:/{Table/56-Max}] initiating a split of this range at key /Table/57/1/741 [r114]\nI180718 05:26:53.582050 4677 storage/replica_proposal.go:222  [n1,s1,r111/1:/{Table/56-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:26:53.877124 12354 storage/replica_command.go:279  [n1,s1,r111/1:/Table/5{6-7/2/\"O\"/…}] initiating a split of this range at key /Table/57/1/741 [r115]\nI180718 05:26:53.877679 12342 storage/replica_command.go:279  [n1,s1,r111/1:/Table/5{6-7/2/\"O\"/…}] initiating a split of this range at key /Table/57/1/4615 [r116]\nI180718 05:26:53.933765 12193 storage/replica_command.go:279  [n1,s1,r112/1:/{Table/57/2/\"…-Max}] initiating a split of this range at key /Table/57/2/\"Q\"/3526 [r117]\nI180718 05:26:54.080248 4724 server/status/runtime.go:255  [n1] runtime stats: 992 MiB RSS, 624 goroutines, 40 MiB/31 MiB/95 MiB GO alloc/idle/total, 143 MiB/182 MiB CGO alloc/total, 1876.93cgo/sec, 0.90/0.08 %(u/s)time, 0.03 %gc (8x)\nI180718 05:26:54.400510 4665 storage/replica_proposal.go:222  [n1,s1,r111/1:/Table/5{6-7/2/\"O\"/…}] 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:26:54.419446 4706 storage/replica_proposal.go:222  [n1,s1,r112/1:/{Table/57/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:26:54.703522 12394 storage/replica_command.go:279  [n1,s1,r115/1:/Table/57/{1/741-2/\"O\"/5…}] initiating a split of this range at key /Table/57/1/4615 [r118]\nI180718 05:26:54.979342 12372 storage/replica_command.go:279  [n1,s1,r115/1:/Table/57/{1/741-2/\"O\"/5…}] initiating a split of this range at key /Table/57/1/1456 [r119]\nI180718 05:26:55.088053 4524 rpc/nodedialer/nodedialer.go:90  [intExec=adopt-job] connection to n3 established\nI180718 05:26:55.210622 12427 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:26:55.274727 4673 storage/replica_proposal.go:222  [n1,s1,r115/1:/Table/57/{1/741-2/\"O\"/5…}] 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:26:55.477699 12372 storage/replica_command.go:279  [n1,s1,r115/1:/Table/57/1/{741-4615}] initiating a split of this range at key /Table/57/1/1456 [r120]\nI180718 05:26:55.562715 12409 storage/replica_command.go:279  [n1,s1,r118/1:/Table/57/{1/4615-2/\"O\"/5…}] initiating a split of this range at key /Table/57/2/\"B\"/3018 [r121]\nI180718 05:26:55.831559 4705 storage/replica_proposal.go:222  [n1,s1,r115/1:/Table/57/1/{741-4615}] 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:26:56.056775 4702 storage/replica_proposal.go:222  [n1,s1,r118/1:/Table/57/{1/4615-2/\"O\"/5…}] 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:26:56.244620 12517 storage/replica_command.go:279  [n1,s1,r120/1:/Table/57/1/{1456-4615}] initiating a split of this range at key /Table/57/1/2171 [r122]\nI180718 05:26:56.704626 12461 storage/replica_command.go:279  [n1,s1,r121/1:/Table/57/2/\"{B\"/3018-O\"/509}] initiating a split of this range at key /Table/57/2/\"F\"/396 [r123]\nI180718 05:26:56.742167 4661 storage/replica_proposal.go:222  [n1,s1,r120/1:/Table/57/1/{1456-4615}] 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:26:56.873012 12447 storage/replica_range_lease.go:554  [n1,s1,r120/1:/Table/57/1/{1456-2171}] transferring lease to s2\nI180718 05:26:57.089903 12447 storage/replica_range_lease.go:617  [n1,s1,r120/1:/Table/57/1/{1456-2171}] done transferring lease to s2: <nil>\nI180718 05:26:57.105405 5332 storage/replica_proposal.go:222  [n2,s2,r120/3:/Table/57/1/{1456-2171}] new range lease repl=(n2,s2):3 seq=4 start=1531891616.906827681,0 epo=1 pro=1531891616.906894192,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:26:57.447617 12598 storage/replica_command.go:279  [n1,s1,r122/1:/Table/57/1/{2171-4615}] initiating a split of this range at key /Table/57/1/2886 [r124]\nI180718 05:26:57.459459 4691 storage/replica_proposal.go:222  [n1,s1,r121/1:/Table/57/2/\"{B\"/3018-O\"/509}] 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:26:57.650030 5245 server/status/runtime.go:255  [n2] runtime stats: 996 MiB RSS, 621 goroutines, 64 MiB/8.2 MiB/95 MiB GO alloc/idle/total, 144 MiB/180 MiB CGO alloc/total, 3101.64cgo/sec, 0.96/0.07 %(u/s)time, 0.03 %gc (7x)\nI180718 05:26:57.682512 12646 storage/replica_range_lease.go:554  [n1,s1,r121/1:/Table/57/2/\"{B\"/3018-F\"/396}] transferring lease to s3\nI180718 05:26:57.812122 5609 storage/replica_proposal.go:222  [n3,s3,r121/2:/Table/57/2/\"{B\"/3018-F\"/396}] new range lease repl=(n3,s3):2 seq=4 start=1531891617.682670600,0 epo=1 pro=1531891617.682699169,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:26:57.837282 12646 storage/replica_range_lease.go:617  [n1,s1,r121/1:/Table/57/2/\"{B\"/3018-F\"/396}] done transferring lease to s3: <nil>\nI180718 05:26:58.187745 4691 storage/replica_proposal.go:222  [n1,s1,r122/1:/Table/57/1/{2171-4615}] 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:26:58.312048 12678 storage/replica_range_lease.go:554  [n1,s1,r122/1:/Table/57/1/2{171-886}] transferring lease to s3\nI180718 05:26:58.368480 5483 storage/replica_proposal.go:222  [n3,s3,r122/2:/Table/57/1/2{171-886}] new range lease repl=(n3,s3):2 seq=4 start=1531891618.312209483,0 epo=1 pro=1531891618.312225066,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:26:58.416709 12678 storage/replica_range_lease.go:617  [n1,s1,r122/1:/Table/57/1/2{171-886}] done transferring lease to s3: <nil>\nI180718 05:26:58.445170 12652 storage/replica_command.go:279  [n1,s1,r123/1:/Table/57/2/\"{F\"/396-O\"/509}] initiating a split of this range at key /Table/57/2/\"I\"/2765 [r125]\nI180718 05:26:58.523502 5496 server/status/runtime.go:255  [n3] runtime stats: 999 MiB RSS, 616 goroutines, 49 MiB/22 MiB/95 MiB GO alloc/idle/total, 143 MiB/180 MiB CGO alloc/total, 3123.04cgo/sec, 0.94/0.07 %(u/s)time, 0.03 %gc (7x)\nI180718 05:26:58.694730 12696 storage/replica_command.go:279  [n1,s1,r124/1:/Table/57/1/{2886-4615}] initiating a split of this range at key /Table/57/1/3601 [r126]\nI180718 05:26:59.557381 5163 rpc/nodedialer/nodedialer.go:90  [intExec=adopt-job] connection to n3 established\nI180718 05:26:59.564292 12666 rpc/nodedialer/nodedialer.go:90  [n3] connection to n2 established\nI180718 05:26:59.747543 4684 storage/replica_proposal.go:222  [n1,s1,r123/1:/Table/57/2/\"{F\"/396-O\"/509}] 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:27:00.047479 4666 storage/replica_proposal.go:222  [n1,s1,r124/1:/Table/57/1/{2886-4615}] 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:27:00.666451 12779 storage/replica_command.go:279  [n1,s1,r126/1:/Table/57/1/{3601-4615}] initiating a split of this range at key /Table/57/1/3900 [r127]\nI180718 05:27:00.846797 12782 storage/replica_command.go:279  [n1,s1,r125/1:/Table/57/2/\"{I\"/2765-O\"/509}] initiating a split of this range at key /Table/57/2/\"K\"/3052 [r128]\nI180718 05:27:01.605950 4666 storage/replica_proposal.go:222  [n1,s1,r126/1:/Table/57/1/{3601-4615}] 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:27:01.774806 4707 storage/replica_proposal.go:222  [n1,s1,r125/1:/Table/57/2/\"{I\"/2765-O\"/509}] 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:27:03.365169 12986 storage/replica_command.go:279  [n1,s1,r117/1:/{Table/57/2/\"…-Max}] initiating a split of this range at key /Table/57/2/\"U\"/983 [r129]\nI180718 05:27:03.803873 4686 storage/replica_proposal.go:222  [n1,s1,r117/1:/{Table/57/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:27:04.106848 4724 server/status/runtime.go:255  [n1] runtime stats: 1019 MiB RSS, 612 goroutines, 65 MiB/9.4 MiB/95 MiB GO alloc/idle/total, 143 MiB/180 MiB CGO alloc/total, 3138.57cgo/sec, 0.89/0.05 %(u/s)time, 0.05 %gc (7x)\nI180718 05:27:04.319896 13029 storage/replica_command.go:279  [n1,s1,r129/1:/{Table/57/2/\"…-Max}] initiating a split of this range at key /Table/57/2/\"X\"/3430 [r130]\nI180718 05:27:04.550755 13016 storage/replica_command.go:279  [n1,s1,r129/1:/{Table/57/2/\"…-Max}] initiating a split of this range at key /Table/57/3/4176/\"Q\"/PrefixEnd [r131]\nI180718 05:27:04.703667 4663 storage/replica_proposal.go:222  [n1,s1,r129/1:/{Table/57/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:27:04.781652 13035 storage/replica_range_lease.go:554  [n1,s1,r129/1:/Table/57/2/\"{U\"/983-X\"/3430}] transferring lease to s3\nI180718 05:27:04.919333 13035 storage/replica_range_lease.go:617  [n1,s1,r129/1:/Table/57/2/\"{U\"/983-X\"/3430}] done transferring lease to s3: <nil>\nI180718 05:27:04.947088 5487 storage/replica_proposal.go:222  [n3,s3,r129/2:/Table/57/2/\"{U\"/983-X\"/3430}] new range lease repl=(n3,s3):2 seq=4 start=1531891624.833986974,0 epo=1 pro=1531891624.834033238,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:27:05.163330 13083 storage/replica_command.go:279  [n1,s1,r130/1:/{Table/57/2/\"…-Max}] initiating a split of this range at key /Table/57/3/248/\"O\"/PrefixEnd [r132]\nI180718 05:27:05.275964 13068 storage/replica_command.go:279  [n1,s1,r130/1:/{Table/57/2/\"…-Max}] initiating a split of this range at key /Table/57/3/4176/\"Q\"/PrefixEnd [r133]\nI180718 05:27:05.564161 4703 storage/replica_proposal.go:222  [n1,s1,r130/1:/{Table/57/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:27:05.697521 13127 storage/replica_range_lease.go:554  [n1,s1,r130/1:/Table/57/{2/\"X\"/3…-3/248/\"…}] transferring lease to s3\nI180718 05:27:05.769637 13053 storage/replica_command.go:279  [n1,s1,r132/1:/{Table/57/3/2…-Max}] initiating a split of this range at key /Table/57/3/4176/\"Q\"/PrefixEnd [r134]\nI180718 05:27:05.771810 5521 storage/replica_proposal.go:222  [n3,s3,r130/2:/Table/57/{2/\"X\"/3…-3/248/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891625.697695305,0 epo=1 pro=1531891625.697748354,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:27:05.827998 13127 storage/replica_range_lease.go:617  [n1,s1,r130/1:/Table/57/{2/\"X\"/3…-3/248/\"…}] done transferring lease to s3: <nil>\nI180718 05:27:06.084136 13157 storage/replica_command.go:279  [n1,s1,r132/1:/{Table/57/3/2…-Max}] initiating a split of this range at key /Table/57/3/916/\"G\"/PrefixEnd [r135]\nI180718 05:27:06.502332 4707 storage/replica_proposal.go:222  [n1,s1,r132/1:/{Table/57/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:27:06.557250 13182 storage/replica_range_lease.go:554  [n1,s1,r132/1:/Table/57/3/{248/\"O…-4176/\"…}] transferring lease to s3\nI180718 05:27:06.609593 13182 storage/replica_range_lease.go:617  [n1,s1,r132/1:/Table/57/3/{248/\"O…-4176/\"…}] done transferring lease to s3: <nil>\nI180718 05:27:06.615282 5484 storage/replica_proposal.go:222  [n3,s3,r132/2:/Table/57/3/{248/\"O…-4176/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:27:06.625265 12996 storage/replica_command.go:279  [n3,s3,r132/2:/Table/57/3/{248/\"O…-4176/\"…}] initiating a split of this range at key /Table/57/3/916/\"G\"/PrefixEnd [r66]\nI180718 05:27:07.156032 13137 storage/replica_command.go:279  [n1,s1,r134/1:/{Table/57/3/4…-Max}] initiating a split of this range at key /Table/57/3/4843/\"H\"/PrefixEnd [r136]\nI180718 05:27:07.607519 5245 server/status/runtime.go:255  [n2] runtime stats: 1022 MiB RSS, 619 goroutines, 50 MiB/21 MiB/95 MiB GO alloc/idle/total, 144 MiB/181 MiB CGO alloc/total, 2724.51cgo/sec, 0.86/0.05 %(u/s)time, 0.05 %gc (7x)\nI180718 05:27:07.635325 5507 storage/replica_proposal.go:222  [n3,s3,r132/2:/Table/57/3/{248/\"O…-4176/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0 following repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0\nI180718 05:27:07.665689 4693 storage/replica_proposal.go:222  [n1,s1,r134/1:/{Table/57/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:27:07.730141 12996 storage/replica_command.go:279  [n3,s3,r66/2:/Table/57/3/{916/\"G…-4176/\"…}] initiating a split of this range at key /Table/57/3/1583/\"X\"/PrefixEnd [r67]\nI180718 05:27:07.762358 13270 storage/replica_range_lease.go:554  [replicate,n1,s1,r134/1:/Table/57/3/4{176/\"Q…-843/\"H…}] transferring lease to s3\nI180718 05:27:07.961135 13270 storage/replica_range_lease.go:617  [replicate,n1,s1,r134/1:/Table/57/3/4{176/\"Q…-843/\"H…}] done transferring lease to s3: <nil>\nI180718 05:27:08.018930 5617 storage/replica_proposal.go:222  [n3,s3,r134/2:/Table/57/3/4{176/\"Q…-843/\"H…}] new range lease repl=(n3,s3):2 seq=4 start=1531891627.762524669,0 epo=1 pro=1531891627.762554195,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:27:08.597126 5496 server/status/runtime.go:255  [n3] runtime stats: 1023 MiB RSS, 619 goroutines, 38 MiB/32 MiB/95 MiB GO alloc/idle/total, 144 MiB/181 MiB CGO alloc/total, 2963.09cgo/sec, 0.86/0.05 %(u/s)time, 0.05 %gc (7x)\nI180718 05:27:08.698905 13352 storage/replica_command.go:279  [n1,s1,r136/1:/{Table/57/3/4…-Max}] initiating a split of this range at key /Table/58 [r137]\nI180718 05:27:08.742820 5640 storage/replica_proposal.go:222  [n3,s3,r66/2:/Table/57/3/{916/\"G…-4176/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0 following repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0\nI180718 05:27:09.330867 12996 storage/replica_command.go:279  [n3,s3,r67/2:/Table/57/3/{1583/\"…-4176/\"…}] initiating a split of this range at key /Table/57/3/2250/\"O\"/PrefixEnd [r68]\nI180718 05:27:09.477404 13374 storage/replica_range_lease.go:554  [replicate,n1,s1,r18/1:/Table/2{1-2}] transferring lease to s2\nI180718 05:27:09.538271 13374 storage/replica_range_lease.go:617  [replicate,n1,s1,r18/1:/Table/2{1-2}] done transferring lease to s2: <nil>\nI180718 05:27:09.546382 4685 storage/replica_proposal.go:222  [n1,s1,r136/1:/{Table/57/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:27:09.633786 13360 storage/replica_range_lease.go:554  [n1,s1,r136/1:/Table/5{7/3/4843…-8}] transferring lease to s2\nI180718 05:27:10.119169 13360 storage/replica_range_lease.go:617  [n1,s1,r136/1:/Table/5{7/3/4843…-8}] done transferring lease to s2: <nil>\nI180718 05:27:10.172855 5323 storage/replica_proposal.go:222  [n2,s2,r18/3:/Table/2{1-2}] new range lease repl=(n2,s2):3 seq=4 start=1531891629.477560422,0 epo=1 pro=1531891629.477593452,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891536.327936518,0\nI180718 05:27:10.196675 5329 storage/replica_proposal.go:222  [n2,s2,r136/3:/Table/5{7/3/4843…-8}] new range lease repl=(n2,s2):3 seq=4 start=1531891629.634001458,0 epo=1 pro=1531891629.634030963,0 following repl=(n1,s1):1 seq=3 start=1531891524.007831293,0 epo=1 pro=1531891532.914834592,0\nI180718 05:27:10.919856 5531 storage/replica_proposal.go:222  [n3,s3,r67/2:/Table/57/3/{1583/\"…-4176/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0 following repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0\nI180718 05:27:11.181903 12996 storage/replica_command.go:279  [n3,s3,r68/2:/Table/57/3/{2250/\"…-4176/\"…}] initiating a split of this range at key /Table/57/3/2917/\"F\"/PrefixEnd [r69]\nI180718 05:27:12.208249 5605 storage/replica_proposal.go:222  [n3,s3,r68/2:/Table/57/3/{2250/\"…-4176/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0 following repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0\nI180718 05:27:12.465227 12996 storage/replica_command.go:279  [n3,s3,r69/2:/Table/57/3/{2917/\"…-4176/\"…}] initiating a split of this range at key /Table/57/3/3510/\"A\" [r70]\nI180718 05:27:13.144112 5638 storage/replica_proposal.go:222  [n3,s3,r69/2:/Table/57/3/{2917/\"…-4176/\"…}] new range lease repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0 following repl=(n3,s3):2 seq=4 start=1531891626.557446328,0 epo=1 pro=1531891626.557478958,0\nI180718 05:27:14.119624 4724 server/status/runtime.go:255  [n1] runtime stats: 1.0 GiB RSS, 589 goroutines, 46 MiB/28 MiB/95 MiB GO alloc/idle/total, 142 MiB/178 MiB CGO alloc/total, 3361.50cgo/sec, 0.93/0.05 %(u/s)time, 0.05 %gc (7x)\nI180718 05:27:14.168053 4525 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:27:14.174462 13597 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\nI180718 05:27:14.285314 4527 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root] connection to n3 established\nI180718 05:27:14.286340 4526 rpc/nodedialer/nodedialer.go:90  [n1,client=127.0.0.1:54394,user=root] connection to n2 established\nI180718 05:27:14.291827 13684 rpc/nodedialer/nodedialer.go:90  [n2] connection to n1 established\nI180718 05:27:14.313736 13699 rpc/nodedialer/nodedialer.go:90  [n3] connection to n1 established\n\n```"},"comment":{"url":"https://api.github.com/repos/cockroachdb/cockroach/issues/comments/406507110","html_url":"https://github.com/cockroachdb/cockroach/issues/27665#issuecomment-406507110","issue_url":"https://api.github.com/repos/cockroachdb/cockroach/issues/27665","id":406507110,"node_id":"MDEyOklzc3VlQ29tbWVudDQwNjUwNzExMA==","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:26Z","updated_at":"2018-07-20T06:57:26Z","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"}}