[Decoder]: Using assembly version of decoder [Sentry] 2020/07/30 05:54:10 Integration installed: ContextifyFrames [Sentry] 2020/07/30 05:54:10 Integration installed: Environment [Sentry] 2020/07/30 05:54:10 Integration installed: Modules [Sentry] 2020/07/30 05:54:10 Integration installed: IgnoreErrors [Decoder]: Using assembly version of decoder [Sentry] 2020/07/30 05:54:10 Integration installed: ContextifyFrames [Sentry] 2020/07/30 05:54:10 Integration installed: Environment [Sentry] 2020/07/30 05:54:10 Integration installed: Modules [Sentry] 2020/07/30 05:54:10 Integration installed: IgnoreErrors I0730 05:54:10.528196 13 init.go:99] Dgraph version : v20.03.1 Dgraph SHA-256 : 6a40b1e084205ae9e29336780b3458a3869db45c0b96b916190881c16d705ba8 Commit SHA-1 : c201611d6 Commit timestamp : 2020-04-24 13:53:41 -0700 Branch : HEAD Go version : go1.14.1 For Dgraph official documentation, visit https://docs.dgraph.io. For discussions about Dgraph , visit https://discuss.dgraph.io. To say hi to the community , visit https://dgraph.slack.com. Licensed variously under the Apache Public License 2.0 and Dgraph Community License. Copyright 2015-2020 Dgraph Labs, Inc. I0730 05:54:10.529296 13 run.go:609] x.Config: {PortOffset:0 QueryEdgeLimit:1000 NormalizeNodeLimit:1000} I0730 05:54:10.529768 13 run.go:610] x.WorkerConfig: {ExportPath:export NumPendingProposals:256 Tracing:1 MyAddr:10.50.34.90:7080 ZeroAddr:[10.50.34.90:5080] RaftId:1 WhiteListedIPRanges:[{Lower:172.19.0.1 Upper:172.19.0.1}] MaxRetries:3 StrictMutations:false AclEnabled:false AbortOlderThan:5m0s SnapshotAfter:10000 ProposedGroupId:0 StartTime:2020-07-30 05:54:10.040604986 +0000 UTC m=+0.012842350 LudicrousMode:false BadgerKeyFile:} I0730 05:54:10.530763 13 run.go:611] worker.Config: {PostingDir:/home/ubuntu/server1/p BadgerTables:disk BadgerVlog:disk BadgerKeyFile: BadgerCompressionLevel:3 WALDir:/home/ubuntu/server1/w MutationsMode:0 AuthToken: AllottedMemory:4096 HmacSecret:[] AccessJwtTtl:0s RefreshJwtTtl:0s AclRefreshInterval:0s} I0730 05:54:10.531629 13 server_state.go:75] Setting Badger Compression Level: 3 I0730 05:54:10.531939 13 server_state.go:84] Setting Badger table load option: disk I0730 05:54:10.532189 13 server_state.go:96] Setting Badger value log load option: disk I0730 05:54:10.532396 13 server_state.go:141] Opening write-ahead log BadgerDB with options: {Dir:/home/ubuntu/server1/w ValueDir:/home/ubuntu/server1/w SyncWrites:false TableLoadingMode:1 ValueLogLoadingMode:0 NumVersionsToKeep:1 ReadOnly:false Truncate:true Logger:0x282e510 Compression:2 InMemory:false MaxTableSize:67108864 LevelSizeMultiplier:10 MaxLevels:7 ValueThreshold:1048576 NumMemtables:5 BlockSize:4096 BloomFalsePositive:0.01 KeepL0InMemory:true MaxCacheSize:10485760 MaxBfCacheSize:0 LoadBloomsOnOpen:false NumLevelZeroTables:5 NumLevelZeroTablesStall:10 LevelOneSize:268435456 ValueLogFileSize:1073741823 ValueLogMaxEntries:10000 NumCompactors:2 CompactL0OnClose:true LogRotatesToFlush:2 ZSTDCompressionLevel:3 VerifyValueChecksum:false EncryptionKey:[] EncryptionKeyRotationDuration:240h0m0s BypassLockGuard:false ChecksumVerificationMode:0 managedTxns:false maxBatchCount:0 maxBatchSize:0} I0730 05:54:10.551259 13 log.go:34] All 0 tables opened in 0s I0730 05:54:10.554013 13 server_state.go:75] Setting Badger Compression Level: 3 I0730 05:54:10.554033 13 server_state.go:84] Setting Badger table load option: disk I0730 05:54:10.554040 13 server_state.go:96] Setting Badger value log load option: disk I0730 05:54:10.554048 13 server_state.go:160] Opening postings BadgerDB with options: {Dir:/home/ubuntu/server1/p ValueDir:/home/ubuntu/server1/p SyncWrites:false TableLoadingMode:0 ValueLogLoadingMode:0 NumVersionsToKeep:2147483647 ReadOnly:false Truncate:true Logger:0x282e510 Compression:2 InMemory:false MaxTableSize:67108864 LevelSizeMultiplier:10 MaxLevels:7 ValueThreshold:1024 NumMemtables:5 BlockSize:4096 BloomFalsePositive:0.01 KeepL0InMemory:true MaxCacheSize:1073741824 MaxBfCacheSize:0 LoadBloomsOnOpen:false NumLevelZeroTables:5 NumLevelZeroTablesStall:10 LevelOneSize:268435456 ValueLogFileSize:1073741823 ValueLogMaxEntries:1000000 NumCompactors:2 CompactL0OnClose:true LogRotatesToFlush:2 ZSTDCompressionLevel:3 VerifyValueChecksum:false EncryptionKey:[] EncryptionKeyRotationDuration:240h0m0s BypassLockGuard:false ChecksumVerificationMode:0 managedTxns:false maxBatchCount:0 maxBatchSize:0} I0730 05:54:10.580164 13 log.go:34] All 0 tables opened in 0s I0730 05:54:10.586817 13 groups.go:107] Current Raft Id: 0x1 I0730 05:54:10.589433 13 worker.go:96] Worker listening at address: [::]:7080 I0730 05:54:10.590658 13 run.go:480] Bringing up GraphQL HTTP API at 0.0.0.0:8080/graphql I0730 05:54:10.593141 13 run.go:481] Bringing up GraphQL HTTP admin API at 0.0.0.0:8080/admin I0730 05:54:10.593252 13 run.go:512] gRPC server started. Listening on port 9080 I0730 05:54:10.593319 13 run.go:513] HTTP server started. Listening on port 8080 I0730 05:54:10.688181 13 pool.go:160] CONNECTING to 10.50.34.90:5080 I0730 05:54:14.704265 13 groups.go:135] Connected to group zero. Assigned group: 1 I0730 05:54:14.704300 13 groups.go:137] Raft Id after connection to Zero: 0x1 I0730 05:54:14.704604 13 pool.go:160] CONNECTING to 10.50.34.90:7080 I0730 05:54:14.704849 13 draft.go:200] Node ID: 0x1 with GroupID: 1 I0730 05:54:14.704893 13 node.go:148] Setting raft.Config to: &{ID:1 peers:[] learners:[] ElectionTick:20 HeartbeatTick:1 Storage:0xc000202800 Applied:0 MaxSizePerMsg:262144 MaxCommittedSizePerReady:67108864 MaxUncommittedEntriesSize:0 MaxInflightMsgs:256 CheckQuorum:false PreVote:true ReadOnlyOption:0 Logger:0x282e510 DisableProposalForwarding:false} E0730 05:54:14.704925 13 storage.go:97] deleteRange failed with error: requested index is unavailable due to compaction, from: 0, until: 0 I0730 05:54:14.705916 13 node.go:326] Group 1 found 0 entries I0730 05:54:14.705933 13 draft.go:1567] New Node for group: 1 I0730 05:54:14.705966 13 log.go:34] 1 became follower at term 0 I0730 05:54:14.705993 13 log.go:34] newRaft 1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0] I0730 05:54:14.706001 13 log.go:34] 1 became follower at term 1 I0730 05:54:14.706037 13 draft.go:147] Operation started with id: opRollup I0730 05:54:14.706053 13 groups.go:155] Server is ready I0730 05:54:14.706209 13 draft.go:962] Found Raft progress: 0 I0730 05:54:14.706282 13 groups.go:784] Got address of a Zero leader: 10.50.34.90:5080 I0730 05:54:14.706360 13 groups.go:797] Starting a new membership stream receive from 10.50.34.90:5080. I0730 05:54:14.709889 13 groups.go:814] Received first state update from Zero: counter:4 groups: > members: > > > zeros: > I0730 05:54:14.711000 13 pool.go:160] CONNECTING to 10.50.34.90:7081 I0730 05:54:14.713885 13 node.go:185] Setting conf state to nodes:1 I0730 05:54:14.714156 13 log.go:34] 1 is starting a new election at term 1 I0730 05:54:14.714168 13 log.go:34] 1 became pre-candidate at term 1 I0730 05:54:14.714173 13 log.go:34] 1 received MsgPreVoteResp from 1 at term 1 I0730 05:54:14.714207 13 log.go:34] 1 became candidate at term 2 I0730 05:54:14.714214 13 log.go:34] 1 received MsgVoteResp from 1 at term 2 I0730 05:54:14.714231 13 log.go:34] 1 became leader at term 2 I0730 05:54:14.714244 13 log.go:34] raft.node: 1 elected leader 1 at term 2 I0730 05:54:15.596311 13 admin.go:523] No GraphQL schema in Dgraph; serving empty GraphQL API I0730 05:54:15.706335 13 groups.go:865] Leader idx=0x1 of group=1 is connecting to Zero for txn updates I0730 05:54:15.706486 13 groups.go:874] Got Zero leader: 10.50.34.90:5080 I0730 05:54:15.710831 13 groups.go:495] Serving tablet for: dgraph.type I0730 05:54:15.711395 13 pool.go:160] CONNECTING to 10.50.34.90:7082 I0730 05:54:15.716726 13 draft.go:147] Operation started with id: opIndexing I0730 05:54:15.716843 13 draft.go:112] Operation completed with id: opRollup I0730 05:54:15.716845 13 index.go:857] Deleting index for attr dgraph.type and tokenizers [] I0730 05:54:15.716868 13 index.go:871] Deleting index for attr dgraph.type and tokenizers [exact] I0730 05:54:15.716892 13 log.go:34] Writes flushed. Stopping compactions now... I0730 05:54:15.718361 13 log.go:34] DropPrefix done I0730 05:54:15.718416 13 log.go:34] Resuming writes I0730 05:54:15.718448 13 log.go:34] Writes flushed. Stopping compactions now... I0730 05:54:15.719699 13 log.go:34] DropPrefix done I0730 05:54:15.719729 13 log.go:34] Resuming writes I0730 05:54:15.719777 13 log.go:34] Writes flushed. Stopping compactions now... I0730 05:54:15.721922 13 log.go:34] DropPrefix done I0730 05:54:15.721965 13 log.go:34] Resuming writes I0730 05:54:15.722026 13 log.go:34] Writes flushed. Stopping compactions now... I0730 05:54:15.722953 13 node.go:586] Trying to add 0x2 to cluster. Addr: 10.50.34.90:7081 I0730 05:54:15.723056 13 node.go:587] Current confstate at 0x1: nodes:1 I0730 05:54:15.724990 13 log.go:34] DropPrefix done I0730 05:54:15.725041 13 log.go:34] Resuming writes I0730 05:54:15.725530 13 node.go:185] Setting conf state to nodes:1 nodes:2 I0730 05:54:15.725623 13 node.go:749] [0x2] Done joining cluster with err: I0730 05:54:15.725839 13 node.go:586] Trying to add 0x3 to cluster. Addr: 10.50.34.90:7082 I0730 05:54:15.725854 13 node.go:587] Current confstate at 0x1: nodes:1 nodes:2 I0730 05:54:15.736196 13 log.go:34] All 0 tables opened in 0s I0730 05:54:15.739136 13 log.go:34] Rebuilding index for predicate dgraph.type (1/2): Sent 0 keys I0730 05:54:15.741343 13 log.go:34] Rebuilding index for predicate dgraph.type (2/2): Sent 0 keys I0730 05:54:15.741496 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 05:54:15.744566 13 index.go:902] Rebuilding index for attr dgraph.type and tokenizers [exact] I0730 05:54:15.748498 13 groups.go:495] Serving tablet for: dgraph.graphql.schema I0730 05:54:15.766650 13 log.go:34] All 0 tables opened in 0s I0730 05:54:15.769370 13 log.go:34] Rebuilding index for predicate dgraph.type (1/2): Sent 0 keys I0730 05:54:15.771254 13 log.go:34] Rebuilding index for predicate dgraph.type (2/2): Sent 0 keys I0730 05:54:15.771540 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 05:54:15.773240 13 mutation.go:193] Done schema update predicate:"dgraph.type" value_type:STRING directive:INDEX tokenizer:"exact" list:true I0730 05:54:15.773406 13 draft.go:112] Operation completed with id: opIndexing I0730 05:54:17.734104 13 node.go:185] Setting conf state to nodes:1 nodes:2 nodes:3 I0730 05:54:17.734222 13 node.go:749] [0x3] Done joining cluster with err: I0730 05:54:17.749069 13 draft.go:147] Operation started with id: opIndexing I0730 05:54:17.749699 13 draft.go:112] Operation completed with id: opIndexing I0730 05:54:17.824712 13 draft.go:147] Operation started with id: opIndexing I0730 05:54:17.825161 13 draft.go:112] Operation completed with id: opIndexing I0730 05:54:19.813807 13 draft.go:147] Operation started with id: opIndexing I0730 05:54:19.813940 13 draft.go:112] Operation completed with id: opIndexing I0730 05:54:25.773592 13 draft.go:147] Operation started with id: opRollup I0730 05:54:36.710054 13 pool.go:160] CONNECTING to 10.50.34.68:7082 I0730 05:54:36.710096 13 pool.go:160] CONNECTING to 10.50.34.68:7080 I0730 05:54:36.710122 13 pool.go:160] CONNECTING to 10.50.34.68:7081 I0730 05:54:40.779354 13 admin.go:343] Updating GraphQL schema from subscription. E0730 05:54:40.779374 13 admin.go:373] Error processing GraphQL schema: input: No schema specified. I0730 06:17:42.312634 13 http.go:560] Got alter request via HTTP from 10.50.1.57:46202 I0730 06:17:42.312806 13 server.go:135] Received ALTER op: schema:": string .\n: string @index(exact) @count .\n: [uid] @count @reverse .\n: [uid] @count @reverse ." I0730 06:17:42.313673 13 server.go:266] Got schema: &{Preds:[predicate:"entity_type" value_type:STRING predicate:"entity_value" value_type:STRING directive:INDEX tokenizer:"exact" count:true predicate:"phone" value_type:UID directive:REVERSE count:true list:true predicate:"device" value_type:UID directive:REVERSE count:true list:true ] Types:[]} I0730 06:17:42.318228 13 groups.go:495] Serving tablet for: entity_type I0730 06:17:42.321210 13 groups.go:495] Serving tablet for: entity_value I0730 06:17:42.323998 13 groups.go:495] Serving tablet for: phone I0730 06:17:42.326908 13 groups.go:495] Serving tablet for: device I0730 06:17:42.334850 13 draft.go:147] Operation started with id: opIndexing I0730 06:17:42.335061 13 index.go:857] Deleting index for attr entity_value and tokenizers [] I0730 06:17:42.335100 13 index.go:871] Deleting index for attr entity_value and tokenizers [exact] I0730 06:17:42.335121 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.336227 13 draft.go:112] Operation completed with id: opRollup I0730 06:17:42.383789 13 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 12 101 110 116 105 116 121 95 118 97 108 117 101 2 2]} I0730 06:17:42.384355 13 log.go:34] Running for level: 0 I0730 06:17:42.414989 13 log.go:34] LOG Compact 0->1, del 1 tables, add 1 tables, took 30.592568ms I0730 06:17:42.415040 13 log.go:34] Compaction for level: 0 DONE I0730 06:17:42.443523 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 28.375447ms I0730 06:17:42.443554 13 log.go:34] DropPrefix done I0730 06:17:42.443563 13 log.go:34] Resuming writes I0730 06:17:42.443588 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.450836 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 5.693981ms I0730 06:17:42.450908 13 log.go:34] DropPrefix done I0730 06:17:42.450954 13 log.go:34] Resuming writes I0730 06:17:42.451181 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.488051 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 19.976521ms I0730 06:17:42.488221 13 log.go:34] DropPrefix done I0730 06:17:42.488465 13 log.go:34] Resuming writes I0730 06:17:42.488585 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.525823 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 29.472318ms I0730 06:17:42.526117 13 log.go:34] DropPrefix done I0730 06:17:42.526210 13 log.go:34] Resuming writes I0730 06:17:42.526225 13 index.go:972] Deleting count index for entity_value I0730 06:17:42.526241 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.551002 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 18.020434ms I0730 06:17:42.551076 13 log.go:34] DropPrefix done I0730 06:17:42.551088 13 log.go:34] Resuming writes I0730 06:17:42.551124 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.590038 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 7.522368ms I0730 06:17:42.590167 13 log.go:34] DropPrefix done I0730 06:17:42.590258 13 log.go:34] Resuming writes I0730 06:17:42.590332 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.638331 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 46.155429ms I0730 06:17:42.638531 13 log.go:34] DropPrefix done I0730 06:17:42.638545 13 log.go:34] Resuming writes I0730 06:17:42.638707 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.666218 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 14.796124ms I0730 06:17:42.666544 13 log.go:34] DropPrefix done I0730 06:17:42.666590 13 log.go:34] Resuming writes I0730 06:17:42.666684 13 index.go:1060] Deleting reverse index for phone I0730 06:17:42.666748 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.677553 13 log.go:34] DropPrefix done I0730 06:17:42.677634 13 log.go:34] Resuming writes I0730 06:17:42.677670 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.699428 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 13.838756ms I0730 06:17:42.699716 13 log.go:34] DropPrefix done I0730 06:17:42.699811 13 log.go:34] Resuming writes I0730 06:17:42.700090 13 index.go:972] Deleting count index for phone I0730 06:17:42.700282 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.709492 13 log.go:34] DropPrefix done I0730 06:17:42.709535 13 log.go:34] Resuming writes I0730 06:17:42.713635 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.757802 13 log.go:34] DropPrefix done I0730 06:17:42.758479 13 log.go:34] Resuming writes I0730 06:17:42.759307 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.783356 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 10.384544ms I0730 06:17:42.783387 13 log.go:34] DropPrefix done I0730 06:17:42.783395 13 log.go:34] Resuming writes I0730 06:17:42.783414 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.801216 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 12.15936ms I0730 06:17:42.801316 13 log.go:34] DropPrefix done I0730 06:17:42.801328 13 log.go:34] Resuming writes I0730 06:17:42.819462 13 log.go:34] All 0 tables opened in 0s I0730 06:17:42.825884 13 log.go:34] Rebuilding index for predicate phone (1/2): Sent 0 keys I0730 06:17:42.828409 13 log.go:34] Rebuilding index for predicate phone (2/2): Sent 0 keys I0730 06:17:42.829376 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:42.834582 13 index.go:1060] Deleting reverse index for device I0730 06:17:42.834615 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.867279 13 log.go:34] DropPrefix done I0730 06:17:42.867853 13 log.go:34] Resuming writes I0730 06:17:42.868270 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.922430 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 36.191909ms I0730 06:17:42.922474 13 log.go:34] DropPrefix done I0730 06:17:42.922485 13 log.go:34] Resuming writes I0730 06:17:42.922498 13 index.go:972] Deleting count index for device I0730 06:17:42.922517 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.928880 13 log.go:34] DropPrefix done I0730 06:17:42.929355 13 log.go:34] Resuming writes I0730 06:17:42.929631 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.938504 13 log.go:34] DropPrefix done I0730 06:17:42.938854 13 log.go:34] Resuming writes I0730 06:17:42.939295 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.963182 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 14.635602ms I0730 06:17:42.963230 13 log.go:34] DropPrefix done I0730 06:17:42.963240 13 log.go:34] Resuming writes I0730 06:17:42.963262 13 log.go:34] Writes flushed. Stopping compactions now... I0730 06:17:42.991572 13 log.go:34] LOG Compact 1->1, del 1 tables, add 1 tables, took 15.499071ms I0730 06:17:42.991610 13 log.go:34] DropPrefix done I0730 06:17:42.991624 13 log.go:34] Resuming writes I0730 06:17:43.009903 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.015694 13 log.go:34] Rebuilding index for predicate device (1/2): Sent 0 keys I0730 06:17:43.019870 13 log.go:34] Rebuilding index for predicate device (2/2): Sent 0 keys I0730 06:17:43.020643 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.028071 13 index.go:1071] Rebuilding reverse index for device I0730 06:17:43.028605 13 index.go:902] Rebuilding index for attr entity_value and tokenizers [exact] I0730 06:17:43.029011 13 index.go:1071] Rebuilding reverse index for phone I0730 06:17:43.046987 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.061447 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.064716 13 log.go:34] Rebuilding index for predicate phone (1/2): Sent 0 keys I0730 06:17:43.066176 13 log.go:34] Rebuilding index for predicate entity_value (1/2): Sent 0 keys I0730 06:17:43.066627 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.067808 13 log.go:34] Rebuilding index for predicate phone (2/2): Sent 0 keys I0730 06:17:43.071713 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.073478 13 log.go:34] Rebuilding index for predicate entity_value (2/2): Sent 0 keys I0730 06:17:43.073957 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.075924 13 log.go:34] Rebuilding index for predicate device (1/2): Sent 0 keys I0730 06:17:43.078199 13 index.go:987] Rebuilding count index for phone I0730 06:17:43.079375 13 log.go:34] Rebuilding index for predicate device (2/2): Sent 0 keys I0730 06:17:43.080076 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.081916 13 index.go:987] Rebuilding count index for entity_value I0730 06:17:43.086298 13 index.go:987] Rebuilding count index for device I0730 06:17:43.097351 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.100648 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.105913 13 log.go:34] Rebuilding index for predicate phone (1/2): Sent 0 keys I0730 06:17:43.107979 13 log.go:34] Rebuilding index for predicate phone (2/2): Sent 0 keys I0730 06:17:43.108604 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.109733 13 log.go:34] Rebuilding index for predicate entity_value (1/2): Sent 0 keys I0730 06:17:43.112232 13 log.go:34] Rebuilding index for predicate entity_value (2/2): Sent 0 keys I0730 06:17:43.113388 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.118227 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.123670 13 log.go:34] Rebuilding index for predicate device (1/2): Sent 0 keys I0730 06:17:43.125588 13 log.go:34] Rebuilding index for predicate device (2/2): Sent 0 keys I0730 06:17:43.126483 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.132530 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.132755 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.137311 13 log.go:34] Rebuilding index for predicate entity_value (1/2): Sent 0 keys I0730 06:17:43.138341 13 log.go:34] Rebuilding index for predicate phone (1/2): Sent 0 keys I0730 06:17:43.139704 13 log.go:34] Rebuilding index for predicate entity_value (2/2): Sent 0 keys I0730 06:17:43.140336 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.140927 13 log.go:34] Rebuilding index for predicate phone (2/2): Sent 0 keys I0730 06:17:43.141580 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.143859 13 log.go:34] All 0 tables opened in 0s I0730 06:17:43.149211 13 mutation.go:193] Done schema update predicate:"phone" value_type:UID directive:REVERSE count:true list:true I0730 06:17:43.149237 13 mutation.go:193] Done schema update predicate:"entity_value" value_type:STRING directive:INDEX tokenizer:"exact" count:true I0730 06:17:43.150707 13 log.go:34] Rebuilding index for predicate device (1/2): Sent 0 keys I0730 06:17:43.152443 13 log.go:34] Rebuilding index for predicate device (2/2): Sent 0 keys I0730 06:17:43.153117 13 log.go:34] Got compaction priority: {level:0 score:1.73 dropPrefix:[]} I0730 06:17:43.154815 13 mutation.go:193] Done schema update predicate:"device" value_type:UID directive:REVERSE count:true list:true I0730 06:17:43.154888 13 draft.go:112] Operation completed with id: opIndexing I0730 06:17:45.029356 13 server.go:285] ALTER op: schema:": string .\n: string @index(exact) @count .\n: [uid] @count @reverse .\n: [uid] @count @reverse ." done I0730 06:17:53.155120 13 draft.go:147] Operation started with id: opRollup I0730 06:25:00.131749 13 draft.go:147] Operation started with id: opIndexing I0730 06:25:00.131853 13 draft.go:112] Operation completed with id: opRollup I0730 06:25:00.131994 13 draft.go:112] Operation completed with id: opIndexing I0730 06:25:10.132784 13 draft.go:147] Operation started with id: opRollup W0730 06:47:33.349833 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.432s. Breakdown: [{sync 1.432s} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:47:48.452180 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.666s. Breakdown: [{sync 1.666s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:48:20.218003 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.31s. Breakdown: [{sync 1.31s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:48:37.297372 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 860ms. Breakdown: [{sync 860ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 06:49:14.770326 13 draft.go:557] Creating snapshot at index: 11632. ReadTs: 11552. W0730 06:49:25.794321 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.468s. Breakdown: [{sync 1.468s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:49:36.311357 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.381s. Breakdown: [{sync 1.381s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:49:42.296556 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 956ms. Breakdown: [{sync 956ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:50:18.674060 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.296s. Breakdown: [{sync 1.296s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 06:51:14.760900 13 draft.go:557] Creating snapshot at index: 23791. ReadTs: 23650. I0730 06:51:15.081558 13 log.go:34] Got compaction priority: {level:0 score:1 dropPrefix:[]} I0730 06:51:15.081633 13 log.go:34] Running for level: 0 I0730 06:51:15.152255 13 log.go:34] LOG Compact 0->1, del 5 tables, add 1 tables, took 70.597748ms I0730 06:51:15.152319 13 log.go:34] Compaction for level: 0 DONE W0730 06:52:45.370504 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.626s. Breakdown: [{sync 1.625s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 06:53:14.776156 13 draft.go:557] Creating snapshot at index: 36177. ReadTs: 35999. W0730 06:54:29.251032 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.664s. Breakdown: [{sync 1.664s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 06:55:14.766572 13 draft.go:557] Creating snapshot at index: 48617. ReadTs: 48395. I0730 06:55:15.081477 13 log.go:34] Got compaction priority: {level:0 score:1 dropPrefix:[]} I0730 06:55:15.081539 13 log.go:34] Running for level: 0 I0730 06:55:15.174671 13 log.go:34] LOG Compact 0->1, del 6 tables, add 1 tables, took 93.108532ms I0730 06:55:15.175169 13 log.go:34] Compaction for level: 0 DONE W0730 06:56:38.296684 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 558ms. Breakdown: [{sync 558ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:57:01.766190 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.791s. Breakdown: [{sync 1.79s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:57:14.807791 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.511s. Breakdown: [{sync 1.51s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 06:57:14.811131 13 draft.go:557] Creating snapshot at index: 61040. ReadTs: 60774. W0730 06:58:46.295827 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 3.03s. Breakdown: [{sync 3.03s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:59:10.296052 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 537ms. Breakdown: [{sync 537ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 06:59:13.081409 13 log.go:34] Got compaction priority: {level:0 score:1 dropPrefix:[]} I0730 06:59:13.081469 13 log.go:34] Running for level: 0 I0730 06:59:13.265483 13 log.go:34] LOG Compact 0->1, del 6 tables, add 1 tables, took 183.990543ms I0730 06:59:13.265530 13 log.go:34] Compaction for level: 0 DONE I0730 06:59:14.775873 13 draft.go:557] Creating snapshot at index: 73737. ReadTs: 73418. W0730 06:59:18.296925 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 637ms. Breakdown: [{sync 637ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 06:59:24.676642 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.791s. Breakdown: [{sync 1.791s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:00:45.367520 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 5.507s. Breakdown: [{sync 5.507s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:00:49.465927 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 570ms. Breakdown: [{sync 569ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:01:14.758630 13 draft.go:557] Creating snapshot at index: 85341. ReadTs: 84976. W0730 07:02:08.690045 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.487s. Breakdown: [{sync 1.487s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:02:51.081418 13 log.go:34] Got compaction priority: {level:0 score:1 dropPrefix:[]} I0730 07:02:51.082121 13 log.go:34] Running for level: 0 I0730 07:02:51.239695 13 log.go:34] LOG Compact 0->1, del 6 tables, add 1 tables, took 157.09447ms I0730 07:02:51.239742 13 log.go:34] Compaction for level: 0 DONE I0730 07:03:14.768999 13 draft.go:557] Creating snapshot at index: 97921. ReadTs: 97506. W0730 07:05:00.117197 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.288s. Breakdown: [{sync 1.287s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:05:14.767566 13 draft.go:557] Creating snapshot at index: 110508. ReadTs: 110038. W0730 07:05:26.296298 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 614ms. Breakdown: [{sync 614ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:05:34.294492 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.718s. Breakdown: [{sync 1.717s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:06:02.625772 13 log.go:34] Got compaction priority: {level:0 score:1 dropPrefix:[]} I0730 07:06:02.625874 13 log.go:34] Running for level: 0 I0730 07:06:02.776935 13 log.go:34] LOG Compact 0->1, del 6 tables, add 1 tables, took 151.013963ms I0730 07:06:02.777020 13 log.go:34] Compaction for level: 0 DONE W0730 07:06:38.297395 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 7.855s. Breakdown: [{sync 7.855s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:07:02.295939 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 287ms. Breakdown: [{sync 287ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:07:14.770417 13 draft.go:557] Creating snapshot at index: 121294. ReadTs: 120785. W0730 07:08:20.236603 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 1.331s. Breakdown: [{sync 1.331s} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:08:48.546279 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 229ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:09:14.735912 13 draft.go:1466] Skipping snapshot at index: 121913. Insufficient discard entries: 1. MinPendingStartTs: 121404 W0730 07:09:45.236474 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 217ms. Breakdown: [{sync 217ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:10:05.895790 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:10:14.726863 13 draft.go:1466] Skipping snapshot at index: 121913. Insufficient discard entries: 1. MinPendingStartTs: 121404 W0730 07:10:16.306154 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 300ms. Breakdown: [{sync 300ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:10:47.966609 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 457ms. Breakdown: [{sync 456ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:10:57.101716 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 683ms. Breakdown: [{sync 683ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:10:57.846377 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 379ms. Breakdown: [{sync 379ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:10:59.586136 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 480ms. Breakdown: [{sync 480ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:10.226319 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 365ms. Breakdown: [{sync 364ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:14.696542 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 248ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:11:14.725342 13 draft.go:1466] Skipping snapshot at index: 121913. Insufficient discard entries: 1. MinPendingStartTs: 121404 W0730 07:11:15.187943 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 237ms. Breakdown: [{sync 237ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:15.766206 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 353ms. Breakdown: [{sync 353ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:16.017750 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 250ms. Breakdown: [{sync 249ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:16.659209 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 223ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:19.616546 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 396ms. Breakdown: [{sync 396ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:20.636643 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 365ms. Breakdown: [{sync 364ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:20.858168 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 206ms. Breakdown: [{sync 206ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:21.186720 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 327ms. Breakdown: [{sync 327ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:21.707279 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 286ms. Breakdown: [{sync 286ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:22.098934 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 218ms. Breakdown: [{sync 217ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:22.328344 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 228ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:22.808186 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 222ms. Breakdown: [{sync 222ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:23.518154 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:24.076550 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 286ms. Breakdown: [{sync 285ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:24.588802 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 255ms. Breakdown: [{sync 255ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:24.936522 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 338ms. Breakdown: [{sync 337ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:25.178195 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 240ms. Breakdown: [{sync 240ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:26.096723 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 286ms. Breakdown: [{sync 285ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:26.426664 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 328ms. Breakdown: [{sync 328ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:27.057414 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 235ms. Breakdown: [{sync 235ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:27.488005 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 242ms. Breakdown: [{sync 242ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:28.129339 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 219ms. Breakdown: [{sync 219ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:28.797967 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 251ms. Breakdown: [{sync 251ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:29.758091 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 228ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:30.007366 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 248ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:30.546602 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 284ms. Breakdown: [{sync 284ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:31.166322 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 291ms. Breakdown: [{sync 291ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:31.566427 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 385ms. Breakdown: [{sync 385ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:32.107620 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 247ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:32.577574 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 236ms. Breakdown: [{sync 236ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:32.837840 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:33.318731 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 225ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:33.838342 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 244ms. Breakdown: [{sync 244ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:34.086370 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 246ms. Breakdown: [{sync 246ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:34.836363 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 387ms. Breakdown: [{sync 386ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:35.357235 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 222ms. Breakdown: [{sync 222ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:41.106375 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 481ms. Breakdown: [{sync 481ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:41.506342 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 399ms. Breakdown: [{sync 399ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:50.050097 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 232ms. Breakdown: [{sync 230ms} {advance 2ms} {disk 0s} {proposals 0s}] Num entries: 1. MustSync: true W0730 07:11:51.746754 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 430ms. Breakdown: [{sync 429ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:55.737013 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 485ms. Breakdown: [{sync 484ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:56.116882 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 379ms. Breakdown: [{sync 379ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:58.507744 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 226ms. Breakdown: [{sync 226ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:59.298017 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 250ms. Breakdown: [{sync 250ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:11:59.626532 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 237ms. Breakdown: [{sync 237ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:00.098757 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 221ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:00.777981 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 254ms. Breakdown: [{sync 254ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:01.008253 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 228ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:01.696870 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 362ms. Breakdown: [{sync 362ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:02.297142 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 344ms. Breakdown: [{sync 344ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:02.528457 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 230ms. Breakdown: [{sync 230ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:02.926813 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 246ms. Breakdown: [{sync 245ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:04.906598 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 208ms. Breakdown: [{sync 208ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:05.496500 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 402ms. Breakdown: [{sync 402ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:08.676675 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 499ms. Breakdown: [{sync 499ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:11.596621 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 332ms. Breakdown: [{sync 332ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:11.956552 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 359ms. Breakdown: [{sync 359ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:13.478702 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 229ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:13.958837 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:14.497319 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 273ms. Breakdown: [{sync 273ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:12:14.726330 13 draft.go:1466] Skipping snapshot at index: 121913. Insufficient discard entries: 1. MinPendingStartTs: 121404 W0730 07:12:14.846598 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 349ms. Breakdown: [{sync 348ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:15.308879 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 222ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:17.276996 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 233ms. Breakdown: [{sync 233ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:18.368084 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 238ms. Breakdown: [{sync 238ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:18.628728 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:19.098241 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 214ms. Breakdown: [{sync 214ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:19.588311 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 223ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:19.848026 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:20.358859 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 256ms. Breakdown: [{sync 256ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:20.818759 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 210ms. Breakdown: [{sync 210ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:21.068646 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 234ms. Breakdown: [{sync 234ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:21.289224 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 218ms. Breakdown: [{sync 218ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:21.798656 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 248ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:22.406708 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 374ms. Breakdown: [{sync 373ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:22.736968 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 329ms. Breakdown: [{sync 328ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:24.386810 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 526ms. Breakdown: [{sync 526ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:24.847135 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 458ms. Breakdown: [{sync 458ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:25.448110 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 327ms. Breakdown: [{sync 327ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:26.768416 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 205ms. Breakdown: [{sync 205ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:27.018157 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 247ms. Breakdown: [{sync 247ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:27.436671 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 311ms. Breakdown: [{sync 311ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:27.927083 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 260ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:28.187066 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:28.607480 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:29.649107 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 228ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:30.128935 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 209ms. Breakdown: [{sync 209ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:30.589489 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 248ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:30.819538 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 229ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:31.330697 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 224ms. Breakdown: [{sync 224ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:31.878305 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 224ms. Breakdown: [{sync 224ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:32.097409 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 216ms. Breakdown: [{sync 216ms} {proposals 1ms} {disk 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:32.588759 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:33.217817 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 230ms. Breakdown: [{sync 230ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:33.839140 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 239ms. Breakdown: [{sync 238ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:34.978703 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 243ms. Breakdown: [{sync 243ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:35.556756 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 371ms. Breakdown: [{sync 370ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:35.790856 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 232ms. Breakdown: [{sync 232ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:36.518436 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 231ms. Breakdown: [{sync 231ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:36.728282 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 208ms. Breakdown: [{sync 208ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:37.668996 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 260ms. Breakdown: [{sync 260ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:38.286794 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 360ms. Breakdown: [{sync 359ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:38.733012 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 224ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:39.266754 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 225ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:39.978092 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 225ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:40.206960 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 226ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:41.856860 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 387ms. Breakdown: [{sync 387ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:42.338776 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 243ms. Breakdown: [{sync 242ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:42.697941 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 202ms. Breakdown: [{sync 202ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:43.668559 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 223ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:44.857095 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 317ms. Breakdown: [{sync 317ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:45.098085 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 239ms. Breakdown: [{sync 239ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:45.729021 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 247ms. Breakdown: [{sync 247ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:46.209086 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 243ms. Breakdown: [{sync 243ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:46.778137 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 226ms. Breakdown: [{sync 226ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:47.386904 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 380ms. Breakdown: [{sync 380ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:47.637356 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 241ms. Breakdown: [{sync 241ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:47.926991 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 289ms. Breakdown: [{sync 289ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:48.418887 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 256ms. Breakdown: [{sync 256ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:48.758755 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:48.989098 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 229ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:49.597340 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 354ms. Breakdown: [{sync 354ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:50.078620 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 237ms. Breakdown: [{sync 237ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:50.338233 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:50.997601 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 297ms. Breakdown: [{sync 297ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:51.596841 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 248ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:52.158229 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 208ms. Breakdown: [{sync 208ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:52.617686 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 226ms. Breakdown: [{sync 226ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:52.848064 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 228ms. Breakdown: [{sync 228ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:53.358686 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 255ms. Breakdown: [{sync 255ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:53.878880 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 244ms. Breakdown: [{sync 244ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:54.138994 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 260ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:54.618305 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:55.098271 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 239ms. Breakdown: [{sync 239ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:55.358606 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 245ms. Breakdown: [{sync 245ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:55.727086 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 367ms. Breakdown: [{sync 367ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:56.426858 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 448ms. Breakdown: [{sync 448ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:57.068149 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 253ms. Breakdown: [{sync 253ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:57.298218 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:57.778291 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 213ms. Breakdown: [{sync 213ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:58.228232 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 245ms. Breakdown: [{sync 245ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:58.887829 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 279ms. Breakdown: [{sync 279ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:12:59.638944 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 223ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:00.017063 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 363ms. Breakdown: [{sync 362ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:00.238347 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:01.448347 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 222ms. Breakdown: [{sync 222ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:02.117321 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 346ms. Breakdown: [{sync 346ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:02.486919 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 368ms. Breakdown: [{sync 368ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:03.048462 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 237ms. Breakdown: [{sync 237ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:03.458167 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 250ms. Breakdown: [{sync 250ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:03.688119 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:03.948457 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:04.428726 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:04.817209 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 266ms. Breakdown: [{sync 266ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:05.038612 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 219ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:05.637325 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 384ms. Breakdown: [{sync 383ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:06.139237 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 253ms. Breakdown: [{sync 253ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:06.379430 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 239ms. Breakdown: [{sync 239ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:07.097617 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 327ms. Breakdown: [{sync 327ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:07.579667 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 252ms. Breakdown: [{sync 252ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:07.838594 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 250ms. Breakdown: [{sync 249ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:08.099326 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:08.597714 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 245ms. Breakdown: [{sync 245ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:09.167456 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 355ms. Breakdown: [{sync 355ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:09.388711 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:09.848165 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 224ms. Breakdown: [{sync 224ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:10.297006 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 210ms. Breakdown: [{sync 210ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:11.037214 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 279ms. Breakdown: [{sync 279ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:12.288214 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 218ms. Breakdown: [{sync 218ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:12.788654 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 247ms. Breakdown: [{sync 247ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:13.148688 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 216ms. Breakdown: [{sync 216ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:13.378240 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 228ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:14.249297 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 225ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:14.509118 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:13:14.726005 13 draft.go:1466] Skipping snapshot at index: 121913. Insufficient discard entries: 1. MinPendingStartTs: 121404 I0730 07:13:14.726424 13 draft.go:1307] Found 1 old transactions. Acting to abort them. I0730 07:13:14.971075 13 draft.go:1268] TryAbort 1 txns with start ts. Error: I0730 07:13:14.971093 13 draft.go:1291] TryAbort selectively proposing only aborted txns: txns: W0730 07:13:15.408868 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 249ms. Breakdown: [{sync 249ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:13:15.409139 13 draft.go:1310] Done abortOldTransactions for 1 txns. Error: W0730 07:13:15.828878 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 221ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:16.098529 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:16.579084 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 256ms. Breakdown: [{sync 256ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:17.248333 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:17.846959 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 341ms. Breakdown: [{sync 341ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:18.368777 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 214ms. Breakdown: [{sync 213ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:18.628408 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:19.417350 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 377ms. Breakdown: [{sync 377ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:19.998363 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 205ms. Breakdown: [{sync 205ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:20.471681 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 221ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:21.107016 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 385ms. Breakdown: [{sync 385ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:21.609325 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:21.857118 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 247ms. Breakdown: [{sync 247ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:22.349174 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 246ms. Breakdown: [{sync 246ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:22.949357 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 235ms. Breakdown: [{sync 235ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:23.188942 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 238ms. Breakdown: [{sync 238ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:23.699181 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 256ms. Breakdown: [{sync 256ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:24.178556 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 250ms. Breakdown: [{sync 250ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:24.438958 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 250ms. Breakdown: [{sync 250ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:24.699022 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:25.347882 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 377ms. Breakdown: [{sync 377ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:25.867838 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 284ms. Breakdown: [{sync 284ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:26.098904 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 230ms. Breakdown: [{sync 230ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:26.729715 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 225ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:27.367100 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 400ms. Breakdown: [{sync 400ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:27.727038 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 359ms. Breakdown: [{sync 359ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:28.507044 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 266ms. Breakdown: [{sync 266ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:29.018332 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 212ms. Breakdown: [{sync 212ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:29.307136 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 273ms. Breakdown: [{sync 273ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:29.538871 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 230ms. Breakdown: [{sync 229ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:30.038734 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 226ms. Breakdown: [{sync 226ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:30.518995 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:30.749678 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 229ms. Breakdown: [{sync 229ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:31.357058 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 353ms. Breakdown: [{sync 353ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:31.868902 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 246ms. Breakdown: [{sync 246ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:32.108909 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 238ms. Breakdown: [{sync 238ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:32.828961 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 205ms. Breakdown: [{sync 204ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:33.088587 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 250ms. Breakdown: [{sync 250ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:33.348787 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 258ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:33.887347 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 265ms. Breakdown: [{sync 265ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:34.517085 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 374ms. Breakdown: [{sync 373ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:34.758336 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 240ms. Breakdown: [{sync 239ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:35.238594 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 222ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:35.669242 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 242ms. Breakdown: [{sync 242ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:36.057126 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 386ms. Breakdown: [{sync 386ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:37.868494 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 248ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:38.407599 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 240ms. Breakdown: [{sync 240ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:40.207682 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 234ms. Breakdown: [{sync 234ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:41.249281 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 213ms. Breakdown: [{sync 213ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:43.129055 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 208ms. Breakdown: [{sync 208ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:44.317504 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 256ms. Breakdown: [{sync 255ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:44.817721 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 214ms. Breakdown: [{sync 214ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:45.917616 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 508ms. Breakdown: [{sync 508ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:46.518002 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 222ms. Breakdown: [{sync 221ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:46.779548 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 260ms. Breakdown: [{sync 259ms} {advance 1ms} {disk 0s} {proposals 0s}] Num entries: 1. MustSync: true W0730 07:13:47.867285 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 290ms. Breakdown: [{disk 290ms} {proposals 0s} {advance 0s}] Num entries: 0. MustSync: false W0730 07:13:48.427867 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 286ms. Breakdown: [{sync 285ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:48.858713 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:49.837246 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 239ms. Breakdown: [{sync 239ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:54.548944 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 223ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:54.819473 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:55.328563 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 252ms. Breakdown: [{sync 252ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:55.848452 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 251ms. Breakdown: [{sync 251ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:56.089373 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 240ms. Breakdown: [{sync 240ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:56.599805 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:57.347175 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 354ms. Breakdown: [{sync 354ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:57.567332 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 211ms. Breakdown: [{sync 211ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:58.028935 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:58.539409 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:59.237308 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 433ms. Breakdown: [{sync 433ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:13:59.747575 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 509ms. Breakdown: [{sync 508ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:00.567506 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 382ms. Breakdown: [{sync 382ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:01.347357 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 507ms. Breakdown: [{sync 506ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:01.617748 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:02.109476 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:02.599526 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 247ms. Breakdown: [{sync 246ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:02.858643 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 243ms. Breakdown: [{sync 243ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:03.091109 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 231ms. Breakdown: [{sync 231ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:03.598948 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 253ms. Breakdown: [{sync 253ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:04.268348 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 405ms. Breakdown: [{sync 405ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:04.637274 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 367ms. Breakdown: [{sync 367ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:06.347259 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 279ms. Breakdown: [{sync 279ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:14:14.867367 13 draft.go:557] Creating snapshot at index: 132611. ReadTs: 132042. W0730 07:14:31.977913 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 232ms. Breakdown: [{sync 232ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:33.117878 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 570ms. Breakdown: [{sync 570ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:34.757659 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 380ms. Breakdown: [{sync 380ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:14:49.277725 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 379ms. Breakdown: [{sync 379ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:05.147677 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 633ms. Breakdown: [{sync 633ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:05.757743 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 600ms. Breakdown: [{sync 599ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:06.077703 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 318ms. Breakdown: [{sync 318ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:08.151353 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 278ms. Breakdown: [{sync 277ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:11.557926 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 371ms. Breakdown: [{sync 370ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:11.920132 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 361ms. Breakdown: [{sync 361ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:12.737816 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 346ms. Breakdown: [{sync 346ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:15:38.798245 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 270ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:16:10.298966 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 290ms. Breakdown: [{sync 289ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:16:46.758927 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:17:12.578703 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 317ms. Breakdown: [{disk 227ms} {sync 90ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:17:18.228998 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 225ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:17:44.039143 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 330ms. Breakdown: [{sync 330ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:18:04.879043 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:18:15.249168 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 279ms. Breakdown: [{sync 279ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:18:46.149746 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:19:17.379665 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 226ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:20:19.890148 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:20:32.081552 13 log.go:34] Got compaction priority: {level:0 score:1 dropPrefix:[]} I0730 07:20:32.081641 13 log.go:34] Running for level: 0 I0730 07:20:32.421925 13 log.go:34] LOG Compact 0->1, del 6 tables, add 1 tables, took 340.256378ms I0730 07:20:32.421988 13 log.go:34] Compaction for level: 0 DONE W0730 07:21:27.490420 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:22:24.320681 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 251ms. Breakdown: [{sync 130ms} {disk 121ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:22:55.080886 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 294ms. Breakdown: [{sync 294ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:23:26.031000 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 300ms. Breakdown: [{sync 299ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:24:02.712936 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 201ms. Breakdown: [{sync 201ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:24:28.511654 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 309ms. Breakdown: [{sync 309ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:24:49.341496 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 217ms. Breakdown: [{sync 217ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:25:30.712105 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 395ms. Breakdown: [{sync 395ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:26:01.702653 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 248ms. Breakdown: [{sync 247ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:26:32.412961 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 271ms. Breakdown: [{sync 270ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:26:48.122774 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 242ms. Breakdown: [{sync 242ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:27:03.602552 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I0730 07:27:14.872981 13 draft.go:557] Creating snapshot at index: 142937. ReadTs: 142264. W0730 07:27:29.463078 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 249ms. Breakdown: [{sync 249ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:27:34.833022 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 259ms. Breakdown: [{sync 259ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:28:06.133136 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 439ms. Breakdown: [{sync 439ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:28:36.993190 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 204ms. Breakdown: [{sync 204ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:28:47.393012 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 221ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:29:07.993778 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 232ms. Breakdown: [{sync 231ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:29:39.263541 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 254ms. Breakdown: [{sync 254ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:30:37.386707 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 877ms. Breakdown: [{sync 877ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:30:41.234373 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:30:46.424313 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 217ms. Breakdown: [{sync 217ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:31:12.144272 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 224ms. Breakdown: [{sync 223ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:31:16.104341 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 267ms. Breakdown: [{sync 267ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:31:43.264485 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 335ms. Breakdown: [{sync 335ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:32:03.884635 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 236ms. Breakdown: [{sync 236ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:32:40.495313 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 361ms. Breakdown: [{sync 361ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:32:45.604489 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 236ms. Breakdown: [{sync 236ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:33:47.304911 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 249ms. Breakdown: [{sync 249ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:34:18.545206 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 247ms. Breakdown: [{sync 247ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:34:49.545207 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 270ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:35:12.183214 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 802ms. Breakdown: [{sync 802ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W0730 07:35:20.745359 13 draft.go:1183] Raft.Ready took too long to process: Timer Total: 269ms. Breakdown: [{sync 269ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true