Skip to content

Instantly share code, notes, and snippets.

@borrrden
Last active February 23, 2018 23:23
Show Gist options
  • Save borrrden/12829ebc6f45a7b2f22b288af8c2f95a to your computer and use it in GitHub Desktop.
Save borrrden/12829ebc6f45a7b2f22b288af8c2f95a to your computer and use it in GitHub Desktop.
07:14:55.054483| This is LiteCore built from master branch, commit 662556b5+CHANGES on Feb 21 2018 17:05:43 ... short version 662556b5+
07:14:55.055604| Beginning logging to C:\tmp\Litecore_C_Tests\LiteCoreAPITests.c4log
SQLite, rev-trees
07:14:55.057460| [DB]: Opening DataFile C:\tmp\Litecore_C_Tests\cbl_core_test\db.sqlite3
07:14:55.094788| [DB]: Opening DataFile C:\tmp\Litecore_C_Tests\cbl_core_test_2\db.sqlite3
07:14:55.133436| [DB]: Opening DataFile C:\tmp\Litecore_C_Tests\cbl_core_test\db.sqlite3
07:14:55.139177| [BLIP]: {Connection#1}==> class litecore::blip::Connection ->ws:srv/
07:14:55.139876| [BLIP]: {Connection#1} Opening connection...
07:14:55.140303| [Actor]: Starting Scheduler<000001F1902CED10> with 4 threads
07:14:55.141674| [Sync]: {Repl#2}==> class litecore::repl::Replicator C:\tmp\Litecore_C_Tests\cbl_core_test\ ->ws:srv/
07:14:55.142327| [Sync]: {Repl#2} Push=continuous, Options={"WS-Protocols":"BLIP_3a2"}
07:14:55.143043| [BLIP]: {Connection#3}==> class litecore::blip::Connection <-ws:cli/
07:14:55.143398| [BLIP]: {Connection#3} Accepted connection
07:14:55.144408| [Sync]: {Repl#4}==> class litecore::repl::Replicator C:\tmp\Litecore_C_Tests\cbl_core_test_2\ <-ws:cli/
07:14:55.144769| [Sync]: {Repl#4} Push=passive, Pull=passive, Options={}
07:14:55.146461| Waiting for replication to complete...
07:14:55.147201| [BLIP]: {Connection#1} Connected!
07:14:55.149503| [BLIP]: {Connection#3} Connected!
07:14:55.150108| [Sync]: {Repl#2} BLIP Connected
07:14:55.150880| [Sync]: {Repl#4} BLIP Connected
07:14:55.152066| [Sync]: {Repl#2} now busy
07:14:55.152526| [Sync]: {Repl#4} now busy
07:14:55.152944| >> Replicator is busy, progress 0/0, 0 docs
07:14:55.153265| [Sync]: {Repl#2} No local checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.153841| [Sync]: {Push#5}==> class litecore::repl::Pusher ->ws:srv/
07:14:55.154219| [Sync]: {Push#5} Starting continuous push from local seq 1
07:14:55.154584| [Sync]: {Push#5} Asking DB for 200 changes since sequence 0 ...
07:14:55.155005| [Sync]: {DBWorker#6}==> class litecore::repl::DBWorker ->ws:srv/
07:14:55.155356| [Sync]: {DBWorker#6} Reading up to 200 local changes since #0
07:14:55.156609| [Sync]: {Push#5} Caught up, at lastSequence 0
07:14:55.160383| [Sync]: {DBWorker#7}==> class litecore::repl::DBWorker <-ws:cli/
07:14:55.161421| [Sync]: {DBWorker#7} Request to get checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.162442| [Sync]: {Pull#8}==> class litecore::repl::Puller <-ws:cli/
07:14:55.162819| [Sync]: {Pull#8} Caught up with remote changes
07:14:55.168310| [Sync]: {Repl#2} No remote checkpoint
07:14:55.168731| [Sync]: {Repl#2} now idle
07:14:55.169702| >> Replicator is idle, progress 0/0, 0 docs
07:14:55.239110| -------- Creating 2 docs --------
07:14:55.241935| [Sync]: {DBWorker#6} Notified of 2 db changes #1 ... #2
07:14:55.243032| [Sync]: {Push#5} Found 2 changes: Pusher sending 'proposeChanges' with sequences 1 - 2
07:14:55.243647| [Sync]: {Repl#2} now busy
07:14:55.244229| >> Replicator is busy, progress 0/32, 0 docs
07:14:55.249754| [Sync]: {DBWorker#7} Looking up 2 proposed revisions in the db
07:14:55.250942| [Sync]: {DBWorker#7} Responding w/request for 2 revs
07:14:55.317979| [Sync]: {DBWorker#7} Inserted 2 revs in 2.44ms (819/sec)
07:14:55.325042| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='': {"local":2} ...
07:14:55.333299| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.341716| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='1-cc'
07:14:55.343350| -------- Creating 4 docs --------
07:14:55.344877| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:55.346377| [Sync]: {Repl#2} now idle
07:14:55.351447| >> Replicator is idle, progress 32/32, 2 docs
07:14:55.354009| [Sync]: {DBWorker#6} Notified of 4 db changes #3 ... #6
07:14:55.356724| [Sync]: {Push#5} Found 4 changes: Pusher sending 'proposeChanges' with sequences 3 - 6
07:14:55.359837| [Sync]: {Repl#2} now busy
07:14:55.361028| >> Replicator is busy, progress 32/96, 2 docs
07:14:55.365905| [Sync]: {DBWorker#7} Looking up 4 proposed revisions in the db
07:14:55.367177| [Sync]: {DBWorker#7} Responding w/request for 4 revs
07:14:55.434718| [Sync]: {DBWorker#7} Inserted 4 revs in 1.25ms (3200/sec)
07:14:55.442084| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='1-cc': {"local":6} ...
07:14:55.444464| >> Replicator is busy, progress 96/96, 6 docs
07:14:55.451108| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.454416| -------- Creating 6 docs --------
07:14:55.457730| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='2-cc'
07:14:55.460342| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:55.461189| [Sync]: {Repl#2} now idle
07:14:55.462455| >> Replicator is idle, progress 96/96, 6 docs
07:14:55.463128| [Sync]: {DBWorker#6} Notified of 6 db changes #7 ... #12
07:14:55.465272| [Sync]: {Push#5} Found 6 changes: Pusher sending 'proposeChanges' with sequences 7 - 12
07:14:55.466856| [Sync]: {Repl#2} now busy
07:14:55.467712| >> Replicator is busy, progress 96/192, 6 docs
07:14:55.473130| [Sync]: {DBWorker#7} Looking up 6 proposed revisions in the db
07:14:55.474761| [Sync]: {DBWorker#7} Responding w/request for 6 revs
07:14:55.541446| [Sync]: {DBWorker#7} Inserted 6 revs in 1.73ms (3461/sec)
07:14:55.550186| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='2-cc': {"local":12} ...
07:14:55.559238| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.570965| >> Replicator is busy, progress 192/192, 12 docs
07:14:55.571634| -------- Creating 8 docs --------
07:14:55.576788| [Sync]: {DBWorker#6} Notified of 8 db changes #13 ... #20
07:14:55.578878| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='3-cc'
07:14:55.579939| [Sync]: {Push#5} Found 8 changes: Pusher sending 'proposeChanges' with sequences 13 - 20
07:14:55.581669| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:55.589301| [Sync]: {DBWorker#7} Looking up 8 proposed revisions in the db
07:14:55.590608| [Sync]: {DBWorker#7} Responding w/request for 8 revs
07:14:55.657353| [Sync]: {DBWorker#7} Inserted 8 revs in 1.49ms (5369/sec)
07:14:55.664606| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='3-cc': {"local":20} ...
07:14:55.667785| >> Replicator is busy, progress 320/320, 20 docs
07:14:55.673289| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.677762| -------- Creating 10 docs --------
07:14:55.679974| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='4-cc'
07:14:55.684742| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:55.685367| [Sync]: {Repl#2} now idle
07:14:55.686174| >> Replicator is idle, progress 320/320, 20 docs
07:14:55.686640| [Sync]: {DBWorker#6} Notified of 10 db changes #21 ... #30
07:14:55.688615| [Sync]: {Push#5} Found 10 changes: Pusher sending 'proposeChanges' with sequences 21 - 30
07:14:55.689484| [Sync]: {Repl#2} now busy
07:14:55.689884| >> Replicator is busy, progress 320/480, 20 docs
07:14:55.695487| [Sync]: {DBWorker#7} Looking up 10 proposed revisions in the db
07:14:55.696973| [Sync]: {DBWorker#7} Responding w/request for 10 revs
07:14:55.761992| >> Replicator is busy, progress 320/480, 20 docs
07:14:55.764000| [Sync]: {DBWorker#7} Inserted 10 revs in 2.01ms (4978/sec)
07:14:55.771512| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='4-cc': {"local":30} ...
07:14:55.778310| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.784891| -------- Creating 12 docs --------
07:14:55.785645| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='5-cc'
07:14:55.793352| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:55.794003| [Sync]: {DBWorker#6} Notified of 12 db changes #31 ... #42
07:14:55.794560| [Sync]: {Repl#2} now idle
07:14:55.795688| >> Replicator is idle, progress 480/480, 30 docs
07:14:55.796408| [Sync]: {Push#5} Found 12 changes: Pusher sending 'proposeChanges' with sequences 31 - 42
07:14:55.797276| [Sync]: {Repl#2} now busy
07:14:55.797742| >> Replicator is busy, progress 480/672, 30 docs
07:14:55.803571| [Sync]: {DBWorker#7} Looking up 12 proposed revisions in the db
07:14:55.804740| [Sync]: {DBWorker#7} Responding w/request for 12 revs
07:14:55.871300| [Sync]: {DBWorker#7} Inserted 12 revs in 2.18ms (5501/sec)
07:14:55.879395| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='5-cc': {"local":42} ...
07:14:55.888575| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:55.894051| -------- Creating 14 docs --------
07:14:55.896271| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='6-cc'
07:14:55.903280| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:55.904051| [Sync]: {Repl#2} now idle
07:14:55.904654| >> Replicator is idle, progress 672/672, 42 docs
07:14:55.905622| [Sync]: {DBWorker#6} Notified of 14 db changes #43 ... #56
07:14:55.907752| [Sync]: {Push#5} Found 14 changes: Pusher sending 'proposeChanges' with sequences 43 - 56
07:14:55.908588| [Sync]: {Repl#2} now busy
07:14:55.908980| >> Replicator is busy, progress 672/896, 42 docs
07:14:55.915837| [Sync]: {DBWorker#7} Looking up 14 proposed revisions in the db
07:14:55.917792| [Sync]: {DBWorker#7} Responding w/request for 14 revs
07:14:55.962181| >> Replicator is busy, progress 672/896, 42 docs
07:14:55.984828| [Sync]: {DBWorker#7} Inserted 14 revs in 3.58ms (3915/sec)
07:14:55.992451| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='6-cc': {"local":56} ...
07:14:55.999553| >> Replicator is busy, progress 896/896, 56 docs
07:14:56.001004| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.003160| -------- Creating 16 docs --------
07:14:56.007373| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='7-cc'
07:14:56.012987| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.013731| [Sync]: {DBWorker#6} Notified of 16 db changes #57 ... #72
07:14:56.014853| [Sync]: {Repl#2} now idle
07:14:56.015656| >> Replicator is idle, progress 896/896, 56 docs
07:14:56.018062| [Sync]: {Push#5} Found 16 changes: Pusher sending 'proposeChanges' with sequences 57 - 72
07:14:56.019103| [Sync]: {Repl#2} now busy
07:14:56.019736| >> Replicator is busy, progress 896/1152, 56 docs
07:14:56.026144| [Sync]: {DBWorker#7} Looking up 16 proposed revisions in the db
07:14:56.028121| [Sync]: {DBWorker#7} Responding w/request for 16 revs
07:14:56.096209| [Sync]: {DBWorker#7} Inserted 16 revs in 2.85ms (5610/sec)
07:14:56.104727| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='7-cc': {"local":72} ...
07:14:56.112861| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.113394| -------- Creating 18 docs --------
07:14:56.119514| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='8-cc'
07:14:56.125490| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.126127| [Sync]: {DBWorker#6} Notified of 18 db changes #73 ... #90
07:14:56.126896| [Sync]: {Repl#2} now idle
07:14:56.127678| >> Replicator is idle, progress 1152/1152, 72 docs
07:14:56.128912| [Sync]: {Push#5} Found 18 changes: Pusher sending 'proposeChanges' with sequences 73 - 90
07:14:56.129842| [Sync]: {Repl#2} now busy
07:14:56.130260| >> Replicator is busy, progress 1152/1440, 72 docs
07:14:56.136166| [Sync]: {DBWorker#7} Looking up 18 proposed revisions in the db
07:14:56.137698| [Sync]: {DBWorker#7} Responding w/request for 18 revs
07:14:56.162419| >> Replicator is busy, progress 1152/1440, 72 docs
07:14:56.211097| [Sync]: {DBWorker#7} Inserted 18 revs in 9.47ms (1900/sec)
07:14:56.223945| >> Replicator is busy, progress 1152/1440, 72 docs
07:14:56.230186| -------- Creating 20 docs --------
07:14:56.234980| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='8-cc': {"local":90} ...
07:14:56.241987| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.247753| [Sync]: {DBWorker#6} Notified of 20 db changes #91 ... #110
07:14:56.249398| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='9-cc'
07:14:56.251904| [Sync]: {Push#5} Found 20 changes: Pusher sending 'proposeChanges' with sequences 91 - 110
07:14:56.252377| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.259439| [Sync]: {DBWorker#7} Looking up 20 proposed revisions in the db
07:14:56.261303| [Sync]: {DBWorker#7} Responding w/request for 20 revs
07:14:56.329117| [Sync]: {DBWorker#7} Inserted 20 revs in 3.46ms (5774/sec)
07:14:56.336287| [Sync]: {Push#5} Checkpoint now at 100
07:14:56.337522| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='9-cc': {"local":110} ...
07:14:56.345065| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.350605| -------- Creating 22 docs --------
07:14:56.351866| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='10-cc'
07:14:56.363767| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.364226| [Sync]: {Repl#2} now idle
07:14:56.365284| [Sync]: {DBWorker#6} Notified of 22 db changes #111 ... #132
07:14:56.366406| >> Replicator is idle, progress 1760/1760, 110 docs
07:14:56.368737| [Sync]: {Push#5} Found 22 changes: Pusher sending 'proposeChanges' with sequences 111 - 132
07:14:56.369931| [Sync]: {Repl#2} now busy
07:14:56.370410| >> Replicator is busy, progress 1760/2112, 110 docs
07:14:56.376429| [Sync]: {DBWorker#7} Looking up 22 proposed revisions in the db
07:14:56.378156| [Sync]: {DBWorker#7} Responding w/request for 22 revs
07:14:56.421787| >> Replicator is busy, progress 1760/2112, 110 docs
07:14:56.446632| [Sync]: {DBWorker#7} Inserted 22 revs in 3.77ms (5828/sec)
07:14:56.455834| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='10-cc': {"local":132} ...
07:14:56.463152| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.463759| -------- Creating 24 docs --------
07:14:56.470016| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='11-cc'
07:14:56.477677| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.478628| [Sync]: {Repl#2} now idle
07:14:56.479478| >> Replicator is idle, progress 2112/2112, 132 docs
07:14:56.479918| [Sync]: {DBWorker#6} Notified of 24 db changes #133 ... #156
07:14:56.483260| [Sync]: {Push#5} Found 24 changes: Pusher sending 'proposeChanges' with sequences 133 - 156
07:14:56.484739| [Sync]: {Repl#2} now busy
07:14:56.485350| >> Replicator is busy, progress 2112/2496, 132 docs
07:14:56.491925| [Sync]: {DBWorker#7} Looking up 24 proposed revisions in the db
07:14:56.494262| [Sync]: {DBWorker#7} Responding w/request for 24 revs
07:14:56.564369| [Sync]: {DBWorker#7} Inserted 24 revs in 5.41ms (4439/sec)
07:14:56.573905| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='11-cc': {"local":156} ...
07:14:56.577910| -------- Creating 26 docs --------
07:14:56.583408| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.590145| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='12-cc'
07:14:56.596983| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.597592| [Sync]: {Repl#2} now idle
07:14:56.598355| [Sync]: {DBWorker#6} Notified of 26 db changes #157 ... #182
07:14:56.599274| >> Replicator is idle, progress 2496/2496, 156 docs
07:14:56.602163| [Sync]: {Push#5} Found 26 changes: Pusher sending 'proposeChanges' with sequences 157 - 182
07:14:56.603194| [Sync]: {Repl#2} now busy
07:14:56.603599| >> Replicator is busy, progress 2496/2912, 156 docs
07:14:56.609816| [Sync]: {DBWorker#7} Looking up 26 proposed revisions in the db
07:14:56.611585| [Sync]: {DBWorker#7} Responding w/request for 26 revs
07:14:56.621952| >> Replicator is busy, progress 2496/2912, 156 docs
07:14:56.679894| [Sync]: {DBWorker#7} Inserted 26 revs in 4.69ms (5540/sec)
07:14:56.687050| >> Replicator is busy, progress 2496/2912, 156 docs
07:14:56.689408| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='12-cc': {"local":182} ...
07:14:56.696461| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.697330| -------- Creating 28 docs --------
07:14:56.703304| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='13-cc'
07:14:56.713560| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.714565| [Sync]: {Repl#2} now idle
07:14:56.715174| >> Replicator is idle, progress 2912/2912, 182 docs
07:14:56.715688| [Sync]: {DBWorker#6} Notified of 28 db changes #183 ... #210
07:14:56.719529| [Sync]: {Push#5} Found 28 changes: Pusher sending 'proposeChanges' with sequences 183 - 210
07:14:56.720648| [Sync]: {Repl#2} now busy
07:14:56.721356| >> Replicator is busy, progress 2912/3360, 182 docs
07:14:56.727029| [Sync]: {DBWorker#7} Looking up 28 proposed revisions in the db
07:14:56.729054| [Sync]: {DBWorker#7} Responding w/request for 28 revs
07:14:56.799646| [Sync]: {DBWorker#7} Inserted 28 revs in 5.25ms (5333/sec)
07:14:56.809362| [Sync]: {Push#5} Checkpoint now at 200
07:14:56.813068| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='13-cc': {"local":210} ...
07:14:56.814759| -------- Creating 30 docs --------
07:14:56.822268| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.829745| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='14-cc'
07:14:56.843448| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.844753| [Sync]: {Repl#2} now idle
07:14:56.845868| >> Replicator is idle, progress 3360/3360, 210 docs
07:14:56.846423| [Sync]: {DBWorker#6} Notified of 30 db changes #211 ... #240
07:14:56.850796| [Sync]: {Push#5} Found 30 changes: Pusher sending 'proposeChanges' with sequences 211 - 240
07:14:56.852211| [Sync]: {Repl#2} now busy
07:14:56.852780| >> Replicator is busy, progress 3360/3840, 210 docs
07:14:56.859499| [Sync]: {DBWorker#7} Looking up 30 proposed revisions in the db
07:14:56.861995| [Sync]: {DBWorker#7} Responding w/request for 30 revs
07:14:56.888420| >> Replicator is busy, progress 3360/3840, 210 docs
07:14:56.921541| >> Replicator is busy, progress 3360/3840, 210 docs
07:14:56.938559| [Sync]: {DBWorker#7} Inserted 30 revs in 12.04ms (2493/sec)
07:14:56.943450| -------- Creating 32 docs --------
07:14:56.951824| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='14-cc': {"local":240} ...
07:14:56.960042| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:56.967491| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='15-cc'
07:14:56.974387| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:56.975521| [Sync]: {Repl#2} now idle
07:14:56.976670| [Sync]: {DBWorker#6} Notified of 32 db changes #241 ... #272
07:14:56.977331| >> Replicator is idle, progress 3840/3840, 240 docs
07:14:56.981273| [Sync]: {Push#5} Found 32 changes: Pusher sending 'proposeChanges' with sequences 241 - 272
07:14:56.983185| [Sync]: {Repl#2} now busy
07:14:56.985461| >> Replicator is busy, progress 3840/4352, 240 docs
07:14:56.990454| [Sync]: {DBWorker#7} Looking up 32 proposed revisions in the db
07:14:56.992728| [Sync]: {DBWorker#7} Responding w/request for 32 revs
07:14:57.068215| [Sync]: {DBWorker#7} Inserted 32 revs in 11.41ms (2805/sec)
07:14:57.074662| -------- Creating 34 docs --------
07:14:57.080193| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='15-cc': {"local":272} ...
07:14:57.088547| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:57.096098| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='16-cc'
07:14:57.110282| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:57.111114| [Sync]: {Repl#2} now idle
07:14:57.111701| >> Replicator is idle, progress 4352/4352, 272 docs
07:14:57.112165| [Sync]: {DBWorker#6} Notified of 34 db changes #273 ... #306
07:14:57.116872| [Sync]: {Push#5} Found 34 changes: Pusher sending 'proposeChanges' with sequences 273 - 306
07:14:57.118446| [Sync]: {Repl#2} now busy
07:14:57.118844| >> Replicator is busy, progress 4352/4896, 272 docs
07:14:57.121768| >> Replicator is busy, progress 4352/4896, 272 docs
07:14:57.125685| [Sync]: {DBWorker#7} Looking up 34 proposed revisions in the db
07:14:57.127904| [Sync]: {DBWorker#7} Responding w/request for 34 revs
07:14:57.185897| >> Replicator is busy, progress 4352/4896, 272 docs
07:14:57.197574| [Sync]: {DBWorker#7} Inserted 34 revs in 5.62ms (6045/sec)
07:14:57.207709| [Sync]: {Push#5} Checkpoint now at 300
07:14:57.208743| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='16-cc': {"local":306} ...
07:14:57.210926| -------- Creating 36 docs --------
07:14:57.215481| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:57.223173| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='17-cc'
07:14:57.231975| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:57.232611| [Sync]: {Repl#2} now idle
07:14:57.234403| [Sync]: {DBWorker#6} Notified of 36 db changes #307 ... #342
07:14:57.234991| >> Replicator is idle, progress 4896/4896, 306 docs
07:14:57.238512| [Sync]: {Push#5} Found 36 changes: Pusher sending 'proposeChanges' with sequences 307 - 342
07:14:57.240002| [Sync]: {Repl#2} now busy
07:14:57.241163| >> Replicator is busy, progress 4896/5472, 306 docs
07:14:57.246970| [Sync]: {DBWorker#7} Looking up 36 proposed revisions in the db
07:14:57.250584| [Sync]: {DBWorker#7} Responding w/request for 36 revs
07:14:57.330091| [Sync]: {DBWorker#7} Inserted 36 revs in 13.77ms (2614/sec)
07:14:57.332336| -------- Creating 38 docs --------
07:14:57.345728| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='17-cc': {"local":342} ...
07:14:57.352767| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:57.359480| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='18-cc'
07:14:57.367218| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:57.368270| [Sync]: {Repl#2} now idle
07:14:57.369160| [Sync]: {DBWorker#6} Notified of 38 db changes #343 ... #380
07:14:57.369810| >> Replicator is idle, progress 5472/5472, 342 docs
07:14:57.373620| [Sync]: {Push#5} Found 38 changes: Pusher sending 'proposeChanges' with sequences 343 - 380
07:14:57.375043| [Sync]: {Repl#2} now busy
07:14:57.376229| >> Replicator is busy, progress 5472/6080, 342 docs
07:14:57.382382| [Sync]: {DBWorker#7} Looking up 38 proposed revisions in the db
07:14:57.385125| [Sync]: {DBWorker#7} Responding w/request for 38 revs
07:14:57.386310| >> Replicator is busy, progress 5472/6080, 342 docs
07:14:57.441449| >> Replicator is busy, progress 5472/6080, 342 docs
07:14:57.457719| [Sync]: {DBWorker#7} Inserted 38 revs in 6.21ms (6122/sec)
07:14:57.468617| -------- Creating 40 docs --------
07:14:57.469990| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='18-cc': {"local":380} ...
07:14:57.477887| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:57.484666| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='19-cc'
07:14:57.492437| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:57.493003| [Sync]: {Repl#2} now idle
07:14:57.493500| >> Replicator is idle, progress 6080/6080, 380 docs
07:14:57.493901| [Sync]: {DBWorker#6} Notified of 40 db changes #381 ... #420
07:14:57.498110| [Sync]: {Push#5} Found 40 changes: Pusher sending 'proposeChanges' with sequences 381 - 420
07:14:57.499944| [Sync]: {Repl#2} now busy
07:14:57.501024| >> Replicator is busy, progress 6080/6720, 380 docs
07:14:57.506626| [Sync]: {DBWorker#7} Looking up 40 proposed revisions in the db
07:14:57.509322| [Sync]: {DBWorker#7} Responding w/request for 40 revs
07:14:57.580355| [Sync]: {DBWorker#7} Inserted 40 revs in 6.71ms (5965/sec)
07:14:57.590315| [Sync]: {Push#5} Checkpoint now at 400
07:14:57.592554| -------- Creating 42 docs --------
07:14:57.593223| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='19-cc': {"local":420} ...
07:14:57.601177| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:57.607822| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='20-cc'
07:14:57.618062| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:57.618994| [Sync]: {Repl#2} now idle
07:14:57.619642| >> Replicator is idle, progress 6720/6720, 420 docs
07:14:57.620130| [Sync]: {DBWorker#6} Notified of 42 db changes #421 ... #462
07:14:57.624747| [Sync]: {Push#5} Found 42 changes: Pusher sending 'proposeChanges' with sequences 421 - 462
07:14:57.626387| [Sync]: {Repl#2} now busy
07:14:57.626809| >> Replicator is busy, progress 6720/7392, 420 docs
07:14:57.634914| [Sync]: {DBWorker#7} Looking up 42 proposed revisions in the db
07:14:57.638166| [Sync]: {DBWorker#7} Responding w/request for 42 revs
07:14:57.641652| >> Replicator is busy, progress 6720/7392, 420 docs
07:14:57.701897| >> Replicator is busy, progress 6720/7392, 420 docs
07:14:57.718710| [Sync]: {DBWorker#7} Inserted 42 revs in 16.75ms (2507/sec)
07:14:57.720130| -------- Creating 44 docs --------
07:14:57.734926| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='20-cc': {"local":462} ...
07:14:57.742258| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:57.749388| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='21-cc'
07:14:57.752317| [Sync]: {DBWorker#6} Notified of 44 db changes #463 ... #506
07:14:57.757702| [Sync]: {Push#5} Found 44 changes: Pusher sending 'proposeChanges' with sequences 463 - 506
07:14:57.758444| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:57.759297| [Sync]: {Repl#2} now idle
07:14:57.759786| >> Replicator is idle, progress 7392/7392, 462 docs
07:14:57.760272| [Sync]: {Repl#2} now busy
07:14:57.760754| >> Replicator is busy, progress 7392/7392, 462 docs
07:14:57.767240| [Sync]: {DBWorker#7} Looking up 44 proposed revisions in the db
07:14:57.770770| [Sync]: {DBWorker#7} Responding w/request for 44 revs
07:14:57.843392| [Sync]: {DBWorker#7} Inserted 44 revs in 7.30ms (6028/sec)
07:14:57.853979| [Sync]: {Push#5} Checkpoint now at 500
07:14:57.854941| -------- Creating 46 docs --------
07:14:57.855480| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='21-cc': {"local":506} ...
07:14:57.862396| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:57.869255| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='22-cc'
07:14:57.881155| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:57.882176| [Sync]: {Repl#2} now idle
07:14:57.883018| [Sync]: {DBWorker#6} Notified of 46 db changes #507 ... #552
07:14:57.883863| >> Replicator is idle, progress 8096/8096, 506 docs
07:14:57.888382| [Sync]: {Push#5} Found 46 changes: Pusher sending 'proposeChanges' with sequences 507 - 552
07:14:57.890346| [Sync]: {Repl#2} now busy
07:14:57.891078| >> Replicator is busy, progress 8096/8832, 506 docs
07:14:57.897574| [Sync]: {DBWorker#7} Looking up 46 proposed revisions in the db
07:14:57.900822| [Sync]: {DBWorker#7} Responding w/request for 46 revs
07:14:57.902098| >> Replicator is busy, progress 8096/8832, 506 docs
07:14:57.961326| >> Replicator is busy, progress 8096/8832, 506 docs
07:14:57.980707| [Sync]: {DBWorker#7} Inserted 46 revs in 13.44ms (3424/sec)
07:14:57.981745| -------- Creating 48 docs --------
07:14:58.001491| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='22-cc': {"local":552} ...
07:14:58.009714| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.018528| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='23-cc'
07:14:58.023054| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.023813| [Sync]: {Repl#2} now idle
07:14:58.024507| >> Replicator is idle, progress 8832/8832, 552 docs
07:14:58.025071| [Sync]: {DBWorker#6} Notified of 48 db changes #553 ... #600
07:14:58.030079| [Sync]: {Push#5} Found 48 changes: Pusher sending 'proposeChanges' with sequences 553 - 600
07:14:58.031691| [Sync]: {Repl#2} now busy
07:14:58.032411| >> Replicator is busy, progress 8832/9600, 552 docs
07:14:58.038575| [Sync]: {DBWorker#7} Looking up 48 proposed revisions in the db
07:14:58.041345| [Sync]: {DBWorker#7} Responding w/request for 48 revs
07:14:58.113584| [Sync]: {DBWorker#7} Inserted 48 revs in 8.11ms (5922/sec)
07:14:58.123404| -------- Creating 50 docs --------
07:14:58.124616| [Sync]: {Push#5} Checkpoint now at 600
07:14:58.125927| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='23-cc': {"local":600} ...
07:14:58.133041| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.141663| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='24-cc'
07:14:58.152976| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.153697| [Sync]: {Repl#2} now idle
07:14:58.154478| >> Replicator is idle, progress 9600/9600, 600 docs
07:14:58.154907| [Sync]: {DBWorker#6} Notified of 50 db changes #601 ... #650
07:14:58.160358| [Sync]: {Push#5} Found 50 changes: Pusher sending 'proposeChanges' with sequences 601 - 650
07:14:58.161570| >> Replicator is idle, progress 9600/9600, 600 docs
07:14:58.162444| [Sync]: {Repl#2} now busy
07:14:58.162827| >> Replicator is busy, progress 9600/10400, 600 docs
07:14:58.170038| [Sync]: {DBWorker#7} Looking up 50 proposed revisions in the db
07:14:58.173101| [Sync]: {DBWorker#7} Responding w/request for 50 revs
07:14:58.232639| >> Replicator is busy, progress 9600/10400, 600 docs
07:14:58.246155| [Sync]: {DBWorker#7} Inserted 50 revs in 8.43ms (5931/sec)
07:14:58.253631| -------- Creating 52 docs --------
07:14:58.258702| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='24-cc': {"local":650} ...
07:14:58.266078| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.273879| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='25-cc'
07:14:58.286718| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.287858| [Sync]: {Repl#2} now idle
07:14:58.288507| >> Replicator is idle, progress 10400/10400, 650 docs
07:14:58.288971| [Sync]: {DBWorker#6} Notified of 52 db changes #651 ... #702
07:14:58.294200| [Sync]: {Push#5} Found 52 changes: Pusher sending 'proposeChanges' with sequences 651 - 702
07:14:58.296544| [Sync]: {Repl#2} now busy
07:14:58.297625| >> Replicator is busy, progress 10400/11232, 650 docs
07:14:58.303994| [Sync]: {DBWorker#7} Looking up 52 proposed revisions in the db
07:14:58.307204| [Sync]: {DBWorker#7} Responding w/request for 52 revs
07:14:58.381137| [Sync]: {DBWorker#7} Inserted 52 revs in 9.19ms (5659/sec)
07:14:58.386808| -------- Creating 54 docs --------
07:14:58.393858| [Sync]: {Push#5} Checkpoint now at 700
07:14:58.394711| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='25-cc': {"local":702} ...
07:14:58.402330| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.410098| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='26-cc'
07:14:58.421161| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.421809| [Sync]: {Repl#2} now idle
07:14:58.422593| >> Replicator is idle, progress 11232/11232, 702 docs
07:14:58.423175| [Sync]: {DBWorker#6} Notified of 54 db changes #703 ... #756
07:14:58.428988| [Sync]: {Push#5} Found 54 changes: Pusher sending 'proposeChanges' with sequences 703 - 756
07:14:58.430941| [Sync]: {Repl#2} now busy
07:14:58.431462| >> Replicator is busy, progress 11232/12096, 702 docs
07:14:58.432892| >> Replicator is busy, progress 11232/12096, 702 docs
07:14:58.438253| [Sync]: {DBWorker#7} Looking up 54 proposed revisions in the db
07:14:58.441604| [Sync]: {DBWorker#7} Responding w/request for 54 revs
07:14:58.498057| >> Replicator is busy, progress 11232/12096, 702 docs
07:14:58.515350| [Sync]: {DBWorker#7} Inserted 54 revs in 9.26ms (5832/sec)
07:14:58.520989| -------- Creating 56 docs --------
07:14:58.526912| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='26-cc': {"local":756} ...
07:14:58.534558| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.541249| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='27-cc'
07:14:58.556229| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.557400| [Sync]: {Repl#2} now idle
07:14:58.557962| >> Replicator is idle, progress 12096/12096, 756 docs
07:14:58.558406| [Sync]: {DBWorker#6} Notified of 56 db changes #757 ... #812
07:14:58.564254| [Sync]: {Push#5} Found 56 changes: Pusher sending 'proposeChanges' with sequences 757 - 812
07:14:58.566949| [Sync]: {Repl#2} now busy
07:14:58.568118| >> Replicator is busy, progress 12096/12992, 756 docs
07:14:58.574391| [Sync]: {DBWorker#7} Looking up 56 proposed revisions in the db
07:14:58.577962| [Sync]: {DBWorker#7} Responding w/request for 56 revs
07:14:58.652581| [Sync]: {DBWorker#7} Inserted 56 revs in 9.24ms (6061/sec)
07:14:58.656271| -------- Creating 58 docs --------
07:14:58.663348| [Sync]: {Push#5} Checkpoint now at 800
07:14:58.665165| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='27-cc': {"local":812} ...
07:14:58.672751| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.679469| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='28-cc'
07:14:58.692584| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.693280| [Sync]: {Repl#2} now idle
07:14:58.694058| >> Replicator is idle, progress 12992/12992, 812 docs
07:14:58.694515| [Sync]: {DBWorker#6} Notified of 58 db changes #813 ... #870
07:14:58.698337| >> Replicator is idle, progress 12992/12992, 812 docs
07:14:58.700814| [Sync]: {Push#5} Found 58 changes: Pusher sending 'proposeChanges' with sequences 813 - 870
07:14:58.702562| [Sync]: {Repl#2} now busy
07:14:58.703020| >> Replicator is busy, progress 12992/13920, 812 docs
07:14:58.709757| [Sync]: {DBWorker#7} Looking up 58 proposed revisions in the db
07:14:58.713258| [Sync]: {DBWorker#7} Responding w/request for 58 revs
07:14:58.768471| >> Replicator is busy, progress 12992/13920, 812 docs
07:14:58.788720| [Sync]: {DBWorker#7} Inserted 58 revs in 10.24ms (5662/sec)
07:14:58.792417| -------- Creating 60 docs --------
07:14:58.800976| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='28-cc': {"local":870} ...
07:14:58.808874| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.815680| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='29-cc'
07:14:58.829329| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.830493| [Sync]: {Repl#2} now idle
07:14:58.831039| >> Replicator is idle, progress 13920/13920, 870 docs
07:14:58.831468| [Sync]: {DBWorker#6} Notified of 60 db changes #871 ... #930
07:14:58.838067| [Sync]: {Push#5} Found 60 changes: Pusher sending 'proposeChanges' with sequences 871 - 930
07:14:58.840328| [Sync]: {Repl#2} now busy
07:14:58.841203| >> Replicator is busy, progress 13920/14880, 870 docs
07:14:58.847988| [Sync]: {DBWorker#7} Looking up 60 proposed revisions in the db
07:14:58.851934| [Sync]: {DBWorker#7} Responding w/request for 60 revs
07:14:58.928257| [Sync]: {DBWorker#7} Inserted 60 revs in 10.39ms (5774/sec)
07:14:58.929613| -------- Creating 62 docs --------
07:14:58.939145| [Sync]: {Push#5} Checkpoint now at 901
07:14:58.941616| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='29-cc': {"local":930} ...
07:14:58.949087| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:58.955898| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='30-cc'
07:14:58.968685| >> Replicator is busy, progress 14880/14880, 930 docs
07:14:58.970786| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:58.971768| [Sync]: {Repl#2} now idle
07:14:58.972270| >> Replicator is idle, progress 14880/14880, 930 docs
07:14:58.973188| [Sync]: {DBWorker#6} Notified of 62 db changes #931 ... #992
07:14:58.979362| [Sync]: {Push#5} Found 62 changes: Pusher sending 'proposeChanges' with sequences 931 - 992
07:14:58.981283| [Sync]: {Repl#2} now busy
07:14:58.982992| >> Replicator is busy, progress 14880/15872, 930 docs
07:14:58.988143| [Sync]: {DBWorker#7} Looking up 62 proposed revisions in the db
07:14:58.991856| [Sync]: {DBWorker#7} Responding w/request for 62 revs
07:14:59.041896| >> Replicator is busy, progress 14880/15872, 930 docs
07:14:59.070822| -------- Creating 64 docs --------
07:14:59.077697| [Sync]: {DBWorker#7} Inserted 62 revs in 21.76ms (2849/sec)
07:14:59.092803| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='30-cc': {"local":992} ...
07:14:59.100330| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:59.110109| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='31-cc'
07:14:59.115971| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:59.117075| [Sync]: {Repl#2} now idle
07:14:59.118168| [Sync]: {DBWorker#6} Notified of 64 db changes #993 ... #1056
07:14:59.119236| >> Replicator is idle, progress 15872/15872, 992 docs
07:14:59.125208| [Sync]: {Push#5} Found 64 changes: Pusher sending 'proposeChanges' with sequences 993 - 1056
07:14:59.127616| [Sync]: {Repl#2} now busy
07:14:59.128017| >> Replicator is busy, progress 15872/16896, 992 docs
07:14:59.134547| [Sync]: {DBWorker#7} Looking up 64 proposed revisions in the db
07:14:59.139056| [Sync]: {DBWorker#7} Responding w/request for 64 revs
07:14:59.214155| [Sync]: {DBWorker#7} Inserted 64 revs in 11.03ms (5802/sec)
07:14:59.216074| -------- Creating 66 docs --------
07:14:59.222792| [Sync]: {Push#5} Checkpoint now at 1000
07:14:59.227078| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='31-cc': {"local":1056} ...
07:14:59.234731| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:59.242120| >> Replicator is busy, progress 16896/16896, 1056 docs
07:14:59.243282| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='32-cc'
07:14:59.259890| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:59.261366| [Sync]: {Repl#2} now idle
07:14:59.261944| >> Replicator is idle, progress 16896/16896, 1056 docs
07:14:59.262356| [Sync]: {DBWorker#6} Notified of 66 db changes #1057 ... #1122
07:14:59.269137| [Sync]: {Push#5} Found 66 changes: Pusher sending 'proposeChanges' with sequences 1057 - 1122
07:14:59.272132| [Sync]: {Repl#2} now busy
07:14:59.272525| >> Replicator is busy, progress 16896/17952, 1056 docs
07:14:59.279719| [Sync]: {DBWorker#7} Looking up 66 proposed revisions in the db
07:14:59.283824| [Sync]: {DBWorker#7} Responding w/request for 66 revs
07:14:59.329210| >> Replicator is busy, progress 16896/17952, 1056 docs
07:14:59.360233| -------- Creating 68 docs --------
07:14:59.374989| [Sync]: {DBWorker#7} Inserted 66 revs in 25.59ms (2579/sec)
07:14:59.389112| [Sync]: {Push#5} Checkpoint now at 1100
07:14:59.391935| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='32-cc': {"local":1122} ...
07:14:59.398733| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:59.405489| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='33-cc'
07:14:59.419285| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:59.420147| [Sync]: {Repl#2} now idle
07:14:59.420957| [Sync]: {DBWorker#6} Notified of 68 db changes #1123 ... #1190
07:14:59.421880| >> Replicator is idle, progress 17952/17952, 1122 docs
07:14:59.428287| [Sync]: {Push#5} Found 68 changes: Pusher sending 'proposeChanges' with sequences 1123 - 1190
07:14:59.430587| [Sync]: {Repl#2} now busy
07:14:59.430989| >> Replicator is busy, progress 17952/19040, 1122 docs
07:14:59.437969| [Sync]: {DBWorker#7} Looking up 68 proposed revisions in the db
07:14:59.442089| [Sync]: {DBWorker#7} Responding w/request for 68 revs
07:14:59.517701| [Sync]: {DBWorker#7} Inserted 68 revs in 11.13ms (6109/sec)
07:14:59.519788| -------- Creating 70 docs --------
07:14:59.529660| >> Replicator is busy, progress 18272/19040, 1142 docs
07:14:59.534113| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='33-cc': {"local":1190} ...
07:14:59.542935| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:59.549742| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='34-cc'
07:14:59.561048| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:59.561508| [Sync]: {Repl#2} now idle
07:14:59.562365| >> Replicator is idle, progress 19040/19040, 1190 docs
07:14:59.563257| [Sync]: {DBWorker#6} Notified of 70 db changes #1191 ... #1260
07:14:59.571193| [Sync]: {Push#5} Found 70 changes: Pusher sending 'proposeChanges' with sequences 1191 - 1260
07:14:59.574141| [Sync]: {Repl#2} now busy
07:14:59.574776| >> Replicator is busy, progress 19040/20160, 1190 docs
07:14:59.581107| [Sync]: {DBWorker#7} Looking up 70 proposed revisions in the db
07:14:59.584986| [Sync]: {DBWorker#7} Responding w/request for 70 revs
07:14:59.631663| >> Replicator is busy, progress 19040/20160, 1190 docs
07:14:59.661639| -------- Creating 72 docs --------
07:14:59.682050| [Sync]: {DBWorker#7} Inserted 70 revs in 31.32ms (2235/sec)
07:14:59.692126| [Sync]: {Push#5} Checkpoint now at 1200
07:14:59.697774| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='34-cc': {"local":1260} ...
07:14:59.705159| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:59.711885| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='35-cc'
07:14:59.720324| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:59.721306| [Sync]: {Repl#2} now idle
07:14:59.722172| >> Replicator is idle, progress 20160/20160, 1260 docs
07:14:59.722577| [Sync]: {DBWorker#6} Notified of 72 db changes #1261 ... #1332
07:14:59.729591| [Sync]: {Push#5} Found 72 changes: Pusher sending 'proposeChanges' with sequences 1261 - 1332
07:14:59.730211| >> Replicator is idle, progress 20160/20160, 1260 docs
07:14:59.731833| [Sync]: {Repl#2} now busy
07:14:59.732253| >> Replicator is busy, progress 20160/21312, 1260 docs
07:14:59.740368| [Sync]: {DBWorker#7} Looking up 72 proposed revisions in the db
07:14:59.744751| [Sync]: {DBWorker#7} Responding w/request for 72 revs
07:14:59.820896| -------- Creating 74 docs --------
07:14:59.823564| [Sync]: {DBWorker#7} Inserted 72 revs in 12.63ms (5701/sec)
07:14:59.832533| >> Replicator is busy, progress 20480/21312, 1280 docs
07:14:59.837520| [Sync]: {Push#5} Checkpoint now at 1300
07:14:59.839399| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='35-cc': {"local":1332} ...
07:14:59.846368| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:59.854175| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='36-cc'
07:14:59.866894| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:14:59.867778| [Sync]: {Repl#2} now idle
07:14:59.869551| [Sync]: {DBWorker#6} Notified of 74 db changes #1333 ... #1406
07:14:59.869967| >> Replicator is idle, progress 21312/21312, 1332 docs
07:14:59.877173| [Sync]: {Push#5} Found 74 changes: Pusher sending 'proposeChanges' with sequences 1333 - 1406
07:14:59.879245| [Sync]: {Repl#2} now busy
07:14:59.879647| >> Replicator is busy, progress 21312/22496, 1332 docs
07:14:59.886714| [Sync]: {DBWorker#7} Looking up 74 proposed revisions in the db
07:14:59.890824| [Sync]: {DBWorker#7} Responding w/request for 74 revs
07:14:59.933027| >> Replicator is busy, progress 21312/22496, 1332 docs
07:14:59.967422| [Sync]: {DBWorker#7} Inserted 74 revs in 12.31ms (6014/sec)
07:14:59.971064| -------- Creating 76 docs --------
07:14:59.980112| [Sync]: {Push#5} Checkpoint now at 1400
07:14:59.981088| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='36-cc': {"local":1406} ...
07:14:59.987529| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:14:59.994263| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='37-cc'
07:15:00.015195| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:00.015910| [Sync]: {Repl#2} now idle
07:15:00.016596| [Sync]: {DBWorker#6} Notified of 76 db changes #1407 ... #1482
07:15:00.017095| >> Replicator is idle, progress 22496/22496, 1406 docs
07:15:00.024480| [Sync]: {Push#5} Found 76 changes: Pusher sending 'proposeChanges' with sequences 1407 - 1482
07:15:00.026634| [Sync]: {Repl#2} now busy
07:15:00.026991| >> Replicator is busy, progress 22496/23712, 1406 docs
07:15:00.033150| >> Replicator is busy, progress 22496/23712, 1406 docs
07:15:00.033836| [Sync]: {DBWorker#7} Looking up 76 proposed revisions in the db
07:15:00.038531| [Sync]: {DBWorker#7} Responding w/request for 76 revs
07:15:00.115252| -------- Creating 78 docs --------
07:15:00.132209| [Sync]: {DBWorker#7} Inserted 76 revs in 29.87ms (2545/sec)
07:15:00.138499| >> Replicator is busy, progress 22496/23712, 1406 docs
07:15:00.148495| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='37-cc': {"local":1482} ...
07:15:00.155834| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:00.162539| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='38-cc'
07:15:00.177396| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:00.178226| [Sync]: {Repl#2} now idle
07:15:00.179115| [Sync]: {DBWorker#6} Notified of 78 db changes #1483 ... #1560
07:15:00.179850| >> Replicator is idle, progress 23712/23712, 1482 docs
07:15:00.187151| [Sync]: {Push#5} Found 78 changes: Pusher sending 'proposeChanges' with sequences 1483 - 1560
07:15:00.189770| [Sync]: {Repl#2} now busy
07:15:00.190238| >> Replicator is busy, progress 23712/24960, 1482 docs
07:15:00.197037| [Sync]: {DBWorker#7} Looking up 78 proposed revisions in the db
07:15:00.201290| [Sync]: {DBWorker#7} Responding w/request for 78 revs
07:15:00.277436| -------- Creating 80 docs --------
07:15:00.280026| [Sync]: {DBWorker#7} Inserted 78 revs in 13.47ms (5789/sec)
07:15:00.289722| [Sync]: {Push#5} Checkpoint now at 1500
07:15:00.294780| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='38-cc': {"local":1560} ...
07:15:00.301928| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:00.309704| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='39-cc'
07:15:00.330218| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:00.331002| [Sync]: {Repl#2} now idle
07:15:00.331863| >> Replicator is idle, progress 24960/24960, 1560 docs
07:15:00.332492| [Sync]: {DBWorker#6} Notified of 80 db changes #1561 ... #1640
07:15:00.335609| >> Replicator is idle, progress 24960/24960, 1560 docs
07:15:00.340561| [Sync]: {Push#5} Found 80 changes: Pusher sending 'proposeChanges' with sequences 1561 - 1640
07:15:00.342933| [Sync]: {Repl#2} now busy
07:15:00.343286| >> Replicator is busy, progress 24960/26240, 1560 docs
07:15:00.352409| [Sync]: {DBWorker#7} Looking up 80 proposed revisions in the db
07:15:00.357246| [Sync]: {DBWorker#7} Responding w/request for 80 revs
07:15:00.390835| >> Replicator is busy, progress 24960/26240, 1560 docs
07:15:00.430643| -------- Creating 82 docs --------
07:15:00.435428| [Sync]: {DBWorker#7} Inserted 80 revs in 13.69ms (5845/sec)
07:15:00.446872| [Sync]: {Push#5} Checkpoint now at 1600
07:15:00.450882| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='39-cc': {"local":1640} ...
07:15:00.458174| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:00.464892| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='40-cc'
07:15:00.479315| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:00.480137| [Sync]: {Repl#2} now idle
07:15:00.480967| [Sync]: {DBWorker#6} Notified of 82 db changes #1641 ... #1722
07:15:00.481590| >> Replicator is idle, progress 26240/26240, 1640 docs
07:15:00.489456| [Sync]: {Push#5} Found 82 changes: Pusher sending 'proposeChanges' with sequences 1641 - 1722
07:15:00.491802| [Sync]: {Repl#2} now busy
07:15:00.492411| >> Replicator is busy, progress 26240/27552, 1640 docs
07:15:00.499457| [Sync]: {DBWorker#7} Looking up 82 proposed revisions in the db
07:15:00.504573| [Sync]: {DBWorker#7} Responding w/request for 82 revs
07:15:00.579828| -------- Creating 84 docs --------
07:15:00.583924| [Sync]: {DBWorker#7} Inserted 82 revs in 14.02ms (5847/sec)
07:15:00.592864| >> Replicator is busy, progress 26240/27552, 1640 docs
07:15:00.598595| [Sync]: {Push#5} Checkpoint now at 1700
07:15:00.601369| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='40-cc': {"local":1722} ...
07:15:00.609350| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:00.616076| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='41-cc'
07:15:00.630456| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:00.631156| [Sync]: {Repl#2} now idle
07:15:00.632036| >> Replicator is idle, progress 27552/27552, 1722 docs
07:15:00.632478| [Sync]: {DBWorker#6} Notified of 84 db changes #1723 ... #1806
07:15:00.640702| [Sync]: {Push#5} Found 84 changes: Pusher sending 'proposeChanges' with sequences 1723 - 1806
07:15:00.643207| [Sync]: {Repl#2} now busy
07:15:00.643630| >> Replicator is busy, progress 27552/28896, 1722 docs
07:15:00.650697| [Sync]: {DBWorker#7} Looking up 84 proposed revisions in the db
07:15:00.655579| [Sync]: {DBWorker#7} Responding w/request for 84 revs
07:15:00.731018| -------- Creating 86 docs --------
07:15:00.735433| [Sync]: {DBWorker#7} Inserted 84 revs in 14.37ms (5847/sec)
07:15:00.750695| [Sync]: {Push#5} Checkpoint now at 1800
07:15:00.753318| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='41-cc': {"local":1806} ...
07:15:00.760510| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:00.768387| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='42-cc'
07:15:00.783432| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:00.783850| [Sync]: {Repl#2} now idle
07:15:00.784674| >> Replicator is idle, progress 28896/28896, 1806 docs
07:15:00.785673| [Sync]: {DBWorker#6} Notified of 86 db changes #1807 ... #1892
07:15:00.793167| >> Replicator is idle, progress 28896/28896, 1806 docs
07:15:00.794912| [Sync]: {Push#5} Found 86 changes: Pusher sending 'proposeChanges' with sequences 1807 - 1892
07:15:00.797758| [Sync]: {Repl#2} now busy
07:15:00.798470| >> Replicator is busy, progress 28896/30272, 1806 docs
07:15:00.805956| [Sync]: {DBWorker#7} Looking up 86 proposed revisions in the db
07:15:00.811050| [Sync]: {DBWorker#7} Responding w/request for 86 revs
07:15:00.844198| >> Replicator is busy, progress 28896/30272, 1806 docs
07:15:00.884218| -------- Creating 88 docs --------
07:15:00.892355| [Sync]: {DBWorker#7} Inserted 86 revs in 15.08ms (5702/sec)
07:15:00.908838| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='42-cc': {"local":1892} ...
07:15:00.916664| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:00.923502| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='43-cc'
07:15:00.937911| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:00.938480| [Sync]: {Repl#2} now idle
07:15:00.939072| [Sync]: {DBWorker#6} Notified of 88 db changes #1893 ... #1980
07:15:00.939568| >> Replicator is idle, progress 30272/30272, 1892 docs
07:15:00.947671| [Sync]: {Push#5} Found 88 changes: Pusher sending 'proposeChanges' with sequences 1893 - 1980
07:15:00.949930| [Sync]: {Repl#2} now busy
07:15:00.950886| >> Replicator is busy, progress 30272/31680, 1892 docs
07:15:00.959205| [Sync]: {DBWorker#7} Looking up 88 proposed revisions in the db
07:15:00.964271| [Sync]: {DBWorker#7} Responding w/request for 88 revs
07:15:01.038443| -------- Creating 90 docs --------
07:15:01.043569| [Sync]: {DBWorker#7} Inserted 88 revs in 15.04ms (5851/sec)
07:15:01.046180| >> Replicator is busy, progress 30272/31680, 1892 docs
07:15:01.053259| [Sync]: {Push#5} Checkpoint now at 1900
07:15:01.060463| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='43-cc': {"local":1980} ...
07:15:01.068958| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:01.076744| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='44-cc'
07:15:01.097171| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:01.098338| [Sync]: {Repl#2} now idle
07:15:01.098944| >> Replicator is idle, progress 31680/31680, 1980 docs
07:15:01.099354| [Sync]: {DBWorker#6} Notified of 90 db changes #1981 ... #2070
07:15:01.108211| [Sync]: {Push#5} Found 90 changes: Pusher sending 'proposeChanges' with sequences 1981 - 2070
07:15:01.110703| [Sync]: {Repl#2} now busy
07:15:01.111162| >> Replicator is busy, progress 31680/33120, 1980 docs
07:15:01.118420| [Sync]: {DBWorker#7} Looking up 90 proposed revisions in the db
07:15:01.123547| [Sync]: {DBWorker#7} Responding w/request for 90 revs
07:15:01.197632| -------- Creating 92 docs --------
07:15:01.204296| [Sync]: {DBWorker#7} Inserted 90 revs in 15.57ms (5781/sec)
07:15:01.213221| [Sync]: {Push#5} Checkpoint now at 2000
07:15:01.218908| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='44-cc': {"local":2070} ...
07:15:01.227216| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:01.233883| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='45-cc'
07:15:01.246748| >> Replicator is busy, progress 33120/33120, 2070 docs
07:15:01.257131| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:01.257867| [Sync]: {Repl#2} now idle
07:15:01.258620| >> Replicator is idle, progress 33120/33120, 2070 docs
07:15:01.259237| [Sync]: {DBWorker#6} Notified of 92 db changes #2071 ... #2162
07:15:01.268342| [Sync]: {Push#5} Found 92 changes: Pusher sending 'proposeChanges' with sequences 2071 - 2162
07:15:01.270782| [Sync]: {Repl#2} now busy
07:15:01.271235| >> Replicator is busy, progress 33120/34592, 2070 docs
07:15:01.278601| [Sync]: {DBWorker#7} Looking up 92 proposed revisions in the db
07:15:01.283980| [Sync]: {DBWorker#7} Responding w/request for 92 revs
07:15:01.311842| >> Replicator is busy, progress 33120/34592, 2070 docs
07:15:01.357846| -------- Creating 94 docs --------
07:15:01.365234| [Sync]: {DBWorker#7} Inserted 92 revs in 16.27ms (5654/sec)
07:15:01.376815| [Sync]: {Push#5} Checkpoint now at 2100
07:15:01.382428| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='45-cc': {"local":2162} ...
07:15:01.390400| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:01.397100| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='46-cc'
07:15:01.414136| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:01.415150| [Sync]: {Repl#2} now idle
07:15:01.415701| >> Replicator is idle, progress 34592/34592, 2162 docs
07:15:01.416120| [Sync]: {DBWorker#6} Notified of 94 db changes #2163 ... #2256
07:15:01.425447| [Sync]: {Push#5} Found 94 changes: Pusher sending 'proposeChanges' with sequences 2163 - 2256
07:15:01.428231| [Sync]: {Repl#2} now busy
07:15:01.428892| >> Replicator is busy, progress 34592/36096, 2162 docs
07:15:01.435966| [Sync]: {DBWorker#7} Looking up 94 proposed revisions in the db
07:15:01.441713| [Sync]: {DBWorker#7} Responding w/request for 94 revs
07:15:01.512087| >> Replicator is busy, progress 34592/36096, 2162 docs
07:15:01.514034| -------- Creating 96 docs --------
07:15:01.523084| [Sync]: {DBWorker#7} Inserted 94 revs in 16.95ms (5545/sec)
07:15:01.534694| [Sync]: {Push#5} Checkpoint now at 2200
07:15:01.541750| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='46-cc': {"local":2256} ...
07:15:01.548520| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:01.555347| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='47-cc'
07:15:01.572526| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:01.573362| [Sync]: {Repl#2} now idle
07:15:01.574132| >> Replicator is idle, progress 36096/36096, 2256 docs
07:15:01.574796| [Sync]: {DBWorker#6} Notified of 96 db changes #2257 ... #2352
07:15:01.584084| [Sync]: {Push#5} Found 96 changes: Pusher sending 'proposeChanges' with sequences 2257 - 2352
07:15:01.587320| [Sync]: {Repl#2} now busy
07:15:01.587747| >> Replicator is busy, progress 36096/37632, 2256 docs
07:15:01.595192| [Sync]: {DBWorker#7} Looking up 96 proposed revisions in the db
07:15:01.600869| [Sync]: {DBWorker#7} Responding w/request for 96 revs
07:15:01.672249| -------- Creating 98 docs --------
07:15:01.682494| [Sync]: {DBWorker#7} Inserted 96 revs in 17.22ms (5576/sec)
07:15:01.694695| [Sync]: {Push#5} Checkpoint now at 2300
07:15:01.700632| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='47-cc': {"local":2352} ...
07:15:01.707797| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:01.712327| >> Replicator is busy, progress 37632/37632, 2352 docs
07:15:01.716488| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='48-cc'
07:15:01.730557| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:01.731362| [Sync]: {Repl#2} now idle
07:15:01.732411| >> Replicator is idle, progress 37632/37632, 2352 docs
07:15:01.732814| [Sync]: {DBWorker#6} Notified of 98 db changes #2353 ... #2450
07:15:01.742397| [Sync]: {Push#5} Found 98 changes: Pusher sending 'proposeChanges' with sequences 2353 - 2450
07:15:01.745246| [Sync]: {Repl#2} now busy
07:15:01.745734| >> Replicator is busy, progress 37632/39200, 2352 docs
07:15:01.753604| [Sync]: {DBWorker#7} Looking up 98 proposed revisions in the db
07:15:01.759469| [Sync]: {DBWorker#7} Responding w/request for 98 revs
07:15:01.788617| >> Replicator is busy, progress 37632/39200, 2352 docs
07:15:01.830425| -------- Creating 100 docs --------
07:15:01.840971| [Sync]: {DBWorker#7} Inserted 98 revs in 17.43ms (5622/sec)
07:15:01.853729| [Sync]: {Push#5} Checkpoint now at 2400
07:15:01.860015| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='48-cc': {"local":2450} ...
07:15:01.866943| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:01.873714| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='49-cc'
07:15:01.891398| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:01.892201| [Sync]: {Repl#2} now idle
07:15:01.893045| >> Replicator is idle, progress 39200/39200, 2450 docs
07:15:01.893516| [Sync]: {DBWorker#6} Notified of 100 db changes #2451 ... #2550
07:15:01.903138| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2451 - 2550
07:15:01.906361| [Sync]: {Repl#2} now busy
07:15:01.907073| >> Replicator is busy, progress 39200/40800, 2450 docs
07:15:01.914536| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:01.920283| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:01.989653| >> Replicator is busy, progress 39200/40800, 2450 docs
07:15:01.991628| -------- Creating 102 docs --------
07:15:02.002252| [Sync]: {DBWorker#7} Inserted 100 revs in 17.50ms (5715/sec)
07:15:02.016268| [Sync]: {Push#5} Checkpoint now at 2500
07:15:02.023245| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='49-cc': {"local":2550} ...
07:15:02.030136| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:02.037931| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='50-cc'
07:15:02.051607| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:02.052327| [Sync]: {Repl#2} now idle
07:15:02.053599| >> Replicator is idle, progress 40800/40800, 2550 docs
07:15:02.054610| [Sync]: {DBWorker#6} Notified of 100 db changes #2551 ... #2650
07:15:02.064423| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2551 - 2650
07:15:02.065166| [Sync]: {DBWorker#6} Notified of 2 db changes #2651 ... #2652
07:15:02.067390| [Sync]: {Repl#2} now busy
07:15:02.067773| >> Replicator is busy, progress 40800/42400, 2550 docs
07:15:02.068198| [Sync]: {Push#5} Found 2 changes: Pusher sending 'proposeChanges' with sequences 2651 - 2652
07:15:02.074884| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:02.080739| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:02.128199| [Sync]: {DBWorker#7} Looking up 2 proposed revisions in the db
07:15:02.128904| [Sync]: {DBWorker#7} Responding w/request for 2 revs
07:15:02.151816| -------- Creating 104 docs --------
07:15:02.164396| [Sync]: {DBWorker#7} Inserted 102 revs in 18.51ms (5511/sec)
07:15:02.176499| [Sync]: {Push#5} Checkpoint now at 2600
07:15:02.181533| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='50-cc': {"local":2652} ...
07:15:02.188418| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:02.189888| >> Replicator is busy, progress 42432/42432, 2652 docs
07:15:02.195042| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='51-cc'
07:15:02.213668| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:02.214196| [Sync]: {Repl#2} now idle
07:15:02.214739| [Sync]: {DBWorker#6} Notified of 100 db changes #2653 ... #2752
07:15:02.215263| >> Replicator is idle, progress 42432/42432, 2652 docs
07:15:02.224468| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2653 - 2752
07:15:02.224881| [Sync]: {DBWorker#6} Notified of 4 db changes #2753 ... #2756
07:15:02.226895| [Sync]: {Repl#2} now busy
07:15:02.227271| [Sync]: {Push#5} Found 4 changes: Pusher sending 'proposeChanges' with sequences 2753 - 2756
07:15:02.227802| >> Replicator is busy, progress 42432/44032, 2652 docs
07:15:02.235131| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:02.240765| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:02.268009| >> Replicator is busy, progress 42432/44096, 2652 docs
07:15:02.288741| [Sync]: {DBWorker#7} Looking up 4 proposed revisions in the db
07:15:02.289582| [Sync]: {DBWorker#7} Responding w/request for 4 revs
07:15:02.314020| -------- Creating 106 docs --------
07:15:02.323266| [Sync]: {DBWorker#7} Inserted 104 revs in 18.22ms (5709/sec)
07:15:02.336803| [Sync]: {Push#5} Checkpoint now at 2700
07:15:02.343493| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='51-cc': {"local":2756} ...
07:15:02.350614| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:02.357384| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='52-cc'
07:15:02.376465| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:02.376954| [Sync]: {Repl#2} now idle
07:15:02.377746| >> Replicator is idle, progress 44096/44096, 2756 docs
07:15:02.378356| [Sync]: {DBWorker#6} Notified of 100 db changes #2757 ... #2856
07:15:02.388029| [Sync]: {DBWorker#6} Notified of 6 db changes #2857 ... #2862
07:15:02.388687| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2757 - 2856
07:15:02.391422| [Sync]: {Repl#2} now busy
07:15:02.391864| >> Replicator is busy, progress 44096/45696, 2756 docs
07:15:02.392330| [Sync]: {Push#5} Found 6 changes: Pusher sending 'proposeChanges' with sequences 2857 - 2862
07:15:02.399317| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:02.405253| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:02.428192| >> Replicator is busy, progress 44096/45792, 2756 docs
07:15:02.454044| [Sync]: {DBWorker#7} Looking up 6 proposed revisions in the db
07:15:02.455369| [Sync]: {DBWorker#7} Responding w/request for 6 revs
07:15:02.468289| >> Replicator is busy, progress 44096/45792, 2756 docs
07:15:02.477239| -------- Creating 108 docs --------
07:15:02.490030| [Sync]: {DBWorker#7} Inserted 106 revs in 18.67ms (5679/sec)
07:15:02.503160| [Sync]: {Push#5} Checkpoint now at 2801
07:15:02.510153| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='52-cc': {"local":2862} ...
07:15:02.517698| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:02.524482| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='53-cc'
07:15:02.540440| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:02.541270| [Sync]: {Repl#2} now idle
07:15:02.541953| >> Replicator is idle, progress 45792/45792, 2862 docs
07:15:02.542420| [Sync]: {DBWorker#6} Notified of 100 db changes #2863 ... #2962
07:15:02.552142| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2863 - 2962
07:15:02.552539| [Sync]: {DBWorker#6} Notified of 8 db changes #2963 ... #2970
07:15:02.554690| [Sync]: {Repl#2} now busy
07:15:02.555104| [Sync]: {Push#5} Found 8 changes: Pusher sending 'proposeChanges' with sequences 2963 - 2970
07:15:02.555646| >> Replicator is busy, progress 45792/47392, 2862 docs
07:15:02.562372| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:02.567960| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:02.592787| >> Replicator is busy, progress 45792/47520, 2862 docs
07:15:02.613895| [Sync]: {DBWorker#7} Looking up 8 proposed revisions in the db
07:15:02.614864| [Sync]: {DBWorker#7} Responding w/request for 8 revs
07:15:02.640429| -------- Creating 110 docs --------
07:15:02.651151| [Sync]: {DBWorker#7} Inserted 108 revs in 18.69ms (5779/sec)
07:15:02.664092| [Sync]: {Push#5} Checkpoint now at 2900
07:15:02.668610| >> Replicator is busy, progress 46992/47520, 2937 docs
07:15:02.672155| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='53-cc': {"local":2970} ...
07:15:02.678892| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:02.685719| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='54-cc'
07:15:02.703430| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:02.703994| [Sync]: {Repl#2} now idle
07:15:02.704439| >> Replicator is idle, progress 47520/47520, 2970 docs
07:15:02.705141| [Sync]: {DBWorker#6} Notified of 100 db changes #2971 ... #3070
07:15:02.714841| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2971 - 3070
07:15:02.715260| [Sync]: {DBWorker#6} Notified of 10 db changes #3071 ... #3080
07:15:02.717496| [Sync]: {Repl#2} now busy
07:15:02.718041| >> Replicator is busy, progress 47520/49120, 2970 docs
07:15:02.718565| [Sync]: {Push#5} Found 10 changes: Pusher sending 'proposeChanges' with sequences 3071 - 3080
07:15:02.725818| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:02.731451| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:02.755649| >> Replicator is busy, progress 47520/49280, 2970 docs
07:15:02.781225| [Sync]: {DBWorker#7} Looking up 10 proposed revisions in the db
07:15:02.782406| [Sync]: {DBWorker#7} Responding w/request for 10 revs
07:15:02.793641| >> Replicator is busy, progress 47520/49280, 2970 docs
07:15:02.803640| -------- Creating 112 docs --------
07:15:02.814261| [Sync]: {DBWorker#7} Inserted 101 revs in 17.53ms (5761/sec)
07:15:02.825877| [Sync]: {Push#5} Checkpoint now at 3000
07:15:02.849628| [Sync]: {DBWorker#7} Inserted 9 revs in 1.86ms (4848/sec)
07:15:02.857132| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='54-cc': {"local":3080} ...
07:15:02.864199| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:02.867997| [Sync]: {DBWorker#6} Notified of 100 db changes #3081 ... #3180
07:15:02.868769| >> Replicator is busy, progress 49280/49280, 3080 docs
07:15:02.871108| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='55-cc'
07:15:02.878667| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3081 - 3180
07:15:02.879127| [Sync]: {DBWorker#6} Notified of 12 db changes #3181 ... #3192
07:15:02.881236| [Sync]: {Push#5} Found 12 changes: Pusher sending 'proposeChanges' with sequences 3181 - 3192
07:15:02.881679| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:02.889849| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:02.895421| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:02.918800| >> Replicator is busy, progress 49280/51072, 3080 docs
07:15:02.943545| [Sync]: {DBWorker#7} Looking up 12 proposed revisions in the db
07:15:02.944946| [Sync]: {DBWorker#7} Responding w/request for 12 revs
07:15:02.970932| -------- Creating 114 docs --------
07:15:02.980466| [Sync]: {DBWorker#7} Inserted 108 revs in 19.58ms (5516/sec)
07:15:02.990748| [Sync]: {Push#5} Checkpoint now at 3100
07:15:02.994132| >> Replicator is busy, progress 49584/51072, 3099 docs
07:15:03.012897| [Sync]: {DBWorker#7} Inserted 4 revs in 0.94ms (4233/sec)
07:15:03.019425| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='55-cc': {"local":3192} ...
07:15:03.027402| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:03.034163| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='56-cc'
07:15:03.037606| [Sync]: {DBWorker#6} Notified of 100 db changes #3193 ... #3292
07:15:03.048398| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3193 - 3292
07:15:03.049141| [Sync]: {DBWorker#6} Notified of 14 db changes #3293 ... #3306
07:15:03.051359| [Sync]: {Push#5} Found 14 changes: Pusher sending 'proposeChanges' with sequences 3293 - 3306
07:15:03.052418| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:03.060163| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:03.065752| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:03.069019| >> Replicator is busy, progress 51072/52896, 3192 docs
07:15:03.112847| [Sync]: {DBWorker#7} Looking up 14 proposed revisions in the db
07:15:03.114187| [Sync]: {DBWorker#7} Responding w/request for 14 revs
07:15:03.119079| >> Replicator is busy, progress 51072/52896, 3192 docs
07:15:03.141053| -------- Creating 116 docs --------
07:15:03.149049| [Sync]: {DBWorker#7} Inserted 110 revs in 18.94ms (5808/sec)
07:15:03.157733| [Sync]: {Push#5} Checkpoint now at 3200
07:15:03.166918| [Sync]: {Push#5} Checkpoint now at 3300
07:15:03.182114| [Sync]: {DBWorker#7} Inserted 4 revs in 0.97ms (4144/sec)
07:15:03.188890| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='56-cc': {"local":3306} ...
07:15:03.194145| >> Replicator is busy, progress 52896/52896, 3306 docs
07:15:03.195584| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:03.202305| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='57-cc'
07:15:03.209907| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:03.210354| [Sync]: {Repl#2} now idle
07:15:03.210878| [Sync]: {DBWorker#6} Notified of 100 db changes #3307 ... #3406
07:15:03.211400| >> Replicator is idle, progress 52896/52896, 3306 docs
07:15:03.220838| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3307 - 3406
07:15:03.221599| [Sync]: {DBWorker#6} Notified of 16 db changes #3407 ... #3422
07:15:03.224596| [Sync]: {Repl#2} now busy
07:15:03.225084| [Sync]: {Push#5} Found 16 changes: Pusher sending 'proposeChanges' with sequences 3407 - 3422
07:15:03.225602| >> Replicator is busy, progress 52896/54496, 3306 docs
07:15:03.232328| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:03.237923| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:03.285907| [Sync]: {DBWorker#7} Looking up 16 proposed revisions in the db
07:15:03.287799| [Sync]: {DBWorker#7} Responding w/request for 16 revs
07:15:03.310277| -------- Creating 118 docs --------
07:15:03.319312| >> Replicator is busy, progress 52896/54752, 3306 docs
07:15:03.322419| [Sync]: {DBWorker#7} Inserted 107 revs in 19.10ms (5603/sec)
07:15:03.340789| [Sync]: {Push#5} Checkpoint now at 3400
07:15:03.356373| [Sync]: {DBWorker#7} Inserted 9 revs in 2.01ms (4487/sec)
07:15:03.363305| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='57-cc': {"local":3422} ...
07:15:03.371849| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:03.378207| [Sync]: {DBWorker#6} Notified of 100 db changes #3423 ... #3522
07:15:03.379580| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='58-cc'
07:15:03.388589| [Sync]: {DBWorker#6} Notified of 18 db changes #3523 ... #3540
07:15:03.389172| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3423 - 3522
07:15:03.391273| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:03.391660| [Sync]: {Repl#2} now idle
07:15:03.392157| >> Replicator is idle, progress 54752/54752, 3422 docs
07:15:03.392574| [Sync]: {Push#5} Found 18 changes: Pusher sending 'proposeChanges' with sequences 3523 - 3540
07:15:03.393058| [Sync]: {Repl#2} now busy
07:15:03.393530| >> Replicator is busy, progress 54752/54752, 3422 docs
07:15:03.400361| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:03.405972| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:03.425476| >> Replicator is busy, progress 54752/56640, 3422 docs
07:15:03.452404| [Sync]: {DBWorker#7} Looking up 18 proposed revisions in the db
07:15:03.453932| [Sync]: {DBWorker#7} Responding w/request for 18 revs
07:15:03.480476| -------- Creating 120 docs --------
07:15:03.491906| [Sync]: {DBWorker#7} Inserted 111 revs in 20.37ms (5450/sec)
07:15:03.507935| [Sync]: {Push#5} Checkpoint now at 3500
07:15:03.519566| >> Replicator is busy, progress 56528/56640, 3533 docs
07:15:03.524269| [Sync]: {DBWorker#7} Inserted 7 revs in 1.67ms (4187/sec)
07:15:03.531296| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='58-cc': {"local":3540} ...
07:15:03.538059| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:03.545758| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='59-cc'
07:15:03.548979| [Sync]: {DBWorker#6} Notified of 100 db changes #3541 ... #3640
07:15:03.559098| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3541 - 3640
07:15:03.559778| [Sync]: {DBWorker#6} Notified of 20 db changes #3641 ... #3660
07:15:03.562764| [Sync]: {Push#5} Found 20 changes: Pusher sending 'proposeChanges' with sequences 3641 - 3660
07:15:03.563253| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:03.569724| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:03.575466| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:03.593679| >> Replicator is busy, progress 56640/58560, 3540 docs
07:15:03.623354| [Sync]: {DBWorker#7} Looking up 20 proposed revisions in the db
07:15:03.625365| [Sync]: {DBWorker#7} Responding w/request for 20 revs
07:15:03.625895| >> Replicator is busy, progress 56640/58560, 3540 docs
07:15:03.651688| -------- Creating 122 docs --------
07:15:03.657637| [Sync]: {DBWorker#7} Inserted 103 revs in 17.91ms (5753/sec)
07:15:03.670176| [Sync]: {Push#5} Checkpoint now at 3600
07:15:03.694011| [Sync]: {DBWorker#7} Inserted 17 revs in 3.23ms (5268/sec)
07:15:03.701667| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='59-cc': {"local":3660} ...
07:15:03.708203| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:03.714949| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='60-cc'
07:15:03.719806| >> Replicator is busy, progress 58560/58560, 3660 docs
07:15:03.743593| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:03.744439| [Sync]: {Repl#2} now idle
07:15:03.745218| >> Replicator is idle, progress 58560/58560, 3660 docs
07:15:03.745804| [Sync]: {DBWorker#6} Notified of 100 db changes #3661 ... #3760
07:15:03.756246| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3661 - 3760
07:15:03.757307| [Sync]: {DBWorker#6} Notified of 22 db changes #3761 ... #3782
07:15:03.759383| [Sync]: {Repl#2} now busy
07:15:03.759838| >> Replicator is busy, progress 58560/60160, 3660 docs
07:15:03.760316| [Sync]: {Push#5} Found 22 changes: Pusher sending 'proposeChanges' with sequences 3761 - 3782
07:15:03.766821| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:03.772658| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:03.823265| [Sync]: {DBWorker#7} Looking up 22 proposed revisions in the db
07:15:03.825016| [Sync]: {DBWorker#7} Responding w/request for 22 revs
07:15:03.825965| >> Replicator is busy, progress 58560/60512, 3660 docs
07:15:03.840273| -------- Creating 124 docs --------
07:15:03.857446| [Sync]: {DBWorker#7} Inserted 100 revs in 19.44ms (5144/sec)
07:15:03.869457| [Sync]: {Push#5} Checkpoint now at 3700
07:15:03.893189| [Sync]: {DBWorker#7} Inserted 22 revs in 4.13ms (5321/sec)
07:15:03.901331| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='60-cc': {"local":3782} ...
07:15:03.908464| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:03.913247| [Sync]: {DBWorker#6} Notified of 100 db changes #3783 ... #3882
07:15:03.915220| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='61-cc'
07:15:03.923669| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3783 - 3882
07:15:03.924096| [Sync]: {DBWorker#6} Notified of 24 db changes #3883 ... #3906
07:15:03.927052| [Sync]: {Push#5} Found 24 changes: Pusher sending 'proposeChanges' with sequences 3883 - 3906
07:15:03.927684| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:03.934106| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:03.939798| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:03.960223| >> Replicator is busy, progress 60512/62496, 3782 docs
07:15:03.990131| [Sync]: {DBWorker#7} Looking up 24 proposed revisions in the db
07:15:03.991895| [Sync]: {DBWorker#7} Responding w/request for 24 revs
07:15:04.016149| -------- Creating 126 docs --------
07:15:04.022994| [Sync]: {DBWorker#7} Inserted 100 revs in 18.78ms (5325/sec)
07:15:04.027309| >> Replicator is busy, progress 60512/62496, 3782 docs
07:15:04.033476| [Sync]: {Push#5} Checkpoint now at 3800
07:15:04.059753| [Sync]: {DBWorker#7} Inserted 24 revs in 4.50ms (5335/sec)
07:15:04.067982| [Sync]: {Push#5} Checkpoint now at 3904
07:15:04.068992| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='61-cc': {"local":3906} ...
07:15:04.075665| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:04.082420| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='62-cc'
07:15:04.089768| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:04.090221| [Sync]: {Repl#2} now idle
07:15:04.090813| >> Replicator is idle, progress 62496/62496, 3906 docs
07:15:04.091266| [Sync]: {DBWorker#6} Notified of 100 db changes #3907 ... #4006
07:15:04.101667| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3907 - 4006
07:15:04.102371| [Sync]: {DBWorker#6} Notified of 26 db changes #4007 ... #4032
07:15:04.104712| [Sync]: {Repl#2} now busy
07:15:04.105125| >> Replicator is busy, progress 62496/64096, 3906 docs
07:15:04.107261| [Sync]: {Push#5} Found 26 changes: Pusher sending 'proposeChanges' with sequences 4007 - 4032
07:15:04.112246| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:04.117945| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:04.168308| [Sync]: {DBWorker#7} Looking up 26 proposed revisions in the db
07:15:04.170175| [Sync]: {DBWorker#7} Responding w/request for 26 revs
07:15:04.190571| -------- Creating 128 docs --------
07:15:04.201891| [Sync]: {DBWorker#7} Inserted 100 revs in 18.49ms (5407/sec)
07:15:04.219500| [Sync]: {Push#5} Checkpoint now at 4002
07:15:04.228447| >> Replicator is busy, progress 64096/64512, 4006 docs
07:15:04.239580| [Sync]: {DBWorker#7} Inserted 26 revs in 5.10ms (5095/sec)
07:15:04.250284| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='62-cc': {"local":4032} ...
07:15:04.257981| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:04.264647| [Sync]: {DBWorker#6} Notified of 100 db changes #4033 ... #4132
07:15:04.265345| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='63-cc'
07:15:04.275500| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4033 - 4132
07:15:04.276220| [Sync]: {DBWorker#6} Notified of 28 db changes #4133 ... #4160
07:15:04.279620| [Sync]: {Push#5} Found 28 changes: Pusher sending 'proposeChanges' with sequences 4133 - 4160
07:15:04.280015| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:04.286502| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:04.292339| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:04.305602| >> Replicator is busy, progress 64512/66560, 4032 docs
07:15:04.341345| [Sync]: {DBWorker#7} Looking up 28 proposed revisions in the db
07:15:04.343341| [Sync]: {DBWorker#7} Responding w/request for 28 revs
07:15:04.367583| -------- Creating 130 docs --------
07:15:04.374648| [Sync]: {DBWorker#7} Inserted 101 revs in 17.96ms (5623/sec)
07:15:04.387542| [Sync]: {Push#5} Checkpoint now at 4100
07:15:04.412661| [Sync]: {DBWorker#7} Inserted 27 revs in 4.97ms (5433/sec)
07:15:04.421433| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='63-cc': {"local":4160} ...
07:15:04.428687| >> Replicator is busy, progress 66560/66560, 4160 docs
07:15:04.430067| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:04.436846| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='64-cc'
07:15:04.443449| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:04.443927| [Sync]: {Repl#2} now idle
07:15:04.444464| >> Replicator is idle, progress 66560/66560, 4160 docs
07:15:04.444899| [Sync]: {DBWorker#6} Notified of 100 db changes #4161 ... #4260
07:15:04.454792| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4161 - 4260
07:15:04.455620| [Sync]: {DBWorker#6} Notified of 30 db changes #4261 ... #4290
07:15:04.457979| [Sync]: {Repl#2} now busy
07:15:04.458505| >> Replicator is busy, progress 66560/68160, 4160 docs
07:15:04.460252| [Sync]: {Push#5} Found 30 changes: Pusher sending 'proposeChanges' with sequences 4261 - 4290
07:15:04.465857| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:04.471609| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:04.505929| >> Replicator is busy, progress 66560/68640, 4160 docs
07:15:04.518683| [Sync]: {DBWorker#7} Looking up 30 proposed revisions in the db
07:15:04.520956| [Sync]: {DBWorker#7} Responding w/request for 30 revs
07:15:04.543803| -------- Creating 132 docs --------
07:15:04.555596| [Sync]: {DBWorker#7} Inserted 108 revs in 19.75ms (5468/sec)
07:15:04.569063| [Sync]: {Push#5} Checkpoint now at 4200
07:15:04.591028| [Sync]: {DBWorker#7} Inserted 22 revs in 4.13ms (5327/sec)
07:15:04.598939| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='64-cc': {"local":4290} ...
07:15:04.605347| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:04.612095| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='65-cc'
07:15:04.619789| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:04.620205| [Sync]: {Repl#2} now idle
07:15:04.620671| >> Replicator is idle, progress 68640/68640, 4290 docs
07:15:04.621056| [Sync]: {DBWorker#6} Notified of 100 db changes #4291 ... #4390
07:15:04.631427| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4291 - 4390
07:15:04.631844| [Sync]: {DBWorker#6} Notified of 32 db changes #4391 ... #4422
07:15:04.633916| [Sync]: {Repl#2} now busy
07:15:04.634344| >> Replicator is busy, progress 68640/70240, 4290 docs
07:15:04.635765| [Sync]: {Push#5} Found 32 changes: Pusher sending 'proposeChanges' with sequences 4391 - 4422
07:15:04.641904| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:04.647322| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:04.659977| >> Replicator is busy, progress 68640/70752, 4290 docs
07:15:04.694907| [Sync]: {DBWorker#7} Looking up 32 proposed revisions in the db
07:15:04.697072| [Sync]: {DBWorker#7} Responding w/request for 32 revs
07:15:04.706029| >> Replicator is busy, progress 68640/70752, 4290 docs
07:15:04.720025| -------- Creating 134 docs --------
07:15:04.732822| [Sync]: {DBWorker#7} Inserted 112 revs in 19.73ms (5676/sec)
07:15:04.742565| [Sync]: {Push#5} Checkpoint now at 4300
07:15:04.753765| [Sync]: {Push#5} Checkpoint now at 4400
07:15:04.768008| [Sync]: {DBWorker#7} Inserted 20 revs in 3.88ms (5158/sec)
07:15:04.776507| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='65-cc': {"local":4422} ...
07:15:04.783515| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:04.790316| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='66-cc'
07:15:04.797291| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:04.797719| [Sync]: {Repl#2} now idle
07:15:04.798187| >> Replicator is idle, progress 70752/70752, 4422 docs
07:15:04.798568| [Sync]: {DBWorker#6} Notified of 100 db changes #4423 ... #4522
07:15:04.808503| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4423 - 4522
07:15:04.809149| [Sync]: {DBWorker#6} Notified of 34 db changes #4523 ... #4556
07:15:04.811490| [Sync]: {Repl#2} now busy
07:15:04.811872| >> Replicator is busy, progress 70752/72352, 4422 docs
07:15:04.813680| [Sync]: {Push#5} Found 34 changes: Pusher sending 'proposeChanges' with sequences 4523 - 4556
07:15:04.819144| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:04.824886| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:04.835237| >> Replicator is busy, progress 70752/72896, 4422 docs
07:15:04.874275| [Sync]: {DBWorker#7} Looking up 34 proposed revisions in the db
07:15:04.876903| [Sync]: {DBWorker#7} Responding w/request for 34 revs
07:15:04.897273| -------- Creating 136 docs --------
07:15:04.906722| >> Replicator is busy, progress 70752/72896, 4422 docs
07:15:04.909919| [Sync]: {DBWorker#7} Inserted 101 revs in 19.62ms (5149/sec)
07:15:04.926487| [Sync]: {Push#5} Checkpoint now at 4500
07:15:04.947431| [Sync]: {DBWorker#7} Inserted 33 revs in 6.08ms (5424/sec)
07:15:04.957719| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='66-cc': {"local":4556} ...
07:15:04.964745| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:04.971509| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='67-cc'
07:15:04.979079| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:04.979529| [Sync]: {Repl#2} now idle
07:15:04.980070| [Sync]: {DBWorker#6} Notified of 100 db changes #4557 ... #4656
07:15:04.980569| >> Replicator is idle, progress 72896/72896, 4556 docs
07:15:04.990295| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4557 - 4656
07:15:04.990756| [Sync]: {DBWorker#6} Notified of 36 db changes #4657 ... #4692
07:15:04.992861| [Sync]: {Repl#2} now busy
07:15:04.993328| >> Replicator is busy, progress 72896/74496, 4556 docs
07:15:04.994872| [Sync]: {Push#5} Found 36 changes: Pusher sending 'proposeChanges' with sequences 4657 - 4692
07:15:05.001394| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:05.007640| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:05.012468| >> Replicator is busy, progress 72896/75072, 4556 docs
07:15:05.056496| [Sync]: {DBWorker#7} Looking up 36 proposed revisions in the db
07:15:05.059217| [Sync]: {DBWorker#7} Responding w/request for 36 revs
07:15:05.079481| -------- Creating 138 docs --------
07:15:05.093913| [Sync]: {DBWorker#7} Inserted 105 revs in 20.23ms (5190/sec)
07:15:05.106097| [Sync]: {Push#5} Checkpoint now at 4600
07:15:05.111227| >> Replicator is busy, progress 73584/75072, 4599 docs
07:15:05.130258| [Sync]: {DBWorker#7} Inserted 31 revs in 5.67ms (5463/sec)
07:15:05.139761| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='67-cc': {"local":4692} ...
07:15:05.147986| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:05.154732| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='68-cc'
07:15:05.162452| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:05.162901| [Sync]: {Repl#2} now idle
07:15:05.163449| [Sync]: {DBWorker#6} Notified of 100 db changes #4693 ... #4792
07:15:05.163938| >> Replicator is idle, progress 75072/75072, 4692 docs
07:15:05.174024| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4693 - 4792
07:15:05.174467| [Sync]: {DBWorker#6} Notified of 38 db changes #4793 ... #4830
07:15:05.176520| [Sync]: {Repl#2} now busy
07:15:05.176986| >> Replicator is busy, progress 75072/76672, 4692 docs
07:15:05.179098| [Sync]: {Push#5} Found 38 changes: Pusher sending 'proposeChanges' with sequences 4793 - 4830
07:15:05.184608| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:05.189990| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:05.193667| >> Replicator is busy, progress 75072/77280, 4692 docs
07:15:05.212714| >> Replicator is busy, progress 75072/77280, 4692 docs
07:15:05.241008| [Sync]: {DBWorker#7} Looking up 38 proposed revisions in the db
07:15:05.243486| [Sync]: {DBWorker#7} Responding w/request for 38 revs
07:15:05.263504| -------- Creating 140 docs --------
07:15:05.275493| [Sync]: {DBWorker#7} Inserted 100 revs in 20.75ms (4820/sec)
07:15:05.284893| [Sync]: {Push#5} Checkpoint now at 4700
07:15:05.307825| >> Replicator is busy, progress 76672/77280, 4792 docs
07:15:05.317932| [Sync]: {DBWorker#7} Inserted 38 revs in 9.11ms (4171/sec)
07:15:05.322591| [Sync]: {Push#5} Checkpoint now at 4800
07:15:05.326739| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='68-cc': {"local":4830} ...
07:15:05.333220| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:05.340111| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='69-cc'
07:15:05.346815| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:05.347238| [Sync]: {Repl#2} now idle
07:15:05.347715| >> Replicator is idle, progress 77280/77280, 4830 docs
07:15:05.348242| [Sync]: {DBWorker#6} Notified of 100 db changes #4831 ... #4930
07:15:05.358435| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4831 - 4930
07:15:05.358865| [Sync]: {DBWorker#6} Notified of 40 db changes #4931 ... #4970
07:15:05.360945| [Sync]: {Repl#2} now busy
07:15:05.361620| >> Replicator is busy, progress 77280/78880, 4830 docs
07:15:05.363266| [Sync]: {Push#5} Found 40 changes: Pusher sending 'proposeChanges' with sequences 4931 - 4970
07:15:05.368863| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
07:15:05.374331| [Sync]: {DBWorker#7} Responding w/request for 100 revs
07:15:05.377931| >> Replicator is busy, progress 77280/79520, 4830 docs
07:15:05.413042| >> Replicator is busy, progress 77280/79520, 4830 docs
07:15:05.424973| [Sync]: {DBWorker#7} Looking up 40 proposed revisions in the db
07:15:05.427804| [Sync]: {DBWorker#7} Responding w/request for 40 revs
07:15:05.446958| -------- Creating 142 docs --------
07:15:05.458653| [Sync]: {DBWorker#7} Inserted 100 revs in 19.67ms (5083/sec)
07:15:05.473758| [Sync]: {Push#5} Checkpoint now at 4900
07:15:05.499289| [Sync]: {DBWorker#7} Inserted 40 revs in 7.22ms (5537/sec)
07:15:05.508290| >> Replicator is busy, progress 79056/79520, 4941 docs
07:15:05.510532| [Sync]: {Repl#2} Saving remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= with rev='69-cc': {"local":4970} ...
07:15:05.517460| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y='
07:15:05.524267| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= as rev='70-cc'
07:15:05.531160| -------- Done creating docs --------
07:15:05.531491| [Sync]: {DBWorker#6} Saved local checkpoint cp-V3t46f1p7rZ7kO3f/R8I/a/st/Y= to db
07:15:05.532016| [DB]: Closing DataFile
07:15:05.532500| [Sync]: {Repl#2} now idle
07:15:05.532971| >> Replicator is idle, progress 79520/79520, 4970 docs
07:15:05.533387| >> Stopping idle replicator...
07:15:05.533777| [Sync]: {DBWorker#6} Notified of 100 db changes #4971 ... #5070
07:15:05.534263| [Sync]: {Repl#2} Told to stop!
07:15:05.534768| [BLIP]: {Connection#1} Close connection
07:15:05.535961| [Sync]: {Repl#2} now busy
07:15:05.536478| [BLIP]: {Connection#1} Closed with WebSocket status 1000:
07:15:05.536965| >> Replicator is busy, progress 79520/79520, 4970 docs
07:15:05.537360| [Sync]: {Repl#2} Connection closed with WebSocket status 1000: "" (state=3)
07:15:05.537851| >> Replicator closed with code=0/1000, message=
07:15:05.541149| [BLIP]: {Connection#3} Closed with WebSocket status 1000:
07:15:05.541674| [Sync]: {Repl#4} Connection closed with WebSocket status 1000: "" (state=2)
07:15:05.542335| [Sync]: {Repl#4} now stopped
07:15:05.542782| [BLIP]: {BLIPIO#9}==> class litecore::blip::BLIPIO 000001F19034BB78
07:15:05.543206| [BLIP]: {BLIPIO#9} ~BLIPIO: Sent 41943 bytes, rcvd 107309. Max outbox depth was 1, avg 1.00
07:15:05.544520| [Sync]: {DBWorker#6} Notified of 42 db changes #5071 ... #5112
07:15:05.549268| [BLIP]: {BLIPIO#10}==> class litecore::blip::BLIPIO 000001F190303D08
07:15:05.549609| [BLIP]: {BLIPIO#10} ~BLIPIO: Sent 107309 bytes, rcvd 41943. Max outbox depth was 1, avg 1.00
07:15:05.550345| [Sync]: {Repl#2} now stopped
07:15:05.550846| >> Replicator is stopped, progress 79520/79520, 4970 docs
07:15:05.583138| >>> Replication complete <<<
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
CppTests.exe is a Catch v1.5.6 host application.
Run with -? for options
-----------------------------------------------------------------------------------------------------------------------
Continuous Fast Push
-----------------------------------------------------------------------------------------------------------------------
C:\Users\borrr\Development\couchbase-lite-net-ee\couchbase-lite-core\Replicator\tests\ReplicatorLoopbackTest.cc(384)
.......................................................................................................................
c:\users\borrr\development\couchbase-lite-net-ee\couchbase-lite-core\replicator\tests\ReplicatorLoopbackTest.hh(94): FAILED:
CHECK( _statusReceived.progress.documentCount == _expectedDocumentCount )
with expansion:
4970 == 5112
07:15:05.599039| [DB]: Closing DataFile
07:15:05.618533| [DB]: Deleting database file C:\tmp\Litecore_C_Tests\cbl_core_test_2\db.sqlite3 (with -wal and -shm)
07:15:05.620979| [DB]: Closing DataFile
07:15:05.642329| [DB]: Deleting database file C:\tmp\Litecore_C_Tests\cbl_core_test\db.sqlite3 (with -wal and -shm)
08:21:19.239510| This is LiteCore built from master branch, commit 662556b5+CHANGES on Feb 23 2018 15:43:08 ... short version 662556b5+
08:21:19.241572| Beginning logging to C:\tmp\Litecore_C_Tests\LiteCoreAPITests.c4log
SQLite, rev-trees
08:21:19.248385| [DB]: Opening DataFile C:\tmp\Litecore_C_Tests\cbl_core_test\db.sqlite3
08:21:19.287996| [DB]: Opening DataFile C:\tmp\Litecore_C_Tests\cbl_core_test_2\db.sqlite3
08:21:19.397709| [DB]: Opening DataFile C:\tmp\Litecore_C_Tests\cbl_core_test\db.sqlite3
08:21:19.445087| [BLIP]: {Connection#1}==> class litecore::blip::Connection ->ws:srv/
08:21:19.445600| [BLIP]: {Connection#1} Opening connection...
08:21:19.446069| [Actor]: Starting Scheduler<00000264116A6070> with 4 threads
08:21:19.469531| [Sync]: {Repl#2}==> class litecore::repl::Replicator C:\tmp\Litecore_C_Tests\cbl_core_test\ ->ws:srv/
08:21:19.470516| [Sync]: {Repl#2} Push=continuous, Options={"WS-Protocols":"BLIP_3a2"}
08:21:19.471720| [BLIP]: {Connection#3}==> class litecore::blip::Connection <-ws:cli/
08:21:19.472301| [BLIP]: {Connection#3} Accepted connection
08:21:19.473503| [Sync]: {Repl#4}==> class litecore::repl::Replicator C:\tmp\Litecore_C_Tests\cbl_core_test_2\ <-ws:cli/
08:21:19.473862| [Sync]: {Repl#4} Push=passive, Pull=passive, Options={}
08:21:19.474874| [BLIP]: {Connection#1} Connected!
08:21:19.476612| [BLIP]: {Connection#3} Connected!
08:21:19.502405| Waiting for replication to complete...
08:21:19.515616| [Sync]: {Repl#2} BLIP Connected
08:21:19.516020| [Sync]: {Repl#4} BLIP Connected
08:21:19.516427| [Sync]: {Repl#2} now busy
08:21:19.516825| [Sync]: {Repl#4} now busy
08:21:19.517211| >> Replicator is busy, progress 0/0, 0 docs
08:21:19.518017| [Sync]: {Repl#2} No local checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:19.518661| [Sync]: {Push#5}==> class litecore::repl::Pusher ->ws:srv/
08:21:19.519046| [Sync]: {Push#5} Starting continuous push from local seq 1
08:21:19.519433| [Sync]: {Push#5} Asking DB for 200 changes since sequence 0 ...
08:21:19.519895| [Sync]: {DBWorker#6}==> class litecore::repl::DBWorker ->ws:srv/
08:21:19.520295| [Sync]: {DBWorker#6} Reading up to 200 local changes since #0
08:21:19.521718| [Sync]: {Push#5} Caught up, at lastSequence 0
08:21:19.524490| [Sync]: {DBWorker#7}==> class litecore::repl::DBWorker <-ws:cli/
08:21:19.524894| [Sync]: {DBWorker#7} Request to get checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:19.558552| [Sync]: {Pull#8}==> class litecore::repl::Puller <-ws:cli/
08:21:19.559034| [Sync]: {Pull#8} Caught up with remote changes
08:21:19.561595| -------- Creating 2 docs --------
08:21:19.564326| [Sync]: {DBWorker#6} Notified of 2 db changes #1 ... #2
08:21:19.565414| [Sync]: {Repl#2} No remote checkpoint
08:21:19.566094| [Sync]: {Push#5} Found 2 changes: Pusher sending 'proposeChanges' with sequences 1 - 2
08:21:19.566692| [Sync]: {Repl#2} now idle
08:21:19.567284| >> Replicator is idle, progress 0/0, 0 docs
08:21:19.567741| [Sync]: {Repl#2} now busy
08:21:19.568211| >> Replicator is busy, progress 0/32, 0 docs
08:21:19.573840| [Sync]: {DBWorker#7} Looking up 2 proposed revisions in the db
08:21:19.575462| [Sync]: {DBWorker#7} Responding w/request for 2 revs
08:21:19.645562| [Sync]: {DBWorker#7} Inserted 2 revs in 0.92ms (2168/sec)
08:21:19.651638| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='': {"local":2} ...
08:21:19.660051| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:19.669247| -------- Creating 4 docs --------
08:21:19.672138| [Sync]: {DBWorker#6} Notified of 4 db changes #3 ... #6
08:21:19.684349| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='1-cc'
08:21:19.685136| [Sync]: {Push#5} Found 4 changes: Pusher sending 'proposeChanges' with sequences 3 - 6
08:21:19.685647| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:19.691842| [Sync]: {DBWorker#7} Looking up 4 proposed revisions in the db
08:21:19.692626| [Sync]: {DBWorker#7} Responding w/request for 4 revs
08:21:19.768748| >> Replicator is busy, progress 32/96, 2 docs
08:21:19.769716| [Sync]: {DBWorker#7} Inserted 4 revs in 0.96ms (4147/sec)
08:21:19.773610| -------- Creating 6 docs --------
08:21:19.788244| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='1-cc': {"local":6} ...
08:21:19.793075| [Sync]: {DBWorker#6} Notified of 6 db changes #7 ... #12
08:21:19.794820| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:19.795259| [Sync]: {Push#5} Found 6 changes: Pusher sending 'proposeChanges' with sequences 7 - 12
08:21:19.801978| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='2-cc'
08:21:19.802813| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:19.803279| [Sync]: {DBWorker#7} Looking up 6 proposed revisions in the db
08:21:19.804255| [Sync]: {DBWorker#7} Responding w/request for 6 revs
08:21:19.869775| [Sync]: {DBWorker#7} Inserted 6 revs in 1.21ms (4939/sec)
08:21:19.876233| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='2-cc': {"local":12} ...
08:21:19.884987| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:19.891771| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='3-cc'
08:21:19.892585| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:19.893041| [Sync]: {Repl#2} now idle
08:21:19.897618| -------- Creating 8 docs --------
08:21:19.897915| >> Replicator is idle, progress 192/192, 12 docs
08:21:19.903714| [Sync]: {DBWorker#6} Notified of 8 db changes #13 ... #20
08:21:19.905230| [Sync]: {Push#5} Found 8 changes: Pusher sending 'proposeChanges' with sequences 13 - 20
08:21:19.906565| [Sync]: {Repl#2} now busy
08:21:19.907196| >> Replicator is busy, progress 192/320, 12 docs
08:21:19.913152| [Sync]: {DBWorker#7} Looking up 8 proposed revisions in the db
08:21:19.914133| [Sync]: {DBWorker#7} Responding w/request for 8 revs
08:21:19.977035| >> Replicator is busy, progress 192/320, 12 docs
08:21:20.026030| [Sync]: {DBWorker#7} Inserted 8 revs in 1.48ms (5423/sec)
08:21:20.050553| -------- Creating 10 docs --------
08:21:20.052282| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='3-cc': {"local":20} ...
08:21:20.059188| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.061900| [Sync]: {DBWorker#6} Notified of 10 db changes #21 ... #30
08:21:20.063464| [Sync]: {Push#5} Found 10 changes: Pusher sending 'proposeChanges' with sequences 21 - 30
08:21:20.068987| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='4-cc'
08:21:20.069773| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.070375| [Sync]: {DBWorker#7} Looking up 10 proposed revisions in the db
08:21:20.071458| [Sync]: {DBWorker#7} Responding w/request for 10 revs
08:21:20.136697| [Sync]: {DBWorker#7} Inserted 10 revs in 1.78ms (5619/sec)
08:21:20.143777| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='4-cc': {"local":30} ...
08:21:20.150346| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.157115| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='5-cc'
08:21:20.158028| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.158543| [Sync]: {Repl#2} now idle
08:21:20.160588| >> Replicator is idle, progress 480/480, 30 docs
08:21:20.162773| -------- Creating 12 docs --------
08:21:20.169342| [Sync]: {DBWorker#6} Notified of 12 db changes #31 ... #42
08:21:20.171184| [Sync]: {Push#5} Found 12 changes: Pusher sending 'proposeChanges' with sequences 31 - 42
08:21:20.171987| [Sync]: {Repl#2} now busy
08:21:20.173908| >> Replicator is busy, progress 480/672, 30 docs
08:21:20.177969| >> Replicator is busy, progress 480/672, 30 docs
08:21:20.178701| [Sync]: {DBWorker#7} Looking up 12 proposed revisions in the db
08:21:20.179851| [Sync]: {DBWorker#7} Responding w/request for 12 revs
08:21:20.248161| [Sync]: {DBWorker#7} Inserted 12 revs in 2.14ms (5615/sec)
08:21:20.254954| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='5-cc': {"local":42} ...
08:21:20.261477| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.268178| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='6-cc'
08:21:20.268952| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.269417| [Sync]: {Repl#2} now idle
08:21:20.269899| >> Replicator is idle, progress 672/672, 42 docs
08:21:20.270314| -------- Creating 14 docs --------
08:21:20.278106| [Sync]: {DBWorker#6} Notified of 14 db changes #43 ... #56
08:21:20.280660| [Sync]: {Push#5} Found 14 changes: Pusher sending 'proposeChanges' with sequences 43 - 56
08:21:20.281684| [Sync]: {Repl#2} now busy
08:21:20.282195| >> Replicator is busy, progress 672/896, 42 docs
08:21:20.287771| [Sync]: {DBWorker#7} Looking up 14 proposed revisions in the db
08:21:20.289045| [Sync]: {DBWorker#7} Responding w/request for 14 revs
08:21:20.354876| [Sync]: {DBWorker#7} Inserted 14 revs in 2.70ms (5177/sec)
08:21:20.362295| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='6-cc': {"local":56} ...
08:21:20.369578| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.376419| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='7-cc'
08:21:20.377477| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.378745| >> Replicator is busy, progress 896/896, 56 docs
08:21:20.379072| -------- Creating 16 docs --------
08:21:20.380624| [Sync]: {Repl#2} now idle
08:21:20.381103| >> Replicator is idle, progress 896/896, 56 docs
08:21:20.390048| [Sync]: {DBWorker#6} Notified of 16 db changes #57 ... #72
08:21:20.392835| [Sync]: {Push#5} Found 16 changes: Pusher sending 'proposeChanges' with sequences 57 - 72
08:21:20.393832| [Sync]: {Repl#2} now busy
08:21:20.395081| >> Replicator is busy, progress 896/1152, 56 docs
08:21:20.401038| [Sync]: {DBWorker#7} Looking up 16 proposed revisions in the db
08:21:20.402595| [Sync]: {DBWorker#7} Responding w/request for 16 revs
08:21:20.470385| [Sync]: {DBWorker#7} Inserted 16 revs in 3.02ms (5295/sec)
08:21:20.477922| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='7-cc': {"local":72} ...
08:21:20.482307| >> Replicator is busy, progress 1152/1152, 72 docs
08:21:20.484753| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.490296| -------- Creating 18 docs --------
08:21:20.492498| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='8-cc'
08:21:20.501075| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.501884| [Sync]: {Repl#2} now idle
08:21:20.502817| [Sync]: {DBWorker#6} Notified of 18 db changes #73 ... #90
08:21:20.503398| >> Replicator is idle, progress 1152/1152, 72 docs
08:21:20.505423| [Sync]: {Push#5} Found 18 changes: Pusher sending 'proposeChanges' with sequences 73 - 90
08:21:20.506580| [Sync]: {Repl#2} now busy
08:21:20.507393| >> Replicator is busy, progress 1152/1440, 72 docs
08:21:20.513148| [Sync]: {DBWorker#7} Looking up 18 proposed revisions in the db
08:21:20.514786| [Sync]: {DBWorker#7} Responding w/request for 18 revs
08:21:20.581466| [Sync]: {DBWorker#7} Inserted 18 revs in 2.97ms (6061/sec)
08:21:20.589352| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='8-cc': {"local":90} ...
08:21:20.595499| >> Replicator is busy, progress 1440/1440, 90 docs
08:21:20.596890| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.601414| -------- Creating 20 docs --------
08:21:20.603675| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='9-cc'
08:21:20.614332| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.615127| [Sync]: {Repl#2} now idle
08:21:20.616041| [Sync]: {DBWorker#6} Notified of 20 db changes #91 ... #110
08:21:20.616621| >> Replicator is idle, progress 1440/1440, 90 docs
08:21:20.618730| [Sync]: {Push#5} Found 20 changes: Pusher sending 'proposeChanges' with sequences 91 - 110
08:21:20.619651| [Sync]: {Repl#2} now busy
08:21:20.620055| >> Replicator is busy, progress 1440/1760, 90 docs
08:21:20.627475| [Sync]: {DBWorker#7} Looking up 20 proposed revisions in the db
08:21:20.630217| [Sync]: {DBWorker#7} Responding w/request for 20 revs
08:21:20.698587| [Sync]: {DBWorker#7} Inserted 20 revs in 3.97ms (5036/sec)
08:21:20.706110| [Sync]: {Push#5} Checkpoint now at 100
08:21:20.707046| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='9-cc': {"local":110} ...
08:21:20.707908| >> Replicator is busy, progress 1760/1760, 110 docs
08:21:20.714030| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.714582| -------- Creating 22 docs --------
08:21:20.720803| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='10-cc'
08:21:20.727031| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.728080| [Sync]: {Repl#2} now idle
08:21:20.729281| [Sync]: {DBWorker#6} Notified of 22 db changes #111 ... #132
08:21:20.730221| >> Replicator is idle, progress 1760/1760, 110 docs
08:21:20.732475| [Sync]: {Push#5} Found 22 changes: Pusher sending 'proposeChanges' with sequences 111 - 132
08:21:20.733644| [Sync]: {Repl#2} now busy
08:21:20.734226| >> Replicator is busy, progress 1760/2112, 110 docs
08:21:20.740634| [Sync]: {DBWorker#7} Looking up 22 proposed revisions in the db
08:21:20.742251| [Sync]: {DBWorker#7} Responding w/request for 22 revs
08:21:20.810693| [Sync]: {DBWorker#7} Inserted 22 revs in 3.94ms (5589/sec)
08:21:20.818480| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='10-cc': {"local":132} ...
08:21:20.820704| >> Replicator is busy, progress 2112/2112, 132 docs
08:21:20.825152| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.827690| -------- Creating 24 docs --------
08:21:20.831939| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='11-cc'
08:21:20.841302| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.842349| [Sync]: {Repl#2} now idle
08:21:20.842959| [Sync]: {DBWorker#6} Notified of 24 db changes #133 ... #156
08:21:20.843649| >> Replicator is idle, progress 2112/2112, 132 docs
08:21:20.846445| [Sync]: {Push#5} Found 24 changes: Pusher sending 'proposeChanges' with sequences 133 - 156
08:21:20.849059| [Sync]: {Repl#2} now busy
08:21:20.849483| >> Replicator is busy, progress 2112/2496, 132 docs
08:21:20.855554| [Sync]: {DBWorker#7} Looking up 24 proposed revisions in the db
08:21:20.858046| [Sync]: {DBWorker#7} Responding w/request for 24 revs
08:21:20.927007| [Sync]: {DBWorker#7} Inserted 24 revs in 4.12ms (5830/sec)
08:21:20.934888| >> Replicator is busy, progress 2224/2496, 139 docs
08:21:20.936821| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='11-cc': {"local":156} ...
08:21:20.941849| -------- Creating 26 docs --------
08:21:20.943260| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:20.950078| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='12-cc'
08:21:20.957460| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:20.957938| [Sync]: {Repl#2} now idle
08:21:20.958459| >> Replicator is idle, progress 2496/2496, 156 docs
08:21:20.958927| [Sync]: {DBWorker#6} Notified of 26 db changes #157 ... #182
08:21:20.962623| [Sync]: {Push#5} Found 26 changes: Pusher sending 'proposeChanges' with sequences 157 - 182
08:21:20.964369| [Sync]: {Repl#2} now busy
08:21:20.964881| >> Replicator is busy, progress 2496/2912, 156 docs
08:21:20.970752| [Sync]: {DBWorker#7} Looking up 26 proposed revisions in the db
08:21:20.972575| [Sync]: {DBWorker#7} Responding w/request for 26 revs
08:21:21.042131| [Sync]: {DBWorker#7} Inserted 26 revs in 4.94ms (5264/sec)
08:21:21.050156| >> Replicator is busy, progress 2640/2912, 165 docs
08:21:21.051431| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='12-cc': {"local":182} ...
08:21:21.058017| -------- Creating 28 docs --------
08:21:21.060493| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.067221| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='13-cc'
08:21:21.076006| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.076650| [Sync]: {Repl#2} now idle
08:21:21.077362| [Sync]: {DBWorker#6} Notified of 28 db changes #183 ... #210
08:21:21.079123| >> Replicator is idle, progress 2912/2912, 182 docs
08:21:21.082228| [Sync]: {Push#5} Found 28 changes: Pusher sending 'proposeChanges' with sequences 183 - 210
08:21:21.084421| [Sync]: {Repl#2} now busy
08:21:21.085435| >> Replicator is busy, progress 2912/3360, 182 docs
08:21:21.091002| [Sync]: {DBWorker#7} Looking up 28 proposed revisions in the db
08:21:21.093142| [Sync]: {DBWorker#7} Responding w/request for 28 revs
08:21:21.135146| >> Replicator is busy, progress 2912/3360, 182 docs
08:21:21.162986| [Sync]: {DBWorker#7} Inserted 28 revs in 5.75ms (4867/sec)
08:21:21.165130| >> Replicator is busy, progress 2912/3360, 182 docs
08:21:21.172260| [Sync]: {Push#5} Checkpoint now at 200
08:21:21.173610| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='13-cc': {"local":210} ...
08:21:21.176141| -------- Creating 30 docs --------
08:21:21.180564| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.187372| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='14-cc'
08:21:21.196219| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.196870| [Sync]: {Repl#2} now idle
08:21:21.197721| >> Replicator is idle, progress 3360/3360, 210 docs
08:21:21.198214| [Sync]: {DBWorker#6} Notified of 30 db changes #211 ... #240
08:21:21.201975| [Sync]: {Push#5} Found 30 changes: Pusher sending 'proposeChanges' with sequences 211 - 240
08:21:21.203403| [Sync]: {Repl#2} now busy
08:21:21.203884| >> Replicator is busy, progress 3360/3840, 210 docs
08:21:21.210220| [Sync]: {DBWorker#7} Looking up 30 proposed revisions in the db
08:21:21.212842| [Sync]: {DBWorker#7} Responding w/request for 30 revs
08:21:21.250301| >> Replicator is busy, progress 3360/3840, 210 docs
08:21:21.282499| [Sync]: {DBWorker#7} Inserted 30 revs in 5.17ms (5808/sec)
08:21:21.293007| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='14-cc': {"local":240} ...
08:21:21.296294| -------- Creating 32 docs --------
08:21:21.300759| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.307504| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='15-cc'
08:21:21.315529| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.316253| [Sync]: {Repl#2} now idle
08:21:21.316799| >> Replicator is idle, progress 3840/3840, 240 docs
08:21:21.317613| [Sync]: {DBWorker#6} Notified of 32 db changes #241 ... #272
08:21:21.323268| [Sync]: {Push#5} Found 32 changes: Pusher sending 'proposeChanges' with sequences 241 - 272
08:21:21.324695| [Sync]: {Repl#2} now busy
08:21:21.325330| >> Replicator is busy, progress 3840/4352, 240 docs
08:21:21.331391| [Sync]: {DBWorker#7} Looking up 32 proposed revisions in the db
08:21:21.333480| [Sync]: {DBWorker#7} Responding w/request for 32 revs
08:21:21.365458| >> Replicator is busy, progress 3840/4352, 240 docs
08:21:21.403056| [Sync]: {DBWorker#7} Inserted 32 revs in 5.54ms (5775/sec)
08:21:21.413984| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='15-cc': {"local":272} ...
08:21:21.415425| -------- Creating 34 docs --------
08:21:21.421928| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.429075| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='16-cc'
08:21:21.435602| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.436512| [Sync]: {Repl#2} now idle
08:21:21.437306| >> Replicator is idle, progress 4352/4352, 272 docs
08:21:21.437817| [Sync]: {DBWorker#6} Notified of 34 db changes #273 ... #306
08:21:21.441813| [Sync]: {Push#5} Found 34 changes: Pusher sending 'proposeChanges' with sequences 273 - 306
08:21:21.443209| [Sync]: {Repl#2} now busy
08:21:21.443962| >> Replicator is busy, progress 4352/4896, 272 docs
08:21:21.450567| [Sync]: {DBWorker#7} Looking up 34 proposed revisions in the db
08:21:21.451503| >> Replicator is busy, progress 4352/4896, 272 docs
08:21:21.452905| [Sync]: {DBWorker#7} Responding w/request for 34 revs
08:21:21.522613| [Sync]: {DBWorker#7} Inserted 34 revs in 5.95ms (5719/sec)
08:21:21.531236| [Sync]: {Push#5} Checkpoint now at 301
08:21:21.532353| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='16-cc': {"local":306} ...
08:21:21.535582| -------- Creating 36 docs --------
08:21:21.540030| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.546808| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='17-cc'
08:21:21.556794| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.557239| [Sync]: {Repl#2} now idle
08:21:21.557782| [Sync]: {DBWorker#6} Notified of 36 db changes #307 ... #342
08:21:21.558266| >> Replicator is idle, progress 4896/4896, 306 docs
08:21:21.561789| [Sync]: {Push#5} Found 36 changes: Pusher sending 'proposeChanges' with sequences 307 - 342
08:21:21.563149| [Sync]: {Repl#2} now busy
08:21:21.563538| >> Replicator is busy, progress 4896/5472, 306 docs
08:21:21.565634| >> Replicator is busy, progress 4896/5472, 306 docs
08:21:21.569626| [Sync]: {DBWorker#7} Looking up 36 proposed revisions in the db
08:21:21.571847| [Sync]: {DBWorker#7} Responding w/request for 36 revs
08:21:21.642270| [Sync]: {DBWorker#7} Inserted 36 revs in 6.40ms (5627/sec)
08:21:21.651560| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='17-cc': {"local":342} ...
08:21:21.652510| >> Replicator is busy, progress 5472/5472, 342 docs
08:21:21.656735| -------- Creating 38 docs --------
08:21:21.659177| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.665961| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='18-cc'
08:21:21.678307| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.679151| [Sync]: {Repl#2} now idle
08:21:21.680778| [Sync]: {DBWorker#6} Notified of 38 db changes #343 ... #380
08:21:21.681526| >> Replicator is idle, progress 5472/5472, 342 docs
08:21:21.685105| [Sync]: {Push#5} Found 38 changes: Pusher sending 'proposeChanges' with sequences 343 - 380
08:21:21.686322| [Sync]: {Repl#2} now busy
08:21:21.686764| >> Replicator is busy, progress 5472/6080, 342 docs
08:21:21.693099| [Sync]: {DBWorker#7} Looking up 38 proposed revisions in the db
08:21:21.695500| [Sync]: {DBWorker#7} Responding w/request for 38 revs
08:21:21.765909| >> Replicator is busy, progress 5472/6080, 342 docs
08:21:21.767542| [Sync]: {DBWorker#7} Inserted 38 revs in 6.58ms (5772/sec)
08:21:21.776840| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='18-cc': {"local":380} ...
08:21:21.778999| -------- Creating 40 docs --------
08:21:21.784313| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.791112| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='19-cc'
08:21:21.803306| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.803849| [Sync]: {Repl#2} now idle
08:21:21.804410| >> Replicator is idle, progress 6080/6080, 380 docs
08:21:21.804815| [Sync]: {DBWorker#6} Notified of 40 db changes #381 ... #420
08:21:21.808973| [Sync]: {Push#5} Found 40 changes: Pusher sending 'proposeChanges' with sequences 381 - 420
08:21:21.810236| [Sync]: {Repl#2} now busy
08:21:21.810624| >> Replicator is busy, progress 6080/6720, 380 docs
08:21:21.817040| [Sync]: {DBWorker#7} Looking up 40 proposed revisions in the db
08:21:21.819459| [Sync]: {DBWorker#7} Responding w/request for 40 revs
08:21:21.890883| [Sync]: {DBWorker#7} Inserted 40 revs in 6.80ms (5885/sec)
08:21:21.898657| [Sync]: {Push#5} Checkpoint now at 400
08:21:21.900749| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='19-cc': {"local":420} ...
08:21:21.903037| -------- Creating 42 docs --------
08:21:21.908464| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:21.916245| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='20-cc'
08:21:21.927696| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:21.928343| [Sync]: {Repl#2} now idle
08:21:21.928882| [Sync]: {DBWorker#6} Notified of 42 db changes #421 ... #462
08:21:21.929434| >> Replicator is idle, progress 6720/6720, 420 docs
08:21:21.933362| [Sync]: {Push#5} Found 42 changes: Pusher sending 'proposeChanges' with sequences 421 - 462
08:21:21.934786| [Sync]: {Repl#2} now busy
08:21:21.935185| >> Replicator is busy, progress 6720/7392, 420 docs
08:21:21.941200| [Sync]: {DBWorker#7} Looking up 42 proposed revisions in the db
08:21:21.943865| [Sync]: {DBWorker#7} Responding w/request for 42 revs
08:21:21.966158| >> Replicator is busy, progress 6720/7392, 420 docs
08:21:22.012264| >> Replicator is busy, progress 6720/7392, 420 docs
08:21:22.017101| [Sync]: {DBWorker#7} Inserted 42 revs in 7.79ms (5391/sec)
08:21:22.028451| -------- Creating 44 docs --------
08:21:22.029076| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='20-cc': {"local":462} ...
08:21:22.036645| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.043467| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='21-cc'
08:21:22.055324| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.056294| [Sync]: {Repl#2} now idle
08:21:22.057860| [Sync]: {DBWorker#6} Notified of 44 db changes #463 ... #506
08:21:22.058410| >> Replicator is idle, progress 7392/7392, 462 docs
08:21:22.062575| [Sync]: {Push#5} Found 44 changes: Pusher sending 'proposeChanges' with sequences 463 - 506
08:21:22.064441| [Sync]: {Repl#2} now busy
08:21:22.065202| >> Replicator is busy, progress 7392/8096, 462 docs
08:21:22.071469| [Sync]: {DBWorker#7} Looking up 44 proposed revisions in the db
08:21:22.074089| [Sync]: {DBWorker#7} Responding w/request for 44 revs
08:21:22.145993| [Sync]: {DBWorker#7} Inserted 44 revs in 7.60ms (5792/sec)
08:21:22.156017| [Sync]: {Push#5} Checkpoint now at 500
08:21:22.156591| -------- Creating 46 docs --------
08:21:22.157347| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='21-cc': {"local":506} ...
08:21:22.164807| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.171578| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='22-cc'
08:21:22.183473| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.184043| [Sync]: {Repl#2} now idle
08:21:22.184569| >> Replicator is idle, progress 8096/8096, 506 docs
08:21:22.185042| [Sync]: {DBWorker#6} Notified of 46 db changes #507 ... #552
08:21:22.189688| [Sync]: {Push#5} Found 46 changes: Pusher sending 'proposeChanges' with sequences 507 - 552
08:21:22.191321| [Sync]: {Repl#2} now busy
08:21:22.191751| >> Replicator is busy, progress 8096/8832, 506 docs
08:21:22.198702| [Sync]: {DBWorker#7} Looking up 46 proposed revisions in the db
08:21:22.201388| [Sync]: {DBWorker#7} Responding w/request for 46 revs
08:21:22.213463| >> Replicator is busy, progress 8096/8832, 506 docs
08:21:22.265561| >> Replicator is busy, progress 8096/8832, 506 docs
08:21:22.275564| [Sync]: {DBWorker#7} Inserted 46 revs in 8.03ms (5727/sec)
08:21:22.283726| -------- Creating 48 docs --------
08:21:22.287162| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='22-cc': {"local":552} ...
08:21:22.293926| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.301745| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='23-cc'
08:21:22.314022| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.314834| [Sync]: {Repl#2} now idle
08:21:22.315357| >> Replicator is idle, progress 8832/8832, 552 docs
08:21:22.315772| [Sync]: {DBWorker#6} Notified of 48 db changes #553 ... #600
08:21:22.320679| [Sync]: {Push#5} Found 48 changes: Pusher sending 'proposeChanges' with sequences 553 - 600
08:21:22.322336| [Sync]: {Repl#2} now busy
08:21:22.323310| >> Replicator is busy, progress 8832/9600, 552 docs
08:21:22.329912| [Sync]: {DBWorker#7} Looking up 48 proposed revisions in the db
08:21:22.332788| [Sync]: {DBWorker#7} Responding w/request for 48 revs
08:21:22.406801| [Sync]: {DBWorker#7} Inserted 48 revs in 8.01ms (5994/sec)
08:21:22.415624| -------- Creating 50 docs --------
08:21:22.417300| [Sync]: {Push#5} Checkpoint now at 600
08:21:22.418236| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='23-cc': {"local":600} ...
08:21:22.425119| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.431919| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='24-cc'
08:21:22.446355| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.447102| [Sync]: {Repl#2} now idle
08:21:22.447636| [Sync]: {DBWorker#6} Notified of 50 db changes #601 ... #650
08:21:22.448431| >> Replicator is idle, progress 9600/9600, 600 docs
08:21:22.453007| [Sync]: {Push#5} Found 50 changes: Pusher sending 'proposeChanges' with sequences 601 - 650
08:21:22.454482| [Sync]: {Repl#2} now busy
08:21:22.455675| >> Replicator is busy, progress 9600/10400, 600 docs
08:21:22.462116| [Sync]: {DBWorker#7} Looking up 50 proposed revisions in the db
08:21:22.466176| [Sync]: {DBWorker#7} Responding w/request for 50 revs
08:21:22.466765| >> Replicator is busy, progress 9600/10400, 600 docs
08:21:22.523869| >> Replicator is busy, progress 9600/10400, 600 docs
08:21:22.539570| [Sync]: {DBWorker#7} Inserted 50 revs in 8.67ms (5767/sec)
08:21:22.549693| -------- Creating 52 docs --------
08:21:22.550085| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='24-cc': {"local":650} ...
08:21:22.557285| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.564055| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='25-cc'
08:21:22.579375| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.580177| [Sync]: {Repl#2} now idle
08:21:22.580730| [Sync]: {DBWorker#6} Notified of 52 db changes #651 ... #702
08:21:22.581276| >> Replicator is idle, progress 10400/10400, 650 docs
08:21:22.586446| [Sync]: {Push#5} Found 52 changes: Pusher sending 'proposeChanges' with sequences 651 - 702
08:21:22.588002| [Sync]: {Repl#2} now busy
08:21:22.588881| >> Replicator is busy, progress 10400/11232, 650 docs
08:21:22.595161| [Sync]: {DBWorker#7} Looking up 52 proposed revisions in the db
08:21:22.598249| [Sync]: {DBWorker#7} Responding w/request for 52 revs
08:21:22.671223| [Sync]: {DBWorker#7} Inserted 52 revs in 9.15ms (5683/sec)
08:21:22.681710| -------- Creating 54 docs --------
08:21:22.683089| [Sync]: {Push#5} Checkpoint now at 700
08:21:22.684077| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='25-cc': {"local":702} ...
08:21:22.691493| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.698338| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='26-cc'
08:21:22.714178| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.714662| [Sync]: {Repl#2} now idle
08:21:22.715230| >> Replicator is idle, progress 11232/11232, 702 docs
08:21:22.715836| [Sync]: {DBWorker#6} Notified of 54 db changes #703 ... #756
08:21:22.721743| [Sync]: {Push#5} Found 54 changes: Pusher sending 'proposeChanges' with sequences 703 - 756
08:21:22.723956| [Sync]: {Repl#2} now busy
08:21:22.724698| >> Replicator is busy, progress 11232/12096, 702 docs
08:21:22.725686| >> Replicator is busy, progress 11232/12096, 702 docs
08:21:22.732466| [Sync]: {DBWorker#7} Looking up 54 proposed revisions in the db
08:21:22.735651| [Sync]: {DBWorker#7} Responding w/request for 54 revs
08:21:22.789200| >> Replicator is busy, progress 11232/12096, 702 docs
08:21:22.810453| [Sync]: {DBWorker#7} Inserted 54 revs in 10.22ms (5282/sec)
08:21:22.814180| -------- Creating 56 docs --------
08:21:22.820828| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='26-cc': {"local":756} ...
08:21:22.827601| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.834404| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='27-cc'
08:21:22.849544| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.850081| [Sync]: {Repl#2} now idle
08:21:22.850590| >> Replicator is idle, progress 12096/12096, 756 docs
08:21:22.851054| [Sync]: {DBWorker#6} Notified of 56 db changes #757 ... #812
08:21:22.856560| [Sync]: {Push#5} Found 56 changes: Pusher sending 'proposeChanges' with sequences 757 - 812
08:21:22.858427| [Sync]: {Repl#2} now busy
08:21:22.859204| >> Replicator is busy, progress 12096/12992, 756 docs
08:21:22.865617| [Sync]: {DBWorker#7} Looking up 56 proposed revisions in the db
08:21:22.868757| [Sync]: {DBWorker#7} Responding w/request for 56 revs
08:21:22.942705| [Sync]: {DBWorker#7} Inserted 56 revs in 9.31ms (6014/sec)
08:21:22.949332| -------- Creating 58 docs --------
08:21:22.953187| [Sync]: {Push#5} Checkpoint now at 800
08:21:22.954223| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='27-cc': {"local":812} ...
08:21:22.960812| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:22.967564| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='28-cc'
08:21:22.985605| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:22.986174| [Sync]: {Repl#2} now idle
08:21:22.986648| >> Replicator is idle, progress 12992/12992, 812 docs
08:21:22.987056| [Sync]: {DBWorker#6} Notified of 58 db changes #813 ... #870
08:21:22.989485| >> Replicator is idle, progress 12992/12992, 812 docs
08:21:22.993492| [Sync]: {Push#5} Found 58 changes: Pusher sending 'proposeChanges' with sequences 813 - 870
08:21:22.995119| [Sync]: {Repl#2} now busy
08:21:22.997159| >> Replicator is busy, progress 12992/13920, 812 docs
08:21:23.002753| [Sync]: {DBWorker#7} Looking up 58 proposed revisions in the db
08:21:23.006557| [Sync]: {DBWorker#7} Responding w/request for 58 revs
08:21:23.059534| >> Replicator is busy, progress 12992/13920, 812 docs
08:21:23.080595| [Sync]: {DBWorker#7} Inserted 58 revs in 10.03ms (5783/sec)
08:21:23.085567| -------- Creating 60 docs --------
08:21:23.092108| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='28-cc': {"local":870} ...
08:21:23.098988| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:23.105725| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='29-cc'
08:21:23.123918| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:23.124452| [Sync]: {Repl#2} now idle
08:21:23.124956| >> Replicator is idle, progress 13920/13920, 870 docs
08:21:23.125342| [Sync]: {DBWorker#6} Notified of 60 db changes #871 ... #930
08:21:23.132022| [Sync]: {Push#5} Found 60 changes: Pusher sending 'proposeChanges' with sequences 871 - 930
08:21:23.133738| [Sync]: {Repl#2} now busy
08:21:23.134136| >> Replicator is busy, progress 13920/14880, 870 docs
08:21:23.140936| [Sync]: {DBWorker#7} Looking up 60 proposed revisions in the db
08:21:23.144273| [Sync]: {DBWorker#7} Responding w/request for 60 revs
08:21:23.218978| [Sync]: {DBWorker#7} Inserted 60 revs in 10.23ms (5863/sec)
08:21:23.224699| -------- Creating 62 docs --------
08:21:23.227914| [Sync]: {Push#5} Checkpoint now at 900
08:21:23.232610| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='29-cc': {"local":930} ...
08:21:23.239449| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:23.245934| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='30-cc'
08:21:23.259763| >> Replicator is busy, progress 14880/14880, 930 docs
08:21:23.264490| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:23.265022| [Sync]: {Repl#2} now idle
08:21:23.265537| >> Replicator is idle, progress 14880/14880, 930 docs
08:21:23.265977| [Sync]: {DBWorker#6} Notified of 62 db changes #931 ... #992
08:21:23.272067| [Sync]: {Push#5} Found 62 changes: Pusher sending 'proposeChanges' with sequences 931 - 992
08:21:23.273768| [Sync]: {Repl#2} now busy
08:21:23.274198| >> Replicator is busy, progress 14880/15872, 930 docs
08:21:23.281254| [Sync]: {DBWorker#7} Looking up 62 proposed revisions in the db
08:21:23.284767| [Sync]: {DBWorker#7} Responding w/request for 62 revs
08:21:23.334878| >> Replicator is busy, progress 14880/15872, 930 docs
08:21:23.359466| [Sync]: {DBWorker#7} Inserted 62 revs in 10.55ms (5877/sec)
08:21:23.365158| -------- Creating 64 docs --------
08:21:23.371158| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='30-cc': {"local":992} ...
08:21:23.378287| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:23.385088| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='31-cc'
08:21:23.404934| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:23.405479| [Sync]: {Repl#2} now idle
08:21:23.406004| >> Replicator is idle, progress 15872/15872, 992 docs
08:21:23.406447| [Sync]: {DBWorker#6} Notified of 64 db changes #993 ... #1056
08:21:23.412757| [Sync]: {Push#5} Found 64 changes: Pusher sending 'proposeChanges' with sequences 993 - 1056
08:21:23.414675| [Sync]: {Repl#2} now busy
08:21:23.415683| >> Replicator is busy, progress 15872/16896, 992 docs
08:21:23.422377| [Sync]: {DBWorker#7} Looking up 64 proposed revisions in the db
08:21:23.425862| [Sync]: {DBWorker#7} Responding w/request for 64 revs
08:21:23.501814| [Sync]: {DBWorker#7} Inserted 64 revs in 10.72ms (5971/sec)
08:21:23.505799| -------- Creating 66 docs --------
08:21:23.508747| [Sync]: {Push#5} Checkpoint now at 1000
08:21:23.513922| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='31-cc': {"local":1056} ...
08:21:23.520779| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:23.527250| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='32-cc'
08:21:23.535106| >> Replicator is busy, progress 16896/16896, 1056 docs
08:21:23.546721| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:23.547631| [Sync]: {Repl#2} now idle
08:21:23.548020| [Sync]: {DBWorker#6} Notified of 66 db changes #1057 ... #1122
08:21:23.548563| >> Replicator is idle, progress 16896/16896, 1056 docs
08:21:23.554723| [Sync]: {Push#5} Found 66 changes: Pusher sending 'proposeChanges' with sequences 1057 - 1122
08:21:23.557174| [Sync]: {Repl#2} now busy
08:21:23.557603| >> Replicator is busy, progress 16896/17952, 1056 docs
08:21:23.564768| [Sync]: {DBWorker#7} Looking up 66 proposed revisions in the db
08:21:23.568778| [Sync]: {DBWorker#7} Responding w/request for 66 revs
08:21:23.616236| >> Replicator is busy, progress 16896/17952, 1056 docs
08:21:23.645440| [Sync]: {DBWorker#7} Inserted 66 revs in 11.16ms (5912/sec)
08:21:23.650514| -------- Creating 68 docs --------
08:21:23.656171| [Sync]: {Push#5} Checkpoint now at 1100
08:21:23.658240| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='32-cc': {"local":1122} ...
08:21:23.665716| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:23.672465| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='33-cc'
08:21:23.689057| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:23.689548| [Sync]: {Repl#2} now idle
08:21:23.690872| [Sync]: {DBWorker#6} Notified of 68 db changes #1123 ... #1190
08:21:23.691389| >> Replicator is idle, progress 17952/17952, 1122 docs
08:21:23.698123| [Sync]: {Push#5} Found 68 changes: Pusher sending 'proposeChanges' with sequences 1123 - 1190
08:21:23.700583| [Sync]: {Repl#2} now busy
08:21:23.701168| >> Replicator is busy, progress 17952/19040, 1122 docs
08:21:23.708149| [Sync]: {DBWorker#7} Looking up 68 proposed revisions in the db
08:21:23.712609| [Sync]: {DBWorker#7} Responding w/request for 68 revs
08:21:23.789248| [Sync]: {DBWorker#7} Inserted 68 revs in 11.79ms (5767/sec)
08:21:23.792457| -------- Creating 70 docs --------
08:21:23.802736| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='33-cc': {"local":1190} ...
08:21:23.809839| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:23.816474| >> Replicator is busy, progress 19040/19040, 1190 docs
08:21:23.817168| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='34-cc'
08:21:23.832967| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:23.833500| [Sync]: {Repl#2} now idle
08:21:23.834017| >> Replicator is idle, progress 19040/19040, 1190 docs
08:21:23.834460| [Sync]: {DBWorker#6} Notified of 70 db changes #1191 ... #1260
08:21:23.841755| [Sync]: {Push#5} Found 70 changes: Pusher sending 'proposeChanges' with sequences 1191 - 1260
08:21:23.843667| [Sync]: {Repl#2} now busy
08:21:23.844238| >> Replicator is busy, progress 19040/20160, 1190 docs
08:21:23.851123| [Sync]: {DBWorker#7} Looking up 70 proposed revisions in the db
08:21:23.854962| [Sync]: {DBWorker#7} Responding w/request for 70 revs
08:21:23.901586| >> Replicator is busy, progress 19040/20160, 1190 docs
08:21:23.931610| [Sync]: {DBWorker#7} Inserted 70 revs in 11.98ms (5843/sec)
08:21:23.934872| -------- Creating 72 docs --------
08:21:23.939298| [Sync]: {Push#5} Checkpoint now at 1200
08:21:23.946436| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='34-cc': {"local":1260} ...
08:21:23.953015| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:23.959793| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='35-cc'
08:21:23.980004| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:23.980610| [Sync]: {Repl#2} now idle
08:21:23.981255| [Sync]: {DBWorker#6} Notified of 72 db changes #1261 ... #1332
08:21:23.981761| >> Replicator is idle, progress 20160/20160, 1260 docs
08:21:23.988156| [Sync]: {Push#5} Found 72 changes: Pusher sending 'proposeChanges' with sequences 1261 - 1332
08:21:23.990782| [Sync]: {Repl#2} now busy
08:21:23.991192| >> Replicator is busy, progress 20160/21312, 1260 docs
08:21:23.998328| [Sync]: {DBWorker#7} Looking up 72 proposed revisions in the db
08:21:24.002230| [Sync]: {DBWorker#7} Responding w/request for 72 revs
08:21:24.079260| [Sync]: {DBWorker#7} Inserted 72 revs in 12.45ms (5783/sec)
08:21:24.082572| -------- Creating 74 docs --------
08:21:24.089282| [Sync]: {Push#5} Checkpoint now at 1300
08:21:24.093260| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='35-cc': {"local":1332} ...
08:21:24.100246| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:24.102801| >> Replicator is busy, progress 21312/21312, 1332 docs
08:21:24.107009| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='36-cc'
08:21:24.125268| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:24.125850| [Sync]: {Repl#2} now idle
08:21:24.126379| [Sync]: {DBWorker#6} Notified of 74 db changes #1333 ... #1406
08:21:24.127077| >> Replicator is idle, progress 21312/21312, 1332 docs
08:21:24.135253| [Sync]: {Push#5} Found 74 changes: Pusher sending 'proposeChanges' with sequences 1333 - 1406
08:21:24.137428| [Sync]: {Repl#2} now busy
08:21:24.137871| >> Replicator is busy, progress 21312/22496, 1332 docs
08:21:24.144486| [Sync]: {DBWorker#7} Looking up 74 proposed revisions in the db
08:21:24.148887| [Sync]: {DBWorker#7} Responding w/request for 74 revs
08:21:24.191916| >> Replicator is busy, progress 21312/22496, 1332 docs
08:21:24.225930| [Sync]: {DBWorker#7} Inserted 74 revs in 12.90ms (5735/sec)
08:21:24.229147| -------- Creating 76 docs --------
08:21:24.237815| [Sync]: {Push#5} Checkpoint now at 1400
08:21:24.239605| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='36-cc': {"local":1406} ...
08:21:24.249434| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:24.256161| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='37-cc'
08:21:24.276237| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:24.276783| [Sync]: {Repl#2} now idle
08:21:24.277296| [Sync]: {DBWorker#6} Notified of 76 db changes #1407 ... #1482
08:21:24.277821| >> Replicator is idle, progress 22496/22496, 1406 docs
08:21:24.284553| [Sync]: {Push#5} Found 76 changes: Pusher sending 'proposeChanges' with sequences 1407 - 1482
08:21:24.286583| [Sync]: {Repl#2} now busy
08:21:24.286982| >> Replicator is busy, progress 22496/23712, 1406 docs
08:21:24.293707| [Sync]: {DBWorker#7} Looking up 76 proposed revisions in the db
08:21:24.298223| [Sync]: {DBWorker#7} Responding w/request for 76 revs
08:21:24.375632| [Sync]: {DBWorker#7} Inserted 76 revs in 13.45ms (5652/sec)
08:21:24.376280| -------- Creating 78 docs --------
08:21:24.388404| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='37-cc': {"local":1482} ...
08:21:24.392154| >> Replicator is busy, progress 23712/23712, 1482 docs
08:21:24.395549| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:24.402412| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='38-cc'
08:21:24.426360| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:24.427096| [Sync]: {Repl#2} now idle
08:21:24.427637| >> Replicator is idle, progress 23712/23712, 1482 docs
08:21:24.428064| [Sync]: {DBWorker#6} Notified of 78 db changes #1483 ... #1560
08:21:24.435603| [Sync]: {Push#5} Found 78 changes: Pusher sending 'proposeChanges' with sequences 1483 - 1560
08:21:24.437768| [Sync]: {Repl#2} now busy
08:21:24.438243| >> Replicator is busy, progress 23712/24960, 1482 docs
08:21:24.444898| [Sync]: {DBWorker#7} Looking up 78 proposed revisions in the db
08:21:24.450092| [Sync]: {DBWorker#7} Responding w/request for 78 revs
08:21:24.488351| >> Replicator is busy, progress 23712/24960, 1482 docs
08:21:24.526302| -------- Creating 80 docs --------
08:21:24.527787| [Sync]: {DBWorker#7} Inserted 78 revs in 13.39ms (5827/sec)
08:21:24.537258| [Sync]: {Push#5} Checkpoint now at 1500
08:21:24.543215| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='38-cc': {"local":1560} ...
08:21:24.549879| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:24.556644| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='39-cc'
08:21:24.575130| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:24.576239| [Sync]: {Repl#2} now idle
08:21:24.576746| [Sync]: {DBWorker#6} Notified of 80 db changes #1561 ... #1640
08:21:24.577225| >> Replicator is idle, progress 24960/24960, 1560 docs
08:21:24.584827| [Sync]: {Push#5} Found 80 changes: Pusher sending 'proposeChanges' with sequences 1561 - 1640
08:21:24.587605| [Sync]: {Repl#2} now busy
08:21:24.588343| >> Replicator is busy, progress 24960/26240, 1560 docs
08:21:24.595191| [Sync]: {DBWorker#7} Looking up 80 proposed revisions in the db
08:21:24.600380| [Sync]: {DBWorker#7} Responding w/request for 80 revs
08:21:24.675486| -------- Creating 82 docs --------
08:21:24.678354| [Sync]: {DBWorker#7} Inserted 80 revs in 13.78ms (5805/sec)
08:21:24.688661| >> Replicator is busy, progress 25600/26240, 1600 docs
08:21:24.689847| [Sync]: {Push#5} Checkpoint now at 1610
08:21:24.691944| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='39-cc': {"local":1640} ...
08:21:24.699013| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:24.705707| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='40-cc'
08:21:24.729803| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:24.730388| [Sync]: {Repl#2} now idle
08:21:24.730872| >> Replicator is idle, progress 26240/26240, 1640 docs
08:21:24.731375| [Sync]: {DBWorker#6} Notified of 82 db changes #1641 ... #1722
08:21:24.739658| [Sync]: {Push#5} Found 82 changes: Pusher sending 'proposeChanges' with sequences 1641 - 1722
08:21:24.741744| [Sync]: {Repl#2} now busy
08:21:24.742124| >> Replicator is busy, progress 26240/27552, 1640 docs
08:21:24.749395| [Sync]: {DBWorker#7} Looking up 82 proposed revisions in the db
08:21:24.753697| [Sync]: {DBWorker#7} Responding w/request for 82 revs
08:21:24.788754| >> Replicator is busy, progress 26240/27552, 1640 docs
08:21:24.829682| -------- Creating 84 docs --------
08:21:24.831880| [Sync]: {DBWorker#7} Inserted 82 revs in 14.13ms (5804/sec)
08:21:24.844169| [Sync]: {Push#5} Checkpoint now at 1700
08:21:24.847462| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='40-cc': {"local":1722} ...
08:21:24.856170| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:24.862953| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='41-cc'
08:21:24.883785| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:24.884337| [Sync]: {Repl#2} now idle
08:21:24.884919| [Sync]: {DBWorker#6} Notified of 84 db changes #1723 ... #1806
08:21:24.885428| >> Replicator is idle, progress 27552/27552, 1722 docs
08:21:24.888792| >> Replicator is idle, progress 27552/27552, 1722 docs
08:21:24.893907| [Sync]: {Push#5} Found 84 changes: Pusher sending 'proposeChanges' with sequences 1723 - 1806
08:21:24.896111| [Sync]: {Repl#2} now busy
08:21:24.901728| >> Replicator is busy, progress 27552/28896, 1722 docs
08:21:24.903761| [Sync]: {DBWorker#7} Looking up 84 proposed revisions in the db
08:21:24.908548| [Sync]: {DBWorker#7} Responding w/request for 84 revs
08:21:24.984123| -------- Creating 86 docs --------
08:21:24.987777| [Sync]: {DBWorker#7} Inserted 84 revs in 14.80ms (5676/sec)
08:21:24.994231| >> Replicator is busy, progress 27552/28896, 1722 docs
08:21:25.001442| [Sync]: {Push#5} Checkpoint now at 1800
08:21:25.002234| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='41-cc': {"local":1806} ...
08:21:25.009312| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:25.016124| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='42-cc'
08:21:25.044910| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:25.045443| [Sync]: {Repl#2} now idle
08:21:25.045937| >> Replicator is idle, progress 28896/28896, 1806 docs
08:21:25.046376| [Sync]: {DBWorker#6} Notified of 86 db changes #1807 ... #1892
08:21:25.054816| [Sync]: {Push#5} Found 86 changes: Pusher sending 'proposeChanges' with sequences 1807 - 1892
08:21:25.057049| [Sync]: {Repl#2} now busy
08:21:25.057692| >> Replicator is busy, progress 28896/30272, 1806 docs
08:21:25.065239| [Sync]: {DBWorker#7} Looking up 86 proposed revisions in the db
08:21:25.070677| [Sync]: {DBWorker#7} Responding w/request for 86 revs
08:21:25.145089| -------- Creating 88 docs --------
08:21:25.150564| [Sync]: {DBWorker#7} Inserted 86 revs in 15.41ms (5581/sec)
08:21:25.164954| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='42-cc': {"local":1892} ...
08:21:25.172571| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:25.180579| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='43-cc'
08:21:25.194199| >> Replicator is busy, progress 30272/30272, 1892 docs
08:21:25.207540| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:25.208656| [Sync]: {Repl#2} now idle
08:21:25.209462| >> Replicator is idle, progress 30272/30272, 1892 docs
08:21:25.210180| [Sync]: {DBWorker#6} Notified of 88 db changes #1893 ... #1980
08:21:25.219173| [Sync]: {Push#5} Found 88 changes: Pusher sending 'proposeChanges' with sequences 1893 - 1980
08:21:25.221553| [Sync]: {Repl#2} now busy
08:21:25.222121| >> Replicator is busy, progress 30272/31680, 1892 docs
08:21:25.229197| [Sync]: {DBWorker#7} Looking up 88 proposed revisions in the db
08:21:25.235953| [Sync]: {DBWorker#7} Responding w/request for 88 revs
08:21:25.258431| >> Replicator is busy, progress 30272/31680, 1892 docs
08:21:25.307278| -------- Creating 90 docs --------
08:21:25.317065| [Sync]: {DBWorker#7} Inserted 88 revs in 16.71ms (5268/sec)
08:21:25.325186| [Sync]: {Push#5} Checkpoint now at 1900
08:21:25.337486| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='43-cc': {"local":1980} ...
08:21:25.344758| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:25.351543| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='44-cc'
08:21:25.360374| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:25.360784| [Sync]: {Repl#2} now idle
08:21:25.361261| [Sync]: {DBWorker#6} Notified of 90 db changes #1981 ... #2070
08:21:25.362611| >> Replicator is idle, progress 31680/31680, 1980 docs
08:21:25.371091| [Sync]: {Push#5} Found 90 changes: Pusher sending 'proposeChanges' with sequences 1981 - 2070
08:21:25.373323| [Sync]: {Repl#2} now busy
08:21:25.373707| >> Replicator is busy, progress 31680/33120, 1980 docs
08:21:25.381938| [Sync]: {DBWorker#7} Looking up 90 proposed revisions in the db
08:21:25.386703| [Sync]: {DBWorker#7} Responding w/request for 90 revs
08:21:25.459503| >> Replicator is busy, progress 31680/33120, 1980 docs
08:21:25.460467| -------- Creating 92 docs --------
08:21:25.467074| [Sync]: {DBWorker#7} Inserted 90 revs in 16.49ms (5459/sec)
08:21:25.477357| [Sync]: {Push#5} Checkpoint now at 2000
08:21:25.486219| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='44-cc': {"local":2070} ...
08:21:25.494007| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:25.500747| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='45-cc'
08:21:25.516274| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:25.517385| [Sync]: {Repl#2} now idle
08:21:25.517930| [Sync]: {DBWorker#6} Notified of 92 db changes #2071 ... #2162
08:21:25.518478| >> Replicator is idle, progress 33120/33120, 2070 docs
08:21:25.526736| [Sync]: {Push#5} Found 92 changes: Pusher sending 'proposeChanges' with sequences 2071 - 2162
08:21:25.529402| [Sync]: {Repl#2} now busy
08:21:25.529780| >> Replicator is busy, progress 33120/34592, 2070 docs
08:21:25.539262| [Sync]: {DBWorker#7} Looking up 92 proposed revisions in the db
08:21:25.544319| [Sync]: {DBWorker#7} Responding w/request for 92 revs
08:21:25.616664| -------- Creating 94 docs --------
08:21:25.626087| [Sync]: {DBWorker#7} Inserted 92 revs in 17.35ms (5301/sec)
08:21:25.637302| [Sync]: {Push#5} Checkpoint now at 2100
08:21:25.646372| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='45-cc': {"local":2162} ...
08:21:25.653185| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:25.659769| >> Replicator is busy, progress 34592/34592, 2162 docs
08:21:25.660158| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='46-cc'
08:21:25.673780| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:25.674311| [Sync]: {Repl#2} now idle
08:21:25.674802| >> Replicator is idle, progress 34592/34592, 2162 docs
08:21:25.675212| [Sync]: {DBWorker#6} Notified of 94 db changes #2163 ... #2256
08:21:25.684370| [Sync]: {Push#5} Found 94 changes: Pusher sending 'proposeChanges' with sequences 2163 - 2256
08:21:25.686666| [Sync]: {Repl#2} now busy
08:21:25.687048| >> Replicator is busy, progress 34592/36096, 2162 docs
08:21:25.694661| [Sync]: {DBWorker#7} Looking up 94 proposed revisions in the db
08:21:25.700208| [Sync]: {DBWorker#7} Responding w/request for 94 revs
08:21:25.729885| >> Replicator is busy, progress 34592/36096, 2162 docs
08:21:25.773881| -------- Creating 96 docs --------
08:21:25.782418| [Sync]: {DBWorker#7} Inserted 94 revs in 17.42ms (5395/sec)
08:21:25.795184| [Sync]: {Push#5} Checkpoint now at 2200
08:21:25.802559| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='46-cc': {"local":2256} ...
08:21:25.809339| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:25.816176| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='47-cc'
08:21:25.832174| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:25.832740| [Sync]: {Repl#2} now idle
08:21:25.833519| [Sync]: {DBWorker#6} Notified of 96 db changes #2257 ... #2352
08:21:25.834119| >> Replicator is idle, progress 36096/36096, 2256 docs
08:21:25.842669| [Sync]: {Push#5} Found 96 changes: Pusher sending 'proposeChanges' with sequences 2257 - 2352
08:21:25.844954| [Sync]: {Repl#2} now busy
08:21:25.845370| >> Replicator is busy, progress 36096/37632, 2256 docs
08:21:25.853008| [Sync]: {DBWorker#7} Looking up 96 proposed revisions in the db
08:21:25.857970| [Sync]: {DBWorker#7} Responding w/request for 96 revs
08:21:25.930108| >> Replicator is busy, progress 36096/37632, 2256 docs
08:21:25.932079| -------- Creating 98 docs --------
08:21:25.939874| [Sync]: {DBWorker#7} Inserted 96 revs in 16.74ms (5734/sec)
08:21:25.952986| [Sync]: {Push#5} Checkpoint now at 2300
08:21:25.957829| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='47-cc': {"local":2352} ...
08:21:25.965583| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:25.972307| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='48-cc'
08:21:25.989688| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:25.990227| [Sync]: {Repl#2} now idle
08:21:25.990738| [Sync]: {DBWorker#6} Notified of 98 db changes #2353 ... #2450
08:21:25.991324| >> Replicator is idle, progress 37632/37632, 2352 docs
08:21:26.000561| [Sync]: {Push#5} Found 98 changes: Pusher sending 'proposeChanges' with sequences 2353 - 2450
08:21:26.002916| [Sync]: {Repl#2} now busy
08:21:26.003344| >> Replicator is busy, progress 37632/39200, 2352 docs
08:21:26.012267| [Sync]: {DBWorker#7} Looking up 98 proposed revisions in the db
08:21:26.017987| [Sync]: {DBWorker#7} Responding w/request for 98 revs
08:21:26.090314| -------- Creating 100 docs --------
08:21:26.101358| [Sync]: {DBWorker#7} Inserted 98 revs in 17.99ms (5447/sec)
08:21:26.114849| [Sync]: {Push#5} Checkpoint now at 2400
08:21:26.120518| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='48-cc': {"local":2450} ...
08:21:26.126805| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:26.130383| >> Replicator is busy, progress 39200/39200, 2450 docs
08:21:26.133960| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='49-cc'
08:21:26.150591| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:26.151429| [Sync]: {Repl#2} now idle
08:21:26.151975| [Sync]: {DBWorker#6} Notified of 100 db changes #2451 ... #2550
08:21:26.152691| >> Replicator is idle, progress 39200/39200, 2450 docs
08:21:26.161841| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2451 - 2550
08:21:26.164980| [Sync]: {Repl#2} now busy
08:21:26.165608| >> Replicator is busy, progress 39200/40800, 2450 docs
08:21:26.173476| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:26.179622| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:26.203505| >> Replicator is busy, progress 39200/40800, 2450 docs
08:21:26.250498| -------- Creating 102 docs --------
08:21:26.262218| [Sync]: {DBWorker#7} Inserted 100 revs in 17.63ms (5671/sec)
08:21:26.274634| [Sync]: {Push#5} Checkpoint now at 2500
08:21:26.279730| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='49-cc': {"local":2550} ...
08:21:26.287013| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:26.293721| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='50-cc'
08:21:26.311060| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:26.311584| [Sync]: {Repl#2} now idle
08:21:26.312080| [Sync]: {DBWorker#6} Notified of 100 db changes #2551 ... #2650
08:21:26.312574| >> Replicator is idle, progress 40800/40800, 2550 docs
08:21:26.321728| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2551 - 2650
08:21:26.322144| [Sync]: {DBWorker#6} Notified of 2 db changes #2651 ... #2652
08:21:26.324169| [Sync]: {Repl#2} now busy
08:21:26.324534| >> Replicator is busy, progress 40800/42400, 2550 docs
08:21:26.324953| [Sync]: {Push#5} Found 2 changes: Pusher sending 'proposeChanges' with sequences 2651 - 2652
08:21:26.331654| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:26.337641| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:26.386046| [Sync]: {DBWorker#7} Looking up 2 proposed revisions in the db
08:21:26.386808| [Sync]: {DBWorker#7} Responding w/request for 2 revs
08:21:26.403752| >> Replicator is busy, progress 40800/42432, 2550 docs
08:21:26.411720| -------- Creating 104 docs --------
08:21:26.419850| [Sync]: {DBWorker#7} Inserted 102 revs in 18.10ms (5636/sec)
08:21:26.432352| [Sync]: {Push#5} Checkpoint now at 2600
08:21:26.438948| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='50-cc': {"local":2652} ...
08:21:26.446567| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:26.452969| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='51-cc'
08:21:26.473881| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:26.474781| [Sync]: {Repl#2} now idle
08:21:26.475338| [Sync]: {DBWorker#6} Notified of 100 db changes #2653 ... #2752
08:21:26.475819| >> Replicator is idle, progress 42432/42432, 2652 docs
08:21:26.484940| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2653 - 2752
08:21:26.485355| [Sync]: {DBWorker#6} Notified of 4 db changes #2753 ... #2756
08:21:26.487379| [Sync]: {Repl#2} now busy
08:21:26.487766| [Sync]: {Push#5} Found 4 changes: Pusher sending 'proposeChanges' with sequences 2753 - 2756
08:21:26.488266| >> Replicator is busy, progress 42432/44032, 2652 docs
08:21:26.494871| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:26.500715| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:26.524925| >> Replicator is busy, progress 42432/44096, 2652 docs
08:21:26.552625| [Sync]: {DBWorker#7} Looking up 4 proposed revisions in the db
08:21:26.553554| [Sync]: {DBWorker#7} Responding w/request for 4 revs
08:21:26.573918| -------- Creating 106 docs --------
08:21:26.583940| [Sync]: {DBWorker#7} Inserted 100 revs in 17.99ms (5560/sec)
08:21:26.596546| [Sync]: {Push#5} Checkpoint now at 2700
08:21:26.604008| >> Replicator is busy, progress 44032/44096, 2752 docs
08:21:26.618014| [Sync]: {DBWorker#7} Inserted 4 revs in 0.98ms (4068/sec)
08:21:26.624464| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='51-cc': {"local":2756} ...
08:21:26.631418| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:26.635022| [Sync]: {DBWorker#6} Notified of 100 db changes #2757 ... #2856
08:21:26.638210| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='52-cc'
08:21:26.644374| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2757 - 2856
08:21:26.644745| [Sync]: {DBWorker#6} Notified of 6 db changes #2857 ... #2862
08:21:26.646426| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:26.646875| [Sync]: {Push#5} Found 6 changes: Pusher sending 'proposeChanges' with sequences 2857 - 2862
08:21:26.655132| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:26.660800| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:26.688114| >> Replicator is busy, progress 44096/45792, 2756 docs
08:21:26.708552| [Sync]: {DBWorker#7} Looking up 6 proposed revisions in the db
08:21:26.709457| [Sync]: {DBWorker#7} Responding w/request for 6 revs
08:21:26.725165| >> Replicator is busy, progress 44096/45792, 2756 docs
08:21:26.737124| -------- Creating 108 docs --------
08:21:26.751268| [Sync]: {DBWorker#7} Inserted 106 revs in 26.12ms (4059/sec)
08:21:26.759000| [Sync]: {Push#5} Checkpoint now at 2800
08:21:26.769363| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='52-cc': {"local":2862} ...
08:21:26.776617| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:26.783404| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='53-cc'
08:21:26.801214| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:26.801734| [Sync]: {Repl#2} now idle
08:21:26.802250| [Sync]: {DBWorker#6} Notified of 100 db changes #2863 ... #2962
08:21:26.803318| >> Replicator is idle, progress 45792/45792, 2862 docs
08:21:26.804281| >> Replicator is idle, progress 45792/45792, 2862 docs
08:21:26.812082| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2863 - 2962
08:21:26.812479| [Sync]: {DBWorker#6} Notified of 8 db changes #2963 ... #2970
08:21:26.814610| [Sync]: {Repl#2} now busy
08:21:26.815111| [Sync]: {Push#5} Found 8 changes: Pusher sending 'proposeChanges' with sequences 2963 - 2970
08:21:26.815586| >> Replicator is busy, progress 45792/47392, 2862 docs
08:21:26.822304| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:26.827897| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:26.877868| [Sync]: {DBWorker#7} Looking up 8 proposed revisions in the db
08:21:26.878884| [Sync]: {DBWorker#7} Responding w/request for 8 revs
08:21:26.901329| -------- Creating 110 docs --------
08:21:26.909665| [Sync]: {DBWorker#7} Inserted 101 revs in 17.29ms (5843/sec)
08:21:26.922091| [Sync]: {Push#5} Checkpoint now at 2900
08:21:26.925453| >> Replicator is busy, progress 46848/47520, 2928 docs
08:21:26.944916| [Sync]: {DBWorker#7} Inserted 7 revs in 1.48ms (4742/sec)
08:21:26.951782| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='53-cc': {"local":2970} ...
08:21:26.958801| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:26.965148| [Sync]: {DBWorker#6} Notified of 100 db changes #2971 ... #3070
08:21:26.965868| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='54-cc'
08:21:26.975350| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 2971 - 3070
08:21:26.975760| [Sync]: {DBWorker#6} Notified of 10 db changes #3071 ... #3080
08:21:26.977828| [Sync]: {Push#5} Found 10 changes: Pusher sending 'proposeChanges' with sequences 3071 - 3080
08:21:26.978222| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:26.985526| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:26.990878| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:27.015632| >> Replicator is busy, progress 47520/49280, 2970 docs
08:21:27.038213| [Sync]: {DBWorker#7} Looking up 10 proposed revisions in the db
08:21:27.039291| [Sync]: {DBWorker#7} Responding w/request for 10 revs
08:21:27.067799| -------- Creating 112 docs --------
08:21:27.072102| [Sync]: {DBWorker#7} Inserted 105 revs in 17.49ms (6004/sec)
08:21:27.081751| [Sync]: {Push#5} Checkpoint now at 3000
08:21:27.106823| [Sync]: {DBWorker#7} Inserted 5 revs in 1.17ms (4269/sec)
08:21:27.113142| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='54-cc': {"local":3080} ...
08:21:27.121173| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:27.125657| >> Replicator is busy, progress 49280/49280, 3080 docs
08:21:27.127828| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='55-cc'
08:21:27.137183| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:27.137643| [Sync]: {Repl#2} now idle
08:21:27.138145| [Sync]: {DBWorker#6} Notified of 100 db changes #3081 ... #3180
08:21:27.138632| >> Replicator is idle, progress 49280/49280, 3080 docs
08:21:27.147650| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3081 - 3180
08:21:27.148285| [Sync]: {DBWorker#6} Notified of 12 db changes #3181 ... #3192
08:21:27.150982| [Sync]: {Repl#2} now busy
08:21:27.151458| >> Replicator is busy, progress 49280/50880, 3080 docs
08:21:27.152510| [Sync]: {Push#5} Found 12 changes: Pusher sending 'proposeChanges' with sequences 3181 - 3192
08:21:27.158623| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:27.163781| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:27.214367| [Sync]: {DBWorker#7} Looking up 12 proposed revisions in the db
08:21:27.215795| >> Replicator is busy, progress 49280/51072, 3080 docs
08:21:27.216554| [Sync]: {DBWorker#7} Responding w/request for 12 revs
08:21:27.237764| -------- Creating 114 docs --------
08:21:27.246144| [Sync]: {DBWorker#7} Inserted 100 revs in 17.34ms (5766/sec)
08:21:27.258564| [Sync]: {Push#5} Checkpoint now at 3100
08:21:27.282174| [Sync]: {DBWorker#7} Inserted 12 revs in 2.31ms (5201/sec)
08:21:27.289365| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='55-cc': {"local":3192} ...
08:21:27.296291| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:27.303044| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='56-cc'
08:21:27.305425| [Sync]: {DBWorker#6} Notified of 100 db changes #3193 ... #3292
08:21:27.315185| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3193 - 3292
08:21:27.315632| [Sync]: {DBWorker#6} Notified of 14 db changes #3293 ... #3306
08:21:27.318954| [Sync]: {Push#5} Found 14 changes: Pusher sending 'proposeChanges' with sequences 3293 - 3306
08:21:27.319404| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:27.325909| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:27.331325| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:27.352094| >> Replicator is busy, progress 51072/52896, 3192 docs
08:21:27.382899| [Sync]: {DBWorker#7} Looking up 14 proposed revisions in the db
08:21:27.385440| [Sync]: {DBWorker#7} Responding w/request for 14 revs
08:21:27.407960| -------- Creating 116 docs --------
08:21:27.413739| [Sync]: {DBWorker#7} Inserted 100 revs in 17.75ms (5634/sec)
08:21:27.416853| >> Replicator is busy, progress 51072/52896, 3192 docs
08:21:27.424834| [Sync]: {Push#5} Checkpoint now at 3201
08:21:27.451871| [Sync]: {DBWorker#7} Inserted 14 revs in 2.81ms (4984/sec)
08:21:27.458771| [Sync]: {Push#5} Checkpoint now at 3300
08:21:27.459718| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='56-cc': {"local":3306} ...
08:21:27.467499| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:27.474293| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='57-cc'
08:21:27.474719| [Sync]: {DBWorker#6} Notified of 100 db changes #3307 ... #3406
08:21:27.484776| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3307 - 3406
08:21:27.485451| [Sync]: {DBWorker#6} Notified of 16 db changes #3407 ... #3422
08:21:27.487933| [Sync]: {Push#5} Found 16 changes: Pusher sending 'proposeChanges' with sequences 3407 - 3422
08:21:27.488706| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:27.496107| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:27.502553| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:27.551048| [Sync]: {DBWorker#7} Looking up 16 proposed revisions in the db
08:21:27.553027| [Sync]: {DBWorker#7} Responding w/request for 16 revs
08:21:27.576202| -------- Creating 118 docs --------
08:21:27.585922| [Sync]: {DBWorker#7} Inserted 101 revs in 18.67ms (5411/sec)
08:21:27.603959| [Sync]: {Push#5} Checkpoint now at 3400
08:21:27.617265| >> Replicator is busy, progress 54512/54752, 3407 docs
08:21:27.621184| [Sync]: {DBWorker#7} Inserted 15 revs in 2.91ms (5153/sec)
08:21:27.629343| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='57-cc': {"local":3422} ...
08:21:27.635779| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:27.642471| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='58-cc'
08:21:27.645929| [Sync]: {DBWorker#6} Notified of 100 db changes #3423 ... #3522
08:21:27.655915| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3423 - 3522
08:21:27.656652| [Sync]: {DBWorker#6} Notified of 18 db changes #3523 ... #3540
08:21:27.659751| [Sync]: {Push#5} Found 18 changes: Pusher sending 'proposeChanges' with sequences 3523 - 3540
08:21:27.660186| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:27.667611| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:27.673224| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:27.721997| [Sync]: {DBWorker#7} Looking up 18 proposed revisions in the db
08:21:27.723845| [Sync]: {DBWorker#7} Responding w/request for 18 revs
08:21:27.748395| -------- Creating 120 docs --------
08:21:27.757774| [Sync]: {DBWorker#7} Inserted 104 revs in 19.19ms (5418/sec)
08:21:27.775401| [Sync]: {Push#5} Checkpoint now at 3500
08:21:27.792247| [Sync]: {DBWorker#7} Inserted 14 revs in 2.75ms (5085/sec)
08:21:27.799860| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='58-cc': {"local":3540} ...
08:21:27.806901| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:27.813651| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='59-cc'
08:21:27.817521| >> Replicator is busy, progress 56640/56640, 3540 docs
08:21:27.825841| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:27.826276| [Sync]: {Repl#2} now idle
08:21:27.826750| >> Replicator is idle, progress 56640/56640, 3540 docs
08:21:27.827150| [Sync]: {DBWorker#6} Notified of 100 db changes #3541 ... #3640
08:21:27.837018| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3541 - 3640
08:21:27.837729| [Sync]: {DBWorker#6} Notified of 20 db changes #3641 ... #3660
08:21:27.839933| [Sync]: {Repl#2} now busy
08:21:27.840304| >> Replicator is busy, progress 56640/58240, 3540 docs
08:21:27.841283| [Sync]: {Push#5} Found 20 changes: Pusher sending 'proposeChanges' with sequences 3641 - 3660
08:21:27.847577| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:27.853847| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:27.902354| [Sync]: {DBWorker#7} Looking up 20 proposed revisions in the db
08:21:27.904327| [Sync]: {DBWorker#7} Responding w/request for 20 revs
08:21:27.925622| -------- Creating 122 docs --------
08:21:27.949393| [Sync]: {Push#5} Checkpoint now at 3600
08:21:27.951963| [Sync]: {DBWorker#7} Inserted 104 revs in 31.77ms (3274/sec)
08:21:27.972798| [Sync]: {DBWorker#7} Inserted 16 revs in 3.08ms (5197/sec)
08:21:27.980343| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='59-cc': {"local":3660} ...
08:21:27.987130| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:27.993889| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='60-cc'
08:21:28.025638| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:28.026221| [Sync]: {Repl#2} now idle
08:21:28.027090| [Sync]: {DBWorker#6} Notified of 100 db changes #3661 ... #3760
08:21:28.027562| >> Replicator is idle, progress 58560/58560, 3660 docs
08:21:28.036430| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3661 - 3760
08:21:28.037417| [Sync]: {DBWorker#6} Notified of 22 db changes #3761 ... #3782
08:21:28.040430| [Sync]: {Repl#2} now busy
08:21:28.041167| >> Replicator is busy, progress 58560/60160, 3660 docs
08:21:28.041620| >> Replicator is busy, progress 58560/60160, 3660 docs
08:21:28.042037| [Sync]: {Push#5} Found 22 changes: Pusher sending 'proposeChanges' with sequences 3761 - 3782
08:21:28.048717| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:28.055218| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:28.105549| [Sync]: {DBWorker#7} Looking up 22 proposed revisions in the db
08:21:28.108068| [Sync]: {DBWorker#7} Responding w/request for 22 revs
08:21:28.120912| -------- Creating 124 docs --------
08:21:28.140113| [Sync]: {DBWorker#7} Inserted 100 revs in 19.11ms (5233/sec)
08:21:28.151988| [Sync]: {Push#5} Checkpoint now at 3701
08:21:28.176234| [Sync]: {DBWorker#7} Inserted 22 revs in 4.17ms (5279/sec)
08:21:28.186547| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='60-cc': {"local":3782} ...
08:21:28.193502| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:28.195956| [Sync]: {DBWorker#6} Notified of 100 db changes #3783 ... #3882
08:21:28.200275| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='61-cc'
08:21:28.206062| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3783 - 3882
08:21:28.206466| [Sync]: {DBWorker#6} Notified of 24 db changes #3883 ... #3906
08:21:28.210323| [Sync]: {Push#5} Found 24 changes: Pusher sending 'proposeChanges' with sequences 3883 - 3906
08:21:28.211373| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:28.217230| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:28.222920| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:28.242503| >> Replicator is busy, progress 60512/62496, 3782 docs
08:21:28.272942| [Sync]: {DBWorker#7} Looking up 24 proposed revisions in the db
08:21:28.275080| [Sync]: {DBWorker#7} Responding w/request for 24 revs
08:21:28.299146| -------- Creating 126 docs --------
08:21:28.308651| [Sync]: {DBWorker#7} Inserted 100 revs in 20.44ms (4892/sec)
08:21:28.317759| [Sync]: {Push#5} Checkpoint now at 3800
08:21:28.343645| [Sync]: {DBWorker#7} Inserted 24 revs in 4.41ms (5441/sec)
08:21:28.351738| [Sync]: {Push#5} Checkpoint now at 3900
08:21:28.353113| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='61-cc': {"local":3906} ...
08:21:28.359601| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:28.366406| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='62-cc'
08:21:28.383431| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:28.383996| [Sync]: {Repl#2} now idle
08:21:28.384692| [Sync]: {DBWorker#6} Notified of 100 db changes #3907 ... #4006
08:21:28.385237| >> Replicator is idle, progress 62496/62496, 3906 docs
08:21:28.394020| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 3907 - 4006
08:21:28.394407| [Sync]: {DBWorker#6} Notified of 26 db changes #4007 ... #4032
08:21:28.396805| [Sync]: {Repl#2} now busy
08:21:28.397202| >> Replicator is busy, progress 62496/64096, 3906 docs
08:21:28.398617| [Sync]: {Push#5} Found 26 changes: Pusher sending 'proposeChanges' with sequences 4007 - 4032
08:21:28.404338| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:28.409584| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:28.443454| >> Replicator is busy, progress 62496/64512, 3906 docs
08:21:28.459282| [Sync]: {DBWorker#7} Looking up 26 proposed revisions in the db
08:21:28.461322| [Sync]: {DBWorker#7} Responding w/request for 26 revs
08:21:28.483478| -------- Creating 128 docs --------
08:21:28.495802| [Sync]: {DBWorker#7} Inserted 100 revs in 22.41ms (4462/sec)
08:21:28.509711| [Sync]: {Push#5} Checkpoint now at 4000
08:21:28.531358| [Sync]: {DBWorker#7} Inserted 26 revs in 4.88ms (5323/sec)
08:21:28.540258| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='62-cc': {"local":4032} ...
08:21:28.547866| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:28.554657| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='63-cc'
08:21:28.557502| [Sync]: {DBWorker#6} Notified of 100 db changes #4033 ... #4132
08:21:28.566970| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4033 - 4132
08:21:28.567377| [Sync]: {DBWorker#6} Notified of 28 db changes #4133 ... #4160
08:21:28.571783| [Sync]: {Push#5} Found 28 changes: Pusher sending 'proposeChanges' with sequences 4133 - 4160
08:21:28.572197| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:28.577496| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:28.582815| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:28.597578| >> Replicator is busy, progress 64512/66560, 4032 docs
08:21:28.631551| [Sync]: {DBWorker#7} Looking up 28 proposed revisions in the db
08:21:28.633513| [Sync]: {DBWorker#7} Responding w/request for 28 revs
08:21:28.643595| >> Replicator is busy, progress 64512/66560, 4032 docs
08:21:28.659583| -------- Creating 130 docs --------
08:21:28.668486| [Sync]: {DBWorker#7} Inserted 105 revs in 20.88ms (5029/sec)
08:21:28.686007| [Sync]: {Push#5} Checkpoint now at 4100
08:21:28.703148| [Sync]: {DBWorker#7} Inserted 23 revs in 4.47ms (5149/sec)
08:21:28.711156| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='63-cc': {"local":4160} ...
08:21:28.718144| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:28.724866| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='64-cc'
08:21:28.737517| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:28.737996| [Sync]: {Repl#2} now idle
08:21:28.738520| [Sync]: {DBWorker#6} Notified of 100 db changes #4161 ... #4260
08:21:28.740291| >> Replicator is idle, progress 66560/66560, 4160 docs
08:21:28.749389| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4161 - 4260
08:21:28.750028| [Sync]: {DBWorker#6} Notified of 30 db changes #4261 ... #4290
08:21:28.752063| [Sync]: {Repl#2} now busy
08:21:28.753033| >> Replicator is busy, progress 66560/68160, 4160 docs
08:21:28.753985| [Sync]: {Push#5} Found 30 changes: Pusher sending 'proposeChanges' with sequences 4261 - 4290
08:21:28.760561| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:28.765906| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:28.818823| [Sync]: {DBWorker#7} Looking up 30 proposed revisions in the db
08:21:28.821045| [Sync]: {DBWorker#7} Responding w/request for 30 revs
08:21:28.839328| -------- Creating 132 docs --------
08:21:28.843853| >> Replicator is busy, progress 66560/68640, 4160 docs
08:21:28.849348| [Sync]: {DBWorker#7} Inserted 100 revs in 19.52ms (5124/sec)
08:21:28.861161| [Sync]: {Push#5} Checkpoint now at 4200
08:21:28.890126| [Sync]: {DBWorker#7} Inserted 30 revs in 6.14ms (4883/sec)
08:21:28.900441| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='64-cc': {"local":4290} ...
08:21:28.907322| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:28.914099| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='65-cc'
08:21:28.917936| [Sync]: {DBWorker#6} Notified of 100 db changes #4291 ... #4390
08:21:28.927934| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4291 - 4390
08:21:28.928361| [Sync]: {DBWorker#6} Notified of 32 db changes #4391 ... #4422
08:21:28.931924| [Sync]: {Push#5} Found 32 changes: Pusher sending 'proposeChanges' with sequences 4391 - 4422
08:21:28.932317| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:28.938001| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:28.943206| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:28.953975| >> Replicator is busy, progress 68640/70752, 4290 docs
08:21:28.990032| [Sync]: {DBWorker#7} Looking up 32 proposed revisions in the db
08:21:28.992800| [Sync]: {DBWorker#7} Responding w/request for 32 revs
08:21:29.020033| -------- Creating 134 docs --------
08:21:29.025957| [Sync]: {DBWorker#7} Inserted 105 revs in 18.88ms (5562/sec)
08:21:29.033934| [Sync]: {Push#5} Checkpoint now at 4300
08:21:29.044079| >> Replicator is busy, progress 70320/70752, 4395 docs
08:21:29.064217| [Sync]: {DBWorker#7} Inserted 27 revs in 5.08ms (5313/sec)
08:21:29.070645| [Sync]: {Push#5} Checkpoint now at 4400
08:21:29.072844| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='65-cc': {"local":4422} ...
08:21:29.080535| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:29.087335| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='66-cc'
08:21:29.103702| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:29.104462| [Sync]: {Repl#2} now idle
08:21:29.104962| >> Replicator is idle, progress 70752/70752, 4422 docs
08:21:29.105389| [Sync]: {DBWorker#6} Notified of 100 db changes #4423 ... #4522
08:21:29.114754| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4423 - 4522
08:21:29.115315| [Sync]: {DBWorker#6} Notified of 34 db changes #4523 ... #4556
08:21:29.118203| [Sync]: {Repl#2} now busy
08:21:29.119371| >> Replicator is busy, progress 70752/72352, 4422 docs
08:21:29.120319| [Sync]: {Push#5} Found 34 changes: Pusher sending 'proposeChanges' with sequences 4523 - 4556
08:21:29.127351| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:29.132751| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:29.154306| >> Replicator is busy, progress 70752/72896, 4422 docs
08:21:29.184200| [Sync]: {DBWorker#7} Looking up 34 proposed revisions in the db
08:21:29.186952| [Sync]: {DBWorker#7} Responding w/request for 34 revs
08:21:29.206095| -------- Creating 136 docs --------
08:21:29.229487| [Sync]: {DBWorker#7} Inserted 100 revs in 31.20ms (3206/sec)
08:21:29.233067| [Sync]: {Push#5} Checkpoint now at 4500
08:21:29.244342| >> Replicator is busy, progress 72352/72896, 4522 docs
08:21:29.258871| [Sync]: {DBWorker#7} Inserted 34 revs in 6.49ms (5236/sec)
08:21:29.268823| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='66-cc': {"local":4556} ...
08:21:29.275773| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:29.282538| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='67-cc'
08:21:29.287108| [Sync]: {DBWorker#6} Notified of 100 db changes #4557 ... #4656
08:21:29.296601| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4557 - 4656
08:21:29.297000| [Sync]: {DBWorker#6} Notified of 36 db changes #4657 ... #4692
08:21:29.301182| [Sync]: {Push#5} Found 36 changes: Pusher sending 'proposeChanges' with sequences 4657 - 4692
08:21:29.301750| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:29.307433| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:29.312623| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:29.319478| >> Replicator is busy, progress 72896/75072, 4556 docs
08:21:29.354501| >> Replicator is busy, progress 72896/75072, 4556 docs
08:21:29.359374| [Sync]: {DBWorker#7} Looking up 36 proposed revisions in the db
08:21:29.361543| [Sync]: {DBWorker#7} Responding w/request for 36 revs
08:21:29.389494| -------- Creating 138 docs --------
08:21:29.395554| [Sync]: {DBWorker#7} Inserted 105 revs in 19.03ms (5517/sec)
08:21:29.407219| [Sync]: {Push#5} Checkpoint now at 4600
08:21:29.433310| [Sync]: {DBWorker#7} Inserted 31 revs in 5.71ms (5425/sec)
08:21:29.442608| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='67-cc': {"local":4692} ...
08:21:29.444581| >> Replicator is busy, progress 75072/75072, 4692 docs
08:21:29.448994| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:29.455784| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='68-cc'
08:21:29.469671| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:29.470186| [Sync]: {Repl#2} now idle
08:21:29.470705| >> Replicator is idle, progress 75072/75072, 4692 docs
08:21:29.471162| [Sync]: {DBWorker#6} Notified of 100 db changes #4693 ... #4792
08:21:29.480743| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4693 - 4792
08:21:29.481141| [Sync]: {DBWorker#6} Notified of 38 db changes #4793 ... #4830
08:21:29.483232| [Sync]: {Repl#2} now busy
08:21:29.483608| >> Replicator is busy, progress 75072/76672, 4692 docs
08:21:29.485550| [Sync]: {Push#5} Found 38 changes: Pusher sending 'proposeChanges' with sequences 4793 - 4830
08:21:29.490609| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:29.496526| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:29.546747| [Sync]: {DBWorker#7} Looking up 38 proposed revisions in the db
08:21:29.549098| [Sync]: {DBWorker#7} Responding w/request for 38 revs
08:21:29.555759| >> Replicator is busy, progress 75072/77280, 4692 docs
08:21:29.571237| -------- Creating 140 docs --------
08:21:29.580470| [Sync]: {DBWorker#7} Inserted 100 revs in 19.71ms (5072/sec)
08:21:29.588592| [Sync]: {Push#5} Checkpoint now at 4700
08:21:29.620982| [Sync]: {DBWorker#7} Inserted 38 revs in 7.15ms (5313/sec)
08:21:29.628205| [Sync]: {Push#5} Checkpoint now at 4800
08:21:29.630861| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='68-cc': {"local":4830} ...
08:21:29.637243| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:29.643999| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='69-cc'
08:21:29.654872| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:29.655332| [Sync]: {Repl#2} now idle
08:21:29.655848| >> Replicator is idle, progress 77280/77280, 4830 docs
08:21:29.656277| [Sync]: {DBWorker#6} Notified of 100 db changes #4831 ... #4930
08:21:29.665937| [Sync]: {Push#5} Found 100 changes: Pusher sending 'proposeChanges' with sequences 4831 - 4930
08:21:29.666436| [Sync]: {DBWorker#6} Notified of 40 db changes #4931 ... #4970
08:21:29.668544| [Sync]: {Repl#2} now busy
08:21:29.669080| >> Replicator is busy, progress 77280/78880, 4830 docs
08:21:29.671789| [Sync]: {Push#5} Found 40 changes: Pusher sending 'proposeChanges' with sequences 4931 - 4970
08:21:29.676868| [Sync]: {DBWorker#7} Looking up 100 proposed revisions in the db
08:21:29.682853| [Sync]: {DBWorker#7} Responding w/request for 100 revs
08:21:29.683891| >> Replicator is busy, progress 77280/79520, 4830 docs
08:21:29.730519| [Sync]: {DBWorker#7} Looking up 40 proposed revisions in the db
08:21:29.733053| [Sync]: {DBWorker#7} Responding w/request for 40 revs
08:21:29.756295| >> Replicator is busy, progress 77280/79520, 4830 docs
08:21:29.757336| -------- Creating 142 docs --------
08:21:29.767854| [Sync]: {DBWorker#7} Inserted 104 revs in 20.83ms (4993/sec)
08:21:29.784905| [Sync]: {Push#5} Checkpoint now at 4900
08:21:29.805215| [Sync]: {DBWorker#7} Inserted 36 revs in 7.17ms (5023/sec)
08:21:29.814841| [Sync]: {Repl#2} Saving remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= with rev='69-cc': {"local":4970} ...
08:21:29.821496| [Sync]: {DBWorker#7} Request to set checkpoint 'cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A='
08:21:29.828255| [Sync]: {Repl#2} Successfully saved remote checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= as rev='70-cc'
08:21:29.844208| -------- Done creating docs --------
08:21:29.844621| [Sync]: {DBWorker#6} Saved local checkpoint cp-K0L6W9REgiEHZiTeOkrL7Jxzx9A= to db
08:21:29.845187| [DB]: Closing DataFile
08:21:29.845737| [Sync]: {Repl#2} now idle
08:21:29.846221| [Sync]: {DBWorker#6} Notified of 100 db changes #4971 ... #5070
08:21:29.846993| >> Replicator is idle, progress 79520/79520, 4970 docs
08:21:29.847636| >> Stopping idle replicator...
08:21:29.848099| [Sync]: {Repl#2} Told to stop!
08:21:29.848519| [BLIP]: {Connection#1} Close connection
08:21:29.849027| [Sync]: {Repl#2} now busy
08:21:29.850151| [BLIP]: {Connection#1} Closed with WebSocket status 1000:
08:21:29.850682| >> Replicator is busy, progress 79520/79520, 4970 docs
08:21:29.851647| [Sync]: {Repl#2} Connection closed with WebSocket status 1000: "" (state=3)
08:21:29.853160| >> Replicator closed with code=0/1000, message=
08:21:29.854426| [BLIP]: {Connection#3} Closed with WebSocket status 1000:
08:21:29.854991| [Sync]: {Repl#4} Connection closed with WebSocket status 1000: "" (state=2)
08:21:29.855515| [Sync]: {Repl#4} now stopped
08:21:29.855989| [BLIP]: {BLIPIO#9}==> class litecore::blip::BLIPIO 0000026411AB1258
08:21:29.856432| [BLIP]: {BLIPIO#9} ~BLIPIO: Sent 41943 bytes, rcvd 107309. Max outbox depth was 1, avg 1.00
08:21:29.858531| [Sync]: {DBWorker#6} Notified of 42 db changes #5071 ... #5112
08:21:29.862696| [BLIP]: {BLIPIO#10}==> class litecore::blip::BLIPIO 00000264116E7FF8
08:21:29.863035| [BLIP]: {BLIPIO#10} ~BLIPIO: Sent 107309 bytes, rcvd 41943. Max outbox depth was 1, avg 1.00
08:21:29.863793| [Sync]: {Repl#2} now stopped
08:21:29.864533| >> Replicator is stopped, progress 79520/79520, 4970 docs
08:21:29.962388| >>> Replication complete <<<
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
CppTests.exe is a Catch v1.5.6 host application.
Run with -? for options
-----------------------------------------------------------------------------------------------------------------------
Continuous Fast Push
-----------------------------------------------------------------------------------------------------------------------
C:\Users\borrr\Development\couchbase-lite-net-ee\couchbase-lite-core\Replicator\tests\ReplicatorLoopbackTest.cc(384)
.......................................................................................................................
c:\users\borrr\development\couchbase-lite-net-ee\couchbase-lite-core\replicator\tests\ReplicatorLoopbackTest.hh(94): FAILED:
CHECK( _statusReceived.progress.documentCount == _expectedDocumentCount )
with expansion:
4970 == 5112
C:\Users\borrr\Development\couchbase-lite-net-ee\couchbase-lite-core\Replicator\tests\ReplicatorLoopbackTest.cc(388): FAILED:
CHECK( c4db_getDocumentCount(db) == c4db_getDocumentCount(db2) )
with expansion:
5112 == 4970
08:21:30.028422| [DB]: Closing DataFile
08:21:30.074578| [DB]: Deleting database file C:\tmp\Litecore_C_Tests\cbl_core_test_2\db.sqlite3 (with -wal and -shm)
08:21:30.079261| [DB]: Closing DataFile
08:21:30.110473| [DB]: Deleting database file C:\tmp\Litecore_C_Tests\cbl_core_test\db.sqlite3 (with -wal and -shm)
=======================================================================================================================
test cases: 1 | 0 passed | 1 failed
assertions: 20468 | 20466 passed | 2 failed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment