++ hostname -f + dgraph alpha --my=dgraph-alpha-0.dgraph-alpha.default.svc.cluster.local:7080 --lru_mb 1340 --zero dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 [Decoder]: Using assembly version of decoder I1116 07:38:48.369593 1 init.go:98] Dgraph version : v1.1.0 Dgraph SHA-256 : 7d4294a80f74692695467e2cf17f74648c18087ed7057d798f40e1d3a31d2095 Commit SHA-1 : ef7cdb28 Commit timestamp : 2019-09-04 00:12:51 -0700 Branch : HEAD Go version : go1.12.7 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-2018 Dgraph Labs, Inc. I1116 07:38:48.370479 1 run.go:500] x.Config: {PortOffset:0 QueryEdgeLimit:1000000 NormalizeNodeLimit:10000} I1116 07:38:48.370665 1 run.go:501] x.WorkerConfig: {ExportPath:export NumPendingProposals:256 Tracing:1 MyAddr:dgraph-alpha-0.dgraph-alpha.default.svc.cluster.local:7080 ZeroAddr:dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 RaftId:0 WhiteListedIPRanges:[] MaxRetries:-1 StrictMutations:false AclEnabled:false AbortOlderThan:5m0s SnapshotAfter:10000} I1116 07:38:48.370836 1 run.go:502] edgraph.Config: {PostingDir:p BadgerTables:mmap BadgerVlog:mmap WALDir:w MutationsMode:0 AuthToken: AllottedMemory:1340.0MB AccessJwtTtl:0s RefreshJwtTtl:0s AclRefreshInterval:0s} I1116 07:38:48.372835 1 server.go:133] Setting Badger table load option: mmap I1116 07:38:48.373099 1 server.go:145] Setting Badger value log load option: mmap I1116 07:38:48.373255 1 server.go:173] Opening write-ahead log BadgerDB with options: {Dir:w ValueDir:w SyncWrites:false TableLoadingMode:1 ValueLogLoadingMode:2 NumVersionsToKeep:1 ReadOnly:false Truncate:true Logger:0x22d6ac8 MaxTableSize:67108864 LevelSizeMultiplier:10 MaxLevels:7 ValueThreshold:1048576 NumMemtables:5 BlockSize:4096 BloomFalsePositive:0.01 NumLevelZeroTables:5 NumLevelZeroTablesStall:10 LevelOneSize:268435456 ValueLogFileSize:1073741823 ValueLogMaxEntries:10000 NumCompactors:2 CompactL0OnClose:true LogRotatesToFlush:2 ChecksumVerificationMode:0 managedTxns:false maxBatchCount:0 maxBatchSize:0} I1116 07:38:48.396267 1 log.go:34] All 1 tables opened in 3ms I1116 07:38:48.403489 1 log.go:34] Replaying file id: 0 at offset: 2420 I1116 07:38:48.403830 1 log.go:34] Replay took: 12.008µs I1116 07:38:48.406502 1 server.go:133] Setting Badger table load option: mmap I1116 07:38:48.406781 1 server.go:145] Setting Badger value log load option: mmap I1116 07:38:48.406952 1 server.go:186] Opening postings BadgerDB with options: {Dir:p ValueDir:p SyncWrites:false TableLoadingMode:2 ValueLogLoadingMode:2 NumVersionsToKeep:2147483647 ReadOnly:false Truncate:true Logger:0x22d6ac8 MaxTableSize:67108864 LevelSizeMultiplier:10 MaxLevels:7 ValueThreshold:1024 NumMemtables:5 BlockSize:4096 BloomFalsePositive:0.01 NumLevelZeroTables:5 NumLevelZeroTablesStall:10 LevelOneSize:268435456 ValueLogFileSize:1073741823 ValueLogMaxEntries:1000000 NumCompactors:2 CompactL0OnClose:true LogRotatesToFlush:2 ChecksumVerificationMode:0 managedTxns:false maxBatchCount:0 maxBatchSize:0} I1116 07:38:48.421394 1 log.go:34] All 1 tables opened in 2ms I1116 07:38:48.426044 1 log.go:34] Replaying file id: 0 at offset: 278 I1116 07:38:48.426332 1 log.go:34] Replay took: 12.486µs I1116 07:38:48.427059 1 run.go:417] gRPC server started. Listening on port 9080 I1116 07:38:48.427222 1 run.go:418] HTTP server started. Listening on port 8080 I1116 07:38:48.427558 1 groups.go:98] Current Raft Id: 0x1 I1116 07:38:48.427834 1 worker.go:81] Worker listening at address: [::]:7080 I1116 07:38:48.529178 1 pool.go:148] CONNECTED to dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 I1116 07:38:48.547284 1 groups.go:121] Connected to group zero. Assigned group: 0 I1116 07:38:48.547569 1 groups.go:123] Raft Id after connection to Zero: 0x1 I1116 07:38:48.548005 1 draft.go:76] Node ID: 0x1 with GroupID: 1 I1116 07:38:48.548377 1 node.go:143] Setting raft.Config to: &{ID:1 peers:[] learners:[] ElectionTick:20 HeartbeatTick:1 Storage:0xc000574bd0 Applied:0 MaxSizePerMsg:262144 MaxCommittedSizePerReady:67108864 MaxUncommittedEntriesSize:0 MaxInflightMsgs:256 CheckQuorum:false PreVote:true ReadOnlyOption:0 Logger:0x22d6ac8 DisableProposalForwarding:false} I1116 07:38:48.549115 1 node.go:312] Found hardstate: {Term:4 Vote:1 Commit:15 XXX_unrecognized:[]} I1116 07:38:48.549693 1 node.go:321] Group 1 found 16 entries I1116 07:38:48.550166 1 draft.go:1369] Restarting node for group: 1 I1116 07:38:48.550577 1 log.go:34] 1 became follower at term 4 I1116 07:38:48.550960 1 log.go:34] newRaft 1 [peers: [], term: 4, commit: 15, applied: 0, lastindex: 15, lastterm: 4] I1116 07:38:48.551247 1 groups.go:141] Server is ready I1116 07:38:48.551902 1 draft.go:749] Found Raft progress: 0 I1116 07:38:48.552387 1 groups.go:727] Got address of a Zero leader: dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 I1116 07:38:48.552758 1 groups.go:740] Starting a new membership stream receive from dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080. I1116 07:38:48.560258 1 groups.go:757] Received first state update from Zero: counter:15 groups: > tablets: > checksum:3692025465707345165 > > zeros: > maxTxnTs:10000 maxRaftId:1 cid:"f2695ad9-6621-484e-a460-153517dea620" license: I1116 07:38:48.565206 1 node.go:180] Setting conf state to nodes:1 I1116 07:38:48.566680 1 mutation.go:171] Done schema update predicate:"dgraph.type" value_type:STRING directive:INDEX tokenizer:"exact" list:true I1116 07:38:50.754919 1 log.go:34] 1 is starting a new election at term 4 I1116 07:38:50.755417 1 log.go:34] 1 became pre-candidate at term 4 I1116 07:38:50.755645 1 log.go:34] 1 received MsgPreVoteResp from 1 at term 4 I1116 07:38:50.756307 1 log.go:34] 1 became candidate at term 5 I1116 07:38:50.756635 1 log.go:34] 1 received MsgVoteResp from 1 at term 5 I1116 07:38:50.757951 1 log.go:34] 1 became leader at term 5 I1116 07:38:50.758308 1 log.go:34] raft.node: 1 elected leader 1 at term 5 I1116 07:38:51.552185 1 groups.go:808] Leader idx=0x1 of group=1 is connecting to Zero for txn updates I1116 07:38:51.552347 1 groups.go:817] Got Zero leader: dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 W1116 08:15:05.677770 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 215ms. Breakdown: [{sync 214ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I1116 08:15:52.171228 1 http.go:540] Got alter request via HTTP from 127.0.0.1:40088 I1116 08:15:52.171994 1 server.go:292] Received ALTER op: schema:" : string .\n : string .\n : string @index(fulltext, hash) @upsert .\n : string .\n : float .\n : string .\n : string @index(hash) .\n : string .\n : [uid] @reverse .\n : int .\n : datetime @index(hour) .\n : string @index(hash) .\n : [uid] .\n : string .\n : [uid] .\n : string .\n : string @index(hash) .\n : string .\n : int .\n : string .\n : string .\n : string @index(hash) @upsert .\n : string .\n : [uid] @reverse .\n : [uid] .\n : [uid] .\n : bool .\n : bool .\n : float .\n : geo @index(geo) .\n : string .\n : string .\n : float .\n : int .\n : [uid] @reverse .\n : int .\n : int .\n : string .\n : string @index(hash) .\n : string .\n : string . #for user.game.name (used only in custom games)\n : string . #for user.game.platform (used only in custom games)\n" I1116 08:15:52.173982 1 server.go:418] Got schema: &{Preds:[predicate:"description" value_type:STRING predicate:"game.coverUrl" value_type:STRING predicate:"game.name" value_type:STRING directive:INDEX tokenizer:"fulltext" tokenizer:"hash" upsert:true predicate:"game.platform" value_type:STRING predicate:"game.popularity" value_type:FLOAT predicate:"game.url" value_type:STRING predicate:"game.uuid" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"game" value_type:STRING predicate:"info" value_type:UID directive:REVERSE list:true predicate:"negative" value_type:INT predicate:"offer.epochTimeCreated" value_type:DATETIME directive:INDEX tokenizer:"hour" predicate:"offer.offerId" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"offer.receiverGames" value_type:UID list:true predicate:"offer.receiverId" value_type:STRING predicate:"offer.senderGames" value_type:UID list:true predicate:"offer.senderId" value_type:STRING predicate:"offer.status" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"offer" value_type:STRING predicate:"positive" value_type:INT predicate:"tradeType" value_type:STRING predicate:"user.description" value_type:STRING predicate:"user.email" value_type:STRING directive:INDEX tokenizer:"hash" upsert:true predicate:"user.epochTimeCreated" value_type:STRING predicate:"user.game" value_type:UID directive:REVERSE list:true predicate:"user.gamesHas" value_type:UID list:true predicate:"user.gamesWant" value_type:UID list:true predicate:"user.isPro" value_type:BOOL predicate:"user.matchNotifications" value_type:BOOL predicate:"user.latitude" value_type:FLOAT predicate:"user.location" value_type:GEO directive:INDEX tokenizer:"geo" predicate:"user.country" value_type:STRING predicate:"user.state" value_type:STRING predicate:"user.longitude" value_type:FLOAT predicate:"user.noOfSuccessfulExchanges" value_type:INT predicate:"user.participates" value_type:UID directive:REVERSE list:true predicate:"user.ratingNegative" value_type:INT predicate:"user.ratingPositive" value_type:INT predicate:"user.userImageUrl" value_type:STRING predicate:"user.userName" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"user" value_type:STRING predicate:"name" value_type:STRING predicate:"platform" value_type:STRING ] Types:[]} I1116 08:15:52.183505 1 groups.go:448] Serving tablet for: description I1116 08:15:52.190284 1 groups.go:448] Serving tablet for: game.coverUrl I1116 08:15:52.197621 1 groups.go:448] Serving tablet for: game.name I1116 08:15:52.205578 1 groups.go:448] Serving tablet for: game.platform I1116 08:15:52.213414 1 groups.go:448] Serving tablet for: game.popularity I1116 08:15:52.220798 1 groups.go:448] Serving tablet for: game.url I1116 08:15:52.229148 1 groups.go:448] Serving tablet for: game.uuid I1116 08:15:52.236386 1 groups.go:448] Serving tablet for: game I1116 08:15:52.252897 1 groups.go:448] Serving tablet for: info I1116 08:15:52.259604 1 groups.go:448] Serving tablet for: negative I1116 08:15:52.265928 1 groups.go:448] Serving tablet for: offer.epochTimeCreated I1116 08:15:52.272106 1 groups.go:448] Serving tablet for: offer.offerId I1116 08:15:52.279667 1 groups.go:448] Serving tablet for: offer.receiverGames I1116 08:15:52.289733 1 groups.go:448] Serving tablet for: offer.receiverId I1116 08:15:52.295868 1 groups.go:448] Serving tablet for: offer.senderGames I1116 08:15:52.305100 1 groups.go:448] Serving tablet for: offer.senderId I1116 08:15:52.315535 1 groups.go:448] Serving tablet for: offer.status I1116 08:15:52.322191 1 groups.go:448] Serving tablet for: offer I1116 08:15:52.328651 1 groups.go:448] Serving tablet for: positive I1116 08:15:52.336129 1 groups.go:448] Serving tablet for: tradeType I1116 08:15:52.344345 1 groups.go:448] Serving tablet for: user.description I1116 08:15:52.350514 1 groups.go:448] Serving tablet for: user.email I1116 08:15:52.358386 1 groups.go:448] Serving tablet for: user.epochTimeCreated I1116 08:15:52.365854 1 groups.go:448] Serving tablet for: user.game I1116 08:15:52.373405 1 groups.go:448] Serving tablet for: user.gamesHas I1116 08:15:52.381628 1 groups.go:448] Serving tablet for: user.gamesWant I1116 08:15:52.389039 1 groups.go:448] Serving tablet for: user.isPro I1116 08:15:52.397047 1 groups.go:448] Serving tablet for: user.matchNotifications I1116 08:15:52.403872 1 groups.go:448] Serving tablet for: user.latitude I1116 08:15:52.411525 1 groups.go:448] Serving tablet for: user.location I1116 08:15:52.420902 1 groups.go:448] Serving tablet for: user.country I1116 08:15:52.429719 1 groups.go:448] Serving tablet for: user.state I1116 08:15:52.437837 1 groups.go:448] Serving tablet for: user.longitude I1116 08:15:52.445971 1 groups.go:448] Serving tablet for: user.noOfSuccessfulExchanges I1116 08:15:52.451648 1 groups.go:448] Serving tablet for: user.participates I1116 08:15:52.459184 1 groups.go:448] Serving tablet for: user.ratingNegative I1116 08:15:52.465781 1 groups.go:448] Serving tablet for: user.ratingPositive I1116 08:15:52.475732 1 groups.go:448] Serving tablet for: user.userImageUrl I1116 08:15:52.483776 1 groups.go:448] Serving tablet for: user.userName I1116 08:15:52.490631 1 groups.go:448] Serving tablet for: user I1116 08:15:52.649486 1 groups.go:448] Serving tablet for: name I1116 08:15:52.656391 1 groups.go:448] Serving tablet for: platform I1116 08:15:52.661771 1 mutation.go:171] Done schema update predicate:"description" value_type:STRING I1116 08:15:52.662316 1 mutation.go:171] Done schema update predicate:"game.coverUrl" value_type:STRING I1116 08:15:52.662712 1 index.go:702] Deleting index for attr game.name and tokenizers [] I1116 08:15:52.663225 1 index.go:720] Rebuilding index for attr game.name and tokenizers [fulltext hash] I1116 08:15:52.663686 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:52.680094 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 9 103 97 109 101 46 110 97 109 101 2 0 8]} I1116 08:15:52.680515 1 log.go:34] Running for level: 0 I1116 08:15:52.696450 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 15.722981ms I1116 08:15:52.696860 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:52.697200 1 log.go:34] DropPrefix done I1116 08:15:52.697541 1 log.go:34] Resuming writes I1116 08:15:52.697868 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:52.706179 1 log.go:34] DropPrefix done I1116 08:15:52.708522 1 log.go:34] Resuming writes I1116 08:15:52.708886 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:52.837958 1 log.go:34] DropPrefix done I1116 08:15:52.838355 1 log.go:34] Resuming writes I1116 08:15:52.838678 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:52.986235 1 log.go:34] DropPrefix done I1116 08:15:52.986642 1 log.go:34] Resuming writes I1116 08:15:52.987056 1 log.go:34] Rebuilding index for predicate game.name: Sent 0 keys I1116 08:15:52.989150 1 mutation.go:171] Done schema update predicate:"game.name" value_type:STRING directive:INDEX tokenizer:"fulltext" tokenizer:"hash" upsert:true I1116 08:15:52.989587 1 mutation.go:171] Done schema update predicate:"game.platform" value_type:STRING I1116 08:15:52.989873 1 mutation.go:171] Done schema update predicate:"game.popularity" value_type:FLOAT I1116 08:15:52.990185 1 mutation.go:171] Done schema update predicate:"game.url" value_type:STRING I1116 08:15:52.990435 1 index.go:702] Deleting index for attr game.uuid and tokenizers [] I1116 08:15:52.990582 1 index.go:720] Rebuilding index for attr game.uuid and tokenizers [hash] I1116 08:15:52.990722 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.007798 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 9 103 97 109 101 46 117 117 105 100 2 0 11]} I1116 08:15:53.008155 1 log.go:34] Running for level: 0 I1116 08:15:53.025681 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 17.316157ms I1116 08:15:53.026134 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.026385 1 log.go:34] DropPrefix done I1116 08:15:53.026628 1 log.go:34] Resuming writes I1116 08:15:53.026943 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.249334 1 log.go:34] DropPrefix done I1116 08:15:53.250107 1 log.go:34] Resuming writes I1116 08:15:53.251102 1 log.go:34] Rebuilding index for predicate game.uuid: Sent 0 keys I1116 08:15:53.254811 1 mutation.go:171] Done schema update predicate:"game.uuid" value_type:STRING directive:INDEX tokenizer:"hash" I1116 08:15:53.255375 1 mutation.go:171] Done schema update predicate:"game" value_type:STRING I1116 08:15:53.255856 1 log.go:34] Rebuilding index for predicate info: Sent 0 keys I1116 08:15:53.261287 1 index.go:880] Deleting reverse index for info I1116 08:15:53.261695 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.280076 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 4 105 110 102 111 4 0]} I1116 08:15:53.280488 1 log.go:34] Running for level: 0 I1116 08:15:53.297878 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 17.097488ms I1116 08:15:53.298227 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.298377 1 log.go:34] DropPrefix done I1116 08:15:53.298570 1 log.go:34] Resuming writes I1116 08:15:53.298833 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.311034 1 log.go:34] DropPrefix done I1116 08:15:53.313128 1 log.go:34] Resuming writes I1116 08:15:53.313575 1 index.go:890] Rebuilding reverse index for info I1116 08:15:53.314213 1 log.go:34] Rebuilding index for predicate info: Sent 0 keys I1116 08:15:53.314678 1 mutation.go:171] Done schema update predicate:"info" value_type:UID directive:REVERSE list:true I1116 08:15:53.315118 1 mutation.go:171] Done schema update predicate:"negative" value_type:INT I1116 08:15:53.315447 1 index.go:702] Deleting index for attr offer.epochTimeCreated and tokenizers [] I1116 08:15:53.315626 1 index.go:720] Rebuilding index for attr offer.epochTimeCreated and tokenizers [hour] I1116 08:15:53.315824 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.333570 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 22 111 102 102 101 114 46 101 112 111 99 104 84 105 109 101 67 114 101 97 116 101 100 2 0 67]} I1116 08:15:53.333989 1 log.go:34] Running for level: 0 I1116 08:15:53.348785 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 14.541765ms I1116 08:15:53.349300 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.349619 1 log.go:34] DropPrefix done I1116 08:15:53.349904 1 log.go:34] Resuming writes I1116 08:15:53.350253 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.365330 1 log.go:34] DropPrefix done I1116 08:15:53.367443 1 log.go:34] Resuming writes I1116 08:15:53.367972 1 log.go:34] Rebuilding index for predicate offer.epochTimeCreated: Sent 0 keys I1116 08:15:53.370832 1 mutation.go:171] Done schema update predicate:"offer.epochTimeCreated" value_type:DATETIME directive:INDEX tokenizer:"hour" I1116 08:15:53.371449 1 index.go:702] Deleting index for attr offer.offerId and tokenizers [] I1116 08:15:53.371635 1 index.go:720] Rebuilding index for attr offer.offerId and tokenizers [hash] I1116 08:15:53.371842 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.394710 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 13 111 102 102 101 114 46 111 102 102 101 114 73 100 2 0 11]} I1116 08:15:53.395158 1 log.go:34] Running for level: 0 I1116 08:15:53.409576 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 14.154466ms I1116 08:15:53.409946 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.410124 1 log.go:34] DropPrefix done I1116 08:15:53.410374 1 log.go:34] Resuming writes I1116 08:15:53.410718 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.425666 1 log.go:34] DropPrefix done I1116 08:15:53.426285 1 log.go:34] Resuming writes I1116 08:15:53.427100 1 log.go:34] Rebuilding index for predicate offer.offerId: Sent 0 keys I1116 08:15:53.429634 1 mutation.go:171] Done schema update predicate:"offer.offerId" value_type:STRING directive:INDEX tokenizer:"hash" I1116 08:15:53.430589 1 log.go:34] Rebuilding index for predicate offer.receiverGames: Sent 0 keys I1116 08:15:53.436824 1 mutation.go:171] Done schema update predicate:"offer.receiverGames" value_type:UID list:true I1116 08:15:53.437896 1 mutation.go:171] Done schema update predicate:"offer.receiverId" value_type:STRING I1116 08:15:53.439076 1 log.go:34] Rebuilding index for predicate offer.senderGames: Sent 0 keys I1116 08:15:53.443924 1 mutation.go:171] Done schema update predicate:"offer.senderGames" value_type:UID list:true I1116 08:15:53.444499 1 mutation.go:171] Done schema update predicate:"offer.senderId" value_type:STRING I1116 08:15:53.444840 1 index.go:702] Deleting index for attr offer.status and tokenizers [] I1116 08:15:53.445008 1 index.go:720] Rebuilding index for attr offer.status and tokenizers [hash] I1116 08:15:53.445219 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.462937 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 12 111 102 102 101 114 46 115 116 97 116 117 115 2 0 11]} I1116 08:15:53.463328 1 log.go:34] Running for level: 0 I1116 08:15:53.477594 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 14.073488ms I1116 08:15:53.477931 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.478133 1 log.go:34] DropPrefix done I1116 08:15:53.478390 1 log.go:34] Resuming writes I1116 08:15:53.478728 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.493872 1 log.go:34] DropPrefix done I1116 08:15:53.495604 1 log.go:34] Resuming writes I1116 08:15:53.496360 1 log.go:34] Rebuilding index for predicate offer.status: Sent 0 keys I1116 08:15:53.499189 1 mutation.go:171] Done schema update predicate:"offer.status" value_type:STRING directive:INDEX tokenizer:"hash" I1116 08:15:53.499689 1 mutation.go:171] Done schema update predicate:"offer" value_type:STRING I1116 08:15:53.500118 1 mutation.go:171] Done schema update predicate:"positive" value_type:INT I1116 08:15:53.500669 1 mutation.go:171] Done schema update predicate:"tradeType" value_type:STRING I1116 08:15:53.501028 1 mutation.go:171] Done schema update predicate:"user.description" value_type:STRING I1116 08:15:53.501471 1 index.go:702] Deleting index for attr user.email and tokenizers [] I1116 08:15:53.501699 1 index.go:720] Rebuilding index for attr user.email and tokenizers [hash] I1116 08:15:53.501896 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.524509 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 10 117 115 101 114 46 101 109 97 105 108 2 0 11]} I1116 08:15:53.524976 1 log.go:34] Running for level: 0 I1116 08:15:53.539702 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 14.529792ms I1116 08:15:53.540142 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.540343 1 log.go:34] DropPrefix done I1116 08:15:53.540682 1 log.go:34] Resuming writes I1116 08:15:53.541031 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.552157 1 log.go:34] DropPrefix done I1116 08:15:53.557066 1 log.go:34] Resuming writes I1116 08:15:53.557930 1 log.go:34] Rebuilding index for predicate user.email: Sent 0 keys I1116 08:15:53.561098 1 mutation.go:171] Done schema update predicate:"user.email" value_type:STRING directive:INDEX tokenizer:"hash" upsert:true I1116 08:15:53.562009 1 mutation.go:171] Done schema update predicate:"user.epochTimeCreated" value_type:STRING I1116 08:15:53.562580 1 log.go:34] Rebuilding index for predicate user.game: Sent 0 keys I1116 08:15:53.567780 1 index.go:880] Deleting reverse index for user.game I1116 08:15:53.568013 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.585436 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 9 117 115 101 114 46 103 97 109 101 4 0]} I1116 08:15:53.585993 1 log.go:34] Running for level: 0 I1116 08:15:53.601887 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 15.666301ms I1116 08:15:53.602309 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.602501 1 log.go:34] DropPrefix done I1116 08:15:53.602814 1 log.go:34] Resuming writes I1116 08:15:53.603216 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.618796 1 log.go:34] DropPrefix done I1116 08:15:53.620999 1 log.go:34] Resuming writes I1116 08:15:53.621762 1 index.go:890] Rebuilding reverse index for user.game I1116 08:15:53.622455 1 log.go:34] Rebuilding index for predicate user.game: Sent 0 keys I1116 08:15:53.623349 1 mutation.go:171] Done schema update predicate:"user.game" value_type:UID directive:REVERSE list:true I1116 08:15:53.623963 1 log.go:34] Rebuilding index for predicate user.gamesHas: Sent 0 keys I1116 08:15:53.628830 1 mutation.go:171] Done schema update predicate:"user.gamesHas" value_type:UID list:true I1116 08:15:53.629470 1 log.go:34] Rebuilding index for predicate user.gamesWant: Sent 0 keys I1116 08:15:53.633905 1 mutation.go:171] Done schema update predicate:"user.gamesWant" value_type:UID list:true I1116 08:15:53.634496 1 mutation.go:171] Done schema update predicate:"user.isPro" value_type:BOOL I1116 08:15:53.634917 1 mutation.go:171] Done schema update predicate:"user.matchNotifications" value_type:BOOL I1116 08:15:53.635295 1 mutation.go:171] Done schema update predicate:"user.latitude" value_type:FLOAT I1116 08:15:53.635635 1 index.go:702] Deleting index for attr user.location and tokenizers [] I1116 08:15:53.635825 1 index.go:720] Rebuilding index for attr user.location and tokenizers [geo] I1116 08:15:53.636050 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.659453 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 13 117 115 101 114 46 108 111 99 97 116 105 111 110 2 0 5]} I1116 08:15:53.659879 1 log.go:34] Running for level: 0 I1116 08:15:53.684922 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 24.8434ms I1116 08:15:53.685260 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.685424 1 log.go:34] DropPrefix done I1116 08:15:53.685676 1 log.go:34] Resuming writes I1116 08:15:53.685948 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.711593 1 log.go:34] DropPrefix done I1116 08:15:53.713109 1 log.go:34] Resuming writes I1116 08:15:53.713592 1 log.go:34] Rebuilding index for predicate user.location: Sent 0 keys I1116 08:15:53.716241 1 mutation.go:171] Done schema update predicate:"user.location" value_type:GEO directive:INDEX tokenizer:"geo" I1116 08:15:53.716987 1 mutation.go:171] Done schema update predicate:"user.country" value_type:STRING I1116 08:15:53.717609 1 mutation.go:171] Done schema update predicate:"user.state" value_type:STRING I1116 08:15:53.718315 1 mutation.go:171] Done schema update predicate:"user.longitude" value_type:FLOAT I1116 08:15:53.718975 1 mutation.go:171] Done schema update predicate:"user.noOfSuccessfulExchanges" value_type:INT I1116 08:15:53.720537 1 log.go:34] Rebuilding index for predicate user.participates: Sent 0 keys I1116 08:15:53.726240 1 index.go:880] Deleting reverse index for user.participates I1116 08:15:53.726657 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.748021 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 17 117 115 101 114 46 112 97 114 116 105 99 105 112 97 116 101 115 4 0]} I1116 08:15:53.748387 1 log.go:34] Running for level: 0 I1116 08:15:53.762799 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 14.199711ms I1116 08:15:53.763230 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.763419 1 log.go:34] DropPrefix done I1116 08:15:53.763677 1 log.go:34] Resuming writes I1116 08:15:53.763959 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.777740 1 log.go:34] DropPrefix done I1116 08:15:53.779596 1 log.go:34] Resuming writes I1116 08:15:53.780021 1 index.go:890] Rebuilding reverse index for user.participates I1116 08:15:53.780548 1 log.go:34] Rebuilding index for predicate user.participates: Sent 0 keys I1116 08:15:53.781652 1 mutation.go:171] Done schema update predicate:"user.participates" value_type:UID directive:REVERSE list:true I1116 08:15:53.782103 1 mutation.go:171] Done schema update predicate:"user.ratingNegative" value_type:INT I1116 08:15:53.782500 1 mutation.go:171] Done schema update predicate:"user.ratingPositive" value_type:INT I1116 08:15:53.782827 1 mutation.go:171] Done schema update predicate:"user.userImageUrl" value_type:STRING I1116 08:15:53.783090 1 index.go:702] Deleting index for attr user.userName and tokenizers [] I1116 08:15:53.783228 1 index.go:720] Rebuilding index for attr user.userName and tokenizers [hash] I1116 08:15:53.783441 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.806759 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 13 117 115 101 114 46 117 115 101 114 78 97 109 101 2 0 11]} I1116 08:15:53.807346 1 log.go:34] Running for level: 0 I1116 08:15:53.820830 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 13.267063ms I1116 08:15:53.821276 1 log.go:34] Compaction for level: 0 DONE I1116 08:15:53.821516 1 log.go:34] DropPrefix done I1116 08:15:53.821826 1 log.go:34] Resuming writes I1116 08:15:53.822156 1 log.go:34] Writes flushed. Stopping compactions now... I1116 08:15:53.837422 1 log.go:34] DropPrefix done I1116 08:15:53.838871 1 log.go:34] Resuming writes I1116 08:15:53.839254 1 log.go:34] Rebuilding index for predicate user.userName: Sent 0 keys I1116 08:15:53.841713 1 mutation.go:171] Done schema update predicate:"user.userName" value_type:STRING directive:INDEX tokenizer:"hash" I1116 08:15:53.842170 1 mutation.go:171] Done schema update predicate:"user" value_type:STRING I1116 08:15:53.842516 1 mutation.go:171] Done schema update predicate:"name" value_type:STRING I1116 08:15:53.842850 1 mutation.go:171] Done schema update predicate:"platform" value_type:STRING I1116 08:15:53.843364 1 server.go:423] ALTER op: schema:" : string .\n : string .\n : string @index(fulltext, hash) @upsert .\n : string .\n : float .\n : string .\n : string @index(hash) .\n : string .\n : [uid] @reverse .\n : int .\n : datetime @index(hour) .\n : string @index(hash) .\n : [uid] .\n : string .\n : [uid] .\n : string .\n : string @index(hash) .\n : string .\n : int .\n : string .\n : string .\n : string @index(hash) @upsert .\n : string .\n : [uid] @reverse .\n : [uid] .\n : [uid] .\n : bool .\n : bool .\n : float .\n : geo @index(geo) .\n : string .\n : string .\n : float .\n : int .\n : [uid] @reverse .\n : int .\n : int .\n : string .\n : string @index(hash) .\n : string .\n : string . #for user.game.name (used only in custom games)\n : string . #for user.game.platform (used only in custom games)\n" done W1116 08:33:26.464794 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:33:58.682720 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 261ms. Breakdown: [{sync 260ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:37:09.211761 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 474ms. Breakdown: [{sync 469ms} {advance 4ms} {proposals 1ms} {disk 0s}] Num entries: 1. MustSync: true W1116 08:37:16.264315 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 536ms. Breakdown: [{sync 535ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:37:21.221287 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 219ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:37:26.355194 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 520ms. Breakdown: [{sync 515ms} {disk 1ms} {proposals 1ms} {advance 0s}] Num entries: 1. MustSync: true W1116 08:37:36.901443 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 394ms. Breakdown: [{sync 394ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:38:05.603519 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 409ms. Breakdown: [{sync 403ms} {advance 5ms} {disk 0s} {proposals 0s}] Num entries: 1. MustSync: true W1116 08:39:25.249306 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 211ms. Breakdown: [{sync 209ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:44:42.929076 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 271ms. Breakdown: [{sync 265ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:45:58.219974 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 262ms. Breakdown: [{sync 261ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:46:08.969144 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 240ms. Breakdown: [{sync 240ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:46:36.037157 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 225ms. Breakdown: [{sync 224ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:47:34.349383 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 421ms. Breakdown: [{sync 420ms} {proposals 1ms} {disk 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:52:13.123646 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 265ms. Breakdown: [{sync 264ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 08:53:38.984327 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 256ms. Breakdown: [{sync 255ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 09:16:26.158744 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 256ms. Breakdown: [{sync 256ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 09:26:45.631403 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 256ms} {disk 2ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I1116 09:29:13.635411 1 groups.go:448] Serving tablet for: dgraph.xid I1116 09:29:13.641193 1 groups.go:448] Serving tablet for: dgraph.password I1116 09:29:13.651194 1 groups.go:448] Serving tablet for: dgraph.user.group I1116 09:29:13.657234 1 groups.go:448] Serving tablet for: dgraph.group.acl I1116 09:33:28.291273 1 admin_backup.go:129] Created backup request: read_ts:14829 since_ts:90349 unix_ts:"20191116.093327.668" destination:"s3://s3.us-east-2.amazonaws.com/dgraph-backup-dgraph" . Groups=[1] I1116 09:33:28.291431 1 backup_ee.go:33] Backup request: group 1 at 14829 I1116 09:33:29.073820 1 log.go:34] Dgraph.Backup Sent 0 keys I1116 09:33:29.351548 1 backup.go:157] Backup complete: group 1 at 14829 I1116 09:33:33.384247 1 groups.go:872] Zero leadership changed. Renewing oracle delta stream. E1116 09:33:33.388237 1 groups.go:848] Error in oracle delta stream. Error: rpc error: code = Canceled desc = context canceled I1116 09:33:33.555862 1 groups.go:808] Leader idx=0x1 of group=1 is connecting to Zero for txn updates I1116 09:33:33.556248 1 groups.go:817] Got Zero leader: dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 I1116 09:33:33.688548 1 backup.go:188] Backup completed OK. W1116 09:37:58.800190 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 256ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I1116 09:40:26.436048 1 admin_backup.go:129] Created backup request: read_ts:14858 since_ts:14829 unix_ts:"20191116.094025.943" destination:"s3://s3.us-east-2.amazonaws.com/dgraph-backup-dgraph" . Groups=[1] I1116 09:40:26.436395 1 backup_ee.go:33] Backup request: group 1 at 14858 I1116 09:40:27.510027 1 log.go:34] Dgraph.Backup Time elapsed: 01s, bytes sent: 0 B, speed: 0 B/sec I1116 09:40:28.047998 1 log.go:34] Dgraph.Backup Created batch of size: 1.4 kB in 5.093874ms. I1116 09:40:28.048281 1 log.go:34] Dgraph.Backup Sent 21 keys I1116 09:40:28.408713 1 backup.go:157] Backup complete: group 1 at 14858 I1116 09:40:29.006608 1 backup.go:188] Backup completed OK. I1116 09:40:38.267983 1 http.go:540] Got alter request via HTTP from 127.0.0.1:38388 I1116 09:40:38.270439 1 server.go:292] Received ALTER op: drop_all:true I1116 09:40:38.285126 1 log.go:34] DropAll called. Blocking writes... I1116 09:40:38.285629 1 log.go:34] Writes flushed. Stopping compactions now... I1116 09:40:38.327891 1 log.go:34] Deleted 1 SSTables. Now deleting value logs... I1116 09:40:38.337101 1 log.go:34] Value logs deleted. Creating value log file: 0 I1116 09:40:38.342780 1 log.go:34] Deleted 1 value log files. DropAll done. I1116 09:40:38.343156 1 log.go:34] Resuming writes I1116 09:40:38.344641 1 server.go:337] ALTER op: drop_all:true done I1116 09:43:08.066221 1 admin_backup.go:129] Created backup request: read_ts:14861 unix_ts:"20191116.094307.667" destination:"s3://s3.us-east-2.amazonaws.com/dgraph-backup-dgraph" . Groups=[1] I1116 09:43:08.066839 1 backup_ee.go:33] Backup request: group 1 at 14861 I1116 09:43:08.830592 1 log.go:34] Dgraph.Backup Created batch of size: 54 B in 689.877559ms. I1116 09:43:08.831190 1 log.go:34] Dgraph.Backup Sent 1 keys I1116 09:43:09.174049 1 backup.go:157] Backup complete: group 1 at 14861 I1116 09:43:09.794240 1 backup.go:188] Backup completed OK. W1116 09:55:07.989904 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 258ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1116 22:56:39.890197 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 227ms. Breakdown: [{sync 227ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1118 22:56:23.138954 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 228ms. Breakdown: [{sync 226ms} {proposals 2ms} {disk 0s} {advance 0s}] Num entries: 1. MustSync: true I1119 12:53:47.767316 1 groups.go:872] Zero leadership changed. Renewing oracle delta stream. E1119 12:53:47.768993 1 groups.go:848] Error in oracle delta stream. Error: rpc error: code = Canceled desc = context canceled I1119 12:53:48.552234 1 groups.go:808] Leader idx=0x1 of group=1 is connecting to Zero for txn updates I1119 12:53:48.552273 1 groups.go:817] Got Zero leader: dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 W1119 12:53:48.774863 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 219ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1119 19:18:04.762529 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 217ms. Breakdown: [{sync 217ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1119 22:56:31.725688 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 219ms. Breakdown: [{sync 219ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I1120 03:19:36.333971 1 groups.go:872] Zero leadership changed. Renewing oracle delta stream. E1120 03:19:36.334319 1 groups.go:848] Error in oracle delta stream. Error: rpc error: code = Canceled desc = context canceled I1120 03:19:36.716954 1 groups.go:808] Leader idx=0x1 of group=1 is connecting to Zero for txn updates I1120 03:19:36.889143 1 groups.go:817] Got Zero leader: dgraph-zero-0.dgraph-zero.default.svc.cluster.local:5080 W1120 03:19:37.110872 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 219ms. Breakdown: [{sync 218ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1120 22:56:35.463582 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1121 22:56:33.482133 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 220ms. Breakdown: [{sync 219ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1122 04:11:09.251032 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 221ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1122 22:56:32.575706 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 220ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1123 22:56:31.127120 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 217ms. Breakdown: [{sync 216ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1124 22:56:31.142909 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 218ms. Breakdown: [{sync 217ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:16:44.198323 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 224ms. Breakdown: [{sync 222ms} {disk 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I1126 12:20:24.768898 1 http.go:540] Got alter request via HTTP from 127.0.0.1:50820 I1126 12:20:24.769550 1 server.go:292] Received ALTER op: schema:" : string .\n : string .\n : string @index(fulltext, hash) @upsert .\n : string .\n : float .\n : string .\n : string @index(hash) .\n : string .\n : [uid] @reverse .\n : int .\n : datetime @index(hour) .\n : string @index(hash) .\n : [uid] .\n : string .\n : [uid] .\n : string .\n : string @index(hash) .\n : string .\n : int .\n : string .\n : string .\n : string @index(hash) @upsert .\n : string .\n : [uid] @reverse .\n : [uid] .\n : [uid] .\n : bool .\n : bool .\n : float .\n : geo @index(geo) .\n : string .\n : string .\n : float .\n : int .\n : [uid] @reverse .\n : int .\n : int .\n : string .\n : string @index(hash) .\n : string .\n : string . #for user.game.name (used only in custom games)\n : string . #for user.game.platform (used only in custom games)\n" I1126 12:20:24.771530 1 server.go:418] Got schema: &{Preds:[predicate:"description" value_type:STRING predicate:"game.coverUrl" value_type:STRING predicate:"game.name" value_type:STRING directive:INDEX tokenizer:"fulltext" tokenizer:"hash" upsert:true predicate:"game.platform" value_type:STRING predicate:"game.popularity" value_type:FLOAT predicate:"game.url" value_type:STRING predicate:"game.uuid" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"game" value_type:STRING predicate:"info" value_type:UID directive:REVERSE list:true predicate:"negative" value_type:INT predicate:"offer.epochTimeCreated" value_type:DATETIME directive:INDEX tokenizer:"hour" predicate:"offer.offerId" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"offer.receiverGames" value_type:UID list:true predicate:"offer.receiverId" value_type:STRING predicate:"offer.senderGames" value_type:UID list:true predicate:"offer.senderId" value_type:STRING predicate:"offer.status" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"offer" value_type:STRING predicate:"positive" value_type:INT predicate:"tradeType" value_type:STRING predicate:"user.description" value_type:STRING predicate:"user.email" value_type:STRING directive:INDEX tokenizer:"hash" upsert:true predicate:"user.epochTimeCreated" value_type:STRING predicate:"user.game" value_type:UID directive:REVERSE list:true predicate:"user.gamesHas" value_type:UID list:true predicate:"user.gamesWant" value_type:UID list:true predicate:"user.isPro" value_type:BOOL predicate:"user.matchNotifications" value_type:BOOL predicate:"user.latitude" value_type:FLOAT predicate:"user.location" value_type:GEO directive:INDEX tokenizer:"geo" predicate:"user.country" value_type:STRING predicate:"user.state" value_type:STRING predicate:"user.longitude" value_type:FLOAT predicate:"user.noOfSuccessfulExchanges" value_type:INT predicate:"user.participates" value_type:UID directive:REVERSE list:true predicate:"user.ratingNegative" value_type:INT predicate:"user.ratingPositive" value_type:INT predicate:"user.userImageUrl" value_type:STRING predicate:"user.userName" value_type:STRING directive:INDEX tokenizer:"hash" predicate:"user" value_type:STRING predicate:"name" value_type:STRING predicate:"platform" value_type:STRING ] Types:[]} I1126 12:20:24.788102 1 mutation.go:171] Done schema update predicate:"description" value_type:STRING I1126 12:20:24.789144 1 mutation.go:171] Done schema update predicate:"game.coverUrl" value_type:STRING I1126 12:20:24.789606 1 index.go:702] Deleting index for attr game.name and tokenizers [] I1126 12:20:24.789773 1 index.go:720] Rebuilding index for attr game.name and tokenizers [fulltext hash] I1126 12:20:24.790139 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:24.918471 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 9 103 97 109 101 46 110 97 109 101 2 0 8]} I1126 12:20:24.919460 1 log.go:34] Running for level: 0 I1126 12:20:24.943564 1 log.go:34] LOG Compact 0->1, del 1 tables, add 1 tables, took 23.912321ms I1126 12:20:24.943634 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:24.943689 1 log.go:34] DropPrefix done I1126 12:20:24.943788 1 log.go:34] Resuming writes I1126 12:20:24.943966 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.123286 1 log.go:34] DropPrefix done I1126 12:20:25.124447 1 log.go:34] Resuming writes I1126 12:20:25.125093 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.325843 1 log.go:34] DropPrefix done I1126 12:20:25.326202 1 log.go:34] Resuming writes I1126 12:20:25.329302 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.337939 1 log.go:34] DropPrefix done I1126 12:20:25.338310 1 log.go:34] Resuming writes I1126 12:20:25.338977 1 log.go:34] Rebuilding index for predicate game.name: Sent 0 keys I1126 12:20:25.341626 1 mutation.go:171] Done schema update predicate:"game.name" value_type:STRING directive:INDEX tokenizer:"fulltext" tokenizer:"hash" upsert:true I1126 12:20:25.342219 1 mutation.go:171] Done schema update predicate:"game.platform" value_type:STRING I1126 12:20:25.342599 1 mutation.go:171] Done schema update predicate:"game.popularity" value_type:FLOAT I1126 12:20:25.342961 1 mutation.go:171] Done schema update predicate:"game.url" value_type:STRING I1126 12:20:25.343330 1 index.go:702] Deleting index for attr game.uuid and tokenizers [] I1126 12:20:25.343502 1 index.go:720] Rebuilding index for attr game.uuid and tokenizers [hash] I1126 12:20:25.343765 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.367458 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 9 103 97 109 101 46 117 117 105 100 2 0 11]} I1126 12:20:25.368279 1 log.go:34] Running for level: 0 I1126 12:20:25.389817 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 21.230456ms I1126 12:20:25.389870 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:25.389897 1 log.go:34] DropPrefix done I1126 12:20:25.389955 1 log.go:34] Resuming writes I1126 12:20:25.389982 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.582533 1 log.go:34] DropPrefix done I1126 12:20:25.584063 1 log.go:34] Resuming writes I1126 12:20:25.585097 1 log.go:34] Rebuilding index for predicate game.uuid: Sent 0 keys I1126 12:20:25.588129 1 mutation.go:171] Done schema update predicate:"game.uuid" value_type:STRING directive:INDEX tokenizer:"hash" I1126 12:20:25.589309 1 mutation.go:171] Done schema update predicate:"game" value_type:STRING I1126 12:20:25.590733 1 log.go:34] Rebuilding index for predicate info: Sent 0 keys I1126 12:20:25.597225 1 index.go:880] Deleting reverse index for info I1126 12:20:25.597747 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.624671 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 4 105 110 102 111 4 0]} I1126 12:20:25.625391 1 log.go:34] Running for level: 0 I1126 12:20:25.649135 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 23.492557ms I1126 12:20:25.649519 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:25.649693 1 log.go:34] DropPrefix done I1126 12:20:25.649906 1 log.go:34] Resuming writes I1126 12:20:25.650388 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.669261 1 log.go:34] DropPrefix done I1126 12:20:25.671538 1 log.go:34] Resuming writes I1126 12:20:25.671879 1 index.go:890] Rebuilding reverse index for info I1126 12:20:25.672796 1 log.go:34] Rebuilding index for predicate info: Sent 0 keys I1126 12:20:25.673490 1 mutation.go:171] Done schema update predicate:"info" value_type:UID directive:REVERSE list:true I1126 12:20:25.677667 1 mutation.go:171] Done schema update predicate:"negative" value_type:INT I1126 12:20:25.678497 1 index.go:702] Deleting index for attr offer.epochTimeCreated and tokenizers [] I1126 12:20:25.678716 1 index.go:720] Rebuilding index for attr offer.epochTimeCreated and tokenizers [hour] I1126 12:20:25.679025 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.723344 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 22 111 102 102 101 114 46 101 112 111 99 104 84 105 109 101 67 114 101 97 116 101 100 2 0 67]} I1126 12:20:25.723420 1 log.go:34] Running for level: 0 I1126 12:20:25.743283 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 19.818053ms I1126 12:20:25.743373 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:25.743401 1 log.go:34] DropPrefix done I1126 12:20:25.743478 1 log.go:34] Resuming writes I1126 12:20:25.743607 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.756825 1 log.go:34] DropPrefix done I1126 12:20:25.762245 1 log.go:34] Resuming writes I1126 12:20:25.763148 1 log.go:34] Rebuilding index for predicate offer.epochTimeCreated: Sent 0 keys I1126 12:20:25.765949 1 mutation.go:171] Done schema update predicate:"offer.epochTimeCreated" value_type:DATETIME directive:INDEX tokenizer:"hour" I1126 12:20:25.771442 1 index.go:702] Deleting index for attr offer.offerId and tokenizers [] I1126 12:20:25.777625 1 index.go:720] Rebuilding index for attr offer.offerId and tokenizers [hash] I1126 12:20:25.778010 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.797506 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 13 111 102 102 101 114 46 111 102 102 101 114 73 100 2 0 11]} I1126 12:20:25.797957 1 log.go:34] Running for level: 0 I1126 12:20:25.817257 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 19.078105ms I1126 12:20:25.817676 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:25.817862 1 log.go:34] DropPrefix done I1126 12:20:25.818194 1 log.go:34] Resuming writes I1126 12:20:25.818561 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.839493 1 log.go:34] DropPrefix done I1126 12:20:25.840532 1 log.go:34] Resuming writes I1126 12:20:25.841525 1 log.go:34] Rebuilding index for predicate offer.offerId: Sent 0 keys I1126 12:20:25.847191 1 mutation.go:171] Done schema update predicate:"offer.offerId" value_type:STRING directive:INDEX tokenizer:"hash" I1126 12:20:25.849397 1 log.go:34] Rebuilding index for predicate offer.receiverGames: Sent 0 keys I1126 12:20:25.855204 1 mutation.go:171] Done schema update predicate:"offer.receiverGames" value_type:UID list:true I1126 12:20:25.855976 1 mutation.go:171] Done schema update predicate:"offer.receiverId" value_type:STRING I1126 12:20:25.856581 1 log.go:34] Rebuilding index for predicate offer.senderGames: Sent 0 keys I1126 12:20:25.863673 1 mutation.go:171] Done schema update predicate:"offer.senderGames" value_type:UID list:true I1126 12:20:25.864209 1 mutation.go:171] Done schema update predicate:"offer.senderId" value_type:STRING I1126 12:20:25.864549 1 index.go:702] Deleting index for attr offer.status and tokenizers [] I1126 12:20:25.864709 1 index.go:720] Rebuilding index for attr offer.status and tokenizers [hash] I1126 12:20:25.864988 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.884685 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 12 111 102 102 101 114 46 115 116 97 116 117 115 2 0 11]} I1126 12:20:25.885003 1 log.go:34] Running for level: 0 I1126 12:20:25.899580 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 14.408182ms I1126 12:20:25.899989 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:25.900224 1 log.go:34] DropPrefix done I1126 12:20:25.900531 1 log.go:34] Resuming writes I1126 12:20:25.901017 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.919937 1 log.go:34] DropPrefix done I1126 12:20:25.922680 1 log.go:34] Resuming writes I1126 12:20:25.923385 1 log.go:34] Rebuilding index for predicate offer.status: Sent 0 keys I1126 12:20:25.930716 1 mutation.go:171] Done schema update predicate:"offer.status" value_type:STRING directive:INDEX tokenizer:"hash" I1126 12:20:25.931469 1 mutation.go:171] Done schema update predicate:"offer" value_type:STRING I1126 12:20:25.931947 1 mutation.go:171] Done schema update predicate:"positive" value_type:INT I1126 12:20:25.932240 1 mutation.go:171] Done schema update predicate:"tradeType" value_type:STRING I1126 12:20:25.932561 1 mutation.go:171] Done schema update predicate:"user.description" value_type:STRING I1126 12:20:25.932906 1 index.go:702] Deleting index for attr user.email and tokenizers [] I1126 12:20:25.933910 1 index.go:720] Rebuilding index for attr user.email and tokenizers [hash] I1126 12:20:25.936817 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:25.956453 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 10 117 115 101 114 46 101 109 97 105 108 2 0 11]} I1126 12:20:25.956870 1 log.go:34] Running for level: 0 I1126 12:20:25.984906 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 27.824709ms I1126 12:20:25.985329 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:25.986347 1 log.go:34] DropPrefix done I1126 12:20:25.987743 1 log.go:34] Resuming writes I1126 12:20:25.988187 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.034429 1 log.go:34] DropPrefix done I1126 12:20:26.035635 1 log.go:34] Resuming writes I1126 12:20:26.036616 1 log.go:34] Rebuilding index for predicate user.email: Sent 0 keys I1126 12:20:26.045916 1 mutation.go:171] Done schema update predicate:"user.email" value_type:STRING directive:INDEX tokenizer:"hash" upsert:true I1126 12:20:26.047082 1 mutation.go:171] Done schema update predicate:"user.epochTimeCreated" value_type:STRING I1126 12:20:26.052746 1 log.go:34] Rebuilding index for predicate user.game: Sent 0 keys I1126 12:20:26.062792 1 index.go:880] Deleting reverse index for user.game I1126 12:20:26.063067 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.084259 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 9 117 115 101 114 46 103 97 109 101 4 0]} I1126 12:20:26.084667 1 log.go:34] Running for level: 0 I1126 12:20:26.102774 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 17.898419ms I1126 12:20:26.102844 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:26.102879 1 log.go:34] DropPrefix done I1126 12:20:26.102968 1 log.go:34] Resuming writes I1126 12:20:26.103153 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.150505 1 log.go:34] DropPrefix done I1126 12:20:26.153726 1 log.go:34] Resuming writes I1126 12:20:26.154051 1 index.go:890] Rebuilding reverse index for user.game I1126 12:20:26.155107 1 log.go:34] Rebuilding index for predicate user.game: Sent 0 keys I1126 12:20:26.155650 1 mutation.go:171] Done schema update predicate:"user.game" value_type:UID directive:REVERSE list:true I1126 12:20:26.160142 1 log.go:34] Rebuilding index for predicate user.gamesHas: Sent 0 keys I1126 12:20:26.165562 1 mutation.go:171] Done schema update predicate:"user.gamesHas" value_type:UID list:true I1126 12:20:26.166376 1 log.go:34] Rebuilding index for predicate user.gamesWant: Sent 0 keys I1126 12:20:26.173359 1 mutation.go:171] Done schema update predicate:"user.gamesWant" value_type:UID list:true I1126 12:20:26.173935 1 mutation.go:171] Done schema update predicate:"user.isPro" value_type:BOOL I1126 12:20:26.174243 1 mutation.go:171] Done schema update predicate:"user.matchNotifications" value_type:BOOL I1126 12:20:26.175578 1 mutation.go:171] Done schema update predicate:"user.latitude" value_type:FLOAT I1126 12:20:26.175885 1 index.go:702] Deleting index for attr user.location and tokenizers [] I1126 12:20:26.176035 1 index.go:720] Rebuilding index for attr user.location and tokenizers [geo] I1126 12:20:26.176232 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.196378 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 13 117 115 101 114 46 108 111 99 97 116 105 111 110 2 0 5]} I1126 12:20:26.196887 1 log.go:34] Running for level: 0 I1126 12:20:26.210745 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 13.592051ms I1126 12:20:26.211064 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:26.211244 1 log.go:34] DropPrefix done I1126 12:20:26.211656 1 log.go:34] Resuming writes I1126 12:20:26.212024 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.229179 1 log.go:34] DropPrefix done I1126 12:20:26.231756 1 log.go:34] Resuming writes I1126 12:20:26.233072 1 log.go:34] Rebuilding index for predicate user.location: Sent 0 keys I1126 12:20:26.242532 1 mutation.go:171] Done schema update predicate:"user.location" value_type:GEO directive:INDEX tokenizer:"geo" I1126 12:20:26.243182 1 mutation.go:171] Done schema update predicate:"user.country" value_type:STRING I1126 12:20:26.243489 1 mutation.go:171] Done schema update predicate:"user.state" value_type:STRING I1126 12:20:26.243812 1 mutation.go:171] Done schema update predicate:"user.longitude" value_type:FLOAT I1126 12:20:26.244202 1 mutation.go:171] Done schema update predicate:"user.noOfSuccessfulExchanges" value_type:INT I1126 12:20:26.244635 1 log.go:34] Rebuilding index for predicate user.participates: Sent 0 keys I1126 12:20:26.251114 1 index.go:880] Deleting reverse index for user.participates I1126 12:20:26.251468 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.273228 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 17 117 115 101 114 46 112 97 114 116 105 99 105 112 97 116 101 115 4 0]} I1126 12:20:26.273658 1 log.go:34] Running for level: 0 I1126 12:20:26.313664 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 39.778883ms I1126 12:20:26.314121 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:26.314411 1 log.go:34] DropPrefix done I1126 12:20:26.314745 1 log.go:34] Resuming writes I1126 12:20:26.315255 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.337545 1 log.go:34] DropPrefix done I1126 12:20:26.340790 1 log.go:34] Resuming writes I1126 12:20:26.341145 1 index.go:890] Rebuilding reverse index for user.participates I1126 12:20:26.346036 1 log.go:34] Rebuilding index for predicate user.participates: Sent 0 keys I1126 12:20:26.346561 1 mutation.go:171] Done schema update predicate:"user.participates" value_type:UID directive:REVERSE list:true I1126 12:20:26.347208 1 mutation.go:171] Done schema update predicate:"user.ratingNegative" value_type:INT I1126 12:20:26.347954 1 mutation.go:171] Done schema update predicate:"user.ratingPositive" value_type:INT I1126 12:20:26.348374 1 mutation.go:171] Done schema update predicate:"user.userImageUrl" value_type:STRING I1126 12:20:26.348972 1 index.go:702] Deleting index for attr user.userName and tokenizers [] I1126 12:20:26.349300 1 index.go:720] Rebuilding index for attr user.userName and tokenizers [hash] I1126 12:20:26.349567 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.374897 1 log.go:34] Got compaction priority: {level:0 score:1.74 dropPrefix:[0 0 13 117 115 101 114 46 117 115 101 114 78 97 109 101 2 0 11]} I1126 12:20:26.375395 1 log.go:34] Running for level: 0 I1126 12:20:26.391478 1 log.go:34] LOG Compact 0->1, del 2 tables, add 1 tables, took 15.803918ms I1126 12:20:26.391934 1 log.go:34] Compaction for level: 0 DONE I1126 12:20:26.392183 1 log.go:34] DropPrefix done I1126 12:20:26.392489 1 log.go:34] Resuming writes I1126 12:20:26.392888 1 log.go:34] Writes flushed. Stopping compactions now... I1126 12:20:26.400332 1 log.go:34] DropPrefix done I1126 12:20:26.400769 1 log.go:34] Resuming writes I1126 12:20:26.401431 1 log.go:34] Rebuilding index for predicate user.userName: Sent 0 keys I1126 12:20:26.404245 1 mutation.go:171] Done schema update predicate:"user.userName" value_type:STRING directive:INDEX tokenizer:"hash" I1126 12:20:26.405166 1 mutation.go:171] Done schema update predicate:"user" value_type:STRING I1126 12:20:26.405723 1 mutation.go:171] Done schema update predicate:"name" value_type:STRING I1126 12:20:26.406254 1 mutation.go:171] Done schema update predicate:"platform" value_type:STRING I1126 12:20:26.406842 1 server.go:423] ALTER op: schema:" : string .\n : string .\n : string @index(fulltext, hash) @upsert .\n : string .\n : float .\n : string .\n : string @index(hash) .\n : string .\n : [uid] @reverse .\n : int .\n : datetime @index(hour) .\n : string @index(hash) .\n : [uid] .\n : string .\n : [uid] .\n : string .\n : string @index(hash) .\n : string .\n : int .\n : string .\n : string .\n : string @index(hash) @upsert .\n : string .\n : [uid] @reverse .\n : [uid] .\n : [uid] .\n : bool .\n : bool .\n : float .\n : geo @index(geo) .\n : string .\n : string .\n : float .\n : int .\n : [uid] @reverse .\n : int .\n : int .\n : string .\n : string @index(hash) .\n : string .\n : string . #for user.game.name (used only in custom games)\n : string . #for user.game.platform (used only in custom games)\n" done W1126 12:41:36.259963 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 254ms. Breakdown: [{sync 254ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:42:24.979291 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 257ms. Breakdown: [{sync 257ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:44:31.279698 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 223ms. Breakdown: [{sync 217ms} {proposals 6ms} {disk 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:45:04.094621 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 224ms. Breakdown: [{sync 219ms} {disk 1ms} {proposals 1ms} {advance 0s}] Num entries: 1. MustSync: true W1126 12:45:16.128018 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 246ms. Breakdown: [{disk 245ms} {sync 1ms} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:45:19.871916 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 273ms. Breakdown: [{sync 263ms} {disk 3ms} {proposals 1ms} {advance 0s}] Num entries: 1. MustSync: true W1126 12:45:38.576909 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 217ms. Breakdown: [{sync 215ms} {disk 1ms} {proposals 1ms} {advance 0s}] Num entries: 1. MustSync: true W1126 12:45:42.796968 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 251ms. Breakdown: [{sync 250ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:46:10.455085 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 221ms. Breakdown: [{sync 221ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:47:36.206020 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 291ms. Breakdown: [{sync 291ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true W1126 12:48:27.966817 1 draft.go:958] Raft.Ready took too long to process: Timer Total: 222ms. Breakdown: [{sync 222ms} {disk 0s} {proposals 0s} {advance 0s}] Num entries: 1. MustSync: true I1126 12:51:46.213270 1 admin_backup.go:129] Created backup request: read_ts:18235 unix_ts:"20191126.125145.781" destination:"s3://s3.us-east-2.amazonaws.com/dgraph-backup-dgraph" . Groups=[1] I1126 12:51:46.218940 1 backup_ee.go:33] Backup request: group 1 at 18235