[2025-10-16T00:52:08.966Z] Running test renaissance-dec-tree_0 ... [2025-10-16T00:52:09.403Z] =============================================== [2025-10-16T00:52:09.403Z] renaissance-dec-tree_0 Start Time: Wed Oct 15 17:52:08 2025 Epoch Time (ms): 1760575928328 [2025-10-16T00:52:09.403Z] variation: NoOptions [2025-10-16T00:52:09.403Z] JVM_OPTIONS: [2025-10-16T00:52:09.403Z] { \ [2025-10-16T00:52:09.403Z] echo ""; echo "TEST SETUP:"; \ [2025-10-16T00:52:09.403Z] echo "Nothing to be done for setup."; \ [2025-10-16T00:52:09.403Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1760574717628/renaissance-dec-tree_0"; \ [2025-10-16T00:52:09.403Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1760574717628/renaissance-dec-tree_0"; \ [2025-10-16T00:52:09.403Z] echo ""; echo "TESTING:"; \ [2025-10-16T00:52:09.403Z] "/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_1760574717628/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-10-16T00:52:09.403Z] 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_1760574717628/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-16T00:52:09.403Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-16T00:52:09.403Z] echo "Nothing to be done for teardown."; \ [2025-10-16T00:52:09.403Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1760574717628/TestTargetResult"; [2025-10-16T00:52:09.403Z] [2025-10-16T00:52:09.403Z] TEST SETUP: [2025-10-16T00:52:09.403Z] Nothing to be done for setup. [2025-10-16T00:52:09.403Z] [2025-10-16T00:52:09.403Z] TESTING: [2025-10-16T00:52:11.120Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-16T00:52:11.120Z] 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_1760574717628/renaissance-dec-tree_0/launcher-175208-7687275599217562187/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-16T00:52:11.120Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-16T00:52:11.120Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-16T00:52:17.887Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-16T00:52:20.667Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-10-16T00:52:20.667Z] GC before operation: completed in 64.973 ms, heap usage 91.529 MB -> 34.755 MB. [2025-10-16T00:52:35.196Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (14239.607 ms) ====== [2025-10-16T00:52:35.196Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-10-16T00:52:35.196Z] GC before operation: completed in 134.312 ms, heap usage 244.164 MB -> 68.685 MB. [2025-10-16T00:52:39.890Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (4141.897 ms) ====== [2025-10-16T00:52:39.890Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-10-16T00:52:39.890Z] GC before operation: completed in 170.078 ms, heap usage 164.597 MB -> 68.635 MB. [2025-10-16T00:52:43.871Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4347.488 ms) ====== [2025-10-16T00:52:43.871Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-10-16T00:52:43.871Z] GC before operation: completed in 203.319 ms, heap usage 312.372 MB -> 69.195 MB. [2025-10-16T00:52:47.561Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3502.203 ms) ====== [2025-10-16T00:52:47.561Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-10-16T00:52:47.561Z] GC before operation: completed in 202.689 ms, heap usage 310.755 MB -> 69.419 MB. [2025-10-16T00:52:51.508Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3573.595 ms) ====== [2025-10-16T00:52:51.508Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-10-16T00:52:51.508Z] GC before operation: completed in 176.728 ms, heap usage 161.539 MB -> 69.327 MB. [2025-10-16T00:52:55.389Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3874.397 ms) ====== [2025-10-16T00:52:55.389Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-10-16T00:52:55.389Z] GC before operation: completed in 145.406 ms, heap usage 293.200 MB -> 69.863 MB. [2025-10-16T00:52:59.367Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3456.691 ms) ====== [2025-10-16T00:52:59.367Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-10-16T00:52:59.367Z] GC before operation: completed in 152.943 ms, heap usage 302.128 MB -> 70.092 MB. [2025-10-16T00:53:03.225Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3851.909 ms) ====== [2025-10-16T00:53:03.225Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-10-16T00:53:03.225Z] GC before operation: completed in 244.873 ms, heap usage 150.543 MB -> 97.736 MB. [2025-10-16T00:53:07.211Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4143.168 ms) ====== [2025-10-16T00:53:07.211Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-10-16T00:53:07.615Z] GC before operation: completed in 190.753 ms, heap usage 612.114 MB -> 71.053 MB. [2025-10-16T00:53:10.538Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3144.369 ms) ====== [2025-10-16T00:53:10.538Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-10-16T00:53:10.994Z] GC before operation: completed in 162.239 ms, heap usage 311.693 MB -> 70.851 MB. [2025-10-16T00:53:14.720Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3871.766 ms) ====== [2025-10-16T00:53:14.720Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-10-16T00:53:14.720Z] GC before operation: completed in 217.092 ms, heap usage 456.890 MB -> 70.993 MB. [2025-10-16T00:53:18.745Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3246.038 ms) ====== [2025-10-16T00:53:18.745Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-10-16T00:53:18.745Z] GC before operation: completed in 235.339 ms, heap usage 112.608 MB -> 97.593 MB. [2025-10-16T00:53:22.521Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4265.606 ms) ====== [2025-10-16T00:53:22.521Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-10-16T00:53:23.004Z] GC before operation: completed in 199.658 ms, heap usage 180.880 MB -> 70.566 MB. [2025-10-16T00:53:25.857Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2983.904 ms) ====== [2025-10-16T00:53:25.857Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-10-16T00:53:26.308Z] GC before operation: completed in 241.941 ms, heap usage 444.992 MB -> 98.837 MB. [2025-10-16T00:53:29.230Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3313.244 ms) ====== [2025-10-16T00:53:29.230Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-10-16T00:53:29.650Z] GC before operation: completed in 207.209 ms, heap usage 607.907 MB -> 98.975 MB. [2025-10-16T00:53:33.291Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3711.097 ms) ====== [2025-10-16T00:53:33.291Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-10-16T00:53:33.729Z] GC before operation: completed in 176.969 ms, heap usage 460.636 MB -> 71.649 MB. [2025-10-16T00:53:36.856Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3075.023 ms) ====== [2025-10-16T00:53:36.856Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-10-16T00:53:36.856Z] GC before operation: completed in 198.218 ms, heap usage 304.833 MB -> 71.530 MB. [2025-10-16T00:53:39.578Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2975.380 ms) ====== [2025-10-16T00:53:39.578Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-10-16T00:53:39.986Z] GC before operation: completed in 179.814 ms, heap usage 443.125 MB -> 99.345 MB. [2025-10-16T00:53:43.749Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3666.905 ms) ====== [2025-10-16T00:53:43.749Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-10-16T00:53:43.749Z] GC before operation: completed in 220.764 ms, heap usage 254.405 MB -> 99.373 MB. [2025-10-16T00:53:46.665Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3030.820 ms) ====== [2025-10-16T00:53:46.665Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-10-16T00:53:47.167Z] GC before operation: completed in 185.517 ms, heap usage 195.086 MB -> 71.441 MB. [2025-10-16T00:53:50.747Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3715.032 ms) ====== [2025-10-16T00:53:50.747Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-10-16T00:53:51.180Z] GC before operation: completed in 226.498 ms, heap usage 357.803 MB -> 99.328 MB. [2025-10-16T00:53:54.020Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3107.420 ms) ====== [2025-10-16T00:53:54.020Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-10-16T00:53:54.525Z] GC before operation: completed in 225.881 ms, heap usage 172.621 MB -> 99.204 MB. [2025-10-16T00:53:54.525Z] 17:53:53.382 WARN [block-manager-storage-async-thread-pool-91] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1053_0, which does not exist [2025-10-16T00:53:57.530Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3299.727 ms) ====== [2025-10-16T00:53:57.530Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-10-16T00:53:58.119Z] GC before operation: completed in 151.554 ms, heap usage 609.105 MB -> 87.174 MB. [2025-10-16T00:54:00.301Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2689.238 ms) ====== [2025-10-16T00:54:00.301Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-10-16T00:54:00.785Z] GC before operation: completed in 211.259 ms, heap usage 218.771 MB -> 99.925 MB. [2025-10-16T00:54:04.643Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3520.168 ms) ====== [2025-10-16T00:54:04.643Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-10-16T00:54:04.643Z] GC before operation: completed in 287.942 ms, heap usage 605.996 MB -> 72.547 MB. [2025-10-16T00:54:07.505Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2839.473 ms) ====== [2025-10-16T00:54:07.505Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-10-16T00:54:07.505Z] GC before operation: completed in 190.269 ms, heap usage 308.126 MB -> 100.014 MB. [2025-10-16T00:54:11.442Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3961.446 ms) ====== [2025-10-16T00:54:11.442Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-10-16T00:54:11.442Z] GC before operation: completed in 160.527 ms, heap usage 613.646 MB -> 72.706 MB. [2025-10-16T00:54:14.279Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2562.423 ms) ====== [2025-10-16T00:54:14.279Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-10-16T00:54:14.279Z] GC before operation: completed in 164.404 ms, heap usage 312.448 MB -> 72.982 MB. [2025-10-16T00:54:14.279Z] 17:54:13.421 WARN [block-manager-storage-async-thread-pool-93] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_387, which does not exist [2025-10-16T00:54:17.194Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2945.288 ms) ====== [2025-10-16T00:54:17.194Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-10-16T00:54:17.648Z] GC before operation: completed in 217.471 ms, heap usage 445.950 MB -> 72.990 MB. [2025-10-16T00:54:21.378Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3278.876 ms) ====== [2025-10-16T00:54:21.378Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-10-16T00:54:21.378Z] GC before operation: completed in 212.137 ms, heap usage 453.734 MB -> 73.451 MB. [2025-10-16T00:54:24.389Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3280.083 ms) ====== [2025-10-16T00:54:24.389Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-10-16T00:54:24.389Z] GC before operation: completed in 181.510 ms, heap usage 112.801 MB -> 72.919 MB. [2025-10-16T00:54:27.153Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2873.601 ms) ====== [2025-10-16T00:54:27.153Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-10-16T00:54:27.665Z] GC before operation: completed in 239.331 ms, heap usage 474.538 MB -> 100.871 MB. [2025-10-16T00:54:31.390Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3117.172 ms) ====== [2025-10-16T00:54:31.390Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-10-16T00:54:31.390Z] GC before operation: completed in 258.545 ms, heap usage 597.012 MB -> 73.371 MB. [2025-10-16T00:54:34.273Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3423.692 ms) ====== [2025-10-16T00:54:34.273Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-10-16T00:54:34.736Z] GC before operation: completed in 257.943 ms, heap usage 294.112 MB -> 73.595 MB. [2025-10-16T00:54:38.378Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3383.891 ms) ====== [2025-10-16T00:54:38.378Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-10-16T00:54:38.378Z] GC before operation: completed in 183.244 ms, heap usage 378.839 MB -> 73.408 MB. [2025-10-16T00:54:41.191Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2936.574 ms) ====== [2025-10-16T00:54:41.191Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-10-16T00:54:41.191Z] GC before operation: completed in 210.834 ms, heap usage 121.356 MB -> 72.993 MB. [2025-10-16T00:54:45.074Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3673.139 ms) ====== [2025-10-16T00:54:45.074Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-10-16T00:54:45.074Z] GC before operation: completed in 164.902 ms, heap usage 496.796 MB -> 73.976 MB. [2025-10-16T00:54:47.937Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2864.663 ms) ====== [2025-10-16T00:54:47.937Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-10-16T00:54:48.340Z] GC before operation: completed in 202.948 ms, heap usage 456.082 MB -> 74.338 MB. [2025-10-16T00:54:51.226Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3131.168 ms) ====== [2025-10-16T00:54:51.226Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-10-16T00:54:51.639Z] GC before operation: completed in 227.745 ms, heap usage 454.713 MB -> 101.657 MB. [2025-10-16T00:54:54.314Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2828.947 ms) ====== [2025-10-16T00:54:56.301Z] ----------------------------------- [2025-10-16T00:54:56.301Z] renaissance-dec-tree_0_PASSED [2025-10-16T00:54:56.301Z] ----------------------------------- [2025-10-16T00:54:56.301Z] [2025-10-16T00:54:56.301Z] TEST TEARDOWN: [2025-10-16T00:54:56.301Z] Nothing to be done for teardown. [2025-10-16T00:54:56.301Z] renaissance-dec-tree_0 Finish Time: Wed Oct 15 17:54:55 2025 Epoch Time (ms): 1760576095077