BatchChangesResultingInFeedFailure.txt

12:37:47.187073 2016-11-02T12:37:47.187-04:00 Cache: SAVING #4081
12:37:47.188073 2016-11-02T12:37:47.188-04:00 Bucket: WriteUpdate("demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server", 0, ...) --> <nil> [4ms]
12:37:47.188073 2016-11-02T12:37:47.188-04:00 CRUD: Stored doc "demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server" / "90-102d74f22672852e3c6d67e9a755d30f"
12:37:47.188073 2016-11-02T12:37:47.188-04:00 HTTP+: #236:     --> 201   (4.0 ms)
12:37:47.190073 2016-11-02T12:37:47.190-04:00 HTTP:  #237: GET /pm/mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30  (ADMIN)
12:37:47.190073 2016-11-02T12:37:47.190-04:00 Bucket: Get("mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30") [0]
12:37:47.191073 2016-11-02T12:37:47.191-04:00 HTTP+: #237:     --> 200   (1.0 ms)
12:37:47.193073 2016-11-02T12:37:47.193-04:00 HTTP:  #238: PUT /pm/mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30?rev=53-8eb78e6e4c68b59f48e59700b767c29d  (ADMIN)
12:37:47.194073 2016-11-02T12:37:47.194-04:00 CRUD+: Invoking sync on doc "mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30" rev 54-3992bd41f429a7c0dc5393f5ccf8163f
12:37:47.194073 2016-11-02T12:37:47.194-04:00 CRUD+: Saving old revision "mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30" / "53-8eb78e6e4c68b59f48e59700b767c29d" (619 bytes)
12:37:47.194073 2016-11-02T12:37:47.194-04:00 Bucket: SetRaw("_sync:rev:mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30:35:53-8eb78e6e4c68b59f48e59700b767c29d", 300, ...) [0]
12:37:47.194073 2016-11-02T12:37:47.194-04:00 CRUD+: Backed up obsolete rev "mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30"/"53-8eb78e6e4c68b59f48e59700b767c29d"
12:37:47.194073 2016-11-02T12:37:47.194-04:00 Bucket: Incr("_sync:seq", 1, 1, 0) [0]
12:37:47.194073 2016-11-02T12:37:47.194-04:00 Cache: SAVING #4082
12:37:47.195073 2016-11-02T12:37:47.195-04:00 Bucket: WriteUpdate("mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30", 0, ...) --> <nil> [2ms]
12:37:47.195073 2016-11-02T12:37:47.195-04:00 CRUD: Stored doc "mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30" / "54-3992bd41f429a7c0dc5393f5ccf8163f"
12:37:47.195073 2016-11-02T12:37:47.195-04:00 HTTP+: #238:     --> 201   (2.0 ms)
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache: Received deduplicated #4079 for ("demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server" / "90-102d74f22672852e3c6d67e9a755d30f")
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache:   Deferring #4079 (1 now waiting for #4078...#4078)
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache: Received #4081 after 1844ms ("demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server" / "90-102d74f22672852e3c6d67e9a755d30f")
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache:   Deferring #4081 (2 now waiting for #4078...#4078)
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache: Received #4080 after 1857ms ("mml_cache" / "195-d7f1b5ae89df3f2e404dbda56bb1f5b8")
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache:   Deferring #4080 (3 now waiting for #4078...#4078)
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache: Received deduplicated #4078 for ("mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30" / "54-3992bd41f429a7c0dc5393f5ccf8163f")
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache:     #4080 ==> channel "*"
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache:     #4081 ==> channel "entity_demo"
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache:     #4081 ==> channel "*"
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache: Received #4082 after 1838ms ("mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30" / "54-3992bd41f429a7c0dc5393f5ccf8163f")
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache:     #4082 ==> channel "*"
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: Notifying that "pm" changed (keys="{*}") count=35
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: MultiChangesFeed: channels expand to channels.TimedSet{"*":channels.VbSequence{VbNo:(*uint16)(nil), Sequence:0x0}} ... 
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Cache: getCachedChanges("*", {0 0 0 4077 <nil> <nil> %!d(string=) 0 0 0 %!d(string=)}) --> 3 changes valid from #4078
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: Sending seq:4080 from channel *
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: Sending seq:4081 from channel *
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: MultiChangesFeed sending &{Seq:4080 ID:mml_cache Deleted:false Removed:{} Doc:map[] Changes:[map[rev:195-d7f1b5ae89df3f2e404dbda56bb1f5b8]] Err:<nil> allRemoved:false branched:false} 
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes: sending 1 change(s)
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: Sending seq:4082 from channel *
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: MultiChangesFeed sending &{Seq:4081 ID:demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server Deleted:false Removed:{} Doc:map[] Changes:[map[rev:90-102d74f22672852e3c6d67e9a755d30f]] Err:<nil> allRemoved:false branched:false} 
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes: sending 1 change(s)
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: MultiChangesFeed sending &{Seq:4082 ID:mml_cache_demo_ac86a003-7f24-403e-9406-afcc8a03bc30 Deleted:false Removed:{} Doc:map[] Changes:[map[rev:54-3992bd41f429a7c0dc5393f5ccf8163f]] Err:<nil> allRemoved:false branched:false} 
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: MultiChangesFeed waiting... 
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes+: Waiting for "pm"'s count to pass 35
12:37:49.032073 2016-11-02T12:37:49.032-04:00 Changes: sending 1 change(s)
12:37:51.606073 2016-11-02T12:37:51.606-04:00 Bucket: Get("_sync:session:46dd66d8435a75e9a912feafa1c1e7e077ee4061") [0]
12:37:51.606073 2016-11-02T12:37:51.606-04:00 Bucket: Update("_sync:user:demo", 0, ...) --> CAS quit [0]
12:37:51.606073 2016-11-02T12:37:51.606-04:00 HTTP:  #239: PUT /pm/_local/e4aa7f6565f9887931d491c1bf3d214cc5a34ed5  (as demo)
12:37:51.607073 2016-11-02T12:37:51.607-04:00 Bucket: Update("_sync:local:e4aa7f6565f9887931d491c1bf3d214cc5a34ed5", 0, ...) --> <nil> [1ms]
12:37:51.607073 2016-11-02T12:37:51.607-04:00 HTTP+: #239:     --> 201   (1.0 ms)
12:37:52.567073 2016-11-02T12:37:52.567-04:00 Bucket: Get("_sync:session:46dd66d8435a75e9a912feafa1c1e7e077ee4061") [0]
12:37:52.568073 2016-11-02T12:37:52.568-04:00 Bucket: Update("_sync:user:demo", 0, ...) --> CAS quit [0]
12:37:52.568073 2016-11-02T12:37:52.568-04:00 HTTP:  #240: PUT /pm/_local/6b5d5372c11d217f1a80985c8715b5f6da13ef1d  (as demo)
12:37:52.568073 2016-11-02T12:37:52.568-04:00 Bucket: Update("_sync:local:6b5d5372c11d217f1a80985c8715b5f6da13ef1d", 0, ...) --> <nil> [0]
12:37:52.568073 2016-11-02T12:37:52.568-04:00 HTTP+: #240:     --> 201   (1.0 ms)
12:37:56.113073 2016-11-02T12:37:56.113-04:00 HTTP:  #241: GET /pm/demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server  (ADMIN)
12:37:56.114073 2016-11-02T12:37:56.114-04:00 Bucket: Get("demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server") [1ms]
12:37:56.115073 2016-11-02T12:37:56.115-04:00 HTTP+: #241:     --> 200   (2.0 ms)
12:37:56.117073 2016-11-02T12:37:56.117-04:00 HTTP:  #242: PUT /pm/demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server?rev=90-102d74f22672852e3c6d67e9a755d30f  (ADMIN)
12:37:56.118073 2016-11-02T12:37:56.118-04:00 CRUD+: Invoking sync on doc "demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server" rev 91-dedccddb96a937e5e2cc91a992254026
12:37:56.118073 2016-11-02T12:37:56.118-04:00 CRUD+: Saving old revision "demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server" / "90-102d74f22672852e3c6d67e9a755d30f" (536 bytes)
12:37:56.118073 2016-11-02T12:37:56.118-04:00 Bucket: SetRaw("_sync:rev:demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server:35:90-102d74f22672852e3c6d67e9a755d30f", 300, ...) [0]
12:37:56.119073 2016-11-02T12:37:56.119-04:00 CRUD+: Backed up obsolete rev "demo_ac86a003-7f24-403e-9406-afcc8a03bc30_bmt_from_server"/"90-102d74f22672852e3c6d67e9a755d30f"
12:37:56.119073 2016-11-02T12:37:56.119-04:00 Bucket: Incr("_sync:seq", 1, 1, 0) [0]