[2026-03-05T17:46:34.346Z] Running test renaissance-dec-tree_0 ... [2026-03-05T17:46:34.346Z] =============================================== [2026-03-05T17:46:34.346Z] renaissance-dec-tree_0 Start Time: Thu Mar 5 17:46:34 2026 Epoch Time (ms): 1772732794252 [2026-03-05T17:46:34.346Z] variation: NoOptions [2026-03-05T17:46:34.346Z] JVM_OPTIONS: [2026-03-05T17:46:34.346Z] { \ [2026-03-05T17:46:34.346Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T17:46:34.346Z] echo "Nothing to be done for setup."; \ [2026-03-05T17:46:34.346Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17727318215358/renaissance-dec-tree_0"; \ [2026-03-05T17:46:34.346Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17727318215358/renaissance-dec-tree_0"; \ [2026-03-05T17:46:34.346Z] echo ""; echo "TESTING:"; \ [2026-03-05T17:46:34.346Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/bin/java" --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.util=ALL-UNNAMED --add-opens java.base/java.util.concurrent=ALL-UNNAMED --add-opens java.base/java.nio=ALL-UNNAMED --add-opens java.base/sun.nio.ch=ALL-UNNAMED --add-opens java.base/java.lang.invoke=ALL-UNNAMED -jar "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17727318215358/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-03-05T17:46:34.346Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17727318215358/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T17:46:34.346Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T17:46:34.346Z] echo "Nothing to be done for teardown."; \ [2026-03-05T17:46:34.346Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17727318215358/TestTargetResult"; [2026-03-05T17:46:34.346Z] [2026-03-05T17:46:34.346Z] TEST SETUP: [2026-03-05T17:46:34.346Z] Nothing to be done for setup. [2026-03-05T17:46:34.346Z] [2026-03-05T17:46:34.346Z] TESTING: [2026-03-05T17:46:42.507Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-03-05T17:46:44.065Z] WARNING: An illegal reflective access operation has occurred [2026-03-05T17:46:44.065Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17727318215358/renaissance-dec-tree_0/harness-174635-11732497042460136993/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-03-05T17:46:44.065Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-03-05T17:46:44.065Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-03-05T17:46:44.065Z] WARNING: All illegal access operations will be denied in a future release [2026-03-05T17:46:46.493Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-03-05T17:46:46.493Z] GC before operation: completed in 103.221 ms, heap usage 52.805 MB -> 34.381 MB. [2026-03-05T17:47:02.438Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (15232.412 ms) ====== [2026-03-05T17:47:02.438Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-03-05T17:47:02.438Z] GC before operation: completed in 140.598 ms, heap usage 150.237 MB -> 68.095 MB. [2026-03-05T17:47:05.839Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3727.416 ms) ====== [2026-03-05T17:47:05.839Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-03-05T17:47:05.839Z] GC before operation: completed in 219.382 ms, heap usage 109.679 MB -> 68.260 MB. [2026-03-05T17:47:09.213Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3648.343 ms) ====== [2026-03-05T17:47:09.213Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-03-05T17:47:09.963Z] GC before operation: completed in 189.548 ms, heap usage 116.055 MB -> 68.506 MB. [2026-03-05T17:47:12.386Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2359.633 ms) ====== [2026-03-05T17:47:12.386Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-03-05T17:47:12.386Z] GC before operation: completed in 220.377 ms, heap usage 174.270 MB -> 69.208 MB. [2026-03-05T17:47:14.810Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2587.358 ms) ====== [2026-03-05T17:47:14.810Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-03-05T17:47:14.810Z] GC before operation: completed in 185.697 ms, heap usage 181.959 MB -> 69.416 MB. [2026-03-05T17:47:17.229Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2099.813 ms) ====== [2026-03-05T17:47:17.229Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-03-05T17:47:17.229Z] GC before operation: completed in 169.682 ms, heap usage 210.178 MB -> 80.620 MB. [2026-03-05T17:47:19.658Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1973.897 ms) ====== [2026-03-05T17:47:19.658Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-03-05T17:47:19.658Z] GC before operation: completed in 138.625 ms, heap usage 271.577 MB -> 69.842 MB. [2026-03-05T17:47:21.045Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1969.357 ms) ====== [2026-03-05T17:47:21.045Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-03-05T17:47:21.799Z] GC before operation: completed in 177.368 ms, heap usage 184.828 MB -> 69.820 MB. [2026-03-05T17:47:23.357Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1811.900 ms) ====== [2026-03-05T17:47:23.357Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-03-05T17:47:23.357Z] GC before operation: completed in 154.440 ms, heap usage 145.160 MB -> 69.921 MB. [2026-03-05T17:47:28.763Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (5232.458 ms) ====== [2026-03-05T17:47:28.763Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-03-05T17:47:28.763Z] GC before operation: completed in 137.447 ms, heap usage 210.701 MB -> 86.532 MB. [2026-03-05T17:47:30.319Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1831.950 ms) ====== [2026-03-05T17:47:30.319Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-03-05T17:47:31.073Z] GC before operation: completed in 150.874 ms, heap usage 308.109 MB -> 70.297 MB. [2026-03-05T17:47:32.633Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1580.193 ms) ====== [2026-03-05T17:47:32.633Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-03-05T17:47:32.633Z] GC before operation: completed in 172.309 ms, heap usage 254.859 MB -> 70.505 MB. [2026-03-05T17:47:32.633Z] 17:47:32.138 WARN [block-manager-storage-async-thread-pool-79] org.apache.spark.storage.BlockManager - Block broadcast_163 could not be removed as it was not found on disk or in memory [2026-03-05T17:47:32.633Z] 17:47:32.395 WARN [block-manager-storage-async-thread-pool-79] org.apache.spark.storage.BlockManager - Block broadcast_163 was not removed normally. [2026-03-05T17:47:32.633Z] 17:47:32.396 ERROR [block-manager-storage-async-thread-pool-79] org.apache.spark.storage.BlockManagerStorageEndpoint - Error in removing broadcast 163 [2026-03-05T17:47:32.633Z] org.apache.spark.SparkException: Block broadcast_163 does not exist [2026-03-05T17:47:32.633Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.633Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.633Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.633Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3$adapted(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManagerStorageEndpoint$$anonfun$receiveAndReply$1.$anonfun$applyOrElse$4(BlockManagerStorageEndpoint.scala:69) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] [2026-03-05T17:47:32.634Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] [2026-03-05T17:47:32.634Z] at java.lang.Thread.run(Thread.java:829) [?:?] [2026-03-05T17:47:32.634Z] 17:47:32.419 WARN [block-manager-ask-thread-pool-27] org.apache.spark.storage.BlockManagerMaster - Failed to remove broadcast 163 with removeFromMaster = true - Block broadcast_163 does not exist [2026-03-05T17:47:32.634Z] org.apache.spark.SparkException: Block broadcast_163 does not exist [2026-03-05T17:47:32.634Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3$adapted(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManagerStorageEndpoint$$anonfun$receiveAndReply$1.$anonfun$applyOrElse$4(BlockManagerStorageEndpoint.scala:69) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] [2026-03-05T17:47:32.634Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] [2026-03-05T17:47:32.634Z] at java.lang.Thread.run(Thread.java:829) [?:?] [2026-03-05T17:47:32.634Z] 17:47:32.421 ERROR [Spark Context Cleaner] org.apache.spark.ContextCleaner - Error cleaning broadcast 163 [2026-03-05T17:47:32.634Z] org.apache.spark.SparkException: Exception thrown in awaitResult: [2026-03-05T17:47:32.634Z] at org.apache.spark.util.SparkThreadUtils$.awaitResult(SparkThreadUtils.scala:56) ~[spark-common-utils_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.util.ThreadUtils$.awaitResult(ThreadUtils.scala:310) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.rpc.RpcTimeout.awaitResult(RpcTimeout.scala:75) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManagerMaster.removeBroadcast(BlockManagerMaster.scala:222) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.broadcast.TorrentBroadcast$.unpersist(TorrentBroadcast.scala:395) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.broadcast.TorrentBroadcastFactory.unbroadcast(TorrentBroadcastFactory.scala:49) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.broadcast.BroadcastManager.unbroadcast(BroadcastManager.scala:82) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.ContextCleaner.doCleanupBroadcast(ContextCleaner.scala:256) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$3(ContextCleaner.scala:204) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$3$adapted(ContextCleaner.scala:195) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.Option.foreach(Option.scala:437) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$1(ContextCleaner.scala:195) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.util.Utils$.tryOrStopSparkContext(Utils.scala:1356) [spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.ContextCleaner.org$apache$spark$ContextCleaner$$keepCleaning(ContextCleaner.scala:189) [spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.ContextCleaner$$anon$1.run(ContextCleaner.scala:79) [spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] Caused by: org.apache.spark.SparkException: Block broadcast_163 does not exist [2026-03-05T17:47:32.634Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3$adapted(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManagerStorageEndpoint$$anonfun$receiveAndReply$1.$anonfun$applyOrElse$4(BlockManagerStorageEndpoint.scala:69) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2026-03-05T17:47:32.634Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) ~[scala-library-2.13.15.jar:?] [2026-03-05T17:47:32.634Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?] [2026-03-05T17:47:32.634Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?] [2026-03-05T17:47:32.634Z] at java.lang.Thread.run(Thread.java:829) ~[?:?] [2026-03-05T17:47:34.201Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1664.605 ms) ====== [2026-03-05T17:47:34.201Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-03-05T17:47:34.201Z] GC before operation: completed in 131.283 ms, heap usage 161.058 MB -> 71.475 MB. [2026-03-05T17:47:35.763Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1719.645 ms) ====== [2026-03-05T17:47:35.763Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-03-05T17:47:36.513Z] GC before operation: completed in 156.126 ms, heap usage 116.926 MB -> 71.148 MB. [2026-03-05T17:47:38.067Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1726.363 ms) ====== [2026-03-05T17:47:38.067Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-03-05T17:47:38.067Z] GC before operation: completed in 170.956 ms, heap usage 274.010 MB -> 82.657 MB. [2026-03-05T17:47:39.634Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1763.048 ms) ====== [2026-03-05T17:47:39.634Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-03-05T17:47:40.439Z] GC before operation: completed in 161.889 ms, heap usage 180.326 MB -> 71.601 MB. [2026-03-05T17:47:42.003Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1789.070 ms) ====== [2026-03-05T17:47:42.003Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-03-05T17:47:42.003Z] GC before operation: completed in 218.118 ms, heap usage 173.654 MB -> 72.197 MB. [2026-03-05T17:47:44.439Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2502.995 ms) ====== [2026-03-05T17:47:44.439Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-03-05T17:47:45.191Z] GC before operation: completed in 283.153 ms, heap usage 253.273 MB -> 72.120 MB. [2026-03-05T17:47:46.743Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2240.767 ms) ====== [2026-03-05T17:47:46.743Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-03-05T17:47:47.518Z] GC before operation: completed in 237.040 ms, heap usage 239.001 MB -> 83.147 MB. [2026-03-05T17:47:50.880Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3167.991 ms) ====== [2026-03-05T17:47:50.880Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-03-05T17:47:50.880Z] GC before operation: completed in 209.399 ms, heap usage 285.372 MB -> 83.472 MB. [2026-03-05T17:47:53.314Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2225.629 ms) ====== [2026-03-05T17:47:53.314Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-03-05T17:47:53.314Z] GC before operation: completed in 280.213 ms, heap usage 232.302 MB -> 72.257 MB. [2026-03-05T17:47:55.740Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2749.722 ms) ====== [2026-03-05T17:47:55.740Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-03-05T17:47:56.489Z] GC before operation: completed in 299.068 ms, heap usage 323.155 MB -> 72.512 MB. [2026-03-05T17:47:58.932Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2523.565 ms) ====== [2026-03-05T17:47:58.932Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-03-05T17:47:58.932Z] GC before operation: completed in 266.398 ms, heap usage 109.967 MB -> 72.301 MB. [2026-03-05T17:48:01.367Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2314.176 ms) ====== [2026-03-05T17:48:01.367Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-03-05T17:48:01.367Z] GC before operation: completed in 262.916 ms, heap usage 240.527 MB -> 72.586 MB. [2026-03-05T17:48:03.789Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2301.988 ms) ====== [2026-03-05T17:48:03.789Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-03-05T17:48:04.541Z] GC before operation: completed in 243.543 ms, heap usage 116.728 MB -> 72.206 MB. [2026-03-05T17:48:06.969Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2355.576 ms) ====== [2026-03-05T17:48:06.969Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-03-05T17:48:06.969Z] GC before operation: completed in 363.632 ms, heap usage 143.400 MB -> 100.060 MB. [2026-03-05T17:48:09.391Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2394.345 ms) ====== [2026-03-05T17:48:09.391Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-03-05T17:48:09.391Z] GC before operation: completed in 272.957 ms, heap usage 384.624 MB -> 72.894 MB. [2026-03-05T17:48:11.822Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2318.594 ms) ====== [2026-03-05T17:48:11.822Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-03-05T17:48:11.822Z] GC before operation: completed in 225.743 ms, heap usage 111.013 MB -> 72.831 MB. [2026-03-05T17:48:14.248Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2276.675 ms) ====== [2026-03-05T17:48:14.248Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-03-05T17:48:14.998Z] GC before operation: completed in 293.994 ms, heap usage 323.428 MB -> 73.294 MB. [2026-03-05T17:48:17.420Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2561.897 ms) ====== [2026-03-05T17:48:17.420Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-03-05T17:48:17.420Z] GC before operation: completed in 306.504 ms, heap usage 113.095 MB -> 72.727 MB. [2026-03-05T17:48:19.853Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2433.011 ms) ====== [2026-03-05T17:48:19.853Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-03-05T17:48:20.605Z] GC before operation: completed in 344.110 ms, heap usage 244.173 MB -> 73.521 MB. [2026-03-05T17:48:20.605Z] 17:48:19.919 WARN [block-manager-storage-async-thread-pool-43] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429_piece0, which does not exist [2026-03-05T17:48:22.175Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2162.756 ms) ====== [2026-03-05T17:48:22.175Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-03-05T17:48:22.929Z] GC before operation: completed in 315.652 ms, heap usage 226.423 MB -> 73.442 MB. [2026-03-05T17:48:25.358Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2399.742 ms) ====== [2026-03-05T17:48:25.358Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-03-05T17:48:25.358Z] GC before operation: completed in 312.401 ms, heap usage 224.716 MB -> 101.147 MB. [2026-03-05T17:48:27.786Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2348.628 ms) ====== [2026-03-05T17:48:27.786Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-03-05T17:48:28.541Z] GC before operation: completed in 296.440 ms, heap usage 323.507 MB -> 84.942 MB. [2026-03-05T17:48:30.113Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2090.698 ms) ====== [2026-03-05T17:48:30.113Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-03-05T17:48:30.869Z] GC before operation: completed in 292.367 ms, heap usage 307.678 MB -> 73.967 MB. [2026-03-05T17:48:33.309Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2447.977 ms) ====== [2026-03-05T17:48:33.309Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-03-05T17:48:33.309Z] GC before operation: completed in 217.118 ms, heap usage 115.125 MB -> 73.539 MB. [2026-03-05T17:48:35.753Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2476.612 ms) ====== [2026-03-05T17:48:35.753Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-03-05T17:48:35.753Z] GC before operation: completed in 263.188 ms, heap usage 223.526 MB -> 74.109 MB. [2026-03-05T17:48:38.186Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2273.160 ms) ====== [2026-03-05T17:48:38.186Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-03-05T17:48:38.947Z] GC before operation: completed in 321.664 ms, heap usage 295.558 MB -> 74.202 MB. [2026-03-05T17:48:41.399Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2461.545 ms) ====== [2026-03-05T17:48:41.399Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-03-05T17:48:41.399Z] GC before operation: completed in 313.271 ms, heap usage 290.305 MB -> 74.310 MB. [2026-03-05T17:48:43.833Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2416.655 ms) ====== [2026-03-05T17:48:44.585Z] ----------------------------------- [2026-03-05T17:48:44.585Z] renaissance-dec-tree_0_PASSED [2026-03-05T17:48:44.585Z] ----------------------------------- [2026-03-05T17:48:44.585Z] [2026-03-05T17:48:44.585Z] TEST TEARDOWN: [2026-03-05T17:48:44.585Z] Nothing to be done for teardown. [2026-03-05T17:48:44.585Z] renaissance-dec-tree_0 Finish Time: Thu Mar 5 17:48:43 2026 Epoch Time (ms): 1772732923888