[2025-09-05T17:05:50.080Z] Running test renaissance-dec-tree_0 ... [2025-09-05T17:05:50.080Z] =============================================== [2025-09-05T17:05:50.080Z] renaissance-dec-tree_0 Start Time: Fri Sep 5 10:05:49 2025 Epoch Time (ms): 1757091949371 [2025-09-05T17:05:50.080Z] variation: NoOptions [2025-09-05T17:05:50.080Z] JVM_OPTIONS: [2025-09-05T17:05:50.080Z] { \ [2025-09-05T17:05:50.080Z] echo ""; echo "TEST SETUP:"; \ [2025-09-05T17:05:50.080Z] echo "Nothing to be done for setup."; \ [2025-09-05T17:05:50.080Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17570903486685/renaissance-dec-tree_0"; \ [2025-09-05T17:05:50.080Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17570903486685/renaissance-dec-tree_0"; \ [2025-09-05T17:05:50.080Z] echo ""; echo "TESTING:"; \ [2025-09-05T17:05:50.080Z] "/Users/admin/workspace/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17570903486685/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-09-05T17:05:50.080Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17570903486685/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-05T17:05:50.080Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-05T17:05:50.080Z] echo "Nothing to be done for teardown."; \ [2025-09-05T17:05:50.080Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17570903486685/TestTargetResult"; [2025-09-05T17:05:50.080Z] [2025-09-05T17:05:50.080Z] TEST SETUP: [2025-09-05T17:05:50.080Z] Nothing to be done for setup. [2025-09-05T17:05:50.080Z] [2025-09-05T17:05:50.080Z] TESTING: [2025-09-05T17:05:52.128Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-09-05T17:05:52.128Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/output_17570903486685/renaissance-dec-tree_0/launcher-100550-13509883342064106889/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-09-05T17:05:52.128Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-09-05T17:05:52.128Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-09-05T17:06:10.331Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-09-05T17:06:18.002Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-09-05T17:06:18.002Z] GC before operation: completed in 246.308 ms, heap usage 69.366 MB -> 34.762 MB. [2025-09-05T17:06:49.192Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (28601.997 ms) ====== [2025-09-05T17:06:49.192Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-09-05T17:06:49.192Z] GC before operation: completed in 286.010 ms, heap usage 251.112 MB -> 96.389 MB. [2025-09-05T17:06:54.440Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (8299.596 ms) ====== [2025-09-05T17:06:54.440Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-09-05T17:06:54.440Z] GC before operation: completed in 299.536 ms, heap usage 414.010 MB -> 96.335 MB. [2025-09-05T17:06:54.440Z] 10:06:53.939 WARN [block-manager-storage-async-thread-pool-3] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_23, which does not exist [2025-09-05T17:07:00.744Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6254.261 ms) ====== [2025-09-05T17:07:00.744Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-09-05T17:07:01.220Z] GC before operation: completed in 292.639 ms, heap usage 424.303 MB -> 69.438 MB. [2025-09-05T17:07:07.441Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5991.347 ms) ====== [2025-09-05T17:07:07.441Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-09-05T17:07:07.441Z] GC before operation: completed in 458.293 ms, heap usage 255.189 MB -> 69.372 MB. [2025-09-05T17:07:12.324Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (4999.343 ms) ====== [2025-09-05T17:07:12.324Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-09-05T17:07:12.785Z] GC before operation: completed in 339.983 ms, heap usage 178.521 MB -> 69.166 MB. [2025-09-05T17:07:17.936Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4925.828 ms) ====== [2025-09-05T17:07:17.936Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-09-05T17:07:17.936Z] GC before operation: completed in 274.087 ms, heap usage 308.851 MB -> 70.054 MB. [2025-09-05T17:07:17.936Z] 10:07:17.584 WARN [block-manager-storage-async-thread-pool-83] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_79_piece0, which does not exist [2025-09-05T17:07:22.911Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4602.644 ms) ====== [2025-09-05T17:07:22.911Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-09-05T17:07:22.911Z] GC before operation: completed in 295.823 ms, heap usage 342.899 MB -> 70.155 MB. [2025-09-05T17:07:28.096Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4814.867 ms) ====== [2025-09-05T17:07:28.096Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-09-05T17:07:28.096Z] GC before operation: completed in 343.223 ms, heap usage 220.210 MB -> 97.781 MB. [2025-09-05T17:07:32.894Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4634.037 ms) ====== [2025-09-05T17:07:32.894Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-09-05T17:07:32.894Z] GC before operation: completed in 177.520 ms, heap usage 428.470 MB -> 70.340 MB. [2025-09-05T17:07:36.662Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3721.167 ms) ====== [2025-09-05T17:07:36.662Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-09-05T17:07:36.662Z] GC before operation: completed in 235.652 ms, heap usage 124.191 MB -> 69.670 MB. [2025-09-05T17:07:41.621Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (4898.425 ms) ====== [2025-09-05T17:07:41.621Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-09-05T17:07:42.097Z] GC before operation: completed in 341.046 ms, heap usage 433.834 MB -> 98.277 MB. [2025-09-05T17:07:46.778Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4768.548 ms) ====== [2025-09-05T17:07:46.778Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-09-05T17:07:47.233Z] GC before operation: completed in 263.220 ms, heap usage 273.104 MB -> 71.022 MB. [2025-09-05T17:07:51.868Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4434.009 ms) ====== [2025-09-05T17:07:51.868Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-09-05T17:07:51.868Z] GC before operation: completed in 302.824 ms, heap usage 462.570 MB -> 70.967 MB. [2025-09-05T17:07:55.749Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4050.320 ms) ====== [2025-09-05T17:07:55.749Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-09-05T17:07:56.185Z] GC before operation: completed in 174.237 ms, heap usage 212.035 MB -> 98.550 MB. [2025-09-05T17:08:00.994Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4502.724 ms) ====== [2025-09-05T17:08:00.994Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-09-05T17:08:00.994Z] GC before operation: completed in 211.654 ms, heap usage 214.638 MB -> 70.693 MB. [2025-09-05T17:08:04.871Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (4039.999 ms) ====== [2025-09-05T17:08:04.871Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-09-05T17:08:05.380Z] GC before operation: completed in 342.594 ms, heap usage 294.052 MB -> 71.260 MB. [2025-09-05T17:08:08.900Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3599.429 ms) ====== [2025-09-05T17:08:08.900Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-09-05T17:08:08.900Z] GC before operation: completed in 253.381 ms, heap usage 293.644 MB -> 84.233 MB. [2025-09-05T17:08:12.692Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3661.127 ms) ====== [2025-09-05T17:08:12.692Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-09-05T17:08:13.193Z] GC before operation: completed in 287.801 ms, heap usage 425.090 MB -> 99.131 MB. [2025-09-05T17:08:18.017Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4510.632 ms) ====== [2025-09-05T17:08:18.017Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-09-05T17:08:18.017Z] GC before operation: completed in 200.044 ms, heap usage 249.207 MB -> 71.406 MB. [2025-09-05T17:08:21.759Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3891.103 ms) ====== [2025-09-05T17:08:21.759Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-09-05T17:08:21.759Z] GC before operation: completed in 281.495 ms, heap usage 465.156 MB -> 99.402 MB. [2025-09-05T17:08:25.720Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3988.849 ms) ====== [2025-09-05T17:08:25.720Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-09-05T17:08:26.187Z] GC before operation: completed in 283.485 ms, heap usage 263.868 MB -> 71.657 MB. [2025-09-05T17:08:30.236Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3828.755 ms) ====== [2025-09-05T17:08:30.236Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-09-05T17:08:30.236Z] GC before operation: completed in 175.168 ms, heap usage 313.995 MB -> 99.419 MB. [2025-09-05T17:08:34.011Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3953.594 ms) ====== [2025-09-05T17:08:34.011Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-09-05T17:08:34.494Z] GC before operation: completed in 407.043 ms, heap usage 213.760 MB -> 71.595 MB. [2025-09-05T17:08:38.401Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3905.544 ms) ====== [2025-09-05T17:08:38.401Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-09-05T17:08:38.949Z] GC before operation: completed in 335.130 ms, heap usage 394.538 MB -> 72.262 MB. [2025-09-05T17:08:42.883Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3887.908 ms) ====== [2025-09-05T17:08:42.883Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-09-05T17:08:42.883Z] GC before operation: completed in 288.533 ms, heap usage 352.216 MB -> 72.245 MB. [2025-09-05T17:08:48.041Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4444.890 ms) ====== [2025-09-05T17:08:48.041Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-09-05T17:08:48.041Z] GC before operation: completed in 281.670 ms, heap usage 221.307 MB -> 99.844 MB. [2025-09-05T17:08:51.695Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4040.025 ms) ====== [2025-09-05T17:08:51.695Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-09-05T17:08:52.142Z] GC before operation: completed in 335.119 ms, heap usage 627.579 MB -> 72.929 MB. [2025-09-05T17:08:55.795Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3651.190 ms) ====== [2025-09-05T17:08:55.795Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-09-05T17:08:55.795Z] GC before operation: completed in 357.923 ms, heap usage 335.043 MB -> 72.443 MB. [2025-09-05T17:09:00.634Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3990.876 ms) ====== [2025-09-05T17:09:00.634Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-09-05T17:09:00.634Z] GC before operation: completed in 290.459 ms, heap usage 150.671 MB -> 99.829 MB. [2025-09-05T17:09:05.703Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (4766.570 ms) ====== [2025-09-05T17:09:05.703Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-09-05T17:09:05.703Z] GC before operation: completed in 318.681 ms, heap usage 664.624 MB -> 73.001 MB. [2025-09-05T17:09:08.564Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3279.477 ms) ====== [2025-09-05T17:09:08.564Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-09-05T17:09:09.021Z] GC before operation: completed in 246.542 ms, heap usage 315.782 MB -> 72.689 MB. [2025-09-05T17:09:12.609Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3552.083 ms) ====== [2025-09-05T17:09:12.609Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-09-05T17:09:12.609Z] GC before operation: completed in 287.206 ms, heap usage 122.711 MB -> 72.309 MB. [2025-09-05T17:09:16.554Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3875.137 ms) ====== [2025-09-05T17:09:16.554Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-09-05T17:09:17.194Z] GC before operation: completed in 389.249 ms, heap usage 433.035 MB -> 73.283 MB. [2025-09-05T17:09:21.996Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4573.668 ms) ====== [2025-09-05T17:09:21.996Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-09-05T17:09:21.996Z] GC before operation: completed in 306.494 ms, heap usage 146.350 MB -> 72.694 MB. [2025-09-05T17:09:25.744Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3817.317 ms) ====== [2025-09-05T17:09:25.744Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-09-05T17:09:26.303Z] GC before operation: completed in 282.849 ms, heap usage 261.642 MB -> 100.899 MB. [2025-09-05T17:09:30.142Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3854.915 ms) ====== [2025-09-05T17:09:30.142Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-09-05T17:09:30.142Z] GC before operation: completed in 315.525 ms, heap usage 630.694 MB -> 73.495 MB. [2025-09-05T17:09:33.917Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3419.313 ms) ====== [2025-09-05T17:09:33.917Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-09-05T17:09:33.917Z] GC before operation: completed in 244.007 ms, heap usage 354.489 MB -> 73.735 MB. [2025-09-05T17:09:37.556Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3383.335 ms) ====== [2025-09-05T17:09:37.556Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-09-05T17:09:37.556Z] GC before operation: completed in 383.441 ms, heap usage 130.699 MB -> 73.567 MB. [2025-09-05T17:09:42.478Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4741.999 ms) ====== [2025-09-05T17:09:42.478Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-09-05T17:09:42.478Z] GC before operation: completed in 258.496 ms, heap usage 222.583 MB -> 73.567 MB. [2025-09-05T17:09:47.045Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3906.042 ms) ====== [2025-09-05T17:09:47.487Z] ----------------------------------- [2025-09-05T17:09:47.487Z] renaissance-dec-tree_0_PASSED [2025-09-05T17:09:47.487Z] ----------------------------------- [2025-09-05T17:09:47.487Z] [2025-09-05T17:09:47.487Z] TEST TEARDOWN: [2025-09-05T17:09:47.487Z] Nothing to be done for teardown. [2025-09-05T17:09:47.487Z] renaissance-dec-tree_0 Finish Time: Fri Sep 5 10:09:46 2025 Epoch Time (ms): 1757092186836