Skip to content

Instantly share code, notes, and snippets.

@d4l3k

d4l3k/block_writer

Last active June 20, 2016 22:32
Show Gist options
  • Save d4l3k/bcc16b7e29b6d2fc94ec6a20fb3e5f2a to your computer and use it in GitHub Desktop.
Save d4l3k/bcc16b7e29b6d2fc94ec6a20fb3e5f2a to your computer and use it in GitHub Desktop.
Download ZIP
d13
Raw
block_writer
✘  ~/go/src/github.com/cockroachdb/cockroach   figuringoutshit ●  block_writer
2016/06/20 18:21:11 1s: 0.0/sec
2016/06/20 18:21:12 2s: 649.4/sec
2016/06/20 18:21:13 3s: 566.4/sec
2016/06/20 18:21:14 4s: 535.0/sec
2016/06/20 18:21:15 5s: 588.5/sec
2016/06/20 18:21:16 6s: 569.8/sec
2016/06/20 18:21:17 7s: 597.8/sec
2016/06/20 18:21:18 8s: 564.8/sec
2016/06/20 18:21:19 9s: 595.6/sec
2016/06/20 18:21:20 10s: 571.6/sec
2016/06/20 18:21:21 11s: 538.7/sec
2016/06/20 18:21:22 12s: 598.9/sec
2016/06/20 18:21:23 13s: 608.5/sec
2016/06/20 18:21:24 14s: 602.9/sec
2016/06/20 18:21:25 15s: 571.7/sec
2016/06/20 18:21:26 16s: 565.0/sec
2016/06/20 18:21:27 17s: 581.6/sec
2016/06/20 18:21:28 18s: 632.9/sec
2016/06/20 18:21:29 19s: 579.8/sec
2016/06/20 18:21:30 20s: 582.2/sec
2016/06/20 18:21:31 21s: 580.7/sec
2016/06/20 18:21:32 22s: 553.0/sec
2016/06/20 18:21:33 23s: 659.3/sec
2016/06/20 18:21:34 24s: 651.7/sec
2016/06/20 18:21:35 25s: 609.5/sec
2016/06/20 18:21:36 26s: 571.2/sec
2016/06/20 18:21:37 27s: 600.1/sec
2016/06/20 18:21:38 28s: 610.4/sec
2016/06/20 18:21:39 29s: 609.7/sec
2016/06/20 18:21:40 30s: 597.2/sec
2016/06/20 18:21:41 31s: 521.7/sec
2016/06/20 18:21:42 32s: 522.2/sec
2016/06/20 18:21:43 33s: 663.4/sec
2016/06/20 18:21:44 34s: 635.2/sec
2016/06/20 18:21:45 35s: 634.6/sec
2016/06/20 18:21:46 36s: 708.1/sec
2016/06/20 18:21:47 37s: 764.2/sec
2016/06/20 18:21:48 38s: 737.6/sec
2016/06/20 18:21:49 39s: 732.2/sec
2016/06/20 18:21:50 40s: 732.5/sec
2016/06/20 18:21:51 41s: 626.1/sec
2016/06/20 18:21:52 42s: 734.4/sec
2016/06/20 18:21:53 43s: 748.8/sec
2016/06/20 18:21:54 44s: 722.3/sec
2016/06/20 18:21:55 45s: 717.4/sec
2016/06/20 18:21:56 46s: 758.8/sec
2016/06/20 18:21:57 47s: 729.3/sec
2016/06/20 18:21:58 48s: 735.2/sec
2016/06/20 18:21:59 49s: 598.5/sec
2016/06/20 18:22:00 50s: 709.9/sec
2016/06/20 18:22:01 51s: 576.2/sec
2016/06/20 18:22:02 52s: 0.0/sec
2016/06/20 18:22:03 53s: 0.0/sec
2016/06/20 18:22:04 54s: 224.4/sec
2016/06/20 18:22:05 55s: 837.8/sec
2016/06/20 18:22:06 56s: 779.1/sec
2016/06/20 18:22:07 57s: 791.3/sec
2016/06/20 18:22:08 58s: 753.8/sec
2016/06/20 18:22:09 59s: 747.6/sec
2016/06/20 18:22:10 1m0s: 581.1/sec
2016/06/20 18:22:11 1m1s: 471.4/sec
2016/06/20 18:22:12 1m2s: 720.7/sec
Raw
drop
✘  ~/go/src/github.com/petermattis/roachdemo/d13   master ●  ag "18:22:.*(\[split\]|splitting|campaigning|raft/raft|AdminSplit|leader lease|initiating a split|LeaderLease|extending lease)"
cockroach-data/1/logs/cockroach.Tristans-MacBook-Pro.wiz.log.INFO.2016-06-20T18_21_58-04_00.4850
22907:W160620 18:22:00.607982 storage/replica.go:683 extending lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m1.75720807s at 1466461320.607952377,0
22908:W160620 18:22:00.607989 storage/replica.go:683 extending lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m1.75720807s at 1466461320.607972531,0
22917:I160620 18:22:00.608805 storage/replica.go:562 requestLeaderLease 6
23009:I160620 18:22:00.609097 storage/replica.go:600 proposing LeaderLeaseRequest 6
23015:I160620 18:22:00.609484 storage/replica.go:562 requestLeaderLease 6
23108:I160620 18:22:00.610594 storage/replica_command.go:169 executed LeaderLease command header:<key:"\272" > lease:<start:<wall_time:1466461320607952377 logical:0 > start_stasis:<wall_time:1466461325107952377 logical:0 > expiration:<wall_time:1466461325357952377 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
26978:I160620 18:22:00.880708 storage/split_queue.go:97 splitting range=6 [/Table/50-/Max) at keys [/Table/51/0]
26979:I160620 18:22:00.880841 kv/transport.go:164 sending request to Tristans-MacBook-Pro.local:26257: AdminSplit [/Table/51/0,/Min)
26980:I160620 18:22:00.880901 storage/replica.go:1195 [admin] leader lease: acquiring 6
26981:I160620 18:22:00.880908 storage/replica.go:1200 [admin] leader lease: acquired 6
26982:I160620 18:22:00.880917 storage/replica_command.go:2067 [split] split begins: 6
26983:I160620 18:22:00.880925 storage/replica_command.go:2109 [split] found split key: 6
26984:I160620 18:22:00.880951 storage/replica_command.go:2122 initiating a split of range=6 [/Table/50-/Max) at key /Table/51
26999:I160620 18:22:00.882266 storage/replica.go:562 requestLeaderLease 1
27117:I160620 18:22:00.882307 storage/replica.go:600 proposing LeaderLeaseRequest 1
27164:I160620 18:22:00.886982 storage/replica_command.go:169 executed LeaderLease command header:<key:"" > lease:<start:<wall_time:1466461320881368700 logical:0 > start_stasis:<wall_time:1466461325381368700 logical:0 > expiration:<wall_time:1466461325631368700 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
27235:I160620 18:22:00.890980 storage/replica_command.go:169 executed BeginTransaction command header:<key:"\001k\022\273\000\001rdsc" > : header:<txn:<meta:<id:<47217333-5414-4af9-975e-d5c6fce65cc4> isolation:SERIALIZABLE key:"\001k\022\273\000\001rdsc" epoch:0 timestamp:<wall_time:1466461320880998297 logical:0 > priority:272644 sequence:3 batch_index:0 > name:"storage/replica_command.go:2124 (*Replica).AdminSplit" status:PENDING orig_timestamp:<wall_time:1466461320880998297 logical:0 > max_timestamp:<wall_time:1466461320880998297 logical:0 > observed_timestamps:<key:1 value:<wall_time:1466461320880998297 logical:0 > > writing:true write_too_old:false retry_on_push:false > > , err=%!s(<nil>)
27316:I160620 18:22:00.895856 storage/replica.go:562 requestLeaderLease 4
27446:I160620 18:22:00.895911 storage/replica.go:600 proposing LeaderLeaseRequest 4
27491:I160620 18:22:00.898105 storage/replica_command.go:169 executed LeaderLease command header:<key:"\225" > lease:<start:<wall_time:1466461320894918251 logical:0 > start_stasis:<wall_time:1466461325394918251 logical:0 > expiration:<wall_time:1466461325644918251 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
27566:I160620 18:22:00.904350 storage/replica.go:562 requestLeaderLease 2
27697:I160620 18:22:00.904548 storage/replica.go:600 proposing LeaderLeaseRequest 2
27743:I160620 18:22:00.906719 storage/replica_command.go:169 executed LeaderLease command header:<key:"\223" > lease:<start:<wall_time:1466461320903322850 logical:0 > start_stasis:<wall_time:1466461325403322850 logical:0 > expiration:<wall_time:1466461325653322850 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
27757:I160620 18:22:00.908427 storage/replica.go:562 requestLeaderLease 5
27887:I160620 18:22:00.908479 storage/replica.go:600 proposing LeaderLeaseRequest 5
27923:I160620 18:22:00.910868 storage/replica_command.go:169 executed LeaderLease command header:<key:"\226" > lease:<start:<wall_time:1466461320907448006 logical:0 > start_stasis:<wall_time:1466461325407448006 logical:0 > expiration:<wall_time:1466461325657448006 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
28096:I160620 18:22:00.927594 storage/replica_command.go:169 executed EndTransaction command header:<key:"\001k\022\273\000\001rdsc" > commit:true internal_commit_trigger:<split_trigger:<updated_desc:<range_id:6 start_key:"\272" end_key:"\273" replicas:<node_id:1 store_id:1 replica_id:1 > replicas:<node_id:2 store_id:2 replica_id:2 > replicas:<node_id:3 store_id:3 replica_id:3 > next_replica_id:4 > new_desc:<range_id:7 start_key:"\273" end_key:"\377\377" replicas:<node_id:1 store_id:1 replica_id:1 > replicas:<node_id:2 store_id:2 replica_id:2 > replicas:<node_id:3 store_id:3 replica_id:3 > next_replica_id:4 > initial_leader_store_id:1 > > intent_spans:<key:"\001k\022\225\000\001rtn-" > intent_spans:<key:"\001k\022\226\000\001rtn-" > intent_spans:<key:"\001k\022\272\000\001rdsc" > intent_spans:<key:"\001k\022\272\000\001rtn-" > intent_spans:<key:"\001k\022\273\000\001rdsc" > intent_spans:<key:"\001k\022\273\000\001rtn-" > intent_spans:<key:"\003\273" > intent_spans:<key:"\003\377\377" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\210" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\212\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\213\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\214\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\215\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\216\211" > : header:<txn:<meta:<id:<47217333-5414-4af9-975e-d5c6fce65cc4> isolation:SERIALIZABLE key:"\001k\022\273\000\001rdsc" epoch:0 timestamp:<wall_time:1466461320880998297 logical:0 > priority:272644 sequence:3 batch_index:0 > name:"storage/replica_command.go:2124 (*Replica).AdminSplit" status:COMMITTED orig_timestamp:<wall_time:1466461320880998297 logical:0 > max_timestamp:<wall_time:1466461320880998297 logical:0 > observed_timestamps:<key:1 value:<wall_time:1466461320880998297 logical:0 > > writing:true write_too_old:false retry_on_push:false intents:<key:"\001k\022\225\000\001rtn-" > intents:<key:"\001k\022\226\000\001rtn-" > intents:<key:"\003\273" > intents:<key:"\003\377\377" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\210" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\212\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\213\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\214\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\215\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\216\211" > > > commit_wait:0 one_phase_commit:false , err=%!s(<nil>)
28113:I160620 18:22:00.928592 kv/dist_sender.go:981 RPC reply: *roachpb.AdminSplitResponse
28114:I160620 18:22:00.928630 storage/queue.go:436 [split] range=6 [/Table/50-/Table/51): done: 47.927232ms
28159:I160620 18:22:00.965376 raft/raft.go:469 [group 7] 1 became follower at term 5
28160:I160620 18:22:00.965439 raft/raft.go:238 [group 7] newRaft 1 [peers: [1,2,3], term: 5, commit: 10, applied: 10, lastindex: 10, lastterm: 5]
28176:I160620 18:22:01.929502 storage/replica_command.go:2334 range=6 campaigning after split
28177:I160620 18:22:01.929518 raft/raft.go:549 [group 7] 1 is starting a new election at term 5
28178:I160620 18:22:01.929531 raft/raft.go:482 [group 7] 1 became candidate at term 6
28179:I160620 18:22:01.929535 raft/raft.go:531 [group 7] 1 received vote from 1 at term 6
28180:I160620 18:22:01.929686 raft/raft.go:524 [group 7] 1 [logterm: 5, index: 10] sent vote request to 2 at term 6
28181:I160620 18:22:01.929750 raft/raft.go:524 [group 7] 1 [logterm: 5, index: 10] sent vote request to 3 at term 6
28187:I160620 18:22:01.966050 raft/raft.go:531 [group 7] 1 received vote from 2 at term 6
28188:I160620 18:22:01.966057 raft/raft.go:760 [group 7] 1 [quorum:2] has received 2 votes and 0 vote rejections
28189:I160620 18:22:01.966072 raft/raft.go:510 [group 7] 1 became leader at term 6
28242:I160620 18:22:03.937154 storage/replica.go:562 requestLeaderLease 7
28330:I160620 18:22:03.937811 storage/replica.go:600 proposing LeaderLeaseRequest 7
28332:I160620 18:22:03.938307 storage/replica.go:562 requestLeaderLease 7
28420:I160620 18:22:03.938453 storage/replica.go:562 requestLeaderLease 7
28508:I160620 18:22:03.938798 storage/replica_command.go:1570 range 7: new leader lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m8.681676226s following replica {0 0 0} 1970-01-01 00:00:00 +0000 UTC 0 [physicalTime=2016-06-20 22:22:03.938724335 +0000 UTC]
28509:I160620 18:22:03.938860 storage/replica_command.go:169 executed LeaderLease command header:<key:"\273" > lease:<start:<wall_time:1466461323931676226 logical:0 > start_stasis:<wall_time:1466461328431676226 logical:0 > expiration:<wall_time:1466461328681676226 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
cockroach-data/1/logs/cockroach.Tristans-MacBook-Pro.wiz.log.INFO.2016-06-20T18_22_06-04_00.4850
16071:W160620 18:22:07.531982 storage/replica.go:683 extending lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m8.681676226s at 1466461327.531957658,0
16077:W160620 18:22:07.532393 storage/replica.go:683 extending lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m8.681676226s at 1466461327.532369306,0
16081:I160620 18:22:07.532716 storage/replica.go:562 requestLeaderLease 7
16170:I160620 18:22:07.532970 storage/replica.go:562 requestLeaderLease 7
16262:I160620 18:22:07.533210 storage/replica.go:600 proposing LeaderLeaseRequest 7
16291:I160620 18:22:07.534800 storage/replica_command.go:169 executed LeaderLease command header:<key:"\273" > lease:<start:<wall_time:1466461327531957658 logical:0 > start_stasis:<wall_time:1466461332031957658 logical:0 > expiration:<wall_time:1466461332281957658 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
cockroach-data/1/logs/cockroach.Tristans-MacBook-Pro.wiz.log.INFO.2016-06-20T18_22_04-04_00.4850
25048:I160620 18:22:05.901528 storage/replica.go:562 requestLeaderLease 1
25111:I160620 18:22:05.902369 storage/replica.go:600 proposing LeaderLeaseRequest 1
25144:I160620 18:22:05.906751 storage/replica_command.go:169 executed LeaderLease command header:<key:"" > lease:<start:<wall_time:1466461325895449678 logical:0 > start_stasis:<wall_time:1466461330395449678 logical:0 > expiration:<wall_time:1466461330645449678 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
cockroach-data/1/logs/cockroach.Tristans-MacBook-Pro.wiz.log.INFO.2016-06-20T18_22_08-04_00.4850
24516:W160620 18:22:11.132003 storage/replica.go:683 extending lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m12.281957658s at 1466461331.131971027,0
24525:I160620 18:22:11.132845 storage/replica.go:562 requestLeaderLease 7
24618:I160620 18:22:11.133094 storage/replica.go:600 proposing LeaderLeaseRequest 7
24650:I160620 18:22:11.136715 storage/replica_command.go:169 executed LeaderLease command header:<key:"\273" > lease:<start:<wall_time:1466461331131971027 logical:0 > start_stasis:<wall_time:1466461335631971027 logical:0 > expiration:<wall_time:1466461335881971027 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
cockroach-data/1/logs/cockroach.Tristans-MacBook-Pro.wiz.log.WARNING.2016-06-20T18_20_35-04_00.4850
426:W160620 18:22:00.607982 storage/replica.go:683 extending lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m1.75720807s at 1466461320.607952377,0
427:W160620 18:22:00.607989 storage/replica.go:683 extending lease replica {1 1 1} 1970-01-01 00:00:00 +0000 UTC 407350h22m1.75720807s at 1466461320.607972531,0
cockroach-data/2/logs/cockroach.Tristans-MacBook-Pro.wiz.log.INFO.2016-06-20T18_21_57-04_00.4851
4774:I160620 18:22:00.610983 storage/replica_command.go:169 executed LeaderLease command header:<key:"\272" > lease:<start:<wall_time:1466461320607952377 logical:0 > start_stasis:<wall_time:1466461325107952377 logical:0 > expiration:<wall_time:1466461325357952377 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
5211:I160620 18:22:00.886840 storage/replica_command.go:169 executed LeaderLease command header:<key:"" > lease:<start:<wall_time:1466461320881368700 logical:0 > start_stasis:<wall_time:1466461325381368700 logical:0 > expiration:<wall_time:1466461325631368700 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
5220:I160620 18:22:00.891411 storage/replica_command.go:169 executed BeginTransaction command header:<key:"\001k\022\273\000\001rdsc" > : header:<txn:<meta:<id:<47217333-5414-4af9-975e-d5c6fce65cc4> isolation:SERIALIZABLE key:"\001k\022\273\000\001rdsc" epoch:0 timestamp:<wall_time:1466461320880998297 logical:0 > priority:272644 sequence:3 batch_index:0 > name:"storage/replica_command.go:2124 (*Replica).AdminSplit" status:PENDING orig_timestamp:<wall_time:1466461320880998297 logical:0 > max_timestamp:<wall_time:1466461320880998297 logical:0 > observed_timestamps:<key:1 value:<wall_time:1466461320880998297 logical:0 > > writing:true write_too_old:false retry_on_push:false > > , err=%!s(<nil>)
5235:I160620 18:22:00.898723 storage/replica_command.go:169 executed LeaderLease command header:<key:"\225" > lease:<start:<wall_time:1466461320894918251 logical:0 > start_stasis:<wall_time:1466461325394918251 logical:0 > expiration:<wall_time:1466461325644918251 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
5254:I160620 18:22:00.907908 storage/replica_command.go:169 executed LeaderLease command header:<key:"\223" > lease:<start:<wall_time:1466461320903322850 logical:0 > start_stasis:<wall_time:1466461325403322850 logical:0 > expiration:<wall_time:1466461325653322850 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
5259:I160620 18:22:00.911449 storage/replica_command.go:169 executed LeaderLease command header:<key:"\226" > lease:<start:<wall_time:1466461320907448006 logical:0 > start_stasis:<wall_time:1466461325407448006 logical:0 > expiration:<wall_time:1466461325657448006 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
5283:I160620 18:22:00.925193 storage/replica_command.go:169 executed EndTransaction command header:<key:"\001k\022\273\000\001rdsc" > commit:true internal_commit_trigger:<split_trigger:<updated_desc:<range_id:6 start_key:"\272" end_key:"\273" replicas:<node_id:1 store_id:1 replica_id:1 > replicas:<node_id:2 store_id:2 replica_id:2 > replicas:<node_id:3 store_id:3 replica_id:3 > next_replica_id:4 > new_desc:<range_id:7 start_key:"\273" end_key:"\377\377" replicas:<node_id:1 store_id:1 replica_id:1 > replicas:<node_id:2 store_id:2 replica_id:2 > replicas:<node_id:3 store_id:3 replica_id:3 > next_replica_id:4 > initial_leader_store_id:1 > > intent_spans:<key:"\001k\022\225\000\001rtn-" > intent_spans:<key:"\001k\022\226\000\001rtn-" > intent_spans:<key:"\001k\022\272\000\001rdsc" > intent_spans:<key:"\001k\022\272\000\001rtn-" > intent_spans:<key:"\001k\022\273\000\001rdsc" > intent_spans:<key:"\001k\022\273\000\001rtn-" > intent_spans:<key:"\003\273" > intent_spans:<key:"\003\377\377" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\210" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\212\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\213\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\214\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\215\211" > intent_spans:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\216\211" > : header:<txn:<meta:<id:<47217333-5414-4af9-975e-d5c6fce65cc4> isolation:SERIALIZABLE key:"\001k\022\273\000\001rdsc" epoch:0 timestamp:<wall_time:1466461320880998297 logical:0 > priority:272644 sequence:3 batch_index:0 > name:"storage/replica_command.go:2124 (*Replica).AdminSplit" status:COMMITTED orig_timestamp:<wall_time:1466461320880998297 logical:0 > max_timestamp:<wall_time:1466461320880998297 logical:0 > observed_timestamps:<key:1 value:<wall_time:1466461320880998297 logical:0 > > writing:true write_too_old:false retry_on_push:false intents:<key:"\001k\022\225\000\001rtn-" > intents:<key:"\001k\022\226\000\001rtn-" > intents:<key:"\003\273" > intents:<key:"\003\377\377" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\210" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\212\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\213\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\214\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\215\211" > intents:<key:"\225\211\024\371Whl\210\3714\202\366p\375\002\034\017\267\321&\000\000\216\211" > > > commit_wait:0 one_phase_commit:false , err=%!s(<nil>)
5299:I160620 18:22:00.962995 raft/raft.go:469 [group 7] 2 became follower at term 5
5300:I160620 18:22:00.963119 raft/raft.go:238 [group 7] newRaft 2 [peers: [1,2,3], term: 5, commit: 10, applied: 10, lastindex: 10, lastterm: 5]
5304:I160620 18:22:01.965738 raft/raft.go:571 [group 7] 2 [term: 5] received a MsgVote message with higher term from 1 [term: 6]
5305:I160620 18:22:01.965752 raft/raft.go:469 [group 7] 2 became follower at term 6
5306:I160620 18:22:01.965875 raft/raft.go:797 [group 7] 2 [logterm: 5, index: 10, vote: 0] voted for 1 [logterm: 5, index: 10] at term 6
5316:I160620 18:22:03.939173 storage/replica_command.go:169 executed LeaderLease command header:<key:"\273" > lease:<start:<wall_time:1466461323931676226 logical:0 > start_stasis:<wall_time:1466461328431676226 logical:0 > expiration:<wall_time:1466461328681676226 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
8589:I160620 18:22:05.906848 storage/replica_command.go:169 executed LeaderLease command header:<key:"" > lease:<start:<wall_time:1466461325895449678 logical:0 > start_stasis:<wall_time:1466461330395449678 logical:0 > expiration:<wall_time:1466461330645449678 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
cockroach-data/2/logs/cockroach.Tristans-MacBook-Pro.wiz.log.INFO.2016-06-20T18_22_05-04_00.4851
2808:I160620 18:22:07.535424 storage/replica_command.go:169 executed LeaderLease command header:<key:"\273" > lease:<start:<wall_time:1466461327531957658 logical:0 > start_stasis:<wall_time:1466461332031957658 logical:0 > expiration:<wall_time:1466461332281957658 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
7340:I160620 18:22:11.136545 storage/replica_command.go:169 executed LeaderLease command header:<key:"\273" > lease:<start:<wall_time:1466461331131971027 logical:0 > start_stasis:<wall_time:1466461335631971027 logical:0 > expiration:<wall_time:1466461335881971027 logical:0 > replica:<node_id:1 store_id:1 replica_id:1 > > : header:<> , err=%!s(<nil>)
Raw
requestLeaderLease stack
I160620 18:22:03.937154 storage/replica.go:562 requestLeaderLease 7
goroutine 473 [running]:
runtime/debug.Stack(0x0, 0x0, 0x0)
/usr/local/Cellar/go/1.6.2/libexec/src/runtime/debug/stack.go:24 +0x80
github.com/cockroachdb/cockroach/storage.(*Replica).requestLeaderLease(0xc821adad80, 0x1459eacdf1cbee42, 0xc800000000, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/storage/replica.go:562 +0x30
github.com/cockroachdb/cockroach/storage.(*Replica).redirectOnOrAcquireLeaderLease(0xc821adad80, 0x818e2e8, 0xc820fc66c0, 0xc820881e80)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/storage/replica.go:697 +0x591
github.com/cockroachdb/cockroach/storage.(*Replica).addWriteCmd(0xc821adad80, 0x818e2e8, 0xc820fc66c0, 0x1459eacd3e69d4ec, 0x0, 0x100000001, 0x1, 0x7, 0x0, 0xc820a69bc0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/storage/replica.go:1322 +0x1af
github.com/cockroachdb/cockroach/storage.(*Replica).Send(0xc821adad80, 0x818e2e8, 0xc820fc66c0, 0x1459eacd3e69d4ec, 0x0, 0x100000001, 0x1, 0x7, 0x0, 0xc820a69bc0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/storage/replica.go:938 +0x215
github.com/cockroachdb/cockroach/storage.(*Store).Send(0xc8202b1200, 0x818e2e8, 0xc820fc66c0, 0x1459eacd3e69d4ec, 0x0, 0x100000001, 0x1, 0x7, 0x0, 0xc820a69bc0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/storage/store.go:1846 +0xd10
github.com/cockroachdb/cockroach/storage.(*Stores).Send(0xc8201be6c0, 0x818e2e8, 0xc820fc6600, 0x0, 0x0, 0x100000001, 0x1, 0x7, 0x0, 0xc820a69bc0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/storage/stores.go:178 +0x4ff
github.com/cockroachdb/cockroach/server.(*Node).Batch.func3()
/Users/wiz/go/src/github.com/cockroachdb/cockroach/server/node.go:791 +0x559
github.com/cockroachdb/cockroach/util/stop.(*Stopper).RunTask(0xc8203532d0, 0xc821b4b4b8, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/util/stop/stopper.go:166 +0xd9
github.com/cockroachdb/cockroach/server.(*Node).Batch(0xc8201d0000, 0x8190178, 0xc820fc6540, 0xc820096b68, 0x0, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/server/node.go:803 +0x327
github.com/cockroachdb/cockroach/kv.(*grpcTransport).SendNext(0xc82198c230, 0xc820e44d80)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/transport.go:171 +0x408
github.com/cockroachdb/cockroach/kv.(*DistSender).sendToReplicas(0xc82034c900, 0x818e2e8, 0xc820fbe720, 0x2540be400, 0xb2d05e00, 0x0, 0x7, 0xc82198c190, 0x3, 0x3, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/dist_sender.go:957 +0x48d
github.com/cockroachdb/cockroach/kv.(*DistSender).sendRPC(0xc82034c900, 0x818e2e8, 0xc820fbe720, 0x7, 0xc82198c190, 0x3, 0x3, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/dist_sender.go:344 +0x1ee
github.com/cockroachdb/cockroach/kv.(*DistSender).sendSingleRange(0xc82034c900, 0x818e2e8, 0xc820fbe720, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc820833140, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/dist_sender.go:460 +0x1ea
github.com/cockroachdb/cockroach/kv.(*DistSender).sendChunk.func2(0xc821b4c448, 0xc821b4c130, 0xc821b4cbe0, 0xc82034c900, 0xc821b4cbd0, 0x60, 0xc8206ce3f0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/dist_sender.go:719 +0x443
github.com/cockroachdb/cockroach/kv.(*DistSender).sendChunk(0xc82034c900, 0x818e2e8, 0xc820fbe720, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc820833140, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/dist_sender.go:720 +0xa20
github.com/cockroachdb/cockroach/kv.(*DistSender).Send(0xc82034c900, 0x818e2e8, 0xc820fbe720, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc820833140, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/dist_sender.go:564 +0x6d4
github.com/cockroachdb/cockroach/kv.(*TxnCoordSender).Send(0xc8203b0c60, 0x818e2e8, 0xc820fbe720, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc820833080, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/kv/txn_coord_sender.go:374 +0xa5b
github.com/cockroachdb/cockroach/client.(*txnSender).Send(0xc8210b7340, 0x818d228, 0xc82005ce90, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc8210b7398, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/txn.go:53 +0xb6
github.com/cockroachdb/cockroach/client.(*DB).send(0xc8210b7340, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/db.go:471 +0x3ec
github.com/cockroachdb/cockroach/client.(*Txn).send(0xc8210b7340, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/txn.go:667 +0x961
github.com/cockroachdb/cockroach/client.(*Txn).(github.com/cockroachdb/cockroach/client.send)-fm(0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/txn.go:355 +0x3a
github.com/cockroachdb/cockroach/client.sendAndFill(0xc821b4e0c0, 0xc821839b00, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/db.go:400 +0x1ff
github.com/cockroachdb/cockroach/client.(*Txn).Run(0xc8210b7340, 0xc821839b00, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/txn.go:355 +0xbd
github.com/cockroachdb/cockroach/client.(*Txn).CommitInBatch(0xc8210b7340, 0xc821839b00, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/txn.go:396 +0x28d
github.com/cockroachdb/cockroach/sql.(*tableInserter).finalize(0xc8217a7680, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/tablewriter.go:110 +0x60
github.com/cockroachdb/cockroach/sql.(*insertNode).Next(0xc821ada300, 0x8762901, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/insert.go:256 +0xce
github.com/cockroachdb/cockroach/sql.countRowsAffected(0x8762918, 0xc821ada300, 0x6, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:560 +0x116
github.com/cockroachdb/cockroach/sql.(*Executor).execStmt(0xc8201cf860, 0x81c0330, 0xc821a194f0, 0xc821465090, 0xc821a19401, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:1004 +0x1dc
github.com/cockroachdb/cockroach/sql.(*Executor).execStmtInOpenTxn(0xc8201cf860, 0x81c0330, 0xc821a194f0, 0xc821465090, 0x101, 0xc821465028, 0x0, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:887 +0x42e
github.com/cockroachdb/cockroach/sql.(*Executor).execStmtsInCurrentTxn(0xc8201cf860, 0xc82137a560, 0x1, 0x1, 0xc821465090, 0xc821465028, 0x5670101, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:659 +0x5da
github.com/cockroachdb/cockroach/sql.runTxnAttempt(0xc8201cf860, 0xc821465090, 0x1, 0xc821465028, 0xc82081bf80, 0xc82137a560, 0x1, 0x1, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:587 +0x18d
github.com/cockroachdb/cockroach/sql.(*Executor).execRequest.func2(0xc8210b7340, 0xc82081bf80, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:480 +0x47b
github.com/cockroachdb/cockroach/client.(*Txn).Exec(0xc8210b7340, 0xc820dd0101, 0x1459eacd3e69d4ec, 0x0, 0xc820dde6c0, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/client/txn.go:538 +0x2eb
github.com/cockroachdb/cockroach/sql.(*Executor).execRequest(0xc8201cf860, 0x814e238, 0xc8213d9200, 0xc821465000, 0xc821825622, 0x56, 0x0, 0x0, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:485 +0xba7
github.com/cockroachdb/cockroach/sql.(*Executor).ExecuteStatements(0xc8201cf860, 0x814e238, 0xc8213d9200, 0xc821465000, 0xc821825622, 0x56, 0xc821b4f618, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/executor.go:345 +0xf6
github.com/cockroachdb/cockroach/sql/pgwire.(*v3Conn).executeStatements(0xc821750780, 0x814e238, 0xc8213d9200, 0xc821825622, 0x56, 0xc821b4f618, 0x60ead00, 0x0, 0x0, 0x0, ...)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/pgwire/v3.go:668 +0x98
github.com/cockroachdb/cockroach/sql/pgwire.(*v3Conn).handleExecute(0xc821750780, 0x814e238, 0xc8213d9200, 0xc8217507a8, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/pgwire/v3.go:656 +0x35a
github.com/cockroachdb/cockroach/sql/pgwire.(*v3Conn).serve(0xc821750780, 0x0, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/pgwire/v3.go:309 +0xf03
github.com/cockroachdb/cockroach/sql/pgwire.(*Server).ServeConn(0xc820351c80, 0x81530a0, 0xc8200d73f0, 0x0, 0x0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/sql/pgwire/server.go:226 +0x9d9
github.com/cockroachdb/cockroach/server.(*Server).Start.func8.1(0x81530a0, 0xc8200d73f0)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/server/server.go:370 +0x42
github.com/cockroachdb/cockroach/util.(*Server).ServeWith.func1(0xc8200661d0, 0x81530a0, 0xc8200d73f0, 0xc820238060)
/Users/wiz/go/src/github.com/cockroachdb/cockroach/util/net.go:131 +0x62
created by github.com/cockroachdb/cockroach/util.(*Server).ServeWith
/Users/wiz/go/src/github.com/cockroachdb/cockroach/util/net.go:133 +0x333
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment