[2026-01-14T15:24:12.606Z] Running test renaissance-dec-tree_0 ... [2026-01-14T15:24:12.606Z] =============================================== [2026-01-14T15:24:12.606Z] renaissance-dec-tree_0 Start Time: Wed Jan 14 15:24:11 2026 Epoch Time (ms): 1768404251574 [2026-01-14T15:24:12.606Z] variation: NoOptions [2026-01-14T15:24:12.606Z] JVM_OPTIONS: [2026-01-14T15:24:12.606Z] { \ [2026-01-14T15:24:12.606Z] echo ""; echo "TEST SETUP:"; \ [2026-01-14T15:24:12.606Z] echo "Nothing to be done for setup."; \ [2026-01-14T15:24:12.606Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17684033291261/renaissance-dec-tree_0"; \ [2026-01-14T15:24:12.606Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17684033291261/renaissance-dec-tree_0"; \ [2026-01-14T15:24:12.606Z] echo ""; echo "TESTING:"; \ [2026-01-14T15:24:12.607Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_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_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17684033291261/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-01-14T15:24:12.607Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17684033291261/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-14T15:24:12.607Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-14T15:24:12.607Z] echo "Nothing to be done for teardown."; \ [2026-01-14T15:24:12.607Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17684033291261/TestTargetResult"; [2026-01-14T15:24:12.607Z] [2026-01-14T15:24:12.607Z] TEST SETUP: [2026-01-14T15:24:12.607Z] Nothing to be done for setup. [2026-01-14T15:24:12.607Z] [2026-01-14T15:24:12.607Z] TESTING: [2026-01-14T15:24:13.660Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-14T15:24:13.660Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_17684033291261/renaissance-dec-tree_0/launcher-152411-14975396210399979965/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-14T15:24:13.660Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-14T15:24:13.660Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-14T15:24:43.284Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-14T15:24:45.503Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-01-14T15:24:45.503Z] GC before operation: completed in 72.679 ms, heap usage 46.377 MB -> 35.031 MB. [2026-01-14T15:25:01.702Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (15570.953 ms) ====== [2026-01-14T15:25:01.702Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-01-14T15:25:01.702Z] GC before operation: completed in 173.876 ms, heap usage 229.391 MB -> 68.774 MB. [2026-01-14T15:25:04.872Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3639.739 ms) ====== [2026-01-14T15:25:04.872Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-01-14T15:25:05.852Z] GC before operation: completed in 177.595 ms, heap usage 126.774 MB -> 74.338 MB. [2026-01-14T15:25:07.828Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2544.144 ms) ====== [2026-01-14T15:25:07.828Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-01-14T15:25:07.828Z] GC before operation: completed in 109.663 ms, heap usage 295.691 MB -> 69.376 MB. [2026-01-14T15:25:10.959Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2679.221 ms) ====== [2026-01-14T15:25:10.959Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-01-14T15:25:10.959Z] GC before operation: completed in 127.400 ms, heap usage 220.170 MB -> 69.656 MB. [2026-01-14T15:25:12.937Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2234.109 ms) ====== [2026-01-14T15:25:12.937Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-01-14T15:25:12.937Z] GC before operation: completed in 152.158 ms, heap usage 171.351 MB -> 69.667 MB. [2026-01-14T15:25:16.032Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2236.657 ms) ====== [2026-01-14T15:25:16.032Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-01-14T15:25:16.032Z] GC before operation: completed in 150.923 ms, heap usage 170.538 MB -> 69.891 MB. [2026-01-14T15:25:18.008Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1985.389 ms) ====== [2026-01-14T15:25:18.008Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-01-14T15:25:18.008Z] GC before operation: completed in 122.586 ms, heap usage 264.238 MB -> 70.280 MB. [2026-01-14T15:25:19.951Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2251.661 ms) ====== [2026-01-14T15:25:19.951Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-01-14T15:25:19.951Z] GC before operation: completed in 132.442 ms, heap usage 108.708 MB -> 81.141 MB. [2026-01-14T15:25:22.113Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2075.580 ms) ====== [2026-01-14T15:25:22.113Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-01-14T15:25:22.113Z] GC before operation: completed in 141.304 ms, heap usage 175.246 MB -> 70.230 MB. [2026-01-14T15:25:24.093Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2000.993 ms) ====== [2026-01-14T15:25:24.093Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-01-14T15:25:24.093Z] GC before operation: completed in 150.151 ms, heap usage 490.239 MB -> 70.947 MB. [2026-01-14T15:25:27.352Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2203.541 ms) ====== [2026-01-14T15:25:27.352Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-01-14T15:25:27.352Z] GC before operation: completed in 177.920 ms, heap usage 173.009 MB -> 71.044 MB. [2026-01-14T15:25:29.323Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2148.905 ms) ====== [2026-01-14T15:25:29.323Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-01-14T15:25:29.323Z] GC before operation: completed in 148.381 ms, heap usage 199.883 MB -> 70.880 MB. [2026-01-14T15:25:31.320Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1776.028 ms) ====== [2026-01-14T15:25:31.320Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-01-14T15:25:31.320Z] GC before operation: completed in 151.838 ms, heap usage 172.548 MB -> 98.520 MB. [2026-01-14T15:25:31.320Z] 15:25:30.565 WARN [block-manager-storage-async-thread-pool-55] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_177_piece0, which does not exist [2026-01-14T15:25:32.279Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1605.692 ms) ====== [2026-01-14T15:25:32.279Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-01-14T15:25:33.343Z] GC before operation: completed in 364.737 ms, heap usage 244.478 MB -> 71.156 MB. [2026-01-14T15:25:35.324Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1961.848 ms) ====== [2026-01-14T15:25:35.324Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-01-14T15:25:35.324Z] GC before operation: completed in 163.014 ms, heap usage 171.049 MB -> 87.450 MB. [2026-01-14T15:25:37.310Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1826.466 ms) ====== [2026-01-14T15:25:37.310Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-01-14T15:25:37.310Z] GC before operation: completed in 143.098 ms, heap usage 237.283 MB -> 71.426 MB. [2026-01-14T15:25:38.272Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1480.590 ms) ====== [2026-01-14T15:25:38.272Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-01-14T15:25:39.310Z] GC before operation: completed in 138.615 ms, heap usage 106.746 MB -> 71.360 MB. [2026-01-14T15:25:40.271Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1855.952 ms) ====== [2026-01-14T15:25:40.271Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-01-14T15:25:41.282Z] GC before operation: completed in 160.140 ms, heap usage 228.031 MB -> 99.908 MB. [2026-01-14T15:25:41.282Z] 15:25:40.260 WARN [block-manager-storage-async-thread-pool-98] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_251_piece0, which does not exist [2026-01-14T15:25:42.244Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1678.755 ms) ====== [2026-01-14T15:25:42.244Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-01-14T15:25:42.244Z] GC before operation: completed in 147.245 ms, heap usage 236.728 MB -> 71.936 MB. [2026-01-14T15:25:44.220Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1608.452 ms) ====== [2026-01-14T15:25:44.220Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-01-14T15:25:44.220Z] GC before operation: completed in 163.160 ms, heap usage 274.964 MB -> 71.966 MB. [2026-01-14T15:25:46.199Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1582.391 ms) ====== [2026-01-14T15:25:46.199Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-01-14T15:25:46.199Z] GC before operation: completed in 159.996 ms, heap usage 107.163 MB -> 71.544 MB. [2026-01-14T15:25:48.176Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1570.109 ms) ====== [2026-01-14T15:25:48.177Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-01-14T15:25:48.177Z] GC before operation: completed in 145.754 ms, heap usage 251.171 MB -> 72.324 MB. [2026-01-14T15:25:49.138Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1485.620 ms) ====== [2026-01-14T15:25:49.138Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-01-14T15:25:49.138Z] GC before operation: completed in 151.112 ms, heap usage 106.025 MB -> 99.774 MB. [2026-01-14T15:25:49.138Z] 15:25:48.969 WARN [block-manager-storage-async-thread-pool-37] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1101_2, which does not exist [2026-01-14T15:25:51.121Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1552.358 ms) ====== [2026-01-14T15:25:51.121Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-01-14T15:25:51.121Z] GC before operation: completed in 172.056 ms, heap usage 247.955 MB -> 88.992 MB. [2026-01-14T15:25:51.121Z] 15:25:50.692 WARN [block-manager-storage-async-thread-pool-4] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_331, which does not exist [2026-01-14T15:25:53.098Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1532.091 ms) ====== [2026-01-14T15:25:53.098Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-01-14T15:25:53.099Z] GC before operation: completed in 159.850 ms, heap usage 302.329 MB -> 72.558 MB. [2026-01-14T15:25:54.066Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1520.987 ms) ====== [2026-01-14T15:25:54.066Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-01-14T15:25:55.031Z] GC before operation: completed in 147.747 ms, heap usage 231.101 MB -> 72.775 MB. [2026-01-14T15:25:55.988Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1406.217 ms) ====== [2026-01-14T15:25:55.988Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-01-14T15:25:55.988Z] GC before operation: completed in 142.538 ms, heap usage 239.898 MB -> 100.423 MB. [2026-01-14T15:25:57.976Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1798.877 ms) ====== [2026-01-14T15:25:57.976Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-01-14T15:25:57.976Z] GC before operation: completed in 206.225 ms, heap usage 117.270 MB -> 72.322 MB. [2026-01-14T15:25:59.954Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1675.844 ms) ====== [2026-01-14T15:25:59.954Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-01-14T15:25:59.954Z] GC before operation: completed in 159.239 ms, heap usage 225.792 MB -> 73.091 MB. [2026-01-14T15:26:01.933Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1548.895 ms) ====== [2026-01-14T15:26:01.933Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-01-14T15:26:01.933Z] GC before operation: completed in 191.965 ms, heap usage 239.259 MB -> 73.565 MB. [2026-01-14T15:26:03.359Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1527.667 ms) ====== [2026-01-14T15:26:03.359Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-01-14T15:26:03.359Z] GC before operation: completed in 164.387 ms, heap usage 207.432 MB -> 73.295 MB. [2026-01-14T15:26:05.337Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1475.747 ms) ====== [2026-01-14T15:26:05.337Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-01-14T15:26:05.337Z] GC before operation: completed in 189.992 ms, heap usage 108.209 MB -> 72.746 MB. [2026-01-14T15:26:06.298Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1283.450 ms) ====== [2026-01-14T15:26:06.298Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-01-14T15:26:06.298Z] GC before operation: completed in 164.961 ms, heap usage 264.186 MB -> 73.500 MB. [2026-01-14T15:26:08.275Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1464.816 ms) ====== [2026-01-14T15:26:08.275Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-01-14T15:26:08.275Z] GC before operation: completed in 179.499 ms, heap usage 157.081 MB -> 100.923 MB. [2026-01-14T15:26:10.268Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1829.995 ms) ====== [2026-01-14T15:26:10.268Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-01-14T15:26:10.268Z] GC before operation: completed in 190.405 ms, heap usage 216.279 MB -> 73.703 MB. [2026-01-14T15:26:12.250Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1716.193 ms) ====== [2026-01-14T15:26:12.250Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-01-14T15:26:12.250Z] GC before operation: completed in 165.587 ms, heap usage 219.723 MB -> 84.733 MB. [2026-01-14T15:26:13.218Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1554.003 ms) ====== [2026-01-14T15:26:13.218Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-01-14T15:26:14.182Z] GC before operation: completed in 200.141 ms, heap usage 241.815 MB -> 73.919 MB. [2026-01-14T15:26:15.145Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1646.048 ms) ====== [2026-01-14T15:26:15.145Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-01-14T15:26:16.103Z] GC before operation: completed in 202.311 ms, heap usage 109.382 MB -> 73.627 MB. [2026-01-14T15:26:17.067Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1614.607 ms) ====== [2026-01-14T15:26:17.067Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-01-14T15:26:17.067Z] GC before operation: completed in 164.714 ms, heap usage 239.080 MB -> 90.520 MB. [2026-01-14T15:26:19.045Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1232.911 ms) ====== [2026-01-14T15:26:19.045Z] ----------------------------------- [2026-01-14T15:26:19.045Z] renaissance-dec-tree_0_PASSED [2026-01-14T15:26:19.045Z] ----------------------------------- [2026-01-14T15:26:19.045Z] [2026-01-14T15:26:19.045Z] TEST TEARDOWN: [2026-01-14T15:26:19.045Z] Nothing to be done for teardown. [2026-01-14T15:26:19.045Z] renaissance-dec-tree_0 Finish Time: Wed Jan 14 15:26:18 2026 Epoch Time (ms): 1768404378657