SGW-Logs-Incoming-doc-with-docid-of-previously-deleted-doc

SGW-lo

2019-05-10T18:41:41.265Z [DBG] SyncMsg+: c:[35d219ee] #4: Type:rev Id:doc1 Rev:3-a7947267ceb469e999e9141062592e2f685a3bf0 Sequence:5  User:admin
2019-05-10T18:41:41.268Z [DBG] CRUD+: c:[35d219ee] Invoking sync on doc "doc1" rev 3-a7947267ceb469e999e9141062592e2f685a3bf0
2019-05-10T18:41:41.269Z [DBG] CRUD+: Backed up revision body "doc1"/"2-6cc55e24e75c5f1d5a86ec6c833746a69725ec6c" (18 bytes, ttl:300)
2019-05-10T18:41:41.281Z [DBG] CRUD+: c:[35d219ee] Saving doc (seq: #38, id: doc1 rev: 3-a7947267ceb469e999e9141062592e2f685a3bf0)
2019-05-10T18:41:41.281Z [INF] CRUD: c:[35d219ee] Stored doc "doc1" / "3-a7947267ceb469e999e9141062592e2f685a3bf0" as #38
2019-05-10T18:41:41.281Z [DBG] SyncMsg+: c:[35d219ee] #4: Type:rev   --> OK Time:29.6462ms User:admin 
2019-05-10T18:41:41.281Z [DBG] WS+: c:[35d219ee] Push RPY#4
2019-05-10T18:41:41.281Z [DBG] WS+: c:[35d219ee] Sending frame: RPY#4 (flags=       1, size=    1)
2019-05-10T18:41:41.283Z [DBG] WS+: c:[35d219ee] Received frame: MSG#5 (flags=       0, length=93)
2019-05-10T18:41:41.283Z [DBG] WSFrame+: c:[35d219ee] Incoming BLIP Request: MSG#5
2019-05-10T18:41:41.283Z [INF] SyncMsg: c:[35d219ee] #5: Type:setCheckpoint Client:cp-sadLH/fPXDyG6EXNuTUbUjD20jM= Rev:0-3  User:admin
2019-05-10T18:41:41.301Z [DBG] SyncMsg+: c:[35d219ee] #5: Type:setCheckpoint   --> OK Time:18.5298ms User:admin 
2019-05-10T18:41:41.301Z [DBG] WS+: c:[35d219ee] Push RPY#5
2019-05-10T18:41:41.301Z [DBG] WS+: c:[35d219ee] Sending frame: RPY#5 (flags=       1, size=    9)
2019-05-10T18:41:41.306Z [INF] Cache: Received #38 after  25ms ("doc1" / "3-a7947267ceb469e999e9141062592e2f685a3bf0")
2019-05-10T18:41:41.306Z [DBG] Cache+: Pruned 0 entries from channel "*"
2019-05-10T18:41:41.306Z [INF] Cache: #38 ==> channels {*}
2019-05-10T18:41:41.306Z [DBG] Changes+: Notifying that "travel-sample" changed (keys="{*}") count=10
2019-05-10T18:41:41.306Z [DBG] Changes+: c:[35d219ee] MultiChangesFeed: channels expand to "!:1,*:1" ...   (to admin)
2019-05-10T18:41:41.306Z [INF] Cache: c:[35d219ee] getCachedChanges("!", 37) --> 0 changes valid from #1
2019-05-10T18:41:41.306Z [DBG] Changes+: c:[35d219ee] [changesFeed] Found 0 changes for channel !
2019-05-10T18:41:41.306Z [INF] Cache: c:[35d219ee] getCachedChanges("*", 37) --> 1 changes valid from #36
2019-05-10T18:41:41.306Z [DBG] Changes+: c:[35d219ee] [changesFeed] Found 1 changes for channel *
2019-05-10T18:41:41.306Z [DBG] Changes+: c:[35d219ee] Channel feed processing seq:38 in channel *   (to admin)
2019-05-10T18:41:41.306Z [DBG] Changes+: c:[35d219ee] MultiChangesFeed sending {Seq:38, ID:doc1, Changes:[map[rev:3-a7947267ceb469e999e9141062592e2f685a3bf0]]}   (to admin)
2019-05-10T18:41:41.306Z [DBG] Changes+: c:[35d219ee] MultiChangesFeed waiting...   (to admin)
2019-05-10T18:41:41.306Z [DBG] Sync+: c:[35d219ee]     Sending 1 changes. User:admin
2019-05-10T18:41:41.306Z [DBG] WSFrame+: c:[35d219ee] Queued MSG#2~
2019-05-10T18:41:41.306Z [DBG] WS+: c:[35d219ee] Push MSG#2~
2019-05-10T18:41:41.306Z [DBG] WS+: c:[35d219ee] Sending frame: MSG#2~ (flags=    1000, size=  105)
2019-05-10T18:41:41.306Z [DBG] Changes+: No new changes to send to change listener.  Waiting for "travel-sample"'s count to pass 10
2019-05-10T18:41:41.307Z [DBG] WS+: c:[35d219ee] Received frame: RPY#2~ (flags=    1001, length=24)
2019-05-10T18:41:41.307Z [DBG] WS+: c:[35d219ee] Received frame: MSG#6 (flags=       0, length=93)
2019-05-10T18:41:41.307Z [DBG] WSFrame+: c:[35d219ee] Incoming BLIP Request: MSG#6
2019-05-10T18:41:41.307Z [INF] SyncMsg: c:[35d219ee] #6: Type:setCheckpoint Client:cp-sadLH/fPXDyG6EXNuTUbUjD20jM= Rev:0-4  User:admin
2019-05-10T18:41:41.308Z [INF] Sync: c:[35d219ee] Sent 1 changes to client, from seq 38.  User:admin
2019-05-10T18:41:41.308Z [DBG] Sync+: c:[35d219ee]     Sending 0 changes. User:admin
2019-05-10T18:41:41.325Z [DBG] SyncMsg+: c:[35d219ee] #6: Type:setCheckpoint   --> OK Time:17.365ms User:admin 
2019-05-10T18:41:41.325Z [DBG] WS+: c:[35d219ee] Push RPY#6
2019-05-10T18:41:41.325Z [DBG] WS+: c:[35d219ee] Sending frame: RPY#6 (flags=       1, size=    9)
2019-05-10T18:42:00.276Z [DBG] Cache+: Pruned 0 old entries from channel "*"
2019-05-10T18:42:00.276Z [DBG] Cache+: Pruned 0 old entries from channel "!"
2019-05-10T18:42:00.276Z [DBG] Cache+: Pruned 0 old entries from channel "channel.tester"