[
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-unsubscr...@cassandra.apache.org
For additional commands, e-mail: commits-h...@cassandra.apache.org
