Created
January 12, 2019 17:21
-
-
Save irpab/554270c044d4aa4c5d14193619433064 to your computer and use it in GitHub Desktop.
CBLite push replication issue logs
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| 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