Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
CBLite push replication issue logs
01-12 20:05:57.732 6260-6260/? I/zygote: Not late-enabling -Xcheck:jni (already on)
01-12 20:05:57.740 6260-6260/? W/zygote: Unexpected CPU variant for X86 using defaults: x86
01-12 20:05:57.816 6260-6260/com.pab.testcbclient W/ActivityThread: Application com.pab.testcbclient is waiting for the debugger on port 8100...
01-12 20:05:57.817 6260-6260/com.pab.testcbclient I/System.out: Sending WAIT chunk
01-12 20:05:58.733 6260-6267/com.pab.testcbclient I/zygote: Debugger is active
01-12 20:05:58.817 6260-6260/com.pab.testcbclient I/System.out: Debugger has connected
waiting for debugger to settle...
01-12 20:05:59.018 6260-6260/com.pab.testcbclient I/System.out: waiting for debugger to settle...
01-12 20:05:59.218 6260-6260/com.pab.testcbclient I/chatty: uid=10083(u0_a83) com.pab.testcbclient identical 1 line
01-12 20:05:59.418 6260-6260/com.pab.testcbclient I/System.out: waiting for debugger to settle...
01-12 20:05:59.618 6260-6260/com.pab.testcbclient I/System.out: waiting for debugger to settle...
01-12 20:05:59.818 6260-6260/com.pab.testcbclient I/chatty: uid=10083(u0_a83) com.pab.testcbclient identical 1 line
01-12 20:06:00.018 6260-6260/com.pab.testcbclient I/System.out: waiting for debugger to settle...
01-12 20:06:00.219 6260-6260/com.pab.testcbclient I/System.out: debugger has settled (1455)
01-12 20:06:00.243 6260-6260/com.pab.testcbclient I/InstantRun: starting instant run server: is main process
01-12 20:06:00.302 6260-6260/com.pab.testcbclient I/test_main: DB opening/creating
01-12 20:06:00.304 6260-6260/com.pab.testcbclient I/DB: CouchbaseLite/2.1.2-2 (Java; Android 8.0.0; Android SDK built for x86) Build/0 Commit/b72e27e LiteCore/ (2)
01-12 20:06:00.305 6260-6260/com.pab.testcbclient I/DB: Opening Database@57a24f3{name='test_db'} at path /data/user/0/com.pab.testcbclient/files/test_db.cblite2
01-12 20:06:00.305 6260-6260/com.pab.testcbclient I/LiteCore [DB]: Opening database /data/user/0/com.pab.testcbclient/files/test_db.cblite2/db.sqlite3
01-12 20:06:00.306 6260-6260/com.pab.testcbclient I/test_main: DB opened/created
01-12 20:06:00.309 6260-6260/com.pab.testcbclient I/test_main: document exist
initial counter = 1, seq = 1
01-12 20:06:00.313 6260-6260/com.pab.testcbclient I/test_main: OnReplicationSwitchChanged: OFF
01-12 20:06:00.317 6260-6277/com.pab.testcbclient D/OpenGLRenderer: HWUI GL Pipeline
01-12 20:06:00.347 6260-6277/com.pab.testcbclient I/OpenGLRenderer: Initialized EGL, version 1.4
01-12 20:06:00.347 6260-6277/com.pab.testcbclient D/OpenGLRenderer: Swap behavior 1
01-12 20:06:00.348 6260-6277/com.pab.testcbclient W/OpenGLRenderer: Failed to choose config with EGL_SWAP_BEHAVIOR_PRESERVED, retrying without...
01-12 20:06:00.348 6260-6277/com.pab.testcbclient D/OpenGLRenderer: Swap behavior 0
01-12 20:06:00.356 6260-6277/com.pab.testcbclient D/EGL_emulation: eglCreateContext: 0x9e222700: maj 2 min 0 rcv 2
01-12 20:06:00.381 6260-6277/com.pab.testcbclient D/EGL_emulation: eglMakeCurrent: 0x9e222700: ver 2 0 (tinfo 0x9fdf5c60)
01-12 20:06:00.404 6260-6277/com.pab.testcbclient D/EGL_emulation: eglMakeCurrent: 0x9e222700: ver 2 0 (tinfo 0x9fdf5c60)
01-12 20:06:00.448 6260-6260/com.pab.testcbclient I/AssistStructure: Flattened final assist data: 3036 bytes, containing 1 windows, 10 views
01-12 20:06:04.205 6260-6260/com.pab.testcbclient I/test_main: OnReplicationSwitchChanged: ON
01-12 20:06:04.206 6260-6260/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}]: Starting
01-12 20:06:04.206 6260-6260/com.pab.testcbclient I/LiteCore [DB]: Opening database /data/user/0/com.pab.testcbclient/files/test_db.cblite2/db.sqlite3
01-12 20:06:04.207 6260-6260/com.pab.testcbclient I/LiteCore [BLIP]: {N8litecore4blip10ConnectionE#1}==> N8litecore4blip10ConnectionE ->ws://192.168.1.221:4984/db/_blipsync
{N8litecore4blip10ConnectionE#1} Opening connection...
01-12 20:06:04.207 6260-6260/com.pab.testcbclient I/LiteCore [Actor]: Starting Scheduler<0xa26e4580> with 4 threads
01-12 20:06:04.207 6260-6260/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1}==> N8litecore4repl10ReplicatorE /data/user/0/com.pab.testcbclient/files/test_db.cblite2/ ->ws://192.168.1.221:4984/db/_blipsync
{Repl#1} Push=continuous, Pull=continuous, Options={{auth:{password:"********", type:"Basic", username:"pab"}, headers:{User-Agent:"CouchbaseLite/2.1.2-2 (Java; Android 8.0.0; Android SDK built for x86) Build/0 Commit/b72e27e LiteCore/ (2)"}}}
01-12 20:06:04.207 6260-6287/com.pab.testcbclient W/C4Socket: C4Socket.open() socket -> 2883791128
C4Socket.open() clazz -> com.couchbase.lite.internal.replicator.CBLWebSocket
01-12 20:06:04.208 6260-6260/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is connecting, progress 0/0, error: null
C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=2, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:04.208 6260-6260/com.pab.testcbclient I/test_main: replication started
01-12 20:06:04.208 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=2, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:04.209 6260-6287/com.pab.testcbclient V/WS: CBLWebSocket.socket_open()
01-12 20:06:04.209 6260-6288/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is connecting, progress 0/0, error: null
01-12 20:06:04.210 6260-6265/com.pab.testcbclient I/zygote: Do partial code cache collection, code=30KB, data=29KB
01-12 20:06:04.211 6260-6265/com.pab.testcbclient I/zygote: After code cache collection, code=30KB, data=29KB
Increasing code cache capacity to 128KB
01-12 20:06:04.218 6260-6287/com.pab.testcbclient D/NetworkSecurityConfig: No Network Security Config specified, using platform default
01-12 20:06:04.219 6260-6287/com.pab.testcbclient V/WS: CBLWebSocket connecting to ws://192.168.1.221:4984/db/_blipsync...
01-12 20:06:04.224 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=CONNECTING, progress=Progress{completed=0, total=0}, error=null}}
01-12 20:06:04.225 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=connecting: connectionState=1
01-12 20:06:04.225 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#2}==> N8litecore4repl8DBWorkerE ->ws://192.168.1.221:4984/db/_blipsync
{DBWorker#2} activityLevel=idle: pendingResponseCount=0, eventCount=1
01-12 20:06:04.225 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} Local checkpoint 'cp-EMrLDayx2Heg81fVbXtS75sAWTU=' is [0, '2']; getting remote ...
{Repl#1} activityLevel=connecting: connectionState=1
{Repl#1} pushStatus=busy, pullStatus=busy, dbStatus=idle, progress=0/0
{Repl#1} activityLevel=connecting: connectionState=1
01-12 20:06:04.236 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onOpen() response -> Response{protocol=http/1.1, code=101, message=Switching Protocols, url=http://192.168.1.221:4984/db/_blipsync}
receivedHTTPResponse() httpStatus -> 101
01-12 20:06:04.236 6260-6289/com.pab.testcbclient I/WS: CBLWebSocket CONNECTED!
01-12 20:06:04.236 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=connecting: connectionState=1
01-12 20:06:04.236 6260-6289/com.pab.testcbclient I/LiteCore [BLIP]: {N8litecore4blip10ConnectionE#1} Connected!
01-12 20:06:04.236 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} Connected!
{Repl#1} activityLevel=busy: connectionState=2
{Repl#1} now busy
01-12 20:06:04.237 6260-6285/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791128
01-12 20:06:04.237 6260-6284/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:04.237 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:04.237 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is busy, progress 0/0, error: null
01-12 20:06:04.238 6260-6285/com.pab.testcbclient W/C4Socket: completedWrite(long) handle -> 2883791128, byteCount -> 68
01-12 20:06:04.239 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 0109522b4a2d6330d0356470cecf2b49cd2bd10da92c4865482c28c8c94c4e2cc9cccfd3cf2acecf63a8562a4acdcd2f4955b232aa0500c2dd15d5
01-12 20:06:04.240 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} Received remote checkpoint: [0, '2'] rev='0-1'
{Repl#1} activityLevel=busy: connectionState=2
{Push#3}==> N8litecore4repl6PusherE ->ws://192.168.1.221:4984/db/_blipsync
{Push#3} Starting continuous push from local seq #1
{Push#3} activityLevel=busy: pendingResponseCount=0, caughtUp=0, changeLists=1, revsInFlight=0, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=0
{Pull#4}==> N8litecore4repl6PullerE ->ws://192.168.1.221:4984/db/_blipsync
{Pull#4} Starting pull from remote seq 2
{Pull#4} activityLevel=busy: pendingResponseCount=1, _caughtUp=0, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:06:04.240 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791128
01-12 20:06:04.240 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} pushStatus=busy, pullStatus=busy, dbStatus=idle, progress=0/0
{Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:04.240 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#2} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#1} pushStatus=busy, pullStatus=busy, dbStatus=idle, progress=0/0
{Repl#1} activityLevel=busy: connectionState=2
{Pull#4} activityLevel=busy: pendingResponseCount=1, _caughtUp=0, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
{Push#3} Found 0 changes up to #1
01-12 20:06:04.240 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791128
01-12 20:06:04.240 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Push#3} Caught up, at lastSequence #1
{Push#3} activityLevel=idle: pendingResponseCount=0, caughtUp=1, changeLists=0, revsInFlight=0, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=0
{Repl#1} pushStatus=idle, pullStatus=busy, dbStatus=idle, progress=0/0
{Repl#1} activityLevel=busy: connectionState=2
{Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:04.240 6260-6286/com.pab.testcbclient W/C4Socket: completedWrite(long) handle -> 2883791128, byteCount -> 60
01-12 20:06:04.241 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791128
completedWrite(long) handle -> 2883791128, byteCount -> 32
01-12 20:06:04.241 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:04.241 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791128
completedWrite(long) handle -> 2883791128, byteCount -> 98
01-12 20:06:04.242 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 020100247914c3
01-12 20:06:04.242 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=busy: pendingResponseCount=0, _caughtUp=0, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:06:04.242 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 0128d20b28ca4fcbcc496548ce48cc4b4f2d26a035af342707008fdc95db
01-12 20:06:04.242 6260-6287/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791128
01-12 20:06:04.243 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} Caught up with remote changes
{Pull#4} activityLevel=idle: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
{Repl#1} pushStatus=idle, pullStatus=idle, dbStatus=idle, progress=0/0
{Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:04.243 6260-6287/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791128
01-12 20:06:04.245 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=BUSY, progress=Progress{completed=0, total=0}, error=null}}
01-12 20:06:04.246 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 04010872657600302d3200e8287eeb
01-12 20:06:04.246 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} Saved remote checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= as rev='0-2'
{Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:04.247 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#2} Saved local checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= to db
{DBWorker#2} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#1} activityLevel=idle: connectionState=2
{Repl#1} now idle
01-12 20:06:04.247 6260-6285/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:04.247 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is idle, progress 0/0, error: null
01-12 20:06:04.247 6260-6289/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791128
01-12 20:06:04.259 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=IDLE, progress=Progress{completed=0, total=0}, error=null}}
01-12 20:06:12.909 6260-6265/com.pab.testcbclient I/zygote: Do partial code cache collection, code=59KB, data=58KB
After code cache collection, code=59KB, data=58KB
Increasing code cache capacity to 256KB
01-12 20:06:15.714 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 020822514b74b4b18e52727e695e496a517c4a7eb2928e9291ae91a9858561529245b2a589a191a1654a62b245927172a2516aa241a2915192a5526c2c00627a0d88
01-12 20:06:15.714 6260-6284/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791128
01-12 20:06:15.714 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=busy: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=1, _pendingRevMessages=0, _activeIncomingRevs=0
{DBWorker#2} Received 1 changes (seq '3'..'3')
01-12 20:06:15.715 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#2} Responded to 'changes' REQ#2 w/request for 1 revs
{DBWorker#2} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#1} pushStatus=idle, pullStatus=busy, dbStatus=idle, progress=0/0
{Repl#1} activityLevel=busy: connectionState=2
{Repl#1} now busy
01-12 20:06:15.715 6260-6284/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:15.715 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=busy: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=1, _activeIncomingRevs=0
01-12 20:06:15.715 6260-6284/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791128
01-12 20:06:15.715 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} pushStatus=idle, pullStatus=busy, dbStatus=idle, progress=0/1
{Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:15.715 6260-6284/com.pab.testcbclient W/C4Socket: completedWrite(long) handle -> 2883791128, byteCount -> 74
01-12 20:06:15.715 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:15.716 6260-6288/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is busy, progress 0/0, error: null
01-12 20:06:15.716 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=BUSY, progress=Progress{completed=0, total=0}, error=null}}
01-12 20:06:15.716 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 03289ac608b307e4abcc14062433c042844d61284e2d2c4dcd4b4e653066c8c82c2ec92faa6430d44db6484d323636344bb5b0b04cb3344d4bb448364b4bb33030303137b34836211c7a508780820f00c558d1d4
01-12 20:06:15.716 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791128
01-12 20:06:15.716 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=busy: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=1
01-12 20:06:15.716 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=busy: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=1
01-12 20:06:15.742 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#2} Inserted 1 revs in 0.63ms (1583/sec)
{DBWorker#2} activityLevel=busy: pendingResponseCount=0, eventCount=2
01-12 20:06:15.742 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=busy: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:06:15.742 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=idle: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:06:15.742 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} pushStatus=idle, pullStatus=busy, dbStatus=busy, progress=0/1
01-12 20:06:15.742 6260-6260/com.pab.testcbclient I/test_main: OnDocumentChanged: DocumentChange{database='test_db'documentID='counter_doc'}
01-12 20:06:15.742 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=busy: connectionState=2
{Repl#1} pushStatus=idle, pullStatus=busy, dbStatus=busy, progress=1/1
{Repl#1} activityLevel=busy: connectionState=2
{Repl#1} pushStatus=idle, pullStatus=idle, dbStatus=busy, progress=1/1
{Repl#1} activityLevel=busy: connectionState=2
{Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:15.742 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:15.742 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791128
01-12 20:06:15.743 6260-6286/com.pab.testcbclient W/C4Socket: completedWrite(long) handle -> 2883791128, byteCount -> 98
01-12 20:06:15.743 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#2} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#1} pushStatus=idle, pullStatus=idle, dbStatus=idle, progress=1/1
{Repl#1} activityLevel=busy: connectionState=2
01-12 20:06:15.744 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 05010872657600302d330051c7ab87
01-12 20:06:15.744 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791128
01-12 20:06:15.744 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} Saved remote checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= as rev='0-3'
{Repl#1} activityLevel=busy: connectionState=2
{DBWorker#2} Saved local checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= to db
{DBWorker#2} activityLevel=idle: pendingResponseCount=0, eventCount=1
01-12 20:06:15.744 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=idle: connectionState=2
{Repl#1} now idle
01-12 20:06:15.745 6260-6284/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:15.745 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is idle, progress 1/1, error: null
01-12 20:06:15.747 6260-6260/com.pab.testcbclient I/test_main: change counter = 2, seq = 2
01-12 20:06:15.751 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=IDLE, progress=Progress{completed=1, total=1}, error=null}}
01-12 20:06:15.915 6260-6287/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:15.915 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=idle: connectionState=2
01-12 20:06:15.915 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:06:15.916 6260-6288/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is idle, progress 1/1, error: null
01-12 20:06:15.916 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=IDLE, progress=Progress{completed=1, total=1}, error=null}}
01-12 20:06:24.642 6260-6265/com.pab.testcbclient I/zygote: Do full code cache collection, code=118KB, data=117KB
After code cache collection, code=106KB, data=73KB
01-12 20:06:35.870 6260-6260/com.pab.testcbclient I/test_main: updated counter from 2 to 3
01-12 20:06:35.871 6260-6260/com.pab.testcbclient I/test_main: OnDocumentChanged: DocumentChange{database='test_db'documentID='counter_doc'}
01-12 20:06:35.872 6260-6260/com.pab.testcbclient I/test_main: change counter = 3, seq = 3
01-12 20:06:56.267 6260-6260/com.pab.testcbclient I/test_main: updated counter from 3 to 4
01-12 20:06:56.268 6260-6260/com.pab.testcbclient I/test_main: OnDocumentChanged: DocumentChange{database='test_db'documentID='counter_doc'}
01-12 20:06:56.268 6260-6265/com.pab.testcbclient I/zygote: Do partial code cache collection, code=122KB, data=100KB
After code cache collection, code=122KB, data=100KB
Increasing code cache capacity to 512KB
01-12 20:06:56.269 6260-6260/com.pab.testcbclient I/test_main: change counter = 4, seq = 4
01-12 20:07:14.742 6260-6260/com.pab.testcbclient I/test_main: OnReplicationSwitchChanged: OFF
01-12 20:07:14.742 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} Told to stop!
01-12 20:07:14.742 6260-6285/com.pab.testcbclient I/LiteCore [BLIP]: {N8litecore4blip10ConnectionE#1} Closing with code 1000, msg ''
01-12 20:07:14.742 6260-6260/com.pab.testcbclient I/test_main: replication stopped
01-12 20:07:14.742 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} activityLevel=busy: connectionState=3
{Repl#1} now busy
01-12 20:07:14.742 6260-6286/com.pab.testcbclient I/LiteCore [WS]: {N8litecore4repl12C4SocketImplE#1}==> N8litecore4repl12C4SocketImplE ws://192.168.1.221:4984/db/_blipsync
{N8litecore4repl12C4SocketImplE#1} Requesting close with status=1000, message=''
01-12 20:07:14.743 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.requestClose() handle -> 2883791128
01-12 20:07:14.743 6260-6285/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:14.743 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is busy, progress 1/1, error: null
01-12 20:07:14.744 6260-6289/com.pab.testcbclient V/WS: WebSocketListener.onClosing() code -> 1000, reason ->
WebSocketListener.onClosed() code -> 1000, reason ->
01-12 20:07:14.744 6260-6289/com.pab.testcbclient I/LiteCore [WS]: {N8litecore4repl12C4SocketImplE#1} sent 430 bytes, rcvd 276, in 70.508 sec (6/sec, 4/sec)
01-12 20:07:14.744 6260-6284/com.pab.testcbclient W/C4Socket: C4Socket.dispose() handle -> 2883791128
01-12 20:07:14.744 6260-6284/com.pab.testcbclient I/LiteCore [BLIP]: {N8litecore4blip10ConnectionE#1} Closed with WebSocket status 1000:
01-12 20:07:14.744 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#1} Connection closed with WebSocket status 1000: "" (state=3)
{Repl#1} activityLevel=stopped: connectionState=0
{Repl#1} now stopped
01-12 20:07:14.745 6260-6284/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=0, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:14.745 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#2} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Push#3} activityLevel=idle: pendingResponseCount=0, caughtUp=1, changeLists=0, revsInFlight=0, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=0
01-12 20:07:14.745 6260-6284/com.pab.testcbclient I/LiteCore [BLIP]: {N8litecore4blip6BLIPIOE#2}==> N8litecore4blip6BLIPIOE 0xabe34c40
{N8litecore4blip6BLIPIOE#2} ~BLIPIO: Sent 430 bytes, rcvd 276. Max outbox depth was 1, avg 1.00
01-12 20:07:14.745 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Pull#4} activityLevel=idle: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:07:14.745 6260-6284/com.pab.testcbclient I/LiteCore [DB]: Closing database /data/user/0/com.pab.testcbclient/files/test_db.cblite2/db.sqlite3
01-12 20:07:14.745 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=0, progressUnitsCompleted=1, progressUnitsTotal=1, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is stopped, progress 1/1, error: null
01-12 20:07:14.756 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=BUSY, progress=Progress{completed=1, total=1}, error=null}}
01-12 20:07:14.759 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=STOPPED, progress=Progress{completed=1, total=1}, error=null}}
01-12 20:07:26.393 6260-6260/com.pab.testcbclient I/test_main: OnReplicationSwitchChanged: ON
01-12 20:07:26.393 6260-6260/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}]: Starting
01-12 20:07:26.393 6260-6260/com.pab.testcbclient I/LiteCore [DB]: Opening database /data/user/0/com.pab.testcbclient/files/test_db.cblite2/db.sqlite3
01-12 20:07:26.393 6260-6260/com.pab.testcbclient I/LiteCore [BLIP]: {N8litecore4blip10ConnectionE#3}==> N8litecore4blip10ConnectionE ->ws://192.168.1.221:4984/db/_blipsync
{N8litecore4blip10ConnectionE#3} Opening connection...
01-12 20:07:26.393 6260-6260/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5}==> N8litecore4repl10ReplicatorE /data/user/0/com.pab.testcbclient/files/test_db.cblite2/ ->ws://192.168.1.221:4984/db/_blipsync
{Repl#5} Push=continuous, Pull=continuous, Options={{auth:{password:"********", type:"Basic", username:"pab"}, headers:{User-Agent:"CouchbaseLite/2.1.2-2 (Java; Android 8.0.0; Android SDK built for x86) Build/0 Commit/b72e27e LiteCore/ (2)"}}}
01-12 20:07:26.394 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.open() socket -> 2883791704
C4Socket.open() clazz -> com.couchbase.lite.internal.replicator.CBLWebSocket
01-12 20:07:26.394 6260-6260/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is connecting, progress 0/0, error: null
01-12 20:07:26.394 6260-6286/com.pab.testcbclient V/WS: CBLWebSocket.socket_open()
01-12 20:07:26.394 6260-6260/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=2, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:26.394 6260-6260/com.pab.testcbclient I/test_main: replication started
01-12 20:07:26.394 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=2, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is connecting, progress 0/0, error: null
01-12 20:07:26.394 6260-6286/com.pab.testcbclient V/WS: CBLWebSocket connecting to ws://192.168.1.221:4984/db/_blipsync...
01-12 20:07:26.395 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} activityLevel=connecting: connectionState=1
01-12 20:07:26.396 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#6}==> N8litecore4repl8DBWorkerE ->ws://192.168.1.221:4984/db/_blipsync
{DBWorker#6} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#5} Local checkpoint 'cp-EMrLDayx2Heg81fVbXtS75sAWTU=' is [1, '3']; getting remote ...
{Repl#5} activityLevel=connecting: connectionState=1
{Repl#5} pushStatus=busy, pullStatus=busy, dbStatus=idle, progress=0/0
{Repl#5} activityLevel=connecting: connectionState=1
01-12 20:07:26.399 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=CONNECTING, progress=Progress{completed=0, total=0}, error=null}}
01-12 20:07:26.399 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onOpen() response -> Response{protocol=http/1.1, code=101, message=Switching Protocols, url=http://192.168.1.221:4984/db/_blipsync}
receivedHTTPResponse() httpStatus -> 101
01-12 20:07:26.399 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} activityLevel=connecting: connectionState=1
01-12 20:07:26.399 6260-6319/com.pab.testcbclient I/WS: CBLWebSocket CONNECTED!
01-12 20:07:26.400 6260-6319/com.pab.testcbclient I/LiteCore [BLIP]: {N8litecore4blip10ConnectionE#3} Connected!
01-12 20:07:26.400 6260-6284/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} Connected!
{Repl#5} activityLevel=busy: connectionState=2
{Repl#5} now busy
01-12 20:07:26.400 6260-6284/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:26.401 6260-6284/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791704
completedWrite(long) handle -> 2883791704, byteCount -> 68
01-12 20:07:26.401 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.401 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=4, progressUnitsCompleted=0, progressUnitsTotal=0, progressDocumentCount=0, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:26.402 6260-6288/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is busy, progress 0/0, error: null
01-12 20:07:26.402 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 0109522b4a2d6330d0356670cecf2b49cd2bd10da92c4865482c28c8c94c4e2cc9cccfd3cf2acecf63a856cac94f4ecc51b232d4512a4acdcd2f4955b232ae050037b41ba0
01-12 20:07:26.403 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} Received remote checkpoint: [1, '3'] rev='0-3'
{Repl#5} activityLevel=busy: connectionState=2
{Pull#7}==> N8litecore4repl6PullerE ->ws://192.168.1.221:4984/db/_blipsync
{Pull#7} Starting pull from remote seq 3
01-12 20:07:26.403 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Push#8}==> N8litecore4repl6PusherE ->ws://192.168.1.221:4984/db/_blipsync
{Push#8} Starting continuous push from local seq #2
{Push#8} activityLevel=busy: pendingResponseCount=0, caughtUp=0, changeLists=1, revsInFlight=0, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=0
01-12 20:07:26.403 6260-6319/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.403 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791704
completedWrite(long) handle -> 2883791704, byteCount -> 60
01-12 20:07:26.403 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#6} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#5} pushStatus=busy, pullStatus=busy, dbStatus=idle, progress=0/0
{Repl#5} activityLevel=busy: connectionState=2
{Push#8} Read 1 local changes up to #4: sending 'proposeChanges' with sequences #4 - #4
{Push#8} Caught up, at lastSequence #4
{Push#8} activityLevel=busy: pendingResponseCount=1, caughtUp=1, changeLists=1, revsInFlight=0, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=1
01-12 20:07:26.403 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791704
completedWrite(long) handle -> 2883791704, byteCount -> 119
01-12 20:07:26.403 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} pushStatus=busy, pullStatus=busy, dbStatus=idle, progress=0/128
{Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.403 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 02010004e3f87e
01-12 20:07:26.403 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Push#8} activityLevel=busy: pendingResponseCount=1, caughtUp=1, changeLists=1, revsInFlight=0, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=1
01-12 20:07:26.403 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Pull#7} activityLevel=busy: pendingResponseCount=1, _caughtUp=0, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:07:26.404 6260-6287/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.404 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} pushStatus=busy, pullStatus=busy, dbStatus=idle, progress=0/128
{Repl#5} activityLevel=busy: connectionState=2
{Pull#7} activityLevel=busy: pendingResponseCount=1, _caughtUp=0, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
{Pull#7} activityLevel=busy: pendingResponseCount=0, _caughtUp=0, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:07:26.404 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 0128d20b28ca4fcbcc496548ce48cc4b4f2d2660485e694e0e005dbb390e
01-12 20:07:26.404 6260-6287/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.404 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Pull#7} Caught up with remote changes
01-12 20:07:26.405 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Pull#7} activityLevel=idle: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
{Repl#5} pushStatus=busy, pullStatus=idle, dbStatus=idle, progress=0/128
{Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.405 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 031962888e0500eb7dba2d
01-12 20:07:26.405 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.405 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Push#8} Got response for 1 local changes (sequences from 4)
{Push#8} activityLevel=busy: pendingResponseCount=0, caughtUp=1, changeLists=0, revsInFlight=1, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=1
{DBWorker#6} activityLevel=busy: pendingResponseCount=1, eventCount=1
01-12 20:07:26.406 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791704
completedWrite(long) handle -> 2883791704, byteCount -> 114
01-12 20:07:26.406 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} pushStatus=busy, pullStatus=idle, dbStatus=busy, progress=0/128
{Repl#5} activityLevel=busy: connectionState=2
{DBWorker#6} activityLevel=busy: pendingResponseCount=1, eventCount=1
{Push#8} activityLevel=busy: pendingResponseCount=0, caughtUp=1, changeLists=0, revsInFlight=0, blobsInFlight=0, awaitingReply=200, revsToSend=0, pendingSequences=1
01-12 20:07:26.407 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 0401009736ce42
01-12 20:07:26.408 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.408 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 020894cbc109c5200c06e05d72f6c11f8cf1398b480949ae154abb7f67e800df4735a714f2fd9c775e476ca742f263c02b6a624834a858539354feeb3076a0f75470745aeb0503c039e1
01-12 20:07:26.408 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#6} activityLevel=idle: pendingResponseCount=0, eventCount=1
01-12 20:07:26.408 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.408 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Push#8} activityLevel=idle: pendingResponseCount=0, caughtUp=1, changeLists=0, revsInFlight=0, blobsInFlight=0, awaitingReply=0, revsToSend=0, pendingSequences=0
{Repl#5} pushStatus=busy, pullStatus=idle, dbStatus=idle, progress=0/128
{Repl#5} activityLevel=busy: connectionState=2
{Pull#7} activityLevel=busy: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=1, _pendingRevMessages=0, _activeIncomingRevs=0
{Repl#5} pushStatus=idle, pullStatus=idle, dbStatus=idle, progress=128/128
{Repl#5} activityLevel=busy: connectionState=2
{DBWorker#6} Received 1 changes (seq '4'..'4')
{DBWorker#6} Marked 1 revs as synced-to-server in 0.12ms (8592/sec)
{DBWorker#6} Responded to 'changes' REQ#2 w/request for 0 revs
{DBWorker#6} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#5} pushStatus=idle, pullStatus=busy, dbStatus=idle, progress=128/128
{Repl#5} activityLevel=busy: connectionState=2
{Pull#7} activityLevel=idle: pendingResponseCount=0, _caughtUp=1, _waitingForChangesCallback=0, _pendingRevMessages=0, _activeIncomingRevs=0
01-12 20:07:26.408 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791704
completedWrite(long) handle -> 2883791704, byteCount -> 31
01-12 20:07:26.408 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.408 6260-6286/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791704
01-12 20:07:26.409 6260-6286/com.pab.testcbclient W/C4Socket: completedWrite(long) handle -> 2883791704, byteCount -> 98
01-12 20:07:26.409 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} pushStatus=idle, pullStatus=idle, dbStatus=idle, progress=128/128
{Repl#5} activityLevel=busy: connectionState=2
{Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.418 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=BUSY, progress=Progress{completed=0, total=0}, error=null}}
01-12 20:07:26.433 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#6} activityLevel=idle: pendingResponseCount=0, eventCount=1
01-12 20:07:26.448 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 05010872657600302d3400318343fb
01-12 20:07:26.450 6260-6285/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.450 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} Saved remote checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= as rev='0-4'
{Repl#5} activityLevel=busy: connectionState=2
{DBWorker#6} Saved local checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= to db
{DBWorker#6} activityLevel=idle: pendingResponseCount=0, eventCount=1
{Repl#5} activityLevel=busy: connectionState=2
{Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.450 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.451 6260-6285/com.pab.testcbclient W/C4Socket: C4Socket.write() handle -> 2883791704
completedWrite(long) handle -> 2883791704, byteCount -> 98
01-12 20:07:26.452 6260-6319/com.pab.testcbclient V/WS: WebSocketListener.onMessage() bytes -> 06010872657600302d350010b7fe4f
01-12 20:07:26.453 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} Saved remote checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= as rev='0-5'
{Repl#5} activityLevel=busy: connectionState=2
01-12 20:07:26.453 6260-6287/com.pab.testcbclient W/C4Socket: C4Socket.completedReceive() handle -> 2883791704
01-12 20:07:26.453 6260-6286/com.pab.testcbclient I/LiteCore [Sync]: {DBWorker#6} Saved local checkpoint cp-EMrLDayx2Heg81fVbXtS75sAWTU= to db
{DBWorker#6} activityLevel=idle: pendingResponseCount=0, eventCount=1
01-12 20:07:26.453 6260-6285/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} activityLevel=idle: connectionState=2
{Repl#5} now idle
01-12 20:07:26.454 6260-6285/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=128, progressUnitsTotal=128, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:26.454 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=128, progressUnitsTotal=128, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is idle, progress 128/128, error: null
01-12 20:07:26.466 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=IDLE, progress=Progress{completed=128, total=128}, error=null}}
01-12 20:07:26.600 6260-6287/com.pab.testcbclient I/Sync: C4ReplicatorListener.statusChanged() status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=128, progressUnitsTotal=128, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:26.600 6260-6287/com.pab.testcbclient I/LiteCore [Sync]: {Repl#5} activityLevel=idle: connectionState=2
01-12 20:07:26.600 6260-6288/com.pab.testcbclient I/Sync: statusChanged() c4Status -> C4ReplicatorStatus{activityLevel=3, progressUnitsCompleted=128, progressUnitsTotal=128, progressDocumentCount=1, errorDomain=0, errorCode=0, errorInternalInfo=0}
01-12 20:07:26.601 6260-6288/com.pab.testcbclient I/Sync: Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}] is idle, progress 128/128, error: null
01-12 20:07:26.617 6260-6260/com.pab.testcbclient I/test_main: OnReplicationStatusChanged: ReplicatorChange{replicator=Replicator[<*> Database@57a24f3{name='test_db'} URLEndpoint{url=ws://192.168.1.221:4984/db}], status=Status{activityLevel=IDLE, progress=Progress{completed=128, total=128}, error=null}}
01-12 20:07:33.165 6260-6267/? W/zygote: Debugger told VM to exit with status -1
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.