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

concurrent.TimeoutException when run N1QLQuery during rebalance in situational tests

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: Major
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 2.2.0
    • Component/s: N1QL
    • Security Level: Public
    • Labels:
      None
    • Environment:
      3.5.0-1335
      couchbase-java-client commit f0ef8a81a0639f625fd6c
      couchbase-jvm-core commit 02443d7254fd47
      sdkd-java commit 90c21c1cc

      Description

      when I tried to run java Rb2In-N1QL situational tests against 3.5.0-1335 I got exceptions during rebalance

      [530.36 INFO] (SDKD log:137) Mar 01, 2015 7:50:14 PM com.couchbase.sdkd.cbclient.N1QLQueryCommandContext scanSingleRow
      [530.36 INFO] (SDKD log:137) INFO: parameterized => QueryRow :{"default":{"InflateContent":"meh","KVSequence":999,"KIdent":"N1QLFillerSeed","InflateLevel":35}}
      [531.67 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 64%
      [532.97 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 66%
      [534.34 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 67%
      [535.63 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 68%
      [537.00 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 69%
      [538.29 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 71%
      [539.59 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 72%
      [540.99 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 73%
      [542.32 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 74%
      [543.61 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 76%
      [544.98 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 77%
      [545.70 INFO] (SDKD log:137) Mar 01, 2015 7:50:29 PM com.couchbase.sdkd.cbclient.N1QLQueryCommandContext scanSingleRow
      [545.70 INFO] (SDKD log:137) INFO: simple => QueryRow :{"default":{"InflateContent":"meh","KVSequence":999,"KIdent":"N1QLFillerSeed","InflateLevel":35}}
      [545.70 INFO] (SDKD log:137) Mar 01, 2015 7:50:29 PM com.couchbase.sdkd.cbclient.N1QLQueryCommandContext scanSingleRow
      [545.71 INFO] (SDKD log:137) INFO: parameterized => QueryRow :{"default":{"InflateContent":"meh","KVSequence":999,"KIdent":"N1QLFillerSeed","InflateLevel":35}}
      [545.99 INFO] (SDKD log:137) Mar 01, 2015 7:50:29 PM com.couchbase.sdkd.cbclient.N1QLQueryCommandContext scanSingleRow
      [545.99 INFO] (SDKD log:137) INFO: prepared => QueryRow :{"default":{"InflateContent":"meh","KVSequence":999,"KIdent":"N1QLFillerSeed","InflateLevel":35}}
      [546.32 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 77%
      [547.65 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 78%
      [548.94 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 79%
      [550.24 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 80%
      [551.53 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 80%
      [552.87 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 81%
      [554.17 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 82%
      [554.49 INFO] (SDKD log:137) Exception in thread "SDK Handle-107: CMD [CB_N1QL_QUERY] " java.lang.RuntimeException: java.util.concurrent.TimeoutException
      [554.49 INFO] (LineGobbler doFilter:115) +++ Following exception has internal ID: 1
      [554.49 INFO] (SDKD log:137) at com.couchbase.client.java.util.Blocking.blockForSingle(Blocking.java:93)
      at com.couchbase.client.java.CouchbaseBucket.query(CouchbaseBucket.java:547)
      at com.couchbase.client.java.CouchbaseBucket.query(CouchbaseBucket.java:490)
      at com.couchbase.sdkd.cbclient.N1QLQueryCommandContext.execIter(N1QLQueryCommandContext.java:277)
      at com.couchbase.sdkd.cbclient.CommandContext.execute(CommandContext.java:327)
      at com.couchbase.sdkd.server.SdkServer.executeCommand(SdkServer.java:143)
      at com.couchbase.sdkd.server.SdkServer.handleRequest(SdkServer.java:164)
      at com.couchbase.sdkd.server.SdkServer.run(SdkServer.java:219)
      [554.49 INFO] (SDKD log:137) Caused by: java.util.concurrent.TimeoutException
      [554.49 INFO] (SDKD log:137) ... 8 more
      [554.67 INFO] (SDKD log:137) Exception in thread "SDK Handle-106: CMD [CB_N1QL_QUERY] " java.lang.RuntimeException: java.util.concurrent.TimeoutException
      [554.67 INFO] (SDKD log:137) +++ Received exception of ID 0
      [554.67 INFO] (SDKD log:137) Caused by: java.util.concurrent.TimeoutException
      [554.67 INFO] (SDKD log:137) ... 8 more
      [554.71 INFO] (SDKD log:137) Exception in thread "SDK Handle-110: CMD [CB_N1QL_QUERY] " java.lang.RuntimeException: java.util.concurrent.TimeoutException
      [554.71 INFO] (LineGobbler doFilter:115) +++ Following exception has internal ID: 3
      [554.71 INFO] (SDKD log:137) at com.couchbase.client.java.util.Blocking.blockForSingle(Blocking.java:93)
      at com.couchbase.client.java.CouchbaseBucket.prepare(CouchbaseBucket.java:583)
      at com.couchbase.client.java.CouchbaseBucket.prepare(CouchbaseBucket.java:499)
      at com.couchbase.sdkd.cbclient.N1QLQueryCommandContext.execIter(N1QLQueryCommandContext.java:275)
      at com.couchbase.sdkd.cbclient.CommandContext.execute(CommandContext.java:327)
      at com.couchbase.sdkd.server.SdkServer.executeCommand(SdkServer.java:143)
      at com.couchbase.sdkd.server.SdkServer.handleRequest(SdkServer.java:164)
      at com.couchbase.sdkd.server.SdkServer.run(SdkServer.java:219)
      [554.71 INFO] (SDKD log:137) Caused by: java.util.concurrent.TimeoutException
      [554.72 INFO] (SDKD log:137) ... 8 more
      [554.79 INFO] (SDKD log:137) Exception in thread "SDK Handle-111: CMD [CB_N1QL_QUERY] " java.lang.RuntimeException: java.util.concurrent.TimeoutException
      [554.79 INFO] (SDKD log:137) +++ Received exception of ID 2
      [554.79 INFO] (SDKD log:137) Caused by: java.util.concurrent.TimeoutException
      [554.79 INFO] (SDKD log:137) ... 8 more
      [555.53 DEBUG] (RebalanceWaiter sweepOnce:38) Rebalance Progress: 82%
      [556.15 INFO] (SDKD log:137) Mar 01, 2015 7:50:40 PM com.couchbase.sdkd.cbclient.N1QLQueryCommandContext scanSingleRow
      [556.15 INFO] (SDKD log:137) INFO: prepared => QueryRow :{"default":{"InflateContent":"meh","KVSequence":999,"KIdent":"N1QLFillerSeed","InflateLevel":35}}

      please note that in my run I had only one doc in the cluster( see SDKQE-640) but I think it should not in any way affect

        Attachments

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

          Activity

            People

            • Assignee:
              daschl Michael Nitschinger
              Reporter:
              andreibaranouski Andrei Baranouski
            • Votes:
              0 Vote for this issue
              Watchers:
              4 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved:

                Gerrit Reviews

                There are no open Gerrit changes