Uploaded image for project: 'Couchbase C client library libcouchbase'
  1. Couchbase C client library libcouchbase
  2. CCBC-1615

Bootstrap routine interprets rate-limit as protocol error

    XMLWordPrintable

Details

    • Bug
    • Resolution: Fixed
    • Major
    • 3.3.9
    • None
    • library
    • None
    • 1
    • SDK38

    Description

      test RateLimitTest.testRateLimitsKVMaxConnections fails on 7.1

      4767ms [I69619809596b3611] {98726/103} [WARN] (cccp - L:292) <NOHOST:NOPORT> (CTX=0x0,) Failed to bootstrap using CCCP
      4767ms [I69619809596b3611] {98726/103} [INFO] (confmon - L:217) Provider 'CCCP' failed: LCB_ERR_PROTOCOL_ERROR (1050)
      4767ms [I69619809596b3611] {98726/103} [TRACE] (confmon - L:266) Maximum provider reached. Resetting index
      4767ms [I69619809596b3611] {98726/103} [ERROR] (bootstrap - L:204) Failed to bootstrap client=0x616000017480. Error=LCB_ERR_NO_MATCHING_SERVER (1010) (Last=LCB_ERR_PROTOCOL_ERROR (1050)), Message="No more bootstrap providers remain"
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (server - L:1341) <172.23.111.131:11210> (CTX=0x61000001df40,memcached,SRV=0x616000016280,IX=0) Finalizing context
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (ioctx - L:140) <172.23.111.131:11210> (CTX=0x61000001df40,memcached) Destroying context for SOCK=4f7cbdf2295c0305. Pending Writes=0, Entered=false, Socket Refcount=1
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (server - L:1341) <172.23.111.132:11210> (CTX=0x61000001de40,memcached,SRV=0x616000016580,IX=1) Finalizing context
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (ioctx - L:140) <172.23.111.132:11210> (CTX=0x61000001de40,memcached) Destroying context for SOCK=49633f0942e17a90. Pending Writes=0, Entered=false, Socket Refcount=1
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (server - L:1341) <172.23.111.133:11210> (CTX=0x61000001dd40,memcached,SRV=0x616000016880,IX=2) Finalizing context
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (ioctx - L:140) <172.23.111.133:11210> (CTX=0x61000001dd40,memcached) Destroying context for SOCK=4b6c7166eb5d3f7a. Pending Writes=0, Entered=false, Socket Refcount=1
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (server - L:1341) <172.23.111.134:11210> (CTX=0x61000001c840,memcached,SRV=0x616000016b80,IX=3) Finalizing context
      4768ms [I9ee12a2853b121c3] {98726/103} [DEBUG] (ioctx - L:140) <172.23.111.134:11210> (CTX=0x61000001c840,memcached) Destroying context for SOCK=388d637cbb370b53. Pending Writes=0, Entered=false, Socket Refcount=1
      4768ms [I1254d8c11bc55c94] {98726/103} [INFO] (instance - L:545) Version=3.3.8_3_gcd1253088c, Changeset=cd1253088c93879f1dfbdba915e029efaee8349f
      4768ms [I1254d8c11bc55c94] {98726/103} [INFO] (instance - L:546) Effective connection string: couchbase://172.23.111.134?dnssrv=off&bootstrap_on=cccp&compression=off. Bucket=default
      4768ms [I1254d8c11bc55c94] {98726/103} [DEBUG] (instance - L:389) Applying initial cntl compression=off
      4768ms [I1254d8c11bc55c94] {98726/103} [DEBUG] (instance - L:155) Adding host 172.23.111.134:8091 to initial HTTP bootstrap list
      4768ms [I1254d8c11bc55c94] {98726/103} [DEBUG] (instance - L:155) Adding host 172.23.111.134:11210 to initial CCCP bootstrap list
      4768ms [I1254d8c11bc55c94] {98726/103} [TRACE] (instance - L:193) Bootstrap hosts loaded (cccp:1, http:1)
      4768ms [I1254d8c11bc55c94] {98726/103} [INFO] (bootstrap - L:284) Requested network configuration: heuristic
      4768ms [I1254d8c11bc55c94] {98726/103} [DEBUG] (confmon - L:94) Preparing providers (this may be called multiple times)
      4768ms [I1254d8c11bc55c94] {98726/103} [DEBUG] (confmon - L:100) Provider CCCP is ENABLED
      4768ms [I1254d8c11bc55c94] {98726/103} [TRACE] (confmon - L:329) Refreshing current cluster map (bucket: "default"), next update in 0us
      4768ms [I1254d8c11bc55c94] {98726/103} [INFO] (cccp - L:276) Requesting connection to node 172.23.111.134:11210 for CCCP configuration
      4768ms [I1254d8c11bc55c94] {98726/103} [DEBUG] (lcbio_mgr - L:501) <172.23.111.134:11210> (HE=0x60c0000a1440) Creating new connection because none are available in the pool
      4768ms [I1254d8c11bc55c94] {98726/103} [TRACE] (lcbio_mgr - L:413) <172.23.111.134:11210> (HE=0x60c0000a1440) New pool entry: I=0x608000026ea0
      4768ms [I1254d8c11bc55c94] {98726/103} [INFO] (connection - L:500) <172.23.111.134:11210> (SOCK=37d42c7a1390904f) Starting. Timeout=2000000us
      4768ms [I1254d8c11bc55c94] {98726/103} [DEBUG] (connection - L:265) <172.23.111.134:11210> (SOCK=37d42c7a1390904f) Created new socket with FD=5
      4768ms [I1254d8c11bc55c94] {98726/103} [TRACE] (connection - L:365) <172.23.111.134:11210> (SOCK=37d42c7a1390904f) Scheduling I/O watcher for asynchronous connection completion.
      tests/iotests/t_ratelimit.cc:242: Failure
      Expected equality of these values:
        LCB_ERR_RATE_LIMITED
          Which is: 220
        actual_code__242
          Which is: 1050
      LCB_ERR_RATE_LIMITED (220) != LCB_ERR_PROTOCOL_ERROR (1050)
      

      Attachments

        For Gerrit Dashboard: CCBC-1615
        # Subject Branch Project Status CR V

        Activity

          People

            avsej Sergey Avseyev
            avsej Sergey Avseyev
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              Gerrit Reviews

                There are no open Gerrit changes

                PagerDuty