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

TXN:Execute on prepare statement takes too long time

    XMLWordPrintable

Details

    • Untriaged
    • 1
    • Unknown

    Description

      On build 7.0.0-3507
      Execute on preper statement takes too long time and finally fails with socket error

      2020-10-27 16:01:17,970 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE PRIMARY INDEX on default:`vykKmFVfQlPK-52-981000`.`_default`.`_default` USING GSI
      2020-10-27 16:01:20,526 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 2.55015402s
      2020-10-27 16:01:20,568 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE INDEX `Z-20-532000` ON default:`vykKmFVfQlPK-52-981000`.`_default`.`_default`(VISA=['US', 'CANADA']) USING GSI
      2020-10-27 16:01:24,536 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 3.964857471s
      2020-10-27 16:01:24,549 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE INDEX `hyybM4bEy_t5YjMqXBJA-24-538000` ON default:`vykKmFVfQlPK-52-981000`.`_default`.`_default`(join_yr,job_title) USING GSI
      2020-10-27 16:01:27,835 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 3.279539035s
      2020-10-27 16:01:27,846 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE INDEX `wrPs8-27-835000` ON default:`vykKmFVfQlPK-52-981000`.`_default`.`_default`(job_title,skills) USING GSI
      2020-10-27 16:01:31,099 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 3.249474441s
      2020-10-27 16:01:31,117 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE INDEX `I5qZQuk55h-31-101000` ON default:`vykKmFVfQlPK-52-981000`.`_default`.`_default`(job_title) USING GSI
      2020-10-27 16:01:34,509 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 3.38861611s
      2020-10-27 16:01:34,520 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE INDEX `2-34-510000` ON default:`vykKmFVfQlPK-52-981000`.`_default`.`_default`(join_day,join_mo) USING GSI
      2020-10-27 16:01:37,997 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 3.473954853s
      2020-10-27 16:01:38,009 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE INDEX `xmCDcL0bA-37-999000` ON default:`vykKmFVfQlPK-52-981000`.`_default`.`_default`(join_day) USING GSI
      2020-10-27 16:01:41,611 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 3.599377218s
      2020-10-27 16:01:41,624 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY CREATE INDEX `TvLdcsy1wdEJxt-41-613000` ON default:`vykKmFVfQlPK-52-981000`.`_default`.`_default`(VISA=['US', 'CANADA']) USING GSI
      2020-10-27 16:01:44,944 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 3.316876525s
      2020-10-27 16:01:44,966 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY BEGIN WORK
      2020-10-27 16:01:44,974 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 1.500691ms
      2020-10-27 16:01:45,012 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY PREPARE p_stmt as UPDATE default:`vykKmFVfQlPK-52-981000`.`_default`.`_default` USE KEYS  ['test_collections-16', 'test_collections-5', 'test_collections-19', 'test_collections-12', 'test_collections-1', 'test_collections-18', 'test_collections-10', 'test_collections-15', 'test_collections-7', 'test_collections-9', 'test_collections-14', 'test_collections-11', 'test_collections-17', 'test_collections-0', 'test_collections-2', 'test_collections-6', 'test_collections-8', 'test_collections-3', 'test_collections-4', 'test_collections-13'] SET mutated=1 RETURNING meta().id
      2020-10-27 16:01:45,153 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:200] TOTAL ELAPSED TIME: 61.591449ms
      2020-10-27 16:01:45,171 | test  | INFO    | MainThread | [N1qlBase:run_cbq_query:144] RUN QUERY EXECUTE p_stmt
      2020-10-27 16:12:35,176 | test  | ERROR   | MainThread | [rest_client:_http_request:751] Socket error while connecting to http://10.112.190.101:8093/query?txid=540de5ee-87c0-44dc-b537-c97815de4d18&scan_consistency=REQUEST_PLUS&statement=EXECUTE+p_stmt error timed out 
      2020-10-27 16:12:35,183 | test  | INFO    | MainThread | [N1qlBase:full_execute_query:618] Unable to reach the host @ 10.112.190.101
      FAIL
      

      There are only 4 collection with 20 documents each. total of 80 documents in the cluster. Also attached cbcollect logs and test.logs

      To reproduce:
      git fetch "http://review.couchbase.org/TAF" refs/changes/75/138375/5 && git checkout FETCH_HEAD
      guides/gradlew --refresh-dependencies testrunner -P jython='/opt/jython2.7.0/bin/jython' -P 'args=-i b/try.ini -p rerun=False,data_spec_name=initial_load_N1ql -t N1qlTransaction.basic_ops.BasicOps.test_txn_same_keys,nodes_init=2,num_stmt_txn=5,prepare_stmt=True,GROUP=P0,bucket_spec=single_bucket.def_scope_ten_collections -m rest'

      https://cb-jira.s3.us-east-2.amazonaws.com/logs/Execute_error/collectinfo-2020-10-27T112647-ns_1%4010.112.190.101.zip
      https://cb-jira.s3.us-east-2.amazonaws.com/logs/Execute_error/collectinfo-2020-10-27T112647-ns_1@10.112.190.102.zip

      Attachments

        Activity

          People

            anitha.kuberan Anitha Kuberan
            anitha.kuberan Anitha Kuberan
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              PagerDuty