SG log with empty channels: [INF] CRUD: Doc "doc_id / "rev_id(?)" in channels "{}"

Mobile Sync Gateway
1

I’m trying to understand the following logs. Here’s an example:

[INF] CRUD: 	Doc "doc_id / "rev_id(?)" in channels "{}"

Does this mean this document has no channel? My data model always requires a channel for every document. Could this be a delete statement of a document and hence it has no channel?

Here’s a longer excerpt with user id 4b6bae75-1778-4b0b-b56d-5b213b2daae3

2019-11-20T21:57:11.296+01:00 [INF] CRUD: 	Doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::e5961b0baa36" / "101-5a39e9555ed93fcd82031aea42c0c503b12dabee" in channels "{}"
2019-11-20T21:57:11.300+01:00 [INF] Changes: c:[4378fbc4] 148382919 is the oldest skipped sequence, using stable sequence number of 148382918 for this feed   (to 4b6bae75-1778-4b0b-b56d-5b213b2daae3)
2019-11-20T21:57:11.300+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("!", 148336894) --> 0 changes valid from #1
2019-11-20T21:57:11.300+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("4b6bae75-1778-4b0b-b56d-5b213b2daae3", 148336894) --> 1 changes valid from #148336895
2019-11-20T21:57:11.333+01:00 [INF] CRUD: 	Doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::ed5c1c951179" / "35-f76012a9c8a2b9cbc56f831a5d5623feb14a1c2b" in channels "{}"
2019-11-20T21:57:11.333+01:00 [INF] CRUD: 	Doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::db854236399e" / "51-5f9a76c223ed05719d578f94ae82815c042e40af" in channels "{}"
2019-11-20T21:57:11.334+01:00 [INF] CRUD: 	Doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::926ad9c36038" / "6-15bae51bae4005b238aeab63153ed92f40137e33" in channels "{}"
2019-11-20T21:57:11.334+01:00 [INF] CRUD: 	Doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::2ebf306e09d8" / "7-a1a4d37595673c2f9357fcb5b9b19547e7e3a864" in channels "{}"
2019-11-20T21:57:11.334+01:00 [INF] CRUD: 	Doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::7b8dd9608091" / "22-113a5ec01bccab38fcc886b0cc9e9a668d209ab7" in channels "{}"
2019-11-20T21:57:11.334+01:00 [INF] CRUD: 	Doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::4ff31dd304f9" / "30-695146e8476bb5a2ea924ce2d5192dea9fc169f4" in channels "{}"
2019-11-20T21:57:11.334+01:00 [INF] Cache:   Deferring #148390249 (1 now waiting for #148390248...#148390248) doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::db854236399e" / "51-5f9a76c223ed05719d578f94ae82815c042e40af"
2019-11-20T21:57:11.335+01:00 [INF] Changes: c:[4378fbc4] 148382919 is the oldest skipped sequence, using stable sequence number of 148382918 for this feed   (to 4b6bae75-1778-4b0b-b56d-5b213b2daae3)
2019-11-20T21:57:11.335+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("!", 148390247) --> 0 changes valid from #1
2019-11-20T21:57:11.335+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("4b6bae75-1778-4b0b-b56d-5b213b2daae3", 148390247) --> 3 changes valid from #148390248
2019-11-20T21:57:11.335+01:00 [INF] Cache:   Deferring #148390253 (1 now waiting for #148390251...#148390252) doc "4b6bae75-1778-4b0b-b56d-5b213b2daae3::shoppingListItem::k2qh9t4rq8wt::4ff31dd304f9" / "30-695146e8476bb5a2ea924ce2d5192dea9fc169f4"
2019-11-20T21:57:11.336+01:00 [INF] Changes: c:[4378fbc4] 148382919 is the oldest skipped sequence, using stable sequence number of 148382918 for this feed   (to 4b6bae75-1778-4b0b-b56d-5b213b2daae3)
2019-11-20T21:57:11.336+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("!", 148390250) --> 0 changes valid from #1
2019-11-20T21:57:11.336+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("4b6bae75-1778-4b0b-b56d-5b213b2daae3", 148390250) --> 2 changes valid from #148390251
2019-11-20T21:57:11.365+01:00 [INF] SyncMsg: c:[4378fbc4] #13: Type:setCheckpoint Client:cp-QcNL5yPdvWqOdI6PdbMv8F8qNLA= Rev:0-200 
2019-11-20T21:57:11.365+01:00 [INF] Sync: c:[4378fbc4] Sent 1 changes to client, from seq 148382918::148390247
2019-11-20T21:57:11.399+01:00 [INF] Sync: c:[4378fbc4] Sent 3 changes to client, from seq 148382918::148390248
2019-11-20T21:57:11.430+01:00 [INF] SyncMsg: c:[4378fbc4] #14: Type:setCheckpoint Client:cp-QcNL5yPdvWqOdI6PdbMv8F8qNLA= Rev:0-201 
2019-11-20T21:57:11.436+01:00 [INF] Changes: c:[4378fbc4] 148382919 is the oldest skipped sequence, using stable sequence number of 148382918 for this feed   (to 4b6bae75-1778-4b0b-b56d-5b213b2daae3)
2019-11-20T21:57:11.436+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("!", 148390251) --> 0 changes valid from #1
2019-11-20T21:57:11.436+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("4b6bae75-1778-4b0b-b56d-5b213b2daae3", 148390251) --> 2 changes valid from #148390252
2019-11-20T21:57:11.436+01:00 [INF] Changes: c:[4378fbc4] 148382919 is the oldest skipped sequence, using stable sequence number of 148382918 for this feed   (to 4b6bae75-1778-4b0b-b56d-5b213b2daae3)
2019-11-20T21:57:11.436+01:00 [INF] Cache: c:[4378fbc4] GetCachedChanges("!", 148390253) --> 0 changes valid from #1
2019-11-20T21:57:11.463+01:00 [INF] Sync: c:[4378fbc4] Sent 1 changes to client, from seq 148382918::148390251

Here’s an excerpt with a channel:

2019-11-20T22:22:42.016+01:00 [INF] CRUD:   Doc "a48f8ebf-4c62-4616-b362-df995e2eb02c::shoppingListItem::59nzmpq6z2os::e2ecd7b9feef" / "7-cd33830148a970c4729e91111f203a00d75b7121" in channels "{a48f8ebf-4c62-4616-b362-df995e2eb02c}"

I had a user report that under no circumstances specific documents do not synchronize between clients. Other documents do synchronize. So I wonder if it has something to do with the " in channels "{}"

Setup: SG 2.6

2

I checked on my dev cluster and can confirm that " in channels "{}" appears every time a document was deleted by CBL 2.x on Android. Here’s a code snippet of the Android client:

database.delete(document);

As some documents refuse to sync but others do sync I think my issue is similar to one of my past issue which was already solved. Here’s the solution. Grepping the sg logs I didn’t find similar logs as I saw in my other forum post. I’ll keep digging!