Hi there,
got a serious replication issue with changing *one* document in couchbase
lite iOS within a short time (below 3sec). I think it´s a sync_gateway
cache issue. If I do fast changes on different documents everything works
fine. If I do the same with one document sync_gateway is caching the
changes and will start replication after around 80sec which is not realtime
enough for my application. I have the feeling that this was much better in
previous versions of sg, maybe before 1.0.3. Thanks again for your help.
wish you all the best. Please find attached my sg log.
best
dominik
document changes within 2 seconds from couchbase lite ios
21:05:44.272012 HTTP: #110: POST /whwgroup_sg/_revs_diff (as
PfY7eAcPnUj7Gwnfo9)
21:05:44.275717 HTTP+: #110: --> 200 (4.5 ms)
21:05:44.317182 HTTP: #111: POST /whwgroup_sg/_bulk_docs (as
PfY7eAcPnUj7Gwnfo9)
21:05:44.320315 CRUD+: Invoking sync on doc
"59238463-B4DD-48CB-A607-D3701DBA794F" rev
36-f203f0c8163c9bdb1fdf9c3d7dc613df
21:05:44.321101 CRUD+: Saving old revision
"59238463-B4DD-48CB-A607-D3701DBA794F" /
"35-2677e936a89b439fb1173339c6c5d28e" (305 bytes)
21:05:44.321468 CRUD+: Backed up obsolete rev
"59238463-B4DD-48CB-A607-D3701DBA794F"/"35-2677e936a89b439fb1173339c6c5d28e"
21:05:44.322515 Cache: SAVING #11297
21:05:44.323521 CRUD: Stored doc "59238463-B4DD-48CB-A607-D3701DBA794F" /
"36-f203f0c8163c9bdb1fdf9c3d7dc613df"
21:05:44.323600 HTTP+: #111: --> 201 (7.2 ms)
21:05:44.741974 HTTP: #112: POST /whwgroup_sg/_revs_diff (as
PfY7eAcPnUj7Gwnfo9)
21:05:44.745658 HTTP+: #112: --> 200 (4.5 ms)
21:05:44.826215 HTTP: #113: POST /whwgroup_sg/_bulk_docs (as
PfY7eAcPnUj7Gwnfo9)
21:05:44.829333 CRUD+: Invoking sync on doc
"59238463-B4DD-48CB-A607-D3701DBA794F" rev
37-211b94bddd4dbb44d3918a1eda6d8ccf
21:05:44.829519 CRUD+: Saving old revision
"59238463-B4DD-48CB-A607-D3701DBA794F" /
"36-f203f0c8163c9bdb1fdf9c3d7dc613df" (304 bytes)
21:05:44.829624 CRUD+: Backed up obsolete rev
"59238463-B4DD-48CB-A607-D3701DBA794F"/"36-f203f0c8163c9bdb1fdf9c3d7dc613df"
21:05:44.829780 Cache: SAVING #11298
21:05:44.830011 CRUD: Stored doc "59238463-B4DD-48CB-A607-D3701DBA794F" /
"37-211b94bddd4dbb44d3918a1eda6d8ccf"
21:05:44.830028 HTTP+: #113: --> 201 (9.4 ms)
21:05:45.691936 Cache: Received #11298 after 862ms
("59238463-B4DD-48CB-A607-D3701DBA794F" /
"37-211b94bddd4dbb44d3918a1eda6d8ccf")
21:05:45.691950 Cache: Deferring #11298 (1 now waiting for
#11297...#11297)
21:05:45.692385 Shadow: Pushing "59238463-B4DD-48CB-A607-D3701DBA794F", rev
"37-211b94bddd4dbb44d3918a1eda6d8ccf"
21:05:46.701115 Shadow+: Pulling "59238463-B4DD-48CB-A607-D3701DBA794F",
CAS=7bbe4225486027 ... have
UpstreamRev="35-2677e936a89b439fb1173339c6c5d28e", UpstreamCAS=c210233490
21:05:46.701142 Shadow+: Not pulling
"59238463-B4DD-48CB-A607-D3701DBA794F", CAS=7bbe4225486027 (echo of rev
"37-211b94bddd4dbb44d3918a1eda6d8ccf")
21:05:46.701185 CRUD+: Invoking sync on doc
"59238463-B4DD-48CB-A607-D3701DBA794F" rev
37-211b94bddd4dbb44d3918a1eda6d8ccf
21:05:46.701301 CRUD+: Got old revision
"59238463-B4DD-48CB-A607-D3701DBA794F" /
"36-f203f0c8163c9bdb1fdf9c3d7dc613df" --> 304 bytes
21:05:46.701530 CRUD+: updateDoc("59238463-B4DD-48CB-A607-D3701DBA794F"):
Rev "37-211b94bddd4dbb44d3918a1eda6d8ccf" leaves
"37-211b94bddd4dbb44d3918a1eda6d8ccf" still current
21:05:46.701674 Cache: SAVING #11299
21:05:46.701863 CRUD: Stored doc "59238463-B4DD-48CB-A607-D3701DBA794F" /
"37-211b94bddd4dbb44d3918a1eda6d8ccf"
21:05:47.669161 Cache: Received #11299 after 967ms
("59238463-B4DD-48CB-A607-D3701DBA794F" /
"37-211b94bddd4dbb44d3918a1eda6d8ccf")
21:05:47.669171 Cache: Deferring #11299 (2 now waiting for
#11297...#11297)
21:05:49.385009 HTTP: #114: PUT
/whwgroup_sg/_local/a229f2c95375a826f7b04140ea38d1b1ad307a6d (as
PfY7eAcPnUj7Gwnfo9)
21:05:49.385207 HTTP+: #114: --> 201 (0.4 ms)
21:05:56.928506 HTTP: #115: GET /
21:05:56.928542 HTTP+: #115: --> 200 (0.0 ms)
cached replication after around 80 seconds.
21:06:13.523228 HTTP: #116: GET /
21:06:13.523259 HTTP+: #116: --> 200 (0.0 ms)
21:06:21.996337 HTTP: #117: GET /
21:06:21.996367 HTTP+: #117: --> 200 (0.0 ms)
21:06:24.303221 HTTP: #118: GET /
21:06:24.303249 HTTP+: #118: --> 200 (0.0 ms)
21:06:25.172388 HTTP: #119: GET /
21:06:25.172433 HTTP+: #119: --> 200 (0.1 ms)
21:07:02.354625 WARNING: changeCache: Giving up, accepting #11298 even
though #11297 is missing -- db.(*changeCache)._addPendingLogs() at
change_cache.go:320
21:07:02.354686 Cache: #11298 ==> channel "whw_news"
21:07:02.354708 Cache: #11298 ==> channel "*"
21:07:02.354752 Cache: #11299 ==> channel "whw_news"
21:07:02.354769 Cache: #11299 ==> channel "*"
21:07:02.354837 Changes+: Notifying that "whwgroup_sg" changed (keys="{*,
whw_news}") count=3
21:07:02.355019 Changes+: MultiChangesFeed: channels expand to
channels.TimedSet{"whw_news":0x1} ... (to PfY7eAcPnUj7Gwnfo9)
21:07:02.355030 Changes+: MultiChangesFeed: channels expand to
channels.TimedSet{"whw_news":0x1} ... (to PfY7eAcPnUj7Gwnfo9)
21:07:02.355030 Changes+: MultiChangesFeed: channels expand to
channels.TimedSet{"whw_news":0x1} ... (to PfY7eAcPnUj7Gwnfo9)
21:07:02.355147 Cache: getCachedChanges("whw_news", {0 11296}) --> 1
changes valid from #11286
21:07:02.355085 Cache: getCachedChanges("whw_news", {0 11296}) --> 1
changes valid from #11286
21:07:02.355220 Cache: getCachedChanges("whw_news", {0 11296}) --> 1
changes valid from #11286
21:07:02.358133 Changes+: MultiChangesFeed sending &{Seq:11299
ID:59238463-B4DD-48CB-A607-D3701DBA794F Deleted:false Removed:{} Doc:map[]
Changes:[map[rev:37-211b94bddd4dbb44d3918a1eda6d8ccf]
map[rev:27-97424609e18e1df04b6d725b9920651d]
map[rev:16-e4fa6c34f21b165556e951b08f54b5ed]] branched:true} (to
PfY7eAcPnUj7Gwnfo9)
21:07:02.358177 Changes: MultiChangesFeed done (to PfY7eAcPnUj7Gwnfo9)
21:07:02.358272 HTTP+: #099: --> 200 OK (0.0 ms)
21:07:02.358367 Changes+: MultiChangesFeed sending &{Seq:11299
ID:59238463-B4DD-48CB-A607-D3701DBA794F Deleted:false Removed:{} Doc:map[]
Changes:[map[rev:37-211b94bddd4dbb44d3918a1eda6d8ccf]
map[rev:27-97424609e18e1df04b6d725b9920651d]
map[rev:16-e4fa6c34f21b165556e951b08f54b5ed]] branched:true} (to
PfY7eAcPnUj7Gwnfo9)
21:07:02.358409 Changes: MultiChangesFeed done (to PfY7eAcPnUj7Gwnfo9)
21:07:02.358496 HTTP+: #103: --> 200 OK (0.0 ms)
21:07:02.358607 Changes+: MultiChangesFeed sending &{Seq:11299
ID:59238463-B4DD-48CB-A607-D3701DBA794F Deleted:false Removed:{} Doc:map[]
Changes:[map[rev:37-211b94bddd4dbb44d3918a1eda6d8ccf]
map[rev:27-97424609e18e1df04b6d725b9920651d]
map[rev:16-e4fa6c34f21b165556e951b08f54b5ed]] branched:true} (to
PfY7eAcPnUj7Gwnfo9)
21:07:02.358659 Changes: MultiChangesFeed done (to PfY7eAcPnUj7Gwnfo9)
21:07:02.358736 HTTP+: #100: --> 200 OK (0.0 ms)
21:07:02.505633 HTTP: #120: GET
/whwgroup_sg/59238463-B4DD-48CB-A607-D3701DBA794F?rev=37-211b94bddd4dbb44d3918a1eda6d8ccf&revs=true&attachments=true
(as PfY7eAcPnUj7Gwnfo9)
21:07:02.505953 HTTP+: #120: --> 200 (0.5 ms)
21:07:02.510610 HTTP: #121: POST /whwgroup_sg/_changes (as
PfY7eAcPnUj7Gwnfo9)
21:07:02.510655 Changes: MultiChangesFeed({whw_news}, {Since:11299 Limit:0
Conflicts:true IncludeDocs:false Wait:true Continuous:false
Terminator:0xc2105f8840}) ... (to PfY7eAcPnUj7Gwnfo9)
21:07:02.510737 Changes+: MultiChangesFeed: channels expand to
channels.TimedSet{"whw_news":0x1} ... (to PfY7eAcPnUj7Gwnfo9)
21:07:02.510755 Changes+: MultiChangesFeed waiting... (to
PfY7eAcPnUj7Gwnfo9)
21:07:02.510759 Changes+: Waiting for "whwgroup_sg"'s count to pass 3
21:07:02.785815 HTTP: #122: POST /whwgroup_sg/_changes (as
PfY7eAcPnUj7Gwnfo9)
21:07:02.785855 Changes: MultiChangesFeed({whw_news}, {Since:11299 Limit:0
Conflicts:true IncludeDocs:false Wait:true Continuous:false
Terminator:0xc2102e5c00}) ... (to PfY7eAcPnUj7Gwnfo9)
21:07:02.785983 Changes+: MultiChangesFeed: channels expand to
channels.TimedSet{"whw_news":0x1} ... (to PfY7eAcPnUj7Gwnfo9)
21:07:02.785990 Changes+: MultiChangesFeed waiting... (to
PfY7eAcPnUj7Gwnfo9)
21:07:02.785995 Changes+: Waiting for "whwgroup_sg"'s count to pass 3
21:07:04.426567 HTTP: #123: POST /whwgroup_sg/_changes (as
PfY7eAcPnUj7Gwnfo9)
21:07:04.426645 Changes: MultiChangesFeed({whw_news}, {Since:11299 Limit:0
Conflicts:true IncludeDocs:false Wait:true Continuous:false
Terminator:0xc2109d5e40}) ... (to PfY7eAcPnUj7Gwnfo9)
21:07:04.426839 Changes+: MultiChangesFeed: channels expand to
channels.TimedSet{"whw_news":0x1} ... (to PfY7eAcPnUj7Gwnfo9)
21:07:04.426853 Changes+: MultiChangesFeed waiting... (to
PfY7eAcPnUj7Gwnfo9)
21:07:04.426863 Changes+: Waiting for "whwgroup_sg"'s count to pass 3
21:07:04.434544 HTTP: #124: GET
/whwgroup_sg/59238463-B4DD-48CB-A607-D3701DBA794F?rev=37-211b94bddd4dbb44d3918a1eda6d8ccf&revs=true&attachments=true
(as PfY7eAcPnUj7Gwnfo9)
21:07:04.435124 HTTP+: #124: --> 200 (0.9 ms)
21:07:07.546798 HTTP: #125: PUT
/whwgroup_sg/_local/70107ccc88c7c12c91393eb729783ea600649dbc (as
PfY7eAcPnUj7Gwnfo9)
21:07:07.547625 HTTP+: #125: --> 201 (1.6 ms)
21:07:07.699151 HTTP: #126: PUT
/whwgroup_sg/_local/2df45004d9ca2371137005997333d6ffdef07650 (as
PfY7eAcPnUj7Gwnfo9)
21:07:07.699904 HTTP+: #126: --> 201 (1.5 ms)
21:07:09.853778 HTTP: #127: PUT
/whwgroup_sg/_local/21e4877fb354bf25314e04780f5e2f7034389ce0 (as
PfY7eAcPnUj7Gwnfo9)
21:07:09.853971 HTTP+: #127: --> 201 (0.4 ms)
21:07:13.694054 HTTP: #128: GET /
21:07:13.694158 HTTP+: #128: --> 200 (0.1 ms)
--
You received this message because you are subscribed to the Google Groups
"Couchbase Mobile" group.
To unsubscribe from this group and stop receiving emails from it, send an email
to [email protected].
To view this discussion on the web visit
https://groups.google.com/d/msgid/mobile-couchbase/f4d4ed26-eac4-4f65-b2ae-e5483f103774%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
