[2026-01-21T07:16:46.980Z] Running test renaissance-dec-tree_0 ... [2026-01-21T07:16:46.980Z] =============================================== [2026-01-21T07:16:46.980Z] renaissance-dec-tree_0 Start Time: Wed Jan 21 07:16:45 2026 Epoch Time (ms): 1768979805153 [2026-01-21T07:16:46.980Z] variation: NoOptions [2026-01-21T07:16:46.980Z] JVM_OPTIONS: [2026-01-21T07:16:46.980Z] { \ [2026-01-21T07:16:46.980Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T07:16:46.980Z] echo "Nothing to be done for setup."; \ [2026-01-21T07:16:46.980Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689786632902/renaissance-dec-tree_0"; \ [2026-01-21T07:16:46.980Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689786632902/renaissance-dec-tree_0"; \ [2026-01-21T07:16:46.980Z] echo ""; echo "TESTING:"; \ [2026-01-21T07:16:46.980Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689786632902/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-01-21T07:16:46.980Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689786632902/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T07:16:46.980Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T07:16:46.980Z] echo "Nothing to be done for teardown."; \ [2026-01-21T07:16:46.980Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689786632902/TestTargetResult"; [2026-01-21T07:16:46.980Z] [2026-01-21T07:16:46.980Z] TEST SETUP: [2026-01-21T07:16:46.980Z] Nothing to be done for setup. [2026-01-21T07:16:46.980Z] [2026-01-21T07:16:46.980Z] TESTING: [2026-01-21T07:16:52.963Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-21T07:16:53.683Z] WARNING: An illegal reflective access operation has occurred [2026-01-21T07:16:53.683Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17689786632902/renaissance-dec-tree_0/harness-071645-11586360609138244059/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-21T07:16:53.683Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-21T07:16:53.683Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-21T07:16:53.683Z] WARNING: All illegal access operations will be denied in a future release [2026-01-21T07:16:55.368Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-01-21T07:16:55.368Z] GC before operation: completed in 67.951 ms, heap usage 47.850 MB -> 34.159 MB. [2026-01-21T07:17:11.340Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (15368.738 ms) ====== [2026-01-21T07:17:11.340Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-01-21T07:17:11.340Z] GC before operation: completed in 139.193 ms, heap usage 154.491 MB -> 66.770 MB. [2026-01-21T07:17:16.154Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (4461.533 ms) ====== [2026-01-21T07:17:16.154Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-01-21T07:17:16.154Z] GC before operation: completed in 135.111 ms, heap usage 177.727 MB -> 67.371 MB. [2026-01-21T07:17:19.155Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3184.035 ms) ====== [2026-01-21T07:17:19.155Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-01-21T07:17:19.155Z] GC before operation: completed in 138.936 ms, heap usage 167.822 MB -> 67.853 MB. [2026-01-21T07:17:22.162Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2881.786 ms) ====== [2026-01-21T07:17:22.162Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-01-21T07:17:22.162Z] GC before operation: completed in 188.673 ms, heap usage 146.741 MB -> 67.793 MB. [2026-01-21T07:17:24.455Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2517.862 ms) ====== [2026-01-21T07:17:24.455Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-01-21T07:17:24.455Z] GC before operation: completed in 147.297 ms, heap usage 133.761 MB -> 95.443 MB. [2026-01-21T07:17:27.456Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2404.674 ms) ====== [2026-01-21T07:17:27.456Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-01-21T07:17:27.456Z] GC before operation: completed in 146.899 ms, heap usage 347.703 MB -> 68.205 MB. [2026-01-21T07:17:29.743Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2280.040 ms) ====== [2026-01-21T07:17:29.743Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-01-21T07:17:29.743Z] GC before operation: completed in 155.308 ms, heap usage 164.074 MB -> 68.541 MB. [2026-01-21T07:17:32.125Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2208.740 ms) ====== [2026-01-21T07:17:32.125Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-01-21T07:17:32.125Z] GC before operation: completed in 150.755 ms, heap usage 215.803 MB -> 68.586 MB. [2026-01-21T07:17:34.416Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2238.256 ms) ====== [2026-01-21T07:17:34.416Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-01-21T07:17:34.416Z] GC before operation: completed in 154.348 ms, heap usage 264.799 MB -> 76.711 MB. [2026-01-21T07:17:36.699Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2097.756 ms) ====== [2026-01-21T07:17:36.699Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-01-21T07:17:36.699Z] GC before operation: completed in 164.442 ms, heap usage 194.315 MB -> 88.119 MB. [2026-01-21T07:17:38.989Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2092.694 ms) ====== [2026-01-21T07:17:38.990Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-01-21T07:17:38.990Z] GC before operation: completed in 151.214 ms, heap usage 133.345 MB -> 68.603 MB. [2026-01-21T07:17:41.299Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2359.134 ms) ====== [2026-01-21T07:17:41.300Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-01-21T07:17:41.300Z] GC before operation: completed in 156.315 ms, heap usage 143.989 MB -> 88.264 MB. [2026-01-21T07:17:43.599Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1999.210 ms) ====== [2026-01-21T07:17:43.599Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-01-21T07:17:43.599Z] GC before operation: completed in 158.065 ms, heap usage 289.810 MB -> 77.564 MB. [2026-01-21T07:17:45.890Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1934.226 ms) ====== [2026-01-21T07:17:45.890Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-01-21T07:17:45.890Z] GC before operation: completed in 157.417 ms, heap usage 212.101 MB -> 69.257 MB. [2026-01-21T07:17:47.577Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1930.393 ms) ====== [2026-01-21T07:17:47.577Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-01-21T07:17:47.577Z] GC before operation: completed in 163.137 ms, heap usage 246.971 MB -> 88.667 MB. [2026-01-21T07:17:49.890Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2023.817 ms) ====== [2026-01-21T07:17:49.890Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-01-21T07:17:49.890Z] GC before operation: completed in 155.752 ms, heap usage 125.613 MB -> 69.203 MB. [2026-01-21T07:17:52.175Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1973.499 ms) ====== [2026-01-21T07:17:52.175Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-01-21T07:17:52.175Z] GC before operation: completed in 166.808 ms, heap usage 196.338 MB -> 97.087 MB. [2026-01-21T07:17:52.175Z] 07:17:51.867 WARN [block-manager-storage-async-thread-pool-25] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_237, which does not exist [2026-01-21T07:17:53.866Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1954.081 ms) ====== [2026-01-21T07:17:53.866Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-01-21T07:17:54.199Z] GC before operation: completed in 174.344 ms, heap usage 323.620 MB -> 69.626 MB. [2026-01-21T07:17:56.483Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2113.283 ms) ====== [2026-01-21T07:17:56.483Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-01-21T07:17:56.483Z] GC before operation: completed in 165.479 ms, heap usage 145.728 MB -> 97.322 MB. [2026-01-21T07:17:58.773Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1984.037 ms) ====== [2026-01-21T07:17:58.773Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-01-21T07:17:58.773Z] GC before operation: completed in 169.593 ms, heap usage 286.410 MB -> 69.995 MB. [2026-01-21T07:18:00.454Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1919.487 ms) ====== [2026-01-21T07:18:00.454Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-01-21T07:18:00.789Z] GC before operation: completed in 196.356 ms, heap usage 290.997 MB -> 78.106 MB. [2026-01-21T07:18:02.557Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1916.028 ms) ====== [2026-01-21T07:18:02.557Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-01-21T07:18:02.891Z] GC before operation: completed in 166.496 ms, heap usage 182.744 MB -> 70.419 MB. [2026-01-21T07:18:04.576Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1870.643 ms) ====== [2026-01-21T07:18:04.576Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-01-21T07:18:04.913Z] GC before operation: completed in 168.498 ms, heap usage 287.093 MB -> 70.175 MB. [2026-01-21T07:18:07.205Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1943.538 ms) ====== [2026-01-21T07:18:07.205Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-01-21T07:18:07.205Z] GC before operation: completed in 172.671 ms, heap usage 217.573 MB -> 78.830 MB. [2026-01-21T07:18:08.884Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1844.642 ms) ====== [2026-01-21T07:18:08.884Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-01-21T07:18:08.884Z] GC before operation: completed in 167.354 ms, heap usage 200.002 MB -> 70.380 MB. [2026-01-21T07:18:11.163Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1891.109 ms) ====== [2026-01-21T07:18:11.163Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-01-21T07:18:11.163Z] GC before operation: completed in 171.646 ms, heap usage 281.060 MB -> 79.020 MB. [2026-01-21T07:18:12.842Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1832.002 ms) ====== [2026-01-21T07:18:12.842Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-01-21T07:18:13.177Z] GC before operation: completed in 169.042 ms, heap usage 180.595 MB -> 70.573 MB. [2026-01-21T07:18:14.882Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1875.390 ms) ====== [2026-01-21T07:18:14.882Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-01-21T07:18:15.218Z] GC before operation: completed in 173.977 ms, heap usage 171.023 MB -> 79.219 MB. [2026-01-21T07:18:15.218Z] 07:18:14.912 WARN [block-manager-storage-async-thread-pool-81] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_387, which does not exist [2026-01-21T07:18:15.218Z] 07:18:14.912 WARN [block-manager-storage-async-thread-pool-75] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_387_piece0, which does not exist [2026-01-21T07:18:16.905Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1823.432 ms) ====== [2026-01-21T07:18:16.905Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-01-21T07:18:17.242Z] GC before operation: completed in 173.145 ms, heap usage 314.618 MB -> 79.351 MB. [2026-01-21T07:18:18.929Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1721.970 ms) ====== [2026-01-21T07:18:18.930Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-01-21T07:18:18.930Z] GC before operation: completed in 170.807 ms, heap usage 252.403 MB -> 71.120 MB. [2026-01-21T07:18:20.618Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1741.859 ms) ====== [2026-01-21T07:18:20.618Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-01-21T07:18:20.952Z] GC before operation: completed in 179.560 ms, heap usage 231.838 MB -> 90.225 MB. [2026-01-21T07:18:22.642Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1808.003 ms) ====== [2026-01-21T07:18:22.642Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-01-21T07:18:22.976Z] GC before operation: completed in 180.904 ms, heap usage 304.284 MB -> 90.339 MB. [2026-01-21T07:18:24.691Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1796.549 ms) ====== [2026-01-21T07:18:24.691Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-01-21T07:18:25.028Z] GC before operation: completed in 179.404 ms, heap usage 284.383 MB -> 90.655 MB. [2026-01-21T07:18:26.711Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1901.131 ms) ====== [2026-01-21T07:18:26.711Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-01-21T07:18:27.047Z] GC before operation: completed in 172.557 ms, heap usage 135.832 MB -> 70.990 MB. [2026-01-21T07:18:28.823Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1770.941 ms) ====== [2026-01-21T07:18:28.823Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-01-21T07:18:28.823Z] GC before operation: completed in 181.774 ms, heap usage 236.776 MB -> 90.652 MB. [2026-01-21T07:18:30.519Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1703.201 ms) ====== [2026-01-21T07:18:30.519Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-01-21T07:18:30.853Z] GC before operation: completed in 183.755 ms, heap usage 282.601 MB -> 90.772 MB. [2026-01-21T07:18:32.536Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1785.154 ms) ====== [2026-01-21T07:18:32.536Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-01-21T07:18:32.872Z] GC before operation: completed in 184.901 ms, heap usage 344.351 MB -> 79.983 MB. [2026-01-21T07:18:34.556Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1773.549 ms) ====== [2026-01-21T07:18:34.556Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-01-21T07:18:34.556Z] GC before operation: completed in 180.608 ms, heap usage 273.160 MB -> 72.115 MB. [2026-01-21T07:18:36.843Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1854.237 ms) ====== [2026-01-21T07:18:36.843Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-01-21T07:18:36.843Z] GC before operation: completed in 180.872 ms, heap usage 261.286 MB -> 91.269 MB. [2026-01-21T07:18:38.522Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1835.678 ms) ====== [2026-01-21T07:18:38.856Z] ----------------------------------- [2026-01-21T07:18:38.856Z] renaissance-dec-tree_0_PASSED [2026-01-21T07:18:38.856Z] ----------------------------------- [2026-01-21T07:18:38.856Z] [2026-01-21T07:18:38.856Z] TEST TEARDOWN: [2026-01-21T07:18:38.856Z] Nothing to be done for teardown. [2026-01-21T07:18:38.856Z] renaissance-dec-tree_0 Finish Time: Wed Jan 21 07:18:38 2026 Epoch Time (ms): 1768979918729