Following is the log of sync-gateway when a doc is created, further doc is assigned to a channel and replicated among the other users who is having channel access but we feel its slow.
Can anyone please help me out to debug through log , specifically what is the meaning of this particular message “Changes+: Waiting for “letschat-dev”'s count to pass 2”
Above message is appearing so many times in the log snippet when a doc is created and synced,
2016-01-22T12:42:33.061Z HTTP: #010: PUT /my_test_config/_local/e2d4a58452400f4be4a92decf8a76ae687de988b
2016-01-22T12:42:33.061Z HTTP+: #010: --> 201 (0.6 ms)
2016-01-22T12:42:33.189Z HTTP: #011: POST /my_test_config/_revs_diff
2016-01-22T12:42:33.192Z HTTP+: #011: --> 200 (2.9 ms)
2016-01-22T12:42:33.407Z HTTP: #012: POST /my_test_config/_bulk_docs
2016-01-22T12:42:33.407Z CRUD+: Invoking sync on doc “250fac22-6931-43ae-9c54-4fa31e5f0d69” rev 1-246683d59eb198415fe8ab95b170596b
2016-01-22T12:42:33.485Z CRUD: Doc “250fac22-6931-43ae-9c54-4fa31e5f0d69” in channels “{9c323967-29d0-4cf8-9b18-f73a32d95ca7}”
2016-01-22T12:42:33.486Z Cache: SAVING #213
2016-01-22T12:42:33.486Z CRUD: Stored doc “250fac22-6931-43ae-9c54-4fa31e5f0d69” / “1-246683d59eb198415fe8ab95b170596b”
2016-01-22T12:42:33.487Z HTTP+: #012: --> 201 (80.1 ms)
2016-01-22T12:42:33.487Z Events+: Event queue worker sending event Document change event for doc id: 250fac22-6931-43ae-9c54-4fa31e5f0d69 to: Webhook handler [http://localhost:8080/formhandler]
2016-01-22T12:42:34.188Z Cache: Received #213 after 702ms (“250fac22-6931-43ae-9c54-4fa31e5f0d69” / “1-246683d59eb198415fe8ab95b170596b”)
2016-01-22T12:42:34.188Z Cache: #213 ==> channel “9c323967-29d0-4cf8-9b18-f73a32d95ca7"
2016-01-22T12:42:34.188Z Cache: #213 ==> channel ““
2016-01-22T12:42:34.188Z Changes+: Notifying that “letschat-dev” changed (keys=”{, 9c323967-29d0-4cf8-9b18-f73a32d95ca7}”) count=2
2016-01-22T12:42:34.188Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{”":0x1, “!”:0x1} …
2016-01-22T12:42:34.188Z Cache: getCachedChanges("", {0 0 212}) --> 1 changes valid from #213
2016-01-22T12:42:34.188Z Cache: getCachedChanges("!", {0 0 212}) --> 0 changes valid from #207
2016-01-22T12:42:34.188Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"!":0x1, “":0x1} …
2016-01-22T12:42:34.188Z Cache: getCachedChanges("!", {0 0 212}) --> 0 changes valid from #207
2016-01-22T12:42:34.188Z Cache: getCachedChanges("”, {0 0 212}) --> 1 changes valid from #213
2016-01-22T12:42:34.188Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"":0x1, “!”:0x1} …
2016-01-22T12:42:34.188Z Cache: getCachedChanges("", {0 0 212}) --> 1 changes valid from #213
2016-01-22T12:42:34.188Z Cache: getCachedChanges("!", {0 0 212}) --> 0 changes valid from #207
2016-01-22T12:42:34.188Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"":0x1, “!”:0x1} …
2016-01-22T12:42:34.188Z Cache: getCachedChanges("!", {0 0 212}) --> 0 changes valid from #207
2016-01-22T12:42:34.189Z Cache: getCachedChanges("", {0 0 212}) --> 1 changes valid from #213
2016-01-22T12:42:34.189Z Changes+: MultiChangesFeed sending &{Seq:213 ID:250fac22-6931-43ae-9c54-4fa31e5f0d69 Deleted:false Removed:{} Doc:map[] Changes:[map[rev:1-246683d59eb198415fe8ab95b170596b]] Err: branched:false}
2016-01-22T12:42:34.189Z Changes: MultiChangesFeed done
2016-01-22T12:42:34.189Z Changes+: MultiChangesFeed sending &{Seq:213 ID:250fac22-6931-43ae-9c54-4fa31e5f0d69 Deleted:false Removed:{} Doc:map[] Changes:[map[rev:1-246683d59eb198415fe8ab95b170596b]] Err: branched:false}
2016-01-22T12:42:34.189Z Changes: MultiChangesFeed done
2016-01-22T12:42:34.189Z Changes+: MultiChangesFeed sending &{Seq:213 ID:250fac22-6931-43ae-9c54-4fa31e5f0d69 Deleted:false Removed:{} Doc:map[] Changes:[map[rev:1-246683d59eb198415fe8ab95b170596b]] Err: branched:false}
2016-01-22T12:42:34.189Z Changes: MultiChangesFeed done
2016-01-22T12:42:34.189Z Changes+: MultiChangesFeed sending &{Seq:213 ID:250fac22-6931-43ae-9c54-4fa31e5f0d69 Deleted:false Removed:{} Doc:map[] Changes:[map[rev:1-246683d59eb198415fe8ab95b170596b]] Err: branched:false}
2016-01-22T12:42:34.189Z Changes: MultiChangesFeed done
2016-01-22T12:42:34.189Z HTTP+: #001: --> 200 OK (0.0 ms)
2016-01-22T12:42:34.189Z HTTP+: #003: --> 200 OK (0.0 ms)
2016-01-22T12:42:34.189Z HTTP+: #004: --> 200 OK (0.0 ms)
2016-01-22T12:42:34.189Z HTTP+: #008: --> 200 OK (0.0 ms)
2016-01-22T12:42:34.519Z HTTP: #013: POST /my_test_config/_changes
2016-01-22T12:42:34.519Z Changes: MultiChangesFeed({}, {Since:213 Limit:50 Conflicts:true IncludeDocs:false Wait:true Continuous:false Terminator:0xc2087ff800 HeartbeatMs:300000 TimeoutMs:300000}) …
2016-01-22T12:42:34.519Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"!":0x1, "":0x1} …
2016-01-22T12:42:34.519Z Cache: getCachedChanges("!", {0 0 213}) --> 0 changes valid from #207
2016-01-22T12:42:34.519Z Changes+: MultiChangesFeed waiting…
2016-01-22T12:42:34.519Z Changes+: Waiting for “letschat-dev”'s count to pass 2
2016-01-22T12:42:35.515Z HTTP: #014: POST /my_test_config/_changes
2016-01-22T12:42:35.515Z Changes: MultiChangesFeed({}, {Since:213 Limit:50 Conflicts:true IncludeDocs:false Wait:true Continuous:false Terminator:0xc20873b560 HeartbeatMs:300000 TimeoutMs:300000}) …
2016-01-22T12:42:35.515Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"":0x1, “!”:0x1} …
2016-01-22T12:42:35.515Z Cache: getCachedChanges("!", {0 0 213}) --> 0 changes valid from #207
2016-01-22T12:42:35.515Z Changes+: MultiChangesFeed waiting…
2016-01-22T12:42:35.515Z Changes+: Waiting for “letschat-dev”'s count to pass 2
2016-01-22T12:42:35.574Z HTTP: #015: POST /my_test_config/_changes
2016-01-22T12:42:35.574Z Changes: MultiChangesFeed({}, {Since:213 Limit:50 Conflicts:true IncludeDocs:false Wait:true Continuous:false Terminator:0xc20873b7a0 HeartbeatMs:300000 TimeoutMs:300000}) …
2016-01-22T12:42:35.575Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"!":0x1, "":0x1} …
2016-01-22T12:42:35.575Z Cache: getCachedChanges("!", {0 0 213}) --> 0 changes valid from #207
2016-01-22T12:42:35.575Z Changes+: MultiChangesFeed waiting…
2016-01-22T12:42:35.575Z Changes+: Waiting for “letschat-dev”'s count to pass 2
2016-01-22T12:42:35.830Z HTTP: #016: GET /my_test_config/250fac22-6931-43ae-9c54-4fa31e5f0d69?rev=1-246683d59eb198415fe8ab95b170596b&revs=true&attachments=true
2016-01-22T12:42:35.830Z HTTP+: #016: --> 200 (0.3 ms)
2016-01-22T12:42:36.266Z HTTP: #017: GET /my_test_config/250fac22-6931-43ae-9c54-4fa31e5f0d69?rev=1-246683d59eb198415fe8ab95b170596b&revs=true&attachments=true
2016-01-22T12:42:36.266Z HTTP+: #017: --> 200 (0.3 ms)
2016-01-22T12:42:36.949Z HTTP: #018: POST /my_test_config/_changes
2016-01-22T12:42:36.949Z Changes: MultiChangesFeed({}, {Since:213 Limit:50 Conflicts:true IncludeDocs:false Wait:true Continuous:false Terminator:0xc20873be00 HeartbeatMs:300000 TimeoutMs:300000}) …
2016-01-22T12:42:36.949Z Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"!":0x1, "":0x1} …
2016-01-22T12:42:36.949Z Cache: getCachedChanges("!", {0 0 213}) --> 0 changes valid from #207
2016-01-22T12:42:36.949Z Changes+: MultiChangesFeed waiting…
2016-01-22T12:42:36.949Z Changes+: Waiting for “letschat-dev”'s count to pass 2
2016-01-22T12:42:38.787Z HTTP: #019: PUT /my_test_config/_local/8c3ec2c7a6128d974e5c54fe8834744f4b1b5c95
2016-01-22T12:42:38.787Z HTTP+: #019: --> 201 (0.6 ms)
2016-01-22T12:42:39.675Z HTTP: #020: PUT /my_test_config/_local/097c1863c387639e4b06eab5a8ca8f2906d35fe6
2016-01-22T12:42:39.675Z HTTP+: #020: --> 201 (0.6 ms)
2016-01-22T12:42:42.227Z HTTP: #021: GET /my_test_config/250fac22-6931-43ae-9c54-4fa31e5f0d69?rev=1-246683d59eb198415fe8ab95b170596b&revs=true&attachments=true
2016-01-22T12:42:42.227Z HTTP+: #021: --> 200 (0.3 ms)
2016-01-22T12:42:43.858Z HTTP: #022: PUT /my_test_config/_local/37208ce571ae60ab786c8cf08a00f201576318a1
2016-01-22T12:42:43.858Z HTTP+: #022: --> 201 (0.6 ms)
2016-01-22T12:42:44.271Z HTTP: #023: PUT /my_test_config/_local/4426220c6aa0e2214863c66be1294b116ea1be6e
2016-01-22T12:42:44.273Z HTTP+: #023: --> 201 (1.7 ms)