[2025-03-30T21:23:08.744Z] Running test renaissance-dec-tree_0 ... [2025-03-30T21:23:08.744Z] =============================================== [2025-03-30T21:23:08.744Z] renaissance-dec-tree_0 Start Time: Sun Mar 30 21:23:07 2025 Epoch Time (ms): 1743369787913 [2025-03-30T21:23:08.744Z] variation: NoOptions [2025-03-30T21:23:08.744Z] JVM_OPTIONS: [2025-03-30T21:23:08.744Z] { \ [2025-03-30T21:23:08.744Z] echo ""; echo "TEST SETUP:"; \ [2025-03-30T21:23:08.744Z] echo "Nothing to be done for setup."; \ [2025-03-30T21:23:08.744Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/renaissance-dec-tree_0"; \ [2025-03-30T21:23:08.744Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/renaissance-dec-tree_0"; \ [2025-03-30T21:23:08.744Z] echo ""; echo "TESTING:"; \ [2025-03-30T21:23:08.744Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-03-30T21:23:08.745Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-30T21:23:08.745Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-30T21:23:08.745Z] echo "Nothing to be done for teardown."; \ [2025-03-30T21:23:08.745Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/TestTargetResult"; [2025-03-30T21:23:08.745Z] [2025-03-30T21:23:08.745Z] TEST SETUP: [2025-03-30T21:23:08.745Z] Nothing to be done for setup. [2025-03-30T21:23:08.745Z] [2025-03-30T21:23:08.745Z] TESTING: [2025-03-30T21:23:17.000Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2025-03-30T21:23:18.588Z] WARNING: An illegal reflective access operation has occurred [2025-03-30T21:23:18.588Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17433693453819/renaissance-dec-tree_0/harness-212308-3683606148903593211/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-03-30T21:23:18.588Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-30T21:23:18.588Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-30T21:23:18.588Z] WARNING: All illegal access operations will be denied in a future release [2025-03-30T21:23:20.178Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-30T21:23:20.945Z] GC before operation: completed in 86.518 ms, heap usage 84.496 MB -> 35.156 MB. [2025-03-30T21:23:32.601Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11642.017 ms) ====== [2025-03-30T21:23:32.601Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-30T21:23:32.601Z] GC before operation: completed in 101.019 ms, heap usage 416.948 MB -> 71.218 MB. [2025-03-30T21:23:35.066Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2721.456 ms) ====== [2025-03-30T21:23:35.066Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-30T21:23:35.066Z] GC before operation: completed in 95.669 ms, heap usage 226.280 MB -> 71.582 MB. [2025-03-30T21:23:37.528Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2453.905 ms) ====== [2025-03-30T21:23:37.528Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-30T21:23:37.528Z] GC before operation: completed in 112.055 ms, heap usage 394.645 MB -> 72.085 MB. [2025-03-30T21:23:39.994Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2278.672 ms) ====== [2025-03-30T21:23:39.994Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-30T21:23:39.994Z] GC before operation: completed in 112.302 ms, heap usage 151.572 MB -> 71.753 MB. [2025-03-30T21:23:42.467Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2198.525 ms) ====== [2025-03-30T21:23:42.467Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-30T21:23:42.467Z] GC before operation: completed in 110.882 ms, heap usage 245.378 MB -> 99.748 MB. [2025-03-30T21:23:44.934Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2139.628 ms) ====== [2025-03-30T21:23:44.934Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-30T21:23:44.934Z] GC before operation: completed in 108.249 ms, heap usage 348.215 MB -> 72.720 MB. [2025-03-30T21:23:46.518Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2095.849 ms) ====== [2025-03-30T21:23:46.518Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-30T21:23:46.518Z] GC before operation: completed in 129.268 ms, heap usage 295.818 MB -> 72.700 MB. [2025-03-30T21:23:49.008Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2070.247 ms) ====== [2025-03-30T21:23:49.008Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-30T21:23:49.008Z] GC before operation: completed in 124.569 ms, heap usage 233.008 MB -> 72.825 MB. [2025-03-30T21:23:51.472Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2024.338 ms) ====== [2025-03-30T21:23:51.472Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-30T21:23:51.472Z] GC before operation: completed in 128.171 ms, heap usage 226.943 MB -> 72.938 MB. [2025-03-30T21:23:53.055Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2078.213 ms) ====== [2025-03-30T21:23:53.055Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-30T21:23:53.833Z] GC before operation: completed in 133.230 ms, heap usage 184.687 MB -> 72.753 MB. [2025-03-30T21:23:55.417Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2073.937 ms) ====== [2025-03-30T21:23:55.417Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-30T21:23:55.417Z] GC before operation: completed in 137.674 ms, heap usage 181.280 MB -> 89.428 MB. [2025-03-30T21:23:55.417Z] 21:23:55.180 WARN [block-manager-storage-async-thread-pool-31] org.apache.spark.storage.BlockManager - Block broadcast_149 could not be removed as it was not found on disk or in memory [2025-03-30T21:23:56.185Z] 21:23:55.387 WARN [block-manager-storage-async-thread-pool-31] org.apache.spark.storage.BlockManager - Block broadcast_149 was not removed normally. [2025-03-30T21:23:56.185Z] 21:23:55.389 ERROR [block-manager-storage-async-thread-pool-31] org.apache.spark.storage.BlockManagerStorageEndpoint - Error in removing broadcast 149 [2025-03-30T21:23:56.185Z] org.apache.spark.SparkException: Block broadcast_149 does not exist [2025-03-30T21:23:56.185Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] 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-03-30T21:23:56.185Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] 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-03-30T21:23:56.185Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] [2025-03-30T21:23:56.185Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] [2025-03-30T21:23:56.185Z] at java.lang.Thread.run(Thread.java:829) [?:?] [2025-03-30T21:23:56.185Z] 21:23:55.407 WARN [block-manager-ask-thread-pool-6] org.apache.spark.storage.BlockManagerMaster - Failed to remove broadcast 149 with removeFromMaster = true - Block broadcast_149 does not exist [2025-03-30T21:23:56.185Z] org.apache.spark.SparkException: Block broadcast_149 does not exist [2025-03-30T21:23:56.185Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.$anonfun$removeBroadcast$3(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] 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-03-30T21:23:56.185Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManager.removeBroadcast(BlockManager.scala:2029) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] 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-03-30T21:23:56.185Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-03-30T21:23:56.185Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2025-03-30T21:23:56.185Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] [2025-03-30T21:23:56.185Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] [2025-03-30T21:23:56.185Z] at java.lang.Thread.run(Thread.java:829) [?:?] [2025-03-30T21:23:57.777Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2068.668 ms) ====== [2025-03-30T21:23:57.777Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-30T21:23:57.777Z] GC before operation: completed in 139.052 ms, heap usage 197.509 MB -> 73.912 MB. [2025-03-30T21:24:00.239Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2028.178 ms) ====== [2025-03-30T21:24:00.239Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-30T21:24:00.239Z] GC before operation: completed in 142.089 ms, heap usage 268.951 MB -> 74.330 MB. [2025-03-30T21:24:01.835Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2009.390 ms) ====== [2025-03-30T21:24:01.835Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-30T21:24:01.835Z] GC before operation: completed in 145.145 ms, heap usage 152.828 MB -> 73.976 MB. [2025-03-30T21:24:04.300Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1981.474 ms) ====== [2025-03-30T21:24:04.300Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-30T21:24:04.300Z] GC before operation: completed in 145.110 ms, heap usage 246.046 MB -> 74.801 MB. [2025-03-30T21:24:05.888Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1919.127 ms) ====== [2025-03-30T21:24:05.888Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-30T21:24:06.655Z] GC before operation: completed in 145.470 ms, heap usage 245.855 MB -> 74.724 MB. [2025-03-30T21:24:08.248Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1896.545 ms) ====== [2025-03-30T21:24:08.248Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-30T21:24:08.248Z] GC before operation: completed in 150.429 ms, heap usage 277.719 MB -> 85.801 MB. [2025-03-30T21:24:10.715Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1922.197 ms) ====== [2025-03-30T21:24:10.715Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-30T21:24:10.715Z] GC before operation: completed in 146.832 ms, heap usage 328.186 MB -> 74.985 MB. [2025-03-30T21:24:12.306Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1927.769 ms) ====== [2025-03-30T21:24:12.306Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-30T21:24:12.306Z] GC before operation: completed in 151.177 ms, heap usage 322.232 MB -> 75.095 MB. [2025-03-30T21:24:14.776Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1959.515 ms) ====== [2025-03-30T21:24:14.776Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-30T21:24:14.776Z] GC before operation: completed in 143.457 ms, heap usage 334.344 MB -> 86.114 MB. [2025-03-30T21:24:16.357Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1926.415 ms) ====== [2025-03-30T21:24:16.357Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-30T21:24:17.124Z] GC before operation: completed in 181.336 ms, heap usage 205.409 MB -> 74.956 MB. [2025-03-30T21:24:18.719Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1984.397 ms) ====== [2025-03-30T21:24:18.719Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-30T21:24:18.719Z] GC before operation: completed in 184.553 ms, heap usage 229.821 MB -> 75.377 MB. [2025-03-30T21:24:21.190Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1910.581 ms) ====== [2025-03-30T21:24:21.190Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-30T21:24:21.190Z] GC before operation: completed in 195.691 ms, heap usage 445.764 MB -> 97.307 MB. [2025-03-30T21:24:22.771Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1864.415 ms) ====== [2025-03-30T21:24:22.771Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-30T21:24:22.771Z] GC before operation: completed in 185.746 ms, heap usage 390.040 MB -> 75.842 MB. [2025-03-30T21:24:25.253Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1916.687 ms) ====== [2025-03-30T21:24:25.253Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-30T21:24:25.253Z] GC before operation: completed in 177.648 ms, heap usage 260.926 MB -> 75.684 MB. [2025-03-30T21:24:26.846Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1906.306 ms) ====== [2025-03-30T21:24:26.846Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-30T21:24:27.615Z] GC before operation: completed in 180.152 ms, heap usage 361.229 MB -> 86.681 MB. [2025-03-30T21:24:29.207Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1884.383 ms) ====== [2025-03-30T21:24:29.207Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-30T21:24:29.207Z] GC before operation: completed in 164.736 ms, heap usage 124.674 MB -> 75.550 MB. [2025-03-30T21:24:31.686Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1874.085 ms) ====== [2025-03-30T21:24:31.686Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-30T21:24:31.686Z] GC before operation: completed in 156.261 ms, heap usage 362.698 MB -> 87.112 MB. [2025-03-30T21:24:33.269Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1875.585 ms) ====== [2025-03-30T21:24:33.269Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-30T21:24:33.269Z] GC before operation: completed in 156.987 ms, heap usage 191.350 MB -> 75.787 MB. [2025-03-30T21:24:35.735Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1893.433 ms) ====== [2025-03-30T21:24:35.735Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-30T21:24:35.735Z] GC before operation: completed in 178.448 ms, heap usage 245.153 MB -> 76.390 MB. [2025-03-30T21:24:35.735Z] 21:24:35.011 WARN [block-manager-storage-async-thread-pool-29] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-03-30T21:24:37.315Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1855.109 ms) ====== [2025-03-30T21:24:37.315Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-30T21:24:37.315Z] GC before operation: completed in 193.610 ms, heap usage 239.226 MB -> 76.300 MB. [2025-03-30T21:24:39.788Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1847.380 ms) ====== [2025-03-30T21:24:39.788Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-30T21:24:39.788Z] GC before operation: completed in 166.836 ms, heap usage 314.348 MB -> 76.272 MB. [2025-03-30T21:24:41.371Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1877.066 ms) ====== [2025-03-30T21:24:41.371Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-30T21:24:41.371Z] GC before operation: completed in 176.807 ms, heap usage 241.597 MB -> 82.197 MB. [2025-03-30T21:24:43.839Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1857.033 ms) ====== [2025-03-30T21:24:43.839Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-30T21:24:43.839Z] GC before operation: completed in 173.767 ms, heap usage 313.153 MB -> 87.545 MB. [2025-03-30T21:24:45.420Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1906.656 ms) ====== [2025-03-30T21:24:45.420Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-30T21:24:45.420Z] GC before operation: completed in 193.010 ms, heap usage 356.981 MB -> 76.767 MB. [2025-03-30T21:24:45.420Z] 21:24:45.261 WARN [block-manager-storage-async-thread-pool-81] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_485_piece0, which does not exist [2025-03-30T21:24:47.888Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1884.052 ms) ====== [2025-03-30T21:24:47.888Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-30T21:24:47.888Z] GC before operation: completed in 182.035 ms, heap usage 268.722 MB -> 76.850 MB. [2025-03-30T21:24:49.476Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1826.179 ms) ====== [2025-03-30T21:24:49.476Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-30T21:24:49.476Z] GC before operation: completed in 186.326 ms, heap usage 327.972 MB -> 87.850 MB. [2025-03-30T21:24:51.966Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1863.349 ms) ====== [2025-03-30T21:24:51.966Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-30T21:24:51.966Z] GC before operation: completed in 204.624 ms, heap usage 312.250 MB -> 88.209 MB. [2025-03-30T21:24:53.550Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1784.145 ms) ====== [2025-03-30T21:24:53.550Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-30T21:24:53.550Z] GC before operation: completed in 185.214 ms, heap usage 278.995 MB -> 77.169 MB. [2025-03-30T21:24:56.023Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1806.943 ms) ====== [2025-03-30T21:24:56.023Z] ----------------------------------- [2025-03-30T21:24:56.023Z] renaissance-dec-tree_0_PASSED [2025-03-30T21:24:56.023Z] ----------------------------------- [2025-03-30T21:24:56.023Z] [2025-03-30T21:24:56.023Z] TEST TEARDOWN: [2025-03-30T21:24:56.023Z] Nothing to be done for teardown. [2025-03-30T21:24:56.023Z] renaissance-dec-tree_0 Finish Time: Sun Mar 30 21:24:55 2025 Epoch Time (ms): 1743369895566