Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Second part of #1644 fix: C*4 from 4.0.1 to 4.0.3 upgrade #1647

Merged
merged 15 commits into from Feb 24, 2022

Conversation

tatu-at-datastax
Copy link
Collaborator

@tatu-at-datastax tatu-at-datastax commented Feb 17, 2022

What this PR does:

Second upgrade for #1644: Cassandra 4.x dependency from 4.0.1 to 4.0.3

Which issue(s) this PR fixes:

Part of #1644.

Checklist

  • Changes manually tested
  • Automated Tests added/updated
  • Documentation added/updated
  • CLA Signed: DataStax CLA

@tatu-at-datastax tatu-at-datastax added the dependencies Pull requests that update a dependency file label Feb 17, 2022
@tatu-at-datastax tatu-at-datastax self-assigned this Feb 17, 2022
@tatu-at-datastax
Copy link
Collaborator Author

tatu-at-datastax commented Feb 17, 2022

Similar to C*3, new version of Cassandra-4 also triggers one new IT failure:

  • io.stargate.it.grpc.streaming.ReactiveBatchTest method simpleReactiveBiDirectionalQueries

Relevant stack trace seems to be:

ERROR [Native-Transport-Requests-1] 2022-02-17 00:25:41,281 ErrorMessage.java:457 - Unexpected exception during request
java.lang.NullPointerException: null
  at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
  at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
  at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
  at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
  at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
  at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
  at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
  at java.lang.Thread.run(Thread.java:748)

@ivansenic
Copy link
Contributor

@tatu-at-datastax Did the analysis of the above exception.. I even reproduced it locally, but it's very hard to do so as it's a race condition bug in the Cassandra 3.11.12 and 4.0.2.. Here's what's going on:

  • QueryProcessor prepare method changed recently.. They added some kind of new mechanism for the "new prepared statement behavior"..
  • So in the test where it fails ExecuteBatchStreamingTest seems that the prepared path leads through the lines 601-606, which evicts the prepared statement from the cache and re-prepares it
  • However, if there was an exactly the same statement prepared a moment before by another thread, and that thread moves to the QueryProcessor#getPrepared, that thread can get the null as the result, due to the eviction by another thread
  • We don't protect against that null and we fail terribly here..

This is a complete mess:

  1. We need to figure out a this new behavior and if we are doing something wrong ourselves..
  2. We need to understand if we need to protect against null here, I see that C code throws throw new PreparedQueryNotFoundException(statementId); in such cases
  3. We need to check how in the earth can this work with previous versions, not everybody would move to the new behavior so easily..

For start I am thinking to create a test to easily reproduce this..

Copy link
Contributor

@ivansenic ivansenic left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Stops from merging, due to the #1647 (comment)

@ivansenic
Copy link
Contributor

ivansenic commented Feb 17, 2022

Here's the patch to easily reproduce locally. Confirmed that the same patch is reproducing the same error with C* 3.11.12.

diff --git a/testing/src/main/java/io/stargate/it/grpc/streaming/ExecuteBatchStreamingTest.java b/testing/src/main/java/io/stargate/it/grpc/streaming/ExecuteBatchStreamingTest.java
index 82002582..bf0921f7 100644
--- a/testing/src/main/java/io/stargate/it/grpc/streaming/ExecuteBatchStreamingTest.java
+++ b/testing/src/main/java/io/stargate/it/grpc/streaming/ExecuteBatchStreamingTest.java
@@ -78,42 +78,24 @@ public class ExecuteBatchStreamingTest extends GrpcIntegrationTest {
 
     StreamObserver<Batch> requestObserver = stub.executeBatchStream(responseStreamObserver);
 
-    requestObserver.onNext(
-        Batch.newBuilder()
-            .addQueries(cqlBatchQuery("INSERT INTO test (k, v) VALUES ('a', 1)"))
-            .addQueries(
-                cqlBatchQuery(
-                    "INSERT INTO test (k, v) VALUES (?, ?)", Values.of("b"), Values.of(2)))
-            .setParameters(batchParameters(keyspace))
-            .build());
+    for (int i = 0; i < 1000; i++) {
+      requestObserver.onNext(
+          Batch.newBuilder()
+              .addQueries(cqlBatchQuery("INSERT INTO test (k, v) VALUES ('a', 1)"))
+              .addQueries(
+                  cqlBatchQuery(
+                      "INSERT INTO test (k, v) VALUES (?, ?)", Values.of("b"), Values.of(2)))
+              .setParameters(batchParameters(keyspace))
+              .build());
+    }
 
-    requestObserver.onNext(
-        Batch.newBuilder()
-            .addQueries(
-                cqlBatchQuery(
-                    "INSERT INTO test (k, v) VALUES (?, ?)", Values.of("c"), Values.of(3)))
-            .setParameters(batchParameters(keyspace))
-            .build());
 
     // all inserted records may be not visible to the subsequent SELECT query
     // because all reactive calls are non-blocking. Therefore, we need to wait for response of two
     // batch insert
     // queries
-    Awaitility.await().atMost(10, TimeUnit.SECONDS).until(() -> responses.size() == 2);
+    Awaitility.await().atMost(30, TimeUnit.SECONDS).until(() -> responses.size() == 2000);
     requestObserver.onCompleted();
-
-    Response response =
-        stubWithCallCredentials()
-            .executeQuery(cqlQuery("SELECT * FROM test", queryParameters(keyspace)));
-    assertThat(response.hasResultSet()).isTrue();
-    ResultSet rs = response.getResultSet();
-    assertThat(new HashSet<>(rs.getRowsList()))
-        .isEqualTo(
-            new HashSet<>(
-                Arrays.asList(
-                    rowOf(Values.of("a"), Values.of(1)),
-                    rowOf(Values.of("b"), Values.of(2)),
-                    rowOf(Values.of("c"), Values.of(3)))));
   }
 
   @Test

@tatu-at-datastax tatu-at-datastax changed the title Second part of #1644 fix: C*4 from 4.0.1 to 4.0.2 upgrade Second part of #1644 fix: C*4 from 4.0.1 to 4.0.3 upgrade Feb 17, 2022
@tatu-at-datastax
Copy link
Collaborator Author

From parent issue: possibly due to:

https://issues.apache.org/jira/browse/CASSANDRA-17248

@tatu-at-datastax
Copy link
Collaborator Author

@ivansenic Thank you for digging into this! Do you think it'd make sense to merge the test change via separate PR (I assume it passes against current C3.11.11 / C4.0.1)?

Also, do you think C*3.11 failures are due to same root cause or something different?

@ivansenic
Copy link
Contributor

@tatu-at-datastax tests are green now, but the stuff was not fixed in the 4.0.3.. Thus I am still blocking the merge :)

@JeremiahDJordan
Copy link

Is that client level code or internal? Any chance you can try similar against straight C*, not stargate, to show a Cassandra bug to be fixed?

@ivansenic
Copy link
Contributor

Is that client level code or internal? Any chance you can try similar against straight C*, not stargate, to show a Cassandra bug to be fixed?

Sure let me try.

@mpenick
Copy link
Contributor

mpenick commented Feb 18, 2022

This is likely to race no matter how the internals are implemented. prepared == null is always a possiblity. wdyt think about this? Wait, maybe we'll always take the slow path though when constantly repreparing.

diff --git a/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java b/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java
index fba767be..978afa0b 100644
--- a/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java
+++ b/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java
@@ -151,8 +151,14 @@ public class StargateQueryHandler implements QueryHandler {
       throws RequestValidationException {
     ResultMessage.Prepared prepare = QueryProcessor.instance.prepare(s, clientState, map);
     Prepared prepared = QueryProcessor.instance.getPrepared(prepare.statementId);
-    boolean idempotent = IdempotencyAnalyzer.isIdempotent(prepared.statement);
-    boolean useKeyspace = prepared.statement instanceof UseStatement;
+    CQLStatement statement;
+    if (prepared == null) { // Prepared cache evicted the entry, re-parse the statement (slow path)
+      statement = QueryProcessor.getStatement(s, clientState);
+    } else {
+      statement = prepared.statement;
+    }
+    boolean idempotent = IdempotencyAnalyzer.isIdempotent(statement);
+    boolean useKeyspace = statement instanceof UseStatement;
     return new PreparedWithInfo(idempotent, useKeyspace, prepare);
   }

@ivansenic
Copy link
Contributor

This is likely to race no matter how the internals are implemented. prepared == null is always a possiblity. wdyt think about this? Wait, maybe we'll always take the slow path though when constantly repreparing.

diff --git a/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java b/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java
index fba767be..978afa0b 100644
--- a/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java
+++ b/persistence-cassandra-4.0/src/main/java/io/stargate/db/cassandra/impl/StargateQueryHandler.java
@@ -151,8 +151,14 @@ public class StargateQueryHandler implements QueryHandler {
       throws RequestValidationException {
     ResultMessage.Prepared prepare = QueryProcessor.instance.prepare(s, clientState, map);
     Prepared prepared = QueryProcessor.instance.getPrepared(prepare.statementId);
-    boolean idempotent = IdempotencyAnalyzer.isIdempotent(prepared.statement);
-    boolean useKeyspace = prepared.statement instanceof UseStatement;
+    CQLStatement statement;
+    if (prepared == null) { // Prepared cache evicted the entry, re-parse the statement (slow path)
+      statement = QueryProcessor.getStatement(s, clientState);
+    } else {
+      statement = prepared.statement;
+    }
+    boolean idempotent = IdempotencyAnalyzer.isIdempotent(statement);
+    boolean useKeyspace = statement instanceof UseStatement;
     return new PreparedWithInfo(idempotent, useKeyspace, prepare);
   }

But is the prepared == null expected exactly after a call to prepare it? If this was the case until now, why did not we have similar fix until now? I am more for waiting that this is fixed in C*, if this is a bug there.. If not then yea let s have something like this..

@tatu-at-datastax
Copy link
Collaborator Author

@tatu-at-datastax tests are green now, but the stuff was not fixed in the 4.0.3.. Thus I am still blocking the merge :)

lol. Agreed, 4.0.3 has no fixes here (afaik) over 4.0.2.

@ivansenic
Copy link
Contributor

ivansenic commented Feb 18, 2022

Tried to reproduce against C* 4.0.2 without Stargate, but I had no luck.. Then I used the same code and fired against Stargate and still it worked fine and no errors where there.. Maybe my test setup was wrong, I used the following code:

        SimpleStatementBuilder statementBuilder = new SimpleStatementBuilder("USE test");
        SimpleStatement statement = statementBuilder.setTimeout(Duration.ofSeconds(1)).build();
        session.execute(statement);

        Flux.generate(
                        () -> 1,
                        (state, sink) -> {
                            sink.next(state);
                            return state + 1;
                        }
                )
                .flatMap(e -> Flux.just(e)
                        .publishOn(Schedulers.boundedElastic())
                        .flatMap(elem -> {
                            String id = RandomStringUtils.randomAlphanumeric(32);
                            SimpleStatementBuilder builder = new SimpleStatementBuilder("INSERT INTO tbl (k) VALUES (?)");
                            BoundStatement bind = session.prepare(builder.build()).bind(id);

                            ResultSet result = session.execute(bind);
                            ExecutionInfo executionInfo = result.getExecutionInfo();

                            List<Map.Entry<Node, Throwable>> errors = executionInfo.getErrors();
                            if (!errors.isEmpty()) {
                                LOG.info("Errors {}", errors);
                            }
                            return Flux.just(errors);
                        }), 1000)
                .subscribe();

This should hit the CQL with high concurrency from the client side.. And I tried to use same statement as in the failing test.. Maybe the CQL driver fixes the statement to be fully qualified.. Not sure really..

UPDATE: OK, I figured out what's going on, this is so hard to reproduce.. Problem is that race condition between two threads needs to happen on a first occasion when the statement is prepared.. The only way to reproduce is that two threads enter the prepare method at the same time when the statement is not prepared.. As soon as one thread executes the method without interference of other, there is no chance to get the error in the future.. I am not sure if I can reproduce this with driver.. I guess we were quite lucky to hit this, and quite lucky to have grpc streaming as I guess without it it would be impossible..

@tatu-at-datastax
Copy link
Collaborator Author

tatu-at-datastax commented Feb 24, 2022

@ivansenic I may be wrong but it seems to me that the reproduction you had might not actually reproduce the bug (see my comment on #1655). Fix may well be valid but it'd be important to be able to reproduce the fail first to verify it.
I am hoping that maybe we can use the original failing IT or something; unless this is something specific to Google cloud environment.

In addition to the problem of reproducing the issue with. new tests, I tried to make ReactiveBatchTest fail locally (against Cassandra-4.0): that does not happen either (at least not reliably). Attempts to use @RepeatedTest with values like 100 or 1000 do not reproduce the failure either. At least not against 4.0.3, running on local machine.
But neither does this test seem to fail any more on CI (see test runs for #1655).

@ivansenic
Copy link
Contributor

It's super easy to reproduce this on my machine.. Just use my diff and start the INT test locally with the -Dccm.version=4.0.3. Yes the assertion is wrong, should be 1000 responses, not 2000. I am adding test updates as a commit here..

@ivansenic
Copy link
Contributor

ivansenic commented Feb 24, 2022

With d19a15d builds are failing for 3.11 and 4.0 as expected with:

Stack trace
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:37,978 ProcessRunner.java:78 - stargate12-0> INFO  [Native-Transport-Requests-1] 2022-02-24 11:59:37,978 QueryProcessor.java:547 - Fully upgraded to at least 4.0.3
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-10] 2022-02-24 11:59:38,006 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,007 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-3] 2022-02-24 11:59:38,006 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,009 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-6] 2022-02-24 11:59:38,006 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,010 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,011 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-8] 2022-02-24 11:59:38,007 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,011 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,011 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,011 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,011 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,011 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,011 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-4] 2022-02-24 11:59:38,007 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-9] 2022-02-24 11:59:38,009 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-2] 2022-02-24 11:59:38,006 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,012 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,013 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,013 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,013 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,013 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,013 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-1] 2022-02-24 11:59:38,009 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-11] 2022-02-24 11:59:38,023 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,023 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,024 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-14] 2022-02-24 11:59:38,026 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,030 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-27] 2022-02-24 11:59:38,033 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-7] 2022-02-24 11:59:38,034 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,034 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-17] 2022-02-24 11:59:38,035 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,036 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-18] 2022-02-24 11:59:38,042 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,042 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-16] 2022-02-24 11:59:38,042 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,046 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-5] 2022-02-24 11:59:38,048 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,050 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,051 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,058 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-20] 2022-02-24 11:59:38,058 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,059 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,062 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-25] 2022-02-24 11:59:38,062 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,062 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,062 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,062 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,062 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-19] 2022-02-24 11:59:38,062 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-31] 2022-02-24 11:59:38,062 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,063 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-21] 2022-02-24 11:59:38,062 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-33] 2022-02-24 11:59:38,062 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,064 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-28] 2022-02-24 11:59:38,062 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-24] 2022-02-24 11:59:38,064 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,065 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-30] 2022-02-24 11:59:38,065 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> ERROR [Native-Transport-Requests-26] 2022-02-24 11:59:38,065 ErrorMessage.java:457 - Unexpected exception during request
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> java.lang.NullPointerException: null
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.StargateQueryHandler.prepare(StargateQueryHandler.java:154)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.messages.PrepareMessage.execute(PrepareMessage.java:125)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:455)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,066 ProcessRunner.java:78 - stargate12-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,067 ProcessRunner.java:78 - stargate12-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
Step #4 - "cassandra-4.0": INFO  [Exec Stream Pumper] 2022-02-24 11:59:38,067 ProcessRunner.java:78 - stargate12-0> 	at java.lang.Thread.run(Thread.java:748)
Step #4 - "cassandra-4.0": WARN  [main] 2022-02-24 11:59:42,622 ExternalStorage.java:153 - Test error in [engine:junit-jupiter]/[class:io.stargate.it.grpc.streaming.ExecuteBatchStreamingTest]/[method:manyStreamingBatch(com.datastax.oss.driver.api.core.CqlIdentifier)]
Step #4 - "cassandra-4.0": org.awaitility.core.ConditionTimeoutException: Assertion condition defined as a lambda expression in io.stargate.it.grpc.streaming.ExecuteBatchStreamingTest that uses java.util.List, java.util.Listint 
Step #4 - "cassandra-4.0": Expecting all elements of:
Step #4 - "cassandra-4.0":   [code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: Unexpected persistence error: null"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\"Unexpected persistence error: null"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: Unexpected persistence error: null"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\"Unexpected persistence error: null"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     code: 13
Step #4 - "cassandra-4.0": message: "INTERNAL: java.lang.NullPointerException"
Step #4 - "cassandra-4.0": details {
Step #4 - "cassandra-4.0":   type_url: "type.googleapis.com/google.rpc.ErrorInfo"
Step #4 - "cassandra-4.0":   value: "\n\036java.lang.NullPointerException"
Step #4 - "cassandra-4.0": }
Step #4 - "cassandra-4.0": ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ,
Step #4 - "cassandra-4.0":     ]
Step #4 - "cassandra-4.0": to satisfy given requirements, but these elements did not:

@ivansenic
Copy link
Contributor

The 5033209 should fix build with C* 4.0.

@ivansenic
Copy link
Contributor

The a57630c sholuld fix build with C* 3.11.

@ivansenic
Copy link
Contributor

OK preparing problems are solved. However, new issue is unrevealed wrt gRPC now.

Seems that gRPC is not implementing retries in case of the PreparedQueryNotFoundException. This is not in line with the PersistenceBackedDataStore. In fact retry strategies are completely different.

INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> ERROR [Native-Transport-Requests-48] 2022-02-24 16:06:57,881 Cassandra40Persistence.java:274 - Exception occurred in persistence.
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> org.apache.cassandra.stargate.exceptions.PreparedQueryNotFoundException: Prepared query with ID 77039fa03b9a8795ba5a8318fb110e86 not found (either the query was not prepared on this host (maybe the host has been restarted?) or you have prepared too many queries and it has been evicted from the internal cache)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at io.stargate.db.cassandra.impl.Conversion.toExternal(Conversion.java:382)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at io.stargate.db.cassandra.impl.Conversion.convertInternalException(Conversion.java:509)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:464)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at io.stargate.db.cassandra.impl.Cassandra40Persistence.lambda$runOnExecutor$1(Cassandra40Persistence.java:271)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$FutureTask.run(AbstractLocalAwareExecutorService.java:165)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at org.apache.cassandra.concurrent.AbstractLocalAwareExecutorService$LocalSessionFutureTask.run(AbstractLocalAwareExecutorService.java:137)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at org.apache.cassandra.concurrent.SEPWorker.run(SEPWorker.java:119)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	at java.lang.Thread.run(Thread.java:748)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,882 ProcessRunner.java:78 - stargate0-0> 	Suppressed: org.apache.cassandra.exceptions.PreparedQueryNotFoundException: Prepared query with ID 77039fa03b9a8795ba5a8318fb110e86 not found (either the query was not prepared on this host (maybe the host has been restarted?) or you have prepared too many queries and it has been evicted from the internal cache)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,883 ProcessRunner.java:78 - stargate0-0> 		at org.apache.cassandra.transport.messages.BatchMessage.execute(BatchMessage.java:193)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,883 ProcessRunner.java:78 - stargate0-0> 		at org.apache.cassandra.transport.Message$Request.execute(Message.java:242)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,883 ProcessRunner.java:78 - stargate0-0> 		at io.stargate.db.cassandra.impl.Cassandra40Persistence$Cassandra40Connection.lambda$executeRequestOnExecutor$0(Cassandra40Persistence.java:456)
INFO  [Exec Stream Pumper] 2022-02-24 16:06:57,883 ProcessRunner.java:78 - stargate0-0> 		... 7 common frames omitted

@mpenick I will add a small fix for the io.stargate.grpc.service.MessageHandler#shouldRetry and add:

      case UNPREPARED:
        return RetryDecision.RETRY;

I am sure this has to be valid.

@tatu-at-datastax
Copy link
Collaborator Author

And just for sake of completeness: my failure to reproduce underlying issue was due to PR (#1655) not containing C*4 upgrade so was testing against 4.0.1 which does not have the issue.

@ivansenic ivansenic self-requested a review February 24, 2022 16:15
@tatu-at-datastax
Copy link
Collaborator Author

Looks good @ivansenic -- approved.

CQLStatement statement =
Optional.ofNullable(QueryProcessor.instance.getPrepared(prepare.statementId))
.map(p -> p.statement)
.orElseGet(() -> QueryProcessor.getStatement(s, clientState));
Copy link
Collaborator

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

So if I'm following correctly, QueryProcessor.getStatement re-prepares the statement but does not put it in the cache?
How does the cache eventually get populated? Is it by the other thread we were racing with, or just the next time StargateQueryHandler.prepare gets called?

Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Yes QueryProcessor.getStatement re-parse and re-prepares, with out interacting with the cache. In addition we get a statement back. Instead of the MD5Digest.

Cache will get evicted and then re-populated by another thread. Last thread always inserts to cache.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
dependencies Pull requests that update a dependency file
Projects
None yet
Development

Successfully merging this pull request may close these issues.

None yet

5 participants