Details
-
Bug
-
Resolution: Fixed
-
Major
-
2.1.1
-
Security Level: Public
-
None
-
CBG Sprint 13, CBG Sprint 14
-
3
Description
A user reported an issue we thought we'd fixed since SG 1.5, but is actually easily reproducible in at least 2.1.2, and Iridium.
Forum post:
GitHub Issues:
- https://github.com/couchbase/sync_gateway/issues/2151
- https://github.com/couchbase/sync_gateway/issues/2529
GitHub PR for prior fix:
Tasks
- Add a unit test for the steps below, and assert that we see no error
- Find out why these user documents have entry.pseudoDoc == false, when the above fix implies _user documents should be marked as pseudo docs.
Steps to reproduce
- Create a new SG user
- Run a _changes request with include_docs=true
- See "400 Invalid doc ID" error message for _user doc
Example log
2019-02-12T14:43:15.150Z [INF] HTTP: #001: POST /db/_user/ (as ADMIN)
|
2019-02-12T14:43:15.150Z [TRC] Bucket: Update("_sync:user:alice1", 0, ...) --> Cancel update [2.809µs]
|
2019-02-12T14:43:15.150Z [TRC] Bucket: ViewQuery("sync_gateway_2.1", "access", ...) [218.284µs]
|
2019-02-12T14:43:15.150Z [INF] Access: Computed channels for "alice1": !:1
|
2019-02-12T14:43:15.150Z [TRC] Bucket: ViewQuery("sync_gateway_2.1", "role_access", ...) [124.072µs]
|
2019-02-12T14:43:15.150Z [INF] Access: Computed roles for "alice1":
|
2019-02-12T14:43:15.226Z [TRC] Bucket: Incr("_sync:seq", 1, 1, 0) [9.956µs]
|
2019-02-12T14:43:15.226Z [TRC] Bucket: WriteCas("_sync:user:alice1", 0x0, 0, 0, ..., 0x0) [50.796µs]
|
2019-02-12T14:43:15.226Z [DBG] Changes+: Notifying that "db" changed (keys="{_sync:user:alice1}") count=4
|
2019-02-12T14:43:15.226Z [INF] Auth: Saved _sync:user:alice1: &{roleImpl:{Name_:alice1 ExplicitChannels_: Channels_:!:1 Sequence_:2 PreviousChannels_: vbNo:<nil> cas:6} userImplBody:{Email_: Disabled_:false PasswordHash_:[36 50 97 36 49 48 36 97 77 111 100 52 107 52 87 87 76 67 122 55 110 71 120 68 87 77 99 98 117 106 86 116 66 97 85 75 46 106 118 67 116 66 110 82 102 52 47 57 112 116 111 85 121 56 49 90 78 85 108 54] OldPasswordHash_:<nil> ExplicitRoles_: RolesSince_: OldExplicitRoles_:[]} auth:0xc000794330 roles:[]}
|
2019-02-12T14:43:15.226Z [INF] HTTP+: #001: --> 201 Created (76.3 ms)
|
2019-02-12T14:43:15.226Z [INF] Cache: Received #2 ("_user/alice1")
|
2019-02-12T14:43:15.226Z [DBG] Cache+: Pruned 0 entries from channel "*"
|
2019-02-12T14:43:15.226Z [INF] Cache: #2 ==> channels [*]
|
2019-02-12T14:43:15.226Z [DBG] Changes+: Notifying that "db" changed (keys="{*}") count=5
|
|
|
|
|
|
|
2019-02-12T14:43:34.704Z [INF] HTTP: #004: POST /db/_changes?include_docs=true (as ADMIN)
|
2019-02-12T14:43:34.705Z [DBG] Changes+: Changes POST request. URL: /db/_changes?include_docs=true, feed: , options: {Since:0 Limit:0 Conflicts:false IncludeDocs:true Wait:false Continuous:false Terminator:<nil> HeartbeatMs:0 TimeoutMs:300000 ActiveOnly:false Ctx:<nil>}, filter: , bychannel: [], docIds: []
|
2019-02-12T14:43:34.705Z [DBG] Changes+: c:#004 Int sequence multi changes feed...
|
2019-02-12T14:43:34.705Z [INF] Changes: c:#004 MultiChangesFeed(channels: {*}, options: {Since:0 Limit:0 Conflicts:false IncludeDocs:true Wait:false Continuous:false Terminator:0xc00002c3c0 HeartbeatMs:0 TimeoutMs:300000 ActiveOnly:false Ctx:<nil>}) ...
|
2019-02-12T14:43:34.705Z [DBG] Changes+: c:#004 MultiChangesFeed: channels expand to "" ...
|
2019-02-12T14:43:34.705Z [INF] Cache: getCachedChanges("*", 0) --> 2 changes valid from #1
|
2019-02-12T14:43:34.705Z [DBG] Changes+: c:#004 [changesFeed] Found 2 changes for channel *
|
2019-02-12T14:43:34.705Z [DBG] Changes+: c:#004 Channel feed processing seq:1 in channel *
|
2019-02-12T14:43:34.705Z [DBG] Changes+: c:#004 Channel feed processing seq:2 in channel *
|
2019-02-12T14:43:34.705Z [WRN] c:#004 Changes feed: error getting revision body for "_user/admin" (): 400 Invalid doc ID -- db.(*Database).addDocToChangeEntry() at changes.go:127
|
2019-02-12T14:43:34.705Z [DBG] Changes+: c:#004 MultiChangesFeed sending {Seq:1, ID:_user/admin, Changes:[map[rev:]]}
|
2019-02-12T14:43:34.705Z [WRN] c:#004 Changes feed: error getting revision body for "_user/alice1" (): 400 Invalid doc ID -- db.(*Database).addDocToChangeEntry() at changes.go:127
|
2019-02-12T14:43:34.705Z [DBG] Changes+: c:#004 MultiChangesFeed sending {Seq:2, ID:_user/alice1, Changes:[map[rev:]]}
|
2019-02-12T14:43:34.705Z [INF] Changes: c:#004 MultiChangesFeed done
|
2019-02-12T14:43:34.705Z [INF] HTTP+: #004: --> 200 OK (0.3 ms)
|