[2025-09-05T15:04:07.497Z] Running test renaissance-dec-tree_0 ... [2025-09-05T15:04:07.497Z] =============================================== [2025-09-05T15:04:07.497Z] renaissance-dec-tree_0 Start Time: Fri Sep 5 15:04:07 2025 Epoch Time (ms): 1757084647226 [2025-09-05T15:04:07.497Z] variation: NoOptions [2025-09-05T15:04:07.497Z] JVM_OPTIONS: [2025-09-05T15:04:07.497Z] { \ [2025-09-05T15:04:07.497Z] echo ""; echo "TEST SETUP:"; \ [2025-09-05T15:04:07.497Z] echo "Nothing to be done for setup."; \ [2025-09-05T15:04:07.497Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17570841288023/renaissance-dec-tree_0"; \ [2025-09-05T15:04:07.497Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17570841288023/renaissance-dec-tree_0"; \ [2025-09-05T15:04:07.497Z] echo ""; echo "TESTING:"; \ [2025-09-05T15:04:07.497Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17570841288023/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-09-05T15:04:07.497Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17570841288023/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-05T15:04:07.497Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-05T15:04:07.497Z] echo "Nothing to be done for teardown."; \ [2025-09-05T15:04:07.497Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17570841288023/TestTargetResult"; [2025-09-05T15:04:07.497Z] [2025-09-05T15:04:07.497Z] TEST SETUP: [2025-09-05T15:04:07.497Z] Nothing to be done for setup. [2025-09-05T15:04:07.497Z] [2025-09-05T15:04:07.497Z] TESTING: [2025-09-05T15:04:08.143Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-09-05T15:04:08.143Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17570841288023/renaissance-dec-tree_0/launcher-150407-3805126053546547565/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-09-05T15:04:08.143Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-09-05T15:04:08.143Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-09-05T15:04:16.866Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-09-05T15:04:18.203Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-09-05T15:04:18.853Z] GC before operation: completed in 42.959 ms, heap usage 46.514 MB -> 34.969 MB. [2025-09-05T15:04:29.282Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10466.321 ms) ====== [2025-09-05T15:04:29.282Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-09-05T15:04:29.282Z] GC before operation: completed in 110.821 ms, heap usage 274.769 MB -> 67.699 MB. [2025-09-05T15:04:32.200Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2766.592 ms) ====== [2025-09-05T15:04:32.200Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-09-05T15:04:32.200Z] GC before operation: completed in 91.295 ms, heap usage 222.898 MB -> 67.976 MB. [2025-09-05T15:04:34.295Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2200.495 ms) ====== [2025-09-05T15:04:34.295Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-09-05T15:04:34.295Z] GC before operation: completed in 89.477 ms, heap usage 280.971 MB -> 68.194 MB. [2025-09-05T15:04:36.388Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1931.513 ms) ====== [2025-09-05T15:04:36.388Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-09-05T15:04:36.388Z] GC before operation: completed in 88.242 ms, heap usage 200.220 MB -> 68.437 MB. [2025-09-05T15:04:38.478Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1851.347 ms) ====== [2025-09-05T15:04:38.478Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-09-05T15:04:38.478Z] GC before operation: completed in 88.033 ms, heap usage 378.492 MB -> 69.131 MB. [2025-09-05T15:04:39.810Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1707.420 ms) ====== [2025-09-05T15:04:39.810Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-09-05T15:04:39.810Z] GC before operation: completed in 95.605 ms, heap usage 351.504 MB -> 68.873 MB. [2025-09-05T15:04:41.901Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1722.047 ms) ====== [2025-09-05T15:04:41.901Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-09-05T15:04:41.901Z] GC before operation: completed in 89.974 ms, heap usage 207.079 MB -> 69.032 MB. [2025-09-05T15:04:43.235Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1614.070 ms) ====== [2025-09-05T15:04:43.235Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-09-05T15:04:43.942Z] GC before operation: completed in 103.757 ms, heap usage 209.402 MB -> 69.236 MB. [2025-09-05T15:04:45.283Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1805.306 ms) ====== [2025-09-05T15:04:45.283Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-09-05T15:04:45.283Z] GC before operation: completed in 95.168 ms, heap usage 333.074 MB -> 69.386 MB. [2025-09-05T15:04:47.369Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1513.239 ms) ====== [2025-09-05T15:04:47.369Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-09-05T15:04:47.369Z] GC before operation: completed in 92.002 ms, heap usage 209.202 MB -> 69.486 MB. [2025-09-05T15:04:48.702Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1590.447 ms) ====== [2025-09-05T15:04:48.702Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-09-05T15:04:48.702Z] GC before operation: completed in 131.815 ms, heap usage 333.443 MB -> 78.070 MB. [2025-09-05T15:04:50.087Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1444.461 ms) ====== [2025-09-05T15:04:50.087Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-09-05T15:04:50.087Z] GC before operation: completed in 95.999 ms, heap usage 165.396 MB -> 69.447 MB. [2025-09-05T15:04:52.584Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1552.937 ms) ====== [2025-09-05T15:04:52.584Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-09-05T15:04:52.584Z] GC before operation: completed in 99.198 ms, heap usage 410.370 MB -> 70.109 MB. [2025-09-05T15:04:53.924Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1547.662 ms) ====== [2025-09-05T15:04:53.924Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-09-05T15:04:53.924Z] GC before operation: completed in 107.068 ms, heap usage 346.327 MB -> 69.947 MB. [2025-09-05T15:04:55.259Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1581.271 ms) ====== [2025-09-05T15:04:55.259Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-09-05T15:04:55.259Z] GC before operation: completed in 100.615 ms, heap usage 338.484 MB -> 70.019 MB. [2025-09-05T15:04:56.597Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1344.665 ms) ====== [2025-09-05T15:04:56.597Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-09-05T15:04:56.597Z] GC before operation: completed in 105.043 ms, heap usage 315.712 MB -> 70.232 MB. [2025-09-05T15:04:57.932Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1363.864 ms) ====== [2025-09-05T15:04:57.932Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-09-05T15:04:58.573Z] GC before operation: completed in 110.020 ms, heap usage 263.916 MB -> 97.879 MB. [2025-09-05T15:04:59.936Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1396.143 ms) ====== [2025-09-05T15:04:59.936Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-09-05T15:04:59.936Z] GC before operation: completed in 109.033 ms, heap usage 503.243 MB -> 70.671 MB. [2025-09-05T15:05:01.293Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1437.474 ms) ====== [2025-09-05T15:05:01.293Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-09-05T15:05:01.293Z] GC before operation: completed in 106.580 ms, heap usage 146.193 MB -> 70.498 MB. [2025-09-05T15:05:02.627Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1371.741 ms) ====== [2025-09-05T15:05:02.627Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-09-05T15:05:02.627Z] GC before operation: completed in 112.672 ms, heap usage 486.896 MB -> 70.769 MB. [2025-09-05T15:05:04.065Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1369.454 ms) ====== [2025-09-05T15:05:04.065Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-09-05T15:05:04.065Z] GC before operation: completed in 107.679 ms, heap usage 290.409 MB -> 70.784 MB. [2025-09-05T15:05:05.409Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1405.891 ms) ====== [2025-09-05T15:05:05.409Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-09-05T15:05:06.051Z] GC before operation: completed in 120.299 ms, heap usage 249.559 MB -> 70.733 MB. [2025-09-05T15:05:07.407Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1275.996 ms) ====== [2025-09-05T15:05:07.407Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-09-05T15:05:07.407Z] GC before operation: completed in 117.685 ms, heap usage 249.997 MB -> 70.970 MB. [2025-09-05T15:05:08.740Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1409.606 ms) ====== [2025-09-05T15:05:08.740Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-09-05T15:05:08.740Z] GC before operation: completed in 111.695 ms, heap usage 219.083 MB -> 70.992 MB. [2025-09-05T15:05:10.082Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1344.878 ms) ====== [2025-09-05T15:05:10.082Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-09-05T15:05:10.082Z] GC before operation: completed in 107.424 ms, heap usage 235.620 MB -> 71.111 MB. [2025-09-05T15:05:11.415Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1482.585 ms) ====== [2025-09-05T15:05:11.415Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-09-05T15:05:12.055Z] GC before operation: completed in 116.466 ms, heap usage 249.427 MB -> 71.186 MB. [2025-09-05T15:05:13.390Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1528.528 ms) ====== [2025-09-05T15:05:13.390Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-09-05T15:05:13.390Z] GC before operation: completed in 120.807 ms, heap usage 233.831 MB -> 71.383 MB. [2025-09-05T15:05:14.729Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1317.309 ms) ====== [2025-09-05T15:05:14.729Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-09-05T15:05:14.729Z] GC before operation: completed in 114.973 ms, heap usage 394.631 MB -> 71.616 MB. [2025-09-05T15:05:16.064Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1346.945 ms) ====== [2025-09-05T15:05:16.064Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-09-05T15:05:16.064Z] GC before operation: completed in 110.457 ms, heap usage 265.032 MB -> 71.598 MB. [2025-09-05T15:05:17.397Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1313.412 ms) ====== [2025-09-05T15:05:17.397Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-09-05T15:05:18.042Z] GC before operation: completed in 122.396 ms, heap usage 250.037 MB -> 99.218 MB. [2025-09-05T15:05:18.042Z] 15:05:17.428 WARN [block-manager-storage-async-thread-pool-65] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-09-05T15:05:19.378Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1298.663 ms) ====== [2025-09-05T15:05:19.378Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-09-05T15:05:19.378Z] GC before operation: completed in 115.941 ms, heap usage 503.455 MB -> 71.969 MB. [2025-09-05T15:05:20.713Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1360.750 ms) ====== [2025-09-05T15:05:20.713Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-09-05T15:05:20.713Z] GC before operation: completed in 112.119 ms, heap usage 148.298 MB -> 71.687 MB. [2025-09-05T15:05:22.046Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1387.745 ms) ====== [2025-09-05T15:05:22.046Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-09-05T15:05:22.046Z] GC before operation: completed in 124.667 ms, heap usage 469.034 MB -> 72.650 MB. [2025-09-05T15:05:23.379Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1286.995 ms) ====== [2025-09-05T15:05:23.379Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-09-05T15:05:23.379Z] GC before operation: completed in 116.221 ms, heap usage 238.772 MB -> 72.159 MB. [2025-09-05T15:05:24.712Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1317.849 ms) ====== [2025-09-05T15:05:24.712Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-09-05T15:05:25.355Z] GC before operation: completed in 124.791 ms, heap usage 234.856 MB -> 72.317 MB. [2025-09-05T15:05:26.696Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1426.174 ms) ====== [2025-09-05T15:05:26.696Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-09-05T15:05:26.696Z] GC before operation: completed in 121.822 ms, heap usage 237.053 MB -> 72.326 MB. [2025-09-05T15:05:28.029Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1358.293 ms) ====== [2025-09-05T15:05:28.029Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-09-05T15:05:28.029Z] GC before operation: completed in 118.369 ms, heap usage 228.287 MB -> 72.461 MB. [2025-09-05T15:05:29.850Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1288.199 ms) ====== [2025-09-05T15:05:29.851Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-09-05T15:05:29.851Z] GC before operation: completed in 118.702 ms, heap usage 237.022 MB -> 72.590 MB. [2025-09-05T15:05:31.197Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1397.292 ms) ====== [2025-09-05T15:05:31.197Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-09-05T15:05:31.197Z] GC before operation: completed in 118.020 ms, heap usage 218.652 MB -> 72.593 MB. [2025-09-05T15:05:32.524Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1341.356 ms) ====== [2025-09-05T15:05:32.524Z] ----------------------------------- [2025-09-05T15:05:32.524Z] renaissance-dec-tree_0_PASSED [2025-09-05T15:05:32.524Z] ----------------------------------- [2025-09-05T15:05:32.524Z] [2025-09-05T15:05:32.524Z] TEST TEARDOWN: [2025-09-05T15:05:32.524Z] Nothing to be done for teardown. [2025-09-05T15:05:32.524Z] renaissance-dec-tree_0 Finish Time: Fri Sep 5 15:05:32 2025 Epoch Time (ms): 1757084732380