cassandra-commits mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Jason Brown (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (CASSANDRA-13734) BufferUnderflowException when using uppercase UUID
Date Wed, 13 Sep 2017 21:50:00 GMT

    [ https://issues.apache.org/jira/browse/CASSANDRA-13734?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16165349#comment-16165349
] 

Jason Brown commented on CASSANDRA-13734:
-----------------------------------------

[~clauds2x] Thanks for all the info you've provided. Similar to [~bdeggleston], i haven't
been able to reproduce yet. Can you share examples of how you insert/update data? Also, do
you use TTLs on the table? If you can share the data from a failing row that would be great,
as well, but it looks like your data domain is probably rather sensitive, judging from the
column names.

The stack trace shows that we're failing to serialize the {{participants frozen<set<vivates_participant>>>}}
correctly, as we've reached the end of the {{ByteBuffer}}. Looking at your log dump, the line
SliceQueryFilter that describes loaded/merged {{participants}} is:

{code}
TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: participants:false:0@1501267173323000
{code}

The "0" after the {{false}} indicated how many bytes are in the buffer for that {{participants}}
column. When we try to get the size of the set ([here|https://github.com/apache/cassandra/blob/cassandra-2.2/src/java/org/apache/cassandra/serializers/CollectionSerializer.java#L85]),
it tries to read an int from the buffer, but because it's empty the {{BufferUnderflowException}}
is thrown.

It's not clear to me how we would get an empty buffer there; further, I'm not sure how the
UUID is related. Providing the insert/update statements will help a lot. Also, do you perform
a lot of rapid updates to a given partition, especially of the {{participants}} field?

If you can create an explicit example of how to repro that would be great. I'll keep digging
in the meantime...

> BufferUnderflowException when using uppercase UUID
> --------------------------------------------------
>
>                 Key: CASSANDRA-13734
>                 URL: https://issues.apache.org/jira/browse/CASSANDRA-13734
>             Project: Cassandra
>          Issue Type: Bug
>         Environment: Cassandra 2.2.8 running on OSX 10.12.5
> * org.apache.cassandra:cassandra-all:jar:2.2.8
> * com.datastax.cassandra:cassandra-driver-core:jar:3.0.0
> * org.apache.cassandra:cassandra-thrift:jar:2.2.8
>            Reporter: Claudia S
>
> We have a table with a primary key of type uuid which we query for results in JSON format.
When I accidentally caused a query passing a UUID which has an uppercase letter I noticed
that this causes a BufferUnderflowException on Cassandra.
> I directly attempted the queries using cqlsh, I can retrieve the entry using standard
select but whenever I pass JSON I get a BufferUnderflowException.
> {code:title=cql queries}
> cassandra@cqlsh:event_log_system> SELECT * FROM event WHERE id = 559a4d83-9410-4b69-b459-566b8cf57aaa;
> [RESULT REMOVED]
> (1 rows)
> cassandra@cqlsh:event_log_system> SELECT * FROM event WHERE id = 559a4d83-9410-4b69-b459-566b8cf57AAA;
> [RESULT REMOVED]
> (1 rows)
> cassandra@cqlsh:event_log_system> SELECT JSON * FROM event WHERE id = 559a4d83-9410-4b69-b459-566b8cf57AAA;
> ServerError: java.nio.BufferUnderflowException
> cassandra@cqlsh:event_log_system> SELECT JSON * FROM event WHERE id = 559a4d83-9410-4b69-b459-566b8cf57aaa;
> ServerError: java.nio.BufferUnderflowException
> {code}
> {code:title=log}
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,392 Message.java:506 - Received: QUERY
SELECT JSON * FROM event WHERE id = 559a4d83-9410-4b69-b459-566b8cf57AAA;, v=4
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,392 QueryProcessor.java:221 - Process
org.apache.cassandra.cql3.statements.SelectStatement@67e6c0c @CL.ONE
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,392 ReadCallback.java:76 - Blockfor is
1; setting up requests to localhost/127.0.0.1
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,393 AbstractReadExecutor.java:118 - reading
data locally
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,393 SliceQueryFilter.java:269 - collecting
0 of 2147483647: :false:0@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,393 SliceQueryFilter.java:269 - collecting
1 of 2147483647: can_login:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,393 SliceQueryFilter.java:269 - collecting
1 of 2147483647: is_superuser:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,393 SliceQueryFilter.java:269 - collecting
1 of 2147483647: salted_hash:false:60@1501267019830000
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,393 StorageProxy.java:1449 - Read: 0
ms.
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,394 ReadCallback.java:76 - Blockfor is
1; setting up requests to localhost/127.0.0.1
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,394 AbstractReadExecutor.java:118 - reading
data locally
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,394 SliceQueryFilter.java:269 - collecting
0 of 2147483647: :false:0@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,394 SliceQueryFilter.java:269 - collecting
1 of 2147483647: can_login:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,394 SliceQueryFilter.java:269 - collecting
1 of 2147483647: is_superuser:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,394 SliceQueryFilter.java:269 - collecting
1 of 2147483647: salted_hash:false:60@1501267019830000
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,395 StorageProxy.java:1449 - Read: 0
ms.
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:41,395 SliceQueryPager.java:92 - Querying
next page of slice query; new filter: SliceQueryFilter [reversed=false, slices=[[, ]], count=100,
toGroup = 0]
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,395 ReadCallback.java:76 - Blockfor is
1; setting up requests to localhost/127.0.0.1
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,395 AbstractReadExecutor.java:118 - reading
data locally
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
0 of 100: :false:0@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: action:false:4@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: action_outcome:false:7@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: business_process_id:false:22@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: created_at:false:8@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: event_type:false:22@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: hp_id:true:4@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: message:true:4@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: participants:false:0@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: patient_id:false:6@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: session_id:false:18@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,396 SliceQueryFilter.java:269 - collecting
1 of 100: session_type:false:6@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,397 SliceQueryFilter.java:269 - collecting
1 of 100: source_id:false:11@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,397 SliceQueryFilter.java:269 - collecting
1 of 100: user:false:25@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:41,397 SliceQueryFilter.java:269 - collecting
1 of 100: version:false:3@1501267173323000
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,397 StorageProxy.java:1449 - Read: 1
ms.
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:41,397 AbstractQueryPager.java:95 - Fetched
1 live rows
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:41,397 AbstractQueryPager.java:112 - Got
result (1) smaller than page size (100), considering pager exhausted
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:41,397 AbstractQueryPager.java:133 - Remaining
rows to page: 2147483646
> ERROR [SharedPool-Worker-1] 2017-07-28 20:40:41,398 QueryMessage.java:136 - Unexpected
error during query
> java.nio.BufferUnderflowException: null
> 	at java.nio.Buffer.nextGetIndex(Buffer.java:506) ~[na:1.8.0_131]
> 	at java.nio.HeapByteBuffer.getInt(HeapByteBuffer.java:361) ~[na:1.8.0_131]
> 	at org.apache.cassandra.serializers.CollectionSerializer.readCollectionSize(CollectionSerializer.java:87)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.ListType.setOrListToJsonString(ListType.java:212)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.SetType.toJSONString(SetType.java:186) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.rowToJson(Selection.java:383)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.getOutputRow(Selection.java:359)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.build(Selection.java:346)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.process(SelectStatement.java:681)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.processResults(SelectStatement.java:282)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:243)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:179)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:76)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.processStatement(QueryProcessor.java:226)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:257) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:242) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.messages.QueryMessage.execute(QueryMessage.java:123)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:507)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:401)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:333)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.access$700(AbstractChannelHandlerContext.java:32)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext$8.run(AbstractChannelHandlerContext.java:324)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_131]
> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:164)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:105) [apache-cassandra-2.2.8.jar:2.2.8]
> 	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
> ERROR [SharedPool-Worker-1] 2017-07-28 20:40:41,398 ErrorMessage.java:338 - Unexpected
exception during request
> java.nio.BufferUnderflowException: null
> 	at java.nio.Buffer.nextGetIndex(Buffer.java:506) ~[na:1.8.0_131]
> 	at java.nio.HeapByteBuffer.getInt(HeapByteBuffer.java:361) ~[na:1.8.0_131]
> 	at org.apache.cassandra.serializers.CollectionSerializer.readCollectionSize(CollectionSerializer.java:87)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.ListType.setOrListToJsonString(ListType.java:212)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.SetType.toJSONString(SetType.java:186) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.rowToJson(Selection.java:383)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.getOutputRow(Selection.java:359)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.build(Selection.java:346)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.process(SelectStatement.java:681)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.processResults(SelectStatement.java:282)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:243)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:179)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:76)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.processStatement(QueryProcessor.java:226)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:257) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:242) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.messages.QueryMessage.execute(QueryMessage.java:123)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:507)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:401)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:333)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.access$700(AbstractChannelHandlerContext.java:32)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext$8.run(AbstractChannelHandlerContext.java:324)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_131]
> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:164)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:105) [apache-cassandra-2.2.8.jar:2.2.8]
> 	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,399 Tracing.java:156 - request complete
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:41,399 Message.java:525 - Responding: ERROR
SERVER_ERROR: java.nio.BufferUnderflowException, v=4
> TRACE [GossipTasks:1] 2017-07-28 20:40:41,420 Gossiper.java:144 - My heartbeat is now
747
> TRACE [GossipTasks:1] 2017-07-28 20:40:41,420 Gossiper.java:502 - Gossip Digests are
: localhost/127.0.0.1:1501267007:747
> TRACE [GossipTasks:1] 2017-07-28 20:40:41,420 Gossiper.java:739 - Performing status check
...
> TRACE [EXPIRING-MAP-REAPER:1] 2017-07-28 20:40:41,520 ExpiringMap.java:102 - Expired
0 entries
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,312 Message.java:506 - Received: QUERY
SELECT JSON * FROM event WHERE id = 559a4d83-9410-4b69-b459-566b8cf57aaa;, v=4
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,312 QueryProcessor.java:221 - Process
org.apache.cassandra.cql3.statements.SelectStatement@33dbcc81 @CL.ONE
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,313 ReadCallback.java:76 - Blockfor is
1; setting up requests to localhost/127.0.0.1
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,313 AbstractReadExecutor.java:118 - reading
data locally
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,313 SliceQueryFilter.java:269 - collecting
0 of 2147483647: :false:0@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,313 SliceQueryFilter.java:269 - collecting
1 of 2147483647: can_login:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,313 SliceQueryFilter.java:269 - collecting
1 of 2147483647: is_superuser:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,313 SliceQueryFilter.java:269 - collecting
1 of 2147483647: salted_hash:false:60@1501267019830000
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,313 StorageProxy.java:1449 - Read: 0
ms.
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,314 ReadCallback.java:76 - Blockfor is
1; setting up requests to localhost/127.0.0.1
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,314 AbstractReadExecutor.java:118 - reading
data locally
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,314 SliceQueryFilter.java:269 - collecting
0 of 2147483647: :false:0@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,314 SliceQueryFilter.java:269 - collecting
1 of 2147483647: can_login:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,314 SliceQueryFilter.java:269 - collecting
1 of 2147483647: is_superuser:false:1@1501267019830000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,314 SliceQueryFilter.java:269 - collecting
1 of 2147483647: salted_hash:false:60@1501267019830000
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,315 StorageProxy.java:1449 - Read: 0
ms.
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:45,315 SliceQueryPager.java:92 - Querying
next page of slice query; new filter: SliceQueryFilter [reversed=false, slices=[[, ]], count=100,
toGroup = 0]
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,315 ReadCallback.java:76 - Blockfor is
1; setting up requests to localhost/127.0.0.1
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,315 AbstractReadExecutor.java:118 - reading
data locally
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,315 SliceQueryFilter.java:269 - collecting
0 of 100: :false:0@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,315 SliceQueryFilter.java:269 - collecting
1 of 100: action:false:4@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,315 SliceQueryFilter.java:269 - collecting
1 of 100: action_outcome:false:7@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,315 SliceQueryFilter.java:269 - collecting
1 of 100: business_process_id:false:22@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: created_at:false:8@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: event_type:false:22@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: hp_id:true:4@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: message:true:4@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: participants:false:0@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: patient_id:false:6@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: session_id:false:18@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: session_type:false:6@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: source_id:false:11@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: user:false:25@1501267173323000
> TRACE [SharedPool-Worker-2] 2017-07-28 20:40:45,316 SliceQueryFilter.java:269 - collecting
1 of 100: version:false:3@1501267173323000
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,316 StorageProxy.java:1449 - Read: 1
ms.
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:45,316 AbstractQueryPager.java:95 - Fetched
1 live rows
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:45,317 AbstractQueryPager.java:112 - Got
result (1) smaller than page size (100), considering pager exhausted
> DEBUG [SharedPool-Worker-1] 2017-07-28 20:40:45,317 AbstractQueryPager.java:133 - Remaining
rows to page: 2147483646
> ERROR [SharedPool-Worker-1] 2017-07-28 20:40:45,317 QueryMessage.java:136 - Unexpected
error during query
> java.nio.BufferUnderflowException: null
> 	at java.nio.Buffer.nextGetIndex(Buffer.java:506) ~[na:1.8.0_131]
> 	at java.nio.HeapByteBuffer.getInt(HeapByteBuffer.java:361) ~[na:1.8.0_131]
> 	at org.apache.cassandra.serializers.CollectionSerializer.readCollectionSize(CollectionSerializer.java:87)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.ListType.setOrListToJsonString(ListType.java:212)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.SetType.toJSONString(SetType.java:186) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.rowToJson(Selection.java:383)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.getOutputRow(Selection.java:359)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.build(Selection.java:346)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.process(SelectStatement.java:681)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.processResults(SelectStatement.java:282)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:243)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:179)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:76)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.processStatement(QueryProcessor.java:226)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:257) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:242) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.messages.QueryMessage.execute(QueryMessage.java:123)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:507)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:401)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:333)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.access$700(AbstractChannelHandlerContext.java:32)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext$8.run(AbstractChannelHandlerContext.java:324)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_131]
> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:164)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:105) [apache-cassandra-2.2.8.jar:2.2.8]
> 	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
> ERROR [SharedPool-Worker-1] 2017-07-28 20:40:45,317 ErrorMessage.java:338 - Unexpected
exception during request
> java.nio.BufferUnderflowException: null
> 	at java.nio.Buffer.nextGetIndex(Buffer.java:506) ~[na:1.8.0_131]
> 	at java.nio.HeapByteBuffer.getInt(HeapByteBuffer.java:361) ~[na:1.8.0_131]
> 	at org.apache.cassandra.serializers.CollectionSerializer.readCollectionSize(CollectionSerializer.java:87)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.ListType.setOrListToJsonString(ListType.java:212)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.db.marshal.SetType.toJSONString(SetType.java:186) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.rowToJson(Selection.java:383)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.getOutputRow(Selection.java:359)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.selection.Selection$ResultSetBuilder.build(Selection.java:346)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.process(SelectStatement.java:681)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.processResults(SelectStatement.java:282)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:243)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:179)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.statements.SelectStatement.execute(SelectStatement.java:76)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.processStatement(QueryProcessor.java:226)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:257) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.cql3.QueryProcessor.process(QueryProcessor.java:242) ~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.messages.QueryMessage.execute(QueryMessage.java:123)
~[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:507)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.transport.Message$Dispatcher.channelRead0(Message.java:401)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:333)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext.access$700(AbstractChannelHandlerContext.java:32)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at io.netty.channel.AbstractChannelHandlerContext$8.run(AbstractChannelHandlerContext.java:324)
[netty-all-4.0.23.Final.jar:4.0.23.Final]
> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_131]
> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:164)
[apache-cassandra-2.2.8.jar:2.2.8]
> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:105) [apache-cassandra-2.2.8.jar:2.2.8]
> 	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,318 Tracing.java:156 - request complete
> TRACE [SharedPool-Worker-1] 2017-07-28 20:40:45,318 Message.java:525 - Responding: ERROR
SERVER_ERROR: java.nio.BufferUnderflowException, v=4
> TRACE [GossipTasks:1] 2017-07-28 20:40:45,434 Gossiper.java:144 - My heartbeat is now
759
> TRACE [GossipTasks:1] 2017-07-28 20:40:45,434 Gossiper.java:502 - Gossip Digests are
: localhost/127.0.0.1:1501267007:759
> TRACE [GossipTasks:1] 2017-07-28 20:40:45,434 Gossiper.java:739 - Performing status check
...
> {code}
> When the query is executed using {{com.datastax.driver.core.Session}} it works - no matter
if the UUID is correct or contains uppercase letters.
> I didn't find a human readable query in the log in this case - for comparison - but in
our code we use the template: {{SELECT JSON * FROM event WHERE id = ?}}
> Obviously I did something wrong here but my colleague pointed out that the effect it
has is not so cool. ;)



--
This message was sent by Atlassian JIRA
(v6.4.14#64029)

---------------------------------------------------------------------
To unsubscribe, e-mail: commits-unsubscribe@cassandra.apache.org
For additional commands, e-mail: commits-help@cassandra.apache.org


Mime
View raw message