Uploaded image for project: 'Couchbase Server'
  1. Couchbase Server
  2. MB-47955

[Windows] - Loading travel-sample bucket failed

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed
    • Priority: Blocker
    • Resolution: Duplicate
    • Affects Version/s: 7.0.2
    • Fix Version/s: 7.0.2
    • Component/s: query
    • Labels:
    • Environment:
      Enterprise Edition 7.0.2 build 6512
    • Triage:
      Untriaged
    • Operating System:
      Windows 64-bit
    • Story Points:
      1
    • Is this a Regression?:
      No

      Description

      Loading travel-sample failed with the following error on windows.

      Loading sample bucket travel-sample failed. Samples loader exited with status 1.
      		 
      Loader's output was:
      		 
       
      		 
      2021-08-16T05:01:43.980-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/nodeServices'
      		 
      2021-08-16T05:01:43.982-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/nodeServices'
      		 
      2021-08-16T05:01:43.983-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools'
      		 
      2021-08-16T05:01:43.984-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools'
      		 
      2021-08-16T05:01:43.984-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default'
      		 
      2021-08-16T05:01:43.985-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default'
      		 
      2021-08-16T05:01:43.985-07:00 (REST) Successfully connected to cluster | {"enterprise":true,"uuid":"cf5614fe6724c695a377ded20b53975a","version":{"min_version":"7.0.2","is_mixed_cluster":false}}
      		 
      2021-08-16T05:01:44.084-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:44.085-07:00 (REST) (Attempt 1) (GET) (404) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:44.085-07:00 WARN: (REST) (Attempt 1) (GET) Request to endpoint '/pools/default/buckets/travel-sample' failed with status code 404 -- rest.(*Request).Execute() at request.go:166
      		 
      2021-08-16T05:01:44.086-07:00 (Plan) (Bucket) Creating remote bucket 'travel-sample' if it does not exist
      		 
      2021-08-16T05:01:44.086-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:44.086-07:00 (REST) (Attempt 1) (GET) (404) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:44.086-07:00 WARN: (REST) (Attempt 1) (GET) Request to endpoint '/pools/default/buckets/travel-sample' failed with status code 404 -- rest.(*Request).Execute() at request.go:166
      		 
      2021-08-16T05:01:44.087-07:00 Bucket travel-sample does not exist, creating one with the specified settings
      		 
      2021-08-16T05:01:44.087-07:00 (REST) (Attempt 1) (POST) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets'
      		 
      2021-08-16T05:01:44.123-07:00 (REST) (Attempt 1) (POST) (202) Received response from 'http://127.0.0.1:8091/pools/default/buckets'
      		 
      2021-08-16T05:01:45.124-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:45.137-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:46.123-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:46.139-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:47.123-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:47.137-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:48.123-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:48.128-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:48.129-07:00 (Plan) (Bucket) Successfully ensured bucket 'travel-sample' exists | {"number":1,"duration":"4.0419998s"}
      		 
      2021-08-16T05:01:48.129-07:00 (Plan) (Bucket) Automatically creating/editing scopes/collections for bucket 'travel-sample'
      		 
      2021-08-16T05:01:48.129-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:48.134-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:48.134-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes'
      		 
      2021-08-16T05:01:48.135-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes'
      		 
      2021-08-16T05:01:48.135-07:00 (REST) (Attempt 1) (POST) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes/%40ensureManifest/0'
      		 
      2021-08-16T05:01:48.138-07:00 (REST) (Attempt 1) (POST) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes/%40ensureManifest/0'
      		 
      2021-08-16T05:01:48.138-07:00 (REST) (Attempt 1) (PUT) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes?validOnUid=0'
      		 
      2021-08-16T05:01:48.237-07:00 (REST) (Attempt 1) (PUT) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes?validOnUid=0'
      		 
      2021-08-16T05:01:48.237-07:00 (REST) (Attempt 1) (POST) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes/%40ensureManifest/1'
      		 
      2021-08-16T05:01:49.121-07:00 (REST) (Attempt 1) (POST) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample/scopes/%40ensureManifest/1'
      		 
      2021-08-16T05:01:49.121-07:00 (Plan) (Bucket) Successfully created/edited scopes/collections for bucket 'travel-sample' | {"number":2,"duration":"991.9678ms"}
      		 
      2021-08-16T05:01:49.121-07:00 (Plan) (Bucket) Transferring bucket configuration for bucket 'travel-sample'
      		 
      2021-08-16T05:01:49.122-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:49.127-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:49.127-07:00 Editing bucket to conform with settings provided
      		 
      2021-08-16T05:01:49.127-07:00 (REST) (Attempt 1) (POST) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:49.249-07:00 (REST) (Attempt 1) (POST) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:50.250-07:00 (REST) (Attempt 1) (GET) Dispatching request to 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:50.255-07:00 (REST) (Attempt 1) (GET) (200) Received response from 'http://127.0.0.1:8091/pools/default/buckets/travel-sample'
      		 
      2021-08-16T05:01:50.256-07:00 (Plan) (Bucket) Successfully transferred bucket configuration for bucket 'travel-sample' | {"number":3,"duration":"1.1349994s"}
      		 
      2021-08-16T05:01:50.256-07:00 (Plan) (Index) Transferring views definitions for bucket 'travel-sample'
      		 
      2021-08-16T05:01:50.354-07:00 (Plan) (Index) Successfully transferred views definitions for bucket 'travel-sample' | {"number":4,"duration":"98.0043ms"}
      		 
      2021-08-16T05:01:50.354-07:00 (Plan) (Query) Executing queries for bucket 'travel-sample'
      		 
      2021-08-16T05:02:50.454-07:00 JSON import failed: failed to execute cluster operations: failed to execute bucket operation for bucket 'travel-sample': failed to execute queries for bucket 'travel-sample': failed to execute queries against sink bucket: failed to ensure gocb is connected to the cluster: unambiguous timeout | {"InnerError":{"InnerError":{"InnerError":{},"Message":"unambiguous timeout"}},"OperationID":"WaitUntilReady","Opaque":"","TimeObserved":60001011200,"RetryReasons":["NOT_READY"],"RetryAttempts":64,"LastDispatchedTo":"","LastDispatchedFrom":"","LastConnectionID":""}
      		 
      JSON import failed: operation has timed out

      cbcollect_info attached.

        Attachments

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

          Activity

          Ascending order - Click to sort in descending order
          Hide
          Permalink
          james.lee James Lee added a comment -

          Hi Balakumaran Gopal,

          I don't believe this is an issue with 'cbimport', looking at the logs we see a few things worth noting:
          1) The timeout is "unambiguous" (meaning the SDK hit the user provided deadline) in this case, 1 minute. We can see from the logs that this timeout is valid
          2) We appear to be correctly waiting for the query service to become available, as can be seen by the 'WaitUntilReady' response 'NOT_READY'

          Looking at the query logs I see the following repeated a number of times:

          Failed to start service

          		 
          _time=2021-08-16T05:01:18.864-07:00 _level=ERROR _msg=cbq-engine (HTTP_ADDR :8093) exiting with error: Failed to start service: listen tcp4 :8093: bind: Only one usage of each socket address (protocol/network address/port) is normally permitted.

          It looks like this is either a setup issue (i.e. someone else is using port 8093) or something wrong with the Query Service; assigning to Query and removing tools.

          Thanks,
          James

          Show
          james.lee James Lee added a comment - Hi Balakumaran Gopal , I don't believe this is an issue with ' cbimport ', looking at the logs we see a few things worth noting: 1) The timeout is "unambiguous" (meaning the SDK hit the user provided deadline) in this case, 1 minute. We can see from the logs that this timeout is valid 2) We appear to be correctly waiting for the query service to become available, as can be seen by the ' WaitUntilReady ' response ' NOT_READY ' Looking at the query logs I see the following repeated a number of times: Failed to start service _time=2021-08-16T05:01:18.864-07:00 _level=ERROR _msg=cbq-engine (HTTP_ADDR :8093) exiting with error: Failed to start service: listen tcp4 :8093: bind: Only one usage of each socket address (protocol/network address/port) is normally permitted. It looks like this is either a setup issue (i.e. someone else is using port 8093) or something wrong with the Query Service; assigning to Query and removing tools. Thanks, James
          Hide
          Permalink
          kamini.jagtiani Kamini Jagtiani added a comment -

          Duplicate of MB-47925

          Show
          kamini.jagtiani Kamini Jagtiani added a comment - Duplicate of  MB-47925
          Hide
          Permalink
          mihir.kamdar Mihir Kamdar added a comment -

          Bulk closing not-fixed bugs

          Show
          mihir.kamdar Mihir Kamdar added a comment - Bulk closing not-fixed bugs

            People

            Assignee:
            isha Isha Kandaswamy
            Reporter:
            Balakumaran.Gopal Balakumaran Gopal
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved:

                Gerrit Reviews

                There are no open Gerrit changes

                  PagerDuty