Details
-
Bug
-
Resolution: Incomplete
-
Critical
-
3.0.0-alpha.7
-
core-io-2.0.0-alpha.7.jar
java-client-3.0.0-alpha.7.jar
Description
Steps to reproduce:
- 2 node cluster, Couchbase bucket (replica=1)
- Stop Memcached on replica node using `kill -STOP`
- Load 1 doc from client-1 that targets active node with large timeout and durability=MAJORITY
- Perform same mutation from Client-2 on same doc
Observation:
In step #4, SDK raises 'RequestTimeoutException'. But expected to raise 'DurableWriteInProgressException'. As per the Wireshark logs, SDK received the response code "0x00a2" which maps to KV's SyncWriteInProgress
Note: The same tests works fine with alpha-6 release.
SDK responses in test case:
com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11372,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-1"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:31,665 | test | ERROR | MainThread | [basetestcase:log_failure:418] Invalid exception for test_docs-1: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11372,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-1"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:36,756 | infra | ERROR | MainThread | [sdk_client3:replace:476] Something else happened: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11514,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-2"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:36,757 | test | ERROR | MainThread | [basetestcase:log_failure:418] Invalid exception for test_docs-2: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11514,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-2"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:41,857 | infra | ERROR | MainThread | [sdk_client3:replace:476] Something else happened: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11662,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-4"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:41,862 | test | ERROR | MainThread | [basetestcase:log_failure:418] Invalid exception for test_docs-4: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11662,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-4"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:46,959 | infra | ERROR | MainThread | [sdk_client3:replace:476] Something else happened: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11788,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-7"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:46,960 | test | ERROR | MainThread | [basetestcase:log_failure:418] Invalid exception for test_docs-7: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11788,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-7"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:52,059 | infra | ERROR | MainThread | [sdk_client3:replace:476] Something else happened: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11936,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-8"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
2019-09-11 09:15:52,065 | test | ERROR | MainThread | [basetestcase:log_failure:418] Invalid exception for test_docs-8: com.couchbase.client.core.error.RequestTimeoutException: ReplaceRequest {"retried":17,"reason":"TIMEOUT","requestId":11936,"timeoutMs":5000,"service":{"bucket":"default","scope":{"present":true},"collection":{"present":true},"type":"kv","key":"test_docs-8"},"timings":null,"cancelled":true,"coreId":18,"completed":true,"retryReasons":["KV_SYNC_WRITE_IN_PROGRESS"]}
|
Attachments
| For Gerrit Dashboard: JCBC-1443 | ||||||
|---|---|---|---|---|---|---|
| # | Subject | Branch | Project | Status | CR | V |
| 114663,3 | JCBC-1443: SyncWriteInProgress tests fix to align with SDK change | master | TAF | Status: MERGED | +2 | +1 |
| 114701,2 | JCBC-1443: Updating Subdoc_xattr SyncWrite test to align with alpha-7 changes | master | TAF | Status: MERGED | +2 | +1 |
| 114752,4 | JCBC-1443: Updating Subdoc_xattr SyncWrite test to align with alpha-7 changes | master | TAF | Status: MERGED | +2 | +1 |
| 114787,3 | JCBC-1443: Decreasing timeout to avoid test failures | master | TAF | Status: MERGED | +2 | +1 |