[2025-06-19T04:41:04.144Z] Running test renaissance-dec-tree_0 ... [2025-06-19T04:41:04.144Z] =============================================== [2025-06-19T04:41:04.144Z] renaissance-dec-tree_0 Start Time: Thu Jun 19 04:41:03 2025 Epoch Time (ms): 1750308063805 [2025-06-19T04:41:04.144Z] variation: NoOptions [2025-06-19T04:41:04.144Z] JVM_OPTIONS: [2025-06-19T04:41:04.144Z] { \ [2025-06-19T04:41:04.144Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T04:41:04.144Z] echo "Nothing to be done for setup."; \ [2025-06-19T04:41:04.144Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/renaissance-dec-tree_0"; \ [2025-06-19T04:41:04.144Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/renaissance-dec-tree_0"; \ [2025-06-19T04:41:04.144Z] echo ""; echo "TESTING:"; \ [2025-06-19T04:41:04.145Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-19T04:41:04.145Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T04:41:04.145Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T04:41:04.145Z] echo "Nothing to be done for teardown."; \ [2025-06-19T04:41:04.145Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/TestTargetResult"; [2025-06-19T04:41:04.145Z] [2025-06-19T04:41:04.145Z] TEST SETUP: [2025-06-19T04:41:04.145Z] Nothing to be done for setup. [2025-06-19T04:41:04.145Z] [2025-06-19T04:41:04.145Z] TESTING: [2025-06-19T04:41:14.052Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-19T04:41:17.453Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-19T04:41:17.453Z] GC before operation: completed in 65.255 ms, heap usage 75.352 MB -> 34.898 MB. [2025-06-19T04:41:39.686Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (20491.024 ms) ====== [2025-06-19T04:41:39.686Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-19T04:41:39.686Z] GC before operation: completed in 175.695 ms, heap usage 210.034 MB -> 68.033 MB. [2025-06-19T04:41:43.145Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (4650.107 ms) ====== [2025-06-19T04:41:43.145Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-19T04:41:43.145Z] GC before operation: completed in 157.804 ms, heap usage 194.400 MB -> 87.410 MB. [2025-06-19T04:41:43.145Z] 04:41:42.763 WARN [block-manager-storage-async-thread-pool-6] org.apache.spark.storage.BlockManager - Asked to remove block rdd_93_0, which does not exist [2025-06-19T04:41:46.616Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3277.255 ms) ====== [2025-06-19T04:41:46.616Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-19T04:41:46.616Z] GC before operation: completed in 152.269 ms, heap usage 232.331 MB -> 87.712 MB. [2025-06-19T04:41:50.243Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3099.583 ms) ====== [2025-06-19T04:41:50.243Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-19T04:41:50.243Z] GC before operation: completed in 185.240 ms, heap usage 347.394 MB -> 68.656 MB. [2025-06-19T04:41:53.688Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3190.132 ms) ====== [2025-06-19T04:41:53.688Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-19T04:41:53.688Z] GC before operation: completed in 164.497 ms, heap usage 312.057 MB -> 68.847 MB. [2025-06-19T04:41:56.152Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3001.302 ms) ====== [2025-06-19T04:41:56.152Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-19T04:41:56.152Z] GC before operation: completed in 152.242 ms, heap usage 366.391 MB -> 69.462 MB. [2025-06-19T04:41:59.644Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2808.356 ms) ====== [2025-06-19T04:41:59.644Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-19T04:41:59.644Z] GC before operation: completed in 182.308 ms, heap usage 370.374 MB -> 77.670 MB. [2025-06-19T04:42:02.152Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2749.003 ms) ====== [2025-06-19T04:42:02.152Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-19T04:42:02.152Z] GC before operation: completed in 149.077 ms, heap usage 250.084 MB -> 69.575 MB. [2025-06-19T04:42:05.644Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2764.263 ms) ====== [2025-06-19T04:42:05.644Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-19T04:42:05.644Z] GC before operation: completed in 265.392 ms, heap usage 347.162 MB -> 69.759 MB. [2025-06-19T04:42:08.146Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2659.931 ms) ====== [2025-06-19T04:42:08.146Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-19T04:42:08.146Z] GC before operation: completed in 168.593 ms, heap usage 108.156 MB -> 69.234 MB. [2025-06-19T04:42:10.628Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2805.992 ms) ====== [2025-06-19T04:42:10.628Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-19T04:42:11.396Z] GC before operation: completed in 220.999 ms, heap usage 381.528 MB -> 78.250 MB. [2025-06-19T04:42:13.887Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2696.175 ms) ====== [2025-06-19T04:42:13.887Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-19T04:42:13.887Z] GC before operation: completed in 239.509 ms, heap usage 375.985 MB -> 89.413 MB. [2025-06-19T04:42:17.315Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2647.230 ms) ====== [2025-06-19T04:42:17.315Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-19T04:42:17.315Z] GC before operation: completed in 212.897 ms, heap usage 260.292 MB -> 70.167 MB. [2025-06-19T04:42:19.815Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2561.669 ms) ====== [2025-06-19T04:42:19.815Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-19T04:42:19.815Z] GC before operation: completed in 209.270 ms, heap usage 145.864 MB -> 69.999 MB. [2025-06-19T04:42:22.286Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2320.763 ms) ====== [2025-06-19T04:42:22.286Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-19T04:42:22.286Z] GC before operation: completed in 182.969 ms, heap usage 198.280 MB -> 70.346 MB. [2025-06-19T04:42:24.744Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2381.119 ms) ====== [2025-06-19T04:42:24.744Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-19T04:42:24.744Z] GC before operation: completed in 160.760 ms, heap usage 266.799 MB -> 70.554 MB. [2025-06-19T04:42:27.216Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2201.055 ms) ====== [2025-06-19T04:42:27.216Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-19T04:42:27.216Z] GC before operation: completed in 193.215 ms, heap usage 314.244 MB -> 79.028 MB. [2025-06-19T04:42:29.715Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2275.506 ms) ====== [2025-06-19T04:42:29.715Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-19T04:42:29.715Z] GC before operation: completed in 163.306 ms, heap usage 206.974 MB -> 89.952 MB. [2025-06-19T04:42:32.173Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2441.682 ms) ====== [2025-06-19T04:42:32.173Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-19T04:42:32.173Z] GC before operation: completed in 173.379 ms, heap usage 405.788 MB -> 89.960 MB. [2025-06-19T04:42:34.267Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2010.466 ms) ====== [2025-06-19T04:42:34.267Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-19T04:42:34.267Z] GC before operation: completed in 168.012 ms, heap usage 259.058 MB -> 98.554 MB. [2025-06-19T04:42:36.745Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1978.967 ms) ====== [2025-06-19T04:42:36.745Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-19T04:42:36.745Z] GC before operation: completed in 170.048 ms, heap usage 242.911 MB -> 79.300 MB. [2025-06-19T04:42:38.338Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2066.728 ms) ====== [2025-06-19T04:42:38.338Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-19T04:42:39.102Z] GC before operation: completed in 175.019 ms, heap usage 325.101 MB -> 90.374 MB. [2025-06-19T04:42:40.815Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1906.844 ms) ====== [2025-06-19T04:42:40.815Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-19T04:42:40.815Z] GC before operation: completed in 165.304 ms, heap usage 443.052 MB -> 90.428 MB. [2025-06-19T04:42:43.273Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1983.038 ms) ====== [2025-06-19T04:42:43.273Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-19T04:42:43.273Z] GC before operation: completed in 191.013 ms, heap usage 114.531 MB -> 79.015 MB. [2025-06-19T04:42:44.851Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2042.523 ms) ====== [2025-06-19T04:42:44.851Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-19T04:42:45.616Z] GC before operation: completed in 198.087 ms, heap usage 379.633 MB -> 98.990 MB. [2025-06-19T04:42:47.195Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2037.653 ms) ====== [2025-06-19T04:42:47.195Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-19T04:42:47.195Z] GC before operation: completed in 173.768 ms, heap usage 371.998 MB -> 99.118 MB. [2025-06-19T04:42:49.662Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1881.162 ms) ====== [2025-06-19T04:42:49.662Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-19T04:42:49.662Z] GC before operation: completed in 222.422 ms, heap usage 344.817 MB -> 99.251 MB. [2025-06-19T04:42:49.662Z] 04:42:49.220 WARN [block-manager-storage-async-thread-pool-33] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_373, which does not exist [2025-06-19T04:42:52.143Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2158.344 ms) ====== [2025-06-19T04:42:52.143Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-19T04:42:52.143Z] GC before operation: completed in 201.745 ms, heap usage 444.117 MB -> 99.290 MB. [2025-06-19T04:42:53.729Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1920.194 ms) ====== [2025-06-19T04:42:53.729Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-19T04:42:54.495Z] GC before operation: completed in 197.138 ms, heap usage 194.805 MB -> 80.183 MB. [2025-06-19T04:42:56.086Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1974.339 ms) ====== [2025-06-19T04:42:56.086Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-19T04:42:56.086Z] GC before operation: completed in 238.729 ms, heap usage 298.486 MB -> 91.191 MB. [2025-06-19T04:42:58.565Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1943.030 ms) ====== [2025-06-19T04:42:58.565Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-19T04:42:58.565Z] GC before operation: completed in 202.751 ms, heap usage 415.710 MB -> 91.282 MB. [2025-06-19T04:43:01.034Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2045.867 ms) ====== [2025-06-19T04:43:01.034Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-19T04:43:01.034Z] GC before operation: completed in 223.269 ms, heap usage 413.916 MB -> 72.153 MB. [2025-06-19T04:43:02.616Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1846.435 ms) ====== [2025-06-19T04:43:02.616Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-19T04:43:02.616Z] GC before operation: completed in 226.527 ms, heap usage 189.356 MB -> 91.557 MB. [2025-06-19T04:43:05.122Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1978.695 ms) ====== [2025-06-19T04:43:05.122Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-19T04:43:05.122Z] GC before operation: completed in 200.285 ms, heap usage 354.068 MB -> 91.677 MB. [2025-06-19T04:43:06.709Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1908.177 ms) ====== [2025-06-19T04:43:06.709Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-19T04:43:07.474Z] GC before operation: completed in 174.676 ms, heap usage 290.647 MB -> 72.511 MB. [2025-06-19T04:43:09.053Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1991.026 ms) ====== [2025-06-19T04:43:09.053Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-19T04:43:09.053Z] GC before operation: completed in 173.439 ms, heap usage 262.582 MB -> 72.643 MB. [2025-06-19T04:43:11.515Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1946.255 ms) ====== [2025-06-19T04:43:11.515Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-19T04:43:11.515Z] GC before operation: completed in 191.255 ms, heap usage 252.401 MB -> 100.332 MB. [2025-06-19T04:43:13.094Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1914.427 ms) ====== [2025-06-19T04:43:13.094Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-19T04:43:13.886Z] GC before operation: completed in 185.597 ms, heap usage 390.744 MB -> 100.418 MB. [2025-06-19T04:43:15.984Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1912.966 ms) ====== [2025-06-19T04:43:15.984Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-19T04:43:15.984Z] GC before operation: completed in 182.072 ms, heap usage 386.845 MB -> 92.180 MB. [2025-06-19T04:43:17.563Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1859.724 ms) ====== [2025-06-19T04:43:18.329Z] ----------------------------------- [2025-06-19T04:43:18.329Z] renaissance-dec-tree_0_PASSED [2025-06-19T04:43:18.329Z] ----------------------------------- [2025-06-19T04:43:18.329Z] [2025-06-19T04:43:18.329Z] TEST TEARDOWN: [2025-06-19T04:43:18.329Z] Nothing to be done for teardown. [2025-06-19T04:43:18.329Z] renaissance-dec-tree_0 Finish Time: Thu Jun 19 04:43:17 2025 Epoch Time (ms): 1750308197637