Details
-
Bug
-
Resolution: Fixed
-
Major
-
Cheshire-Cat
-
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