Running make with -j24 GOPATH set to /home/tschottdorf/go go test -exec 'stress ' -tags ' make x86_64_linux_gnu' -ldflags '-X github.com/cockroachdb/cockroach/pkg/build.typ=development -extldflags "" -X "github.com/cockroachdb/cockroach/pkg/build.tag=v2.2.0-alpha.20181119-478-gf4b28a0129" -X "github.com/cockroachdb/cockroach/pkg/build.rev=f4b28a0129a8673d17fb879cf8dceb4b6f94ccef" -X "github.com/cockroachdb/cockroach/pkg/build.cgoTargetTriple=x86_64-linux-gnu" ' -run "SplitTriggerRaftSnap" -timeout 0 ./pkg/storage/ -v -args -test.timeout 8m 0 runs so far, 0 failures, over 5s 24 runs so far, 0 failures, over 10s 45 runs so far, 0 failures, over 15s 53 runs so far, 0 failures, over 20s 71 runs so far, 0 failures, over 25s 91 runs so far, 0 failures, over 30s 108 runs so far, 0 failures, over 35s 126 runs so far, 0 failures, over 40s 144 runs so far, 0 failures, over 45s 159 runs so far, 0 failures, over 50s 178 runs so far, 0 failures, over 55s 196 runs so far, 0 failures, over 1m0s 215 runs so far, 0 failures, over 1m5s 231 runs so far, 0 failures, over 1m10s 250 runs so far, 0 failures, over 1m15s 266 runs so far, 0 failures, over 1m20s 282 runs so far, 0 failures, over 1m25s 303 runs so far, 0 failures, over 1m30s 317 runs so far, 0 failures, over 1m35s 333 runs so far, 0 failures, over 1m40s 355 runs so far, 0 failures, over 1m45s 375 runs so far, 0 failures, over 1m50s 388 runs so far, 0 failures, over 1m55s 408 runs so far, 0 failures, over 2m0s 423 runs so far, 0 failures, over 2m5s 444 runs so far, 0 failures, over 2m10s 460 runs so far, 0 failures, over 2m15s 475 runs so far, 0 failures, over 2m20s 494 runs so far, 0 failures, over 2m25s 514 runs so far, 0 failures, over 2m30s 532 runs so far, 0 failures, over 2m35s 546 runs so far, 0 failures, over 2m40s 564 runs so far, 0 failures, over 2m45s 582 runs so far, 0 failures, over 2m50s 603 runs so far, 0 failures, over 2m55s 618 runs so far, 0 failures, over 3m0s 639 runs so far, 0 failures, over 3m5s 659 runs so far, 0 failures, over 3m10s 678 runs so far, 0 failures, over 3m15s 690 runs so far, 0 failures, over 3m20s 711 runs so far, 0 failures, over 3m25s 728 runs so far, 0 failures, over 3m30s 748 runs so far, 0 failures, over 3m35s 766 runs so far, 0 failures, over 3m40s 780 runs so far, 0 failures, over 3m45s 801 runs so far, 0 failures, over 3m50s 819 runs so far, 0 failures, over 3m55s 837 runs so far, 0 failures, over 4m0s 852 runs so far, 0 failures, over 4m5s 870 runs so far, 0 failures, over 4m10s 884 runs so far, 0 failures, over 4m15s 905 runs so far, 0 failures, over 4m20s 922 runs so far, 0 failures, over 4m25s 944 runs so far, 0 failures, over 4m30s 958 runs so far, 0 failures, over 4m35s 975 runs so far, 0 failures, over 4m40s 987 runs so far, 0 failures, over 4m45s 1005 runs so far, 0 failures, over 4m50s 1023 runs so far, 0 failures, over 4m55s 1041 runs so far, 0 failures, over 5m0s 1062 runs so far, 0 failures, over 5m5s 1077 runs so far, 0 failures, over 5m10s 1093 runs so far, 0 failures, over 5m15s 1112 runs so far, 0 failures, over 5m20s 1130 runs so far, 0 failures, over 5m25s 1144 runs so far, 0 failures, over 5m30s 1162 runs so far, 0 failures, over 5m35s 1180 runs so far, 0 failures, over 5m40s 1197 runs so far, 0 failures, over 5m45s 1217 runs so far, 0 failures, over 5m50s 1229 runs so far, 0 failures, over 5m55s 1246 runs so far, 0 failures, over 6m0s 1266 runs so far, 0 failures, over 6m5s 1285 runs so far, 0 failures, over 6m10s 1304 runs so far, 0 failures, over 6m15s 1317 runs so far, 0 failures, over 6m20s 1335 runs so far, 0 failures, over 6m25s 1354 runs so far, 0 failures, over 6m30s 1372 runs so far, 0 failures, over 6m35s 1389 runs so far, 0 failures, over 6m40s 1406 runs so far, 0 failures, over 6m45s 1421 runs so far, 0 failures, over 6m50s 1438 runs so far, 0 failures, over 6m55s 1454 runs so far, 0 failures, over 7m0s 1473 runs so far, 0 failures, over 7m5s 1493 runs so far, 0 failures, over 7m10s 1509 runs so far, 0 failures, over 7m15s 1524 runs so far, 0 failures, over 7m20s 1542 runs so far, 0 failures, over 7m25s 1559 runs so far, 0 failures, over 7m30s 1577 runs so far, 0 failures, over 7m35s 1598 runs so far, 0 failures, over 7m40s 1617 runs so far, 0 failures, over 7m45s 1634 runs so far, 0 failures, over 7m50s 1649 runs so far, 0 failures, over 7m55s 1668 runs so far, 0 failures, over 8m0s 1686 runs so far, 0 failures, over 8m5s 1704 runs so far, 0 failures, over 8m10s 1717 runs so far, 0 failures, over 8m15s 1738 runs so far, 0 failures, over 8m20s 1757 runs so far, 0 failures, over 8m25s 1775 runs so far, 0 failures, over 8m30s 1791 runs so far, 0 failures, over 8m35s 1810 runs so far, 0 failures, over 8m40s 1824 runs so far, 0 failures, over 8m45s 1846 runs so far, 0 failures, over 8m50s 1863 runs so far, 0 failures, over 8m55s 1876 runs so far, 0 failures, over 9m0s 1897 runs so far, 0 failures, over 9m5s 1914 runs so far, 0 failures, over 9m10s 1933 runs so far, 0 failures, over 9m15s 1950 runs so far, 0 failures, over 9m20s 1971 runs so far, 0 failures, over 9m25s 1987 runs so far, 0 failures, over 9m30s 2004 runs so far, 0 failures, over 9m35s 2024 runs so far, 0 failures, over 9m40s 2041 runs so far, 0 failures, over 9m45s 2057 runs so far, 0 failures, over 9m50s 2074 runs so far, 0 failures, over 9m55s 2091 runs so far, 0 failures, over 10m0s 2109 runs so far, 0 failures, over 10m5s 2126 runs so far, 0 failures, over 10m10s 2145 runs so far, 0 failures, over 10m15s 2162 runs so far, 0 failures, over 10m20s 2181 runs so far, 0 failures, over 10m25s 2197 runs so far, 0 failures, over 10m30s 2217 runs so far, 0 failures, over 10m35s 2228 runs so far, 0 failures, over 10m40s 2248 runs so far, 0 failures, over 10m45s 2267 runs so far, 0 failures, over 10m50s 2286 runs so far, 0 failures, over 10m55s 2301 runs so far, 0 failures, over 11m0s 2320 runs so far, 0 failures, over 11m5s 2337 runs so far, 0 failures, over 11m10s 2350 runs so far, 0 failures, over 11m15s 2367 runs so far, 0 failures, over 11m20s 2385 runs so far, 0 failures, over 11m25s 2403 runs so far, 0 failures, over 11m30s 2421 runs so far, 0 failures, over 11m35s 2438 runs so far, 0 failures, over 11m40s 2456 runs so far, 0 failures, over 11m45s 2470 runs so far, 0 failures, over 11m50s 2489 runs so far, 0 failures, over 11m55s 2509 runs so far, 0 failures, over 12m0s 2526 runs so far, 0 failures, over 12m5s 2543 runs so far, 0 failures, over 12m10s 2558 runs so far, 0 failures, over 12m15s 2576 runs so far, 0 failures, over 12m20s 2595 runs so far, 0 failures, over 12m25s 2614 runs so far, 0 failures, over 12m30s 2629 runs so far, 0 failures, over 12m35s 2648 runs so far, 0 failures, over 12m40s 2666 runs so far, 0 failures, over 12m45s 2684 runs so far, 0 failures, over 12m50s 2702 runs so far, 0 failures, over 12m55s 2715 runs so far, 0 failures, over 13m0s 2735 runs so far, 0 failures, over 13m5s 2749 runs so far, 0 failures, over 13m10s 2767 runs so far, 0 failures, over 13m15s 2783 runs so far, 0 failures, over 13m20s 2800 runs so far, 0 failures, over 13m25s 2819 runs so far, 0 failures, over 13m30s 2837 runs so far, 0 failures, over 13m35s 2854 runs so far, 0 failures, over 13m40s 2871 runs so far, 0 failures, over 13m45s 2889 runs so far, 0 failures, over 13m50s 2907 runs so far, 0 failures, over 13m55s 2924 runs so far, 0 failures, over 14m0s 2945 runs so far, 0 failures, over 14m5s 2962 runs so far, 0 failures, over 14m10s 2977 runs so far, 0 failures, over 14m15s 2993 runs so far, 0 failures, over 14m20s 3009 runs so far, 0 failures, over 14m25s 3028 runs so far, 0 failures, over 14m30s 3044 runs so far, 0 failures, over 14m35s 3063 runs so far, 0 failures, over 14m40s 3081 runs so far, 0 failures, over 14m45s 3096 runs so far, 0 failures, over 14m50s 3112 runs so far, 0 failures, over 14m55s 3129 runs so far, 0 failures, over 15m0s 3148 runs so far, 0 failures, over 15m5s 3161 runs so far, 0 failures, over 15m10s 3182 runs so far, 0 failures, over 15m15s 3200 runs so far, 0 failures, over 15m20s 3217 runs so far, 0 failures, over 15m25s 3231 runs so far, 0 failures, over 15m30s 3249 runs so far, 0 failures, over 15m35s 3268 runs so far, 0 failures, over 15m40s 3289 runs so far, 0 failures, over 15m45s 3305 runs so far, 0 failures, over 15m50s 3321 runs so far, 0 failures, over 15m55s 3338 runs so far, 0 failures, over 16m0s 3356 runs so far, 0 failures, over 16m5s 3373 runs so far, 0 failures, over 16m10s 3390 runs so far, 0 failures, over 16m15s 3409 runs so far, 0 failures, over 16m20s 3428 runs so far, 0 failures, over 16m25s 3445 runs so far, 0 failures, over 16m30s 3462 runs so far, 0 failures, over 16m35s 3478 runs so far, 0 failures, over 16m40s 3497 runs so far, 0 failures, over 16m45s 3515 runs so far, 0 failures, over 16m50s 3529 runs so far, 0 failures, over 16m55s 3550 runs so far, 0 failures, over 17m0s 3568 runs so far, 0 failures, over 17m5s 3585 runs so far, 0 failures, over 17m10s 3597 runs so far, 0 failures, over 17m15s 3620 runs so far, 0 failures, over 17m20s 3637 runs so far, 0 failures, over 17m25s 3654 runs so far, 0 failures, over 17m30s 3674 runs so far, 0 failures, over 17m35s 3688 runs so far, 0 failures, over 17m40s 3708 runs so far, 0 failures, over 17m45s 3726 runs so far, 0 failures, over 17m50s 3743 runs so far, 0 failures, over 17m55s 3760 runs so far, 0 failures, over 18m0s 3776 runs so far, 0 failures, over 18m5s 3793 runs so far, 0 failures, over 18m10s 3812 runs so far, 0 failures, over 18m15s 3830 runs so far, 0 failures, over 18m20s 3848 runs so far, 0 failures, over 18m25s 3865 runs so far, 0 failures, over 18m30s 3878 runs so far, 0 failures, over 18m35s 3896 runs so far, 0 failures, over 18m40s 3917 runs so far, 0 failures, over 18m45s 3935 runs so far, 0 failures, over 18m50s 3952 runs so far, 0 failures, over 18m55s 3965 runs so far, 0 failures, over 19m0s 3984 runs so far, 0 failures, over 19m5s 4006 runs so far, 0 failures, over 19m10s 4022 runs so far, 0 failures, over 19m15s 4036 runs so far, 0 failures, over 19m20s 4056 runs so far, 0 failures, over 19m25s 4070 runs so far, 0 failures, over 19m30s 4086 runs so far, 0 failures, over 19m35s 4106 runs so far, 0 failures, over 19m40s 4124 runs so far, 0 failures, over 19m45s 4140 runs so far, 0 failures, over 19m50s 4153 runs so far, 0 failures, over 19m55s 4174 runs so far, 0 failures, over 20m0s 4190 runs so far, 0 failures, over 20m5s 4211 runs so far, 0 failures, over 20m10s 4225 runs so far, 0 failures, over 20m15s 4244 runs so far, 0 failures, over 20m20s 4257 runs so far, 0 failures, over 20m25s 4276 runs so far, 0 failures, over 20m30s 4296 runs so far, 0 failures, over 20m35s 4312 runs so far, 0 failures, over 20m40s 4329 runs so far, 0 failures, over 20m45s 4348 runs so far, 0 failures, over 20m50s 4365 runs so far, 0 failures, over 20m55s 4386 runs so far, 0 failures, over 21m0s 4399 runs so far, 0 failures, over 21m5s 4418 runs so far, 0 failures, over 21m10s 4433 runs so far, 0 failures, over 21m15s 4450 runs so far, 0 failures, over 21m20s 4471 runs so far, 0 failures, over 21m25s 4489 runs so far, 0 failures, over 21m30s 4502 runs so far, 0 failures, over 21m35s 4524 runs so far, 0 failures, over 21m40s 4540 runs so far, 0 failures, over 21m45s 4560 runs so far, 0 failures, over 21m50s 4573 runs so far, 0 failures, over 21m55s 4593 runs so far, 0 failures, over 22m0s 4615 runs so far, 0 failures, over 22m5s 4630 runs so far, 0 failures, over 22m10s 4646 runs so far, 0 failures, over 22m15s 4663 runs so far, 0 failures, over 22m20s 4680 runs so far, 0 failures, over 22m25s 4700 runs so far, 0 failures, over 22m30s 4719 runs so far, 0 failures, over 22m35s 4734 runs so far, 0 failures, over 22m40s 4748 runs so far, 0 failures, over 22m45s 4766 runs so far, 0 failures, over 22m50s 4786 runs so far, 0 failures, over 22m55s 4804 runs so far, 0 failures, over 23m0s 4822 runs so far, 0 failures, over 23m5s 4837 runs so far, 0 failures, over 23m10s 4856 runs so far, 0 failures, over 23m15s 4876 runs so far, 0 failures, over 23m20s 4894 runs so far, 0 failures, over 23m25s 4912 runs so far, 0 failures, over 23m30s 4923 runs so far, 0 failures, over 23m35s 4944 runs so far, 0 failures, over 23m40s 4963 runs so far, 0 failures, over 23m45s 4982 runs so far, 0 failures, over 23m50s 4997 runs so far, 0 failures, over 23m55s 5015 runs so far, 0 failures, over 24m0s 5030 runs so far, 0 failures, over 24m5s 5047 runs so far, 0 failures, over 24m10s 5067 runs so far, 0 failures, over 24m15s 5086 runs so far, 0 failures, over 24m20s 5105 runs so far, 0 failures, over 24m25s 5121 runs so far, 0 failures, over 24m30s 5138 runs so far, 0 failures, over 24m35s 5154 runs so far, 0 failures, over 24m40s 5173 runs so far, 0 failures, over 24m45s 5192 runs so far, 0 failures, over 24m50s 5207 runs so far, 0 failures, over 24m55s 5226 runs so far, 0 failures, over 25m0s 5242 runs so far, 0 failures, over 25m5s 5262 runs so far, 0 failures, over 25m10s 5283 runs so far, 0 failures, over 25m15s 5298 runs so far, 0 failures, over 25m20s 5311 runs so far, 0 failures, over 25m25s 5331 runs so far, 0 failures, over 25m30s 5349 runs so far, 0 failures, over 25m35s 5365 runs so far, 0 failures, over 25m40s 5382 runs so far, 0 failures, over 25m45s 5398 runs so far, 0 failures, over 25m50s 5417 runs so far, 0 failures, over 25m55s 5433 runs so far, 0 failures, over 26m0s 5453 runs so far, 0 failures, over 26m5s 5474 runs so far, 0 failures, over 26m10s 5486 runs so far, 0 failures, over 26m15s 5503 runs so far, 0 failures, over 26m20s 5522 runs so far, 0 failures, over 26m25s 5536 runs so far, 0 failures, over 26m30s 5555 runs so far, 0 failures, over 26m35s 5572 runs so far, 0 failures, over 26m40s 5590 runs so far, 0 failures, over 26m45s 5604 runs so far, 0 failures, over 26m50s 5622 runs so far, 0 failures, over 26m55s 5644 runs so far, 0 failures, over 27m0s 5660 runs so far, 0 failures, over 27m5s 5674 runs so far, 0 failures, over 27m10s 5695 runs so far, 0 failures, over 27m15s 5710 runs so far, 0 failures, over 27m20s 5732 runs so far, 0 failures, over 27m25s 5751 runs so far, 0 failures, over 27m30s 5762 runs so far, 0 failures, over 27m35s 5780 runs so far, 0 failures, over 27m40s 5801 runs so far, 0 failures, over 27m45s 5817 runs so far, 0 failures, over 27m50s 5836 runs so far, 0 failures, over 27m55s 5858 runs so far, 0 failures, over 28m0s 5871 runs so far, 0 failures, over 28m5s 5885 runs so far, 0 failures, over 28m10s 5907 runs so far, 0 failures, over 28m15s 5924 runs so far, 0 failures, over 28m20s 5940 runs so far, 0 failures, over 28m25s 5959 runs so far, 0 failures, over 28m30s 5973 runs so far, 0 failures, over 28m35s 5991 runs so far, 0 failures, over 28m40s 6012 runs so far, 0 failures, over 28m45s 6033 runs so far, 0 failures, over 28m50s 6041 runs so far, 0 failures, over 28m55s 6061 runs so far, 0 failures, over 29m0s 6081 runs so far, 0 failures, over 29m5s 6098 runs so far, 0 failures, over 29m10s 6111 runs so far, 0 failures, over 29m15s 6131 runs so far, 0 failures, over 29m20s 6151 runs so far, 0 failures, over 29m25s 6164 runs so far, 0 failures, over 29m30s 6183 runs so far, 0 failures, over 29m35s 6199 runs so far, 0 failures, over 29m40s 6218 runs so far, 0 failures, over 29m45s 6234 runs so far, 0 failures, over 29m50s 6255 runs so far, 0 failures, over 29m55s 6272 runs so far, 0 failures, over 30m0s 6290 runs so far, 0 failures, over 30m5s 6307 runs so far, 0 failures, over 30m10s 6322 runs so far, 0 failures, over 30m15s 6343 runs so far, 0 failures, over 30m20s 6362 runs so far, 0 failures, over 30m25s 6380 runs so far, 0 failures, over 30m30s 6395 runs so far, 0 failures, over 30m35s 6409 runs so far, 0 failures, over 30m40s 6426 runs so far, 0 failures, over 30m45s 6443 runs so far, 0 failures, over 30m50s 6461 runs so far, 0 failures, over 30m55s 6479 runs so far, 0 failures, over 31m0s 6495 runs so far, 0 failures, over 31m5s 6515 runs so far, 0 failures, over 31m10s 6531 runs so far, 0 failures, over 31m15s 6546 runs so far, 0 failures, over 31m20s 6569 runs so far, 0 failures, over 31m25s 6586 runs so far, 0 failures, over 31m30s 6601 runs so far, 0 failures, over 31m35s 6621 runs so far, 0 failures, over 31m40s 6637 runs so far, 0 failures, over 31m45s 6656 runs so far, 0 failures, over 31m50s 6674 runs so far, 0 failures, over 31m55s 6690 runs so far, 0 failures, over 32m0s 6708 runs so far, 0 failures, over 32m5s 6727 runs so far, 0 failures, over 32m10s 6744 runs so far, 0 failures, over 32m15s 6762 runs so far, 0 failures, over 32m20s 6780 runs so far, 0 failures, over 32m25s 6798 runs so far, 0 failures, over 32m30s 6815 runs so far, 0 failures, over 32m35s 6828 runs so far, 0 failures, over 32m40s 6844 runs so far, 0 failures, over 32m45s 6862 runs so far, 0 failures, over 32m50s 6881 runs so far, 0 failures, over 32m55s 6899 runs so far, 0 failures, over 33m0s 6915 runs so far, 0 failures, over 33m5s 6928 runs so far, 0 failures, over 33m10s 6946 runs so far, 0 failures, over 33m15s 6965 runs so far, 0 failures, over 33m20s 6982 runs so far, 0 failures, over 33m25s 7001 runs so far, 0 failures, over 33m30s 7017 runs so far, 0 failures, over 33m35s 7034 runs so far, 0 failures, over 33m40s 7052 runs so far, 0 failures, over 33m45s 7071 runs so far, 0 failures, over 33m50s 7090 runs so far, 0 failures, over 33m55s 7109 runs so far, 0 failures, over 34m0s 7122 runs so far, 0 failures, over 34m5s 7141 runs so far, 0 failures, over 34m10s 7161 runs so far, 0 failures, over 34m15s 7177 runs so far, 0 failures, over 34m20s 7192 runs so far, 0 failures, over 34m25s 7213 runs so far, 0 failures, over 34m30s 7230 runs so far, 0 failures, over 34m35s 7251 runs so far, 0 failures, over 34m40s 7263 runs so far, 0 failures, over 34m45s 7281 runs so far, 0 failures, over 34m50s 7300 runs so far, 0 failures, over 34m55s 7318 runs so far, 0 failures, over 35m0s 7333 runs so far, 0 failures, over 35m5s 7349 runs so far, 0 failures, over 35m10s 7367 runs so far, 0 failures, over 35m15s 7385 runs so far, 0 failures, over 35m20s 7402 runs so far, 0 failures, over 35m25s 7419 runs so far, 0 failures, over 35m30s 7437 runs so far, 0 failures, over 35m35s 7457 runs so far, 0 failures, over 35m40s 7470 runs so far, 0 failures, over 35m45s 7486 runs so far, 0 failures, over 35m50s 7505 runs so far, 0 failures, over 35m55s 7523 runs so far, 0 failures, over 36m0s 7539 runs so far, 0 failures, over 36m5s 7555 runs so far, 0 failures, over 36m10s 7574 runs so far, 0 failures, over 36m15s 7590 runs so far, 0 failures, over 36m20s 7610 runs so far, 0 failures, over 36m25s 7630 runs so far, 0 failures, over 36m30s 7647 runs so far, 0 failures, over 36m35s 7660 runs so far, 0 failures, over 36m40s 7680 runs so far, 0 failures, over 36m45s 7700 runs so far, 0 failures, over 36m50s 7716 runs so far, 0 failures, over 36m55s 7732 runs so far, 0 failures, over 37m0s 7751 runs so far, 0 failures, over 37m5s 7767 runs so far, 0 failures, over 37m10s 7786 runs so far, 0 failures, over 37m15s 7803 runs so far, 0 failures, over 37m20s 7817 runs so far, 0 failures, over 37m25s 7837 runs so far, 0 failures, over 37m30s 7855 runs so far, 0 failures, over 37m35s 7872 runs so far, 0 failures, over 37m40s 7888 runs so far, 0 failures, over 37m45s 7907 runs so far, 0 failures, over 37m50s 7924 runs so far, 0 failures, over 37m55s 7944 runs so far, 0 failures, over 38m0s 7960 runs so far, 0 failures, over 38m5s 7973 runs so far, 0 failures, over 38m10s 7992 runs so far, 0 failures, over 38m15s 8011 runs so far, 0 failures, over 38m20s 8029 runs so far, 0 failures, over 38m25s 8047 runs so far, 0 failures, over 38m30s 8060 runs so far, 0 failures, over 38m35s 8080 runs so far, 0 failures, over 38m40s 8096 runs so far, 0 failures, over 38m45s 8115 runs so far, 0 failures, over 38m50s 8132 runs so far, 0 failures, over 38m55s 8149 runs so far, 0 failures, over 39m0s 8167 runs so far, 0 failures, over 39m5s 8187 runs so far, 0 failures, over 39m10s 8200 runs so far, 0 failures, over 39m15s 8221 runs so far, 0 failures, over 39m20s 8238 runs so far, 0 failures, over 39m25s 8253 runs so far, 0 failures, over 39m30s 8273 runs so far, 0 failures, over 39m35s 8292 runs so far, 0 failures, over 39m40s 8309 runs so far, 0 failures, over 39m45s 8328 runs so far, 0 failures, over 39m50s 8345 runs so far, 0 failures, over 39m55s 8360 runs so far, 0 failures, over 40m0s 8379 runs so far, 0 failures, over 40m5s 8395 runs so far, 0 failures, over 40m10s 8410 runs so far, 0 failures, over 40m15s 8429 runs so far, 0 failures, over 40m20s 8446 runs so far, 0 failures, over 40m25s 8465 runs so far, 0 failures, over 40m30s 8480 runs so far, 0 failures, over 40m35s 8498 runs so far, 0 failures, over 40m40s 8512 runs so far, 0 failures, over 40m45s 8532 runs so far, 0 failures, over 40m50s 8549 runs so far, 0 failures, over 40m55s 8568 runs so far, 0 failures, over 41m0s 8583 runs so far, 0 failures, over 41m5s 8599 runs so far, 0 failures, over 41m10s 8616 runs so far, 0 failures, over 41m15s 8635 runs so far, 0 failures, over 41m20s 8653 runs so far, 0 failures, over 41m25s 8672 runs so far, 0 failures, over 41m30s 8687 runs so far, 0 failures, over 41m35s 8703 runs so far, 0 failures, over 41m40s 8722 runs so far, 0 failures, over 41m45s 8742 runs so far, 0 failures, over 41m50s 8752 runs so far, 0 failures, over 41m55s 8767 runs so far, 0 failures, over 42m0s 8787 runs so far, 0 failures, over 42m5s 8800 runs so far, 0 failures, over 42m10s 8819 runs so far, 0 failures, over 42m15s 8838 runs so far, 0 failures, over 42m20s 8857 runs so far, 0 failures, over 42m25s 8873 runs so far, 0 failures, over 42m30s 8890 runs so far, 0 failures, over 42m35s 8909 runs so far, 0 failures, over 42m40s 8922 runs so far, 0 failures, over 42m45s 8937 runs so far, 0 failures, over 42m50s 8956 runs so far, 0 failures, over 42m55s 8978 runs so far, 0 failures, over 43m0s 8996 runs so far, 0 failures, over 43m5s 9008 runs so far, 0 failures, over 43m10s 9027 runs so far, 0 failures, over 43m15s 9047 runs so far, 0 failures, over 43m20s 9063 runs so far, 0 failures, over 43m25s 9082 runs so far, 0 failures, over 43m30s 9101 runs so far, 0 failures, over 43m35s 9115 runs so far, 0 failures, over 43m40s 9131 runs so far, 0 failures, over 43m45s 9152 runs so far, 0 failures, over 43m50s 9169 runs so far, 0 failures, over 43m55s 9188 runs so far, 0 failures, over 44m0s 9199 runs so far, 0 failures, over 44m5s 9221 runs so far, 0 failures, over 44m10s 9240 runs so far, 0 failures, over 44m15s 9254 runs so far, 0 failures, over 44m20s 9272 runs so far, 0 failures, over 44m25s 9291 runs so far, 0 failures, over 44m30s 9309 runs so far, 0 failures, over 44m35s 9325 runs so far, 0 failures, over 44m40s 9344 runs so far, 0 failures, over 44m45s 9361 runs so far, 0 failures, over 44m50s 9377 runs so far, 0 failures, over 44m55s 9395 runs so far, 0 failures, over 45m0s 9411 runs so far, 0 failures, over 45m5s 9428 runs so far, 0 failures, over 45m10s 9447 runs so far, 0 failures, over 45m15s 9464 runs so far, 0 failures, over 45m20s 9483 runs so far, 0 failures, over 45m25s 9501 runs so far, 0 failures, over 45m30s 9520 runs so far, 0 failures, over 45m35s 9535 runs so far, 0 failures, over 45m40s 9551 runs so far, 0 failures, over 45m45s 9569 runs so far, 0 failures, over 45m50s 9587 runs so far, 0 failures, over 45m55s 9606 runs so far, 0 failures, over 46m0s 9623 runs so far, 0 failures, over 46m5s 9644 runs so far, 0 failures, over 46m10s 9657 runs so far, 0 failures, over 46m15s 9675 runs so far, 0 failures, over 46m20s 9693 runs so far, 0 failures, over 46m25s 9707 runs so far, 0 failures, over 46m30s 9725 runs so far, 0 failures, over 46m35s 9745 runs so far, 0 failures, over 46m40s 9762 runs so far, 0 failures, over 46m45s 9777 runs so far, 0 failures, over 46m50s 9797 runs so far, 0 failures, over 46m55s 9815 runs so far, 0 failures, over 47m0s 9834 runs so far, 0 failures, over 47m5s 9846 runs so far, 0 failures, over 47m10s 9868 runs so far, 0 failures, over 47m15s 9887 runs so far, 0 failures, over 47m20s 9906 runs so far, 0 failures, over 47m25s 9918 runs so far, 0 failures, over 47m30s 9939 runs so far, 0 failures, over 47m35s 9955 runs so far, 0 failures, over 47m40s 9976 runs so far, 0 failures, over 47m45s 9993 runs so far, 0 failures, over 47m50s 10010 runs so far, 0 failures, over 47m55s 10027 runs so far, 0 failures, over 48m0s 10046 runs so far, 0 failures, over 48m5s 10062 runs so far, 0 failures, over 48m10s 10082 runs so far, 0 failures, over 48m15s 10096 runs so far, 0 failures, over 48m20s 10116 runs so far, 0 failures, over 48m25s 10135 runs so far, 0 failures, over 48m30s 10152 runs so far, 0 failures, over 48m35s 10170 runs so far, 0 failures, over 48m40s 10188 runs so far, 0 failures, over 48m45s 10204 runs so far, 0 failures, over 48m50s 10223 runs so far, 0 failures, over 48m55s 10239 runs so far, 0 failures, over 49m0s 10259 runs so far, 0 failures, over 49m5s 10278 runs so far, 0 failures, over 49m10s 10291 runs so far, 0 failures, over 49m15s 10308 runs so far, 0 failures, over 49m20s 10327 runs so far, 0 failures, over 49m25s 10344 runs so far, 0 failures, over 49m30s 10361 runs so far, 0 failures, over 49m35s 10375 runs so far, 0 failures, over 49m40s 10391 runs so far, 0 failures, over 49m45s 10412 runs so far, 0 failures, over 49m50s 10431 runs so far, 0 failures, over 49m55s 10444 runs so far, 0 failures, over 50m0s 10461 runs so far, 0 failures, over 50m5s 10481 runs so far, 0 failures, over 50m10s 10500 runs so far, 0 failures, over 50m15s 10517 runs so far, 0 failures, over 50m20s 10536 runs so far, 0 failures, over 50m25s 10548 runs so far, 0 failures, over 50m30s 10571 runs so far, 0 failures, over 50m35s 10590 runs so far, 0 failures, over 50m40s 10608 runs so far, 0 failures, over 50m45s 10624 runs so far, 0 failures, over 50m50s 10644 runs so far, 0 failures, over 50m55s 10662 runs so far, 0 failures, over 51m0s 10677 runs so far, 0 failures, over 51m5s 10696 runs so far, 0 failures, over 51m10s 10713 runs so far, 0 failures, over 51m15s 10732 runs so far, 0 failures, over 51m20s 10750 runs so far, 0 failures, over 51m25s 10761 runs so far, 0 failures, over 51m30s 10779 runs so far, 0 failures, over 51m35s 10800 runs so far, 0 failures, over 51m40s 10819 runs so far, 0 failures, over 51m45s 10835 runs so far, 0 failures, over 51m50s 10853 runs so far, 0 failures, over 51m55s 10870 runs so far, 0 failures, over 52m0s 10888 runs so far, 0 failures, over 52m5s 10905 runs so far, 0 failures, over 52m10s 10923 runs so far, 0 failures, over 52m15s 10941 runs so far, 0 failures, over 52m20s 10957 runs so far, 0 failures, over 52m25s 10979 runs so far, 0 failures, over 52m30s 10996 runs so far, 0 failures, over 52m35s 11010 runs so far, 0 failures, over 52m40s 11029 runs so far, 0 failures, over 52m45s 11048 runs so far, 0 failures, over 52m50s 11067 runs so far, 0 failures, over 52m55s 11080 runs so far, 0 failures, over 53m0s 11099 runs so far, 0 failures, over 53m5s 11117 runs so far, 0 failures, over 53m10s 11134 runs so far, 0 failures, over 53m15s 11148 runs so far, 0 failures, over 53m20s 11168 runs so far, 0 failures, over 53m25s 11189 runs so far, 0 failures, over 53m30s 11207 runs so far, 0 failures, over 53m35s 11219 runs so far, 0 failures, over 53m40s 11238 runs so far, 0 failures, over 53m45s 11257 runs so far, 0 failures, over 53m50s 11275 runs so far, 0 failures, over 53m55s 11289 runs so far, 0 failures, over 54m0s 11305 runs so far, 0 failures, over 54m5s 11325 runs so far, 0 failures, over 54m10s 11344 runs so far, 0 failures, over 54m15s 11362 runs so far, 0 failures, over 54m20s 11379 runs so far, 0 failures, over 54m25s 11397 runs so far, 0 failures, over 54m30s 11414 runs so far, 0 failures, over 54m35s 11432 runs so far, 0 failures, over 54m40s 11451 runs so far, 0 failures, over 54m45s 11466 runs so far, 0 failures, over 54m50s 11485 runs so far, 0 failures, over 54m55s 11501 runs so far, 0 failures, over 55m0s 11519 runs so far, 0 failures, over 55m5s 11538 runs so far, 0 failures, over 55m10s 11553 runs so far, 0 failures, over 55m15s 11571 runs so far, 0 failures, over 55m20s 11590 runs so far, 0 failures, over 55m25s 11607 runs so far, 0 failures, over 55m30s 11626 runs so far, 0 failures, over 55m35s 11639 runs so far, 0 failures, over 55m40s 11659 runs so far, 0 failures, over 55m45s 11680 runs so far, 0 failures, over 55m50s 11696 runs so far, 0 failures, over 55m55s 11714 runs so far, 0 failures, over 56m0s 11727 runs so far, 0 failures, over 56m5s 11746 runs so far, 0 failures, over 56m10s 11765 runs so far, 0 failures, over 56m15s 11784 runs so far, 0 failures, over 56m20s 11801 runs so far, 0 failures, over 56m25s 11817 runs so far, 0 failures, over 56m30s 11836 runs so far, 0 failures, over 56m35s 11849 runs so far, 0 failures, over 56m40s 11867 runs so far, 0 failures, over 56m45s 11884 runs so far, 0 failures, over 56m50s 11902 runs so far, 0 failures, over 56m55s 11921 runs so far, 0 failures, over 57m0s 11939 runs so far, 0 failures, over 57m5s I181203 12:45:23.695291 1 rand.go:75 Random seed: 4569118365666636776 === RUN TestSplitTriggerRaftSnapshotRace W181203 12:45:23.752450 15 server/status/runtime.go:295 [n?] Could not parse build timestamp: parsing time "" as "2006/01/02 15:04:05": cannot parse "" as "2006" I181203 12:45:23.782067 15 server/server.go:849 [n?] monitoring forward clock jumps based on server.clock.forward_jump_check_enabled I181203 12:45:23.782655 15 base/addr_validation.go:279 [n?] server certificate addresses: IP=127.0.0.1,::1; DNS=localhost,*.local; CN=node I181203 12:45:23.782690 15 base/addr_validation.go:319 [n?] web UI certificate addresses: IP=127.0.0.1,::1; DNS=localhost,*.local; CN=node I181203 12:45:23.786743 15 server/config.go:484 [n?] 1 storage engine initialized I181203 12:45:23.786774 15 server/config.go:487 [n?] RocksDB cache size: 128 MiB I181203 12:45:23.786790 15 server/config.go:487 [n?] store 0: in-memory, size 0 B I181203 12:45:23.810859 15 server/node.go:370 [n?] **** cluster b0f3163d-fb25-4a28-9f73-3ce2b181a98a has been created I181203 12:45:23.810904 15 server/server.go:1395 [n?] **** add additional nodes by specifying --join=127.0.0.1:46159 I181203 12:45:23.811357 15 gossip/gossip.go:393 [n1] NodeDescriptor set to node_id:1 address: attrs:<> locality:<> ServerVersion: build_tag:"v2.2.0-alpha.20181119-478-gf4b28a0129" started_at:1543841123811172490 I181203 12:45:23.812261 15 server/node.go:474 [n1] initialized store [n1,s1]: disk (capacity=512 MiB, available=512 MiB, used=0 B, logicalBytes=7.1 KiB), ranges=1, leases=0, queries=0.00, writes=0.00, bytesPerReplica={p10=7227.00 p25=7227.00 p50=7227.00 p75=7227.00 p90=7227.00 pMax=7227.00}, writesPerReplica={p10=0.00 p25=0.00 p50=0.00 p75=0.00 p90=0.00 pMax=0.00} I181203 12:45:23.812351 15 storage/stores.go:242 [n1] read 0 node addresses from persistent storage I181203 12:45:23.812584 15 server/node.go:697 [n1] connecting to gossip network to verify cluster ID... I181203 12:45:23.814357 15 server/node.go:722 [n1] node connected via gossip and verified as part of cluster "b0f3163d-fb25-4a28-9f73-3ce2b181a98a" I181203 12:45:23.814395 15 server/node.go:546 [n1] node=1: started with [=] engine(s) and attributes [] I181203 12:45:23.814595 15 server/status/recorder.go:610 [n1] available memory from cgroups (8.0 EiB) exceeds system memory 31 GiB, using system memory I181203 12:45:23.814623 15 server/server.go:1821 [n1] Could not start heap profiler worker due to: directory to store profiles could not be determined I181203 12:45:23.814715 15 server/server.go:1527 [n1] starting https server at 127.0.0.1:34865 (use: 127.0.0.1:34865) I181203 12:45:23.814746 15 server/server.go:1529 [n1] starting grpc/postgres server at 127.0.0.1:46159 I181203 12:45:23.814767 15 server/server.go:1530 [n1] advertising CockroachDB node at 127.0.0.1:46159 W181203 12:45:23.814792 15 jobs/registry.go:318 [n1] unable to get node liveness: node not in the liveness table I181203 12:45:23.849396 30 storage/replica_command.go:349 [n1,split,s1,r1/1:/M{in-ax}] initiating a split of this range at key /System/"" [r2] I181203 12:45:23.884139 118 storage/replica_command.go:349 [n1,split,s1,r2/1:/{System/-Max}] initiating a split of this range at key /System/NodeLiveness [r3] W181203 12:45:23.885246 73 storage/intent_resolver.go:675 [n1,s1] failed to push during intent resolution: failed to push "unnamed" id=513c9565 key=/Table/SystemConfigSpan/Start rw=true pri=0.00330107 iso=SERIALIZABLE stat=PENDING epo=0 ts=1543841123.823423798,0 orig=1543841123.823423798,0 max=1543841123.823423798,0 wto=false rop=false seq=6 I181203 12:45:23.891433 580 sql/event_log.go:126 [n1,intExec=optInToDiagnosticsStatReporting] Event: "set_cluster_setting", target: 0, info: {SettingName:diagnostics.reporting.enabled Value:true User:root} I181203 12:45:23.937721 610 storage/replica_command.go:349 [n1,split,s1,r3/1:/{System/NodeL…-Max}] initiating a split of this range at key /System/NodeLivenessMax [r4] I181203 12:45:23.966837 87 sql/event_log.go:126 [n1,intExec=set-setting] Event: "set_cluster_setting", target: 0, info: {SettingName:version Value:2.1-3 User:root} I181203 12:45:23.993158 80 storage/replica_command.go:349 [n1,split,s1,r4/1:/{System/NodeL…-Max}] initiating a split of this range at key /System/tsd [r5] I181203 12:45:24.002646 596 sql/event_log.go:126 [n1,intExec=disableNetTrace] Event: "set_cluster_setting", target: 0, info: {SettingName:trace.debug.enable Value:false User:root} I181203 12:45:24.012662 627 storage/replica_command.go:349 [n1,split,s1,r5/1:/{System/tsd-Max}] initiating a split of this range at key /System/"tse" [r6] I181203 12:45:24.026650 649 sql/event_log.go:126 [n1,intExec=initializeClusterSecret] Event: "set_cluster_setting", target: 0, info: {SettingName:cluster.secret Value:3d05a7d8-2bb3-4566-8d87-d63b985e3878 User:root} I181203 12:45:24.033891 653 storage/replica_command.go:349 [n1,split,s1,r6/1:/{System/tse-Max}] initiating a split of this range at key /Table/SystemConfigSpan/Start [r7] I181203 12:45:24.054418 636 storage/replica_command.go:349 [n1,split,s1,r7/1:/{Table/System…-Max}] initiating a split of this range at key /Table/11 [r8] I181203 12:45:24.060999 677 sql/event_log.go:126 [n1,intExec=create-default-db] Event: "create_database", target: 50, info: {DatabaseName:defaultdb Statement:CREATE DATABASE IF NOT EXISTS defaultdb User:root} I181203 12:45:24.064636 599 storage/replica_command.go:349 [n1,split,s1,r8/1:/{Table/11-Max}] initiating a split of this range at key /Table/12 [r9] I181203 12:45:24.076216 708 sql/event_log.go:126 [n1,intExec=create-default-db] Event: "create_database", target: 51, info: {DatabaseName:postgres Statement:CREATE DATABASE IF NOT EXISTS postgres User:root} I181203 12:45:24.094132 15 server/server.go:1583 [n1] done ensuring all necessary migrations have run I181203 12:45:24.094188 15 server/server.go:1586 [n1] serving sql connections I181203 12:45:24.094968 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.097041 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.097993 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.098205 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.098354 724 storage/replica_command.go:349 [n1,split,s1,r9/1:/{Table/12-Max}] initiating a split of this range at key /Table/13 [r10] I181203 12:45:24.098677 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.099039 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.099337 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.099462 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.099552 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.099635 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.099711 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.100816 683 sql/event_log.go:126 [n1] Event: "node_join", target: 1, info: {Descriptor:{NodeID:1 Address:{NetworkField:tcp AddressField:127.0.0.1:46159} Attrs: Locality: ServerVersion:2.1-3 BuildTag:v2.2.0-alpha.20181119-478-gf4b28a0129 StartedAt:1543841123811172490 LocalityAddress:[]} ClusterID:b0f3163d-fb25-4a28-9f73-3ce2b181a98a StartedAt:1543841123811172490 LastUp:1543841123811172490} I181203 12:45:24.111097 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.111515 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.111681 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.111906 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.112143 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.114892 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.116135 680 server/server_update.go:68 [n1] no need to upgrade, cluster already at the newest version I181203 12:45:24.116449 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.117103 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.117663 15 server/testserver.go:421 had 9 ranges at startup, expected 20 I181203 12:45:24.143478 695 storage/replica_command.go:349 [n1,split,s1,r10/1:/{Table/13-Max}] initiating a split of this range at key /Table/14 [r11] I181203 12:45:24.146998 15 server/testserver.go:421 had 10 ranges at startup, expected 20 I181203 12:45:24.164134 15 server/testserver.go:421 had 10 ranges at startup, expected 20 I181203 12:45:24.166980 15 server/testserver.go:421 had 10 ranges at startup, expected 20 I181203 12:45:24.179105 754 storage/replica_command.go:349 [n1,split,s1,r11/1:/{Table/14-Max}] initiating a split of this range at key /Table/15 [r12] I181203 12:45:24.180198 15 server/testserver.go:421 had 11 ranges at startup, expected 20 I181203 12:45:24.192970 15 server/testserver.go:421 had 12 ranges at startup, expected 20 I181203 12:45:24.193141 700 storage/replica_command.go:349 [n1,split,s1,r12/1:/{Table/15-Max}] initiating a split of this range at key /Table/16 [r13] I181203 12:45:24.214700 15 server/testserver.go:421 had 13 ranges at startup, expected 20 I181203 12:45:24.214865 772 storage/replica_command.go:349 [n1,split,s1,r13/1:/{Table/16-Max}] initiating a split of this range at key /Table/17 [r14] I181203 12:45:24.248800 779 storage/replica_command.go:349 [n1,split,s1,r14/1:/{Table/17-Max}] initiating a split of this range at key /Table/18 [r15] I181203 12:45:24.264968 835 storage/replica_command.go:349 [n1,split,s1,r15/1:/{Table/18-Max}] initiating a split of this range at key /Table/19 [r16] I181203 12:45:24.265173 15 server/testserver.go:421 had 15 ranges at startup, expected 20 I181203 12:45:24.272280 843 storage/replica_command.go:349 [n1,split,s1,r16/1:/{Table/19-Max}] initiating a split of this range at key /Table/20 [r17] I181203 12:45:24.296405 822 storage/replica_command.go:349 [n1,split,s1,r17/1:/{Table/20-Max}] initiating a split of this range at key /Table/21 [r18] I181203 12:45:24.305455 615 storage/replica_command.go:349 [n1,split,s1,r18/1:/{Table/21-Max}] initiating a split of this range at key /Table/22 [r19] I181203 12:45:24.318816 887 storage/replica_command.go:349 [n1,split,s1,r19/1:/{Table/22-Max}] initiating a split of this range at key /Table/23 [r20] I181203 12:45:24.332569 15 server/testserver.go:421 had 19 ranges at startup, expected 20 W181203 12:45:24.487618 15 server/status/runtime.go:295 [n?] Could not parse build timestamp: parsing time "" as "2006/01/02 15:04:05": cannot parse "" as "2006" I181203 12:45:24.507098 15 server/server.go:849 [n?] monitoring forward clock jumps based on server.clock.forward_jump_check_enabled I181203 12:45:24.507242 15 base/addr_validation.go:279 [n?] server certificate addresses: IP=127.0.0.1,::1; DNS=localhost,*.local; CN=node I181203 12:45:24.507265 15 base/addr_validation.go:319 [n?] web UI certificate addresses: IP=127.0.0.1,::1; DNS=localhost,*.local; CN=node I181203 12:45:24.512564 15 server/config.go:484 [n?] 1 storage engine initialized I181203 12:45:24.512608 15 server/config.go:487 [n?] RocksDB cache size: 128 MiB I181203 12:45:24.512618 15 server/config.go:487 [n?] store 0: in-memory, size 0 B W181203 12:45:24.512716 15 gossip/gossip.go:1501 [n?] no incoming or outgoing connections I181203 12:45:24.512778 15 server/server.go:1400 [n?] no stores bootstrapped and --join flag specified, awaiting init command. I181203 12:45:24.526373 949 gossip/client.go:129 [n?] started gossip client to 127.0.0.1:46159 I181203 12:45:24.551993 909 gossip/server.go:232 [n1] received initial cluster-verification connection from {tcp 127.0.0.1:46175} I181203 12:45:24.552843 15 server/node.go:697 [n?] connecting to gossip network to verify cluster ID... I181203 12:45:24.553199 15 server/node.go:722 [n?] node connected via gossip and verified as part of cluster "b0f3163d-fb25-4a28-9f73-3ce2b181a98a" I181203 12:45:24.567626 909 gossip/server.go:232 [n1] received initial cluster-verification connection from {tcp 127.0.0.1:46175} I181203 12:45:24.579657 15 server/node.go:425 [n?] new node allocated ID 2 I181203 12:45:24.580506 15 gossip/gossip.go:393 [n2] NodeDescriptor set to node_id:2 address: attrs:<> locality:<> ServerVersion: build_tag:"v2.2.0-alpha.20181119-478-gf4b28a0129" started_at:1543841124579709474 I181203 12:45:24.581762 15 storage/stores.go:242 [n2] read 0 node addresses from persistent storage I181203 12:45:24.583112 15 storage/stores.go:261 [n2] wrote 1 node addresses to persistent storage I181203 12:45:24.583853 84 storage/stores.go:261 [n1] wrote 1 node addresses to persistent storage I181203 12:45:24.589119 15 server/node.go:672 [n2] bootstrapped store [n2,s2] I181203 12:45:24.589469 15 server/node.go:546 [n2] node=2: started with [=] engine(s) and attributes [] I181203 12:45:24.589704 15 server/status/recorder.go:610 [n2] available memory from cgroups (8.0 EiB) exceeds system memory 31 GiB, using system memory I181203 12:45:24.589733 15 server/server.go:1821 [n2] Could not start heap profiler worker due to: directory to store profiles could not be determined I181203 12:45:24.589832 15 server/server.go:1527 [n2] starting https server at 127.0.0.1:34961 (use: 127.0.0.1:34961) I181203 12:45:24.589855 15 server/server.go:1529 [n2] starting grpc/postgres server at 127.0.0.1:46175 I181203 12:45:24.589872 15 server/server.go:1530 [n2] advertising CockroachDB node at 127.0.0.1:46175 I181203 12:45:24.595291 15 server/server.go:1583 [n2] done ensuring all necessary migrations have run I181203 12:45:24.595342 15 server/server.go:1586 [n2] serving sql connections W181203 12:45:24.701140 15 server/status/runtime.go:295 [n?] Could not parse build timestamp: parsing time "" as "2006/01/02 15:04:05": cannot parse "" as "2006" I181203 12:45:24.703006 969 server/server_update.go:68 [n2] no need to upgrade, cluster already at the newest version I181203 12:45:24.708417 972 sql/event_log.go:126 [n2] Event: "node_join", target: 2, info: {Descriptor:{NodeID:2 Address:{NetworkField:tcp AddressField:127.0.0.1:46175} Attrs: Locality: ServerVersion:2.1-3 BuildTag:v2.2.0-alpha.20181119-478-gf4b28a0129 StartedAt:1543841124579709474 LocalityAddress:[]} ClusterID:b0f3163d-fb25-4a28-9f73-3ce2b181a98a StartedAt:1543841124579709474 LastUp:1543841124579709474} I181203 12:45:24.709536 15 server/server.go:849 [n?] monitoring forward clock jumps based on server.clock.forward_jump_check_enabled I181203 12:45:24.709805 15 base/addr_validation.go:279 [n?] server certificate addresses: IP=127.0.0.1,::1; DNS=localhost,*.local; CN=node I181203 12:45:24.709842 15 base/addr_validation.go:319 [n?] web UI certificate addresses: IP=127.0.0.1,::1; DNS=localhost,*.local; CN=node I181203 12:45:24.745664 15 server/config.go:484 [n?] 1 storage engine initialized I181203 12:45:24.745681 15 server/config.go:487 [n?] RocksDB cache size: 128 MiB I181203 12:45:24.745691 15 server/config.go:487 [n?] store 0: in-memory, size 0 B W181203 12:45:24.745756 15 gossip/gossip.go:1501 [n?] no incoming or outgoing connections I181203 12:45:24.746389 15 server/server.go:1400 [n?] no stores bootstrapped and --join flag specified, awaiting init command. I181203 12:45:24.772552 989 gossip/client.go:129 [n?] started gossip client to 127.0.0.1:46159 I181203 12:45:24.773071 1347 gossip/server.go:232 [n1] received initial cluster-verification connection from {tcp 127.0.0.1:37509} I181203 12:45:24.774136 15 server/node.go:697 [n?] connecting to gossip network to verify cluster ID... I181203 12:45:24.774166 15 server/node.go:722 [n?] node connected via gossip and verified as part of cluster "b0f3163d-fb25-4a28-9f73-3ce2b181a98a" I181203 12:45:24.775024 1347 gossip/server.go:232 [n1] received initial cluster-verification connection from {tcp 127.0.0.1:37509} I181203 12:45:24.781034 15 server/node.go:425 [n?] new node allocated ID 3 I181203 12:45:24.781102 15 gossip/gossip.go:393 [n3] NodeDescriptor set to node_id:3 address: attrs:<> locality:<> ServerVersion: build_tag:"v2.2.0-alpha.20181119-478-gf4b28a0129" started_at:1543841124781047891 I181203 12:45:24.781173 15 storage/stores.go:242 [n3] read 0 node addresses from persistent storage I181203 12:45:24.781205 15 storage/stores.go:261 [n3] wrote 2 node addresses to persistent storage I181203 12:45:24.782159 84 storage/stores.go:261 [n1] wrote 2 node addresses to persistent storage I181203 12:45:24.782582 900 storage/stores.go:261 [n2] wrote 2 node addresses to persistent storage I181203 12:45:24.784202 15 server/node.go:672 [n3] bootstrapped store [n3,s3] I181203 12:45:24.784582 15 server/node.go:546 [n3] node=3: started with [=] engine(s) and attributes [] I181203 12:45:24.784842 15 server/status/recorder.go:610 [n3] available memory from cgroups (8.0 EiB) exceeds system memory 31 GiB, using system memory I181203 12:45:24.784865 15 server/server.go:1821 [n3] Could not start heap profiler worker due to: directory to store profiles could not be determined I181203 12:45:24.784958 15 server/server.go:1527 [n3] starting https server at 127.0.0.1:33557 (use: 127.0.0.1:33557) I181203 12:45:24.784972 15 server/server.go:1529 [n3] starting grpc/postgres server at 127.0.0.1:37509 I181203 12:45:24.784982 15 server/server.go:1530 [n3] advertising CockroachDB node at 127.0.0.1:37509 I181203 12:45:24.806543 15 server/server.go:1583 [n3] done ensuring all necessary migrations have run I181203 12:45:24.806574 15 server/server.go:1586 [n3] serving sql connections I181203 12:45:24.815336 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:24.827847 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:24.891225 1294 sql/event_log.go:126 [n3] Event: "node_join", target: 3, info: {Descriptor:{NodeID:3 Address:{NetworkField:tcp AddressField:127.0.0.1:37509} Attrs: Locality: ServerVersion:2.1-3 BuildTag:v2.2.0-alpha.20181119-478-gf4b28a0129 StartedAt:1543841124781047891 LocalityAddress:[]} ClusterID:b0f3163d-fb25-4a28-9f73-3ce2b181a98a StartedAt:1543841124781047891 LastUp:1543841124781047891} I181203 12:45:24.891372 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:24.902807 120 storage/store_snapshot.go:746 [n1,replicate,s1,r11/1:/Table/1{4-5}] sending preemptive snapshot 55168720 at applied index 18 I181203 12:45:24.903121 120 storage/store_snapshot.go:789 [n1,replicate,s1,r11/1:/Table/1{4-5}] streamed snapshot to (n3,s3):?: kv pairs: 8, log entries: 8, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:24.903453 1684 storage/replica_raftstorage.go:811 [n3,s3,r11/?:{-}] applying preemptive snapshot at index 18 (id=55168720, encoded size=2677, 1 rocksdb batches, 8 log entries) I181203 12:45:24.904211 1684 storage/replica_raftstorage.go:817 [n3,s3,r11/?:/Table/1{4-5}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:24.904558 120 storage/replica_command.go:899 [n1,replicate,s1,r11/1:/Table/1{4-5}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r11:/Table/1{4-5} [(n1,s1):1, next=2, gen=1] I181203 12:45:24.904816 1291 server/server_update.go:68 [n3] no need to upgrade, cluster already at the newest version I181203 12:45:24.920521 120 storage/replica.go:4002 [n1,s1,r11/1:/Table/1{4-5}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:24.926995 120 storage/store_snapshot.go:746 [n1,replicate,s1,r12/1:/Table/1{5-6}] sending preemptive snapshot 661d5952 at applied index 16 I181203 12:45:24.927194 120 storage/store_snapshot.go:789 [n1,replicate,s1,r12/1:/Table/1{5-6}] streamed snapshot to (n2,s2):?: kv pairs: 8, log entries: 6, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:24.927616 1296 storage/replica_raftstorage.go:811 [n2,s2,r12/?:{-}] applying preemptive snapshot at index 16 (id=661d5952, encoded size=2105, 1 rocksdb batches, 6 log entries) I181203 12:45:24.928220 1296 storage/replica_raftstorage.go:817 [n2,s2,r12/?:/Table/1{5-6}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:24.928497 120 storage/replica_command.go:899 [n1,replicate,s1,r12/1:/Table/1{5-6}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r12:/Table/1{5-6} [(n1,s1):1, next=2, gen=1] I181203 12:45:24.931633 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:24.942943 120 storage/replica.go:4002 [n1,s1,r12/1:/Table/1{5-6}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:24.947557 120 storage/store_snapshot.go:746 [n1,replicate,s1,r19/1:/Table/2{2-3}] sending preemptive snapshot 5f9a6ddc at applied index 16 I181203 12:45:24.947727 120 storage/store_snapshot.go:789 [n1,replicate,s1,r19/1:/Table/2{2-3}] streamed snapshot to (n3,s3):?: kv pairs: 8, log entries: 6, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:24.948069 1600 storage/replica_raftstorage.go:811 [n3,s3,r19/?:{-}] applying preemptive snapshot at index 16 (id=5f9a6ddc, encoded size=2272, 1 rocksdb batches, 6 log entries) I181203 12:45:24.948596 1600 storage/replica_raftstorage.go:817 [n3,s3,r19/?:/Table/2{2-3}] applied preemptive snapshot in 0ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:24.950153 120 storage/replica_command.go:899 [n1,replicate,s1,r19/1:/Table/2{2-3}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r19:/Table/2{2-3} [(n1,s1):1, next=2, gen=1] I181203 12:45:24.957326 120 storage/replica.go:4002 [n1,s1,r19/1:/Table/2{2-3}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:24.959654 120 storage/store_snapshot.go:746 [n1,replicate,s1,r1/1:/{Min-System/}] sending preemptive snapshot 00be68b6 at applied index 74 I181203 12:45:24.964220 743 storage/replica_raftstorage.go:811 [n2,s2,r1/?:{-}] applying preemptive snapshot at index 74 (id=00be68b6, encoded size=17938, 1 rocksdb batches, 64 log entries) I181203 12:45:24.968324 743 storage/replica_raftstorage.go:817 [n2,s2,r1/?:/{Min-System/}] applied preemptive snapshot in 4ms [clear=0ms batch=0ms entries=4ms commit=0ms] I181203 12:45:24.968692 120 storage/store_snapshot.go:789 [n1,replicate,s1,r1/1:/{Min-System/}] streamed snapshot to (n2,s2):?: kv pairs: 55, log entries: 64, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:24.969091 120 storage/replica_command.go:899 [n1,replicate,s1,r1/1:/{Min-System/}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r1:/{Min-System/} [(n1,s1):1, next=2, gen=1] I181203 12:45:24.978736 120 storage/replica.go:4002 [n1,s1,r1/1:/{Min-System/}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:24.983157 120 storage/store_snapshot.go:746 [n1,replicate,s1,r10/1:/Table/1{3-4}] sending preemptive snapshot 880baeb3 at applied index 42 I181203 12:45:24.983865 120 storage/store_snapshot.go:789 [n1,replicate,s1,r10/1:/Table/1{3-4}] streamed snapshot to (n2,s2):?: kv pairs: 147, log entries: 32, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:24.984405 1325 storage/replica_raftstorage.go:811 [n2,s2,r10/?:{-}] applying preemptive snapshot at index 42 (id=880baeb3, encoded size=37124, 1 rocksdb batches, 32 log entries) I181203 12:45:24.987159 1325 storage/replica_raftstorage.go:817 [n2,s2,r10/?:/Table/1{3-4}] applied preemptive snapshot in 3ms [clear=0ms batch=0ms entries=2ms commit=0ms] I181203 12:45:24.987548 120 storage/replica_command.go:899 [n1,replicate,s1,r10/1:/Table/1{3-4}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r10:/Table/1{3-4} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.006215 120 storage/replica.go:4002 [n1,s1,r10/1:/Table/1{3-4}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.008291 120 storage/store_snapshot.go:746 [n1,replicate,s1,r5/1:/System/ts{d-e}] sending preemptive snapshot 9be407d8 at applied index 27 I181203 12:45:25.009017 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:25.020004 120 storage/store_snapshot.go:789 [n1,replicate,s1,r5/1:/System/ts{d-e}] streamed snapshot to (n3,s3):?: kv pairs: 1405, log entries: 2, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:25.024720 1763 storage/replica_raftstorage.go:811 [n3,s3,r5/?:{-}] applying preemptive snapshot at index 27 (id=9be407d8, encoded size=196685, 1 rocksdb batches, 2 log entries) I181203 12:45:25.027502 1763 storage/replica_raftstorage.go:817 [n3,s3,r5/?:/System/ts{d-e}] applied preemptive snapshot in 3ms [clear=0ms batch=0ms entries=0ms commit=2ms] I181203 12:45:25.027870 120 storage/replica_command.go:899 [n1,replicate,s1,r5/1:/System/ts{d-e}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r5:/System/ts{d-e} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.046691 120 storage/replica.go:4002 [n1,s1,r5/1:/System/ts{d-e}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.078382 120 storage/store_snapshot.go:746 [n1,replicate,s1,r13/1:/Table/1{6-7}] sending preemptive snapshot de784eaa at applied index 16 I181203 12:45:25.078707 120 storage/store_snapshot.go:789 [n1,replicate,s1,r13/1:/Table/1{6-7}] streamed snapshot to (n2,s2):?: kv pairs: 8, log entries: 6, rate-limit: 8.0 MiB/sec, 0.03s I181203 12:45:25.079184 1811 storage/replica_raftstorage.go:811 [n2,s2,r13/?:{-}] applying preemptive snapshot at index 16 (id=de784eaa, encoded size=2105, 1 rocksdb batches, 6 log entries) I181203 12:45:25.079849 1811 storage/replica_raftstorage.go:817 [n2,s2,r13/?:/Table/1{6-7}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.080268 120 storage/replica_command.go:899 [n1,replicate,s1,r13/1:/Table/1{6-7}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r13:/Table/1{6-7} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.091637 120 storage/replica.go:4002 [n1,s1,r13/1:/Table/1{6-7}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.093372 120 storage/store_snapshot.go:746 [n1,replicate,s1,r16/1:/Table/{19-20}] sending preemptive snapshot 088cf1e0 at applied index 17 I181203 12:45:25.098390 120 storage/store_snapshot.go:789 [n1,replicate,s1,r16/1:/Table/{19-20}] streamed snapshot to (n3,s3):?: kv pairs: 9, log entries: 7, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:25.098834 1765 storage/replica_raftstorage.go:811 [n3,s3,r16/?:{-}] applying preemptive snapshot at index 17 (id=088cf1e0, encoded size=2417, 1 rocksdb batches, 7 log entries) I181203 12:45:25.099506 1765 storage/replica_raftstorage.go:817 [n3,s3,r16/?:/Table/{19-20}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.099807 120 storage/replica_command.go:899 [n1,replicate,s1,r16/1:/Table/{19-20}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r16:/Table/{19-20} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.109829 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:25.111092 120 storage/replica.go:4002 [n1,s1,r16/1:/Table/{19-20}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.126487 120 storage/store_snapshot.go:746 [n1,replicate,s1,r17/1:/Table/2{0-1}] sending preemptive snapshot 0ae6c7d4 at applied index 16 I181203 12:45:25.126781 120 storage/store_snapshot.go:789 [n1,replicate,s1,r17/1:/Table/2{0-1}] streamed snapshot to (n3,s3):?: kv pairs: 8, log entries: 6, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:25.127147 1829 storage/replica_raftstorage.go:811 [n3,s3,r17/?:{-}] applying preemptive snapshot at index 16 (id=0ae6c7d4, encoded size=2105, 1 rocksdb batches, 6 log entries) I181203 12:45:25.127723 1829 storage/replica_raftstorage.go:817 [n3,s3,r17/?:/Table/2{0-1}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.128058 120 storage/replica_command.go:899 [n1,replicate,s1,r17/1:/Table/2{0-1}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r17:/Table/2{0-1} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.191255 120 storage/replica.go:4002 [n1,s1,r17/1:/Table/2{0-1}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.199009 120 storage/store_snapshot.go:746 [n1,replicate,s1,r18/1:/Table/2{1-2}] sending preemptive snapshot 81c5fcd1 at applied index 16 I181203 12:45:25.199231 120 storage/store_snapshot.go:789 [n1,replicate,s1,r18/1:/Table/2{1-2}] streamed snapshot to (n2,s2):?: kv pairs: 8, log entries: 6, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:25.201325 1706 storage/replica_raftstorage.go:811 [n2,s2,r18/?:{-}] applying preemptive snapshot at index 16 (id=81c5fcd1, encoded size=2105, 1 rocksdb batches, 6 log entries) I181203 12:45:25.205177 1706 storage/replica_raftstorage.go:817 [n2,s2,r18/?:/Table/2{1-2}] applied preemptive snapshot in 4ms [clear=0ms batch=0ms entries=3ms commit=0ms] I181203 12:45:25.207016 120 storage/replica_command.go:899 [n1,replicate,s1,r18/1:/Table/2{1-2}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r18:/Table/2{1-2} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.209208 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:25.217327 120 storage/replica.go:4002 [n1,s1,r18/1:/Table/2{1-2}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.220587 120 storage/store_snapshot.go:746 [n1,replicate,s1,r4/1:/System/{NodeLive…-tsd}] sending preemptive snapshot 88b870f9 at applied index 40 I181203 12:45:25.221560 120 storage/store_snapshot.go:789 [n1,replicate,s1,r4/1:/System/{NodeLive…-tsd}] streamed snapshot to (n3,s3):?: kv pairs: 36, log entries: 30, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.222830 1803 storage/replica_raftstorage.go:811 [n3,s3,r4/?:{-}] applying preemptive snapshot at index 40 (id=88b870f9, encoded size=100065, 1 rocksdb batches, 30 log entries) I181203 12:45:25.229225 1803 storage/replica_raftstorage.go:817 [n3,s3,r4/?:/System/{NodeLive…-tsd}] applied preemptive snapshot in 6ms [clear=0ms batch=0ms entries=4ms commit=2ms] I181203 12:45:25.258077 120 storage/replica_command.go:899 [n1,replicate,s1,r4/1:/System/{NodeLive…-tsd}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r4:/System/{NodeLivenessMax-tsd} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.268322 120 storage/replica.go:4002 [n1,s1,r4/1:/System/{NodeLive…-tsd}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.271138 120 storage/store_snapshot.go:746 [n1,replicate,s1,r8/1:/Table/1{1-2}] sending preemptive snapshot bc3a75f4 at applied index 19 I181203 12:45:25.272240 1771 storage/replica_raftstorage.go:811 [n2,s2,r8/?:{-}] applying preemptive snapshot at index 19 (id=bc3a75f4, encoded size=4619, 1 rocksdb batches, 9 log entries) I181203 12:45:25.273006 1771 storage/replica_raftstorage.go:817 [n2,s2,r8/?:/Table/1{1-2}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.273205 120 storage/store_snapshot.go:789 [n1,replicate,s1,r8/1:/Table/1{1-2}] streamed snapshot to (n2,s2):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.273400 120 storage/replica_command.go:899 [n1,replicate,s1,r8/1:/Table/1{1-2}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r8:/Table/1{1-2} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.280271 120 storage/replica.go:4002 [n1,s1,r8/1:/Table/1{1-2}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.282118 120 storage/store_snapshot.go:746 [n1,replicate,s1,r14/1:/Table/1{7-8}] sending preemptive snapshot 3e6bd9db at applied index 16 I181203 12:45:25.282396 120 storage/store_snapshot.go:789 [n1,replicate,s1,r14/1:/Table/1{7-8}] streamed snapshot to (n3,s3):?: kv pairs: 8, log entries: 6, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.289153 1775 storage/replica_raftstorage.go:811 [n3,s3,r14/?:{-}] applying preemptive snapshot at index 16 (id=3e6bd9db, encoded size=2105, 1 rocksdb batches, 6 log entries) I181203 12:45:25.289734 1775 storage/replica_raftstorage.go:817 [n3,s3,r14/?:/Table/1{7-8}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.290112 120 storage/replica_command.go:899 [n1,replicate,s1,r14/1:/Table/1{7-8}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r14:/Table/1{7-8} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.295778 120 storage/replica.go:4002 [n1,s1,r14/1:/Table/1{7-8}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.298470 120 storage/store_snapshot.go:746 [n1,replicate,s1,r15/1:/Table/1{8-9}] sending preemptive snapshot b1aa6dba at applied index 17 I181203 12:45:25.299124 120 storage/store_snapshot.go:789 [n1,replicate,s1,r15/1:/Table/1{8-9}] streamed snapshot to (n2,s2):?: kv pairs: 9, log entries: 7, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.299582 1839 storage/replica_raftstorage.go:811 [n2,s2,r15/?:{-}] applying preemptive snapshot at index 17 (id=b1aa6dba, encoded size=2417, 1 rocksdb batches, 7 log entries) I181203 12:45:25.300205 1839 storage/replica_raftstorage.go:817 [n2,s2,r15/?:/Table/1{8-9}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.300489 120 storage/replica_command.go:899 [n1,replicate,s1,r15/1:/Table/1{8-9}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r15:/Table/1{8-9} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.306889 120 storage/replica.go:4002 [n1,s1,r15/1:/Table/1{8-9}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.309235 120 storage/store_snapshot.go:746 [n1,replicate,s1,r2/1:/System/{-NodeLive…}] sending preemptive snapshot dec77436 at applied index 23 I181203 12:45:25.309954 120 storage/store_snapshot.go:789 [n1,replicate,s1,r2/1:/System/{-NodeLive…}] streamed snapshot to (n2,s2):?: kv pairs: 7, log entries: 4, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.310678 1863 storage/replica_raftstorage.go:811 [n2,s2,r2/?:{-}] applying preemptive snapshot at index 23 (id=dec77436, encoded size=57128, 1 rocksdb batches, 4 log entries) I181203 12:45:25.314578 1863 storage/replica_raftstorage.go:817 [n2,s2,r2/?:/System/{-NodeLive…}] applied preemptive snapshot in 4ms [clear=0ms batch=0ms entries=0ms commit=3ms] I181203 12:45:25.315250 120 storage/replica_command.go:899 [n1,replicate,s1,r2/1:/System/{-NodeLive…}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r2:/System/{-NodeLiveness} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.315428 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:25.320645 120 storage/replica.go:4002 [n1,s1,r2/1:/System/{-NodeLive…}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.358051 120 storage/store_snapshot.go:746 [n1,replicate,s1,r7/1:/Table/{SystemCon…-11}] sending preemptive snapshot d4b0e99e at applied index 25 I181203 12:45:25.358400 120 storage/store_snapshot.go:789 [n1,replicate,s1,r7/1:/Table/{SystemCon…-11}] streamed snapshot to (n3,s3):?: kv pairs: 61, log entries: 15, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.358787 1876 storage/replica_raftstorage.go:811 [n3,s3,r7/?:{-}] applying preemptive snapshot at index 25 (id=d4b0e99e, encoded size=14406, 1 rocksdb batches, 15 log entries) I181203 12:45:25.372029 1876 storage/replica_raftstorage.go:817 [n3,s3,r7/?:/Table/{SystemCon…-11}] applied preemptive snapshot in 10ms [clear=0ms batch=0ms entries=9ms commit=0ms] I181203 12:45:25.372451 120 storage/replica_command.go:899 [n1,replicate,s1,r7/1:/Table/{SystemCon…-11}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r7:/Table/{SystemConfigSpan/Start-11} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.393174 120 storage/replica.go:4002 [n1,s1,r7/1:/Table/{SystemCon…-11}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.417982 120 storage/store_snapshot.go:746 [n1,replicate,s1,r9/1:/Table/1{2-3}] sending preemptive snapshot 7c0f243f at applied index 23 I181203 12:45:25.418822 120 storage/store_snapshot.go:789 [n1,replicate,s1,r9/1:/Table/1{2-3}] streamed snapshot to (n3,s3):?: kv pairs: 53, log entries: 13, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.419077 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:25.419257 1883 storage/replica_raftstorage.go:811 [n3,s3,r9/?:{-}] applying preemptive snapshot at index 23 (id=7c0f243f, encoded size=14631, 1 rocksdb batches, 13 log entries) I181203 12:45:25.420404 1883 storage/replica_raftstorage.go:817 [n3,s3,r9/?:/Table/1{2-3}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.420701 120 storage/replica_command.go:899 [n1,replicate,s1,r9/1:/Table/1{2-3}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r9:/Table/1{2-3} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.425871 120 storage/replica.go:4002 [n1,s1,r9/1:/Table/1{2-3}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.427741 120 storage/store_snapshot.go:746 [n1,replicate,s1,r20/1:/{Table/23-Max}] sending preemptive snapshot 91867936 at applied index 12 I181203 12:45:25.427947 120 storage/store_snapshot.go:789 [n1,replicate,s1,r20/1:/{Table/23-Max}] streamed snapshot to (n2,s2):?: kv pairs: 11, log entries: 2, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.428273 1888 storage/replica_raftstorage.go:811 [n2,s2,r20/?:{-}] applying preemptive snapshot at index 12 (id=91867936, encoded size=549, 1 rocksdb batches, 2 log entries) I181203 12:45:25.428560 1888 storage/replica_raftstorage.go:817 [n2,s2,r20/?:/{Table/23-Max}] applied preemptive snapshot in 0ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.429364 120 storage/replica_command.go:899 [n1,replicate,s1,r20/1:/{Table/23-Max}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r20:/{Table/23-Max} [(n1,s1):1, next=2, gen=0] I181203 12:45:25.450281 120 storage/replica.go:4002 [n1,s1,r20/1:/{Table/23-Max}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.452210 120 storage/store_snapshot.go:746 [n1,replicate,s1,r3/1:/System/NodeLiveness{-Max}] sending preemptive snapshot b385dfb8 at applied index 24 I181203 12:45:25.452642 120 storage/store_snapshot.go:789 [n1,replicate,s1,r3/1:/System/NodeLiveness{-Max}] streamed snapshot to (n3,s3):?: kv pairs: 10, log entries: 14, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.452999 1865 storage/replica_raftstorage.go:811 [n3,s3,r3/?:{-}] applying preemptive snapshot at index 24 (id=b385dfb8, encoded size=5960, 1 rocksdb batches, 14 log entries) I181203 12:45:25.454045 1865 storage/replica_raftstorage.go:817 [n3,s3,r3/?:/System/NodeLiveness{-Max}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.454445 120 storage/replica_command.go:899 [n1,replicate,s1,r3/1:/System/NodeLiveness{-Max}] change replicas (ADD_REPLICA (n3,s3):2): read existing descriptor r3:/System/NodeLiveness{-Max} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.493037 120 storage/replica.go:4002 [n1,s1,r3/1:/System/NodeLiveness{-Max}] proposing ADD_REPLICA((n3,s3):2): updated=[(n1,s1):1 (n3,s3):2] next=3 I181203 12:45:25.502245 120 storage/store_snapshot.go:746 [n1,replicate,s1,r6/1:/{System/tse-Table/System…}] sending preemptive snapshot 49232f19 at applied index 19 I181203 12:45:25.502533 120 storage/store_snapshot.go:789 [n1,replicate,s1,r6/1:/{System/tse-Table/System…}] streamed snapshot to (n2,s2):?: kv pairs: 7, log entries: 9, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:25.502941 1916 storage/replica_raftstorage.go:811 [n2,s2,r6/?:{-}] applying preemptive snapshot at index 19 (id=49232f19, encoded size=4766, 1 rocksdb batches, 9 log entries) I181203 12:45:25.503696 1916 storage/replica_raftstorage.go:817 [n2,s2,r6/?:/{System/tse-Table/System…}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.504151 120 storage/replica_command.go:899 [n1,replicate,s1,r6/1:/{System/tse-Table/System…}] change replicas (ADD_REPLICA (n2,s2):2): read existing descriptor r6:/{System/tse-Table/SystemConfigSpan/Start} [(n1,s1):1, next=2, gen=1] I181203 12:45:25.509712 120 storage/replica.go:4002 [n1,s1,r6/1:/{System/tse-Table/System…}] proposing ADD_REPLICA((n2,s2):2): updated=[(n1,s1):1 (n2,s2):2] next=3 I181203 12:45:25.510209 120 storage/queue.go:923 [n1,replicate] purgatory is now empty I181203 12:45:25.511547 1896 storage/store_snapshot.go:746 [n1,replicate,s1,r11/1:/Table/1{4-5}] sending preemptive snapshot 01e35ac5 at applied index 21 I181203 12:45:25.511738 1896 storage/store_snapshot.go:789 [n1,replicate,s1,r11/1:/Table/1{4-5}] streamed snapshot to (n2,s2):?: kv pairs: 9, log entries: 11, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.513952 1340 storage/replica_raftstorage.go:811 [n2,s2,r11/?:{-}] applying preemptive snapshot at index 21 (id=01e35ac5, encoded size=3709, 1 rocksdb batches, 11 log entries) I181203 12:45:25.514762 1340 storage/replica_raftstorage.go:817 [n2,s2,r11/?:/Table/1{4-5}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.515430 1896 storage/replica_command.go:899 [n1,replicate,s1,r11/1:/Table/1{4-5}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r11:/Table/1{4-5} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:25.518330 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 20 underreplicated ranges I181203 12:45:25.535294 1896 storage/replica.go:4002 [n1,s1,r11/1:/Table/1{4-5}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:25.540243 1918 storage/store_snapshot.go:746 [n1,replicate,s1,r12/1:/Table/1{5-6}] sending preemptive snapshot c830949a at applied index 19 I181203 12:45:25.540465 1918 storage/store_snapshot.go:789 [n1,replicate,s1,r12/1:/Table/1{5-6}] streamed snapshot to (n3,s3):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.540802 1922 storage/replica_raftstorage.go:811 [n3,s3,r12/?:{-}] applying preemptive snapshot at index 19 (id=c830949a, encoded size=3137, 1 rocksdb batches, 9 log entries) I181203 12:45:25.541583 1922 storage/replica_raftstorage.go:817 [n3,s3,r12/?:/Table/1{5-6}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.541895 1918 storage/replica_command.go:899 [n1,replicate,s1,r12/1:/Table/1{5-6}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r12:/Table/1{5-6} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:25.576219 1918 storage/replica.go:4002 [n1,s1,r12/1:/Table/1{5-6}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:25.588369 1938 storage/store_snapshot.go:746 [n1,replicate,s1,r19/1:/Table/2{2-3}] sending preemptive snapshot b2348531 at applied index 19 I181203 12:45:25.589238 1938 storage/store_snapshot.go:789 [n1,replicate,s1,r19/1:/Table/2{2-3}] streamed snapshot to (n2,s2):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:25.589594 1628 storage/replica_raftstorage.go:811 [n2,s2,r19/?:{-}] applying preemptive snapshot at index 19 (id=b2348531, encoded size=3301, 1 rocksdb batches, 9 log entries) I181203 12:45:25.590356 1628 storage/replica_raftstorage.go:817 [n2,s2,r19/?:/Table/2{2-3}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.590872 1938 storage/replica_command.go:899 [n1,replicate,s1,r19/1:/Table/2{2-3}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r19:/Table/2{2-3} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:25.596828 1938 storage/replica.go:4002 [n1,s1,r19/1:/Table/2{2-3}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:25.607586 1902 storage/store_snapshot.go:746 [n1,replicate,s1,r1/1:/{Min-System/}] sending preemptive snapshot 8673fb0e at applied index 116 I181203 12:45:25.608419 1902 storage/store_snapshot.go:789 [n1,replicate,s1,r1/1:/{Min-System/}] streamed snapshot to (n3,s3):?: kv pairs: 77, log entries: 106, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:25.608925 1924 storage/replica_raftstorage.go:811 [n3,s3,r1/?:{-}] applying preemptive snapshot at index 116 (id=8673fb0e, encoded size=26275, 1 rocksdb batches, 106 log entries) I181203 12:45:25.617869 1924 storage/replica_raftstorage.go:817 [n3,s3,r1/?:/{Min-System/}] applied preemptive snapshot in 9ms [clear=0ms batch=0ms entries=8ms commit=0ms] I181203 12:45:25.618286 1902 storage/replica_command.go:899 [n1,replicate,s1,r1/1:/{Min-System/}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r1:/{Min-System/} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:25.623950 1902 storage/replica.go:4002 [n1,s1,r1/1:/{Min-System/}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:25.630097 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 16 underreplicated ranges I181203 12:45:25.654799 134 gossip/gossip.go:1515 [n1] node has connected to cluster via gossip I181203 12:45:25.655049 134 storage/stores.go:261 [n1] wrote 2 node addresses to persistent storage I181203 12:45:25.655316 1871 storage/store_snapshot.go:746 [n1,replicate,s1,r10/1:/Table/1{3-4}] sending preemptive snapshot 6eea7530 at applied index 84 I181203 12:45:25.658485 1871 storage/store_snapshot.go:789 [n1,replicate,s1,r10/1:/Table/1{3-4}] streamed snapshot to (n3,s3):?: kv pairs: 248, log entries: 74, rate-limit: 8.0 MiB/sec, 0.03s I181203 12:45:25.659259 1945 storage/replica_raftstorage.go:811 [n3,s3,r10/?:{-}] applying preemptive snapshot at index 84 (id=6eea7530, encoded size=77453, 1 rocksdb batches, 74 log entries) I181203 12:45:25.669427 1945 storage/replica_raftstorage.go:817 [n3,s3,r10/?:/Table/1{3-4}] applied preemptive snapshot in 10ms [clear=0ms batch=0ms entries=9ms commit=1ms] I181203 12:45:25.669886 1871 storage/replica_command.go:899 [n1,replicate,s1,r10/1:/Table/1{3-4}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r10:/Table/1{3-4} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:25.683167 1871 storage/replica.go:4002 [n1,s1,r10/1:/Table/1{3-4}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:25.699355 1949 storage/store_snapshot.go:746 [n1,replicate,s1,r5/1:/System/ts{d-e}] sending preemptive snapshot 2a90184b at applied index 30 I181203 12:45:25.706769 1949 storage/store_snapshot.go:789 [n1,replicate,s1,r5/1:/System/ts{d-e}] streamed snapshot to (n2,s2):?: kv pairs: 1406, log entries: 5, rate-limit: 8.0 MiB/sec, 0.02s I181203 12:45:25.707786 1926 storage/replica_raftstorage.go:811 [n2,s2,r5/?:{-}] applying preemptive snapshot at index 30 (id=2a90184b, encoded size=197768, 1 rocksdb batches, 5 log entries) I181203 12:45:25.710388 1926 storage/replica_raftstorage.go:817 [n2,s2,r5/?:/System/ts{d-e}] applied preemptive snapshot in 3ms [clear=0ms batch=0ms entries=0ms commit=2ms] I181203 12:45:25.710789 1949 storage/replica_command.go:899 [n1,replicate,s1,r5/1:/System/ts{d-e}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r5:/System/ts{d-e} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:25.720745 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 15 underreplicated ranges I181203 12:45:25.724778 1949 storage/replica.go:4002 [n1,s1,r5/1:/System/ts{d-e}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:25.728408 1953 storage/store_snapshot.go:746 [n1,replicate,s1,r13/1:/Table/1{6-7}] sending preemptive snapshot 978af57a at applied index 19 I181203 12:45:25.728659 1953 storage/store_snapshot.go:789 [n1,replicate,s1,r13/1:/Table/1{6-7}] streamed snapshot to (n3,s3):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.729050 1957 storage/replica_raftstorage.go:811 [n3,s3,r13/?:{-}] applying preemptive snapshot at index 19 (id=978af57a, encoded size=3137, 1 rocksdb batches, 9 log entries) I181203 12:45:25.729772 1957 storage/replica_raftstorage.go:817 [n3,s3,r13/?:/Table/1{6-7}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.730084 1953 storage/replica_command.go:899 [n1,replicate,s1,r13/1:/Table/1{6-7}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r13:/Table/1{6-7} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:25.762811 1953 storage/replica.go:4002 [n1,s1,r13/1:/Table/1{6-7}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:25.771380 1986 storage/store_snapshot.go:746 [n1,replicate,s1,r16/1:/Table/{19-20}] sending preemptive snapshot acb5d8a9 at applied index 20 I181203 12:45:25.772348 1986 storage/store_snapshot.go:789 [n1,replicate,s1,r16/1:/Table/{19-20}] streamed snapshot to (n2,s2):?: kv pairs: 10, log entries: 10, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.773326 2003 storage/replica_raftstorage.go:811 [n2,s2,r16/?:{-}] applying preemptive snapshot at index 20 (id=acb5d8a9, encoded size=3449, 1 rocksdb batches, 10 log entries) I181203 12:45:25.774213 2003 storage/replica_raftstorage.go:817 [n2,s2,r16/?:/Table/{19-20}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.774527 1986 storage/replica_command.go:899 [n1,replicate,s1,r16/1:/Table/{19-20}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r16:/Table/{19-20} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:25.796614 1986 storage/replica.go:4002 [n1,s1,r16/1:/Table/{19-20}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:25.800814 1990 storage/store_snapshot.go:746 [n1,replicate,s1,r17/1:/Table/2{0-1}] sending preemptive snapshot b6edbed7 at applied index 19 I181203 12:45:25.801069 1990 storage/store_snapshot.go:789 [n1,replicate,s1,r17/1:/Table/2{0-1}] streamed snapshot to (n2,s2):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.801479 1994 storage/replica_raftstorage.go:811 [n2,s2,r17/?:{-}] applying preemptive snapshot at index 19 (id=b6edbed7, encoded size=3137, 1 rocksdb batches, 9 log entries) I181203 12:45:25.802258 1994 storage/replica_raftstorage.go:817 [n2,s2,r17/?:/Table/2{0-1}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.802620 1990 storage/replica_command.go:899 [n1,replicate,s1,r17/1:/Table/2{0-1}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r17:/Table/2{0-1} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:25.809718 1990 storage/replica.go:4002 [n1,s1,r17/1:/Table/2{0-1}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:25.812687 1975 storage/store_snapshot.go:746 [n1,replicate,s1,r18/1:/Table/2{1-2}] sending preemptive snapshot 37287ba3 at applied index 19 I181203 12:45:25.815573 1975 storage/store_snapshot.go:789 [n1,replicate,s1,r18/1:/Table/2{1-2}] streamed snapshot to (n3,s3):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.826570 1928 storage/replica_raftstorage.go:811 [n3,s3,r18/?:{-}] applying preemptive snapshot at index 19 (id=37287ba3, encoded size=3137, 1 rocksdb batches, 9 log entries) I181203 12:45:25.827393 1928 storage/replica_raftstorage.go:817 [n3,s3,r18/?:/Table/2{1-2}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.828399 1975 storage/replica_command.go:899 [n1,replicate,s1,r18/1:/Table/2{1-2}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r18:/Table/2{1-2} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:25.828545 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 11 underreplicated ranges I181203 12:45:25.858169 1975 storage/replica.go:4002 [n1,s1,r18/1:/Table/2{1-2}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:25.863545 1979 storage/store_snapshot.go:746 [n1,replicate,s1,r4/1:/System/{NodeLive…-tsd}] sending preemptive snapshot 5a76bb38 at applied index 43 I181203 12:45:25.864191 1979 storage/store_snapshot.go:789 [n1,replicate,s1,r4/1:/System/{NodeLive…-tsd}] streamed snapshot to (n2,s2):?: kv pairs: 37, log entries: 33, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.868308 1996 storage/replica_raftstorage.go:811 [n2,s2,r4/?:{-}] applying preemptive snapshot at index 43 (id=5a76bb38, encoded size=101249, 1 rocksdb batches, 33 log entries) I181203 12:45:25.895702 1996 storage/replica_raftstorage.go:817 [n2,s2,r4/?:/System/{NodeLive…-tsd}] applied preemptive snapshot in 27ms [clear=0ms batch=0ms entries=2ms commit=25ms] I181203 12:45:25.896079 1979 storage/replica_command.go:899 [n1,replicate,s1,r4/1:/System/{NodeLive…-tsd}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r4:/System/{NodeLivenessMax-tsd} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:25.913680 1979 storage/replica.go:4002 [n1,s1,r4/1:/System/{NodeLive…-tsd}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:25.916788 1933 storage/store_snapshot.go:746 [n1,replicate,s1,r8/1:/Table/1{1-2}] sending preemptive snapshot cd080a56 at applied index 22 I181203 12:45:25.917867 1933 storage/store_snapshot.go:789 [n1,replicate,s1,r8/1:/Table/1{1-2}] streamed snapshot to (n3,s3):?: kv pairs: 10, log entries: 12, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.918250 1936 storage/replica_raftstorage.go:811 [n3,s3,r8/?:{-}] applying preemptive snapshot at index 22 (id=cd080a56, encoded size=5651, 1 rocksdb batches, 12 log entries) I181203 12:45:25.919230 1936 storage/replica_raftstorage.go:817 [n3,s3,r8/?:/Table/1{1-2}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.919551 1933 storage/replica_command.go:899 [n1,replicate,s1,r8/1:/Table/1{1-2}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r8:/Table/1{1-2} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:25.937001 1933 storage/replica.go:4002 [n1,s1,r8/1:/Table/1{1-2}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:25.943779 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 9 underreplicated ranges I181203 12:45:25.947423 1848 storage/store_snapshot.go:746 [n1,replicate,s1,r14/1:/Table/1{7-8}] sending preemptive snapshot 2660a5fc at applied index 19 I181203 12:45:25.948332 1848 storage/store_snapshot.go:789 [n1,replicate,s1,r14/1:/Table/1{7-8}] streamed snapshot to (n2,s2):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.954769 2034 storage/replica_raftstorage.go:811 [n2,s2,r14/?:{-}] applying preemptive snapshot at index 19 (id=2660a5fc, encoded size=3137, 1 rocksdb batches, 9 log entries) I181203 12:45:25.955610 2034 storage/replica_raftstorage.go:817 [n2,s2,r14/?:/Table/1{7-8}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:25.956535 1848 storage/replica_command.go:899 [n1,replicate,s1,r14/1:/Table/1{7-8}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r14:/Table/1{7-8} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:25.964188 1848 storage/replica.go:4002 [n1,s1,r14/1:/Table/1{7-8}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:25.973485 1852 storage/store_snapshot.go:746 [n1,replicate,s1,r15/1:/Table/1{8-9}] sending preemptive snapshot c695c87f at applied index 20 I181203 12:45:25.973672 1852 storage/store_snapshot.go:789 [n1,replicate,s1,r15/1:/Table/1{8-9}] streamed snapshot to (n3,s3):?: kv pairs: 10, log entries: 10, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:25.974052 2023 storage/replica_raftstorage.go:811 [n3,s3,r15/?:{-}] applying preemptive snapshot at index 20 (id=c695c87f, encoded size=3449, 1 rocksdb batches, 10 log entries) I181203 12:45:25.974724 2023 storage/replica_raftstorage.go:817 [n3,s3,r15/?:/Table/1{8-9}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:25.977205 1852 storage/replica_command.go:899 [n1,replicate,s1,r15/1:/Table/1{8-9}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r15:/Table/1{8-9} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:26.014761 1852 storage/replica.go:4002 [n1,s1,r15/1:/Table/1{8-9}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:26.030326 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 6 underreplicated ranges I181203 12:45:26.030689 2025 storage/store_snapshot.go:746 [n1,replicate,s1,r2/1:/System/{-NodeLive…}] sending preemptive snapshot 7ebc1394 at applied index 28 I181203 12:45:26.031083 2025 storage/store_snapshot.go:789 [n1,replicate,s1,r2/1:/System/{-NodeLive…}] streamed snapshot to (n3,s3):?: kv pairs: 9, log entries: 9, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:26.031761 2009 storage/replica_raftstorage.go:811 [n3,s3,r2/?:{-}] applying preemptive snapshot at index 28 (id=7ebc1394, encoded size=58471, 1 rocksdb batches, 9 log entries) I181203 12:45:26.032882 2009 storage/replica_raftstorage.go:817 [n3,s3,r2/?:/System/{-NodeLive…}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:26.033171 2025 storage/replica_command.go:899 [n1,replicate,s1,r2/1:/System/{-NodeLive…}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r2:/System/{-NodeLiveness} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:26.040465 2025 storage/replica.go:4002 [n1,s1,r2/1:/System/{-NodeLive…}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:26.044181 1857 storage/store_snapshot.go:746 [n1,replicate,s1,r7/1:/Table/{SystemCon…-11}] sending preemptive snapshot 7dce4ea5 at applied index 28 I181203 12:45:26.044569 1857 storage/store_snapshot.go:789 [n1,replicate,s1,r7/1:/Table/{SystemCon…-11}] streamed snapshot to (n2,s2):?: kv pairs: 62, log entries: 18, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:26.046195 2069 storage/replica_raftstorage.go:811 [n2,s2,r7/?:{-}] applying preemptive snapshot at index 28 (id=7dce4ea5, encoded size=15438, 1 rocksdb batches, 18 log entries) I181203 12:45:26.047544 2069 storage/replica_raftstorage.go:817 [n2,s2,r7/?:/Table/{SystemCon…-11}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:26.047910 1857 storage/replica_command.go:899 [n1,replicate,s1,r7/1:/Table/{SystemCon…-11}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r7:/Table/{SystemConfigSpan/Start-11} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:26.070367 1857 storage/replica.go:4002 [n1,s1,r7/1:/Table/{SystemCon…-11}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:26.071981 2084 storage/split_queue.go:215 [n1,split,s1,r7/1:/Table/{SystemCon…-11}] initiating a split based on load at key /Table/3/1/1/2/1 E181203 12:45:26.072074 2084 storage/queue.go:837 [n1,split,s1,r7/1:/Table/{SystemCon…-11}] unable to split [n1,s1,r7/1:/Table/{SystemCon…-11}] at key /Table/3/1/1/2/1: cannot split range at key /Table/3/1/1/2/1 I181203 12:45:26.088673 2082 storage/store_snapshot.go:746 [n1,replicate,s1,r9/1:/Table/1{2-3}] sending preemptive snapshot 3b373c12 at applied index 26 I181203 12:45:26.089081 2082 storage/store_snapshot.go:789 [n1,replicate,s1,r9/1:/Table/1{2-3}] streamed snapshot to (n2,s2):?: kv pairs: 54, log entries: 16, rate-limit: 8.0 MiB/sec, 0.02s I181203 12:45:26.090036 2032 storage/replica_raftstorage.go:811 [n2,s2,r9/?:{-}] applying preemptive snapshot at index 26 (id=3b373c12, encoded size=15663, 1 rocksdb batches, 16 log entries) I181203 12:45:26.091273 2032 storage/replica_raftstorage.go:817 [n2,s2,r9/?:/Table/1{2-3}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:26.091685 2082 storage/replica_command.go:899 [n1,replicate,s1,r9/1:/Table/1{2-3}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r9:/Table/1{2-3} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:26.100772 2082 storage/replica.go:4002 [n1,s1,r9/1:/Table/1{2-3}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:26.102466 2012 storage/store_snapshot.go:746 [n1,replicate,s1,r20/1:/{Table/23-Max}] sending preemptive snapshot 61c68a3e at applied index 15 I181203 12:45:26.103270 2012 storage/store_snapshot.go:789 [n1,replicate,s1,r20/1:/{Table/23-Max}] streamed snapshot to (n3,s3):?: kv pairs: 12, log entries: 5, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:26.106635 2015 storage/replica_raftstorage.go:811 [n3,s3,r20/?:{-}] applying preemptive snapshot at index 15 (id=61c68a3e, encoded size=1581, 1 rocksdb batches, 5 log entries) I181203 12:45:26.107131 2015 storage/replica_raftstorage.go:817 [n3,s3,r20/?:/{Table/23-Max}] applied preemptive snapshot in 0ms [clear=0ms batch=0ms entries=0ms commit=0ms] I181203 12:45:26.108024 2012 storage/replica_command.go:899 [n1,replicate,s1,r20/1:/{Table/23-Max}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r20:/{Table/23-Max} [(n1,s1):1, (n2,s2):2, next=3, gen=0] I181203 12:45:26.143768 15 testutils/testcluster/testcluster.go:564 [n1,s1] has 3 underreplicated ranges I181203 12:45:26.166816 2012 storage/replica.go:4002 [n1,s1,r20/1:/{Table/23-Max}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:26.179683 2071 storage/store_snapshot.go:746 [n1,replicate,s1,r3/1:/System/NodeLiveness{-Max}] sending preemptive snapshot 9b9ea998 at applied index 27 I181203 12:45:26.179970 2071 storage/store_snapshot.go:789 [n1,replicate,s1,r3/1:/System/NodeLiveness{-Max}] streamed snapshot to (n2,s2):?: kv pairs: 11, log entries: 17, rate-limit: 8.0 MiB/sec, 0.01s I181203 12:45:26.180382 2100 storage/replica_raftstorage.go:811 [n2,s2,r3/?:{-}] applying preemptive snapshot at index 27 (id=9b9ea998, encoded size=7172, 1 rocksdb batches, 17 log entries) I181203 12:45:26.181646 2100 storage/replica_raftstorage.go:817 [n2,s2,r3/?:/System/NodeLiveness{-Max}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:26.181998 2071 storage/replica_command.go:899 [n1,replicate,s1,r3/1:/System/NodeLiveness{-Max}] change replicas (ADD_REPLICA (n2,s2):3): read existing descriptor r3:/System/NodeLiveness{-Max} [(n1,s1):1, (n3,s3):2, next=3, gen=1] I181203 12:45:26.187827 2071 storage/replica.go:4002 [n1,s1,r3/1:/System/NodeLiveness{-Max}] proposing ADD_REPLICA((n2,s2):3): updated=[(n1,s1):1 (n3,s3):2 (n2,s2):3] next=4 I181203 12:45:26.190953 1999 storage/store_snapshot.go:746 [n1,replicate,s1,r6/1:/{System/tse-Table/System…}] sending preemptive snapshot 16e04737 at applied index 22 I181203 12:45:26.191184 1999 storage/store_snapshot.go:789 [n1,replicate,s1,r6/1:/{System/tse-Table/System…}] streamed snapshot to (n3,s3):?: kv pairs: 8, log entries: 12, rate-limit: 8.0 MiB/sec, 0.00s I181203 12:45:26.191655 2087 storage/replica_raftstorage.go:811 [n3,s3,r6/?:{-}] applying preemptive snapshot at index 22 (id=16e04737, encoded size=5837, 1 rocksdb batches, 12 log entries) I181203 12:45:26.192635 2087 storage/replica_raftstorage.go:817 [n3,s3,r6/?:/{System/tse-Table/System…}] applied preemptive snapshot in 1ms [clear=0ms batch=0ms entries=1ms commit=0ms] I181203 12:45:26.192950 1999 storage/replica_command.go:899 [n1,replicate,s1,r6/1:/{System/tse-Table/System…}] change replicas (ADD_REPLICA (n3,s3):3): read existing descriptor r6:/{System/tse-Table/SystemConfigSpan/Start} [(n1,s1):1, (n2,s2):2, next=3, gen=1] I181203 12:45:26.204706 1999 storage/replica.go:4002 [n1,s1,r6/1:/{System/tse-Table/System…}] proposing ADD_REPLICA((n3,s3):3): updated=[(n1,s1):1 (n2,s2):2 (n3,s3):3] next=4 I181203 12:45:26.658426 2215 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-91" [r21] I181203 12:45:26.663866 2036 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-20" [r22] I181203 12:45:26.664142 2037 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-56" [r23] I181203 12:45:26.664388 2038 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-52" [r24] I181203 12:45:26.664614 2039 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-78" [r25] I181203 12:45:26.664898 2040 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-28" [r26] I181203 12:45:26.665173 2041 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-65" [r27] I181203 12:45:26.671728 2042 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-15" [r28] I181203 12:45:26.671968 2043 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-40" [r29] I181203 12:45:26.672171 2044 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-66" [r30] I181203 12:45:26.675164 2045 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-74" [r31] I181203 12:45:26.676173 2135 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-72" [r41] I181203 12:45:26.676924 2136 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-25" [r42] I181203 12:45:26.677080 2140 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-7" [r46] I181203 12:45:26.677169 2141 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-98" [r47] I181203 12:45:26.677318 2137 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-76" [r43] I181203 12:45:26.677373 2142 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-4" [r48] I181203 12:45:26.677513 2138 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-82" [r44] I181203 12:45:26.677561 2145 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-18" [r51] I181203 12:45:26.677590 2144 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-0" [r50] I181203 12:45:26.677711 2139 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-95" [r45] I181203 12:45:26.677752 2146 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-44" [r52] I181203 12:45:26.677943 2152 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-24" [r58] I181203 12:45:26.677996 2153 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-17" [r59] I181203 12:45:26.678176 2147 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-31" [r53] I181203 12:45:26.678222 2154 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-80" [r60] I181203 12:45:26.678274 2133 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-71" [r39] I181203 12:45:26.677571 2143 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-41" [r49] I181203 12:45:26.678455 2131 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-8" [r37] I181203 12:45:26.678513 2148 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-54" [r54] I181203 12:45:26.678609 2132 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-81" [r38] I181203 12:45:26.678667 2149 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-62" [r55] I181203 12:45:26.678807 2150 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-53" [r56] I181203 12:45:26.678900 2134 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-6" [r40] I181203 12:45:26.679042 2151 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-33" [r57] I181203 12:45:26.679280 2158 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-59" [r64] I181203 12:45:26.679413 2155 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-88" [r61] I181203 12:45:26.679447 2046 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-93" [r32] I181203 12:45:26.679579 2156 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-39" [r62] I181203 12:45:26.679605 2161 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-83" [r67] I181203 12:45:26.679724 2157 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-89" [r63] I181203 12:45:26.679739 2159 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-48" [r65] I181203 12:45:26.679877 2160 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-47" [r66] I181203 12:45:26.679426 2047 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-67" [r33] I181203 12:45:26.680197 2162 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-36" [r68] I181203 12:45:26.680209 2163 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-70" [r69] I181203 12:45:26.680378 2048 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-97" [r34] I181203 12:45:26.680515 2049 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-60" [r35] I181203 12:45:26.680702 2130 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-13" [r36] I181203 12:45:26.680759 2164 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-79" [r70] I181203 12:45:26.691198 2174 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-73" [r80] I181203 12:45:26.691503 2165 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-2" [r71] I181203 12:45:26.691773 2166 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-68" [r72] I181203 12:45:26.691826 2184 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-45" [r90] I181203 12:45:26.692056 2175 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-50" [r81] I181203 12:45:26.692099 2167 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-90" [r73] I181203 12:45:26.692281 2168 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-9" [r74] I181203 12:45:26.692373 2176 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-94" [r82] I181203 12:45:26.692536 2169 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-12" [r75] I181203 12:45:26.692637 2177 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-92" [r83] I181203 12:45:26.692801 2170 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-85" [r76] I181203 12:45:26.692885 2178 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-34" [r84] I181203 12:45:26.693055 2179 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-1" [r85] I181203 12:45:26.693156 2171 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-5" [r77] I181203 12:45:26.693308 2180 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-96" [r86] I181203 12:45:26.693447 2172 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-14" [r78] I181203 12:45:26.693572 2181 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-16" [r87] I181203 12:45:26.693731 2173 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-23" [r79] I181203 12:45:26.693765 2183 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-30" [r89] I181203 12:45:26.694011 2182 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-19" [r88] I181203 12:45:26.694136 2189 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-57" [r95] I181203 12:45:26.694209 2191 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-51" [r97] I181203 12:45:26.694283 2192 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-75" [r98] I181203 12:45:26.694420 2190 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-29" [r96] I181203 12:45:26.694459 2187 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-63" [r93] I181203 12:45:26.694601 2188 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-26" [r94] I181203 12:45:26.694626 2186 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-87" [r92] I181203 12:45:26.694676 2193 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-42" [r99] I181203 12:45:26.694609 2185 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-49" [r91] I181203 12:45:26.694923 2194 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-10" [r100] I181203 12:45:26.698575 2195 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-69" [r101] I181203 12:45:26.699341 2196 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-35" [r102] I181203 12:45:26.699558 2197 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-32" [r103] I181203 12:45:26.699771 2198 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-77" [r104] I181203 12:45:26.700003 2199 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-22" [r105] I181203 12:45:26.700202 2200 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-46" [r106] I181203 12:45:26.700409 2201 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-86" [r107] I181203 12:45:26.700613 2202 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-61" [r108] I181203 12:45:26.700820 2203 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-21" [r109] I181203 12:45:26.701059 2204 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-58" [r110] I181203 12:45:26.725735 2213 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-11" [r111] I181203 12:45:26.726536 2211 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-37" [r117] I181203 12:45:26.726793 2212 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-84" [r118] I181203 12:45:26.727190 2214 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-43" [r119] I181203 12:45:26.727406 2205 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-27" [r120] I181203 12:45:26.727749 2208 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-99" [r114] I181203 12:45:26.728080 2206 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-55" [r112] I181203 12:45:26.728416 2207 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-3" [r113] I181203 12:45:26.728702 2209 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-38" [r115] I181203 12:45:26.729063 2210 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-64" [r116] I181203 12:45:26.864553 1984 storage/replica_command.go:492 [n1,merge,s1,r6/1:{/System/tse-key-91}] initiating a merge of r21:{key-91-/Table/SystemConfigSpan/Start} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=0] into this range I181203 12:45:26.888052 2130 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-13" [r121] I181203 12:45:26.888625 2165 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-2" [r122] I181203 12:45:26.889093 2174 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-73" [r123] I181203 12:45:26.893591 2176 storage/replica_command.go:349 [n1,s1,r21/1:{key-91-/Table/Syste…}] initiating a split of this range at key "key-94" [r124] I181203 12:45:26.895401 2175 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-50" [r125] I181203 12:45:26.896688 2169 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-12" [r126] I181203 12:45:26.896767 2171 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-5" [r127] I181203 12:45:26.897390 2189 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-57" [r128] I181203 12:45:26.897729 2185 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-49" [r129] I181203 12:45:26.898016 2186 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-87" [r130] I181203 12:45:26.900203 2172 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-14" [r135] I181203 12:45:26.900556 2045 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-74" [r136] I181203 12:45:26.900611 2206 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-55" [r137] I181203 12:45:26.900761 2201 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-86" [r132] I181203 12:45:26.900838 2197 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-32" [r133] I181203 12:45:26.901086 2191 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-51" [r131] I181203 12:45:26.901103 2170 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-85" [r138] I181203 12:45:26.901171 2166 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-68" [r134] I181203 12:45:26.901515 2177 storage/replica_command.go:349 [n1,s1,r21/1:{key-91-/Table/Syste…}] initiating a split of this range at key "key-92" [r139] I181203 12:45:26.902646 2168 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-9" [r140] I181203 12:45:26.903083 2214 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-43" [r141] I181203 12:45:26.903099 2167 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-90" [r142] I181203 12:45:26.904114 2180 storage/replica_command.go:349 [n1,s1,r21/1:{key-91-/Table/Syste…}] initiating a split of this range at key "key-96" [r143] I181203 12:45:26.904236 2182 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-19" [r144] I181203 12:45:26.904769 2184 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-45" [r145] I181203 12:45:26.905376 2204 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-58" [r146] I181203 12:45:26.905569 2178 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-34" [r147] I181203 12:45:26.905596 2036 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-20" [r148] I181203 12:45:26.905705 2193 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-42" [r149] I181203 12:45:26.905851 2208 storage/replica_command.go:349 [n1,s1,r21/1:{key-91-/Table/Syste…}] initiating a split of this range at key "key-99" [r150] I181203 12:45:26.917148 2183 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-30" [r155] I181203 12:45:26.917236 947 gossip/gossip.go:1515 [n2] node has connected to cluster via gossip I181203 12:45:26.917486 2187 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-63" [r156] I181203 12:45:26.917531 947 storage/stores.go:261 [n2] wrote 2 node addresses to persistent storage I181203 12:45:26.917578 2199 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-22" [r151] I181203 12:45:26.917717 2205 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-27" [r152] I181203 12:45:26.917803 2207 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-3" [r154] I181203 12:45:26.917851 2181 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-16" [r159] I181203 12:45:26.917877 2190 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-29" [r160] I181203 12:45:26.918007 2212 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-84" [r157] I181203 12:45:26.918041 2198 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-91}] initiating a split of this range at key "key-77" [r158] I181203 12:45:26.923510 470 storage/store.go:2563 [n1,s1,r6/1:{/System/tse-key-91}] removing replica r21/1 I181203 12:45:26.923633 1084 storage/store.go:2563 [n2,s2,r6/2:{/System/tse-key-91}] removing replica r21/2 I181203 12:45:26.923818 1515 storage/store.go:2563 [n3,s3,r6/3:{/System/tse-key-91}] removing replica r21/3 I181203 12:45:26.924777 2202 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-61" [r163] I181203 12:45:26.925365 2179 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-1" [r161] I181203 12:45:26.925508 2173 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-23" [r162] I181203 12:45:26.925651 2196 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-35" [r167] I181203 12:45:26.925775 2188 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-26" [r164] I181203 12:45:26.925910 2195 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-69" [r165] I181203 12:45:26.926038 2203 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-21" [r166] I181203 12:45:26.926165 2037 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-56" [r169] I181203 12:45:26.926260 2213 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-11" [r168] I181203 12:45:26.926701 2039 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-78" [r170] I181203 12:45:26.927515 2194 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-10" [r153] I181203 12:45:26.927798 2211 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-37" [r175] I181203 12:45:26.927816 2038 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-52" [r172] I181203 12:45:26.927830 2044 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-66" [r178] I181203 12:45:26.927837 2042 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-15" [r179] I181203 12:45:26.927957 2200 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-46" [r171] I181203 12:45:26.927986 2210 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-64" [r174] I181203 12:45:26.928059 2192 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-75" [r173] I181203 12:45:26.928103 2209 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-38" [r176] I181203 12:45:26.933550 2135 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-72" [r180] I181203 12:45:26.945282 2041 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-65" [r177] I181203 12:45:26.946682 2043 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-40" [r182] I181203 12:45:26.947054 2040 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-28" [r181] I181203 12:45:27.030347 1288 gossip/gossip.go:1515 [n3] node has connected to cluster via gossip I181203 12:45:27.030673 1288 storage/stores.go:261 [n3] wrote 2 node addresses to persistent storage I181203 12:45:27.093089 2137 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-76" [r183] I181203 12:45:27.093466 2140 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-7" [r184] I181203 12:45:27.095719 2154 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-80" [r185] I181203 12:45:27.098385 2141 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-98" [r186] I181203 12:45:27.098602 2142 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-4" [r187] I181203 12:45:27.098754 2145 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-18" [r188] I181203 12:45:27.100818 2146 storage/replica_command.go:349 [n1,s1,r6/1:/{System/tse-Table/System…}] initiating a split of this range at key "key-44" [r189] I181203 12:45:27.105059 2153 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-76}] initiating a split of this range at key "key-17" [r190] I181203 12:45:27.105149 2144 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-76}] initiating a split of this range at key "key-0" [r191] I181203 12:45:27.105394 2138 storage/replica_command.go:349 [n1,s1,r183/1:{key-76-/Table/Syste…}] initiating a split of this range at key "key-82" [r192] I181203 12:45:27.105432 2139 storage/replica_command.go:349 [n1,s1,r183/1:{key-76-/Table/Syste…}] initiating a split of this range at key "key-95" [r193] I181203 12:45:27.105462 2147 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-76}] initiating a split of this range at key "key-31" [r194] I181203 12:45:27.105898 2133 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-76}] initiating a split of this range at key "key-71" [r195] I181203 12:45:27.106588 2152 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-76}] initiating a split of this range at key "key-24" [r196] I181203 12:45:27.125603 2296 storage/replica_command.go:492 [n1,merge,s1,r6/1:{/System/tse-key-17}] initiating a merge of r183:key-{76-82} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=1] into this range I181203 12:45:27.132365 2043 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-76}] initiating a split of this range at key "key-40" [r197] I181203 12:45:27.132478 2200 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-76}] initiating a split of this range at key "key-46" [r198] I181203 12:45:27.135981 2209 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-76}] initiating a split of this range at key "key-38" [r199] I181203 12:45:27.138213 2469 storage/replica_command.go:492 [n1,merge,s1,r183/1:key-{76-82}] initiating a merge of r192:{key-82-/Table/SystemConfigSpan/Start} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=0] into this range I181203 12:45:27.147312 2177 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-92" [r203] I181203 12:45:27.150238 2180 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-96" [r204] I181203 12:45:27.152297 2176 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-94" [r207] I181203 12:45:27.154974 2213 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-17}] initiating a split of this range at key "key-11" [r212] I181203 12:45:27.154989 2039 storage/replica_command.go:349 [n1,s1,r183/1:key-{76-82}] initiating a split of this range at key "key-78" [r216] I181203 12:45:27.155219 2179 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-17}] initiating a split of this range at key "key-1" [r218] I181203 12:45:27.156281 2194 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-17}] initiating a split of this range at key "key-10" [r214] I181203 12:45:27.157291 2042 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-17}] initiating a split of this range at key "key-15" [r221] I181203 12:45:27.171575 2139 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-95" [r224] I181203 12:45:27.176405 2132 storage/replica_command.go:349 [n1,s1,r183/1:key-{76-82}] initiating a split of this range at key "key-81" [r227] I181203 12:45:27.179638 2046 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-93" [r228] I181203 12:45:27.180490 2155 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-88" [r229] I181203 12:45:27.183231 2048 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-97" [r233] I181203 12:45:27.188399 2161 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-83" [r240] I181203 12:45:27.195459 2157 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-89" [r243] I181203 12:45:27.196888 2159 storage/replica_command.go:349 [n1,s1,r198/1:key-{46-76}] initiating a split of this range at key "key-48" [r244] I181203 12:45:27.198076 2049 storage/replica_command.go:349 [n1,s1,r198/1:key-{46-76}] initiating a split of this range at key "key-60" [r245] I181203 12:45:27.199464 2164 storage/replica_command.go:349 [n1,s1,r183/1:key-{76-82}] initiating a split of this range at key "key-79" [r246] I181203 12:45:27.241524 2186 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-87" [r251] I181203 12:45:27.256668 2201 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-86" [r259] I181203 12:45:27.262160 2189 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-76}] initiating a split of this range at key "key-57" [r261] I181203 12:45:27.263968 2191 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-76}] initiating a split of this range at key "key-51" [r262] I181203 12:45:27.272137 2131 storage/replica_command.go:349 [n1,s1,r183/1:key-{76-82}] initiating a split of this range at key "key-8" [r263] I181203 12:45:27.295605 2168 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-9" [r266] I181203 12:45:27.301413 2167 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-90" [r268] I181203 12:45:27.307283 2144 storage/replica_command.go:349 [n1,s1,r6/1:{/System/tse-key-11}] initiating a split of this range at key "key-0" [r271] I181203 12:45:27.311096 2198 storage/replica_command.go:349 [n1,s1,r183/1:key-{76-82}] initiating a split of this range at key "key-77" [r278] I181203 12:45:27.343464 2212 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-84" [r282] I181203 12:45:27.344084 2141 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-98" [r283] I181203 12:45:27.344173 2154 storage/replica_command.go:349 [n1,s1,r183/1:key-{76-82}] initiating a split of this range at key "key-80" [r288] I181203 12:45:27.379165 2208 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-99" [r222]; delayed split for 0.2s to avoid Raft snapshot I181203 12:45:27.380122 2170 storage/replica_command.go:349 [n1,s1,r192/1:{key-82-/Table/Syste…}] initiating a split of this range at key "key-85" [r291] I181203 12:45:27.404565 2179 storage/replica_command.go:349 [n1,s1,r271/1:key-{0-11}] initiating a split of this range at key "key-1" [r296] I181203 12:45:27.466011 2194 storage/replica_command.go:349 [n1,s1,r296/1:key-1{-1}] initiating a split of this range at key "key-10" [r298] I181203 12:45:28.270223 2197 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-32" [r257]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.270225 2165 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-2" [r250]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.277283 2209 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-38" [r264]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.283151 2043 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-40" [r265]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.305482 2178 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-34" [r267]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.307948 2793 storage/replica_command.go:492 [n2,merge,s2,r190/2:key-{17-32}] initiating a merge of r257:key-{32-46} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=0] into this range I181203 12:45:28.310879 2183 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-30" [r272]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.311076 2036 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-20" [r270]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.311196 2207 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-3" [r269]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.315876 2205 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-27" [r276]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.316692 2147 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-31" [r277]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.322654 2182 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-32}] initiating a split of this range at key "key-19" [r280]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.331535 1012 storage/store.go:2563 [n2,s2,r190/2:key-{17-32}] removing replica r257/2 I181203 12:45:28.335976 1468 storage/store.go:2563 [n3,s3,r190/3:key-{17-32}] removing replica r257/3 I181203 12:45:28.336283 373 storage/store.go:2563 [n1,s1,r190/1:key-{17-32}] removing replica r257/1 I181203 12:45:28.341550 2975 storage/replica_command.go:492 [n2,merge,s2,r190/2:key-{17-46}] initiating a merge of r198:key-4{6-8} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=1] into this range I181203 12:45:28.345327 2044 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-66" [r202]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.347418 2190 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-29" [r281]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.347514 2214 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-43" [r285]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.347567 2210 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-64" [r200]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.347834 2193 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-42" [r284]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.349326 2135 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-72" [r201]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.350309 2184 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-45" [r286]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.377019 2188 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-26" [r210]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.377252 2037 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-56" [r209]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.377266 2203 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-21" [r208]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.377385 2038 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-52" [r205]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.377439 2192 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-75" [r206]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.379259 2702 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-2" [r311] I181203 12:45:28.381143 2686 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-34" [r312] I181203 12:45:28.382304 3112 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-30" [r313] I181203 12:45:28.383497 2173 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-23" [r211]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383662 2041 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-65" [r219]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383692 2142 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-4" [r293]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.383715 2199 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-22" [r292]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.383737 2145 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-18" [r294]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.383745 2152 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-24" [r295]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.383762 2158 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-59" [r231]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383780 2202 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-61" [r213]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383786 2211 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-37" [r220]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383804 2150 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-53" [r226]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383845 2040 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-28" [r223]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383864 2148 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-54" [r230]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383886 2195 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-69" [r215]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383888 2196 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-35" [r217]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.383918 2151 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-33" [r225]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.385417 2156 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-39" [r234]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.388399 2160 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-47" [r237]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.388549 2143 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-41" [r232]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.388563 2047 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-67" [r236]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.390724 3234 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-3" [r314] I181203 12:45:28.391997 2134 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-6" [r235]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.392074 3235 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-27" [r315] I181203 12:45:28.392569 2162 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-36" [r239]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.392804 2929 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-20" [r316] I181203 12:45:28.394100 3045 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-31" [r317] I181203 12:45:28.397864 2149 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-62" [r238]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.400010 2136 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-25" [r242]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.404985 2163 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-70" [r241]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.406180 2146 storage/replica_command.go:349 [n1,s1,r190/1:key-{17-46}] initiating a split of this range at key "key-44" [r247]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.411334 2540 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-38" [r318] I181203 12:45:28.412317 3009 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-19" [r319] I181203 12:45:28.412539 2543 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-46}] initiating a split of this range at key "key-40" [r320] I181203 12:45:28.440221 2042 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-7}] initiating a split of this range at key "key-15" [r297]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:28.453764 1065 storage/store.go:2563 [n2,s2,r190/2:key-{17-46}] removing replica r198/2 I181203 12:45:28.458305 1526 storage/store.go:2563 [n3,s3,r190/3:key-{17-46}] removing replica r198/3 I181203 12:45:28.460403 472 storage/store.go:2563 [n1,s1,r190/1:key-{17-46}] removing replica r198/1 I181203 12:45:28.466605 2175 storage/replica_command.go:349 [n1,s1,r198/1:key-4{6-8}] initiating a split of this range at key "key-50" [r258]; not Raft leader; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.466726 2045 storage/replica_command.go:349 [n1,s1,r198/1:key-4{6-8}] initiating a split of this range at key "key-74" [r256]; not Raft leader; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.466796 2206 storage/replica_command.go:349 [n1,s1,r198/1:key-4{6-8}] initiating a split of this range at key "key-55" [r260]; not Raft leader; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.466924 2185 storage/replica_command.go:349 [n1,s1,r198/1:key-4{6-8}] initiating a split of this range at key "key-49" [r255]; not Raft leader; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.467048 2174 storage/replica_command.go:349 [n1,s1,r198/1:key-4{6-8}] initiating a split of this range at key "key-73" [r253]; not Raft leader; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.467140 2171 storage/replica_command.go:349 [n1,s1,r198/1:key-4{6-8}] initiating a split of this range at key "key-5" [r254]; not Raft leader; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.469971 2172 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-7}] initiating a split of this range at key "key-14" [r249]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.470044 2130 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-7}] initiating a split of this range at key "key-13" [r248]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.470957 2169 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-7}] initiating a split of this range at key "key-12" [r252]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.471109 3275 storage/replica_command.go:492 [n2,merge,s2,r190/2:key-{17-48}] initiating a merge of r244:key-{48-57} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=1] into this range I181203 12:45:28.504423 2702 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-2" [r321] I181203 12:45:28.509620 3181 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-43" [r322] I181203 12:45:28.510354 3179 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-45" [r323] I181203 12:45:28.510737 3371 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-29" [r324] I181203 12:45:28.511624 2686 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-34" [r325] I181203 12:45:28.511980 3222 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-26" [r326] I181203 12:45:28.512064 3221 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-4" [r327] I181203 12:45:28.513243 2575 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-37" [r328] I181203 12:45:28.514897 3191 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-23" [r329] I181203 12:45:28.515884 3375 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-42" [r330] I181203 12:45:28.516030 2181 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-5}] initiating a split of this range at key "key-16" [r274]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:28.516644 3112 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-30" [r331] I181203 12:45:28.516843 3377 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-21" [r332] I181203 12:45:28.517349 2130 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-5}] initiating a split of this range at key "key-13" [r305] I181203 12:45:28.517421 3458 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-28" [r333] I181203 12:45:28.517889 3194 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-33" [r334] I181203 12:45:28.524410 2169 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-5}] initiating a split of this range at key "key-12" [r308] I181203 12:45:28.524855 3493 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-22" [r335] I181203 12:45:28.526858 3492 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-35" [r336] I181203 12:45:28.527499 2172 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-5}] initiating a split of this range at key "key-14" [r310] I181203 12:45:28.528184 3294 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-24" [r337] I181203 12:45:28.528556 3245 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-18" [r338] I181203 12:45:28.530779 3429 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-48}] initiating a split of this range at key "key-39" [r339] I181203 12:45:28.565366 2181 storage/replica_command.go:349 [n1,s1,r297/1:key-1{5-7}] initiating a split of this range at key "key-16" [r345] I181203 12:45:28.634952 2172 storage/replica_command.go:349 [n1,s1,r305/1:key-1{3-5}] initiating a split of this range at key "key-14" [r349] I181203 12:45:28.656800 2169 storage/replica_command.go:349 [n1,s1,r212/1:key-1{1-3}] initiating a split of this range at key "key-12" [r361] I181203 12:45:29.422009 2185 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-49" [r346]; delayed split for 0.8s to avoid Raft snapshot I181203 12:45:29.427529 2206 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-55" [r347]; delayed split for 0.8s to avoid Raft snapshot I181203 12:45:29.457495 2175 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-50" [r362]; delayed split for 0.8s to avoid Raft snapshot I181203 12:45:29.460211 2171 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-5" [r363]; delayed split for 0.8s to avoid Raft snapshot I181203 12:45:29.555608 2166 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-68" [r275]; delayed split for 2.2s to avoid Raft snapshot I181203 12:45:29.555864 2191 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-51" [r299]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.555910 2049 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-60" [r279]; delayed split for 2.2s to avoid Raft snapshot I181203 12:45:29.556047 2150 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-53" [r309]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:29.556107 2148 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-54" [r344]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:29.556162 2140 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-7" [r287]; delayed split for 2.2s to avoid Raft snapshot I181203 12:45:29.556048 2204 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-58" [r289]; delayed split for 2.2s to avoid Raft snapshot I181203 12:45:29.556486 2187 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-63" [r290]; delayed split for 2.2s to avoid Raft snapshot I181203 12:45:29.559072 2133 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-71" [r273]; delayed split for 2.2s to avoid Raft snapshot I181203 12:45:29.559134 2037 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-56" [r303]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:29.559458 2038 storage/replica_command.go:349 [n1,s1,r244/1:key-{48-57}] initiating a split of this range at key "key-52" [r300]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:29.632726 2174 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-76}] initiating a split of this range at key "key-73" [r348]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:29.650725 2045 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-74" [r350]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:29.669303 1086 storage/store.go:2563 [n2,s2,r190/2:key-{17-48}] removing replica r244/2 I181203 12:45:29.669842 358 storage/store.go:2563 [n1,s1,r190/1:key-{17-48}] removing replica r244/1 I181203 12:45:29.670360 1385 storage/store.go:2563 [n3,s3,r190/3:key-{17-48}] removing replica r244/3 I181203 12:45:29.697380 2045 storage/replica_command.go:349 [n1,s1,r348/1:key-7{3-6}] initiating a split of this range at key "key-74" [r364] I181203 12:45:29.720783 3758 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-41" [r340] I181203 12:45:29.727387 3235 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-27" [r351] I181203 12:45:29.729537 4002 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-47" [r352] I181203 12:45:29.730872 3689 storage/replica_command.go:492 [n3,merge,s3,r261/3:key-{57-73}] initiating a merge of r348:key-7{3-4} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=1] into this range I181203 12:45:29.731233 3179 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-45" [r353] I181203 12:45:29.732951 3191 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-23" [r354] I181203 12:45:29.734493 2540 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-38" [r355] I181203 12:45:29.734679 3371 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-29" [r357] I181203 12:45:29.734757 3045 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-31" [r356] I181203 12:45:29.734925 3878 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-44" [r358] I181203 12:45:29.734965 2929 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-20" [r359] I181203 12:45:29.735144 3009 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-19" [r360] I181203 12:45:29.735822 3234 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-3" [r381] I181203 12:45:29.736272 3492 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-35" [r382] I181203 12:45:29.736282 3222 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-26" [r383] I181203 12:45:29.736595 4086 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-25" [r384] I181203 12:45:29.737502 3821 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-73}] initiating a split of this range at key "key-67" [r391] I181203 12:45:29.737850 3221 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-4" [r385] I181203 12:45:29.738141 3493 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-22" [r386] I181203 12:45:29.739321 2575 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-37" [r387] I181203 12:45:29.739455 3181 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-43" [r388] I181203 12:45:29.739783 2543 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-40" [r389] I181203 12:45:29.739849 4023 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-36" [r390] I181203 12:45:29.742350 3142 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-73}] initiating a split of this range at key "key-70" [r392] I181203 12:45:29.743017 3141 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-73}] initiating a split of this range at key "key-62" [r393] I181203 12:45:29.743381 3140 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-73}] initiating a split of this range at key "key-6" [r394] I181203 12:45:29.744897 3377 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-21" [r401] I181203 12:45:29.745095 3375 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-42" [r402] I181203 12:45:29.745209 3429 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-39" [r403] I181203 12:45:29.745748 3194 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-33" [r404] I181203 12:45:29.747026 3458 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-28" [r405] I181203 12:45:29.750039 3245 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-18" [r406] I181203 12:45:29.750208 3294 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-24" [r407] I181203 12:45:29.755682 2192 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-75" [r307]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.755976 2210 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-64" [r304]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.755980 2135 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-72" [r306]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.756253 2158 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-59" [r343]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.756376 2195 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-69" [r342]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.759199 2044 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-66" [r301]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.759527 2041 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-65" [r302]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.761215 2202 storage/replica_command.go:349 [n1,s1,r261/1:key-{57-73}] initiating a split of this range at key "key-61" [r341]; delayed split for 1.2s to avoid Raft snapshot I181203 12:45:29.775231 3112 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-30" [r408] I181203 12:45:29.789029 2686 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-34" [r409] I181203 12:45:29.790046 2702 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-57}] initiating a split of this range at key "key-2" [r410] I181203 12:45:29.826255 1486 storage/store.go:2563 [n3,s3,r261/3:key-{57-73}] removing replica r348/3 I181203 12:45:29.826982 996 storage/store.go:2563 [n2,s2,r261/2:key-{57-73}] removing replica r348/2 I181203 12:45:29.827168 366 storage/store.go:2563 [n1,s1,r261/1:key-{57-73}] removing replica r348/1 I181203 12:45:29.829947 3965 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-74}] initiating a split of this range at key "key-7" [r395] I181203 12:45:29.830314 3871 storage/replica_command.go:492 [n3,merge,s3,r261/3:key-{57-74}] initiating a merge of r364:key-7{4-6} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=0] into this range I181203 12:45:29.868193 4061 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-7}] initiating a split of this range at key "key-63" [r396] I181203 12:45:29.868702 4182 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-49" [r421] I181203 12:45:29.869458 3944 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-56" [r422] I181203 12:45:29.870167 4142 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-7}] initiating a split of this range at key "key-58" [r397] I181203 12:45:29.871067 4306 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-54" [r423] I181203 12:45:29.872074 4095 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-7}] initiating a split of this range at key "key-68" [r398] I181203 12:45:29.873288 4284 storage/replica_command.go:349 [n3,s3,r395/3:key-7{-4}] initiating a split of this range at key "key-71" [r399] I181203 12:45:29.874344 3821 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-7}] initiating a split of this range at key "key-67" [r400] I181203 12:45:29.875022 4072 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-5" [r424] I181203 12:45:29.875518 4172 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-53" [r425] I181203 12:45:29.885717 3585 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-52" [r426] I181203 12:45:29.886226 4065 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-51" [r427] I181203 12:45:29.886312 4174 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-7}] initiating a split of this range at key "key-60" [r411] I181203 12:45:29.886480 4322 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-50" [r428] I181203 12:45:29.903979 4289 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-47" [r429] I181203 12:45:29.916091 4310 storage/replica_command.go:349 [n2,s2,r340/2:key-{41-57}] initiating a split of this range at key "key-44" [r430] I181203 12:45:29.918316 4023 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-36" [r431] I181203 12:45:29.919544 3140 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-7}] initiating a split of this range at key "key-6" [r412] I181203 12:45:29.920746 4086 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-25" [r432] I181203 12:45:29.925672 3141 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-7}] initiating a split of this range at key "key-62" [r413] I181203 12:45:29.936294 2540 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-38" [r433] I181203 12:45:29.936465 4407 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-57}] initiating a split of this range at key "key-55" [r434] I181203 12:45:29.942522 3235 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-27" [r435] I181203 12:45:29.946140 3191 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-23" [r436] I181203 12:45:29.947355 3371 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-29" [r437] I181203 12:45:29.950001 3045 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-31" [r438] I181203 12:45:29.952601 4466 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-9}] initiating a split of this range at key "key-45" [r439] I181203 12:45:29.955827 3234 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-3" [r440] I181203 12:45:29.956182 4442 storage/replica_command.go:492 [n3,merge,s3,r395/3:key-7{-1}] initiating a merge of r399:key-7{1-4} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=0] into this range I181203 12:45:29.956298 3221 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-4" [r441] I181203 12:45:29.977747 3009 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-19" [r442] I181203 12:45:29.979191 2929 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-20" [r443] I181203 12:45:29.979368 3222 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-26" [r444] I181203 12:45:29.979624 3194 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-33" [r445] I181203 12:45:29.980207 3492 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-35" [r446] I181203 12:45:29.980239 2543 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-40" [r447] I181203 12:45:29.980848 3458 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-28" [r448] I181203 12:45:29.981479 4289 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-9}] initiating a split of this range at key "key-47" [r449] I181203 12:45:30.016002 2575 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-37" [r450] I181203 12:45:30.016272 4142 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-63}] initiating a split of this range at key "key-58" [r414] I181203 12:45:30.017238 4063 storage/replica_command.go:492 [n2,merge,s2,r340/2:key-4{1-9}] initiating a merge of r261:key-{57-63} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=4] into this range I181203 12:45:30.019089 4266 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-63}] initiating a split of this range at key "key-59" [r415] I181203 12:45:30.019924 3999 storage/replica_command.go:349 [n3,s3,r396/3:key-{63-7}] initiating a split of this range at key "key-66" [r417] I181203 12:45:30.019954 3997 storage/replica_command.go:349 [n3,s3,r395/3:key-7{-1}] initiating a split of this range at key "key-70" [r416] I181203 12:45:30.020437 4269 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-63}] initiating a split of this range at key "key-61" [r418] I181203 12:45:30.021086 4348 storage/replica_command.go:349 [n3,s3,r396/3:key-{63-7}] initiating a split of this range at key "key-69" [r419] I181203 12:45:30.021153 3998 storage/replica_command.go:349 [n3,s3,r396/3:key-{63-7}] initiating a split of this range at key "key-68" [r420] I181203 12:45:30.022246 3493 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-22" [r451] I181203 12:45:30.022428 4310 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-9}] initiating a split of this range at key "key-44" [r454] I181203 12:45:30.022468 3377 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-21" [r455] I181203 12:45:30.022691 3294 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-24" [r456] I181203 12:45:30.022708 3245 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-18" [r452] I181203 12:45:30.022731 3429 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-41}] initiating a split of this range at key "key-39" [r453] I181203 12:45:30.023719 4446 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-57}] initiating a split of this range at key "key-51" [r457] I181203 12:45:30.025006 4364 storage/replica_command.go:349 [n3,s3,r396/3:key-{63-7}] initiating a split of this range at key "key-65" [r463] I181203 12:45:30.025683 4015 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-57}] initiating a split of this range at key "key-53" [r459] I181203 12:45:30.025691 4611 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-57}] initiating a split of this range at key "key-5" [r460] I181203 12:45:30.025699 4610 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-57}] initiating a split of this range at key "key-56" [r458] I181203 12:45:30.027010 4174 storage/replica_command.go:349 [n3,s3,r261/3:key-{57-63}] initiating a split of this range at key "key-60" [r464] I181203 12:45:30.027482 4017 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-9}] initiating a split of this range at key "key-42" [r474] I181203 12:45:30.027502 4016 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-57}] initiating a split of this range at key "key-50" [r472] I181203 12:45:30.027507 4612 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-57}] initiating a split of this range at key "key-52" [r473] I181203 12:45:30.027541 4614 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-9}] initiating a split of this range at key "key-43" [r471] I181203 12:45:30.028152 2192 storage/replica_command.go:349 [n1,s1,r364/1:key-7{4-6}] initiating a split of this range at key "key-75" [r365] I181203 12:45:30.040923 4613 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-55}] initiating a split of this range at key "key-54" [r475] I181203 12:45:30.066181 4645 storage/replica_command.go:349 [n3,s3,r417/3:key-{66-7}] initiating a split of this range at key "key-67" [r465] I181203 12:45:30.081980 2686 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-36}] initiating a split of this range at key "key-34" [r476] I181203 12:45:30.084258 4611 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-55}] initiating a split of this range at key "key-5" [r477] I181203 12:45:30.085412 4015 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-55}] initiating a split of this range at key "key-53" [r478] I181203 12:45:30.085789 4446 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-55}] initiating a split of this range at key "key-51" [r479] I181203 12:45:30.087700 4473 storage/replica_command.go:349 [n2,s2,r434/2:key-5{5-7}] initiating a split of this range at key "key-56" [r480] I181203 12:45:30.088430 4016 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-55}] initiating a split of this range at key "key-50" [r491] I181203 12:45:30.093274 4612 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-55}] initiating a split of this range at key "key-52" [r492] I181203 12:45:30.093530 3112 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-36}] initiating a split of this range at key "key-30" [r493] I181203 12:45:30.098393 4664 storage/replica_command.go:349 [n3,s3,r417/3:key-{66-7}] initiating a split of this range at key "key-68" [r466] I181203 12:45:30.098401 4665 storage/replica_command.go:349 [n3,s3,r417/3:key-{66-7}] initiating a split of this range at key "key-69" [r467] I181203 12:45:30.104516 1410 storage/store.go:2563 [n3,s3,r395/3:key-7{-1}] removing replica r399/3 I181203 12:45:30.104652 1113 storage/store.go:2563 [n2,s2,r395/2:key-7{-1}] removing replica r399/2 I181203 12:45:30.107972 386 storage/store.go:2563 [n1,s1,r395/1:key-7{-1}] removing replica r399/1 I181203 12:45:30.111778 2702 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-36}] initiating a split of this range at key "key-2" [r494] I181203 12:45:30.143868 4364 storage/replica_command.go:349 [n3,s3,r396/3:key-6{3-6}] initiating a split of this range at key "key-65" [r468] I181203 12:45:30.150789 4086 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-36}] initiating a split of this range at key "key-25" [r496] I181203 12:45:30.189570 3997 storage/replica_command.go:349 [n3,s3,r395/3:key-7{-4}] initiating a split of this range at key "key-70" [r469] I181203 12:45:30.189894 4614 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-5}] initiating a split of this range at key "key-43" [r497] I181203 12:45:30.192040 4539 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-63}] initiating a split of this range at key "key-62" [r470] I181203 12:45:30.192674 4699 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-63}] initiating a split of this range at key "key-61" [r481] I181203 12:45:30.192988 4698 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-63}] initiating a split of this range at key "key-59" [r482] I181203 12:45:30.193575 4540 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-63}] initiating a split of this range at key "key-6" [r483] I181203 12:45:30.193799 4697 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-63}] initiating a split of this range at key "key-60" [r484] I181203 12:45:30.207276 4017 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-5}] initiating a split of this range at key "key-42" [r495] I181203 12:45:30.210733 4756 storage/replica_command.go:492 [n3,merge,s3,r396/3:key-6{3-6}] initiating a merge of r417:key-6{6-7} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=1] into this range I181203 12:45:30.220436 4310 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-5}] initiating a split of this range at key "key-44" [r499] I181203 12:45:30.223158 4347 storage/replica_command.go:349 [n3,s3,r399/3:key-7{1-4}] initiating a split of this range at key "key-72" [r461]; not Raft leader; delayed split for 0.2s to avoid Raft snapshot I181203 12:45:30.223494 4499 storage/replica_command.go:349 [n3,s3,r396/3:key-6{3-6}] initiating a split of this range at key "key-64" [r462]; delayed split for 0.2s to avoid Raft snapshot I181203 12:45:30.229078 3045 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-31" [r500] I181203 12:45:30.243742 5010 storage/replica_command.go:349 [n2,s2,r476/2:key-3{4-6}] initiating a split of this range at key "key-35" [r501] I181203 12:45:30.245234 5011 storage/replica_command.go:349 [n2,s2,r431/2:key-{36-41}] initiating a split of this range at key "key-38" [r502] I181203 12:45:30.246689 3371 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-29" [r503] I181203 12:45:30.250642 3235 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-27" [r504] I181203 12:45:30.252052 2929 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-20" [r505] I181203 12:45:30.254396 3294 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-24" [r506] I181203 12:45:30.257119 3222 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-26" [r507] I181203 12:45:30.257875 3458 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-28" [r508] I181203 12:45:30.258328 5058 storage/replica_command.go:349 [n2,s2,r431/2:key-{36-41}] initiating a split of this range at key "key-39" [r509] I181203 12:45:30.259129 4785 storage/replica_command.go:349 [n2,s2,r431/2:key-{36-41}] initiating a split of this range at key "key-4" [r510] I181203 12:45:30.261560 4813 storage/replica_command.go:349 [n3,s3,r465/3:key-{67-7}] initiating a split of this range at key "key-68" [r485] I181203 12:45:30.261635 4904 storage/replica_command.go:349 [n3,s3,r465/3:key-{67-7}] initiating a split of this range at key "key-69" [r486] I181203 12:45:30.262842 3234 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-34}] initiating a split of this range at key "key-3" [r521] I181203 12:45:30.312013 3245 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-31}] initiating a split of this range at key "key-18" [r522] I181203 12:45:30.312333 3377 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-31}] initiating a split of this range at key "key-21" [r523] I181203 12:45:30.312394 3009 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-31}] initiating a split of this range at key "key-19" [r524] I181203 12:45:30.313769 3191 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-31}] initiating a split of this range at key "key-23" [r525] I181203 12:45:30.314148 3493 storage/replica_command.go:349 [n2,s2,r190/2:key-{17-31}] initiating a split of this range at key "key-22" [r526] I181203 12:45:30.314304 4611 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-54}] initiating a split of this range at key "key-5" [r527] I181203 12:45:30.316119 4015 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-54}] initiating a split of this range at key "key-53" [r528] I181203 12:45:30.343825 4446 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-54}] initiating a split of this range at key "key-51" [r529] I181203 12:45:30.345126 4540 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-62}] initiating a split of this range at key "key-6" [r487] I181203 12:45:30.345816 4698 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-62}] initiating a split of this range at key "key-59" [r488] I181203 12:45:30.345880 4697 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-62}] initiating a split of this range at key "key-60" [r490] I181203 12:45:30.346143 4016 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-54}] initiating a split of this range at key "key-50" [r530] I181203 12:45:30.346166 4699 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-62}] initiating a split of this range at key "key-61" [r511] I181203 12:45:30.345845 4499 storage/replica_command.go:349 [n3,s3,r396/3:key-6{3-5}] initiating a split of this range at key "key-64" [r489] I181203 12:45:30.354601 4612 storage/replica_command.go:349 [n2,s2,r421/2:key-{49-54}] initiating a split of this range at key "key-52" [r531] I181203 12:45:30.354636 4847 storage/replica_command.go:349 [n2,s2,r500/2:key-3{1-4}] initiating a split of this range at key "key-33" [r534] I181203 12:45:30.354685 5000 storage/replica_command.go:349 [n2,s2,r502/2:key-{38-41}] initiating a split of this range at key "key-40" [r533] I181203 12:45:30.354693 4017 storage/replica_command.go:349 [n2,s2,r340/2:key-4{1-3}] initiating a split of this range at key "key-42" [r532] I181203 12:45:30.369068 5095 storage/replica_command.go:349 [n2,s2,r431/2:key-3{6-8}] initiating a split of this range at key "key-37" [r535] I181203 12:45:30.387029 5167 storage/replica_command.go:349 [n2,s2,r502/2:key-{38-41}] initiating a split of this range at key "key-39" [r536] I181203 12:45:30.395738 4834 storage/replica_command.go:349 [n2,s2,r439/2:key-4{5-9}] initiating a split of this range at key "key-47" [r498]; delayed split for 0.2s to avoid Raft snapshot I181203 12:45:30.464317 4813 storage/replica_command.go:349 [n3,s3,r465/3:key-6{7-9}] initiating a split of this range at key "key-68" [r512] I181203 12:45:30.464874 5211 storage/replica_command.go:349 [n2,s2,r502/2:key-{38-41}] initiating a split of this range at key "key-4" [r537] I181203 12:45:30.471527 5214 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-31}] initiating a split of this range at key "key-25" [r538] I181203 12:45:30.498461 5411 storage/replica_command.go:349 [n2,s2,r497/2:key-4{3-5}] initiating a split of this range at key "key-44" [r539] I181203 12:45:30.550647 5167 storage/replica_command.go:349 [n2,s2,r502/2:key-{38-40}] initiating a split of this range at key "key-39" [r540] I181203 12:45:30.551223 5317 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-31}] initiating a split of this range at key "key-30" [r541] I181203 12:45:30.557305 5211 storage/replica_command.go:349 [n2,s2,r502/2:key-{38-40}] initiating a split of this range at key "key-4" [r542] I181203 12:45:30.575617 5387 storage/replica_command.go:349 [n2,s2,r527/2:key-5{-4}] initiating a split of this range at key "key-50" [r543] I181203 12:45:30.580725 5117 storage/replica_command.go:349 [n2,s2,r527/2:key-5{-4}] initiating a split of this range at key "key-51" [r544] I181203 12:45:30.589639 5398 storage/replica_command.go:349 [n2,s2,r527/2:key-5{-4}] initiating a split of this range at key "key-52" [r545] I181203 12:45:30.598041 5289 storage/replica_command.go:349 [n3,s3,r487/3:key-6{-2}] initiating a split of this range at key "key-61" [r514] I181203 12:45:30.599365 5400 storage/replica_command.go:349 [n2,s2,r527/2:key-5{-4}] initiating a split of this range at key "key-53" [r546] I181203 12:45:30.611459 4698 storage/replica_command.go:349 [n3,s3,r414/3:key-{58-6}] initiating a split of this range at key "key-59" [r515] I181203 12:45:30.614961 5084 storage/replica_command.go:349 [n3,s3,r487/3:key-6{-2}] initiating a split of this range at key "key-60" [r516] I181203 12:45:30.615697 5429 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-25}] initiating a split of this range at key "key-2" [r547] I181203 12:45:30.618748 5640 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-31}] initiating a split of this range at key "key-3" [r548] I181203 12:45:30.621421 5652 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-31}] initiating a split of this range at key "key-30" [r549] I181203 12:45:30.629806 5278 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-31}] initiating a split of this range at key "key-26" [r550] I181203 12:45:30.669604 5643 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-31}] initiating a split of this range at key "key-29" [r551] I181203 12:45:30.732155 5513 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-25}] initiating a split of this range at key "key-24" [r552] I181203 12:45:30.732266 5512 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-31}] initiating a split of this range at key "key-27" [r553] I181203 12:45:30.737292 5723 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-25}] initiating a split of this range at key "key-20" [r554] I181203 12:45:30.750709 5520 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-25}] initiating a split of this range at key "key-21" [r555] I181203 12:45:30.758044 5765 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-25}] initiating a split of this range at key "key-23" [r556] I181203 12:45:30.796327 5593 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-3}] initiating a split of this range at key "key-28" [r557] I181203 12:45:30.796528 5727 storage/replica_command.go:349 [n2,s2,r548/2:key-3{-1}] initiating a split of this range at key "key-30" [r558] I181203 12:45:30.797288 5798 storage/replica_command.go:349 [n2,s2,r543/2:key-5{0-4}] initiating a split of this range at key "key-51" [r559] I181203 12:45:30.798128 5726 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-25}] initiating a split of this range at key "key-22" [r560] I181203 12:45:30.802139 5084 storage/replica_command.go:349 [n3,s3,r487/3:key-6{-1}] initiating a split of this range at key "key-60" [r517] I181203 12:45:30.803760 5278 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-3}] initiating a split of this range at key "key-26" [r571] I181203 12:45:30.805011 5802 storage/replica_command.go:349 [n2,s2,r543/2:key-5{0-4}] initiating a split of this range at key "key-53" [r573] I181203 12:45:30.805313 5643 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-3}] initiating a split of this range at key "key-29" [r574] I181203 12:45:30.818130 5512 storage/replica_command.go:349 [n2,s2,r538/2:key-{25-3}] initiating a split of this range at key "key-27" [r575] I181203 12:45:30.842690 5691 storage/replica_command.go:349 [n2,s2,r522/2:key-{18-2}] initiating a split of this range at key "key-19" [r576] I181203 12:45:30.844836 5827 storage/replica_command.go:349 [n2,s2,r540/2:key-{39-40}] initiating a split of this range at key "key-4" [r577] I181203 12:45:30.889807 6050 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-5}] initiating a split of this range at key "key-23" [r578] I181203 12:45:30.892634 5923 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-5}] initiating a split of this range at key "key-24" [r579] I181203 12:45:30.892950 5924 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-5}] initiating a split of this range at key "key-20" [r580] I181203 12:45:30.893158 5925 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-5}] initiating a split of this range at key "key-21" [r581] I181203 12:45:30.958361 5878 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-5}] initiating a split of this range at key "key-22" [r582] I181203 12:45:30.961135 5773 storage/replica_command.go:349 [n2,s2,r559/2:key-5{1-4}] initiating a split of this range at key "key-53" [r583] I181203 12:45:30.964771 5278 storage/replica_command.go:349 [n2,s2,r538/2:key-2{5-8}] initiating a split of this range at key "key-26" [r584] I181203 12:45:31.055798 5512 storage/replica_command.go:349 [n2,s2,r538/2:key-2{5-8}] initiating a split of this range at key "key-27" [r585] I181203 12:45:31.056790 5801 storage/replica_command.go:349 [n2,s2,r543/2:key-5{0-1}] initiating a split of this range at key "key-52" [r572]; delayed split for 0.3s to avoid Raft snapshot I181203 12:45:31.092158 5915 storage/replica_command.go:492 [n2,merge,s2,r421/2:key-{49-5}] initiating a merge of r527:key-5{-0} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=1] into this range I181203 12:45:31.109275 5750 storage/replica_command.go:349 [n2,s2,r559/2:key-5{1-3}] initiating a split of this range at key "key-52" [r587] I181203 12:45:31.116896 6195 storage/replica_command.go:492 [n3,merge,s3,r417/3:key-6{6-7}] initiating a merge of r465:key-6{7-8} [(n1,s1):1, (n2,s2):2, (n3,s3):3, next=4, gen=2] into this range I181203 12:45:31.130998 1013 storage/store.go:2563 [n2,s2,r421/2:key-{49-5}] removing replica r527/2 I181203 12:45:31.131144 363 storage/store.go:2563 [n1,s1,r421/1:key-{49-5}] removing replica r527/1 I181203 12:45:31.131161 1530 storage/store.go:2563 [n3,s3,r421/3:key-{49-5}] removing replica r527/3 I181203 12:45:31.143597 6168 storage/replica_command.go:349 [n2,s2,r578/2:key-2{3-5}] initiating a split of this range at key "key-24" [r588] I181203 12:45:31.145674 5924 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-3}] initiating a split of this range at key "key-20" [r589] I181203 12:45:31.152246 5878 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-3}] initiating a split of this range at key "key-22" [r590] I181203 12:45:31.155221 5925 storage/replica_command.go:349 [n2,s2,r547/2:key-2{-3}] initiating a split of this range at key "key-21" [r591] I181203 12:45:31.164564 1383 storage/store.go:2563 [n3,s3,r417/3:key-6{6-7}] removing replica r465/3 I181203 12:45:31.171483 420 storage/store.go:2563 [n1,s1,r417/1:key-6{6-7}] removing replica r465/1 I181203 12:45:31.189802 1109 storage/store.go:2563 [n2,s2,r417/2:key-6{6-7}] removing replica r465/2 E181203 12:45:31.253045 2176 storage/replica.go:6903 [n1,s1,r10/1:/Table/1{3-4}] stalling replica due to: range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}] W181203 12:45:31.253265 2176 internal/client/txn.go:532 [n1,s1,r192/1:key-{82-95}] failure aborting transaction: replica corruption (processed=true): range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}]; abort caused by: replica corruption (processed=true): range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}] W181203 12:45:31.264466 1596 storage/store.go:3652 [n2,s2,r10/2:/Table/1{3-4}] got error from r10, replica (n1,s1):1: replica corruption (processed=true): range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}] W181203 12:45:31.264500 1596 storage/store.go:3652 [n2,s2,r10/2:/Table/1{3-4}] got error from r10, replica (n1,s1):1: replica corruption (processed=true): range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}] I181203 12:45:31.278667 5838 storage/replica_command.go:349 [n2,s2,r557/2:key-{28-3}] initiating a split of this range at key "key-29" [r586]; delayed split for 0.2s to avoid Raft snapshot I181203 12:45:31.291418 2155 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-88" [r366] I181203 12:45:31.301367 2201 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-86" [r368] I181203 12:45:31.292470 6152 storage/replica_command.go:349 [n2,s2,r589/2:key-2{0-3}] initiating a split of this range at key "key-21" [r592] I181203 12:45:31.292840 6435 storage/replica_command.go:349 [n2,s2,r589/2:key-2{0-3}] initiating a split of this range at key "key-22" [r593] I181203 12:45:31.298055 6440 storage/replica_command.go:349 [n2,s2,r584/2:key-2{6-8}] initiating a split of this range at key "key-27" [r594] I181203 12:45:31.299747 2046 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-93" [r367] I181203 12:45:31.346479 2161 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-83" [r370] I181203 12:45:31.348561 2186 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-87" [r369] I181203 12:45:31.350063 2048 storage/replica_command.go:349 [n1,s1,r224/1:{key-95-/Table/Syste…}] initiating a split of this range at key "key-97" [r371] W181203 12:45:31.350368 6454 storage/intent_resolver.go:675 [n3,s3] failed to push during intent resolution: replica corruption (processed=true): range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}] W181203 12:45:31.462541 6331 storage/intent_resolver.go:745 [n2,s2] failed to cleanup transaction intents: failed to resolve intents: replica corruption (processed=true): range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}] I181203 12:45:31.499403 2157 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-89" [r372] I181203 12:45:31.512436 2141 storage/replica_command.go:349 [n1,s1,r224/1:{key-95-/Table/Syste…}] initiating a split of this range at key "key-98" [r373] I181203 12:45:31.512648 2212 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-84" [r374] I181203 12:45:31.517384 2167 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-90" [r375] I181203 12:45:31.520515 2170 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-85" [r376] I181203 12:45:31.521744 2208 storage/replica_command.go:349 [n1,s1,r224/1:{key-95-/Table/Syste…}] initiating a split of this range at key "key-99" [r377] I181203 12:45:31.545318 2180 storage/replica_command.go:349 [n1,s1,r224/1:{key-95-/Table/Syste…}] initiating a split of this range at key "key-96" [r379] I181203 12:45:31.548156 2168 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-9" [r380] I181203 12:45:31.561385 2177 storage/replica_command.go:349 [n1,s1,r192/1:key-{82-95}] initiating a split of this range at key "key-92" [r378] I181203 12:45:31.651442 5460 storage/replica_command.go:349 [n3,s3,r469/3:key-7{0-4}] initiating a split of this range at key "key-72" [r513]; delayed split for 1.0s to avoid Raft snapshot I181203 12:45:31.661285 6493 util/stop/stopper.go:537 quiescing; tasks left: 2 [async] closedts-subscription 1 storage.intentResolver: processing intents 1 [async] closedts-rangefeed-subscriber I181203 12:45:31.661465 6491 util/stop/stopper.go:537 quiescing; tasks left: 2 [async] closedts-subscription 1 [async] closedts-rangefeed-subscriber I181203 12:45:31.661585 6492 util/stop/stopper.go:537 quiescing; tasks left: 2 [async] closedts-subscription 1 [async] closedts-rangefeed-subscriber W181203 12:45:31.661732 947 gossip/gossip.go:1501 [n2] no incoming or outgoing connections W181203 12:45:31.663460 2499 storage/raft_transport.go:584 [n2] while processing outgoing Raft queue to node 3: rpc error: code = Unavailable desc = transport is closing: W181203 12:45:31.663572 1692 storage/raft_transport.go:584 [n1] while processing outgoing Raft queue to node 3: rpc error: code = Unavailable desc = transport is closing: I181203 12:45:31.663787 6493 util/stop/stopper.go:537 quiescing; tasks left: 2 [async] closedts-subscription 1 [async] closedts-rangefeed-subscriber W181203 12:45:31.663956 1308 storage/raft_transport.go:584 [n1] while processing outgoing Raft queue to node 2: rpc error: code = Unavailable desc = transport is closing: W181203 12:45:31.663958 2350 storage/raft_transport.go:584 [n3] while processing outgoing Raft queue to node 2: rpc error: code = Unavailable desc = transport is closing: W181203 12:45:31.664144 1699 storage/raft_transport.go:584 [n3] while processing outgoing Raft queue to node 1: rpc error: code = Canceled desc = context canceled: W181203 12:45:31.664197 1288 gossip/gossip.go:1501 [n3] no incoming or outgoing connections I181203 12:45:31.664814 6491 util/stop/stopper.go:537 quiescing; tasks left: 2 [async] closedts-subscription W181203 12:45:31.664870 1594 storage/raft_transport.go:584 [n2] while processing outgoing Raft queue to node 1: rpc error: code = Unavailable desc = transport is closing: I181203 12:45:31.664883 6493 util/stop/stopper.go:537 quiescing; tasks left: 1 [async] closedts-subscription 1 [async] closedts-rangefeed-subscriber I181203 12:45:31.664909 6493 util/stop/stopper.go:537 quiescing; tasks left: 1 [async] closedts-subscription I181203 12:45:31.665951 6491 util/stop/stopper.go:537 quiescing; tasks left: 1 [async] closedts-subscription I181203 12:45:31.666009 6492 util/stop/stopper.go:537 quiescing; tasks left: 1 [async] closedts-subscription 1 [async] closedts-rangefeed-subscriber I181203 12:45:31.666040 6492 util/stop/stopper.go:537 quiescing; tasks left: 1 [async] closedts-rangefeed-subscriber --- FAIL: TestSplitTriggerRaftSnapshotRace (7.98s) client_split_test.go:564: "key-94": split unexpected error: split at key "key-94" failed: replica corruption (processed=true): range does not match splits: ("key-82"-"key-94") + ("key-94"-/Table/SystemConfigSpan/Start) != [n1,s1,r10/1:/Table/1{3-4}] FAIL ERROR: exit status 1 11946 runs completed, 1 failures, over 57m7s context canceled FAIL FAIL github.com/cockroachdb/cockroach/pkg/storage 3426.925s Makefile:849: recipe for target 'stress' failed