[2025-10-22T04:34:06.618Z] Running test renaissance-dec-tree_0 ... [2025-10-22T04:34:06.618Z] =============================================== [2025-10-22T04:34:07.018Z] renaissance-dec-tree_0 Start Time: Tue Oct 21 21:34:03 2025 Epoch Time (ms): 1761107643763 [2025-10-22T04:34:07.018Z] variation: NoOptions [2025-10-22T04:34:07.018Z] JVM_OPTIONS: [2025-10-22T04:34:07.018Z] { \ [2025-10-22T04:34:07.018Z] echo ""; echo "TEST SETUP:"; \ [2025-10-22T04:34:07.018Z] echo "Nothing to be done for setup."; \ [2025-10-22T04:34:07.018Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_176110704899/renaissance-dec-tree_0"; \ [2025-10-22T04:34:07.018Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_176110704899/renaissance-dec-tree_0"; \ [2025-10-22T04:34:07.018Z] echo ""; echo "TESTING:"; \ [2025-10-22T04:34:07.018Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_176110704899/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-10-22T04:34:07.018Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_176110704899/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-22T04:34:07.018Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-22T04:34:07.018Z] echo "Nothing to be done for teardown."; \ [2025-10-22T04:34:07.018Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_176110704899/TestTargetResult"; [2025-10-22T04:34:07.018Z] [2025-10-22T04:34:07.018Z] TEST SETUP: [2025-10-22T04:34:07.018Z] Nothing to be done for setup. [2025-10-22T04:34:07.018Z] [2025-10-22T04:34:07.018Z] TESTING: [2025-10-22T04:34:24.425Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-22T04:34:28.979Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-10-22T04:34:28.979Z] GC before operation: completed in 102.645 ms, heap usage 72.815 MB -> 34.936 MB. [2025-10-22T04:34:59.834Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (28605.231 ms) ====== [2025-10-22T04:34:59.834Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-10-22T04:34:59.834Z] GC before operation: completed in 399.159 ms, heap usage 407.707 MB -> 69.066 MB. [2025-10-22T04:35:07.857Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (9006.210 ms) ====== [2025-10-22T04:35:07.857Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-10-22T04:35:07.857Z] GC before operation: completed in 425.979 ms, heap usage 192.043 MB -> 96.743 MB. [2025-10-22T04:35:15.630Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (7168.418 ms) ====== [2025-10-22T04:35:15.630Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-10-22T04:35:15.630Z] GC before operation: completed in 234.056 ms, heap usage 252.331 MB -> 69.778 MB. [2025-10-22T04:35:20.779Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5996.609 ms) ====== [2025-10-22T04:35:20.779Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-10-22T04:35:21.227Z] GC before operation: completed in 266.287 ms, heap usage 191.332 MB -> 69.553 MB. [2025-10-22T04:35:28.684Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (6501.225 ms) ====== [2025-10-22T04:35:28.684Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-10-22T04:35:28.684Z] GC before operation: completed in 219.444 ms, heap usage 340.812 MB -> 97.838 MB. [2025-10-22T04:35:28.684Z] 21:35:25.443 WARN [block-manager-storage-async-thread-pool-18] org.apache.spark.storage.BlockManager - Block broadcast_69_piece0 was not removed normally. [2025-10-22T04:35:28.684Z] 21:35:25.447 ERROR [block-manager-storage-async-thread-pool-19] org.apache.spark.storage.BlockManagerStorageEndpoint - Error in removing broadcast 69 [2025-10-22T04:35:28.684Z] org.apache.spark.SparkException: Block broadcast_69_piece0 does not exist [2025-10-22T04:35:28.684Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3$adapted(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] 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] [2025-10-22T04:35:28.684Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?] [2025-10-22T04:35:28.684Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?] [2025-10-22T04:35:28.684Z] at java.lang.Thread.run(Thread.java:840) [?:?] [2025-10-22T04:35:28.684Z] 21:35:25.474 WARN [block-manager-ask-thread-pool-16] org.apache.spark.storage.BlockManagerMaster - Failed to remove broadcast 69 with removeFromMaster = true - Block broadcast_69_piece0 does not exist [2025-10-22T04:35:28.684Z] org.apache.spark.SparkException: Block broadcast_69_piece0 does not exist [2025-10-22T04:35:28.684Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3$adapted(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.684Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.684Z] 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] [2025-10-22T04:35:28.684Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?] [2025-10-22T04:35:28.685Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?] [2025-10-22T04:35:28.685Z] at java.lang.Thread.run(Thread.java:840) [?:?] [2025-10-22T04:35:28.685Z] 21:35:25.475 ERROR [Spark Context Cleaner] org.apache.spark.ContextCleaner - Error cleaning broadcast 69 [2025-10-22T04:35:28.685Z] org.apache.spark.SparkException: Exception thrown in awaitResult: [2025-10-22T04:35:28.685Z] at org.apache.spark.util.SparkThreadUtils$.awaitResult(SparkThreadUtils.scala:56) ~[spark-common-utils_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.util.ThreadUtils$.awaitResult(ThreadUtils.scala:310) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.rpc.RpcTimeout.awaitResult(RpcTimeout.scala:75) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManagerMaster.removeBroadcast(BlockManagerMaster.scala:222) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.broadcast.TorrentBroadcast$.unpersist(TorrentBroadcast.scala:395) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.broadcast.TorrentBroadcastFactory.unbroadcast(TorrentBroadcastFactory.scala:49) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.broadcast.BroadcastManager.unbroadcast(BroadcastManager.scala:82) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.ContextCleaner.doCleanupBroadcast(ContextCleaner.scala:256) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$3(ContextCleaner.scala:204) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$3$adapted(ContextCleaner.scala:195) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at scala.Option.foreach(Option.scala:437) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$1(ContextCleaner.scala:195) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.util.Utils$.tryOrStopSparkContext(Utils.scala:1356) [spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.ContextCleaner.org$apache$spark$ContextCleaner$$keepCleaning(ContextCleaner.scala:189) [spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.ContextCleaner$$anon$1.run(ContextCleaner.scala:79) [spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] Caused by: org.apache.spark.SparkException: Block broadcast_69_piece0 does not exist [2025-10-22T04:35:28.685Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3$adapted(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] 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] [2025-10-22T04:35:28.685Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-10-22T04:35:28.685Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) ~[scala-library-2.13.15.jar:?] [2025-10-22T04:35:28.685Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) ~[?:?] [2025-10-22T04:35:28.685Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) ~[?:?] [2025-10-22T04:35:28.685Z] at java.lang.Thread.run(Thread.java:840) ~[?:?] [2025-10-22T04:35:33.948Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (5854.225 ms) ====== [2025-10-22T04:35:33.948Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-10-22T04:35:33.948Z] GC before operation: completed in 146.259 ms, heap usage 274.802 MB -> 71.236 MB. [2025-10-22T04:35:40.080Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5394.874 ms) ====== [2025-10-22T04:35:40.080Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-10-22T04:35:40.080Z] GC before operation: completed in 277.804 ms, heap usage 224.547 MB -> 71.591 MB. [2025-10-22T04:35:45.087Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (5252.871 ms) ====== [2025-10-22T04:35:45.087Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-10-22T04:35:45.087Z] GC before operation: completed in 192.301 ms, heap usage 188.200 MB -> 99.010 MB. [2025-10-22T04:35:49.909Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4607.887 ms) ====== [2025-10-22T04:35:49.909Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-10-22T04:35:49.909Z] GC before operation: completed in 224.664 ms, heap usage 528.804 MB -> 71.793 MB. [2025-10-22T04:35:54.658Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4107.720 ms) ====== [2025-10-22T04:35:54.658Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-10-22T04:35:54.658Z] GC before operation: completed in 162.523 ms, heap usage 369.292 MB -> 71.852 MB. [2025-10-22T04:35:57.715Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3388.597 ms) ====== [2025-10-22T04:35:57.715Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-10-22T04:35:57.715Z] GC before operation: completed in 182.023 ms, heap usage 105.877 MB -> 71.720 MB. [2025-10-22T04:36:02.563Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4357.093 ms) ====== [2025-10-22T04:36:02.563Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-10-22T04:36:02.563Z] GC before operation: completed in 209.290 ms, heap usage 446.399 MB -> 71.927 MB. [2025-10-22T04:36:05.832Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3602.201 ms) ====== [2025-10-22T04:36:05.832Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-10-22T04:36:06.320Z] GC before operation: completed in 221.154 ms, heap usage 295.485 MB -> 99.743 MB. [2025-10-22T04:36:10.864Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4534.064 ms) ====== [2025-10-22T04:36:10.864Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-10-22T04:36:10.865Z] GC before operation: completed in 268.614 ms, heap usage 498.023 MB -> 72.276 MB. [2025-10-22T04:36:15.838Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4514.165 ms) ====== [2025-10-22T04:36:15.838Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-10-22T04:36:15.838Z] GC before operation: completed in 264.723 ms, heap usage 285.912 MB -> 72.423 MB. [2025-10-22T04:36:20.741Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (5146.954 ms) ====== [2025-10-22T04:36:20.741Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-10-22T04:36:21.297Z] GC before operation: completed in 239.097 ms, heap usage 254.124 MB -> 72.640 MB. [2025-10-22T04:36:26.757Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (5545.175 ms) ====== [2025-10-22T04:36:26.758Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-10-22T04:36:26.758Z] GC before operation: completed in 161.059 ms, heap usage 117.254 MB -> 72.381 MB. [2025-10-22T04:36:32.045Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4948.745 ms) ====== [2025-10-22T04:36:32.045Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-10-22T04:36:32.045Z] GC before operation: completed in 455.553 ms, heap usage 545.535 MB -> 73.051 MB. [2025-10-22T04:36:37.044Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4378.153 ms) ====== [2025-10-22T04:36:37.044Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-10-22T04:36:37.044Z] GC before operation: completed in 327.020 ms, heap usage 210.628 MB -> 72.944 MB. [2025-10-22T04:36:43.347Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (5629.020 ms) ====== [2025-10-22T04:36:43.347Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-10-22T04:36:43.347Z] GC before operation: completed in 322.521 ms, heap usage 241.591 MB -> 72.919 MB. [2025-10-22T04:36:47.646Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (4561.338 ms) ====== [2025-10-22T04:36:47.646Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-10-22T04:36:47.646Z] GC before operation: completed in 275.464 ms, heap usage 312.596 MB -> 73.482 MB. [2025-10-22T04:36:52.539Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4592.450 ms) ====== [2025-10-22T04:36:52.539Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-10-22T04:36:52.539Z] GC before operation: completed in 299.053 ms, heap usage 382.920 MB -> 73.390 MB. [2025-10-22T04:36:57.340Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (4641.285 ms) ====== [2025-10-22T04:36:57.340Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-10-22T04:36:57.937Z] GC before operation: completed in 287.325 ms, heap usage 448.551 MB -> 100.902 MB. [2025-10-22T04:37:01.835Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4366.380 ms) ====== [2025-10-22T04:37:01.835Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-10-22T04:37:02.353Z] GC before operation: completed in 246.228 ms, heap usage 151.983 MB -> 73.080 MB. [2025-10-22T04:37:08.442Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (5250.471 ms) ====== [2025-10-22T04:37:08.442Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-10-22T04:37:08.442Z] GC before operation: completed in 409.863 ms, heap usage 544.332 MB -> 88.751 MB. [2025-10-22T04:37:12.675Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4577.180 ms) ====== [2025-10-22T04:37:12.675Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-10-22T04:37:12.675Z] GC before operation: completed in 307.570 ms, heap usage 337.107 MB -> 101.335 MB. [2025-10-22T04:37:17.603Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4354.230 ms) ====== [2025-10-22T04:37:17.603Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-10-22T04:37:17.603Z] GC before operation: completed in 408.425 ms, heap usage 607.339 MB -> 73.918 MB. [2025-10-22T04:37:22.561Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4409.380 ms) ====== [2025-10-22T04:37:22.561Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-10-22T04:37:22.561Z] GC before operation: completed in 284.461 ms, heap usage 237.858 MB -> 101.777 MB. [2025-10-22T04:37:27.209Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (5210.332 ms) ====== [2025-10-22T04:37:27.209Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-10-22T04:37:27.684Z] GC before operation: completed in 201.349 ms, heap usage 398.070 MB -> 101.698 MB. [2025-10-22T04:37:31.788Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (4292.430 ms) ====== [2025-10-22T04:37:31.788Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-10-22T04:37:32.313Z] GC before operation: completed in 334.334 ms, heap usage 280.271 MB -> 74.080 MB. [2025-10-22T04:37:37.111Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4443.380 ms) ====== [2025-10-22T04:37:37.111Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-10-22T04:37:37.111Z] GC before operation: completed in 228.386 ms, heap usage 453.610 MB -> 74.372 MB. [2025-10-22T04:37:40.729Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3673.060 ms) ====== [2025-10-22T04:37:40.729Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-10-22T04:37:40.729Z] GC before operation: completed in 231.306 ms, heap usage 299.785 MB -> 102.013 MB. [2025-10-22T04:37:42.813Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2111.648 ms) ====== [2025-10-22T04:37:42.813Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-10-22T04:37:43.257Z] GC before operation: completed in 230.026 ms, heap usage 586.928 MB -> 102.228 MB. [2025-10-22T04:37:46.971Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3692.604 ms) ====== [2025-10-22T04:37:46.972Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-10-22T04:37:46.972Z] GC before operation: completed in 261.191 ms, heap usage 153.339 MB -> 102.189 MB. [2025-10-22T04:37:51.743Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (4159.785 ms) ====== [2025-10-22T04:37:51.743Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-10-22T04:37:51.743Z] GC before operation: completed in 302.145 ms, heap usage 389.689 MB -> 102.299 MB. [2025-10-22T04:37:55.220Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3620.465 ms) ====== [2025-10-22T04:37:55.220Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-10-22T04:37:55.220Z] GC before operation: completed in 196.054 ms, heap usage 600.564 MB -> 75.015 MB. [2025-10-22T04:37:58.023Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2427.888 ms) ====== [2025-10-22T04:37:58.023Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-10-22T04:37:58.023Z] GC before operation: completed in 235.922 ms, heap usage 236.522 MB -> 74.890 MB. [2025-10-22T04:38:00.840Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2638.476 ms) ====== [2025-10-22T04:38:00.840Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-10-22T04:38:00.840Z] GC before operation: completed in 166.838 ms, heap usage 334.251 MB -> 75.226 MB. [2025-10-22T04:38:02.259Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1591.040 ms) ====== [2025-10-22T04:38:02.259Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-10-22T04:38:02.686Z] GC before operation: completed in 91.115 ms, heap usage 394.392 MB -> 102.790 MB. [2025-10-22T04:38:04.119Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1527.944 ms) ====== [2025-10-22T04:38:04.508Z] ----------------------------------- [2025-10-22T04:38:04.508Z] renaissance-dec-tree_0_PASSED [2025-10-22T04:38:04.508Z] ----------------------------------- [2025-10-22T04:38:04.508Z] [2025-10-22T04:38:04.509Z] TEST TEARDOWN: [2025-10-22T04:38:04.509Z] Nothing to be done for teardown. [2025-10-22T04:38:04.509Z] renaissance-dec-tree_0 Finish Time: Tue Oct 21 21:38:01 2025 Epoch Time (ms): 1761107881300