[2025-10-03T20:51:12.112Z] Running test renaissance-dec-tree_0 ... [2025-10-03T20:51:12.112Z] =============================================== [2025-10-03T20:51:12.112Z] renaissance-dec-tree_0 Start Time: Fri Oct 3 20:51:11 2025 Epoch Time (ms): 1759524671459 [2025-10-03T20:51:12.112Z] variation: NoOptions [2025-10-03T20:51:12.112Z] JVM_OPTIONS: [2025-10-03T20:51:12.112Z] { \ [2025-10-03T20:51:12.112Z] echo ""; echo "TEST SETUP:"; \ [2025-10-03T20:51:12.112Z] echo "Nothing to be done for setup."; \ [2025-10-03T20:51:12.112Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/renaissance-dec-tree_0"; \ [2025-10-03T20:51:12.112Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/renaissance-dec-tree_0"; \ [2025-10-03T20:51:12.112Z] echo ""; echo "TESTING:"; \ [2025-10-03T20:51:12.112Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_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_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-10-03T20:51:12.112Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-03T20:51:12.112Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-03T20:51:12.112Z] echo "Nothing to be done for teardown."; \ [2025-10-03T20:51:12.112Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/TestTargetResult"; [2025-10-03T20:51:12.112Z] [2025-10-03T20:51:12.112Z] TEST SETUP: [2025-10-03T20:51:12.112Z] Nothing to be done for setup. [2025-10-03T20:51:12.112Z] [2025-10-03T20:51:12.112Z] TESTING: [2025-10-03T20:51:21.019Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-10-03T20:51:25.735Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-10-03T20:51:25.735Z] GC before operation: completed in 225.113 ms, heap usage 39.898 MB -> 34.932 MB. [2025-10-03T20:51:40.757Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (14861.895 ms) ====== [2025-10-03T20:51:40.757Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-10-03T20:51:40.757Z] GC before operation: completed in 255.356 ms, heap usage 363.437 MB -> 67.969 MB. [2025-10-03T20:51:44.758Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3914.832 ms) ====== [2025-10-03T20:51:44.758Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-10-03T20:51:44.758Z] GC before operation: completed in 172.435 ms, heap usage 274.412 MB -> 68.191 MB. [2025-10-03T20:51:47.812Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3466.337 ms) ====== [2025-10-03T20:51:47.812Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-10-03T20:51:48.485Z] GC before operation: completed in 316.774 ms, heap usage 205.578 MB -> 68.433 MB. [2025-10-03T20:51:52.455Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3723.578 ms) ====== [2025-10-03T20:51:52.455Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-10-03T20:51:52.455Z] GC before operation: completed in 135.429 ms, heap usage 353.583 MB -> 68.765 MB. [2025-10-03T20:51:55.533Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3492.162 ms) ====== [2025-10-03T20:51:55.533Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-10-03T20:51:56.179Z] GC before operation: completed in 374.005 ms, heap usage 220.642 MB -> 68.843 MB. [2025-10-03T20:51:59.310Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2895.284 ms) ====== [2025-10-03T20:51:59.310Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-10-03T20:51:59.310Z] GC before operation: completed in 297.275 ms, heap usage 169.489 MB -> 88.201 MB. [2025-10-03T20:52:02.519Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2942.120 ms) ====== [2025-10-03T20:52:02.519Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-10-03T20:52:02.519Z] GC before operation: completed in 172.799 ms, heap usage 165.137 MB -> 96.456 MB. [2025-10-03T20:52:02.519Z] 20:52:02.069 WARN [block-manager-storage-async-thread-pool-53] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_93_piece0, which does not exist [2025-10-03T20:52:05.989Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3411.312 ms) ====== [2025-10-03T20:52:05.989Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-10-03T20:52:05.989Z] GC before operation: completed in 146.940 ms, heap usage 104.679 MB -> 96.786 MB. [2025-10-03T20:52:08.989Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2924.476 ms) ====== [2025-10-03T20:52:08.989Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-10-03T20:52:08.989Z] GC before operation: completed in 245.830 ms, heap usage 450.136 MB -> 69.593 MB. [2025-10-03T20:52:12.975Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3559.854 ms) ====== [2025-10-03T20:52:12.975Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-10-03T20:52:12.975Z] GC before operation: completed in 133.930 ms, heap usage 222.526 MB -> 69.875 MB. [2025-10-03T20:52:15.970Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3262.797 ms) ====== [2025-10-03T20:52:15.970Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-10-03T20:52:16.681Z] GC before operation: completed in 216.993 ms, heap usage 259.796 MB -> 69.953 MB. [2025-10-03T20:52:19.817Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3317.050 ms) ====== [2025-10-03T20:52:19.817Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-10-03T20:52:19.817Z] GC before operation: completed in 231.507 ms, heap usage 331.181 MB -> 69.983 MB. [2025-10-03T20:52:22.935Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3005.651 ms) ====== [2025-10-03T20:52:22.935Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-10-03T20:52:22.935Z] GC before operation: completed in 224.777 ms, heap usage 331.464 MB -> 97.684 MB. [2025-10-03T20:52:26.943Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3450.279 ms) ====== [2025-10-03T20:52:26.944Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-10-03T20:52:26.944Z] GC before operation: completed in 199.126 ms, heap usage 129.959 MB -> 70.049 MB. [2025-10-03T20:52:29.127Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2398.430 ms) ====== [2025-10-03T20:52:29.127Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-10-03T20:52:29.127Z] GC before operation: completed in 198.260 ms, heap usage 324.127 MB -> 70.422 MB. [2025-10-03T20:52:32.310Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2768.754 ms) ====== [2025-10-03T20:52:32.310Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-10-03T20:52:33.054Z] GC before operation: completed in 661.346 ms, heap usage 149.377 MB -> 97.801 MB. [2025-10-03T20:52:36.038Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3161.713 ms) ====== [2025-10-03T20:52:36.038Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-10-03T20:52:36.038Z] GC before operation: completed in 143.019 ms, heap usage 365.373 MB -> 70.726 MB. [2025-10-03T20:52:39.108Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2989.809 ms) ====== [2025-10-03T20:52:39.108Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-10-03T20:52:39.800Z] GC before operation: completed in 454.618 ms, heap usage 304.315 MB -> 70.806 MB. [2025-10-03T20:52:44.701Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4021.339 ms) ====== [2025-10-03T20:52:44.701Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-10-03T20:52:44.701Z] GC before operation: completed in 158.851 ms, heap usage 312.892 MB -> 70.907 MB. [2025-10-03T20:52:46.981Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2987.340 ms) ====== [2025-10-03T20:52:46.981Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-10-03T20:52:46.981Z] GC before operation: completed in 320.810 ms, heap usage 137.007 MB -> 70.940 MB. [2025-10-03T20:52:50.936Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3569.102 ms) ====== [2025-10-03T20:52:50.936Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-10-03T20:52:50.936Z] GC before operation: completed in 276.299 ms, heap usage 229.403 MB -> 71.052 MB. [2025-10-03T20:52:53.992Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2997.988 ms) ====== [2025-10-03T20:52:53.992Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-10-03T20:52:53.992Z] GC before operation: completed in 293.072 ms, heap usage 268.351 MB -> 71.192 MB. [2025-10-03T20:52:57.973Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3427.745 ms) ====== [2025-10-03T20:52:57.973Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-10-03T20:52:57.973Z] GC before operation: completed in 272.736 ms, heap usage 318.389 MB -> 71.355 MB. [2025-10-03T20:53:01.086Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3081.375 ms) ====== [2025-10-03T20:53:01.086Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-10-03T20:53:01.086Z] GC before operation: completed in 322.682 ms, heap usage 327.306 MB -> 71.460 MB. [2025-10-03T20:53:04.102Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2844.444 ms) ====== [2025-10-03T20:53:04.103Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-10-03T20:53:04.103Z] GC before operation: completed in 212.199 ms, heap usage 153.396 MB -> 71.275 MB. [2025-10-03T20:53:07.123Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2677.138 ms) ====== [2025-10-03T20:53:07.123Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-10-03T20:53:07.124Z] GC before operation: completed in 200.748 ms, heap usage 257.667 MB -> 99.235 MB. [2025-10-03T20:53:10.093Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3016.943 ms) ====== [2025-10-03T20:53:10.093Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-10-03T20:53:10.768Z] GC before operation: completed in 259.263 ms, heap usage 306.439 MB -> 71.788 MB. [2025-10-03T20:53:13.848Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3607.237 ms) ====== [2025-10-03T20:53:13.848Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-10-03T20:53:14.559Z] GC before operation: completed in 327.063 ms, heap usage 306.400 MB -> 80.267 MB. [2025-10-03T20:53:17.702Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3511.190 ms) ====== [2025-10-03T20:53:17.702Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-10-03T20:53:18.457Z] GC before operation: completed in 244.746 ms, heap usage 286.902 MB -> 71.986 MB. [2025-10-03T20:53:21.640Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2971.341 ms) ====== [2025-10-03T20:53:21.640Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-10-03T20:53:21.640Z] GC before operation: completed in 528.692 ms, heap usage 298.705 MB -> 72.360 MB. [2025-10-03T20:53:24.897Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3012.557 ms) ====== [2025-10-03T20:53:24.897Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-10-03T20:53:24.897Z] GC before operation: completed in 427.359 ms, heap usage 292.392 MB -> 72.283 MB. [2025-10-03T20:53:28.478Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3277.628 ms) ====== [2025-10-03T20:53:28.478Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-10-03T20:53:28.478Z] GC before operation: completed in 211.846 ms, heap usage 214.940 MB -> 72.329 MB. [2025-10-03T20:53:31.527Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2706.996 ms) ====== [2025-10-03T20:53:31.527Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-10-03T20:53:31.527Z] GC before operation: completed in 374.753 ms, heap usage 112.087 MB -> 71.835 MB. [2025-10-03T20:53:34.679Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3226.752 ms) ====== [2025-10-03T20:53:34.679Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-10-03T20:53:35.338Z] GC before operation: completed in 268.588 ms, heap usage 422.913 MB -> 72.795 MB. [2025-10-03T20:53:38.366Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2967.086 ms) ====== [2025-10-03T20:53:38.366Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-10-03T20:53:38.366Z] GC before operation: completed in 412.759 ms, heap usage 292.475 MB -> 72.953 MB. [2025-10-03T20:53:42.490Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3363.330 ms) ====== [2025-10-03T20:53:42.490Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-10-03T20:53:42.490Z] GC before operation: completed in 285.900 ms, heap usage 211.849 MB -> 72.713 MB. [2025-10-03T20:53:45.640Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2923.504 ms) ====== [2025-10-03T20:53:45.641Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-10-03T20:53:45.641Z] GC before operation: completed in 351.829 ms, heap usage 170.063 MB -> 99.978 MB. [2025-10-03T20:53:48.804Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3107.609 ms) ====== [2025-10-03T20:53:48.804Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-10-03T20:53:48.804Z] GC before operation: completed in 236.634 ms, heap usage 173.603 MB -> 100.072 MB. [2025-10-03T20:53:51.841Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2583.752 ms) ====== [2025-10-03T20:53:51.841Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-10-03T20:53:51.841Z] GC before operation: completed in 274.945 ms, heap usage 272.013 MB -> 73.313 MB. [2025-10-03T20:53:54.940Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3439.659 ms) ====== [2025-10-03T20:53:55.676Z] ----------------------------------- [2025-10-03T20:53:55.676Z] renaissance-dec-tree_0_PASSED [2025-10-03T20:53:55.676Z] ----------------------------------- [2025-10-03T20:53:55.676Z] [2025-10-03T20:53:55.676Z] TEST TEARDOWN: [2025-10-03T20:53:55.676Z] Nothing to be done for teardown. [2025-10-03T20:53:55.676Z] renaissance-dec-tree_0 Finish Time: Fri Oct 3 20:53:55 2025 Epoch Time (ms): 1759524835318