Major Description
While investigating an issue, I found this "potential" issue on Response Time Observability.
Environment:
CB Server 6.0.2
Java SDK 2.7.4 using Async API.
I'm running the following operations, reusing the following repository:
https://github.com/couchbaselabs/Java-RTO-Demo-Load-Runner
I've added Subdocument operations:
SUBMUTATEINSERT(() -> bucket.mutateIn("airline_1316").insert("first", "Sopy").execute().subscribe(onNext, onError, onComplete)), |
SUBMUTATEUPSERT(() -> bucket.mutateIn("airline_1316").upsert("fax", "07555").execute().subscribe(onNext, onError, onComplete)), |
SUBMUTATEREMOVE(() -> bucket.mutateIn("airline_1316").remove("icao").execute().subscribe(onNext, onError, onComplete)), |
SUBCOUNTER(() -> bucket.mutateIn("airline_1316").counter("logins", 1).execute().subscribe(onNext, onError, onComplete)), |
SUBLOOKUP(() -> bucket.lookupIn("airline_1355").get("name").execute().subscribe(onNext, onError, onComplete)); |
All operations are being logged in Tracer but mutatein().insert() & mutatein().upsert.
Could you please investigate?
12:49:34.372 [cb-tracing-2] WARN c.c.client.core.tracing.ThresholdLogReporter - Operations over threshold: [ { |
"top" : [ { |
"operation_name" : "subdoc_counter", |
"server_us" : 92, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 468756, |
"last_operation_id" : "0x749", |
"total_us" : 1246218 |
}, {
|
"operation_name" : "subdoc_counter", |
"server_us" : 92, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 468088, |
"last_operation_id" : "0x73d", |
"total_us" : 1246169 |
}, {
|
"operation_name" : "subdoc_remove", |
"server_us" : 11, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 461640, |
"last_operation_id" : "0x6ca", |
"total_us" : 1215866 |
}, {
|
"operation_name" : "subdoc_remove", |
"server_us" : 11, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 468386, |
"last_operation_id" : "0xbfb", |
"total_us" : 1188552 |
}, {
|
"operation_name" : "subdoc_counter", |
"server_us" : 117, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 468544, |
"last_operation_id" : "0xc04", |
"total_us" : 1188264 |
}, {
|
"operation_name" : "subdoc_get", |
"server_us" : 49, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 468423, |
"decode_us" : 13, |
"last_operation_id" : "0xc03", |
"total_us" : 1188187 |
}, {
|
"operation_name" : "subdoc_get", |
"server_us" : 84, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 468369, |
"decode_us" : 16, |
"last_operation_id" : "0xc02", |
"total_us" : 1188163 |
}, {
|
"operation_name" : "subdoc_counter", |
"server_us" : 126, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 467738, |
"last_operation_id" : "0xbf6", |
"total_us" : 1188060 |
}, {
|
"operation_name" : "subdoc_counter", |
"server_us" : 135, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 467986, |
"last_operation_id" : "0xc06", |
"total_us" : 1187533 |
}, {
|
"operation_name" : "subdoc_get", |
"server_us" : 84, |
"last_local_id" : "7A6FB646CF258ED9/000000005AA992BA", |
"last_local_address" : "127.0.0.1:52471", |
"last_remote_address" : "127.0.0.1:11210", |
"last_dispatch_us" : 467493, |
"decode_us" : 18, |
"last_operation_id" : "0xbff", |
"total_us" : 1187393 |
} ],
|
"service" : "kv", |
"count" : 52583 |
}, {`
|