[2025-08-28T05:49:13.127Z] Running test renaissance-dec-tree_0 ... [2025-08-28T05:49:13.127Z] =============================================== [2025-08-28T05:49:13.127Z] renaissance-dec-tree_0 Start Time: Thu Aug 28 05:49:12 2025 Epoch Time (ms): 1756360152464 [2025-08-28T05:49:13.127Z] variation: NoOptions [2025-08-28T05:49:13.127Z] JVM_OPTIONS: [2025-08-28T05:49:13.127Z] { \ [2025-08-28T05:49:13.128Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T05:49:13.128Z] echo "Nothing to be done for setup."; \ [2025-08-28T05:49:13.128Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-dec-tree_0"; \ [2025-08-28T05:49:13.128Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-dec-tree_0"; \ [2025-08-28T05:49:13.128Z] echo ""; echo "TESTING:"; \ [2025-08-28T05:49:13.128Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/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_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-28T05:49:13.128Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T05:49:13.128Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T05:49:13.128Z] echo "Nothing to be done for teardown."; \ [2025-08-28T05:49:13.128Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/TestTargetResult"; [2025-08-28T05:49:13.128Z] [2025-08-28T05:49:13.128Z] TEST SETUP: [2025-08-28T05:49:13.128Z] Nothing to be done for setup. [2025-08-28T05:49:13.128Z] [2025-08-28T05:49:13.128Z] TESTING: [2025-08-28T05:49:34.142Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-28T05:49:39.446Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-28T05:49:39.446Z] GC before operation: completed in 104.654 ms, heap usage 69.375 MB -> 35.157 MB. [2025-08-28T05:50:07.838Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (25472.226 ms) ====== [2025-08-28T05:50:07.838Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-28T05:50:07.838Z] GC before operation: completed in 168.046 ms, heap usage 211.649 MB -> 69.274 MB. [2025-08-28T05:50:10.836Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (5212.520 ms) ====== [2025-08-28T05:50:10.836Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-28T05:50:10.836Z] GC before operation: completed in 198.499 ms, heap usage 276.298 MB -> 75.019 MB. [2025-08-28T05:50:14.960Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4258.241 ms) ====== [2025-08-28T05:50:14.960Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-28T05:50:14.960Z] GC before operation: completed in 169.264 ms, heap usage 273.566 MB -> 70.026 MB. [2025-08-28T05:50:19.766Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3419.389 ms) ====== [2025-08-28T05:50:19.766Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-28T05:50:19.766Z] GC before operation: completed in 149.164 ms, heap usage 212.668 MB -> 70.181 MB. [2025-08-28T05:50:21.700Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3462.838 ms) ====== [2025-08-28T05:50:21.700Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-28T05:50:22.643Z] GC before operation: completed in 167.023 ms, heap usage 201.782 MB -> 70.414 MB. [2025-08-28T05:50:25.633Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3161.649 ms) ====== [2025-08-28T05:50:25.633Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-28T05:50:25.633Z] GC before operation: completed in 161.759 ms, heap usage 171.289 MB -> 70.271 MB. [2025-08-28T05:50:28.616Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3371.059 ms) ====== [2025-08-28T05:50:28.616Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-28T05:50:29.555Z] GC before operation: completed in 172.799 ms, heap usage 208.729 MB -> 70.662 MB. [2025-08-28T05:50:31.492Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2700.667 ms) ====== [2025-08-28T05:50:31.492Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-28T05:50:32.430Z] GC before operation: completed in 195.696 ms, heap usage 159.458 MB -> 70.781 MB. [2025-08-28T05:50:35.422Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2979.771 ms) ====== [2025-08-28T05:50:35.422Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-28T05:50:35.422Z] GC before operation: completed in 236.378 ms, heap usage 204.304 MB -> 71.065 MB. [2025-08-28T05:50:38.399Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3459.488 ms) ====== [2025-08-28T05:50:38.399Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-28T05:50:39.340Z] GC before operation: completed in 246.619 ms, heap usage 227.680 MB -> 71.075 MB. [2025-08-28T05:50:42.347Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3215.456 ms) ====== [2025-08-28T05:50:42.347Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-28T05:50:42.347Z] GC before operation: completed in 231.663 ms, heap usage 304.464 MB -> 71.391 MB. [2025-08-28T05:50:45.398Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2846.337 ms) ====== [2025-08-28T05:50:45.398Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-28T05:50:45.398Z] GC before operation: completed in 211.263 ms, heap usage 239.204 MB -> 71.697 MB. [2025-08-28T05:50:48.427Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2839.467 ms) ====== [2025-08-28T05:50:48.427Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-28T05:50:48.427Z] GC before operation: completed in 211.349 ms, heap usage 206.644 MB -> 71.591 MB. [2025-08-28T05:50:51.437Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2578.597 ms) ====== [2025-08-28T05:50:51.437Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-28T05:50:51.437Z] GC before operation: completed in 207.485 ms, heap usage 205.874 MB -> 71.597 MB. [2025-08-28T05:50:54.416Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2807.113 ms) ====== [2025-08-28T05:50:54.416Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-28T05:50:54.416Z] GC before operation: completed in 222.200 ms, heap usage 119.259 MB -> 71.494 MB. [2025-08-28T05:50:57.398Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2804.611 ms) ====== [2025-08-28T05:50:57.398Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-28T05:50:57.398Z] GC before operation: completed in 249.000 ms, heap usage 106.858 MB -> 71.475 MB. [2025-08-28T05:51:00.430Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2922.728 ms) ====== [2025-08-28T05:51:00.430Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-28T05:51:00.430Z] GC before operation: completed in 249.041 ms, heap usage 261.661 MB -> 71.906 MB. [2025-08-28T05:51:03.426Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2968.015 ms) ====== [2025-08-28T05:51:03.426Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-28T05:51:03.426Z] GC before operation: completed in 255.581 ms, heap usage 205.276 MB -> 72.141 MB. [2025-08-28T05:51:06.407Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3008.777 ms) ====== [2025-08-28T05:51:06.407Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-28T05:51:07.515Z] GC before operation: completed in 262.014 ms, heap usage 135.055 MB -> 71.920 MB. [2025-08-28T05:51:09.441Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2725.573 ms) ====== [2025-08-28T05:51:09.441Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-28T05:51:10.381Z] GC before operation: completed in 262.566 ms, heap usage 171.708 MB -> 72.030 MB. [2025-08-28T05:51:13.370Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3000.650 ms) ====== [2025-08-28T05:51:13.370Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-28T05:51:13.370Z] GC before operation: completed in 298.324 ms, heap usage 235.748 MB -> 72.356 MB. [2025-08-28T05:51:16.357Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2513.774 ms) ====== [2025-08-28T05:51:16.357Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-28T05:51:16.357Z] GC before operation: completed in 262.728 ms, heap usage 329.594 MB -> 78.310 MB. [2025-08-28T05:51:19.345Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2890.902 ms) ====== [2025-08-28T05:51:19.346Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-28T05:51:19.346Z] GC before operation: completed in 269.568 ms, heap usage 110.080 MB -> 72.788 MB. [2025-08-28T05:51:22.357Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2892.677 ms) ====== [2025-08-28T05:51:22.357Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-28T05:51:22.357Z] GC before operation: completed in 267.205 ms, heap usage 422.389 MB -> 78.649 MB. [2025-08-28T05:51:25.335Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2866.469 ms) ====== [2025-08-28T05:51:25.335Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-28T05:51:25.335Z] GC before operation: completed in 244.796 ms, heap usage 151.028 MB -> 72.502 MB. [2025-08-28T05:51:28.303Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2986.517 ms) ====== [2025-08-28T05:51:28.303Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-28T05:51:29.691Z] GC before operation: completed in 268.599 ms, heap usage 501.856 MB -> 84.225 MB. [2025-08-28T05:51:31.619Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2674.428 ms) ====== [2025-08-28T05:51:31.619Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-28T05:51:31.619Z] GC before operation: completed in 285.312 ms, heap usage 226.874 MB -> 73.377 MB. [2025-08-28T05:51:31.619Z] 05:51:31.326 WARN [block-manager-storage-async-thread-pool-17] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_373, which does not exist [2025-08-28T05:51:34.633Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2756.834 ms) ====== [2025-08-28T05:51:34.633Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-28T05:51:34.633Z] GC before operation: completed in 307.186 ms, heap usage 497.104 MB -> 73.228 MB. [2025-08-28T05:51:37.621Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2758.896 ms) ====== [2025-08-28T05:51:37.621Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-28T05:51:37.621Z] GC before operation: completed in 282.391 ms, heap usage 230.055 MB -> 73.454 MB. [2025-08-28T05:51:40.618Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2644.535 ms) ====== [2025-08-28T05:51:40.618Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-28T05:51:40.618Z] GC before operation: completed in 291.003 ms, heap usage 549.827 MB -> 85.328 MB. [2025-08-28T05:51:43.598Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2732.086 ms) ====== [2025-08-28T05:51:43.598Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-28T05:51:43.598Z] GC before operation: completed in 305.727 ms, heap usage 210.521 MB -> 73.493 MB. [2025-08-28T05:51:46.585Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2579.171 ms) ====== [2025-08-28T05:51:46.585Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-28T05:51:46.585Z] GC before operation: completed in 273.292 ms, heap usage 423.682 MB -> 73.779 MB. [2025-08-28T05:51:49.552Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2506.429 ms) ====== [2025-08-28T05:51:49.552Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-28T05:51:49.552Z] GC before operation: completed in 277.323 ms, heap usage 223.666 MB -> 73.917 MB. [2025-08-28T05:51:52.544Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2853.419 ms) ====== [2025-08-28T05:51:52.544Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-28T05:51:52.544Z] GC before operation: completed in 316.138 ms, heap usage 584.261 MB -> 79.913 MB. [2025-08-28T05:51:55.547Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2484.405 ms) ====== [2025-08-28T05:51:55.547Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-28T05:51:55.547Z] GC before operation: completed in 301.220 ms, heap usage 183.707 MB -> 73.592 MB. [2025-08-28T05:51:58.535Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3033.831 ms) ====== [2025-08-28T05:51:58.535Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-28T05:51:58.535Z] GC before operation: completed in 297.244 ms, heap usage 383.906 MB -> 74.055 MB. [2025-08-28T05:52:01.526Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2532.659 ms) ====== [2025-08-28T05:52:01.526Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-28T05:52:01.526Z] GC before operation: completed in 297.968 ms, heap usage 271.641 MB -> 74.319 MB. [2025-08-28T05:52:04.506Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2707.224 ms) ====== [2025-08-28T05:52:04.506Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-28T05:52:04.506Z] GC before operation: completed in 293.745 ms, heap usage 304.975 MB -> 74.346 MB. [2025-08-28T05:52:06.504Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2245.517 ms) ====== [2025-08-28T05:52:06.504Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-28T05:52:07.450Z] GC before operation: completed in 290.657 ms, heap usage 432.611 MB -> 90.878 MB. [2025-08-28T05:52:09.422Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2583.373 ms) ====== [2025-08-28T05:52:10.355Z] ----------------------------------- [2025-08-28T05:52:10.355Z] renaissance-dec-tree_0_PASSED [2025-08-28T05:52:10.355Z] ----------------------------------- [2025-08-28T05:52:10.355Z] [2025-08-28T05:52:10.355Z] TEST TEARDOWN: [2025-08-28T05:52:10.355Z] Nothing to be done for teardown. [2025-08-28T05:52:10.355Z] renaissance-dec-tree_0 Finish Time: Thu Aug 28 05:52:10 2025 Epoch Time (ms): 1756360330085