[
https://issues.apache.org/jira/browse/CASSANDRA-5420?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Brandon Williams resolved CASSANDRA-5420.
-----------------------------------------
Resolution: Invalid
You should raise this question on the user list, not jira, this is not a bug.
> Very slow responses to trivial query.
> -------------------------------------
>
> Key: CASSANDRA-5420
> URL: https://issues.apache.org/jira/browse/CASSANDRA-5420
> Project: Cassandra
> Issue Type: Bug
> Components: Core
> Affects Versions: 1.2.2
> Environment: 4 node cluster with replication=3, 16Gb ram, Xeon CPU
> E5-2620@ 2.00GHz
> Reporter: Igor Ivanov
>
> Some requests are fine from time to time, but sometimes they take more than
> 60s. Which is very weird, given HW specs.
> Following is CQL3 session with TRACING ON:
> {code}
> cqlsh:footballsite> DESCRIBE TABLE content_list_lookup
> CREATE TABLE content_list_lookup (
> "editionContentId" text,
> "listId" text,
> "listKey" text,
> PRIMARY KEY ("editionContentId", "listId")
> ) WITH
> bloom_filter_fp_chance=0.010000 AND
> caching='KEYS_ONLY' AND
> comment='' AND
> dclocal_read_repair_chance=0.000000 AND
> gc_grace_seconds=864000 AND
> read_repair_chance=0.100000 AND
> replicate_on_write='true' AND
> populate_io_cache_on_flush='false' AND
> compaction={'class': 'SizeTieredCompactionStrategy'} AND
> compression={'sstable_compression': 'SnappyCompressor'};
> cqlsh:footballsite> SELECT * FROM "content_list_lookup" WHERE
> "editionContentId" = '8bf5cf79-f588-54b4-9114-05023d78d630.pt' ORDER BY
> "listId" ASC LIMIT 2 ;
> Tracing session: 3a0d6870-9bfb-11e2-8b38-3183185e53e2
> activity | timestamp |
> source | source_elapsed
> ---------------------------------------------------------+--------------+-------------+----------------
> execute_cql3_query | 18:10:08,760 |
> 10.10.45.60 | 0
> Parsing statement | 18:10:08,760 |
> 10.10.45.60 | 30
> Peparing statement | 18:10:08,760 |
> 10.10.45.60 | 116
> Sending message to /10.10.45.62 | 18:10:08,760 |
> 10.10.45.60 | 394
> Message received from /10.10.45.60 | 18:10:08,767 |
> 10.10.45.62 | 108
> Executing single-partition query on content_list_lookup | 18:10:08,768 |
> 10.10.45.62 | 1661
> Acquiring sstable references | 18:10:08,768 |
> 10.10.45.62 | 1736
> Merging memtable contents | 18:10:08,768 |
> 10.10.45.62 | 1859
> Key cache hit for sstable 3643 | 18:10:08,769 |
> 10.10.45.62 | 2000
> Key cache hit for sstable 3453 | 18:10:08,769 |
> 10.10.45.62 | 2542
> Key cache hit for sstable 3451 | 18:10:08,770 |
> 10.10.45.62 | 3009
> Merging data from memtables and 3 sstables | 18:10:08,770 |
> 10.10.45.62 | 3417
> Read 0 live cells and 720 tombstoned | 18:11:00,203 |
> 10.10.45.62 | 51436194
> Enqueuing response to /10.10.45.60 | 18:11:00,203 |
> 10.10.45.62 | 51436661
> Sending message to /10.10.45.60 | 18:11:00,203 |
> 10.10.45.62 | 51436861
> Message received from /10.10.45.62 | 18:11:00,240 |
> 10.10.45.60 | 51480364
> Processing response from /10.10.45.62 | 18:11:00,241 |
> 10.10.45.60 | 51480509
> Request complete | 18:11:00,240 |
> 10.10.45.60 | 51480626
> cqlsh:footballsite> SELECT * FROM "content_list_lookup" WHERE
> "editionContentId" = '8bf5cf79-f588-54b4-9114-05023d78d630.pt' ORDER BY
> "listId" ASC LIMIT 2 ;
> Request did not complete within rpc_timeout.
> Tracing session: a8266330-9bff-11e2-8b38-3183185e53e2
> activity | timestamp |
> source | source_elapsed
> ---------------------------------------------------------+--------------+-------------+----------------
> execute_cql3_query | 18:41:51,460 |
> 10.10.45.60 | 0
> Parsing statement | 18:41:51,460 |
> 10.10.45.60 | 37
> Peparing statement | 18:41:51,460 |
> 10.10.45.60 | 141
> Sending message to /10.10.45.59 | 18:41:51,461 |
> 10.10.45.60 | 377
> Message received from /10.10.45.60 | 18:41:51,462 |
> 10.10.45.59 | 24
> Executing single-partition query on content_list_lookup | 18:41:51,462 |
> 10.10.45.59 | 190
> Acquiring sstable references | 18:41:51,462 |
> 10.10.45.59 | 218
> Merging memtable contents | 18:41:51,462 |
> 10.10.45.59 | 246
> Key cache hit for sstable 4865 | 18:41:51,462 |
> 10.10.45.59 | 282
> Partition index lookup complete for sstable 4869 | 18:41:51,463 |
> 10.10.45.59 | 866
> Partition index lookup complete for sstable 4870 | 18:41:51,474 |
> 10.10.45.59 | 11891
> Merging data from memtables and 3 sstables | 18:41:51,481 |
> 10.10.45.59 | 19380
> Request complete | 18:42:51,460 |
> 10.10.45.60 | 60000477
> cqlsh:footballsite> SELECT * FROM "content_list_lookup" WHERE
> "editionContentId" = '8bf5cf79-f588-54b4-9114-05023d78d630.pt' ORDER BY
> "listId" ASC LIMIT 2 ;
> Request did not complete within rpc_timeout.
> Tracing session: 188441b0-9c00-11e2-8b38-3183185e53e2
> activity | timestamp |
> source | source_elapsed
> ---------------------------------------------------------+--------------+-------------+----------------
> execute_cql3_query | 18:44:59,980 |
> 10.10.45.60 | 0
> Parsing statement | 18:44:59,980 |
> 10.10.45.60 | 32
> Peparing statement | 18:44:59,980 |
> 10.10.45.60 | 123
> Sending message to /10.10.45.59 | 18:44:59,980 |
> 10.10.45.60 | 384
> Message received from /10.10.45.60 | 18:44:59,981 |
> 10.10.45.59 | 18
> Executing single-partition query on content_list_lookup | 18:44:59,981 |
> 10.10.45.59 | 204
> Acquiring sstable references | 18:44:59,981 |
> 10.10.45.59 | 222
> Merging memtable contents | 18:44:59,981 |
> 10.10.45.59 | 243
> Partition index lookup complete for sstable 4873 | 18:44:59,983 |
> 10.10.45.59 | 2042
> Key cache hit for sstable 4869 | 18:44:59,984 |
> 10.10.45.59 | 2828
> Key cache hit for sstable 4870 | 18:44:59,984 |
> 10.10.45.59 | 3260
> Merging data from memtables and 3 sstables | 18:44:59,985 |
> 10.10.45.59 | 3692
> Request complete | 18:45:59,980 |
> 10.10.45.60 | 60000424
> {code}
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
