[2025-07-30T20:07:22.806Z] Running test renaissance-dec-tree_0 ... [2025-07-30T20:07:22.806Z] =============================================== [2025-07-30T20:07:22.806Z] renaissance-dec-tree_0 Start Time: Wed Jul 30 15:07:21 2025 Epoch Time (ms): 1753906041856 [2025-07-30T20:07:22.806Z] variation: NoOptions [2025-07-30T20:07:22.806Z] JVM_OPTIONS: [2025-07-30T20:07:22.806Z] { \ [2025-07-30T20:07:22.806Z] echo ""; echo "TEST SETUP:"; \ [2025-07-30T20:07:22.806Z] echo "Nothing to be done for setup."; \ [2025-07-30T20:07:22.806Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17539053688225/renaissance-dec-tree_0"; \ [2025-07-30T20:07:22.806Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17539053688225/renaissance-dec-tree_0"; \ [2025-07-30T20:07:22.806Z] echo ""; echo "TESTING:"; \ [2025-07-30T20:07:22.806Z] "/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_17539053688225/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-07-30T20:07:22.806Z] 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_17539053688225/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-30T20:07:22.806Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-30T20:07:22.806Z] echo "Nothing to be done for teardown."; \ [2025-07-30T20:07:22.806Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17539053688225/TestTargetResult"; [2025-07-30T20:07:22.806Z] [2025-07-30T20:07:22.806Z] TEST SETUP: [2025-07-30T20:07:22.806Z] Nothing to be done for setup. [2025-07-30T20:07:22.806Z] [2025-07-30T20:07:22.806Z] TESTING: [2025-07-30T20:07:28.669Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-07-30T20:07:31.445Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-07-30T20:07:31.445Z] GC before operation: completed in 43.929 ms, heap usage 55.164 MB -> 35.093 MB. [2025-07-30T20:07:39.881Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8976.575 ms) ====== [2025-07-30T20:07:39.881Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-07-30T20:07:39.881Z] GC before operation: completed in 161.964 ms, heap usage 125.209 MB -> 95.673 MB. [2025-07-30T20:07:43.344Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2731.463 ms) ====== [2025-07-30T20:07:43.344Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-07-30T20:07:43.344Z] GC before operation: completed in 141.334 ms, heap usage 248.774 MB -> 68.372 MB. [2025-07-30T20:07:45.587Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2777.789 ms) ====== [2025-07-30T20:07:45.587Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-07-30T20:07:46.271Z] GC before operation: completed in 191.138 ms, heap usage 162.795 MB -> 68.994 MB. [2025-07-30T20:07:49.204Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3293.398 ms) ====== [2025-07-30T20:07:49.204Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-07-30T20:07:49.818Z] GC before operation: completed in 216.262 ms, heap usage 155.301 MB -> 68.812 MB. [2025-07-30T20:07:52.655Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2775.813 ms) ====== [2025-07-30T20:07:52.655Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-07-30T20:07:52.655Z] GC before operation: completed in 172.808 ms, heap usage 168.979 MB -> 69.019 MB. [2025-07-30T20:07:55.475Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2582.420 ms) ====== [2025-07-30T20:07:55.475Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-07-30T20:07:55.475Z] GC before operation: completed in 116.620 ms, heap usage 207.923 MB -> 69.569 MB. [2025-07-30T20:07:57.509Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2342.685 ms) ====== [2025-07-30T20:07:57.509Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-07-30T20:07:57.509Z] GC before operation: completed in 113.006 ms, heap usage 284.908 MB -> 77.954 MB. [2025-07-30T20:08:00.344Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2477.505 ms) ====== [2025-07-30T20:08:00.344Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-07-30T20:08:00.344Z] GC before operation: completed in 153.387 ms, heap usage 254.189 MB -> 97.230 MB. [2025-07-30T20:08:02.366Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2305.620 ms) ====== [2025-07-30T20:08:02.366Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-07-30T20:08:02.997Z] GC before operation: completed in 139.000 ms, heap usage 157.290 MB -> 69.458 MB. [2025-07-30T20:08:04.995Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2605.693 ms) ====== [2025-07-30T20:08:04.995Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-07-30T20:08:05.621Z] GC before operation: completed in 120.956 ms, heap usage 191.363 MB -> 69.918 MB. [2025-07-30T20:08:07.618Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2450.195 ms) ====== [2025-07-30T20:08:07.618Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-07-30T20:08:08.242Z] GC before operation: completed in 149.624 ms, heap usage 257.065 MB -> 70.143 MB. [2025-07-30T20:08:10.265Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2219.100 ms) ====== [2025-07-30T20:08:10.265Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-07-30T20:08:10.265Z] GC before operation: completed in 183.587 ms, heap usage 128.828 MB -> 89.192 MB. [2025-07-30T20:08:13.022Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2188.652 ms) ====== [2025-07-30T20:08:13.022Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-07-30T20:08:13.022Z] GC before operation: completed in 136.727 ms, heap usage 149.271 MB -> 70.361 MB. [2025-07-30T20:08:15.017Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2073.727 ms) ====== [2025-07-30T20:08:15.017Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-07-30T20:08:15.017Z] GC before operation: completed in 166.529 ms, heap usage 230.786 MB -> 89.812 MB. [2025-07-30T20:08:17.003Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2024.931 ms) ====== [2025-07-30T20:08:17.003Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-07-30T20:08:17.003Z] GC before operation: completed in 183.626 ms, heap usage 178.041 MB -> 70.283 MB. [2025-07-30T20:08:18.951Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2000.372 ms) ====== [2025-07-30T20:08:18.951Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-07-30T20:08:19.567Z] GC before operation: completed in 189.091 ms, heap usage 332.257 MB -> 79.086 MB. [2025-07-30T20:08:21.560Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1977.416 ms) ====== [2025-07-30T20:08:21.560Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-07-30T20:08:21.560Z] GC before operation: completed in 107.807 ms, heap usage 293.432 MB -> 70.660 MB. [2025-07-30T20:08:23.578Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2145.859 ms) ====== [2025-07-30T20:08:23.578Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-07-30T20:08:23.578Z] GC before operation: completed in 217.854 ms, heap usage 391.610 MB -> 98.408 MB. [2025-07-30T20:08:26.457Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2265.301 ms) ====== [2025-07-30T20:08:26.457Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-07-30T20:08:26.457Z] GC before operation: completed in 135.418 ms, heap usage 137.218 MB -> 70.877 MB. [2025-07-30T20:08:28.439Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1832.290 ms) ====== [2025-07-30T20:08:28.439Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-07-30T20:08:28.439Z] GC before operation: completed in 160.510 ms, heap usage 324.002 MB -> 71.058 MB. [2025-07-30T20:08:30.429Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1881.296 ms) ====== [2025-07-30T20:08:30.429Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-07-30T20:08:30.429Z] GC before operation: completed in 133.407 ms, heap usage 310.747 MB -> 98.858 MB. [2025-07-30T20:08:32.400Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1940.521 ms) ====== [2025-07-30T20:08:32.400Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-07-30T20:08:32.400Z] GC before operation: completed in 149.063 ms, heap usage 140.905 MB -> 71.353 MB. [2025-07-30T20:08:34.820Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1855.814 ms) ====== [2025-07-30T20:08:34.820Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-07-30T20:08:34.820Z] GC before operation: completed in 171.643 ms, heap usage 295.641 MB -> 90.812 MB. [2025-07-30T20:08:36.139Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2057.838 ms) ====== [2025-07-30T20:08:36.139Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-07-30T20:08:36.743Z] GC before operation: completed in 207.989 ms, heap usage 256.448 MB -> 99.170 MB. [2025-07-30T20:08:36.743Z] 15:08:36.295 WARN [block-manager-storage-async-thread-pool-22] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_331_piece0, which does not exist [2025-07-30T20:08:38.771Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1918.977 ms) ====== [2025-07-30T20:08:38.771Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-07-30T20:08:38.771Z] GC before operation: completed in 131.205 ms, heap usage 173.872 MB -> 71.467 MB. [2025-07-30T20:08:40.753Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1921.465 ms) ====== [2025-07-30T20:08:40.753Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-07-30T20:08:40.753Z] GC before operation: completed in 152.868 ms, heap usage 367.451 MB -> 99.422 MB. [2025-07-30T20:08:42.774Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1900.479 ms) ====== [2025-07-30T20:08:42.774Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-07-30T20:08:42.774Z] GC before operation: completed in 122.373 ms, heap usage 307.752 MB -> 72.227 MB. [2025-07-30T20:08:44.787Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1854.022 ms) ====== [2025-07-30T20:08:44.787Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-07-30T20:08:44.787Z] GC before operation: completed in 237.127 ms, heap usage 225.172 MB -> 72.098 MB. [2025-07-30T20:08:47.635Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2294.095 ms) ====== [2025-07-30T20:08:47.635Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-07-30T20:08:47.635Z] GC before operation: completed in 200.388 ms, heap usage 225.123 MB -> 99.701 MB. [2025-07-30T20:08:47.635Z] 15:08:47.020 WARN [block-manager-storage-async-thread-pool-73] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_405_piece0, which does not exist [2025-07-30T20:08:49.651Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1921.980 ms) ====== [2025-07-30T20:08:49.651Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-07-30T20:08:49.651Z] GC before operation: completed in 140.154 ms, heap usage 223.298 MB -> 72.506 MB. [2025-07-30T20:08:49.651Z] 15:08:49.082 WARN [block-manager-storage-async-thread-pool-42] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-07-30T20:08:51.687Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2061.539 ms) ====== [2025-07-30T20:08:51.687Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-07-30T20:08:51.687Z] GC before operation: completed in 154.457 ms, heap usage 230.597 MB -> 72.264 MB. [2025-07-30T20:08:53.677Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2045.291 ms) ====== [2025-07-30T20:08:53.677Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-07-30T20:08:53.677Z] GC before operation: completed in 148.606 ms, heap usage 310.111 MB -> 91.904 MB. [2025-07-30T20:08:55.708Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2134.739 ms) ====== [2025-07-30T20:08:55.708Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-07-30T20:08:56.318Z] GC before operation: completed in 167.366 ms, heap usage 139.496 MB -> 72.675 MB. [2025-07-30T20:08:58.384Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2024.534 ms) ====== [2025-07-30T20:08:58.384Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-07-30T20:08:58.384Z] GC before operation: completed in 234.273 ms, heap usage 248.841 MB -> 72.761 MB. [2025-07-30T20:09:01.190Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2332.562 ms) ====== [2025-07-30T20:09:01.191Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-07-30T20:09:01.191Z] GC before operation: completed in 172.431 ms, heap usage 223.105 MB -> 72.897 MB. [2025-07-30T20:09:03.257Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2103.991 ms) ====== [2025-07-30T20:09:03.257Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-07-30T20:09:03.257Z] GC before operation: completed in 173.178 ms, heap usage 222.309 MB -> 72.760 MB. [2025-07-30T20:09:05.327Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2049.649 ms) ====== [2025-07-30T20:09:05.327Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-07-30T20:09:05.327Z] GC before operation: completed in 176.319 ms, heap usage 201.162 MB -> 72.891 MB. [2025-07-30T20:09:07.333Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1891.522 ms) ====== [2025-07-30T20:09:07.333Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-07-30T20:09:07.333Z] GC before operation: completed in 115.687 ms, heap usage 117.484 MB -> 72.399 MB. [2025-07-30T20:09:09.365Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1976.327 ms) ====== [2025-07-30T20:09:09.365Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-07-30T20:09:09.365Z] GC before operation: completed in 145.494 ms, heap usage 185.376 MB -> 73.546 MB. [2025-07-30T20:09:12.201Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2197.558 ms) ====== [2025-07-30T20:09:12.201Z] ----------------------------------- [2025-07-30T20:09:12.201Z] renaissance-dec-tree_0_PASSED [2025-07-30T20:09:12.201Z] ----------------------------------- [2025-07-30T20:09:12.201Z] [2025-07-30T20:09:12.201Z] TEST TEARDOWN: [2025-07-30T20:09:12.201Z] Nothing to be done for teardown. [2025-07-30T20:09:12.201Z] renaissance-dec-tree_0 Finish Time: Wed Jul 30 15:09:11 2025 Epoch Time (ms): 1753906151827