[2026-01-24T14:43:35.199Z] Running test renaissance-dec-tree_0 ... [2026-01-24T14:43:35.199Z] =============================================== [2026-01-24T14:43:35.199Z] renaissance-dec-tree_0 Start Time: Sat Jan 24 14:43:34 2026 Epoch Time (ms): 1769265814766 [2026-01-24T14:43:35.199Z] variation: NoOptions [2026-01-24T14:43:35.199Z] JVM_OPTIONS: [2026-01-24T14:43:35.199Z] { \ [2026-01-24T14:43:35.199Z] echo ""; echo "TEST SETUP:"; \ [2026-01-24T14:43:35.199Z] echo "Nothing to be done for setup."; \ [2026-01-24T14:43:35.199Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17692648058318/renaissance-dec-tree_0"; \ [2026-01-24T14:43:35.199Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17692648058318/renaissance-dec-tree_0"; \ [2026-01-24T14:43:35.199Z] echo ""; echo "TESTING:"; \ [2026-01-24T14:43:35.199Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-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_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17692648058318/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-01-24T14:43:35.199Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17692648058318/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-24T14:43:35.199Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-24T14:43:35.199Z] echo "Nothing to be done for teardown."; \ [2026-01-24T14:43:35.199Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17692648058318/TestTargetResult"; [2026-01-24T14:43:35.199Z] [2026-01-24T14:43:35.199Z] TEST SETUP: [2026-01-24T14:43:35.199Z] Nothing to be done for setup. [2026-01-24T14:43:35.199Z] [2026-01-24T14:43:35.199Z] TESTING: [2026-01-24T14:43:35.940Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-24T14:43:35.940Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17692648058318/renaissance-dec-tree_0/launcher-144335-4748159684822396874/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-24T14:43:35.940Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-24T14:43:35.940Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-24T14:43:45.623Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-24T14:43:49.939Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-01-24T14:43:50.681Z] GC before operation: completed in 143.553 ms, heap usage 66.366 MB -> 34.989 MB. [2026-01-24T14:44:16.063Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (24842.229 ms) ====== [2026-01-24T14:44:16.063Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-01-24T14:44:16.063Z] GC before operation: completed in 190.600 ms, heap usage 162.845 MB -> 68.530 MB. [2026-01-24T14:44:20.410Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (5049.180 ms) ====== [2026-01-24T14:44:20.410Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-01-24T14:44:20.410Z] GC before operation: completed in 211.634 ms, heap usage 319.275 MB -> 69.152 MB. [2026-01-24T14:44:25.261Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4367.045 ms) ====== [2026-01-24T14:44:25.261Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-01-24T14:44:25.261Z] GC before operation: completed in 174.883 ms, heap usage 327.715 MB -> 69.545 MB. [2026-01-24T14:44:28.639Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3452.563 ms) ====== [2026-01-24T14:44:28.639Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-01-24T14:44:28.639Z] GC before operation: completed in 164.270 ms, heap usage 220.554 MB -> 69.626 MB. [2026-01-24T14:44:32.002Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3354.265 ms) ====== [2026-01-24T14:44:32.002Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-01-24T14:44:32.744Z] GC before operation: completed in 210.854 ms, heap usage 290.630 MB -> 70.077 MB. [2026-01-24T14:44:35.135Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2970.659 ms) ====== [2026-01-24T14:44:35.135Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-01-24T14:44:35.880Z] GC before operation: completed in 213.101 ms, heap usage 145.236 MB -> 69.664 MB. [2026-01-24T14:44:39.214Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3298.446 ms) ====== [2026-01-24T14:44:39.214Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-01-24T14:44:39.214Z] GC before operation: completed in 218.844 ms, heap usage 381.757 MB -> 70.617 MB. [2026-01-24T14:44:42.545Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2906.800 ms) ====== [2026-01-24T14:44:42.545Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-01-24T14:44:42.545Z] GC before operation: completed in 223.952 ms, heap usage 182.001 MB -> 70.076 MB. [2026-01-24T14:44:44.949Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2721.561 ms) ====== [2026-01-24T14:44:44.949Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-01-24T14:44:44.949Z] GC before operation: completed in 184.225 ms, heap usage 427.200 MB -> 70.690 MB. [2026-01-24T14:44:47.336Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2467.898 ms) ====== [2026-01-24T14:44:47.336Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-01-24T14:44:48.083Z] GC before operation: completed in 199.955 ms, heap usage 160.879 MB -> 76.019 MB. [2026-01-24T14:44:50.489Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2805.873 ms) ====== [2026-01-24T14:44:50.489Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-01-24T14:44:50.489Z] GC before operation: completed in 183.983 ms, heap usage 325.571 MB -> 70.782 MB. [2026-01-24T14:44:53.808Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2816.460 ms) ====== [2026-01-24T14:44:53.808Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-01-24T14:44:53.808Z] GC before operation: completed in 223.113 ms, heap usage 122.570 MB -> 70.313 MB. [2026-01-24T14:44:56.200Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2691.778 ms) ====== [2026-01-24T14:44:56.200Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-01-24T14:44:56.948Z] GC before operation: completed in 221.150 ms, heap usage 477.311 MB -> 71.562 MB. [2026-01-24T14:44:59.344Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2664.801 ms) ====== [2026-01-24T14:44:59.344Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-01-24T14:44:59.344Z] GC before operation: completed in 199.916 ms, heap usage 283.541 MB -> 71.212 MB. [2026-01-24T14:45:02.668Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2691.112 ms) ====== [2026-01-24T14:45:02.668Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-01-24T14:45:02.668Z] GC before operation: completed in 192.146 ms, heap usage 122.403 MB -> 70.727 MB. [2026-01-24T14:45:05.990Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2968.247 ms) ====== [2026-01-24T14:45:05.990Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-01-24T14:45:05.990Z] GC before operation: completed in 199.696 ms, heap usage 320.694 MB -> 71.310 MB. [2026-01-24T14:45:08.399Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2950.013 ms) ====== [2026-01-24T14:45:08.399Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-01-24T14:45:09.141Z] GC before operation: completed in 191.474 ms, heap usage 438.251 MB -> 71.804 MB. [2026-01-24T14:45:11.549Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2503.416 ms) ====== [2026-01-24T14:45:11.549Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-01-24T14:45:11.549Z] GC before operation: completed in 263.412 ms, heap usage 186.302 MB -> 71.226 MB. [2026-01-24T14:45:13.925Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2450.316 ms) ====== [2026-01-24T14:45:13.925Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-01-24T14:45:13.925Z] GC before operation: completed in 187.212 ms, heap usage 371.452 MB -> 71.720 MB. [2026-01-24T14:45:16.815Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2499.286 ms) ====== [2026-01-24T14:45:16.815Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-01-24T14:45:16.815Z] GC before operation: completed in 247.543 ms, heap usage 438.566 MB -> 99.350 MB. [2026-01-24T14:45:19.223Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2536.603 ms) ====== [2026-01-24T14:45:19.223Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-01-24T14:45:19.978Z] GC before operation: completed in 216.586 ms, heap usage 498.180 MB -> 72.061 MB. [2026-01-24T14:45:22.373Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2571.399 ms) ====== [2026-01-24T14:45:22.373Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-01-24T14:45:22.373Z] GC before operation: completed in 334.855 ms, heap usage 290.950 MB -> 72.046 MB. [2026-01-24T14:45:31.008Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (8115.400 ms) ====== [2026-01-24T14:45:31.008Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-01-24T14:45:31.008Z] GC before operation: completed in 181.071 ms, heap usage 423.435 MB -> 72.258 MB. [2026-01-24T14:45:33.406Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2299.163 ms) ====== [2026-01-24T14:45:33.406Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-01-24T14:45:33.406Z] GC before operation: completed in 269.780 ms, heap usage 218.329 MB -> 72.524 MB. [2026-01-24T14:45:35.803Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2328.301 ms) ====== [2026-01-24T14:45:35.803Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-01-24T14:45:35.803Z] GC before operation: completed in 209.595 ms, heap usage 386.618 MB -> 72.311 MB. [2026-01-24T14:45:42.877Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (6517.985 ms) ====== [2026-01-24T14:45:42.877Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-01-24T14:45:42.877Z] GC before operation: completed in 228.928 ms, heap usage 429.741 MB -> 72.757 MB. [2026-01-24T14:45:45.299Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2676.953 ms) ====== [2026-01-24T14:45:45.299Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-01-24T14:45:46.040Z] GC before operation: completed in 218.215 ms, heap usage 290.567 MB -> 72.593 MB. [2026-01-24T14:45:48.431Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2389.639 ms) ====== [2026-01-24T14:45:48.431Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-01-24T14:45:48.431Z] GC before operation: completed in 217.363 ms, heap usage 264.622 MB -> 72.712 MB. [2026-01-24T14:45:50.857Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2371.698 ms) ====== [2026-01-24T14:45:50.857Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-01-24T14:45:50.857Z] GC before operation: completed in 246.825 ms, heap usage 426.187 MB -> 73.100 MB. [2026-01-24T14:45:53.253Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2172.939 ms) ====== [2026-01-24T14:45:53.253Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-01-24T14:45:53.253Z] GC before operation: completed in 232.986 ms, heap usage 429.254 MB -> 73.198 MB. [2026-01-24T14:45:55.647Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2481.522 ms) ====== [2026-01-24T14:45:55.647Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-01-24T14:45:56.392Z] GC before operation: completed in 234.730 ms, heap usage 429.002 MB -> 73.328 MB. [2026-01-24T14:45:58.816Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2389.248 ms) ====== [2026-01-24T14:45:58.816Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-01-24T14:45:58.816Z] GC before operation: completed in 249.179 ms, heap usage 427.895 MB -> 73.658 MB. [2026-01-24T14:46:01.229Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2324.146 ms) ====== [2026-01-24T14:46:01.230Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-01-24T14:46:01.230Z] GC before operation: completed in 248.336 ms, heap usage 418.459 MB -> 73.540 MB. [2026-01-24T14:46:03.623Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2348.632 ms) ====== [2026-01-24T14:46:03.623Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-01-24T14:46:03.623Z] GC before operation: completed in 236.736 ms, heap usage 417.474 MB -> 79.281 MB. [2026-01-24T14:46:06.004Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2292.773 ms) ====== [2026-01-24T14:46:06.004Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-01-24T14:46:06.760Z] GC before operation: completed in 292.824 ms, heap usage 359.356 MB -> 73.508 MB. [2026-01-24T14:46:09.156Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2641.656 ms) ====== [2026-01-24T14:46:09.156Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-01-24T14:46:09.156Z] GC before operation: completed in 372.327 ms, heap usage 420.933 MB -> 73.866 MB. [2026-01-24T14:46:12.057Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2686.411 ms) ====== [2026-01-24T14:46:12.057Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-01-24T14:46:12.796Z] GC before operation: completed in 297.603 ms, heap usage 393.614 MB -> 73.716 MB. [2026-01-24T14:46:12.796Z] 14:46:12.026 WARN [block-manager-storage-async-thread-pool-44] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_513_piece0, which does not exist [2026-01-24T14:46:15.185Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2538.564 ms) ====== [2026-01-24T14:46:15.185Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-01-24T14:46:15.185Z] GC before operation: completed in 235.044 ms, heap usage 429.210 MB -> 74.278 MB. [2026-01-24T14:46:17.593Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2181.803 ms) ====== [2026-01-24T14:46:17.593Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-01-24T14:46:17.593Z] GC before operation: completed in 254.146 ms, heap usage 248.315 MB -> 101.447 MB. [2026-01-24T14:46:19.986Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2478.932 ms) ====== [2026-01-24T14:46:20.730Z] ----------------------------------- [2026-01-24T14:46:20.730Z] renaissance-dec-tree_0_PASSED [2026-01-24T14:46:20.730Z] ----------------------------------- [2026-01-24T14:46:20.730Z] [2026-01-24T14:46:20.730Z] TEST TEARDOWN: [2026-01-24T14:46:20.730Z] Nothing to be done for teardown. [2026-01-24T14:46:20.730Z] renaissance-dec-tree_0 Finish Time: Sat Jan 24 14:46:20 2026 Epoch Time (ms): 1769265980227