[2026-04-21T22:53:40.404Z] Running test renaissance-dec-tree_0 ... [2026-04-21T22:53:40.404Z] =============================================== [2026-04-21T22:53:40.405Z] renaissance-dec-tree_0 Start Time: Tue Apr 21 22:53:39 2026 Epoch Time (ms): 1776812019632 [2026-04-21T22:53:40.405Z] variation: NoOptions [2026-04-21T22:53:40.405Z] JVM_OPTIONS: [2026-04-21T22:53:40.405Z] { \ [2026-04-21T22:53:40.405Z] echo ""; echo "TEST SETUP:"; \ [2026-04-21T22:53:40.405Z] echo "Nothing to be done for setup."; \ [2026-04-21T22:53:40.405Z] mkdir -p "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768115196129/renaissance-dec-tree_0"; \ [2026-04-21T22:53:40.405Z] cd "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768115196129/renaissance-dec-tree_0"; \ [2026-04-21T22:53:40.405Z] echo ""; echo "TESTING:"; \ [2026-04-21T22:53:40.405Z] "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/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/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768115196129/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-04-21T22:53:40.405Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768115196129/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-21T22:53:40.405Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-21T22:53:40.405Z] echo "Nothing to be done for teardown."; \ [2026-04-21T22:53:40.405Z] } 2>&1 | tee -a "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768115196129/TestTargetResult"; [2026-04-21T22:53:40.405Z] [2026-04-21T22:53:40.405Z] TEST SETUP: [2026-04-21T22:53:40.405Z] Nothing to be done for setup. [2026-04-21T22:53:40.405Z] [2026-04-21T22:53:40.405Z] TESTING: [2026-04-21T22:53:51.036Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-21T22:53:52.790Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-04-21T22:53:52.790Z] GC before operation: completed in 28.465 ms, heap usage 46.509 MB -> 34.687 MB. [2026-04-21T22:54:00.327Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7758.040 ms) ====== [2026-04-21T22:54:00.327Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-04-21T22:54:01.253Z] GC before operation: completed in 73.102 ms, heap usage 288.713 MB -> 70.792 MB. [2026-04-21T22:54:03.005Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1848.420 ms) ====== [2026-04-21T22:54:03.005Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-04-21T22:54:03.005Z] GC before operation: completed in 65.426 ms, heap usage 216.138 MB -> 71.239 MB. [2026-04-21T22:54:03.858Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1531.814 ms) ====== [2026-04-21T22:54:03.858Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-04-21T22:54:04.744Z] GC before operation: completed in 65.899 ms, heap usage 229.294 MB -> 71.503 MB. [2026-04-21T22:54:05.596Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1380.876 ms) ====== [2026-04-21T22:54:05.596Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-04-21T22:54:05.596Z] GC before operation: completed in 58.394 ms, heap usage 375.692 MB -> 71.756 MB. [2026-04-21T22:54:07.358Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1270.904 ms) ====== [2026-04-21T22:54:07.358Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-04-21T22:54:07.358Z] GC before operation: completed in 57.953 ms, heap usage 361.335 MB -> 71.970 MB. [2026-04-21T22:54:08.212Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1278.706 ms) ====== [2026-04-21T22:54:08.212Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-04-21T22:54:08.212Z] GC before operation: completed in 70.369 ms, heap usage 183.640 MB -> 71.880 MB. [2026-04-21T22:54:09.974Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1799.048 ms) ====== [2026-04-21T22:54:09.974Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-04-21T22:54:09.974Z] GC before operation: completed in 62.547 ms, heap usage 342.297 MB -> 72.756 MB. [2026-04-21T22:54:11.765Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1164.132 ms) ====== [2026-04-21T22:54:11.765Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-04-21T22:54:11.765Z] GC before operation: completed in 57.883 ms, heap usage 159.990 MB -> 72.176 MB. [2026-04-21T22:54:12.646Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1149.646 ms) ====== [2026-04-21T22:54:12.646Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-04-21T22:54:12.646Z] GC before operation: completed in 57.154 ms, heap usage 392.920 MB -> 72.670 MB. [2026-04-21T22:54:13.510Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1066.162 ms) ====== [2026-04-21T22:54:13.510Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-04-21T22:54:13.510Z] GC before operation: completed in 70.483 ms, heap usage 344.374 MB -> 72.793 MB. [2026-04-21T22:54:15.263Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1310.340 ms) ====== [2026-04-21T22:54:15.263Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-04-21T22:54:15.263Z] GC before operation: completed in 72.815 ms, heap usage 310.710 MB -> 73.144 MB. [2026-04-21T22:54:16.118Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1118.158 ms) ====== [2026-04-21T22:54:16.118Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-04-21T22:54:16.118Z] GC before operation: completed in 65.095 ms, heap usage 318.491 MB -> 73.057 MB. [2026-04-21T22:54:17.887Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1026.625 ms) ====== [2026-04-21T22:54:17.887Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-04-21T22:54:17.887Z] GC before operation: completed in 58.777 ms, heap usage 342.866 MB -> 73.332 MB. [2026-04-21T22:54:18.746Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (992.428 ms) ====== [2026-04-21T22:54:18.746Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-04-21T22:54:18.746Z] GC before operation: completed in 65.860 ms, heap usage 314.544 MB -> 84.224 MB. [2026-04-21T22:54:19.598Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1057.483 ms) ====== [2026-04-21T22:54:19.598Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-04-21T22:54:19.598Z] GC before operation: completed in 65.285 ms, heap usage 185.255 MB -> 83.947 MB. [2026-04-21T22:54:20.451Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1124.934 ms) ====== [2026-04-21T22:54:20.451Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-04-21T22:54:21.362Z] GC before operation: completed in 73.230 ms, heap usage 515.368 MB -> 73.965 MB. [2026-04-21T22:54:22.214Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1060.668 ms) ====== [2026-04-21T22:54:22.214Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-04-21T22:54:22.214Z] GC before operation: completed in 74.701 ms, heap usage 296.841 MB -> 84.982 MB. [2026-04-21T22:54:23.101Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (992.709 ms) ====== [2026-04-21T22:54:23.101Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-04-21T22:54:23.101Z] GC before operation: completed in 64.350 ms, heap usage 177.824 MB -> 84.669 MB. [2026-04-21T22:54:23.959Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (974.664 ms) ====== [2026-04-21T22:54:23.959Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-04-21T22:54:23.959Z] GC before operation: completed in 69.846 ms, heap usage 516.928 MB -> 96.154 MB. [2026-04-21T22:54:24.811Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (978.992 ms) ====== [2026-04-21T22:54:24.811Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-04-21T22:54:24.811Z] GC before operation: completed in 69.410 ms, heap usage 365.881 MB -> 85.401 MB. [2026-04-21T22:54:26.566Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1103.169 ms) ====== [2026-04-21T22:54:26.566Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-04-21T22:54:26.566Z] GC before operation: completed in 69.932 ms, heap usage 162.904 MB -> 73.951 MB. [2026-04-21T22:54:27.419Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1266.986 ms) ====== [2026-04-21T22:54:27.419Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-04-21T22:54:27.419Z] GC before operation: completed in 69.700 ms, heap usage 187.075 MB -> 74.040 MB. [2026-04-21T22:54:28.271Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (945.130 ms) ====== [2026-04-21T22:54:28.271Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-04-21T22:54:28.271Z] GC before operation: completed in 68.962 ms, heap usage 280.108 MB -> 91.053 MB. [2026-04-21T22:54:30.022Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (958.657 ms) ====== [2026-04-21T22:54:30.022Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-04-21T22:54:30.022Z] GC before operation: completed in 68.378 ms, heap usage 420.443 MB -> 91.047 MB. [2026-04-21T22:54:30.892Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (932.359 ms) ====== [2026-04-21T22:54:30.892Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-04-21T22:54:30.892Z] GC before operation: completed in 71.732 ms, heap usage 416.866 MB -> 91.165 MB. [2026-04-21T22:54:31.761Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1138.050 ms) ====== [2026-04-21T22:54:31.761Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-04-21T22:54:31.761Z] GC before operation: completed in 94.877 ms, heap usage 407.697 MB -> 86.049 MB. [2026-04-21T22:54:33.539Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1228.210 ms) ====== [2026-04-21T22:54:33.539Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-04-21T22:54:33.539Z] GC before operation: completed in 102.569 ms, heap usage 519.003 MB -> 86.769 MB. [2026-04-21T22:54:34.389Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (944.414 ms) ====== [2026-04-21T22:54:34.389Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-04-21T22:54:34.389Z] GC before operation: completed in 70.218 ms, heap usage 505.828 MB -> 91.760 MB. [2026-04-21T22:54:35.240Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1075.047 ms) ====== [2026-04-21T22:54:35.240Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-04-21T22:54:35.240Z] GC before operation: completed in 75.961 ms, heap usage 383.289 MB -> 97.140 MB. [2026-04-21T22:54:36.094Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (959.183 ms) ====== [2026-04-21T22:54:36.095Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-04-21T22:54:36.095Z] GC before operation: completed in 74.922 ms, heap usage 565.170 MB -> 86.661 MB. [2026-04-21T22:54:37.847Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (976.026 ms) ====== [2026-04-21T22:54:37.847Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-04-21T22:54:37.847Z] GC before operation: completed in 72.178 ms, heap usage 307.714 MB -> 91.891 MB. [2026-04-21T22:54:38.698Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1167.318 ms) ====== [2026-04-21T22:54:38.698Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-04-21T22:54:38.698Z] GC before operation: completed in 81.646 ms, heap usage 415.718 MB -> 75.471 MB. [2026-04-21T22:54:39.601Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1035.937 ms) ====== [2026-04-21T22:54:39.602Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-04-21T22:54:39.602Z] GC before operation: completed in 70.678 ms, heap usage 409.876 MB -> 92.086 MB. [2026-04-21T22:54:40.457Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (935.679 ms) ====== [2026-04-21T22:54:40.457Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-04-21T22:54:41.327Z] GC before operation: completed in 72.459 ms, heap usage 388.284 MB -> 92.130 MB. [2026-04-21T22:54:41.327Z] 22:54:40.203 WARN [block-manager-storage-async-thread-pool-16] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_471, which does not exist [2026-04-21T22:54:42.286Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1083.563 ms) ====== [2026-04-21T22:54:42.286Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-04-21T22:54:42.286Z] GC before operation: completed in 97.718 ms, heap usage 412.395 MB -> 87.037 MB. [2026-04-21T22:54:43.173Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1231.626 ms) ====== [2026-04-21T22:54:43.173Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-04-21T22:54:43.173Z] GC before operation: completed in 96.372 ms, heap usage 426.445 MB -> 97.862 MB. [2026-04-21T22:54:45.001Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1234.447 ms) ====== [2026-04-21T22:54:45.001Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-04-21T22:54:45.001Z] GC before operation: completed in 76.341 ms, heap usage 305.199 MB -> 92.546 MB. [2026-04-21T22:54:45.850Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (968.100 ms) ====== [2026-04-21T22:54:45.850Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-04-21T22:54:45.850Z] GC before operation: completed in 69.961 ms, heap usage 172.188 MB -> 81.371 MB. [2026-04-21T22:54:46.704Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (970.111 ms) ====== [2026-04-21T22:54:46.704Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-04-21T22:54:46.704Z] GC before operation: completed in 85.600 ms, heap usage 509.535 MB -> 92.994 MB. [2026-04-21T22:54:47.555Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (976.975 ms) ====== [2026-04-21T22:54:48.409Z] ----------------------------------- [2026-04-21T22:54:48.409Z] renaissance-dec-tree_0_PASSED [2026-04-21T22:54:48.409Z] ----------------------------------- [2026-04-21T22:54:48.409Z] [2026-04-21T22:54:48.409Z] TEST TEARDOWN: [2026-04-21T22:54:48.409Z] Nothing to be done for teardown. [2026-04-21T22:54:48.409Z] renaissance-dec-tree_0 Finish Time: Tue Apr 21 22:54:47 2026 Epoch Time (ms): 1776812087379