Here's the situation... we've updated our app to restructure the data into more dbs, so i need to run filtered replication from a source to a target. At the same time upgrading from 1.1.1 to 1.2.0.
I've added the replication function into a design doc on my source 1.1.1.
Then started a replication by POST'ing the following doc into the _replicator
db in 1.2.0 using:
{
"source": "http://localhost:5984/resource_data";,
"target": "http://localhost:6984/resource_data";,
"filter": "resources_only/resource_data",
"create_target": true
}
Everything seemed to start out fine, but it's been running now for 8 hours, and
it seems to be stuck at 78%.
The 1.1.1 log shows lines like this:
[Tue, 01 May 2012 18:04:48 GMT] [info] [<0.5612.1851>] 127.0.0.1 - - 'GET'
/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000
200
[Tue, 01 May 2012 18:04:52 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:04:57 GMT] [info] [<0.151.1851>] 127.0.0.1 - - 'GET'
/resource_data/_changes?feed=continuous&since=2089585&include_docs=true 200
[Tue, 01 May 2012 18:04:57 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:02 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:07 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:12 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:17 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:22 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:27 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:32 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:37 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:42 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:47 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:50 GMT] [info] [<0.6171.1851>] 127.0.0.1 - - 'GET'
/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000
200
[Tue, 01 May 2012 18:05:52 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:05:57 GMT] [info] [<0.151.1851>] 127.0.0.1 - - 'GET'
/resource_data/_changes?feed=continuous&since=2089585&include_docs=true 200
[Tue, 01 May 2012 18:05:57 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:02 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:07 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:12 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:17 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:22 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:27 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:32 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:37 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:42 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:47 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:52 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:06:57 GMT] [info] [<0.151.1851>] 127.0.0.1 - - 'GET'
/resource_data/_changes?feed=continuous&since=2089585&include_docs=true 200
[Tue, 01 May 2012 18:06:57 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:07:02 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:07:07 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:07:12 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:07:18 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:07:23 GMT] [info] [<0.3436.1851>] 127.0.0.1 - - 'GET'
/resource_data/ 200
[Tue, 01 May 2012 18:07:24 GMT] [info] [<0.7019.1851>] 127.0.0.1 - - 'GET'
/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000
200
however the 1.2.0 log shows lines like this:
[Tue, 01 May 2012 18:00:46 GMT] [info] [<0.27147.56>] Document
`f77e31042eba17585a9550e9a5000437` triggered replication
`b982ec538040ac664e15effb8e97297c+create_target`
[Tue, 01 May 2012 18:00:46 GMT] [info] [<0.27141.56>] starting new
replication `b982ec538040ac664e15effb8e97297c+create_target` at <0.27147.56>
(`http://localhost:5984/resource_data/` ->
`http://localhost:6984/resource_data/`)
[Tue, 01 May 2012 18:01:16 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:01:16 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 0.25 seconds
[Tue, 01 May 2012 18:01:46 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:01:46 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 0.5 seconds
[Tue, 01 May 2012 18:02:17 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:02:17 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 1.0 seconds
[Tue, 01 May 2012 18:02:48 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:02:48 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 2.0 seconds
[Tue, 01 May 2012 18:03:20 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:03:20 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 4.0 seconds
[Tue, 01 May 2012 18:03:54 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:03:54 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 8.0 seconds
[Tue, 01 May 2012 18:04:32 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:04:32 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 16.0 seconds
[Tue, 01 May 2012 18:05:18 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:05:18 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 32.0 seconds
[Tue, 01 May 2012 18:06:20 GMT] [error] [<0.27154.56>] Replicator, request
GET to
"http://localhost:5984/resource_data/_changes?filter=resources_only%2Fresource_data&feed=normal&style=all_docs&since=1646685&heartbeat=10000";
failed due to error req_timedout
[Tue, 01 May 2012 18:06:20 GMT] [info] [<0.27154.56>] Retrying _changes
request to source database http://localhost:5984/resource_data/ with
since=1646685 in 64.0 seconds
I'm not sure what to make of this... should I just copy the .couch files for
resource_data into the 1.2.0 instance, restart, and replicate 'internally'?
(I've had success with this in the past).
Or is there some incompatibility that exists that's causing 1.2.0 to timeout
even though 1.1.1 says okay w/ 200? It seems to me this should work.
FWIW, I'm seeing no erlang errors in either log.
- Jim
Jim Klo
Senior Software Engineer
Center for Software Engineering
SRI International
smime.p7s
Description: S/MIME cryptographic signature
