[2026-04-25T13:46:53.907Z] Running test renaissance-dec-tree_0 ... [2026-04-25T13:46:53.907Z] =============================================== [2026-04-25T13:46:53.907Z] renaissance-dec-tree_0 Start Time: Sat Apr 25 13:46:52 2026 Epoch Time (ms): 1777124812743 [2026-04-25T13:46:53.907Z] variation: NoOptions [2026-04-25T13:46:53.907Z] JVM_OPTIONS: [2026-04-25T13:46:53.907Z] { \ [2026-04-25T13:46:53.907Z] echo ""; echo "TEST SETUP:"; \ [2026-04-25T13:46:53.907Z] echo "Nothing to be done for setup."; \ [2026-04-25T13:46:53.907Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17771241728555/renaissance-dec-tree_0"; \ [2026-04-25T13:46:53.907Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17771241728555/renaissance-dec-tree_0"; \ [2026-04-25T13:46:53.907Z] echo ""; echo "TESTING:"; \ [2026-04-25T13:46:53.907Z] "/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_17771241728555/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-04-25T13:46:53.907Z] 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_17771241728555/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-25T13:46:53.907Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-25T13:46:53.907Z] echo "Nothing to be done for teardown."; \ [2026-04-25T13:46:53.907Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17771241728555/TestTargetResult"; [2026-04-25T13:46:53.907Z] [2026-04-25T13:46:53.907Z] TEST SETUP: [2026-04-25T13:46:53.907Z] Nothing to be done for setup. [2026-04-25T13:46:53.907Z] [2026-04-25T13:46:53.907Z] TESTING: [2026-04-25T13:46:58.225Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-25T13:46:59.758Z] WARNING: An illegal reflective access operation has occurred [2026-04-25T13:46:59.758Z] 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_17771241728555/renaissance-dec-tree_0/harness-134653-1918454414258708802/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-25T13:46:59.758Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-25T13:46:59.758Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-25T13:46:59.758Z] WARNING: All illegal access operations will be denied in a future release [2026-04-25T13:47:01.290Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-04-25T13:47:01.290Z] GC before operation: completed in 49.186 ms, heap usage 81.950 MB -> 34.392 MB. [2026-04-25T13:47:16.081Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (12169.995 ms) ====== [2026-04-25T13:47:16.081Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-04-25T13:47:16.081Z] GC before operation: completed in 93.840 ms, heap usage 286.879 MB -> 68.394 MB. [2026-04-25T13:47:16.827Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3902.020 ms) ====== [2026-04-25T13:47:16.827Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-04-25T13:47:17.577Z] GC before operation: completed in 102.358 ms, heap usage 255.966 MB -> 68.604 MB. [2026-04-25T13:47:19.118Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2123.102 ms) ====== [2026-04-25T13:47:19.118Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-04-25T13:47:19.861Z] GC before operation: completed in 121.533 ms, heap usage 131.323 MB -> 69.074 MB. [2026-04-25T13:47:21.405Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1828.821 ms) ====== [2026-04-25T13:47:21.405Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-04-25T13:47:21.405Z] GC before operation: completed in 108.064 ms, heap usage 136.813 MB -> 69.223 MB. [2026-04-25T13:47:22.942Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1690.942 ms) ====== [2026-04-25T13:47:22.942Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-04-25T13:47:22.942Z] GC before operation: completed in 119.562 ms, heap usage 206.048 MB -> 69.450 MB. [2026-04-25T13:47:28.400Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (5194.688 ms) ====== [2026-04-25T13:47:28.400Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-04-25T13:47:28.400Z] GC before operation: completed in 108.445 ms, heap usage 153.234 MB -> 69.640 MB. [2026-04-25T13:47:32.758Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4200.708 ms) ====== [2026-04-25T13:47:32.758Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-04-25T13:47:32.758Z] GC before operation: completed in 106.374 ms, heap usage 219.170 MB -> 69.859 MB. [2026-04-25T13:47:34.296Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1471.408 ms) ====== [2026-04-25T13:47:34.296Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-04-25T13:47:34.296Z] GC before operation: completed in 104.784 ms, heap usage 147.209 MB -> 69.816 MB. [2026-04-25T13:47:36.792Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1347.736 ms) ====== [2026-04-25T13:47:36.792Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-04-25T13:47:36.792Z] GC before operation: completed in 111.290 ms, heap usage 108.959 MB -> 69.771 MB. [2026-04-25T13:47:42.227Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (6643.974 ms) ====== [2026-04-25T13:47:42.227Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-04-25T13:47:42.976Z] GC before operation: completed in 111.593 ms, heap usage 131.556 MB -> 70.193 MB. [2026-04-25T13:47:43.719Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1316.566 ms) ====== [2026-04-25T13:47:43.719Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-04-25T13:47:44.469Z] GC before operation: completed in 123.806 ms, heap usage 204.963 MB -> 70.414 MB. [2026-04-25T13:47:46.033Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1508.673 ms) ====== [2026-04-25T13:47:46.033Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-04-25T13:47:46.033Z] GC before operation: completed in 110.826 ms, heap usage 172.369 MB -> 70.447 MB. [2026-04-25T13:47:46.776Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1388.965 ms) ====== [2026-04-25T13:47:46.776Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-04-25T13:47:47.526Z] GC before operation: completed in 137.629 ms, heap usage 271.348 MB -> 70.560 MB. [2026-04-25T13:47:49.067Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1465.383 ms) ====== [2026-04-25T13:47:49.067Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-04-25T13:47:49.067Z] GC before operation: completed in 126.689 ms, heap usage 253.091 MB -> 70.540 MB. [2026-04-25T13:47:50.628Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1334.070 ms) ====== [2026-04-25T13:47:50.628Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-04-25T13:47:50.628Z] GC before operation: completed in 115.802 ms, heap usage 208.354 MB -> 70.809 MB. [2026-04-25T13:47:51.370Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1341.343 ms) ====== [2026-04-25T13:47:51.370Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-04-25T13:47:52.119Z] GC before operation: completed in 117.359 ms, heap usage 224.002 MB -> 70.990 MB. [2026-04-25T13:47:52.862Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1418.363 ms) ====== [2026-04-25T13:47:52.862Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-04-25T13:47:53.614Z] GC before operation: completed in 130.067 ms, heap usage 155.281 MB -> 71.094 MB. [2026-04-25T13:47:54.356Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1283.131 ms) ====== [2026-04-25T13:47:54.356Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-04-25T13:47:55.100Z] GC before operation: completed in 118.324 ms, heap usage 106.023 MB -> 70.906 MB. [2026-04-25T13:47:55.843Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1365.760 ms) ====== [2026-04-25T13:47:55.843Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-04-25T13:47:56.586Z] GC before operation: completed in 120.196 ms, heap usage 204.920 MB -> 82.458 MB. [2026-04-25T13:47:57.331Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1311.880 ms) ====== [2026-04-25T13:47:57.331Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-04-25T13:47:57.331Z] GC before operation: completed in 120.787 ms, heap usage 256.575 MB -> 71.486 MB. [2026-04-25T13:47:58.866Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1257.378 ms) ====== [2026-04-25T13:47:58.866Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-04-25T13:47:58.866Z] GC before operation: completed in 124.856 ms, heap usage 255.202 MB -> 87.863 MB. [2026-04-25T13:48:00.411Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1270.197 ms) ====== [2026-04-25T13:48:00.411Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-04-25T13:48:00.411Z] GC before operation: completed in 123.300 ms, heap usage 159.299 MB -> 71.615 MB. [2026-04-25T13:48:01.953Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1242.568 ms) ====== [2026-04-25T13:48:01.953Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-04-25T13:48:01.953Z] GC before operation: completed in 130.207 ms, heap usage 173.551 MB -> 71.743 MB. [2026-04-25T13:48:03.488Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1279.186 ms) ====== [2026-04-25T13:48:03.488Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-04-25T13:48:03.488Z] GC before operation: completed in 123.829 ms, heap usage 268.341 MB -> 82.596 MB. [2026-04-25T13:48:04.230Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1248.664 ms) ====== [2026-04-25T13:48:04.230Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-04-25T13:48:04.230Z] GC before operation: completed in 120.128 ms, heap usage 117.387 MB -> 71.649 MB. [2026-04-25T13:48:05.836Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1344.022 ms) ====== [2026-04-25T13:48:05.836Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-04-25T13:48:05.836Z] GC before operation: completed in 124.956 ms, heap usage 211.428 MB -> 83.161 MB. [2026-04-25T13:48:07.373Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1247.918 ms) ====== [2026-04-25T13:48:07.373Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-04-25T13:48:07.373Z] GC before operation: completed in 127.501 ms, heap usage 146.053 MB -> 72.115 MB. [2026-04-25T13:48:08.910Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1219.273 ms) ====== [2026-04-25T13:48:08.910Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-04-25T13:48:08.910Z] GC before operation: completed in 122.577 ms, heap usage 261.623 MB -> 83.165 MB. [2026-04-25T13:48:09.659Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1212.247 ms) ====== [2026-04-25T13:48:09.659Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-04-25T13:48:10.401Z] GC before operation: completed in 136.954 ms, heap usage 366.425 MB -> 83.241 MB. [2026-04-25T13:48:11.148Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1204.020 ms) ====== [2026-04-25T13:48:11.148Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-04-25T13:48:11.148Z] GC before operation: completed in 130.402 ms, heap usage 170.146 MB -> 83.595 MB. [2026-04-25T13:48:11.148Z] 13:48:11.011 WARN [block-manager-storage-async-thread-pool-79] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2026-04-25T13:48:12.687Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1249.707 ms) ====== [2026-04-25T13:48:12.687Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-04-25T13:48:12.687Z] GC before operation: completed in 140.894 ms, heap usage 320.176 MB -> 88.941 MB. [2026-04-25T13:48:12.687Z] 13:48:12.400 WARN [block-manager-storage-async-thread-pool-44] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1485_2, which does not exist [2026-04-25T13:48:14.232Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1259.174 ms) ====== [2026-04-25T13:48:14.232Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-04-25T13:48:14.232Z] GC before operation: completed in 128.399 ms, heap usage 266.712 MB -> 83.471 MB. [2026-04-25T13:48:15.767Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1219.016 ms) ====== [2026-04-25T13:48:15.767Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-04-25T13:48:15.767Z] GC before operation: completed in 144.409 ms, heap usage 156.992 MB -> 88.854 MB. [2026-04-25T13:48:15.767Z] 13:48:15.152 WARN [block-manager-storage-async-thread-pool-95] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1581_2, which does not exist [2026-04-25T13:48:16.509Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1168.313 ms) ====== [2026-04-25T13:48:16.509Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-04-25T13:48:17.253Z] GC before operation: completed in 136.045 ms, heap usage 263.193 MB -> 83.677 MB. [2026-04-25T13:48:17.999Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1172.432 ms) ====== [2026-04-25T13:48:17.999Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-04-25T13:48:17.999Z] GC before operation: completed in 130.212 ms, heap usage 223.755 MB -> 73.034 MB. [2026-04-25T13:48:19.659Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1344.701 ms) ====== [2026-04-25T13:48:19.659Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-04-25T13:48:19.659Z] GC before operation: completed in 133.883 ms, heap usage 242.842 MB -> 84.110 MB. [2026-04-25T13:48:21.202Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1222.578 ms) ====== [2026-04-25T13:48:21.203Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-04-25T13:48:21.203Z] GC before operation: completed in 132.814 ms, heap usage 200.154 MB -> 73.247 MB. [2026-04-25T13:48:22.863Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1331.793 ms) ====== [2026-04-25T13:48:22.863Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-04-25T13:48:22.863Z] GC before operation: completed in 152.794 ms, heap usage 149.799 MB -> 83.914 MB. [2026-04-25T13:48:23.607Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1214.106 ms) ====== [2026-04-25T13:48:23.607Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-04-25T13:48:23.607Z] GC before operation: completed in 125.133 ms, heap usage 117.704 MB -> 73.006 MB. [2026-04-25T13:48:25.149Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1180.886 ms) ====== [2026-04-25T13:48:25.149Z] ----------------------------------- [2026-04-25T13:48:25.149Z] renaissance-dec-tree_0_PASSED [2026-04-25T13:48:25.149Z] ----------------------------------- [2026-04-25T13:48:25.149Z] [2026-04-25T13:48:25.149Z] TEST TEARDOWN: [2026-04-25T13:48:25.149Z] Nothing to be done for teardown. [2026-04-25T13:48:25.149Z] renaissance-dec-tree_0 Finish Time: Sat Apr 25 13:48:24 2026 Epoch Time (ms): 1777124904897