Status of pullreplication does not change to idle, runs infinite

Mobile Couchbase Lite
1

Hi,
i am not able to run a pullreplication from a couchdb instance.
Here is the code (adapt from demo):

@interface CBLTestRep : NSObject
{
    CBLManager*     m_manager;
    CBLReplication* m_push;
    CBLReplication* m_pull;
    NSDictionary*   m_repstate;
}
- (void) run: (CBLManager*) cblmanager;
- (void) replicationChanged: (NSNotification*) n ;
@end

@implementation CBLTestRep

- (void) run: (CBLManager*) cblmanager
{
    m_repstate =  @{
        [NSNumber numberWithInt:kCBLReplicationStopped]:  @"ReplicationStopped",
        [NSNumber numberWithInt:kCBLReplicationOffline]: @"ReplicationOffline",
        [NSNumber numberWithInt:kCBLReplicationIdle]: @"ReplicationIdle",
        [NSNumber numberWithInt:kCBLReplicationActive]: @"ReplicationActive"
    };


    m_manager = cblmanager;
    NSError* error = nil;
    CBLDatabase* db = [m_manager existingDatabaseNamed: @"demodb" error: &error];
    if(!db)
    {
        NSLog(@"demodb not exists, abort.");
        return;
    }
    [CBLManager enableLogging: @"Sync"];
    [CBLManager enableLogging: @"SyncVerbose"];


    NSURL* url = [NSURL URLWithString: @"https://192.168.1.30:6984/demodb/"];
    CBLReplication *push = [db createPushReplication: url];
    CBLReplication *pull = [db createPullReplication: url];
    id<CBLAuthenticator> authenticator = [CBLAuthenticator basicAuthenticatorWithName:@"user" password:@"password"];
    pull.authenticator = authenticator;
    push.authenticator = authenticator;
    push.continuous =  NO;
    pull.continuous = YES;


    [[NSNotificationCenter defaultCenter] addObserver: self
                                             selector: @selector(replicationChanged:)
                                                 name: kCBLReplicationChangeNotification
                                               object: push];
    [[NSNotificationCenter defaultCenter] addObserver: self
                                             selector: @selector(replicationChanged:)
                                                 name: kCBLReplicationChangeNotification
                                               object: pull];
    [push start];
    [pull start];
    // It's important to keep a reference to a running replication,
    // or it is likely to be dealloced!
    m_push = push;
    m_pull = pull;
    // The replications are running now; the -replicationChanged: method will
    // be called with notifications when their status changes.
}


- (void) replicationChanged: (NSNotification*)n
{

    // The replication reporting the notification is n.object , but we
    // want to look at the aggregate of both the push and pull.


    NSLog ( @"App: pull status - %@", [m_repstate objectForKey: [NSNumber numberWithInt:m_pull.status]]);
    NSLog ( @"App: push status - %@", [m_repstate objectForKey: [NSNumber numberWithInt:m_push.status]]);


    // First check whether replication is currently active:
    BOOL active = (m_pull.status == kCBLReplicationActive) || (m_push.status == kCBLReplicationActive);
    if (active) {
        double progress = 0.0;
        double total = m_push.changesCount + m_pull.changesCount;
        if (total > 0.0) {
            progress = (m_push.completedChangesCount + m_pull.completedChangesCount) / total;
        }
        NSLog(@"App: total: %f, progress; %f.", total, progress);
    }
}

@end

If I set pull.continuous to YES, the replicationChanged event will be infinite called with the pull status active. All other settings are ok.

Here is the log (extract)

016-05-11 16:06:37.505 QNative[716:15924] App: pull status - ReplicationActive
2016-05-11 16:06:37.505 QNative[716:15924] App: push status - ReplicationActive
2016-05-11 16:06:37.505 QNative[716:15924] App: total: 0.000000, progress; 0.000000.
2016-05-11 16:06:37.513 QNative[716:16026] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Server is CouchDB/1.6.1 (Erlang OTP/R14B01)
2016-05-11 16:06:37.514 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/1, active=1 (batch=0, net=1), online=1)
2016-05-11 16:06:37.514 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #1 {-BznR-_ssuqVb63jGPMZreV #1-2dd4d06018fcaf574ff9f5aeca31ca29}
2016-05-11 16:06:37.514 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/2, active=1 (batch=1, net=1), online=1)
2016-05-11 16:06:37.514 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #2 {-qiPhw-oJs8RV4kREtZkXhX #1-029d8a7336eb07c4513e622a4f5824dd}
2016-05-11 16:06:37.514 QNative[716:16026] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Caught up with changes!
2016-05-11 16:06:37.514 QNative[716:16026] SyncVerbose: *** CBLRestPuller[https://192.168.1.30:6984/demodb/]: BEGIN processInbox (2 sequences)
2016-05-11 16:06:37.514 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Looking up (
    "{-BznR-_ssuqVb63jGPMZreV #1-2dd4d06018fcaf574ff9f5aeca31ca29}",
    "{-qiPhw-oJs8RV4kREtZkXhX #1-029d8a7336eb07c4513e622a4f5824dd}"
)
2016-05-11 16:06:37.514 QNative[716:16026] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: 2 / 2
2016-05-11 16:06:37.515 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (2/2, active=1 (batch=0, net=0), online=1)
2016-05-11 16:06:37.515 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: no new remote revisions to fetch
2016-05-11 16:06:37.515 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Setting lastSequence to 2 (from (null))
2016-05-11 16:06:37.515 QNative[716:16026] SyncVerbose: *** CBLRestPuller[https://192.168.1.30:6984/demodb/]: END processInbox (lastSequence=2)
2016-05-11 16:06:37.515 QNative[716:16026] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: set active = 0
2016-05-11 16:06:37.515 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (2/2, active=0 (batch=0, net=0), online=1)
2016-05-11 16:06:37.515 QNative[716:16026] SyncVerbose: CBLSocketChangeTracker[0x7fdb07ad2520 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-11 16:06:37.522 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (2/3, active=0 (batch=0, net=0), online=1)
2016-05-11 16:06:37.522 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #1 {-BznR-_ssuqVb63jGPMZreV #1-2dd4d06018fcaf574ff9f5aeca31ca29}
2016-05-11 16:06:37.522 QNative[716:16026] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: set active = 1
2016-05-11 16:06:37.522 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (2/3, active=1 (batch=1, net=0), online=1)
2016-05-11 16:06:37.522 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (2/4, active=1 (batch=1, net=0), online=1)
2016-05-11 16:06:37.522 QNative[716:16026] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #2 {-qiPhw-oJs8RV4kREtZkXhX #1-029d8a7336eb07c4513e622a4f5824dd}
2016-05-11 16:06:37.523 QNative[716:16026] SyncVerbose: CBLSocketChangeTracker[0x7fdb07ad2520 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-11 16:06:37.525 QNative[716:16026] Sync: CBLRestPusher[https://192.168.1.30:6984/demodb/]: Server is CouchDB/1.6.1 (Erlang OTP/R14B01)
2016-05-11 16:06:37.525 QNative[716:16026] Sync: CBLRestPusher[https://192.168.1.30:6984/demodb/]: Replicating from lastSequence=2
2016-05-11 16:06:37.525 QNative[716:16026] SyncVerbose: CBLRestPusher[https://192.168.1.30:6984/demodb/]: Received 0 revs
2016-05-11 16:06:37.525 QNative[716:16026] Sync: CBLRestPusher[https://192.168.1.30:6984/demodb/] Progress: set active = 0
2016-05-11 16:06:37.525 QNative[716:16026] SyncVerbose: CBLRestPusher[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/0, active=0 (batch=0, net=0), online=1)
2016-05-11 16:06:37.525 QNative[716:16026] Sync: CBLRestPusher[https://192.168.1.30:6984/demodb/] STOPPED
2016-05-11 16:06:37.525 QNative[716:16026] Replication: CBLRestPusher[https://192.168.1.30:6984/demodb/] took 0.053 sec; error=(null)

Unfortunately I do not know how to continue.
Can someone help?

2

Hi! I edited your post to add formatting to make the code/log easier to read; hope you don’t mind. (You just add a line with three back-quotes at the start and end of the code.)

3

You’re only showing the first 20ms of logging. In that time the push replicator decides it has nothing to push (probably your local database is empty) and stops. The pull replicator is in the middle of receiving 4 documents from the server. I don’t know what happens next.

You’ll need to show more log output.

4

Hi,

thank you for the answer.

Here is a detailed output, i have dropped 550 lines (forum limitation).

2016-05-17 09:25:29.075 QNative[1348:24295] Sync: CBLReplication[from https://192.168.1.30:6984/demodb/]: offline, progress = 0 / 0, err: (null)
2016-05-17 09:25:29.075 QNative[1348:24295] App: pull status - ReplicationStopped
2016-05-17 09:25:29.075 QNative[1348:24295] App: push status - ReplicationStopped
kCBLReplicationStop reached.
2016-05-17 09:25:29.076 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] STARTING ...
2016-05-17 09:25:29.076 QNative[1348:24399] SyncPerf: CBLRestPuller[https://192.168.1.30:6984/demodb/] STARTING ...
2016-05-17 09:25:29.076 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Reachability state = <192.168.1.30>:reachable (20002), suspended=0
2016-05-17 09:25:29.077 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Going online
2016-05-17 09:25:29.077 QNative[1348:24399] SyncPerf: CBLRestPuller[https://192.168.1.30:6984/demodb/] Getting remote checkpoint
2016-05-17 09:25:29.077 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: set active = 1
2016-05-17 09:25:29.077 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/0, active=1 (batch=0, net=1), online=1)
2016-05-17 09:25:29.077 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: GET _local/2dae62bb19c8965d64c84ca7322e2a23100f347f
2016-05-17 09:25:29.077 QNative[1348:24399] RemoteRequest: CBLRemoteJSONRequest[GET https://192.168.1.30:6984/demodb/_local/2dae62bb19c8965d64c84ca7322e2a23100f347f]: Starting...
2016-05-17 09:25:29.079 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/0, active=1 (batch=0, net=1), online=1)
2016-05-17 09:25:29.079 QNative[1348:24295] Sync: CBLReplication[from https://192.168.1.30:6984/demodb/]: active, progress = 0 / 0, err: (null)
2016-05-17 09:25:29.080 QNative[1348:24295] App: pull status - ReplicationActive
2016-05-17 09:25:29.080 QNative[1348:24295] App: push status - ReplicationStopped
2016-05-17 09:25:29.080 QNative[1348:24295] App: total: 0.000000, progress; 0.000000.
2016-05-17 09:25:29.126 QNative[1348:24399] RemoteRequest: Got challenge for CBLRemoteJSONRequest[GET https://192.168.1.30:6984/demodb/_local/2dae62bb19c8965d64c84ca7322e2a23100f347f]: method=NSURLAuthenticationMethodServerTrust, proposed=(null), err=(null)
2016-05-17 09:25:29.135 QNative[1348:24399] RemoteRequest:     useCredential for trust: <SecTrust 0x7fbf38a40ba0 [0x7fff7a606440]>
2016-05-17 09:25:29.155 QNative[1348:24399] RemoteRequest: CBLRemoteJSONRequest[GET https://192.168.1.30:6984/demodb/_local/2dae62bb19c8965d64c84ca7322e2a23100f347f]: Got response, status 200
2016-05-17 09:25:29.155 QNative[1348:24399] RemoteRequestVerbose: CBLRemoteJSONRequest[GET https://192.168.1.30:6984/demodb/_local/2dae62bb19c8965d64c84ca7322e2a23100f347f]: Got 93 bytes
2016-05-17 09:25:29.155 QNative[1348:24399] RemoteRequest: CBLRemoteJSONRequest[GET https://192.168.1.30:6984/demodb/_local/2dae62bb19c8965d64c84ca7322e2a23100f347f]: Finished loading
2016-05-17 09:25:29.155 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Server is CouchDB/1.6.1 (Erlang OTP/R14B01)
2016-05-17 09:25:29.156 QNative[1348:24399] SyncPerf: CBLRestPuller[https://192.168.1.30:6984/demodb/] Got remote checkpoint
2016-05-17 09:25:29.156 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Replicating from lastSequence=27
2016-05-17 09:25:29.156 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/] starting ChangeTracker: mode=0, since=27
2016-05-17 09:25:29.156 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Starting...
2016-05-17 09:25:29.157 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.157 QNative[1348:24399] SyncVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.158 QNative[1348:24399] ChangeTracker: Changes feed using proxy settings {
    ExceptionsList =     (
        "*.local",
        "169.254/16"
    );
    FTPPassive = 1;
    "__SCOPED__" =     {
        en0 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
        en3 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
    };
}
2016-05-17 09:25:29.159 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Started... <https://192.168.1.30:6984/demodb/_changes>
2016-05-17 09:25:29.159 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Event 1 on <__NSCFInputStream: 0x7fbf36dcbd80>
2016-05-17 09:25:29.162 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: HasBytesAvailable <__NSCFInputStream: 0x7fbf36dcbd80>
2016-05-17 09:25:29.163 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb] got HTTP response headers (200) after 0.004 sec
2016-05-17 09:25:29.163 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: read 927 bytes
2016-05-17 09:25:29.163 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/1, active=1 (batch=0, net=1), online=1)
2016-05-17 09:25:29.163 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #5 {-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}
2016-05-17 09:25:29.163 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/2, active=1 (batch=1, net=1), online=1)
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #6 {-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/3, active=1 (batch=2, net=1), online=1)
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #7 {-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/4, active=1 (batch=3, net=1), online=1)
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #8 {-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/5, active=1 (batch=4, net=1), online=1)
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #9 {-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/6, active=1 (batch=5, net=1), online=1)
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #15 {-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/7, active=1 (batch=6, net=1), online=1)
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #16 {-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/8, active=1 (batch=7, net=1), online=1)
2016-05-17 09:25:29.164 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #21 {-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}
2016-05-17 09:25:29.165 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (0/9, active=1 (batch=8, net=1), online=1)
2016-05-17 09:25:29.165 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #30 {--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}
2016-05-17 09:25:29.165 QNative[1348:24295] Sync: CBLReplication[from https://192.168.1.30:6984/demodb/]: active, progress = 0 / 9, err: (null)
2016-05-17 09:25:29.165 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: EndEncountered <__NSCFInputStream: 0x7fbf36dcbd80>
2016-05-17 09:25:29.165 QNative[1348:24295] App: pull status - ReplicationActive
2016-05-17 09:25:29.165 QNative[1348:24295] App: push status - ReplicationStopped
2016-05-17 09:25:29.165 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb] reached EOF after 0.006 sec
2016-05-17 09:25:29.165 QNative[1348:24295] App: total: 9.000000, progress; 0.000000.
2016-05-17 09:25:29.165 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Caught up with changes!
2016-05-17 09:25:29.165 QNative[1348:24399] SyncVerbose: *** CBLRestPuller[https://192.168.1.30:6984/demodb/]: BEGIN processInbox (9 sequences)
2016-05-17 09:25:29.165 QNative[1348:24399] SyncPerf: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Processing 9 changes
2016-05-17 09:25:29.166 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Looking up (
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}"
)
2016-05-17 09:25:29.166 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: 9 / 9
2016-05-17 09:25:29.166 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (9/9, active=1 (batch=0, net=0), online=1)
2016-05-17 09:25:29.166 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: no new remote revisions to fetch
2016-05-17 09:25:29.166 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Setting lastSequence to 30 (from 27)
2016-05-17 09:25:29.166 QNative[1348:24399] SyncVerbose: *** CBLRestPuller[https://192.168.1.30:6984/demodb/]: END processInbox (lastSequence=30)
2016-05-17 09:25:29.166 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: set active = 0
2016-05-17 09:25:29.166 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (9/9, active=0 (batch=0, net=0), online=1)
2016-05-17 09:25:29.166 QNative[1348:24399] SyncPerf: CBLRestPuller[https://192.168.1.30:6984/demodb/] is now inactive
2016-05-17 09:25:29.166 QNative[1348:24295] Sync: CBLReplication[from https://192.168.1.30:6984/demodb/]: idle, progress = 9 / 9, err: (null)
2016-05-17 09:25:29.166 QNative[1348:24295] App: pull status - ReplicationIdle
2016-05-17 09:25:29.166 QNative[1348:24295] App: push status - ReplicationStopped
2016-05-17 09:25:29.166 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Starting...
kCBLReplicationIdle reached.
2016-05-17 09:25:29.167 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.167 QNative[1348:24399] SyncVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.167 QNative[1348:24399] ChangeTracker: Changes feed using proxy settings {
    ExceptionsList =     (
        "*.local",
        "169.254/16"
    );
    FTPPassive = 1;
    "__SCOPED__" =     {
        en0 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
        en3 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
    };
}
2016-05-17 09:25:29.167 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Started... <https://192.168.1.30:6984/demodb/_changes>
2016-05-17 09:25:29.167 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Event 1 on <__NSCFInputStream: 0x7fbf36a96450>
2016-05-17 09:25:29.170 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: HasBytesAvailable <__NSCFInputStream: 0x7fbf36a96450>
2016-05-17 09:25:29.170 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb] got HTTP response headers (200) after 0.003 sec
2016-05-17 09:25:29.170 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: read 927 bytes
2016-05-17 09:25:29.171 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (9/10, active=0 (batch=0, net=0), online=1)
2016-05-17 09:25:29.171 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #5 {-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}
...
/* 550 Lines dropped */
 ...
2016-05-17 09:25:29.225 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Starting...
2016-05-17 09:25:29.225 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.225 QNative[1348:24399] SyncVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.225 QNative[1348:24399] ChangeTracker: Changes feed using proxy settings {
    ExceptionsList =     (
        "*.local",
        "169.254/16"
    );
    FTPPassive = 1;
    "__SCOPED__" =     {
        en0 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
        en3 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
    };
}
2016-05-17 09:25:29.226 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Started... <https://192.168.1.30:6984/demodb/_changes>
2016-05-17 09:25:29.226 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Event 1 on <__NSCFInputStream: 0x7fbf38acdd80>
2016-05-17 09:25:29.228 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: HasBytesAvailable <__NSCFInputStream: 0x7fbf38acdd80>
2016-05-17 09:25:29.229 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb] got HTTP response headers (200) after 0.003 sec
2016-05-17 09:25:29.229 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: read 927 bytes
2016-05-17 09:25:29.229 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (9/109, active=1 (batch=99, net=0), online=1)
2016-05-17 09:25:29.229 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #5 {-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}
2016-05-17 09:25:29.229 QNative[1348:24399] SyncVerbose: *** CBLRestPuller[https://192.168.1.30:6984/demodb/]: BEGIN processInbox (100 sequences)
2016-05-17 09:25:29.229 QNative[1348:24399] SyncPerf: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Processing 100 changes
2016-05-17 09:25:29.230 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Looking up (
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}",
    "{-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}",
    "{-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}",
    "{-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}",
    "{-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}",
    "{-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}",
    "{-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}",
    "{-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}",
    "{--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}",
    "{-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}"
)
2016-05-17 09:25:29.230 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: 109 / 109
2016-05-17 09:25:29.230 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/109, active=1 (batch=0, net=0), online=1)
2016-05-17 09:25:29.230 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: no new remote revisions to fetch
2016-05-17 09:25:29.230 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Setting lastSequence to 5 (from 30)
2016-05-17 09:25:29.230 QNative[1348:24399] SyncVerbose: *** CBLRestPuller[https://192.168.1.30:6984/demodb/]: END processInbox (lastSequence=5)
2016-05-17 09:25:29.230 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: set active = 0
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/109, active=0 (batch=0, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncPerf: CBLRestPuller[https://192.168.1.30:6984/demodb/] is now inactive
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/110, active=0 (batch=0, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #6 {-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}
2016-05-17 09:25:29.231 QNative[1348:24399] Sync: CBLRestPuller[https://192.168.1.30:6984/demodb/] Progress: set active = 1
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/110, active=1 (batch=1, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/111, active=1 (batch=1, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #7 {-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/112, active=1 (batch=2, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #8 {-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/113, active=1 (batch=3, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #9 {-zH6XTKMk9NAQbWgMSous3U #1-d7623db3c8d3c80eda789ea9e96dd993}
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/114, active=1 (batch=4, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #15 {-OqZbrxQf6b6s_HF06JmoQL #3-44828bf078f519ab1339ea369e68ff4b}
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/115, active=1 (batch=5, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #16 {-P-jEeFNFSF5l71oxziGQyx #3-49b60e76363488c24a40316d08efcfeb}
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/116, active=1 (batch=6, net=0), online=1)
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #21 {-DV2v84-xJq_U9S7yG_S75n #3-131c645282c37fca0e9f67b366ca9ead}
2016-05-17 09:25:29.231 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/117, active=1 (batch=7, net=0), online=1)
2016-05-17 09:25:29.232 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #30 {--TrLQlEtvwTowqZkJRlIhf #17-fa7ac24946d90d6bef3ad2ad77a0edc9}
2016-05-17 09:25:29.232 QNative[1348:24295] Sync: CBLReplication[from https://192.168.1.30:6984/demodb/]: active, progress = 109 / 117, err: (null)
2016-05-17 09:25:29.232 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: EndEncountered <__NSCFInputStream: 0x7fbf38acdd80>
2016-05-17 09:25:29.232 QNative[1348:24295] App: pull status - ReplicationActive
2016-05-17 09:25:29.232 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb] reached EOF after 0.006 sec
2016-05-17 09:25:29.232 QNative[1348:24295] App: push status - ReplicationStopped
2016-05-17 09:25:29.232 QNative[1348:24295] App: total: 117.000000, progress; 0.931624.
2016-05-17 09:25:29.232 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Starting...
2016-05-17 09:25:29.232 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.232 QNative[1348:24399] SyncVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes
2016-05-17 09:25:29.232 QNative[1348:24399] ChangeTracker: Changes feed using proxy settings {
    ExceptionsList =     (
        "*.local",
        "169.254/16"
    );
    FTPPassive = 1;
    "__SCOPED__" =     {
        en0 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
        en3 =         {
            ExceptionsList =             (
                "*.local",
                "169.254/16"
            );
            FTPPassive = 1;
        };
    };
}
2016-05-17 09:25:29.233 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Started... <https://192.168.1.30:6984/demodb/_changes>
2016-05-17 09:25:29.233 QNative[1348:24399] ChangeTracker: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: Event 1 on <__NSCFInputStream: 0x7fbf36aaca80>
2016-05-17 09:25:29.235 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: HasBytesAvailable <__NSCFInputStream: 0x7fbf36aaca80>
2016-05-17 09:25:29.235 QNative[1348:24399] SyncPerf: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb] got HTTP response headers (200) after 0.003 sec
2016-05-17 09:25:29.235 QNative[1348:24399] ChangeTrackerVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: read 927 bytes
2016-05-17 09:25:29.235 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/118, active=1 (batch=8, net=0), online=1)
2016-05-17 09:25:29.235 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #5 {-mThgfVmTpgy8WT5nHPqRzu #1-3f8d29bcbf9db0b8301470ecd272c6e1}
2016-05-17 09:25:29.236 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/119, active=1 (batch=9, net=0), online=1)
2016-05-17 09:25:29.236 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #6 {-quZI-gf1vlxkC-J7Snu72d #1-8d96f74914dc5a47fa9b713ab407402a}
2016-05-17 09:25:29.236 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/120, active=1 (batch=10, net=0), online=1)
2016-05-17 09:25:29.236 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #7 {-rh4xnftncnJmFetC1dbBhf #1-5913ff0ae735c3f667d6de9f78d8dea7}
2016-05-17 09:25:29.236 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: postProgressChanged (109/121, active=1 (batch=11, net=0), online=1)
2016-05-17 09:25:29.236 QNative[1348:24399] SyncVerbose: CBLRestPuller[https://192.168.1.30:6984/demodb/]: Received #8 {-ws8jFyj1Rti-HYfoU-7SRm #1-be9a54e80f6b077dc07ce3a765d9c162}
2016-05-17 09:25:29.242 QNative[1348:24399] SyncVerbose: CBLSocketChangeTracker[0x7fbf36df1dc0 demodb]: POST //192.168.1.30:6984/demodb/_changes\\
5

Hi,
after some experimentation, I found out that linking the CBLFramework is not enough -
I have to embed it. After that the pull replications changes to state idle.

On the other side another error occurs after some time:
JSON error parsing _changes feed: parse error: after array element, I expect ‘,’ or ‘]’

How can this be explained?

This is the new the log-file.

CblLog:Type=Sync, message=CBLRestPuller[http://192.168.1.30:5984/demodb]: Server is CouchDB/1.6.1 (Erlang OTP/R14B01)
CblLog:Type=Sync, message=CBLRestPuller[http://192.168.1.30:5984/demodb]: Replicating from lastSequence=26
CblLog:Type=Sync, message=CBLRestPuller[http://192.168.1.30:5984/demodb] starting ChangeTracker: mode=0, since=26
CblLog:Type=Sync, message=CBLSocketChangeTracker[0x7fced8e04290 demodb]: POST //192.168.1.30:5984/demodb/_changes?feed=normal&heartbeat=300000&style=all_docs&since=26
CblLog:Type=Sync, message=CBLRestPusher[http://192.168.1.30:5984/demodb]: Setting lastSequence to 27 (from 23)
CblLog:Type=Sync, message=CBLRestPusher[http://192.168.1.30:5984/demodb] Progress: set active = 0
CblLog:Type=Sync, message=CBLRestPusher[http://192.168.1.30:5984/demodb]: postProgressChanged (0/0, active=0 (batch=0, net=0), online=1)
CblLog:Type=Sync, message=CBLReplication[to http://192.168.1.30:5984/demodb]: idle, progress = 0 / 0, err: (null)
"Replication new Status:  Active."
CblLog:Type=Sync, message=CBLRestPuller[http://192.168.1.30:5984/demodb]: Caught up with changes!
CblLog:Type=Sync, message=CBLRestPuller[http://192.168.1.30:5984/demodb] Progress: set active = 0
CblLog:Type=Sync, message=CBLRestPuller[http://192.168.1.30:5984/demodb]: postProgressChanged (0/0, active=0 (batch=0, net=0), online=1)
CblLog:Type=Sync, message=CBLSocketChangeTracker[0x7fced8e04290 demodb]: POST //192.168.1.30:5984/demodb/_changes?feed=longpoll&heartbeat=300000&style=all_docs&since=26
CblLog:Type=Sync, message=CBLReplication[from http://192.168.1.30:5984/demodb]: idle, progress = 0 / 0, err: (null)
"Replication new Status:  Idle."
...
...
CblLog:Type=WARNING, message=JSON error parsing _changes feed: parse error: after array element, I expect ',' or ']' {at -[CBLChangeTracker parseBytes:length:]:343}