Uploaded image for project: 'Couchbase Java Client'
  1. Couchbase Java Client
  2. JCBC-1381

[Response Time Observability] Mutating operations "insert" & "upsert" not logged in Tracer

    XMLWordPrintable

Details

    • Bug
    • Status: Resolved
    • Major
    • Resolution: Fixed
    • 2.7.4
    • 2.7.8
    • Core
    • 1
    • High

    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
      }, {`
      

       

      Attachments

        No reviews matched the request. Check your Options in the drop-down menu of this sections header.

        Activity

          Found the issue, changeset is up for review.

          daschl Michael Nitschinger added a comment - Found the issue, changeset is up for review.

          People

            daschl Michael Nitschinger
            ludovic.dufrenoy Ludovic Dufrenoy (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              Gerrit Reviews

                There are no open Gerrit changes

                PagerDuty