[2025-12-24T22:25:20.402Z] Running test renaissance-dec-tree_0 ... [2025-12-24T22:25:20.402Z] =============================================== [2025-12-24T22:25:20.402Z] renaissance-dec-tree_0 Start Time: Wed Dec 24 14:25:20 2025 Epoch Time (ms): 1766615120151 [2025-12-24T22:25:20.402Z] variation: NoOptions [2025-12-24T22:25:20.402Z] JVM_OPTIONS: [2025-12-24T22:25:20.402Z] { \ [2025-12-24T22:25:20.402Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T22:25:20.402Z] echo "Nothing to be done for setup."; \ [2025-12-24T22:25:20.402Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17666141482823/renaissance-dec-tree_0"; \ [2025-12-24T22:25:20.402Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17666141482823/renaissance-dec-tree_0"; \ [2025-12-24T22:25:20.402Z] echo ""; echo "TESTING:"; \ [2025-12-24T22:25:20.402Z] "/Users/admin/workspace/workspace/Test_openjdk21_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/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17666141482823/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-12-24T22:25:20.402Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17666141482823/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T22:25:20.402Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T22:25:20.402Z] echo "Nothing to be done for teardown."; \ [2025-12-24T22:25:20.402Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17666141482823/TestTargetResult"; [2025-12-24T22:25:20.791Z] [2025-12-24T22:25:20.791Z] TEST SETUP: [2025-12-24T22:25:20.791Z] Nothing to be done for setup. [2025-12-24T22:25:20.791Z] [2025-12-24T22:25:20.791Z] TESTING: [2025-12-24T22:25:28.942Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-24T22:25:31.672Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-12-24T22:25:31.672Z] GC before operation: completed in 115.395 ms, heap usage 92.295 MB -> 34.290 MB. [2025-12-24T22:25:46.020Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (14285.757 ms) ====== [2025-12-24T22:25:46.020Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-12-24T22:25:46.406Z] GC before operation: completed in 206.956 ms, heap usage 449.248 MB -> 70.297 MB. [2025-12-24T22:25:48.429Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2325.344 ms) ====== [2025-12-24T22:25:48.429Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-12-24T22:25:48.841Z] GC before operation: completed in 97.456 ms, heap usage 310.293 MB -> 70.713 MB. [2025-12-24T22:25:51.694Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2900.689 ms) ====== [2025-12-24T22:25:51.694Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-12-24T22:25:51.694Z] GC before operation: completed in 75.035 ms, heap usage 137.794 MB -> 81.739 MB. [2025-12-24T22:25:51.694Z] 14:25:51.280 WARN [block-manager-storage-async-thread-pool-85] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_37, which does not exist [2025-12-24T22:25:54.390Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2443.019 ms) ====== [2025-12-24T22:25:54.390Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-12-24T22:25:54.390Z] GC before operation: completed in 77.189 ms, heap usage 417.630 MB -> 71.227 MB. [2025-12-24T22:25:56.831Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2397.117 ms) ====== [2025-12-24T22:25:56.831Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-12-24T22:25:56.831Z] GC before operation: completed in 271.442 ms, heap usage 278.371 MB -> 71.409 MB. [2025-12-24T22:25:58.798Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1637.886 ms) ====== [2025-12-24T22:25:58.798Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-12-24T22:25:58.798Z] GC before operation: completed in 78.523 ms, heap usage 302.957 MB -> 99.175 MB. [2025-12-24T22:26:01.907Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2917.125 ms) ====== [2025-12-24T22:26:01.907Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-12-24T22:26:01.907Z] GC before operation: completed in 79.593 ms, heap usage 254.382 MB -> 71.702 MB. [2025-12-24T22:26:03.986Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2412.195 ms) ====== [2025-12-24T22:26:03.986Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-12-24T22:26:03.986Z] GC before operation: completed in 76.770 ms, heap usage 380.741 MB -> 71.827 MB. [2025-12-24T22:26:06.004Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2046.944 ms) ====== [2025-12-24T22:26:06.004Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-12-24T22:26:06.394Z] GC before operation: completed in 109.064 ms, heap usage 121.804 MB -> 71.629 MB. [2025-12-24T22:26:08.349Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2143.312 ms) ====== [2025-12-24T22:26:08.349Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-12-24T22:26:08.349Z] GC before operation: completed in 81.777 ms, heap usage 352.560 MB -> 72.089 MB. [2025-12-24T22:26:10.341Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1499.742 ms) ====== [2025-12-24T22:26:10.341Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-12-24T22:26:10.341Z] GC before operation: completed in 84.049 ms, heap usage 395.398 MB -> 72.221 MB. [2025-12-24T22:26:12.356Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2063.655 ms) ====== [2025-12-24T22:26:12.356Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-12-24T22:26:12.356Z] GC before operation: completed in 75.009 ms, heap usage 120.814 MB -> 71.716 MB. [2025-12-24T22:26:13.744Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1576.993 ms) ====== [2025-12-24T22:26:13.744Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-12-24T22:26:13.744Z] GC before operation: completed in 116.650 ms, heap usage 418.791 MB -> 72.810 MB. [2025-12-24T22:26:16.610Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2450.101 ms) ====== [2025-12-24T22:26:16.610Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-12-24T22:26:16.610Z] GC before operation: completed in 95.453 ms, heap usage 389.082 MB -> 72.583 MB. [2025-12-24T22:26:19.398Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2629.089 ms) ====== [2025-12-24T22:26:19.398Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-12-24T22:26:19.398Z] GC before operation: completed in 139.882 ms, heap usage 109.148 MB -> 72.077 MB. [2025-12-24T22:26:22.106Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2469.103 ms) ====== [2025-12-24T22:26:22.106Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-12-24T22:26:22.106Z] GC before operation: completed in 78.344 ms, heap usage 505.565 MB -> 73.189 MB. [2025-12-24T22:26:23.586Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1482.892 ms) ====== [2025-12-24T22:26:23.586Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-12-24T22:26:23.586Z] GC before operation: completed in 412.819 ms, heap usage 430.654 MB -> 73.263 MB. [2025-12-24T22:26:25.594Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1889.734 ms) ====== [2025-12-24T22:26:25.594Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-12-24T22:26:25.594Z] GC before operation: completed in 89.452 ms, heap usage 506.416 MB -> 73.066 MB. [2025-12-24T22:26:26.954Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1501.583 ms) ====== [2025-12-24T22:26:27.396Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-12-24T22:26:27.396Z] GC before operation: completed in 378.948 ms, heap usage 138.238 MB -> 72.807 MB. [2025-12-24T22:26:29.335Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1552.305 ms) ====== [2025-12-24T22:26:29.335Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-12-24T22:26:29.335Z] GC before operation: completed in 111.142 ms, heap usage 163.150 MB -> 72.916 MB. [2025-12-24T22:26:31.285Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2156.599 ms) ====== [2025-12-24T22:26:31.285Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-12-24T22:26:31.285Z] GC before operation: completed in 81.731 ms, heap usage 118.785 MB -> 100.782 MB. [2025-12-24T22:26:33.217Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1764.371 ms) ====== [2025-12-24T22:26:33.218Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-12-24T22:26:33.691Z] GC before operation: completed in 441.740 ms, heap usage 460.573 MB -> 73.456 MB. [2025-12-24T22:26:35.658Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2029.504 ms) ====== [2025-12-24T22:26:35.658Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-12-24T22:26:35.658Z] GC before operation: completed in 134.503 ms, heap usage 543.248 MB -> 73.810 MB. [2025-12-24T22:26:38.345Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2150.704 ms) ====== [2025-12-24T22:26:38.345Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-12-24T22:26:38.346Z] GC before operation: completed in 112.290 ms, heap usage 380.474 MB -> 73.674 MB. [2025-12-24T22:26:39.668Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1412.771 ms) ====== [2025-12-24T22:26:39.668Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-12-24T22:26:39.668Z] GC before operation: completed in 86.772 ms, heap usage 342.476 MB -> 73.781 MB. [2025-12-24T22:26:41.648Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1970.957 ms) ====== [2025-12-24T22:26:41.648Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-12-24T22:26:41.648Z] GC before operation: completed in 91.967 ms, heap usage 408.560 MB -> 73.871 MB. [2025-12-24T22:26:42.977Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1189.700 ms) ====== [2025-12-24T22:26:42.977Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-12-24T22:26:42.977Z] GC before operation: completed in 117.382 ms, heap usage 253.316 MB -> 73.984 MB. [2025-12-24T22:26:44.940Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2046.378 ms) ====== [2025-12-24T22:26:44.940Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-12-24T22:26:44.940Z] GC before operation: completed in 90.164 ms, heap usage 327.091 MB -> 74.092 MB. [2025-12-24T22:26:46.284Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1364.668 ms) ====== [2025-12-24T22:26:46.284Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-12-24T22:26:46.670Z] GC before operation: completed in 91.016 ms, heap usage 308.684 MB -> 85.093 MB. [2025-12-24T22:26:49.478Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2583.904 ms) ====== [2025-12-24T22:26:49.478Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-12-24T22:26:49.478Z] GC before operation: completed in 96.781 ms, heap usage 321.357 MB -> 74.438 MB. [2025-12-24T22:26:51.604Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2275.594 ms) ====== [2025-12-24T22:26:51.604Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-12-24T22:26:51.604Z] GC before operation: completed in 85.079 ms, heap usage 109.504 MB -> 74.160 MB. [2025-12-24T22:26:52.989Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1461.222 ms) ====== [2025-12-24T22:26:52.989Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-12-24T22:26:52.989Z] GC before operation: completed in 81.854 ms, heap usage 432.890 MB -> 74.521 MB. [2025-12-24T22:26:55.715Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2304.432 ms) ====== [2025-12-24T22:26:55.715Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-12-24T22:26:55.715Z] GC before operation: completed in 105.501 ms, heap usage 120.377 MB -> 74.129 MB. [2025-12-24T22:26:58.456Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2487.554 ms) ====== [2025-12-24T22:26:58.456Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-12-24T22:26:58.456Z] GC before operation: completed in 150.230 ms, heap usage 110.341 MB -> 74.650 MB. [2025-12-24T22:27:00.495Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2392.791 ms) ====== [2025-12-24T22:27:00.495Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-12-24T22:27:00.495Z] GC before operation: completed in 114.276 ms, heap usage 113.904 MB -> 74.222 MB. [2025-12-24T22:27:03.253Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2090.503 ms) ====== [2025-12-24T22:27:03.253Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-12-24T22:27:03.253Z] GC before operation: completed in 109.030 ms, heap usage 297.959 MB -> 75.070 MB. [2025-12-24T22:27:04.637Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1447.826 ms) ====== [2025-12-24T22:27:04.637Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-12-24T22:27:04.637Z] GC before operation: completed in 344.285 ms, heap usage 335.708 MB -> 75.045 MB. [2025-12-24T22:27:06.639Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1997.898 ms) ====== [2025-12-24T22:27:06.639Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-12-24T22:27:06.639Z] GC before operation: completed in 109.699 ms, heap usage 120.639 MB -> 74.650 MB. [2025-12-24T22:27:09.321Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2248.231 ms) ====== [2025-12-24T22:27:09.321Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-12-24T22:27:09.321Z] GC before operation: completed in 122.000 ms, heap usage 323.356 MB -> 75.389 MB. [2025-12-24T22:27:11.418Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2053.226 ms) ====== [2025-12-24T22:27:11.418Z] ----------------------------------- [2025-12-24T22:27:11.418Z] renaissance-dec-tree_0_PASSED [2025-12-24T22:27:11.418Z] ----------------------------------- [2025-12-24T22:27:11.418Z] [2025-12-24T22:27:11.418Z] TEST TEARDOWN: [2025-12-24T22:27:11.418Z] Nothing to be done for teardown. [2025-12-24T22:27:11.418Z] renaissance-dec-tree_0 Finish Time: Wed Dec 24 14:27:11 2025 Epoch Time (ms): 1766615231172