Uploaded image for project: 'Couchbase Gateway'
  1. Couchbase Gateway
  2. CBG-3397

[3.1.2 Backport] SG warning when client's maxHistory for a rev is exceeded on push

    XMLWordPrintable

Details

    • Bug
    • Resolution: Fixed
    • Major
    • 3.1.2
    • None
    • SyncGateway
    • Security Level: Public
    • None
    • CBG Sprint 135
    • 1

    Description

      It's quite easy to get CBL to build a rev tree for a document which results in SG emitting warnings in the logs for "Found gap in revision list. Expecting gen 6 but got 5 in [...]". This warning actually doesn't only happen on the push, and is continually logged for every missing revision generation every time the affected document is marshalled until SG is able to prune the affected gap away.

      This happens on the client side when a document the server knows about is mutated enough times for the revision tree history to be truncated between the last known server rev and the most recent N revs on the client. Currently this setting determines a maxHistory of 20 revisions, but I believe it should be client-side configurable.

       

      Related user reported issue (for the old warning message):

      Setup

      • CBL / SG Version: Android EE 2.8.6 / 3.0.0-392
      • SG Config: Default database config

      Repro steps

      1. Start CBL and SG with a continuous bidirectional replication
      2. Create a doc (at rev 1) on the client and have it pushed to SG
      3. Stop the CBL replicator
      4. Mutate the document on the client enough to have a gap larger than 20 (rev 22)
      5. Start the CBL replicator again and let rev 22 be synced

      Actual Result:

      • Observe "Found gap" warning in SG logs

      Expected Result:

      • Don't see a warning for this expected behaviour

      Logs :

      Step 2

      2021-09-01T14:30:02.511+01:00 [DBG] WS+: c:#002 Incoming BLIP Request: MSG#4!~
      		 
      2021-09-01T14:30:02.511+01:00 [TRC] SyncMsg+: c:[544756b5] Recv Req MSG#4!~: Body: '<ud>[["foo","1-341d67be2777de6b545ccf6e3cea5bcf200089e4","",20]]</ud>' Properties: <ud>map[Profile:proposeChanges]</ud>
      		 
      2021-09-01T14:30:02.512+01:00 [INF] SyncMsg: c:[544756b5] #4: Type:proposeChanges #Changes: 1
      		 
      2021-09-01T14:30:02.512+01:00 [DBG] CRUD+: No document body found for key=<ud>foo</ud>, xattrKey=<ud>_sync</ud>: sub-document error at index 1: path not found | {"status_code":211,"document_key":"foo","bucket":"db1","scope":"_default","collection":"_default","error_name":"SUBDOC_MULTI_PATH_FAILURE_DELETED","error_description":"Subdoc: One or more paths in a multi-path command failed on a deleted document","opaque":14,"last_dispatched_to":"localhost:11210","last_dispatched_from":"[::1]:55173","last_connection_id":"d6757a0d76c81d1d/b8e661699a9ff4ba"}
      		 
      2021-09-01T14:30:02.512+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetWithXattr([ <ud>foo</ud> <ud>_sync</ud> <ud></ud> ]) [478.008µs]
      		 
      2021-09-01T14:30:02.512+01:00 [DBG] SyncMsg+: c:[544756b5] #4: Type:proposeChanges   --> OK Time:729.721µs
      		 
      2021-09-01T14:30:02.512+01:00 [TRC] SyncMsg+: c:[544756b5] Recv Rsp RPY#4!~: Body: '<ud>[]</ud>' Properties: <ud>map[]</ud>
      		 
      2021-09-01T14:30:02.512+01:00 [DBG] WSFrame+: c:#002 Push RPY#4!~
      		 
      2021-09-01T14:30:02.512+01:00 [DBG] WSFrame+: c:#002 Sending frame: RPY#4!~ (flags=   11001, size=    3)
      		 
      2021-09-01T14:30:02.525+01:00 [DBG] WSFrame+: c:#002 Received frame: MSG#5~ (flags=    1000, length=85)
      		 
      2021-09-01T14:30:02.525+01:00 [DBG] WS+: c:#002 Incoming BLIP Request: MSG#5~
      		 
      2021-09-01T14:30:02.525+01:00 [TRC] SyncMsg+: c:[544756b5] Recv Req MSG#5~: Body: '<ud>{"channels":[],"created_at":1630503002590}</ud>' Properties: <ud>map[Profile:rev id:foo noconflicts:1 rev:1-341d67be2777de6b545ccf6e3cea5bcf200089e4 sequence:1]</ud>
      		 
      2021-09-01T14:30:02.525+01:00 [DBG] SyncMsg+: c:[544756b5] #5: Type:rev Id:<ud>foo</ud> Rev:1-341d67be2777de6b545ccf6e3cea5bcf200089e4 Sequence:1
      		 
      2021-09-01T14:30:02.525+01:00 [TRC] SyncMsg+: c:[544756b5] #5: Properties:<ud>map[Profile:rev id:foo noconflicts:1 rev:1-341d67be2777de6b545ccf6e3cea5bcf200089e4 sequence:1]</ud>  Body:<ud>{"channels":[],"created_at":1630503002590}</ud>
      		 
      2021-09-01T14:30:02.526+01:00 [DBG] CRUD+: No document body found for key=<ud>foo</ud>, xattrKey=<ud>_sync</ud>: sub-document error at index 1: path not found | {"status_code":211,"document_key":"foo","bucket":"db1","scope":"_default","collection":"_default","error_name":"SUBDOC_MULTI_PATH_FAILURE_DELETED","error_description":"Subdoc: One or more paths in a multi-path command failed on a deleted document","opaque":15,"last_dispatched_to":"localhost:11210","last_dispatched_from":"[::1]:55173","last_connection_id":"d6757a0d76c81d1d/b8e661699a9ff4ba"}
      		 
      2021-09-01T14:30:02.526+01:00 [DBG] CRUD+: c:[544756b5] Invoking sync on doc "<ud>foo</ud>" rev 1-341d67be2777de6b545ccf6e3cea5bcf200089e4
      		 
      2021-09-01T14:30:02.527+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).Incr([ <ud>_sync:seq</ud> <ud>1</ud> <ud>1</ud> <ud>0</ud> ]) [361.04µs]
      		 
      2021-09-01T14:30:02.527+01:00 [DBG] CRUD+: c:[544756b5] Saving doc (seq: #11, id: <ud>foo</ud> rev: 1-341d67be2777de6b545ccf6e3cea5bcf200089e4)
      		 
      2021-09-01T14:30:02.528+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).WriteUpdateWithXattr([ <ud>foo</ud> <ud>_sync</ud> <ud>0</ud> ]) [2.481849ms]
      		 
      2021-09-01T14:30:02.528+01:00 [TRC]         UNMARSHAL doc body <ud>foo</ud>/<ud>1-341d67be2777de6b545ccf6e3cea5bcf200089e4</ud> from db.(*Database).updateAndReturnDoc() at crud.go:1913
      		 
      2021-09-01T14:30:02.528+01:00 [DBG] CRUD+: c:[544756b5] Stored doc "<ud>foo</ud>" / "1-341d67be2777de6b545ccf6e3cea5bcf200089e4" as #11
      		 
      2021-09-01T14:30:02.528+01:00 [DBG] SyncMsg+: c:[544756b5] #5: Type:rev   --> OK Time:2.695943ms
      		 
      2021-09-01T14:30:02.528+01:00 [TRC] SyncMsg+: c:[544756b5] Recv Rsp RPY#5: Body: '<ud></ud>' Properties: <ud>map[]</ud>
      		 
      2021-09-01T14:30:02.528+01:00 [DBG] WSFrame+: c:#002 Push RPY#5
      		 
      2021-09-01T14:30:02.528+01:00 [DBG] WSFrame+: c:#002 Sending frame: RPY#5 (flags=       1, size=    1)
      		 
      2021-09-01T14:30:02.529+01:00 [DBG] DCP+: Received #11 after   1ms ("<ud>foo</ud>" / "1-341d67be2777de6b545ccf6e3cea5bcf200089e4")

      Step 5

      2021-09-01T14:30:15.330+01:00 [DBG] WS+: c:#004 Incoming BLIP Request: MSG#3!~
      		 
      2021-09-01T14:30:15.330+01:00 [TRC] SyncMsg+: c:[67b88ae7] Recv Req MSG#3!~: Body: '<ud>[["foo","22-3d811e2c2856b6b3d09e132d9a12c861f19702d1","1-341d67be2777de6b545ccf6e3cea5bcf200089e4",678]]</ud>' Properties: <ud>map[Profile:proposeChanges]</ud>
      		 
      2021-09-01T14:30:15.330+01:00 [INF] SyncMsg: c:[67b88ae7] #3: Type:proposeChanges #Changes: 1
      		 
      2021-09-01T14:30:15.330+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetWithXattr([ <ud>foo</ud> <ud>_sync</ud> <ud></ud> ]) [242.319µs]
      		 
      2021-09-01T14:30:15.330+01:00 [DBG] SyncMsg+: c:[67b88ae7] #3: Type:proposeChanges   --> OK Time:486.523µs
      		 
      2021-09-01T14:30:15.330+01:00 [TRC] SyncMsg+: c:[67b88ae7] Recv Rsp RPY#3!~: Body: '<ud>[]</ud>' Properties: <ud>map[]</ud>
      		 
      2021-09-01T14:30:15.330+01:00 [DBG] WSFrame+: c:#004 Push RPY#3!~
      		 
      2021-09-01T14:30:15.330+01:00 [DBG] WSFrame+: c:#004 Sending frame: RPY#3!~ (flags=   11001, size=    3)
      		 
      2021-09-01T14:30:15.342+01:00 [DBG] WSFrame+: c:#004 Received frame: MSG#4 (flags=       0, length=112)
      		 
      2021-09-01T14:30:15.342+01:00 [DBG] WS+: c:#004 Incoming BLIP Request: MSG#4
      		 
      2021-09-01T14:30:15.342+01:00 [TRC] SyncMsg+: c:[67b88ae7] Recv Req MSG#4: Body: '<ud>{"time":1630503015,"local":21,"remote":11}</ud>' Properties: <ud>map[Profile:setCheckpoint client:cp-2M1otUucAEofcf3UGqsUgBXadiw= rev:0-2]</ud>
      		 
      2021-09-01T14:30:15.342+01:00 [INF] SyncMsg: c:[67b88ae7] #4: Type:setCheckpoint Client:cp-2M1otUucAEofcf3UGqsUgBXadiw= Rev:0-2
      		 
      2021-09-01T14:30:15.343+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).Update([ <ud>_sync:local:checkpoint/cp-2M1otUucAEofcf3UGqsUgBXadiw=</ud> <ud>1638279015</ud> ]) [635.166µs]
      		 
      2021-09-01T14:30:15.343+01:00 [DBG] SyncMsg+: c:[67b88ae7] #4: Type:setCheckpoint   --> OK Time:738.075µs
      		 
      2021-09-01T14:30:15.343+01:00 [TRC] SyncMsg+: c:[67b88ae7] Recv Rsp RPY#4: Body: '<ud></ud>' Properties: <ud>map[rev:0-3]</ud>
      		 
      2021-09-01T14:30:15.343+01:00 [DBG] WSFrame+: c:#004 Push RPY#4
      		 
      2021-09-01T14:30:15.343+01:00 [DBG] WSFrame+: c:#004 Sending frame: RPY#4 (flags=       1, size=    9)
      		 
      2021-09-01T14:30:15.348+01:00 [DBG] WSFrame+: c:#004 Received frame: MSG#5~ (flags=    1000, length=587)
      		 
      2021-09-01T14:30:15.348+01:00 [DBG] WS+: c:#004 Incoming BLIP Request: MSG#5~
      		 
      2021-09-01T14:30:15.348+01:00 [TRC] SyncMsg+: c:[67b88ae7] Recv Req MSG#5~: Body: '<ud>{"channels":[],"created_at":1630503012940}</ud>' Properties: <ud>map[Profile:rev history:21-9c6ef6e09f5e95d66015e0cd5c967c816e8635de,20-c7fd03ecc2c461d53408a27881a1760cb2219188,19-c39ec6168510564a176329e63e8216e1178f9543,18-b1de6277c73780b46429b0e13130babd83f448f3,17-16e29505bc12562e105a57c7307a06a94b938abd,16-d1d67ce8810eb216214657a1128e49f4fb4176aa,15-583e52ac29d9e50817b4577dc2de9d6f025531dc,14-33f3b90f56854487710d54289bcdd8b6b98c731c,13-0d50e916385f0fe556419bcb46702167d64f769d,12-e6f44c069f50100f507b78410356d0f4cd830082,11-d378cccc32d5591c0d090601fb6f6acbee8d4197,10-15c370f750102a9ba688db9a57578b352b939728,9-36ec1aef12d9c5787449567216b7e13171c6f2e6,8-63021f85cadb992a57733bb6817aec7f1c630464,7-184b00ce8ff63a1c8e1d37b8aa1b4ad3f734d6d1,6-a56d185194ef576da412bcb9c57edf16b5c0e1b4,5-b26e27da4a3a0c2a20600b75a823de94f764c8e7,4-3ca979de7f8fade3b8af2855d52648b88da42d0a,3-216de58ac277f796d558fe35a441f59ebf65d831,1-341d67be2777de6b545ccf6e3cea5bcf200089e4 id:foo noconflicts:1 rev:22-3d811e2c2856b6b3d09e132d9a12c861f19702d1 sequence:22]</ud>
      		 
      2021-09-01T14:30:15.348+01:00 [DBG] SyncMsg+: c:[67b88ae7] #5: Type:rev Id:<ud>foo</ud> Rev:22-3d811e2c2856b6b3d09e132d9a12c861f19702d1 Sequence:22
      		 
      2021-09-01T14:30:15.348+01:00 [TRC] SyncMsg+: c:[67b88ae7] #5: Properties:<ud>map[Profile:rev history:21-9c6ef6e09f5e95d66015e0cd5c967c816e8635de,20-c7fd03ecc2c461d53408a27881a1760cb2219188,19-c39ec6168510564a176329e63e8216e1178f9543,18-b1de6277c73780b46429b0e13130babd83f448f3,17-16e29505bc12562e105a57c7307a06a94b938abd,16-d1d67ce8810eb216214657a1128e49f4fb4176aa,15-583e52ac29d9e50817b4577dc2de9d6f025531dc,14-33f3b90f56854487710d54289bcdd8b6b98c731c,13-0d50e916385f0fe556419bcb46702167d64f769d,12-e6f44c069f50100f507b78410356d0f4cd830082,11-d378cccc32d5591c0d090601fb6f6acbee8d4197,10-15c370f750102a9ba688db9a57578b352b939728,9-36ec1aef12d9c5787449567216b7e13171c6f2e6,8-63021f85cadb992a57733bb6817aec7f1c630464,7-184b00ce8ff63a1c8e1d37b8aa1b4ad3f734d6d1,6-a56d185194ef576da412bcb9c57edf16b5c0e1b4,5-b26e27da4a3a0c2a20600b75a823de94f764c8e7,4-3ca979de7f8fade3b8af2855d52648b88da42d0a,3-216de58ac277f796d558fe35a441f59ebf65d831,1-341d67be2777de6b545ccf6e3cea5bcf200089e4 id:foo noconflicts:1 rev:22-3d811e2c2856b6b3d09e132d9a12c861f19702d1 sequence:22]</ud>  Body:<ud>{"channels":[],"created_at":1630503012940}</ud>
      		 
      2021-09-01T14:30:15.349+01:00 [DBG] CRUD+: c:[67b88ae7] Invoking sync on doc "<ud>foo</ud>" rev 22-3d811e2c2856b6b3d09e132d9a12c861f19702d1
      		 
      2021-09-01T14:30:15.349+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:21-9c6ef6e09f5e95d66015e0cd5c967c816e8635de</ud> ]) [200.616µs]
      		 
      2021-09-01T14:30:15.349+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "21-9c6ef6e09f5e95d66015e0cd5c967c816e8635de"
      		 
      2021-09-01T14:30:15.350+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:20-c7fd03ecc2c461d53408a27881a1760cb2219188</ud> ]) [147.243µs]
      		 
      2021-09-01T14:30:15.350+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "20-c7fd03ecc2c461d53408a27881a1760cb2219188"
      		 
      2021-09-01T14:30:15.350+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:19-c39ec6168510564a176329e63e8216e1178f9543</ud> ]) [145.828µs]
      		 
      2021-09-01T14:30:15.350+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "19-c39ec6168510564a176329e63e8216e1178f9543"
      		 
      2021-09-01T14:30:15.350+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:18-b1de6277c73780b46429b0e13130babd83f448f3</ud> ]) [161.969µs]
      		 
      2021-09-01T14:30:15.350+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "18-b1de6277c73780b46429b0e13130babd83f448f3"
      		 
      2021-09-01T14:30:15.350+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:17-16e29505bc12562e105a57c7307a06a94b938abd</ud> ]) [193.102µs]
      		 
      2021-09-01T14:30:15.350+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "17-16e29505bc12562e105a57c7307a06a94b938abd"
      		 
      2021-09-01T14:30:15.350+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:16-d1d67ce8810eb216214657a1128e49f4fb4176aa</ud> ]) [203.724µs]
      		 
      2021-09-01T14:30:15.350+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "16-d1d67ce8810eb216214657a1128e49f4fb4176aa"
      		 
      2021-09-01T14:30:15.351+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:15-583e52ac29d9e50817b4577dc2de9d6f025531dc</ud> ]) [158.938µs]
      		 
      2021-09-01T14:30:15.351+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "15-583e52ac29d9e50817b4577dc2de9d6f025531dc"
      		 
      2021-09-01T14:30:15.351+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:14-33f3b90f56854487710d54289bcdd8b6b98c731c</ud> ]) [170.739µs]
      		 
      2021-09-01T14:30:15.351+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "14-33f3b90f56854487710d54289bcdd8b6b98c731c"
      		 
      2021-09-01T14:30:15.351+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:13-0d50e916385f0fe556419bcb46702167d64f769d</ud> ]) [153.388µs]
      		 
      2021-09-01T14:30:15.351+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "13-0d50e916385f0fe556419bcb46702167d64f769d"
      		 
      2021-09-01T14:30:15.351+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:12-e6f44c069f50100f507b78410356d0f4cd830082</ud> ]) [169.287µs]
      		 
      2021-09-01T14:30:15.351+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "12-e6f44c069f50100f507b78410356d0f4cd830082"
      		 
      2021-09-01T14:30:15.351+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:11-d378cccc32d5591c0d090601fb6f6acbee8d4197</ud> ]) [170.846µs]
      		 
      2021-09-01T14:30:15.351+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "11-d378cccc32d5591c0d090601fb6f6acbee8d4197"
      		 
      2021-09-01T14:30:15.351+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:43:10-15c370f750102a9ba688db9a57578b352b939728</ud> ]) [149.653µs]
      		 
      2021-09-01T14:30:15.351+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "10-15c370f750102a9ba688db9a57578b352b939728"
      		 
      2021-09-01T14:30:15.352+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:42:9-36ec1aef12d9c5787449567216b7e13171c6f2e6</ud> ]) [140.79µs]
      		 
      2021-09-01T14:30:15.352+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "9-36ec1aef12d9c5787449567216b7e13171c6f2e6"
      		 
      2021-09-01T14:30:15.352+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:42:8-63021f85cadb992a57733bb6817aec7f1c630464</ud> ]) [124.439µs]
      		 
      2021-09-01T14:30:15.352+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "8-63021f85cadb992a57733bb6817aec7f1c630464"
      		 
      2021-09-01T14:30:15.352+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:42:7-184b00ce8ff63a1c8e1d37b8aa1b4ad3f734d6d1</ud> ]) [185.464µs]
      		 
      2021-09-01T14:30:15.352+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "7-184b00ce8ff63a1c8e1d37b8aa1b4ad3f734d6d1"
      		 
      2021-09-01T14:30:15.352+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:42:6-a56d185194ef576da412bcb9c57edf16b5c0e1b4</ud> ]) [150.445µs]
      		 
      2021-09-01T14:30:15.352+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "6-a56d185194ef576da412bcb9c57edf16b5c0e1b4"
      		 
      2021-09-01T14:30:15.352+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:42:5-b26e27da4a3a0c2a20600b75a823de94f764c8e7</ud> ]) [129.067µs]
      		 
      2021-09-01T14:30:15.352+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "5-b26e27da4a3a0c2a20600b75a823de94f764c8e7"
      		 
      2021-09-01T14:30:15.353+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:42:4-3ca979de7f8fade3b8af2855d52648b88da42d0a</ud> ]) [195.868µs]
      		 
      2021-09-01T14:30:15.353+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "4-3ca979de7f8fade3b8af2855d52648b88da42d0a"
      		 
      2021-09-01T14:30:15.353+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).GetRaw([ <ud>_sync:rev:foo:42:3-216de58ac277f796d558fe35a441f59ebf65d831</ud> ]) [155.486µs]
      		 
      2021-09-01T14:30:15.353+01:00 [DBG] CRUD+: No old revision "<ud>foo</ud>" / "3-216de58ac277f796d558fe35a441f59ebf65d831"
      		 
      2021-09-01T14:30:15.353+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).SetRaw([ <ud>_sync:rev:foo:42:1-341d67be2777de6b545ccf6e3cea5bcf200089e4</ud> <ud>300</ud> ]) [217.09µs]
      		 
      2021-09-01T14:30:15.353+01:00 [DBG] CRUD+: Backed up revision body "<ud>foo</ud>"/"1-341d67be2777de6b545ccf6e3cea5bcf200089e4" (42 bytes, ttl:300)
      		 
      2021-09-01T14:30:15.353+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).Incr([ <ud>_sync:seq</ud> <ud>1</ud> <ud>1</ud> <ud>0</ud> ]) [252.632µs]
      		 
      2021-09-01T14:30:15.354+01:00 [DBG] CRUD+: c:[67b88ae7] Saving doc (seq: #12, id: <ud>foo</ud> rev: 22-3d811e2c2856b6b3d09e132d9a12c861f19702d1)
      		 
      2021-09-01T14:30:15.354+01:00 [TRC] Bucket+: b:db1 base.(*LoggingBucket).WriteUpdateWithXattr([ <ud>foo</ud> <ud>_sync</ud> <ud>0</ud> ]) [5.860059ms]
      		 
      2021-09-01T14:30:15.354+01:00 [WRN] Found gap in revision list. Expecting gen 2 but got 1 in [22-3d811e2c2856b6b3d09e132d9a12c861f19702d1 21-9c6ef6e09f5e95d66015e0cd5c967c816e8635de 20-c7fd03ecc2c461d53408a27881a1760cb2219188 19-c39ec6168510564a176329e63e8216e1178f9543 18-b1de6277c73780b46429b0e13130babd83f448f3 17-16e29505bc12562e105a57c7307a06a94b938abd 16-d1d67ce8810eb216214657a1128e49f4fb4176aa 15-583e52ac29d9e50817b4577dc2de9d6f025531dc 14-33f3b90f56854487710d54289bcdd8b6b98c731c 13-0d50e916385f0fe556419bcb46702167d64f769d 12-e6f44c069f50100f507b78410356d0f4cd830082 11-d378cccc32d5591c0d090601fb6f6acbee8d4197 10-15c370f750102a9ba688db9a57578b352b939728 9-36ec1aef12d9c5787449567216b7e13171c6f2e6 8-63021f85cadb992a57733bb6817aec7f1c630464 7-184b00ce8ff63a1c8e1d37b8aa1b4ad3f734d6d1 6-a56d185194ef576da412bcb9c57edf16b5c0e1b4 5-b26e27da4a3a0c2a20600b75a823de94f764c8e7 4-3ca979de7f8fade3b8af2855d52648b88da42d0a 3-216de58ac277f796d558fe35a441f59ebf65d831 1-341d67be2777de6b545ccf6e3cea5bcf200089e4] -- db.encodeRevisions() at revtree.go:769
      		 
      2021-09-01T14:30:15.354+01:00 [TRC]         UNMARSHAL doc body <ud>foo</ud>/<ud>22-3d811e2c2856b6b3d09e132d9a12c861f19702d1</ud> from db.(*Database).updateAndReturnDoc() at crud.go:1913
      		 
      2021-09-01T14:30:15.355+01:00 [DBG] CRUD+: c:[67b88ae7] Stored doc "<ud>foo</ud>" / "22-3d811e2c2856b6b3d09e132d9a12c861f19702d1" as #12
      		 
      2021-09-01T14:30:15.355+01:00 [DBG] SyncMsg+: c:[67b88ae7] #5: Type:rev   --> OK Time:6.307823ms
      		 
      2021-09-01T14:30:15.355+01:00 [TRC] SyncMsg+: c:[67b88ae7] Recv Rsp RPY#5: Body: '<ud></ud>' Properties: <ud>map[]</ud>
      		 
      2021-09-01T14:30:15.355+01:00 [DBG] WSFrame+: c:#004 Push RPY#5
      		 
      2021-09-01T14:30:15.355+01:00 [DBG] WSFrame+: c:#004 Sending frame: RPY#5 (flags=       1, size=    1)
      		 
      2021-09-01T14:30:15.355+01:00 [DBG] DCP+: Received #12 after   1ms ("<ud>foo</ud>" / "22-3d811e2c2856b6b3d09e132d9a12c861f19702d1")

      Attachments

        Issue Links

          Clones

          Bug - A problem which impairs or prevents the functions of the product. CBG-1669 SG warning when client's maxHistory for a rev is exceeded on push

          • Major - Major loss of function.
          • Closed
          relates to

          Bug - A problem which impairs or prevents the functions of the product. CBL-2694 CBL sending rev list with gaps, when sync'ing more than 20 revs

          • Major - Major loss of function.
          • Closed

          Activity

            People

              ben.brooks Ben Brooks
              ben.brooks Ben Brooks
              Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

              Dates

                Created:
                Updated:
                Resolved:

                PagerDuty