[CASSANDRA-18288] Test Failure: TopPartitionsTest.basicRegularTombstonesTest

• Type: Bug
• Status: Open
• Priority: Normal
• Resolution: Unresolved
• Fix Version/s: 5.0.x, 5.x
• Component/s: Test/dtest/java
• Bug Category: Correctness - Test Failure
• Severity: Normal
• Complexity: Normal
• Discovered By: User Report
• Platform: All

Test and Documentation Plan

See comments

• Assignee: Unassigned
• Reporter: Michael Semb Wever

Reviewers

Brandon Williams

• Created: 27/Feb/23 21:11
• Updated: 08/Jan/24 08:54

Description

from

• https://ci-cassandra.apache.org/job/Cassandra-trunk/1469/testReport/org.apache.cassandra.distributed.test/TopPartitionsTest/basicRegularTombstonesTest_Incremental___jdk11/
• https://ci-cassandra.apache.org/job/Cassandra-trunk-jvm-dtest/1534/jdk=jdk_11_latest,label=cassandra,split=8/testReport/junit/org.apache.cassandra.distributed.test/TopPartitionsTest/basicRegularTombstonesTest_Incremental___jdk11/

Stacktrace

java.lang.RuntimeException: 
	at org.psjava.util.AssertStatus.assertTrue(AssertStatus.java:18)
	at org.psjava.util.AssertStatus.assertTrue(AssertStatus.java:5)
	at org.apache.cassandra.distributed.test.TopPartitionsTest.lambda$basicRegularTombstonesTest$e2f532b5$1(TopPartitionsTest.java:226)
	at org.apache.cassandra.concurrent.FutureTask$1.call(FutureTask.java:96)
	at org.apache.cassandra.concurrent.FutureTask.call(FutureTask.java:61)
	at org.apache.cassandra.concurrent.FutureTask.run(FutureTask.java:71)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
	at java.base/java.lang.Thread.run(Thread.java:829)

Standard Output

INFO  [main] <main> 2023-02-25 00:15:20,407 Reflections.java:219 - Reflections took 1661 ms to scan 9 urls, producing 1832 keys and 7268 values
INFO  [main] <main> 2023-02-25 00:15:21,602 Reflections.java:219 - Reflections took 1131 ms to scan 9 urls, producing 1832 keys and 7268 values
Node id topology:
node 1: dc = datacenter0, rack = rack0
node 2: dc = datacenter0, rack = rack0
Configured node count: 2, nodeIdTopology size: 2
DEBUG [main] node1 2023-02-25 00:15:22,419 InternalLoggerFactory.java:63 - Using SLF4J as the default logging framework
DEBUG [main] node1 2023-02-25 00:15:22,426 PlatformDependent0.java:417 - -Dio.netty.noUnsafe: false
DEBUG [main] node1 2023-02-25 00:15:22,427 PlatformDependent0.java:897 - Java version: 11
DEBUG [main] node1 2023-02-25 00:15:22,428 PlatformDependent0.java:130 - sun.misc.Unsafe.theUnsafe: available
DEBUG [main] node1 2023-02-25 00:15:22,429 PlatformDependent0.java:154 - sun.misc.Unsafe.copyMemory: available
DEBUG [main] node1 2023-02-25 00:15:22,430 PlatformDependent0.java:192 - java.nio.Buffer.address: available
DEBUG [main] node1 2023-02-25 00:15:22,431 PlatformDependent0.java:257 - direct buffer constructor: available
DEBUG [main] node1 2023-02-25 00:15:22,432 PlatformDependent0.java:331 - java.nio.Bits.unaligned: available, true
DEBUG [main] node1 2023-02-25 00:15:22,434 PlatformDependent0.java:393 - jdk.internal.misc.Unsafe.allocateUninitializedArray(int): available
DEBUG [main] node1 2023-02-25 00:15:22,435 PlatformDependent0.java:403 - java.nio.DirectByteBuffer.<init>(long, int): available
DEBUG [main] node1 2023-02-25 00:15:22,435 PlatformDependent.java:1079 - sun.misc.Unsafe: available
DEBUG [main] node1 2023-02-25 00:15:22,448 PlatformDependent.java:1181 - maxDirectMemory: 1056309248 bytes (maybe)
DEBUG [main] node1 2023-02-25 00:15:22,449 PlatformDependent.java:1200 - -Dio.netty.tmpdir: /home/cassandra/cassandra/tmp (java.io.tmpdir)
DEBUG [main] node1 2023-02-25 00:15:22,449 PlatformDependent.java:1279 - -Dio.netty.bitMode: 64 (sun.arch.data.model)
DEBUG [main] node1 2023-02-25 00:15:22,450 PlatformDependent.java:177 - -Dio.netty.maxDirectMemory: 1056309248 bytes
DEBUG [main] node1 2023-02-25 00:15:22,451 PlatformDependent.java:184 - -Dio.netty.uninitializedArrayAllocationThreshold: 1024
DEBUG [main] node1 2023-02-25 00:15:22,452 CleanerJava9.java:71 - java.nio.ByteBuffer.cleaner(): available
DEBUG [main] node1 2023-02-25 00:15:22,453 PlatformDependent.java:204 - -Dio.netty.noPreferDirect: false
DEBUG [main] node2 2023-02-25 00:15:23,126 InternalLoggerFactory.java:63 - Using SLF4J as the default logging framework
DEBUG [main] node2 2023-02-25 00:15:23,133 PlatformDependent0.java:417 - -Dio.netty.noUnsafe: false
DEBUG [main] node2 2023-02-25 00:15:23,134 PlatformDependent0.java:897 - Java version: 11
DEBUG [main] node2 2023-02-25 00:15:23,135 PlatformDependent0.java:130 - sun.misc.Unsafe.theUnsafe: available
DEBUG [main] node2 2023-02-25 00:15:23,136 PlatformDependent0.java:154 - sun.misc.Unsafe.copyMemory: available
DEBUG [main] node2 2023-02-25 00:15:23,136 PlatformDependent0.java:192 - java.nio.Buffer.address: available
DEBUG [main] node2 2023-02-25 00:15:23,137 PlatformDependent0.java:257 - direct buffer constructor: available
DEBUG [main] node2 2023-02-25 00:15:23,138 PlatformDependent0.java:331 - java.nio.Bits.unaligned: available, true
DEBUG [main] node2 2023-02-25 00:15:23,139 PlatformDependent0.java:393 - jdk.internal.misc.Unsafe.allocateUninitializedArray(int): available
DEBUG [main] node2 2023-02-25 00:15:23,140 PlatformDependent0.java:403 - java.nio.DirectByteBuffer.<init>(long, int): available
DEBUG [main] node2 2023-02-25 00:15:23,140 PlatformDependent.java:1079 - sun.misc.Unsafe: available
DEBUG [main] node2 2023-02-25 00:15:23,141 PlatformDependent.java:1181 - maxDirectMemory: 1056309248 bytes (maybe)
DEBUG [main] node2 2023-02-25 00:15:23,141 PlatformDependent.java:1200 - -Dio.netty.tmpdir: /home/cassandra/cassandra/tmp (java.io.tmpdir)
DEBUG [main] node2 2023-02-25 00:15:23,141 PlatformDependent.java:1279 - -Dio.netty.bitMode: 64 (sun.arch.data.model)
DEBUG [main] node2 2023-02-25 00:15:23,143 PlatformDependent.java:177 - -Dio.netty.maxDirectMemory: 1056309248 bytes
DEBUG [main] node2 2023-02-25 00:15:23,143 PlatformDependent.java:184 - -Dio.netty.uninitializedArrayAllocationThreshold: 1024
DEBUG [main] node2 2023-02-25 00:15:23,144 CleanerJava9.java:71 - java.nio.ByteBuffer.cleaner(): available
DEBUG [main] node2 2023-02-25 00:15:23,144 PlatformDependent.java:204 - -Dio.netty.noPreferDirect: false
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,251 DatabaseDescriptor.java:434 - Syncing log with batch mode
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,252 DatabaseDescriptor.java:466 - DiskAccessMode 'auto' determined to be mmap, indexAccessMode is mmap
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,256 DatabaseDescriptor.java:520 - Global memtable on-heap threshold is enabled at 10MiB
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,257 DatabaseDescriptor.java:524 - Global memtable off-heap threshold is enabled at 251MiB
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,257 DatabaseDescriptor.java:591 - Native transport rate-limiting disabled.
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,546 SimpleSeedProvider.java:99 - Only resolving one IP per DNS record - 127.0.0.1:7012 resolves to /127.0.0.1:7012
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,562 SSLFactory.java:251 - Initializing hot reloading SSLContext
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,620 CassandraDaemon.java:637 - Hostname: b849a9e1fa8a:7012:7001
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,621 CassandraDaemon.java:644 - JVM vendor/version: OpenJDK 64-Bit Server VM/11.0.17
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,623 CassandraDaemon.java:645 - Heap size: 77.500MiB/1007.375MiB
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,624 CassandraDaemon.java:650 - CodeHeap 'non-nmethods' Non-heap memory: init = 2555904(2496K) used = 1332480(1301K) committed = 2555904(2496K) max = 5828608(5692K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,632 CassandraDaemon.java:650 - Metaspace Non-heap memory: init = 0(0K) used = 36712928(35852K) committed = 38060032(37168K) max = -1(-1K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,632 CassandraDaemon.java:650 - CodeHeap 'profiled nmethods' Non-heap memory: init = 2555904(2496K) used = 5426048(5298K) committed = 5439488(5312K) max = 122912768(120032K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,632 CassandraDaemon.java:650 - Compressed Class Space Non-heap memory: init = 0(0K) used = 4344736(4242K) committed = 4849664(4736K) max = 1073741824(1048576K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,633 CassandraDaemon.java:650 - Par Eden Space Heap memory: init = 22413312(21888K) used = 22544384(22016K) committed = 22544384(22016K) max = 139591680(136320K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,633 CassandraDaemon.java:650 - Par Survivor Space Heap memory: init = 2752512(2688K) used = 2752512(2688K) committed = 2752512(2688K) max = 17432576(17024K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,633 CassandraDaemon.java:650 - CodeHeap 'non-profiled nmethods' Non-heap memory: init = 2555904(2496K) used = 1390464(1357K) committed = 2555904(2496K) max = 122916864(120036K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,633 CassandraDaemon.java:650 - CMS Old Gen Heap memory: init = 55967744(54656K) used = 17377336(16970K) committed = 55967744(54656K) max = 899284992(878208K)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,634 CassandraDaemon.java:652 - Classpath: /usr/share/ant/lib/ant-launcher.jar:/usr/share/ant/lib/ant-antlr.jar:/usr/share/ant/lib/ant-apache-bcel.jar:/usr/share/ant/lib/ant-apache-bsf.jar:/usr/share/ant/lib/ant-apache-log4j.jar:/usr/share/ant/lib/ant-apache-oro.jar:/usr/share/ant/lib/ant-apache-regexp.jar:/usr/share/ant/lib/ant-apache-resolver.jar:/usr/share/ant/lib/ant-apache-xalan2.jar:/usr/share/ant/lib/ant-commons-logging.jar:/usr/share/ant/lib/ant-commons-net.jar:/usr/share/ant/lib/ant-javamail.jar:/usr/share/ant/lib/ant-jdepend.jar:/usr/share/ant/lib/ant-jmf.jar:/usr/share/ant/lib/ant-jsch.jar:/usr/share/ant/lib/ant-junit.jar:/usr/share/ant/lib/ant-junit4.jar:/usr/share/ant/lib/ant-swing.jar:/usr/share/ant/lib/ant-testutil.jar:/usr/share/ant/lib/ant-xz.jar:/usr/share/ant/lib/ant.jar:/home/cassandra/cassandra/build/classes/stress:/home/cassandra/cassandra/build/classes/fqltool:/home/cassandra/cassandra/build/test/classes:/home/cassandra/cassandra/build/apache-cassandra-4.2-SNAPSHOT.jar:/home/cassandra/cassandra/build/lib/jars/HdrHistogram-2.1.9.jar:/home/cassandra/cassandra/build/lib/jars/ST4-4.0.8.jar:/home/cassandra/cassandra/build/lib/jars/agrona-1.17.1.jar:/home/cassandra/cassandra/build/lib/jars/airline-0.8.jar:/home/cassandra/cassandra/build/lib/jars/antlr-3.5.2.jar:/home/cassandra/cassandra/build/lib/jars/antlr-runtime-3.5.2.jar:/home/cassandra/cassandra/build/lib/jars/asm-9.3.jar:/home/cassandra/cassandra/build/lib/jars/assertj-core-3.15.0.jar:/home/cassandra/cassandra/build/lib/jars/big-math-2.3.0.jar:/home/cassandra/cassandra/build/lib/jars/byteman-4.0.20.jar:/home/cassandra/cassandra/build/lib/jars/byteman-bmunit-4.0.20.jar:/home/cassandra/cassandra/build/lib/jars/byteman-install-4.0.20.jar:/home/cassandra/cassandra/build/lib/jars/byteman-submit-4.0.20.jar:/home/cassandra/cassandra/build/lib/jars/caffeine-2.9.2.jar:/home/cassandra/cassandra/build/lib/jars/cassandra-driver-core-3.11.0-shaded.jar:/home/cassandra/cassandra/build/lib/jars/checker-qual-3.10.0.jar:/home/cassandra/cassandra/build/lib/jars/chronicle-bytes-2.20.111.jar:/home/cassandra/cassandra/build/lib/jars/chronicle-core-2.20.126.jar:/home/cassandra/cassandra/build/lib/jars/chronicle-queue-5.20.123.jar:/home/cassandra/cassandra/build/lib/jars/chronicle-threads-2.20.111.jar:/home/cassandra/cassandra/build/lib/jars/chronicle-wire-2.20.117.jar:/home/cassandra/cassandra/build/lib/jars/commons-beanutils-1.7.0.jar:/home/cassandra/cassandra/build/lib/jars/commons-beanutils-core-1.8.0.jar:/home/cassandra/cassandra/build/lib/jars/commons-cli-1.1.jar:/home/cassandra/cassandra/build/lib/jars/commons-codec-1.9.jar:/home/cassandra/cassandra/build/lib/jars/commons-collections-3.2.1.jar:/home/cassandra/cassandra/build/lib/jars/commons-configuration-1.6.jar:/home/cassandra/cassandra/build/lib/jars/commons-digester-1.8.jar:/home/cassandra/cassandra/build/lib/jars/commons-el-1.0.jar:/home/cassandra/cassandra/build/lib/jars/commons-httpclient-3.0.1.jar:/home/cassandra/cassandra/build/lib/jars/commons-lang3-3.11.jar:/home/cassandra/cassandra/build/lib/jars/commons-math-2.1.jar:/home/cassandra/cassandra/build/lib/jars/commons-math3-3.2.jar:/home/cassandra/cassandra/build/lib/jars/commons-net-1.4.1.jar:/home/cassandra/cassandra/build/lib/jars/compile-command-annotations-1.2.0.jar:/home/cassandra/cassandra/build/lib/jars/compress-lzf-0.8.4.jar:/home/cassandra/cassandra/build/lib/jars/concurrent-trees-2.4.0.jar:/home/cassandra/cassandra/build/lib/jars/ecj-4.6.1.jar:/home/cassandra/cassandra/build/lib/jars/error_prone_annotations-2.5.1.jar:/home/cassandra/cassandra/build/lib/jars/ftplet-api-1.0.0.jar:/home/cassandra/cassandra/build/lib/jars/ftpserver-core-1.0.0.jar:/home/cassandra/cassandra/build/lib/jars/ftpserver-deprecated-1.0.0-M2.jar:/home/cassandra/cassandra/build/lib/jars/guava-27.0-jre.jar:/home/cassandra/cassandra/build/lib/jars/hadoop-core-1.0.3.jar:/home/cassandra/cassandra/build/lib/jars/hadoop-minicluster-1.0.3.jar:/home/cassandra/cassandra/build/lib/jars/hadoop-test-1.0.3.jar:/home/cassandra/cassandra/build/lib/jars/high-scale-lib-1.0.6.jar:/home/cassandra/cassandra/build/lib/jars/hppc-0.8.1.jar:/home/cassandra/cassandra/build/lib/jars/hsqldb-1.8.0.10.jar:/home/cassandra/cassandra/build/lib/jars/ipaddress-5.3.3.jar:/home/cassandra/cassandra/build/lib/jars/j2objc-annotations-1.3.jar:/home/cassandra/cassandra/build/lib/jars/jackson-annotations-2.13.2.jar:/home/cassandra/cassandra/build/lib/jars/jackson-core-2.13.2.jar:/home/cassandra/cassandra/build/lib/jars/jackson-databind-2.13.2.2.jar:/home/cassandra/cassandra/build/lib/jars/jackson-datatype-jsr310-2.13.2.jar:/home/cassandra/cassandra/build/lib/jars/jacocoagent.jar:/home/cassandra/cassandra/build/lib/jars/jamm-0.3.2.jar:/home/cassandra/cassandra/build/lib/jars/jasper-compiler-5.5.12.jar:/home/cassandra/cassandra/build/lib/jars/jasper-runtime-5.5.12.jar:/home/cassandra/cassandra/build/lib/jars/java-cup-runtime-11b-20160615.jar:/home/cassandra/cassandra/build/lib/jars/javax.inject-1.jar:/home/cassandra/cassandra/build/lib/jars/jbcrypt-0.4.jar:/home/cassandra/cassandra/build/lib/jars/jcl-over-slf4j-1.7.25.jar:/home/cassandra/cassandra/build/lib/jars/jcommander-1.30.jar:/home/cassandra/cassandra/build/lib/jars/jctools-core-3.1.0.jar:/home/cassandra/cassandra/build/lib/jars/jersey-core-1.0.jar:/home/cassandra/cassandra/build/lib/jars/jersey-server-1.0.jar:/home/cassandra/cassandra/build/lib/jars/jets3t-0.7.1.jar:/home/cassandra/cassandra/build/lib/jars/jetty-6.1.26.jar:/home/cassandra/cassandra/build/lib/jars/jetty-util-6.1.26.jar:/home/cassandra/cassandra/build/lib/jars/jflex-1.8.2.jar:/home/cassandra/cassandra/build/lib/jars/jna-5.13.0.jar:/home/cassandra/cassandra/build/lib/jars/json-simple-1.1.jar:/home/cassandra/cassandra/build/lib/jars/jsp-2.1-6.1.14.jar:/home/cassandra/cassandra/build/lib/jars/jsp-api-2.1-6.1.14.jar:/home/cassandra/cassandra/build/lib/jars/jsr305-2.0.2.jar:/home/cassandra/cassandra/build/lib/jars/jsr311-api-1.0.jar:/home/cassandra/cassandra/build/lib/jars/jvm-attach-api-1.5.jar:/home/cassandra/cassandra/build/lib/jars/kfs-0.3.jar:/home/cassandra/cassandra/build/lib/jars/log4j-over-slf4j-1.7.25.jar:/home/cassandra/cassandra/build/lib/jars/logback-classic-1.2.9.jar:/home/cassandra/cassandra/build/lib/jars/logback-core-1.2.9.jar:/home/cassandra/cassandra/build/lib/jars/lz4-java-1.8.0.jar:/home/cassandra/cassandra/build/lib/jars/metrics-core-3.1.5.jar:/home/cassandra/cassandra/build/lib/jars/metrics-jvm-3.1.5.jar:/home/cassandra/cassandra/build/lib/jars/metrics-logback-3.1.5.jar:/home/cassandra/cassandra/build/lib/jars/mina-core-2.0.0-M5.jar:/home/cassandra/cassandra/build/lib/jars/mxdump-0.14.jar:/home/cassandra/cassandra/build/lib/jars/netty-all-4.1.58.Final.jar:/home/cassandra/cassandra/build/lib/jars/netty-tcnative-boringssl-static-2.0.36.Final.jar:/home/cassandra/cassandra/build/lib/jars/ohc-core-0.5.1.jar:/home/cassandra/cassandra/build/lib/jars/ohc-core-j8-0.5.1.jar:/home/cassandra/cassandra/build/lib/jars/oro-2.0.8.jar:/home/cassandra/cassandra/build/lib/jars/psjava-0.1.19.jar:/home/cassandra/cassandra/build/lib/jars/reporter-config-base-3.0.3.jar:/home/cassandra/cassandra/build/lib/jars/reporter-config3-3.0.3.jar:/home/cassandra/cassandra/build/lib/jars/servlet-api-2.5-6.1.14.jar:/home/cassandra/cassandra/build/lib/jars/sigar-1.6.4.jar:/home/cassandra/cassandra/build/lib/jars/sjk-cli-0.14.jar:/home/cassandra/cassandra/build/lib/jars/sjk-core-0.14.jar:/home/cassandra/cassandra/build/lib/jars/sjk-json-0.14.jar:/home/cassandra/cassandra/build/lib/jars/sjk-stacktrace-0.14.jar:/home/cassandra/cassandra/build/lib/jars/slf4j-api-1.7.25.jar:/home/cassandra/cassandra/build/lib/jars/snakeyaml-1.26.jar:/home/cassandra/cassandra/build/lib/jars/snappy-java-1.1.8.4.jar:/home/cassandra/cassandra/build/lib/jars/snowball-stemmer-1.3.0.581.1.jar:/home/cassandra/cassandra/build/lib/jars/stream-2.5.2.jar:/home/cassandra/cassandra/build/lib/jars/xmlenc-0.52.jar:/home/cassandra/cassandra/build/lib/jars/zstd-jni-1.5.4-1.jar:/home/cassandra/cassandra/build/test/lib/jars/Saxon-HE-10.3.jar:/home/cassandra/cassandra/build/test/lib/jars/antlr-2.7.7.jar:/home/cassandra/cassandra/build/test/lib/jars/antlr4-runtime-4.9.1.jar:/home/cassandra/cassandra/build/test/lib/jars/asm-9.3.jar:/home/cassandra/cassandra/build/test/lib/jars/asm-analysis-9.3.jar:/home/cassandra/cassandra/build/test/lib/jars/asm-commons-9.3.jar:/home/cassandra/cassandra/build/test/lib/jars/asm-tree-9.3.jar:/home/cassandra/cassandra/build/test/lib/jars/asm-util-9.3.jar:/home/cassandra/cassandra/build/test/lib/jars/asm-xml-6.0.jar:/home/cassandra/cassandra/build/test/lib/jars/awaitility-4.0.3.jar:/home/cassandra/cassandra/build/test/lib/jars/byte-buddy-1.12.13.jar:/home/cassandra/cassandra/build/test/lib/jars/byte-buddy-agent-1.12.13.jar:/home/cassandra/cassandra/build/test/lib/jars/checkstyle-8.40.jar:/home/cassandra/cassandra/build/test/lib/jars/commons-beanutils-1.9.4.jar:/home/cassandra/cassandra/build/test/lib/jars/commons-collections-3.2.2.jar:/home/cassandra/cassandra/build/test/lib/jars/commons-io-2.6.jar:/home/cassandra/cassandra/build/test/lib/jars/commons-logging-1.2.jar:/home/cassandra/cassandra/build/test/lib/jars/commons-math3-3.2.jar:/home/cassandra/cassandra/build/test/lib/jars/dtest-api-0.0.13.jar:/home/cassandra/cassandra/build/test/lib/jars/guava-18.0.jar:/home/cassandra/cassandra/build/test/lib/jars/hamcrest-2.2.jar:/home/cassandra/cassandra/build/test/lib/jars/harry-core-0.0.1.jar:/home/cassandra/cassandra/build/test/lib/jars/jackson-annotations-2.11.3.jar:/home/cassandra/cassandra/build/test/lib/jars/jackson-core-2.13.2.jar:/home/cassandra/cassandra/build/test/lib/jars/jackson-databind-2.11.3.jar:/home/cassandra/cassandra/build/test/lib/jars/jackson-dataformat-yaml-2.13.2.jar:/home/cassandra/cassandra/build/test/lib/jars/java-allocation-instrumenter-3.1.0.jar:/home/cassandra/cassandra/build/test/lib/jars/javassist-3.28.0-GA.jar:/home/cassandra/cassandra/build/test/lib/jars/jimfs-1.1.jar:/home/cassandra/cassandra/build/test/lib/jars/jmh-core-1.21.jar:/home/cassandra/cassandra/build/test/lib/jars/jmh-generator-annprocess-1.21.jar:/home/cassandra/cassandra/build/test/lib/jars/jopt-simple-4.6.jar:/home/cassandra/cassandra/build/test/lib/jars/jsr305-3.0.2.jar:/home/cassandra/cassandra/build/test/lib/jars/junit-4.12.jar:/home/cassandra/cassandra/build/test/lib/jars/mockito-core-4.7.0.jar:/home/cassandra/cassandra/build/test/lib/jars/mockito-inline-4.7.0.jar:/home/cassandra/cassandra/build/test/lib/jars/objenesis-3.2.jar:/home/cassandra/cassandra/build/test/lib/jars/org.jacoco.agent-0.8.8.jar:/home/cassandra/cassandra/build/test/lib/jars/org.jacoco.ant-0.8.8.jar:/home/cassandra/cassandra/build/test/lib/jars/org.jacoco.core-0.8.8.jar:/home/cassandra/cassandra/build/test/lib/jars/org.jacoco.report-0.8.8.jar:/home/cassandra/cassandra/build/test/lib/jars/picocli-4.6.1.jar:/home/cassandra/cassandra/build/test/lib/jars/quicktheories-0.26.jar:/home/cassandra/cassandra/build/test/lib/jars/reflections-0.10.2.jar:/home/cassandra/cassandra/build/test/lib/jars/semver4j-3.1.0.jar:/home/cassandra/cassandra/build/test/lib/jars/simulator-asm.jar:/home/cassandra/cassandra/build/test/lib/jars/simulator-bootstrap.jar:/home/cassandra/cassandra/build/test/lib/jars/slf4j-api-1.7.32.jar:/home/cassandra/cassandra/build/test/stress-classes:/home/cassandra/cassandra/build/test/fqltool-classes:/home/cassandra/cassandra/test/conf:/home/cassandra/cassandra/build/classes/main:/usr/share/java/ant-launcher-1.10.7.jar
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,634 CassandraDaemon.java:654 - JVM Arguments: [-Dstorage-config=/home/cassandra/cassandra/test/conf, -Djava.awt.headless=true, -javaagent:/home/cassandra/cassandra/lib/jamm-0.3.2.jar, -ea, -Djava.io.tmpdir=/home/cassandra/cassandra/tmp, -Dcassandra.debugrefcount=true, -Xss384k, -XX:SoftRefLRUPolicyMSPerMB=0, -XX:ActiveProcessorCount=2, -XX:HeapDumpPath=build/test, -Dcassandra.test.driver.connection_timeout_ms=10000, -Dcassandra.test.driver.read_timeout_ms=24000, -Dcassandra.memtable_row_overhead_computation_step=100, -Dcassandra.test.use_prepared=true, -Dcassandra.test.sstableformatdevelopment=true, -Djava.security.egd=file:/dev/urandom, -Dcassandra.testtag=.jdk11, -Dcassandra.keepBriefBrief=${cassandra.keepBriefBrief}, -Dcassandra.strict.runtime.checks=true, -Dcassandra.reads.thresholds.coordinator.defensive_checks_enabled=true, -Dcassandra.test.flush_local_schema_changes=false, -Dcassandra.test.messagingService.nonGracefulShutdown=true, -Dcassandra.use_nix_recursive_delete=true, -Djdk.attach.allowAttachSelf=true, -XX:+UseConcMarkSweepGC, -XX:+CMSParallelRemarkEnabled, -XX:SurvivorRatio=8, -XX:MaxTenuringThreshold=1, -XX:CMSInitiatingOccupancyFraction=75, -XX:+UseCMSInitiatingOccupancyOnly, -XX:CMSWaitDuration=10000, -XX:+CMSParallelInitialMarkEnabled, -XX:+CMSEdenChunksRecordAlways, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/jdk.internal.ref=ALL-UNNAMED, --add-exports=java.base/sun.nio.ch=ALL-UNNAMED, --add-exports=java.management.rmi/com.sun.jmx.remote.internal.rmi=ALL-UNNAMED, --add-exports=java.rmi/sun.rmi.registry=ALL-UNNAMED, --add-exports=java.rmi/sun.rmi.server=ALL-UNNAMED, --add-exports=java.sql/java.sql=ALL-UNNAMED, --add-opens=java.base/java.lang.module=ALL-UNNAMED, --add-opens=java.base/java.net=ALL-UNNAMED, --add-opens=java.base/jdk.internal.loader=ALL-UNNAMED, --add-opens=java.base/jdk.internal.ref=ALL-UNNAMED, --add-opens=java.base/jdk.internal.reflect=ALL-UNNAMED, --add-opens=java.base/jdk.internal.math=ALL-UNNAMED, --add-opens=java.base/jdk.internal.module=ALL-UNNAMED, --add-opens=java.base/jdk.internal.util.jar=ALL-UNNAMED, --add-opens=jdk.management/com.sun.management.internal=ALL-UNNAMED, -DQT_SHRINKS=0, -XX:-CMSClassUnloadingEnabled, -Dio.netty.tryReflectionSetAccessible=true, -Dlegacy-sstable-root=/home/cassandra/cassandra/test/data/legacy-sstables, -Dinvalid-legacy-sstable-root=/home/cassandra/cassandra/test/data/invalid-legacy-sstables, -Dcassandra.ring_delay_ms=1000, -Dcassandra.tolerate_sstable_size=true, -Dcassandra.skip_sync=true, -Xmx1024m]
INFO  [node1_ScheduledTasks:1] node1 2023-02-25 00:15:23,662 StorageService.java:195 - Overriding RING_DELAY to 15000ms
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,666 MonotonicClock.java:208 - Scheduling approximate time conversion task with an interval of 10000 milliseconds
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,667 MonotonicClock.java:344 - Scheduling approximate time-check task with a precision of 2 milliseconds
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,708 InternalThreadLocalMap.java:83 - -Dio.netty.threadLocalMap.stringBuilder.initialSize: 1024
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,708 InternalThreadLocalMap.java:86 - -Dio.netty.threadLocalMap.stringBuilder.maxSize: 4096
WARN  00:15:23 jemalloc shared library could not be preloaded to speed up memory allocations
WARN  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,815 StartupChecks.java:200 - jemalloc shared library could not be preloaded to speed up memory allocations
WARN  00:15:23 JMX is not enabled to receive remote connections. Please see cassandra-env.sh for more info.
WARN  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,824 StartupChecks.java:258 - JMX is not enabled to receive remote connections. Please see cassandra-env.sh for more info.
WARN  00:15:23 The JVM is not configured to stop on OutOfMemoryError which can cause data corruption. Use one of the following JVM options to configure the behavior on OutOfMemoryError:  -XX:+ExitOnOutOfMemoryError, -XX:+CrashOnOutOfMemoryError, or -XX:OnOutOfMemoryError="<cmd args>;<cmd args>"
WARN  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,825 StartupChecks.java:315 - The JVM is not configured to stop on OutOfMemoryError which can cause data corruption. Use one of the following JVM options to configure the behavior on OutOfMemoryError:  -XX:+ExitOnOutOfMemoryError, -XX:+CrashOnOutOfMemoryError, or -XX:OnOutOfMemoryError="<cmd args>;<cmd args>"
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,827 SigarLibrary.java:46 - Initializing SIGAR library
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,841 SigarLog.java:60 - no libsigar-amd64-linux.so in java.library.path: [/usr/java/packages/lib, /usr/lib/x86_64-linux-gnu/jni, /lib/x86_64-linux-gnu, /usr/lib/x86_64-linux-gnu, /usr/lib/jni, /lib, /usr/lib]
org.hyperic.sigar.SigarException: no libsigar-amd64-linux.so in java.library.path: [/usr/java/packages/lib, /usr/lib/x86_64-linux-gnu/jni, /lib/x86_64-linux-gnu, /usr/lib/x86_64-linux-gnu, /usr/lib/jni, /lib, /usr/lib]
	at org.hyperic.sigar.Sigar.loadLibrary(Sigar.java:172)
	at org.hyperic.sigar.Sigar.<clinit>(Sigar.java:100)
	at org.apache.cassandra.utils.SigarLibrary.<init>(SigarLibrary.java:49)
	at org.apache.cassandra.utils.SigarLibrary.<clinit>(SigarLibrary.java:30)
	at org.apache.cassandra.service.StartupChecks$8.execute(StartupChecks.java:367)
	at org.apache.cassandra.service.StartupChecks.verify(StartupChecks.java:175)
	at org.apache.cassandra.service.CassandraDaemon.runStartupChecks(CassandraDaemon.java:494)
	at org.apache.cassandra.distributed.impl.Instance.lambda$startup$12(Instance.java:602)
	at org.apache.cassandra.concurrent.FutureTask$1.call(FutureTask.java:96)
	at org.apache.cassandra.concurrent.FutureTask.call(FutureTask.java:61)
	at org.apache.cassandra.concurrent.FutureTask.run(FutureTask.java:71)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
	at java.base/java.lang.Thread.run(Thread.java:829)
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,843 SigarLibrary.java:59 - Could not initialize SIGAR library 'org.hyperic.sigar.FileSystem[] org.hyperic.sigar.Sigar.getFileSystemListNative()' 
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,843 SigarLibrary.java:183 - Sigar could not be initialized, test for checking degraded mode omitted.
WARN  00:15:23 Maximum number of memory map areas per process (vm.max_map_count) 65530 is too low, recommended value: 1048575, you can change it with sysctl.
WARN  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,844 StartupChecks.java:493 - Maximum number of memory map areas per process (vm.max_map_count) 65530 is too low, recommended value: 1048575, you can change it with sysctl.
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,846 StartupChecks.java:425 - No 'read_ahead_kb' setting found for device overlay of data directory /home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data2.
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,855 StartupChecks.java:513 - Checking directory /home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,860 StartupChecks.java:513 - Checking directory /home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data1
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,861 StartupChecks.java:513 - Checking directory /home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,861 StartupChecks.java:513 - Checking directory /home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/commitlog
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,861 StartupChecks.java:513 - Checking directory /home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/saved_caches
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:23,862 StartupChecks.java:513 - Checking directory /home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/hints
WARN  00:15:24 epoll not available
java.lang.UnsupportedOperationException: Native transport was explicit disabled with -Dio.netty.transport.noNative=true
	at io.netty.channel.epoll.Epoll.<clinit>(Epoll.java:33)
	at org.apache.cassandra.service.NativeTransportService.useEpoll(NativeTransportService.java:165)
	at org.apache.cassandra.net.SocketFactory$Provider.optimalProvider(SocketFactory.java:164)
	at org.apache.cassandra.net.SocketFactory.<init>(SocketFactory.java:185)
	at org.apache.cassandra.net.MessagingService.<init>(MessagingService.java:261)
	at org.apache.cassandra.net.MessagingService.<init>(MessagingService.java:288)
	at org.apache.cassandra.net.MessagingService$MSHandle.<clinit>(MessagingService.java:253)
	at org.apache.cassandra.net.MessagingService.instance(MessagingService.java:258)
	at org.apache.cassandra.schema.DefaultSchemaUpdateHandler.<init>(DefaultSchemaUpdateHandler.java:81)
	at org.apache.cassandra.schema.DefaultSchemaUpdateHandlerFactory.getSchemaUpdateHandler(DefaultSchemaUpdateHandlerFactory.java:33)
	at org.apache.cassandra.schema.Schema.<init>(Schema.java:114)
	at org.apache.cassandra.schema.Schema.<clinit>(Schema.java:78)
	at org.apache.cassandra.service.StartupChecks$13.execute(StartupChecks.java:660)
	at org.apache.cassandra.service.StartupChecks.verify(StartupChecks.java:175)
	at org.apache.cassandra.service.CassandraDaemon.runStartupChecks(CassandraDaemon.java:494)
	at org.apache.cassandra.distributed.impl.Instance.lambda$startup$12(Instance.java:602)
	at org.apache.cassandra.concurrent.FutureTask$1.call(FutureTask.java:96)
	at org.apache.cassandra.concurrent.FutureTask.call(FutureTask.java:61)
	at org.apache.cassandra.concurrent.FutureTask.run(FutureTask.java:71)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
	at java.base/java.lang.Thread.run(Thread.java:829)
WARN  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,398 NativeTransportService.java:166 - epoll not available
java.lang.UnsupportedOperationException: Native transport was explicit disabled with -Dio.netty.transport.noNative=true
	at io.netty.channel.epoll.Epoll.<clinit>(Epoll.java:33)
	at org.apache.cassandra.service.NativeTransportService.useEpoll(NativeTransportService.java:165)
	at org.apache.cassandra.net.SocketFactory$Provider.optimalProvider(SocketFactory.java:164)
	at org.apache.cassandra.net.SocketFactory.<init>(SocketFactory.java:185)
	at org.apache.cassandra.net.MessagingService.<init>(MessagingService.java:261)
	at org.apache.cassandra.net.MessagingService.<init>(MessagingService.java:288)
	at org.apache.cassandra.net.MessagingService$MSHandle.<clinit>(MessagingService.java:253)
	at org.apache.cassandra.net.MessagingService.instance(MessagingService.java:258)
	at org.apache.cassandra.schema.DefaultSchemaUpdateHandler.<init>(DefaultSchemaUpdateHandler.java:81)
	at org.apache.cassandra.schema.DefaultSchemaUpdateHandlerFactory.getSchemaUpdateHandler(DefaultSchemaUpdateHandlerFactory.java:33)
	at org.apache.cassandra.schema.Schema.<init>(Schema.java:114)
	at org.apache.cassandra.schema.Schema.<clinit>(Schema.java:78)
	at org.apache.cassandra.service.StartupChecks$13.execute(StartupChecks.java:660)
	at org.apache.cassandra.service.StartupChecks.verify(StartupChecks.java:175)
	at org.apache.cassandra.service.CassandraDaemon.runStartupChecks(CassandraDaemon.java:494)
	at org.apache.cassandra.distributed.impl.Instance.lambda$startup$12(Instance.java:602)
	at org.apache.cassandra.concurrent.FutureTask$1.call(FutureTask.java:96)
	at org.apache.cassandra.concurrent.FutureTask.call(FutureTask.java:61)
	at org.apache.cassandra.concurrent.FutureTask.run(FutureTask.java:71)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
	at java.base/java.lang.Thread.run(Thread.java:829)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,398 SocketFactory.java:154 - using netty NIO event loop for pool prefix Messaging-AcceptLoop
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,405 MultithreadEventLoopGroup.java:44 - -Dio.netty.eventLoopThreads: 4
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,436 NioEventLoop.java:106 - -Dio.netty.noKeySetOptimization: false
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,436 NioEventLoop.java:107 - -Dio.netty.selectorAutoRebuildThreshold: 512
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,444 SocketFactory.java:154 - using netty NIO event loop for pool prefix node1_Messaging-EventLoop
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,445 SocketFactory.java:154 - using netty NIO event loop for pool prefix Streaming-EventLoop
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,591 Keyspace.java:367 - Creating replication strategy system params KeyspaceParams{durable_writes=true, replication=ReplicationParams{class=org.apache.cassandra.locator.LocalStrategy}}
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,602 Keyspace.java:371 - New replication settings for keyspace system - invalidating disk boundary caches
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,632 ColumnFamilyStore.java:489 - Initializing system.IndexInfo
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,637 AbstractAllocatorMemtable.java:91 - Memtables allocating with on-heap slabs
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,715 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.IndexInfo
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,724 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,726 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.IndexInfo
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,788 ColumnFamilyStore.java:489 - Initializing system.batches
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,796 ColumnFamilyStore.java:489 - Initializing system.paxos
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,797 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.paxos
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,797 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,797 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data1}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data2}], positions=null, ringVersion=0, directoriesVersion=0} for system.paxos
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,806 SecondaryIndexManager.java:210 - Index [PaxosUncommittedIndex] registered and writable.
INFO  [node1_SecondaryIndexManagement:1] node1 2023-02-25 00:15:24,817 SecondaryIndexManager.java:665 - Index [PaxosUncommittedIndex] became queryable after successful build.
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,818 ColumnFamilyStore.java:489 - Initializing system.paxos_repair_history
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,818 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.paxos_repair_history
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,819 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,819 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.paxos_repair_history
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,824 ColumnFamilyStore.java:489 - Initializing system.local
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,824 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.local
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,825 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,825 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.local
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,830 ColumnFamilyStore.java:489 - Initializing system.peers_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,831 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.peers_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,831 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,831 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.peers_v2
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,835 ColumnFamilyStore.java:489 - Initializing system.peers
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,836 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.peers
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,836 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,836 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.peers
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,840 ColumnFamilyStore.java:489 - Initializing system.peer_events_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,841 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.peer_events_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,841 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,841 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.peer_events_v2
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,846 ColumnFamilyStore.java:489 - Initializing system.peer_events
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,846 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.peer_events
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,847 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,847 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.peer_events
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,853 ColumnFamilyStore.java:489 - Initializing system.compaction_history
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,853 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.compaction_history
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,854 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,854 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data1}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data2}], positions=null, ringVersion=0, directoriesVersion=0} for system.compaction_history
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,859 ColumnFamilyStore.java:489 - Initializing system.sstable_activity
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,860 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.sstable_activity
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,860 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,860 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.sstable_activity
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,866 ColumnFamilyStore.java:489 - Initializing system.sstable_activity_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,867 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.sstable_activity_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,867 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,867 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.sstable_activity_v2
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,884 ColumnFamilyStore.java:489 - Initializing system.size_estimates
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,885 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.size_estimates
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,885 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,885 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.size_estimates
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,890 ColumnFamilyStore.java:489 - Initializing system.table_estimates
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,890 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.table_estimates
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,890 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,891 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.table_estimates
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,895 ColumnFamilyStore.java:489 - Initializing system.available_ranges_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,896 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.available_ranges_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,896 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,896 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.available_ranges_v2
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,900 ColumnFamilyStore.java:489 - Initializing system.available_ranges
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,900 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.available_ranges
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,901 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,901 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.available_ranges
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,904 ColumnFamilyStore.java:489 - Initializing system.transferred_ranges_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,904 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.transferred_ranges_v2
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,904 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,904 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.transferred_ranges_v2
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,907 ColumnFamilyStore.java:489 - Initializing system.transferred_ranges
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,908 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.transferred_ranges
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,908 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,908 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.transferred_ranges
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,911 ColumnFamilyStore.java:489 - Initializing system.view_builds_in_progress
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,912 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.view_builds_in_progress
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,912 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,912 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.view_builds_in_progress
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,915 ColumnFamilyStore.java:489 - Initializing system.built_views
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,916 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.built_views
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,916 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,916 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}], positions=null, ringVersion=0, directoriesVersion=0} for system.built_views
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,920 ColumnFamilyStore.java:489 - Initializing system.prepared_statements
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,921 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.prepared_statements
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,921 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,921 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data1}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data2}], positions=null, ringVersion=0, directoriesVersion=0} for system.prepared_statements
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,926 ColumnFamilyStore.java:489 - Initializing system.repairs
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,927 DiskBoundaryManager.java:54 - Refreshing disk boundary cache for system.repairs
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,927 DiskBoundaryManager.java:93 - Got local ranges [] (ringVersion = 0)
DEBUG [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,927 DiskBoundaryManager.java:57 - Updating boundaries from null to DiskBoundaries{directories=[DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data0}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data1}, DataDirectory{location=/home/cassandra/cassandra/tmp/dtests8095700069672955140/node1/data2}], positions=null, ringVersion=0, directoriesVersion=0} for system.repairs
INFO  [node1_isolatedExecutor:1] node1 2023-02-25 00:15:24,931 ColumnFamilyStore.java:489 - Initializing system.top_partitions
DEBUG [node1_isolatedExecu
...[truncated 4094032 chars]...
erkle trees with merkle trees size 2, 387 partitions, 180 bytes
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,466 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl2, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,470 Validator.java:221 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -     [0..0]: 463
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -     [1..2]: 50
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -     [0..0]: 210
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -     [1..2]: 46
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 Validator.java:223 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -     [0..204]: 466
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -   [205..939]: 10
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -  [940..1674]: 4
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 - [1675..2409]: 12
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 - [2410..5226]: 21
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,471 Validator.java:221 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -     [0..378]: 213
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,471 EstimatedHistogram.java:337 -  [379..1316]: 11
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,472 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,472 EstimatedHistogram.java:337 - [1317..2254]: 9
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,472 EstimatedHistogram.java:337 - [2255..3192]: 13
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,472 EstimatedHistogram.java:337 -     [0..0]: 256
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,472 EstimatedHistogram.java:337 - [3193..4146]: 10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,472 Validator.java:223 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,472 EstimatedHistogram.java:337 -     [0..0]: 513
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,472 Validator.java:254 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Local completed merkle tree for /127.0.0.1:7012 for distributed_test_keyspace.tbl10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,472 EstimatedHistogram.java:337 -     [0..0]: 256
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,472 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,472 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl2
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,472 ValidationManager.java:152 - Validation of 99 partitions (~82.471KiB) finished in 21 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl10, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,472 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,472 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl10 from /127.0.0.1:7012
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,472 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,473 ValidationManager.java:152 - Validation of 0 partitions (~156.163KiB) finished in 12 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl2, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,473 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
INFO  [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,473 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl5
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,473 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl2 from /127.0.0.2:7012
INFO  [node1_RepairJobTask:5] node1 2023-02-25 00:21:15,474 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 1ms)
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,474 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,474 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl5, [(-1,9223372036854775805], (9223372036854775805,-1]]]
INFO  [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,474 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl4
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,475 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,475 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl4, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 Validator.java:221 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 EstimatedHistogram.java:337 -     [0..0]: 463
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 EstimatedHistogram.java:337 -     [1..2]: 50
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 EstimatedHistogram.java:337 -     [0..0]: 210
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 EstimatedHistogram.java:337 -     [1..2]: 46
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 Validator.java:223 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 EstimatedHistogram.java:337 -     [0..204]: 466
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 EstimatedHistogram.java:337 -   [205..939]: 10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,485 EstimatedHistogram.java:337 -  [940..1674]: 4
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 EstimatedHistogram.java:337 - [1675..2409]: 12
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 EstimatedHistogram.java:337 - [2410..5226]: 21
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 EstimatedHistogram.java:337 -     [0..378]: 213
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 EstimatedHistogram.java:337 -  [379..1316]: 11
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 EstimatedHistogram.java:337 - [1317..2254]: 9
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 EstimatedHistogram.java:337 - [2255..3192]: 13
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 EstimatedHistogram.java:337 - [3193..4146]: 10
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,486 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl5
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,486 ValidationManager.java:152 - Validation of 99 partitions (~83.662KiB) finished in 13 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl5, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,487 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
INFO  [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,487 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl3
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,487 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,487 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl5 from /127.0.0.2:7012
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,487 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
INFO  [node1_RepairJobTask:7] node1 2023-02-25 00:21:15,488 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 1ms)
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,488 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl3, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,492 Validator.java:221 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,492 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,492 EstimatedHistogram.java:337 -     [0..0]: 256
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,492 Validator.java:223 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,492 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,492 EstimatedHistogram.java:337 -     [0..0]: 256
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,492 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl3
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,493 ValidationManager.java:152 - Validation of 0 partitions (~64.395KiB) finished in 6 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl3, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,493 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
INFO  [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,493 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl8
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,493 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,494 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,494 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl3 from /127.0.0.2:7012
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,494 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl8, [(-1,9223372036854775805], (9223372036854775805,-1]]]
INFO  [node1_RepairJobTask:5] node1 2023-02-25 00:21:15,494 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 0ms)
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,499 Validator.java:221 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,499 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,499 EstimatedHistogram.java:337 -     [0..0]: 256
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,499 Validator.java:223 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,500 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,500 EstimatedHistogram.java:337 -     [0..0]: 256
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,500 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl8
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,500 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,501 ValidationManager.java:152 - Validation of 0 partitions (~64.158KiB) finished in 7 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl8, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,501 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,501 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl8 from /127.0.0.2:7012
INFO  [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,501 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,502 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,502 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl10, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,502 Validator.java:221 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 -     [0..0]: 463
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 -     [1..2]: 50
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 -     [0..0]: 210
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 -     [1..2]: 46
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 Validator.java:223 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 -     [0..315]: 467
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 -  [316..1458]: 10
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 - [1459..2601]: 3
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 - [2602..3744]: 12
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,503 EstimatedHistogram.java:337 - [3745..8113]: 21
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,504 EstimatedHistogram.java:337 -     [0..584]: 214
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,504 EstimatedHistogram.java:337 -  [585..2041]: 10
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,504 EstimatedHistogram.java:337 - [2042..3498]: 9
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,504 EstimatedHistogram.java:337 - [3499..4955]: 13
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,504 EstimatedHistogram.java:337 - [4956..6412]: 10
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,504 Validator.java:254 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Local completed merkle tree for /127.0.0.1:7012 for distributed_test_keyspace.tbl4
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,504 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,504 ValidationManager.java:152 - Validation of 99 partitions (~149.472KiB) finished in 32 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl4, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,504 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,504 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl4 from /127.0.0.1:7012
INFO  [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,506 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 0 sstables in distributed_test_keyspace.tbl13
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,506 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 0 partitions, 180 bytes
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,506 Validator.java:150 - Prepared AEService trees of size 2 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl13, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,506 Validator.java:221 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,507 EstimatedHistogram.java:337 -     [0..0]: 1
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,507 EstimatedHistogram.java:337 -     [0..0]: 1
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,507 Validator.java:223 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,507 EstimatedHistogram.java:337 -     [0..0]: 1
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,507 EstimatedHistogram.java:337 -     [0..0]: 1
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,507 Validator.java:254 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Local completed merkle tree for /127.0.0.1:7012 for distributed_test_keyspace.tbl13
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,507 MerkleTree.java:741 - Allocating direct buffer of size 114 for an off-heap merkle tree
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,507 ValidationManager.java:152 - Validation of 0 partitions (~0.000KiB) finished in 2 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl13, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,507 MerkleTree.java:741 - Allocating direct buffer of size 114 for an off-heap merkle tree
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,507 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl13 from /127.0.0.1:7012
INFO  [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,509 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 0 sstables in distributed_test_keyspace.tbl8
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 0 partitions, 180 bytes
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 Validator.java:150 - Prepared AEService trees of size 2 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl8, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 Validator.java:221 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 EstimatedHistogram.java:337 -     [0..0]: 1
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 EstimatedHistogram.java:337 -     [0..0]: 1
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 Validator.java:223 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 EstimatedHistogram.java:337 -     [0..0]: 1
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 EstimatedHistogram.java:337 -     [0..0]: 1
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,510 Validator.java:254 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Local completed merkle tree for /127.0.0.1:7012 for distributed_test_keyspace.tbl8
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,510 MerkleTree.java:741 - Allocating direct buffer of size 114 for an off-heap merkle tree
DEBUG [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,510 MerkleTree.java:741 - Allocating direct buffer of size 114 for an off-heap merkle tree
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,510 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl8 from /127.0.0.1:7012
DEBUG [node1_ValidationExecutor:1] node1 2023-02-25 00:21:15,510 ValidationManager.java:152 - Validation of 0 partitions (~0.000KiB) finished in 3 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl8, [(-1,9223372036854775805], (9223372036854775805,-1]]]
INFO  [node1_RepairJobTask:7] node1 2023-02-25 00:21:15,510 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 0ms)
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,519 Validator.java:221 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -     [0..0]: 463
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -     [1..2]: 50
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -     [0..0]: 210
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -     [1..2]: 46
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 Validator.java:223 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -     [0..204]: 466
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -   [205..939]: 10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -  [940..1674]: 4
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 - [1675..2409]: 12
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 - [2410..5226]: 21
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -     [0..378]: 213
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 -  [379..1316]: 11
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 - [1317..2254]: 9
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 - [2255..3192]: 13
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,520 EstimatedHistogram.java:337 - [3193..4146]: 10
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,521 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,525 ValidationManager.java:152 - Validation of 99 partitions (~82.471KiB) finished in 24 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl10, [(-1,9223372036854775805], (9223372036854775805,-1]]]
INFO  [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,526 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl4
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,526 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,526 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,526 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,527 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl10 from /127.0.0.2:7012
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,527 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl4, [(-1,9223372036854775805], (9223372036854775805,-1]]]
INFO  [node1_RepairJobTask:5] node1 2023-02-25 00:21:15,527 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 0ms)
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,542 Validator.java:221 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -     [0..0]: 463
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -     [1..2]: 50
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -     [0..0]: 210
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -     [1..2]: 46
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 Validator.java:223 - Validated 99 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -     [0..315]: 467
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -  [316..1458]: 10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 - [1459..2601]: 3
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 - [2602..3744]: 12
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 - [3745..8113]: 21
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -     [0..584]: 214
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 -  [585..2041]: 10
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 - [2042..3498]: 9
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 - [3499..4955]: 13
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,543 EstimatedHistogram.java:337 - [4956..6412]: 10
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,543 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl4
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,544 ValidationManager.java:152 - Validation of 99 partitions (~149.472KiB) finished in 18 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl4, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,544 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
INFO  [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,544 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl13
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,545 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,545 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,545 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl4 from /127.0.0.2:7012
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,545 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl13, [(-1,9223372036854775805], (9223372036854775805,-1]]]
INFO  [node1_RepairJobTask:7] node1 2023-02-25 00:21:15,545 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 0ms)
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,548 Validator.java:221 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,548 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,548 EstimatedHistogram.java:337 -     [0..0]: 256
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,548 Validator.java:223 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,548 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,548 EstimatedHistogram.java:337 -     [0..0]: 256
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,549 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl13
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,549 ValidationManager.java:152 - Validation of 0 partitions (~64.006KiB) finished in 5 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl13, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,549 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
INFO  [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,549 CassandraValidationIterator.java:212 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905], parentSessionId=50de4900-b4a2-11ed-8341-618b49b30905: Performing validation compaction on 3 sstables in distributed_test_keyspace.tbl11
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,550 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,550 ValidationManager.java:85 - Created 2 merkle trees with merkle trees size 2, 387 partitions, 180 bytes
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,550 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl13 from /127.0.0.2:7012
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,550 Validator.java:150 - Prepared AEService trees of size 768 for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl11, [(-1,9223372036854775805], (9223372036854775805,-1]]]
INFO  [node1_RepairJobTask:5] node1 2023-02-25 00:21:15,550 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 0ms)
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,554 Validator.java:221 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partitions per leaf are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,554 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,554 EstimatedHistogram.java:337 -     [0..0]: 256
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,554 Validator.java:223 - Validated 0 partitions for 50debe30-b4a2-11ed-8341-618b49b30905.  Partition sizes are:
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,554 EstimatedHistogram.java:337 -     [0..0]: 513
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,554 EstimatedHistogram.java:337 -     [0..0]: 256
INFO  [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,554 Validator.java:249 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Sending completed merkle tree to /127.0.0.1:7012 for distributed_test_keyspace.tbl11
DEBUG [node2_ValidationExecutor:1] node2 2023-02-25 00:21:15,554 ValidationManager.java:152 - Validation of 0 partitions (~69.046KiB) finished in 5 msec, for [repair #50debe30-b4a2-11ed-8341-618b49b30905 on distributed_test_keyspace/tbl11, [(-1,9223372036854775805], (9223372036854775805,-1]]]
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,555 MerkleTree.java:741 - Allocating direct buffer of size 42016 for an off-heap merkle tree
DEBUG [node1_Messaging-EventLoop-3-1] node1 2023-02-25 00:21:15,555 MerkleTree.java:741 - Allocating direct buffer of size 21024 for an off-heap merkle tree
INFO  [node1_AntiEntropyStage:1] node1 2023-02-25 00:21:15,555 RepairSession.java:220 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Received merkle tree for tbl11 from /127.0.0.2:7012
INFO  [node1_RepairJobTask:7] node1 2023-02-25 00:21:15,556 RepairJob.java:331 - Created 0 sync tasks based on 2 merkle tree responses for 50de4900-b4a2-11ed-8341-618b49b30905 (took: 1ms)
INFO  [node1_Repair#51:1] node1 2023-02-25 00:21:15,556 RepairSession.java:333 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] Session completed successfully
INFO  [node1_Repair#51:1] node1 2023-02-25 00:21:15,556 RepairRunnable.java:175 - Repair session 50debe30-b4a2-11ed-8341-618b49b30905 for range [(-1,9223372036854775805], (9223372036854775805,-1]] finished
DEBUG [node1_Repair#51:1] node1 2023-02-25 00:21:15,556 AbstractRepairTask.java:96 - Repair result: [RepairSessionResult{sessionId=50debe30-b4a2-11ed-8341-618b49b30905, keyspace='distributed_test_keyspace', ranges=[(-1,9223372036854775805], (9223372036854775805,-1]], repairJobResults=[org.apache.cassandra.repair.RepairResult@37dc8ad2, org.apache.cassandra.repair.RepairResult@72754a8d, org.apache.cassandra.repair.RepairResult@61382c65, org.apache.cassandra.repair.RepairResult@604bcd66, org.apache.cassandra.repair.RepairResult@339251a7, org.apache.cassandra.repair.RepairResult@6beb547a, org.apache.cassandra.repair.RepairResult@4b100666, org.apache.cassandra.repair.RepairResult@750ec29f, org.apache.cassandra.repair.RepairResult@54b0aaf0, org.apache.cassandra.repair.RepairResult@65c96c98, org.apache.cassandra.repair.RepairResult@2ea17571, org.apache.cassandra.repair.RepairResult@2d385000, org.apache.cassandra.repair.RepairResult@7c0e887d, org.apache.cassandra.repair.RepairResult@528d86c8, org.apache.cassandra.repair.RepairResult@77a27b15], skippedReplicas=false}]
INFO  [node1_Repair#51:1] node1 2023-02-25 00:21:15,556 RepairRunnable.java:141 - Previewed data was in sync
INFO  [node1_Repair#51:1] node1 2023-02-25 00:21:15,557 RepairRunnable.java:223 - [preview repair #50de4900-b4a2-11ed-8341-618b49b30905]Repair command #51 finished in 0 seconds
DEBUG [node2_AntiEntropyStage:1] node2 2023-02-25 00:21:15,558 RepairMessageVerbHandler.java:229 - cleaning up repair
DEBUG [node1_Repair#51:1] node1 2023-02-25 00:21:15,559 RepairSession.java:382 - [preview repair #50debe30-b4a2-11ed-8341-618b49b30905] session task executor shut down gracefully
INFO  [node2_isolatedExecutor:5] node2 2023-02-25 00:21:15,811 Gossiper.java:2159 - Announcing shutdown
DEBUG [node2_isolatedExecutor:5] node2 2023-02-25 00:21:15,813 StorageService.java:3025 - Node /127.0.0.2:7012 state shutdown, token [9223372036854775805]
INFO  [node2_isolatedExecutor:5] node2 2023-02-25 00:21:15,813 StorageService.java:3028 - Node /127.0.0.2:7012 state jump to shutdown
DEBUG [node2_isolatedExecutor:5] node2 2023-02-25 00:21:15,814 StorageService.java:3025 - Node /127.0.0.2:7012 state shutdown, token [9223372036854775805]
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,814 TokenMetadata.java:876 - Starting pending range calculation for system_traces
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,814 TokenMetadata.java:881 - Pending range calculation for system_traces completed (took: 0ms)
INFO  [node2_isolatedExecutor:5] node2 2023-02-25 00:21:15,814 StorageService.java:3028 - Node /127.0.0.2:7012 state jump to shutdown
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,814 TokenMetadata.java:876 - Starting pending range calculation for system_distributed
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,814 TokenMetadata.java:881 - Pending range calculation for system_distributed completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:876 - Starting pending range calculation for system_auth
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:881 - Pending range calculation for system_auth completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:876 - Starting pending range calculation for distributed_test_keyspace
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:881 - Pending range calculation for distributed_test_keyspace completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:876 - Starting pending range calculation for system_traces
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:881 - Pending range calculation for system_traces completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:876 - Starting pending range calculation for system_distributed
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:881 - Pending range calculation for system_distributed completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:876 - Starting pending range calculation for system_auth
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:881 - Pending range calculation for system_auth completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:876 - Starting pending range calculation for distributed_test_keyspace
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:15,815 TokenMetadata.java:881 - Pending range calculation for distributed_test_keyspace completed (took: 0ms)
INFO  [node1_GossipStage:1] node1 2023-02-25 00:21:15,816 Gossiper.java:1382 - InetAddress /127.0.0.2:7012 is now DOWN
INFO  [node2_isolatedExecutor:7] node2 2023-02-25 00:21:15,817 HintsService.java:235 - Paused hints dispatch
INFO  [node1_GossipStage:1] node1 2023-02-25 00:21:15,817 MessagingService.java:501 - Interrupted outbound connections to /127.0.0.2:7012
DEBUG [node1_GossipStage:1] node1 2023-02-25 00:21:15,818 FailureDetector.java:370 - Forcing conviction of /127.0.0.2:7012
INFO  [node1_isolatedExecutor:6] node1 2023-02-25 00:21:15,818 Gossiper.java:2159 - Announcing shutdown
DEBUG [node1_isolatedExecutor:6] node1 2023-02-25 00:21:15,818 StorageService.java:3025 - Node /127.0.0.1:7012 state shutdown, token [-1]
INFO  [node1_isolatedExecutor:6] node1 2023-02-25 00:21:15,818 StorageService.java:3028 - Node /127.0.0.1:7012 state jump to shutdown
DEBUG [node1_GossipStage:1] node1 2023-02-25 00:21:15,819 StorageService.java:3025 - Node /127.0.0.2:7012 state shutdown, token [9223372036854775805]
INFO  [node1_GossipStage:1] node1 2023-02-25 00:21:15,819 StorageService.java:3028 - Node /127.0.0.2:7012 state jump to shutdown
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,819 TokenMetadata.java:876 - Starting pending range calculation for system_traces
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,820 TokenMetadata.java:881 - Pending range calculation for system_traces completed (took: 1ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,820 TokenMetadata.java:876 - Starting pending range calculation for system_distributed
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,820 TokenMetadata.java:881 - Pending range calculation for system_distributed completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,820 TokenMetadata.java:876 - Starting pending range calculation for system_auth
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,820 TokenMetadata.java:881 - Pending range calculation for system_auth completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,820 TokenMetadata.java:876 - Starting pending range calculation for distributed_test_keyspace
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,820 TokenMetadata.java:881 - Pending range calculation for distributed_test_keyspace completed (took: 0ms)
DEBUG [node1_isolatedExecutor:6] node1 2023-02-25 00:21:15,820 StorageService.java:3025 - Node /127.0.0.1:7012 state shutdown, token [-1]
INFO  [node1_isolatedExecutor:6] node1 2023-02-25 00:21:15,820 StorageService.java:3028 - Node /127.0.0.1:7012 state jump to shutdown
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:876 - Starting pending range calculation for system_traces
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:881 - Pending range calculation for system_traces completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:876 - Starting pending range calculation for system_distributed
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:881 - Pending range calculation for system_distributed completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:876 - Starting pending range calculation for system_auth
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:881 - Pending range calculation for system_auth completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:876 - Starting pending range calculation for distributed_test_keyspace
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,826 TokenMetadata.java:881 - Pending range calculation for distributed_test_keyspace completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:876 - Starting pending range calculation for system_traces
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:881 - Pending range calculation for system_traces completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:876 - Starting pending range calculation for system_distributed
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:881 - Pending range calculation for system_distributed completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:876 - Starting pending range calculation for system_auth
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:881 - Pending range calculation for system_auth completed (took: 0ms)
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:876 - Starting pending range calculation for distributed_test_keyspace
DEBUG [node1_PendingRangeCalculator:1] node1 2023-02-25 00:21:15,827 TokenMetadata.java:881 - Pending range calculation for distributed_test_keyspace completed (took: 0ms)
DEBUG [node1_GossipStage:1] node1 2023-02-25 00:21:15,827 Gossiper.java:608 - Marked /127.0.0.2:7012 as shutdown
INFO  [node1_isolatedExecutor:5] node1 2023-02-25 00:21:15,830 HintsService.java:235 - Paused hints dispatch
INFO  [node2_Messaging-EventLoop-3-2] node2 2023-02-25 00:21:16,356 InboundConnectionInitiator.java:567 - /127.0.0.1:7012(/127.0.0.1:39098)->/127.0.0.2:7012-URGENT_MESSAGES-35ea5742 messaging connection established, version = 12, framing = CRC, encryption = unencrypted
INFO  [node1_Messaging-EventLoop-3-2] node1 2023-02-25 00:21:16,356 OutboundConnection.java:1155 - /127.0.0.1:7012(/127.0.0.1:39098)->/127.0.0.2:7012-URGENT_MESSAGES-b7abeff9 successfully connected, version = 12, framing = CRC, encryption = unencrypted
DEBUG [node2_GossipStage:1] node2 2023-02-25 00:21:16,360 StorageService.java:3025 - Node /127.0.0.1:7012 state shutdown, token [-1]
INFO  [node2_GossipStage:1] node2 2023-02-25 00:21:16,360 StorageService.java:3028 - Node /127.0.0.1:7012 state jump to shutdown
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,365 TokenMetadata.java:876 - Starting pending range calculation for system_traces
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,365 TokenMetadata.java:881 - Pending range calculation for system_traces completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,366 TokenMetadata.java:876 - Starting pending range calculation for system_distributed
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,366 TokenMetadata.java:881 - Pending range calculation for system_distributed completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,366 TokenMetadata.java:876 - Starting pending range calculation for system_auth
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,366 TokenMetadata.java:881 - Pending range calculation for system_auth completed (took: 0ms)
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,366 TokenMetadata.java:876 - Starting pending range calculation for distributed_test_keyspace
DEBUG [node2_PendingRangeCalculator:1] node2 2023-02-25 00:21:16,366 TokenMetadata.java:881 - Pending range calculation for distributed_test_keyspace completed (took: 0ms)
DEBUG [node2_isolatedExecutor:5] node2 2023-02-25 00:21:17,817 MessagingService.java:540 - Shutting down: timeout=60s, gracefully=false, shutdownExecutors=true
INFO  [node2_isolatedExecutor:5] node2 2023-02-25 00:21:17,817 MessagingService.java:548 - Waiting for messaging service to quiesce
DEBUG [node1_isolatedExecutor:6] node1 2023-02-25 00:21:17,827 MessagingService.java:540 - Shutting down: timeout=60s, gracefully=false, shutdownExecutors=true
INFO  [node1_isolatedExecutor:6] node1 2023-02-25 00:21:17,827 MessagingService.java:548 - Waiting for messaging service to quiesce
DEBUG [node2_COMMIT-LOG-WRITER] node2 2023-02-25 00:21:19,837 HeapUtils.java:134 - Heap dump creation on uncaught exceptions is disabled.
DEBUG [node1_COMMIT-LOG-WRITER] node1 2023-02-25 00:21:19,864 HeapUtils.java:134 - Heap dump creation on uncaught exceptions is disabled.