couchdb-replication mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Scott Weber <scotty2...@sbcglobal.net>
Subject Re: Replication of attachment is extremely slow.. LOGGED INFORMATION
Date Fri, 24 Jan 2014 19:41:17 GMT
I do not see the log attached. It must have been stripped by the list server. 
I will copy/paste it to the bottom of this email.  It is 1024 lines.




----- Original Message -----
From: Scott Weber <scotty2541@sbcglobal.net>
To: "user@couchdb.apache.org" <user@couchdb.apache.org>
Cc: "replication@couchdb.apache.org" <replication@couchdb.apache.org>
Sent: Friday, January 24, 2014 1:34 PM
Subject: Re: Replication of attachment is extremely slow.. LOGGED INFORMATION

We have a duplication of the problem from a cleaned installation.  And there are some interesting things in the log, but I don't know what they mean, since I am not familiar with the internals of CouchDB.

I have attached the couch log. I can send the actual file being replicated, but it is about 4Meg.  Too big to make a reasonable attachment.  And I don't think it will be of value to this issue.

As to the log, it is broken in three sections (tests), which I will outline:
In all cases, it first deletes all databases, then creates them, and adds a doc and attachment.
<1>
The first is where replication was requested via a script HTTP post.
The attachment is uploaded at line 36 - content length 4185522 bytes
The "_replicate" request is PUT at line 49.
Then a bunch of "Minor error in HTTP request" messages appear.  Not sure what that means.
Then starting at line 328 to 329, you can see 5 second gaps as it tries to "GET" from the "from" database
and a bunch of "New task status" messages are repeated (about 15 of them).
This repeats, showing 5 second pauses until line 740, where it says "POST /to/_ensure_full_commit 201"
TOTAL TIME:  16:03:03 to 16:04:16   One minute, thirteen seconds.

<2>
The next test was done using CURL, starting at line 770, it deletes the databases, and starts over.
At line 818, there is the PUT request to the _replicator db  (NOTE: This is "_replicator", not "_replicate", what is the difference?)
There are only 2 "new task status" messages, and the replication is done by at line 911
TOTAL TIME:  16:17:28 to 16:17:30   2 seconds.

<3>
The next test was done using curl as well. It is a repeat of the second test, except the replication request was PUT to "_replicate" rather than "_replicator", just like the first test.
It starts at line 912, and looks to be identical to everything in test 2.
It took two seconds, there were only 2 "New task status" messages again.

So, the only difference we see is the script used a header that has a different user-agent (and had a few other minor differences), and posts a replication request JSON which is this:
   {"_id" : "test", 
    "source" : "http://localhost:5984/from",
    "target" : "http://localhost:5984/to", 
    "create_target" : false, 
    "continuous" : false }

Which is slightly more comprehensive than the CURL JSON which is just this:
     {"source":"from","target":"to"}

But these differences should not cause the replication to be 30 times longer, should they?

Any other ideas why one form of replication takes so much longer?

-Scott

-----------------PASTE-----------------------------------------
[Fri, 24 Jan 2014 16:02:43 GMT] [info] [<0.35.0>] Apache CouchDB has started on http://0.0.0.0:5984/
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.122.0>] 'DELETE' /from {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.122.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.122.0>] 127.0.0.1 - - DELETE /from 200
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.173.0>] 'DELETE' /to {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.173.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.173.0>] 127.0.0.1 - - DELETE /to 200
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.125.0>] 'PUT' /from {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.125.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.125.0>] 127.0.0.1 - - PUT /from 201
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.124.0>] 'PUT' /to {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.124.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.124.0>] 127.0.0.1 - - PUT /to 201
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.126.0>] 'PUT' /from/test {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Content-Length',"16"},
          {'Content-Type',"application/x-www-form-urlencoded"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.126.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.126.0>] 127.0.0.1 - - PUT /from/test 201
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.127.0>] 'PUT' /from/test/x937?rev=1-23a888c74ba58fe20dc0d424133750bb {1,
                                                                                  1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Content-Length',"4185522"},
          {'Content-Type',"application/octet-stream"},
          {"Expect","100-continue"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.127.0>] OAuth Params: [{"rev",
                                    "1-23a888c74ba58fe20dc0d424133750bb"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.127.0>] 127.0.0.1 - - PUT /from/test/x937?rev=1-23a888c74ba58fe20dc0d424133750bb 201
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.114.0>] 'POST' /_replicate {1,0} from "127.0.0.1"
Headers: [{'Authorization',"Basic cmlhbjpwYXNzd29yZA=="},
          {'Content-Length',"135"},
          {'Content-Type',"application/json"},
          {'Host',"127.0.0.1"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.114.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] 'HEAD' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.115.0>] 127.0.0.1 - - HEAD /from/ 200
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] 'HEAD' /to/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.117.0>] 127.0.0.1 - - HEAD /to/ 200
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.115.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] 'GET' /to/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.117.0>] 127.0.0.1 - - GET /to/ 200
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] 'GET' /from/_local/34754f379443f9584e7595cc8de826ff {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] Minor error in HTTP request: {not_found,missing}
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] Stacktrace: [{couch_httpd_db,couch_doc_open,4,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,806}]},
                                 {couch_httpd_db,db_doc_req,3,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,498}]},
                                 {couch_httpd_db,do_db_req,2,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,234}]},
                                 {couch_httpd,handle_request_int,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd.erl"},
                                   {line,332}]},
                                 {mochiweb_http,headers,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/mochiweb/mochiweb_http.erl"},
                                   {line,94}]},
                                 {proc_lib,init_p_do_apply,3,
                                  [{file,"proc_lib.erl"},{line,239}]}]
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.115.0>] 127.0.0.1 - - GET /from/_local/34754f379443f9584e7595cc8de826ff 404
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] httpd 404 error response:
 {"error":"not_found","reason":"missing"}

[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] 'GET' /from/_local/b035f0fbb5d2d5e1afac34f3708e844e {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] Minor error in HTTP request: {not_found,missing}
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] Stacktrace: [{couch_httpd_db,couch_doc_open,4,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,806}]},
                                 {couch_httpd_db,db_doc_req,3,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,498}]},
                                 {couch_httpd_db,do_db_req,2,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,234}]},
                                 {couch_httpd,handle_request_int,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd.erl"},
                                   {line,332}]},
                                 {mochiweb_http,headers,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/mochiweb/mochiweb_http.erl"},
                                   {line,94}]},
                                 {proc_lib,init_p_do_apply,3,
                                  [{file,"proc_lib.erl"},{line,239}]}]
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.115.0>] 127.0.0.1 - - GET /from/_local/b035f0fbb5d2d5e1afac34f3708e844e 404
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] httpd 404 error response:
 {"error":"not_found","reason":"missing"}

[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] 'GET' /from/_local/829e755391ecedb3c3db2f24421f3bcb {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] Minor error in HTTP request: {not_found,missing}
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] Stacktrace: [{couch_httpd_db,couch_doc_open,4,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,806}]},
                                 {couch_httpd_db,db_doc_req,3,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,498}]},
                                 {couch_httpd_db,do_db_req,2,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,234}]},
                                 {couch_httpd,handle_request_int,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd.erl"},
                                   {line,332}]},
                                 {mochiweb_http,headers,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/mochiweb/mochiweb_http.erl"},
                                   {line,94}]},
                                 {proc_lib,init_p_do_apply,3,
                                  [{file,"proc_lib.erl"},{line,239}]}]
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.115.0>] 127.0.0.1 - - GET /from/_local/829e755391ecedb3c3db2f24421f3bcb 404
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.115.0>] httpd 404 error response:
 {"error":"not_found","reason":"missing"}

[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] 'GET' /to/_local/34754f379443f9584e7595cc8de826ff {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] Minor error in HTTP request: {not_found,missing}
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] Stacktrace: [{couch_httpd_db,couch_doc_open,4,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,806}]},
                                 {couch_httpd_db,db_doc_req,3,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,498}]},
                                 {couch_httpd_db,do_db_req,2,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,234}]},
                                 {couch_httpd,handle_request_int,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd.erl"},
                                   {line,332}]},
                                 {mochiweb_http,headers,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/mochiweb/mochiweb_http.erl"},
                                   {line,94}]},
                                 {proc_lib,init_p_do_apply,3,
                                  [{file,"proc_lib.erl"},{line,239}]}]
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.117.0>] 127.0.0.1 - - GET /to/_local/34754f379443f9584e7595cc8de826ff 404
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] httpd 404 error response:
 {"error":"not_found","reason":"missing"}

[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] 'GET' /to/_local/b035f0fbb5d2d5e1afac34f3708e844e {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] Minor error in HTTP request: {not_found,missing}
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] Stacktrace: [{couch_httpd_db,couch_doc_open,4,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,806}]},
                                 {couch_httpd_db,db_doc_req,3,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,498}]},
                                 {couch_httpd_db,do_db_req,2,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,234}]},
                                 {couch_httpd,handle_request_int,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd.erl"},
                                   {line,332}]},
                                 {mochiweb_http,headers,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/mochiweb/mochiweb_http.erl"},
                                   {line,94}]},
                                 {proc_lib,init_p_do_apply,3,
                                  [{file,"proc_lib.erl"},{line,239}]}]
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.117.0>] 127.0.0.1 - - GET /to/_local/b035f0fbb5d2d5e1afac34f3708e844e 404
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] httpd 404 error response:
 {"error":"not_found","reason":"missing"}

[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] 'GET' /to/_local/829e755391ecedb3c3db2f24421f3bcb {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] Minor error in HTTP request: {not_found,missing}
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] Stacktrace: [{couch_httpd_db,couch_doc_open,4,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,806}]},
                                 {couch_httpd_db,db_doc_req,3,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,498}]},
                                 {couch_httpd_db,do_db_req,2,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd_db.erl"},
                                   {line,234}]},
                                 {couch_httpd,handle_request_int,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/couchdb/couch_httpd.erl"},
                                   {line,332}]},
                                 {mochiweb_http,headers,5,
                                  [{file,
                                    "c:/cygwin/relax/APACHE~1.0/src/mochiweb/mochiweb_http.erl"},
                                   {line,94}]},
                                 {proc_lib,init_p_do_apply,3,
                                  [{file,"proc_lib.erl"},{line,239}]}]
[Fri, 24 Jan 2014 16:03:03 GMT] [info] [<0.117.0>] 127.0.0.1 - - GET /to/_local/829e755391ecedb3c3db2f24421f3bcb 404
[Fri, 24 Jan 2014 16:03:03 GMT] [debug] [<0.117.0>] httpd 404 error response:
 {"error":"not_found","reason":"missing"}

[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.119.0>] 'GET' /from/_changes?feed=normal&style=all_docs&since=0&heartbeat=10000 {1,
                                                                                             1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.119.0>] OAuth Params: [{"feed","normal"},
                                   {"style","all_docs"},
                                   {"since","0"},
                                   {"heartbeat","10000"}]
[Fri, 24 Jan 2014 16:03:04 GMT] [info] [<0.119.0>] 127.0.0.1 - - GET /from/_changes?feed=normal&style=all_docs&since=0&heartbeat=10000 200
[Fri, 24 Jan 2014 16:03:04 GMT] [info] [<0.203.0>] Replication `"34754f379443f9584e7595cc8de826ff"` is using:
    4 worker processes
    a worker batch size of 500
    20 HTTP connections
    a connection timeout of 30000 milliseconds
    10 retries per request
    socket options are: [{keepalive,true},{nodelay,false}]
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.203.0>] Worker pids are: [<0.217.0>,<0.219.0>,<0.221.0>,<0.223.0>]
[Fri, 24 Jan 2014 16:03:04 GMT] [info] [<0.114.0>] starting new replication `34754f379443f9584e7595cc8de826ff` at <0.203.0> (`http://localhost:5984/from/` -> `http://localhost:5984/to/`)
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.117.0>] 'POST' /to/_revs_diff {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"47"},
          {'Content-Type',"application/json"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:04 GMT] [info] [<0.117.0>] 127.0.0.1 - - POST /to/_revs_diff 200
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.115.0>] 'GET' /from/test?revs=true&open_revs=%5B%222-d598f71bc82752ec68c68645c2bd46d5%22%5D&latest=true {1,
                                                                                                                     1} from "127.0.0.1"
Headers: [{'Accept',"multipart/mixed"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.115.0>] OAuth Params: [{"revs","true"},
                                   {"open_revs",
                                    "[\"2-d598f71bc82752ec68c68645c2bd46d5\"]"},
                                   {"latest","true"}]
[Fri, 24 Jan 2014 16:03:04 GMT] [info] [<0.115.0>] 127.0.0.1 - - GET /from/test?revs=true&open_revs=%5B%222-d598f71bc82752ec68c68645c2bd46d5%22%5D&latest=true 200
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.230.0>] Worker flushing doc with attachments
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.117.0>] 'PUT' /to/test?new_edits=false {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"4186112"},
          {'Content-Type',"multipart/related; boundary=\"49aeafb3545e308aef185b0c610bd9f0\""},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:04 GMT] [debug] [<0.117.0>] OAuth Params: [{"new_edits","false"}]
[Fri, 24 Jan 2014 16:03:09 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:09 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:09 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:09 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579389}]
[Fri, 24 Jan 2014 16:03:14 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:14 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:14 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:14 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579394}]
[Fri, 24 Jan 2014 16:03:19 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:19 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:19 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:19 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579399}]
[Fri, 24 Jan 2014 16:03:24 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:24 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:24 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:24 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579404}]
[Fri, 24 Jan 2014 16:03:29 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:29 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:29 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:29 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579409}]
[Fri, 24 Jan 2014 16:03:34 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:34 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:34 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:34 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579414}]
[Fri, 24 Jan 2014 16:03:39 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:39 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:39 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:39 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579419}]
[Fri, 24 Jan 2014 16:03:44 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:44 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:44 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:44 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579424}]
[Fri, 24 Jan 2014 16:03:49 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:49 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:49 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:49 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579429}]
[Fri, 24 Jan 2014 16:03:54 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:54 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:54 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:54 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579434}]
[Fri, 24 Jan 2014 16:03:59 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:03:59 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:03:59 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:03:59 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579439}]
[Fri, 24 Jan 2014 16:04:04 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:04:04 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:04 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:04:04 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579444}]
[Fri, 24 Jan 2014 16:04:09 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:04:09 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:09 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:04:09 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579449}]
[Fri, 24 Jan 2014 16:04:14 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:04:14 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:14 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:04:14 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,0},
                                                   {docs_written,0},
                                                   {missing_revisions_found,0},
                                                   {progress,0},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,0},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579454}]
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.117.0>] 127.0.0.1 - - PUT /to/test?new_edits=false 201
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.218.0>] Worker reported completion of seq {1,2}
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.203.0>] Worker reported seq {1,2}, through seq was {0,0}, new through seq is {1,
                                                                                          2}, highest seq done was {0,
                                                                                                                    0}, new highest seq done is {1,
                                                                                                                                                 2}
Seqs in progress were: [{1,2}]
Seqs in progress are now: []
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.93.0>] New task status for <0.203.0>: [{checkpointed_source_seq,0},
                                                   {continuous,false},
                                                   {doc_id,<<"test">>},
                                                   {doc_write_failures,0},
                                                   {docs_read,1},
                                                   {docs_written,1},
                                                   {missing_revisions_found,1},
                                                   {progress,100},
                                                   {replication_id,
                                                    <<"34754f379443f9584e7595cc8de826ff">>},
                                                   {revisions_checked,1},
                                                   {source,
                                                    <<"http://localhost:5984/from/">>},
                                                   {source_seq,2},
                                                   {started_on,1390579384},
                                                   {target,
                                                    <<"http://localhost:5984/to/">>},
                                                   {type,replication},
                                                   {updated_on,1390579456}]
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] 'POST' /from/_ensure_full_commit {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Content-Type',"application/json"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.117.0>] 'POST' /to/_ensure_full_commit {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Content-Type',"application/json"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.121.0>] 127.0.0.1 - - POST /from/_ensure_full_commit 201
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.117.0>] 127.0.0.1 - - POST /to/_ensure_full_commit 201
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.203.0>] recording a checkpoint for `http://localhost:5984/from/` -> `http://localhost:5984/to/` at source update_seq 2
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] 'PUT' /from/_local/34754f379443f9584e7595cc8de826ff {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"473"},
          {'Content-Type',"application/json"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"},
          {"X-Couch-Full-Commit","false"}]
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.121.0>] 127.0.0.1 - - PUT /from/_local/34754f379443f9584e7595cc8de826ff 201
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.117.0>] 'PUT' /to/_local/34754f379443f9584e7595cc8de826ff {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"473"},
          {'Content-Type',"application/json"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"},
          {"X-Couch-Full-Commit","false"}]
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.117.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.117.0>] 127.0.0.1 - - PUT /to/_local/34754f379443f9584e7595cc8de826ff 201
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] 'GET' /from/ {1,1} from "127.0.0.1"
Headers: [{'Accept',"application/json"},
          {'Content-Length',"0"},
          {'Host',"localhost:5984"},
          {'User-Agent',"CouchDB/1.5.0"}]
[Fri, 24 Jan 2014 16:04:16 GMT] [debug] [<0.121.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.121.0>] 127.0.0.1 - - GET /from/ 200
[Fri, 24 Jan 2014 16:04:16 GMT] [info] [<0.114.0>] 127.0.0.1 - - POST /_replicate 200
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.241.0>] 'DELETE' /from {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.241.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:17:27 GMT] [info] [<0.241.0>] 127.0.0.1 - - DELETE /from 200
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.120.0>] 'DELETE' /to {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.120.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:17:27 GMT] [info] [<0.120.0>] 127.0.0.1 - - DELETE /to 200
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.118.0>] 'PUT' /from {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.118.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:17:27 GMT] [info] [<0.118.0>] 127.0.0.1 - - PUT /from 201
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.116.0>] 'PUT' /to {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.116.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:17:27 GMT] [info] [<0.116.0>] 127.0.0.1 - - PUT /to 201
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.113.0>] 'PUT' /from/test {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Content-Length',"16"},
          {'Content-Type',"application/x-www-form-urlencoded"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:17:27 GMT] [debug] [<0.113.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:17:27 GMT] [info] [<0.113.0>] 127.0.0.1 - - PUT /from/test 201
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.112.0>] 'PUT' /from/test/x937?rev=1-23a888c74ba58fe20dc0d424133750bb {1,
                                                                                  1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Content-Length',"4185522"},
          {'Content-Type',"application/octet-stream"},
          {"Expect","100-continue"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.112.0>] OAuth Params: [{"rev",
                                    "1-23a888c74ba58fe20dc0d424133750bb"}]
[Fri, 24 Jan 2014 16:17:28 GMT] [info] [<0.112.0>] 127.0.0.1 - - PUT /from/test/x937?rev=1-23a888c74ba58fe20dc0d424133750bb 201
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.123.0>] 'PUT' /_replicator/test_slow {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Content-Length',"31"},
          {'Content-Type',"application/x-www-form-urlencoded"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.123.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process Start :: #Port<0.5880>
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.104.0>] Teach ddoc to new proc {proc,<0.1148.0>,<<"javascript">>,
                                            [],
                                            {couch_os_process,prompt},
                                            {couch_os_process,set_timeout},
                                            {couch_os_process,stop}} with DDocKey: {<<"_design/_replicator">>,
                                                                                    <<"1-5bfa2c99eefe2b2eb4962db50aa3cfd4">>}
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Input  :: ["ddoc","new","_design/_replicator",{"_id":"_design/_replicator","_rev":"1-5bfa2c99eefe2b2eb4962db50aa3cfd4","language":"javascript","validate_doc_update":"\n    function(newDoc, oldDoc, userCtx) {\n        function reportError(error_msg) {\n            log('Error writing document `' + newDoc._id +\n                '\\' to the replicator database: ' + error_msg);\n            throw({forbidden: error_msg});\n        }\n\n        function validateEndpoint(endpoint, fieldName) {\n            if ((typeof endpoint !== 'string') &&\n                ((typeof endpoint !== 'object') || (endpoint === null))) {\n\n                reportError('The `' + fieldName + '\\' property must exist' +\n                    ' and be either a string or an
 object.');\n            }\n\n            if (typeof endpoint === 'object') {\n                if ((typeof endpoint.url !== 'string') || !endpoint.url) {\n                    reportError('The url property must exist in the `' +\n                        fieldName + '\\' field and must be a non-empty string.');\n                }\n\n                if ((typeof endpoint.auth !== 'undefined') &&\n                    ((typeof endpoint.auth !== 'object') ||\n                        endpoint.auth === null)) {\n\n                    reportError('`' + fieldName +\n                        '.auth\\' must be a non-null object.');\n                }\n\n                if ((typeof endpoint.headers !== 'undefined')
 &&\n                    ((typeof endpoint.headers !== 'object') ||\n                        endpoint.headers === null)) {\n\n                    reportError('`' + fieldName +\n                        '.headers\\' must be a non-null object.');\n                }\n            }\n        }\n\n        var isReplicator = (userCtx.roles.indexOf('_replicator') >= 0);\n        var isAdmin = (userCtx.roles.indexOf('_admin') >= 0);\n\n        if (oldDoc && !newDoc._deleted && !isReplicator &&\n            (oldDoc._replication_state === 'triggered')) {\n            reportError('Only the replicator can edit replication documents ' +\n                'that are in the triggered state.');\n        }\n\n        if (!newDoc._deleted) {\n           
 validateEndpoint(newDoc.source, 'source');\n            validateEndpoint(newDoc.target, 'target');\n\n            if ((typeof newDoc.create_target !== 'undefined') &&\n                (typeof newDoc.create_target !== 'boolean')) {\n\n                reportError('The `create_target\\' field must be a boolean.');\n            }\n\n            if ((typeof newDoc.continuous !== 'undefined') &&\n                (typeof newDoc.continuous !== 'boolean')) {\n\n                reportError('The `continuous\\' field must be a boolean.');\n            }\n\n            if ((typeof newDoc.doc_ids !== 'undefined') &&\n                !isArray(newDoc.doc_ids)) {\n\n                reportError('The `doc_ids\\' field must be an array of strings.');\n            }\n\n            if ((typeof
 newDoc.filter !== 'undefined') &&\n                ((typeof newDoc.filter !== 'string') || !newDoc.filter)) {\n\n                reportError('The `filter\\' field must be a non-empty string.');\n            }\n\n            if ((typeof newDoc.query_params !== 'undefined') &&\n                ((typeof newDoc.query_params !== 'object') ||\n                    newDoc.query_params === null)) {\n\n                reportError('The `query_params\\' field must be an object.');\n            }\n\n            if (newDoc.user_ctx) {\n                var user_ctx = newDoc.user_ctx;\n\n                if ((typeof user_ctx !== 'object') || (user_ctx === null)) {\n                    reportError('The `user_ctx\\' property must be a ' +\n                        'non-null
 object.');\n                }\n\n                if (!(user_ctx.name === null ||\n                    (typeof user_ctx.name === 'undefined') ||\n                    ((typeof user_ctx.name === 'string') &&\n                        user_ctx.name.length > 0))) {\n\n                    reportError('The `user_ctx.name\\' property must be a ' +\n                        'non-empty string or null.');\n                }\n\n                if (!isAdmin && (user_ctx.name !== userCtx.name)) {\n                    reportError('The given `user_ctx.name\\' is not valid');\n                }\n\n                if (user_ctx.roles && !isArray(user_ctx.roles)) {\n                    reportError('The `user_ctx.roles\\' property must be '
 +\n                        'an array of strings.');\n                }\n\n                if (!isAdmin && user_ctx.roles) {\n                    for (var i = 0; i < user_ctx.roles.length; i++) {\n                        var role = user_ctx.roles[i];\n\n                        if (typeof role !== 'string' || role.length === 0) {\n                            reportError('Roles must be non-empty strings.');\n                        }\n                        if (userCtx.roles.indexOf(role) === -1) {\n                            reportError('Invalid role (`' + role +\n                                '\\') in the `user_ctx\\'');\n                       
 }\n                    }\n                }\n            } else {\n                if (!isAdmin) {\n                    reportError('The `user_ctx\\' property is missing (it is ' +\n                       'optional for admins only).');\n                }\n            }\n        } else {\n            if (!isAdmin) {\n                if (!oldDoc.user_ctx || (oldDoc.user_ctx.name !== userCtx.name)) {\n                    reportError('Replication documents can only be deleted by ' +\n                        'admins or by the users who created them.');\n                }\n            }\n        }\n    }\n"}]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Output :: true
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Input  :: ["reset",{"reduce_limit":true,"timeout":600000}]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Output :: true
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Input  :: ["ddoc","_design/_replicator",["validate_doc_update"],[{"_id":"test_slow","source":"from","target":"to","owner":"admin","_revisions":{"start":0,"ids":[]}},null,{"db":"_replicator","name":"admin","roles":["_admin"]},{}]]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Output :: 1
[Fri, 24 Jan 2014 16:17:28 GMT] [info] [<0.123.0>] 127.0.0.1 - - PUT /_replicator/test_slow 201
[Fri, 24 Jan 2014 16:17:28 GMT] [info] [<0.141.0>] Attempting to start replication `2c7e8c1e1f8e0dde2e29b03395320da4` (document `test_slow`).
[Fri, 24 Jan 2014 16:17:28 GMT] [info] [<0.1151.0>] Replication `"2c7e8c1e1f8e0dde2e29b03395320da4"` is using:
    4 worker processes
    a worker batch size of 500
    20 HTTP connections
    a connection timeout of 30000 milliseconds
    10 retries per request
    socket options are: [{keepalive,true},{nodelay,false}]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1151.0>] Worker pids are: [<0.1157.0>,<0.1158.0>,<0.1159.0>,
                                       <0.1160.0>]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1157.0>] Worker flushing doc with attachments
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.104.0>] DDocProc found for DDocKey: {<<"_design/_replicator">>,
                                                 <<"1-5bfa2c99eefe2b2eb4962db50aa3cfd4">>}
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Input  :: ["reset",{"reduce_limit":true,"timeout":600000}]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Output :: true
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Input  ::
 ["ddoc","_design/_replicator",["validate_doc_update"],[{"_id":"test_slow","_rev":"9-e8771f3a7da376bf5ec7de6d0621e094","source":"from","target":"to","owner":"admin","_replication_state":"triggered","_replication_state_time":"2014-01-24T10:17:28-06:00","_replication_id":"2c7e8c1e1f8e0dde2e29b03395320da4","_revisions":{"start":9,"ids":["e8771f3a7da376bf5ec7de6d0621e094","bc6b5a78f5aca5df6186f319bdfff864","b13beb5b518d56958c6f686712512608","0fceb2c155368db6f57f9236244f92ee","c458b4d077f9a36c2aa5f90ffad0722e","c8ce2f3c769aa5080587f2223c5a2fa1","eecc4a3518160cd983b2bd7f01f3b166","7b75ee746380c1a791f5e9f2fb4a2386","5c3c9988bbf8fd048bdde2d7ecab7dd6"]}},{"_id":"test_slow","_rev":"9-e8771f3a7da376bf5ec7de6d0621e094","source":"from","target":"to","owner":"admin","_revisions":{"start":9,"ids":["e8771f3a7da376bf5ec7de6d0621e094","bc6b5a78f5aca5df6186f319bdfff864","b13beb5b518d56958c6f686712512608","0fceb2c155368db6f57f9236244f92ee","c458b4d077f9a36c2aa5f90ffad0722e
","c8ce2f3c769aa5080587f2223c5a2fa1","eecc4a3518160cd983b2bd7f01f3b166","7b75ee746380c1a791f5e9f2fb4a2386","5c3c9988bbf8fd048bdde2d7ecab7dd6"]}},{"db":"_replicator","name":null,"roles":["_admin","_replicator"]},{}]]
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Output :: 1
[Fri, 24 Jan 2014 16:17:28 GMT] [info] [<0.1151.0>] Document `test_slow` triggered replication `2c7e8c1e1f8e0dde2e29b03395320da4`
[Fri, 24 Jan 2014 16:17:28 GMT] [info] [<0.1150.0>] starting new replication `2c7e8c1e1f8e0dde2e29b03395320da4` at <0.1151.0> (`from` -> `to`)
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1157.0>] Worker reported completion of seq {1,2}
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.1151.0>] Worker reported seq {1,2}, through seq was {0,0}, new through seq is {1,
                                                                                           2}, highest seq done was {0,
                                                                                                                     0}, new highest seq done is {1,
                                                                                                                                                  2}
Seqs in progress were: [{1,2}]
Seqs in progress are now: []
[Fri, 24 Jan 2014 16:17:28 GMT] [debug] [<0.93.0>] New task status for <0.1151.0>: [{checkpointed_source_seq,
                                                     0},
                                                    {continuous,false},
                                                    {doc_id,<<"test_slow">>},
                                                    {doc_write_failures,0},
                                                    {docs_read,1},
                                                    {docs_written,1},
                                                    {missing_revisions_found,
                                                     1},
                                                    {progress,100},
                                                    {replication_id,
                                                     <<"2c7e8c1e1f8e0dde2e29b03395320da4">>},
                                                    {revisions_checked,1},
                                                    {source,<<"from">>},
                                                    {source_seq,2},
                                                    {started_on,1390580248},
                                                    {target,<<"to">>},
                                                    {type,replication},
                                                    {updated_on,1390580248}]
[Fri, 24 Jan 2014 16:17:30 GMT] [info] [<0.1151.0>] recording a checkpoint for `from` -> `to` at source update_seq 2
[Fri, 24 Jan 2014 16:17:30 GMT] [debug] [<0.93.0>] New task status for <0.1151.0>: [{checkpointed_source_seq,
                                                     2},
                                                    {continuous,false},
                                                    {doc_id,<<"test_slow">>},
                                                    {doc_write_failures,0},
                                                    {docs_read,1},
                                                    {docs_written,1},
                                                    {missing_revisions_found,
                                                     1},
                                                    {progress,100},
                                                    {replication_id,
                                                     <<"2c7e8c1e1f8e0dde2e29b03395320da4">>},
                                                    {revisions_checked,1},
                                                    {source,<<"from">>},
                                                    {source_seq,2},
                                                    {started_on,1390580248},
                                                    {target,<<"to">>},
                                                    {type,replication},
                                                    {updated_on,1390580250}]
[Fri, 24 Jan 2014 16:17:30 GMT] [debug] [<0.104.0>] DDocProc found for DDocKey: {<<"_design/_replicator">>,
                                                 <<"1-5bfa2c99eefe2b2eb4962db50aa3cfd4">>}
[Fri, 24 Jan 2014 16:17:30 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Input  :: ["reset",{"reduce_limit":true,"timeout":600000}]
[Fri, 24 Jan 2014 16:17:30 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Output :: true
[Fri, 24 Jan 2014 16:17:30 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Input  ::
 ["ddoc","_design/_replicator",["validate_doc_update"],[{"_id":"test_slow","_rev":"10-5ec32fc496eaa6af8e0af0ff9e75ec16","source":"from","target":"to","owner":"admin","_replication_state":"completed","_replication_state_time":"2014-01-24T10:17:30-06:00","_replication_id":"2c7e8c1e1f8e0dde2e29b03395320da4","_replication_stats":{"revisions_checked":1,"missing_revisions_found":1,"docs_read":1,"docs_written":1,"doc_write_failures":0,"checkpointed_source_seq":2},"_revisions":{"start":10,"ids":["5ec32fc496eaa6af8e0af0ff9e75ec16","e8771f3a7da376bf5ec7de6d0621e094","bc6b5a78f5aca5df6186f319bdfff864","b13beb5b518d56958c6f686712512608","0fceb2c155368db6f57f9236244f92ee","c458b4d077f9a36c2aa5f90ffad0722e","c8ce2f3c769aa5080587f2223c5a2fa1","eecc4a3518160cd983b2bd7f01f3b166","7b75ee746380c1a791f5e9f2fb4a2386","5c3c9988bbf8fd048bdde2d7ecab7dd6"]}},{"_id":"test_slow","_rev":"10-5ec32fc496eaa6af8e0af0ff9e75ec16","source":"from","target":"to","owner":"admin","_replicati
on_state":"triggered","_replication_state_time":"2014-01-24T10:17:28-06:00","_replication_id":"2c7e8c1e1f8e0dde2e29b03395320da4","_revisions":{"start":10,"ids":["5ec32fc496eaa6af8e0af0ff9e75ec16","e8771f3a7da376bf5ec7de6d0621e094","bc6b5a78f5aca5df6186f319bdfff864","b13beb5b518d56958c6f686712512608","0fceb2c155368db6f57f9236244f92ee","c458b4d077f9a36c2aa5f90ffad0722e","c8ce2f3c769aa5080587f2223c5a2fa1","eecc4a3518160cd983b2bd7f01f3b166","7b75ee746380c1a791f5e9f2fb4a2386","5c3c9988bbf8fd048bdde2d7ecab7dd6"]}},{"db":"_replicator","name":null,"roles":["_admin","_replicator"]},{}]]
[Fri, 24 Jan 2014 16:17:30 GMT] [debug] [<0.1148.0>] OS Process #Port<0.5880> Output :: 1
[Fri, 24 Jan 2014 16:17:30 GMT] [info] [<0.1151.0>] Replication `2c7e8c1e1f8e0dde2e29b03395320da4` finished (triggered by document `test_slow`)
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1119.0>] 'DELETE' /from {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1119.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1119.0>] 127.0.0.1 - - DELETE /from 200
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1122.0>] 'DELETE' /to {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1122.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1122.0>] 127.0.0.1 - - DELETE /to 200
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1124.0>] 'PUT' /from {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1124.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1124.0>] 127.0.0.1 - - PUT /from 201
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1134.0>] 'PUT' /to {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1134.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1134.0>] 127.0.0.1 - - PUT /to 201
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1144.0>] 'PUT' /from/test {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Content-Length',"16"},
          {'Content-Type',"application/x-www-form-urlencoded"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1144.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1144.0>] 127.0.0.1 - - PUT /from/test 201
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1145.0>] 'PUT' /from/test/x937?rev=1-23a888c74ba58fe20dc0d424133750bb {1,
                                                                                   1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Content-Length',"4185522"},
          {'Content-Type',"application/octet-stream"},
          {"Expect","100-continue"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1145.0>] OAuth Params: [{"rev",
                                     "1-23a888c74ba58fe20dc0d424133750bb"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1145.0>] 127.0.0.1 - - PUT /from/test/x937?rev=1-23a888c74ba58fe20dc0d424133750bb 201
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1147.0>] 'POST' /_replicate {1,1} from "127.0.0.1"
Headers: [{'Accept',"*/*"},
          {'Authorization',"Basic YWRtaW46YWRtaW4="},
          {'Content-Length',"31"},
          {'Content-Type',"application/json"},
          {'Host',"127.0.0.1:5984"},
          {'User-Agent',"curl/7.31.0"}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1147.0>] OAuth Params: []
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1744.0>] Replication `"32b3b1ede908033172b54853f9031d30"` is using:
    4 worker processes
    a worker batch size of 500
    20 HTTP connections
    a connection timeout of 30000 milliseconds
    10 retries per request
    socket options are: [{keepalive,true},{nodelay,false}]
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1744.0>] Worker pids are: [<0.1750.0>,<0.1751.0>,<0.1752.0>,
                                       <0.1753.0>]
[Fri, 24 Jan 2014 16:26:38 GMT] [info] [<0.1147.0>] starting new replication `32b3b1ede908033172b54853f9031d30` at <0.1744.0> (`from` -> `to`)
[Fri, 24 Jan 2014 16:26:38 GMT] [debug] [<0.1750.0>] Worker flushing doc with attachments
[Fri, 24 Jan 2014 16:26:39 GMT] [debug] [<0.1750.0>] Worker reported completion of seq {1,2}
[Fri, 24 Jan 2014 16:26:39 GMT] [debug] [<0.1744.0>] Worker reported seq {1,2}, through seq was {0,0}, new through seq is {1,
                                                                                           2}, highest seq done was {0,
                                                                                                                     0}, new highest seq done is {1,
                                                                                                                                                  2}
Seqs in progress were: [{1,2}]
Seqs in progress are now: []
[Fri, 24 Jan 2014 16:26:39 GMT] [debug] [<0.93.0>] New task status for <0.1744.0>: [{checkpointed_source_seq,
                                                     0},
                                                    {continuous,false},
                                                    {doc_id,null},
                                                    {doc_write_failures,0},
                                                    {docs_read,1},
                                                    {docs_written,1},
                                                    {missing_revisions_found,
                                                     1},
                                                    {progress,100},
                                                    {replication_id,
                                                     <<"32b3b1ede908033172b54853f9031d30">>},
                                                    {revisions_checked,1},
                                                    {source,<<"from">>},
                                                    {source_seq,2},
                                                    {started_on,1390580798},
                                                    {target,<<"to">>},
                                                    {type,replication},
                                                    {updated_on,1390580799}]
[Fri, 24 Jan 2014 16:26:40 GMT] [info] [<0.1744.0>] recording a checkpoint for `from` -> `to` at source update_seq 2
[Fri, 24 Jan 2014 16:26:40 GMT] [debug] [<0.93.0>] New task status for <0.1744.0>: [{checkpointed_source_seq,
                                                     2},
                                                    {continuous,false},
                                                    {doc_id,null},
                                                    {doc_write_failures,0},
                                                    {docs_read,1},
                                                    {docs_written,1},
                                                    {missing_revisions_found,
                                                     1},
                                                    {progress,100},
                                                    {replication_id,
                                                     <<"32b3b1ede908033172b54853f9031d30">>},
                                                    {revisions_checked,1},
                                                    {source,<<"from">>},
                                                    {source_seq,2},
                                                    {started_on,1390580798},
                                                    {target,<<"to">>},
                                                    {type,replication},
                                                    {updated_on,1390580800}]
[Fri, 24 Jan 2014 16:26:40 GMT] [info] [<0.1147.0>] 127.0.0.1 - - POST /_replicate 200

Mime
View raw message