[2025-05-28T20:56:34.320Z] Running test renaissance-dec-tree_0 ... [2025-05-28T20:56:34.320Z] =============================================== [2025-05-28T20:56:34.320Z] renaissance-dec-tree_0 Start Time: Wed May 28 20:56:33 2025 Epoch Time (ms): 1748465793699 [2025-05-28T20:56:34.320Z] variation: NoOptions [2025-05-28T20:56:34.320Z] JVM_OPTIONS: [2025-05-28T20:56:34.320Z] { \ [2025-05-28T20:56:34.320Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T20:56:34.320Z] echo "Nothing to be done for setup."; \ [2025-05-28T20:56:34.320Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_174846531254/renaissance-dec-tree_0"; \ [2025-05-28T20:56:34.320Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_174846531254/renaissance-dec-tree_0"; \ [2025-05-28T20:56:34.320Z] echo ""; echo "TESTING:"; \ [2025-05-28T20:56:34.321Z] "/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_174846531254/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-28T20:56:34.321Z] 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_174846531254/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T20:56:34.321Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T20:56:34.321Z] echo "Nothing to be done for teardown."; \ [2025-05-28T20:56:34.321Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_174846531254/TestTargetResult"; [2025-05-28T20:56:34.321Z] [2025-05-28T20:56:34.321Z] TEST SETUP: [2025-05-28T20:56:34.321Z] Nothing to be done for setup. [2025-05-28T20:56:34.321Z] [2025-05-28T20:56:34.321Z] TESTING: [2025-05-28T20:56:44.368Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-28T20:56:47.902Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-28T20:56:47.902Z] GC before operation: completed in 109.087 ms, heap usage 61.989 MB -> 34.931 MB. [2025-05-28T20:57:07.285Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (17653.235 ms) ====== [2025-05-28T20:57:07.285Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-28T20:57:07.285Z] GC before operation: completed in 234.330 ms, heap usage 302.352 MB -> 68.212 MB. [2025-05-28T20:57:09.760Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3917.178 ms) ====== [2025-05-28T20:57:09.760Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-28T20:57:10.542Z] GC before operation: completed in 224.565 ms, heap usage 201.094 MB -> 68.374 MB. [2025-05-28T20:57:13.047Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2975.615 ms) ====== [2025-05-28T20:57:13.047Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-28T20:57:13.047Z] GC before operation: completed in 244.911 ms, heap usage 106.340 MB -> 87.254 MB. [2025-05-28T20:57:16.611Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2896.876 ms) ====== [2025-05-28T20:57:16.611Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-28T20:57:16.611Z] GC before operation: completed in 211.718 ms, heap usage 176.386 MB -> 68.610 MB. [2025-05-28T20:57:19.192Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2707.061 ms) ====== [2025-05-28T20:57:19.192Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-28T20:57:19.192Z] GC before operation: completed in 236.512 ms, heap usage 308.208 MB -> 69.001 MB. [2025-05-28T20:57:21.647Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2449.344 ms) ====== [2025-05-28T20:57:21.647Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-28T20:57:22.439Z] GC before operation: completed in 204.764 ms, heap usage 271.900 MB -> 69.191 MB. [2025-05-28T20:57:24.030Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2308.334 ms) ====== [2025-05-28T20:57:24.030Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-28T20:57:24.811Z] GC before operation: completed in 218.097 ms, heap usage 364.795 MB -> 69.503 MB. [2025-05-28T20:57:27.311Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2317.536 ms) ====== [2025-05-28T20:57:27.311Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-28T20:57:27.311Z] GC before operation: completed in 220.793 ms, heap usage 373.717 MB -> 69.559 MB. [2025-05-28T20:57:29.759Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2249.127 ms) ====== [2025-05-28T20:57:29.759Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-28T20:57:29.759Z] GC before operation: completed in 218.134 ms, heap usage 371.008 MB -> 69.701 MB. [2025-05-28T20:57:32.238Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2112.363 ms) ====== [2025-05-28T20:57:32.238Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-28T20:57:32.238Z] GC before operation: completed in 243.906 ms, heap usage 334.333 MB -> 97.538 MB. [2025-05-28T20:57:33.831Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2148.195 ms) ====== [2025-05-28T20:57:33.831Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-28T20:57:34.611Z] GC before operation: completed in 210.282 ms, heap usage 376.076 MB -> 78.455 MB. [2025-05-28T20:57:36.212Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2037.327 ms) ====== [2025-05-28T20:57:36.212Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-28T20:57:36.978Z] GC before operation: completed in 241.741 ms, heap usage 229.019 MB -> 70.114 MB. [2025-05-28T20:57:38.599Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2214.313 ms) ====== [2025-05-28T20:57:38.599Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-28T20:57:39.368Z] GC before operation: completed in 206.795 ms, heap usage 295.180 MB -> 78.580 MB. [2025-05-28T20:57:40.984Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1937.884 ms) ====== [2025-05-28T20:57:40.984Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-28T20:57:40.984Z] GC before operation: completed in 211.947 ms, heap usage 213.319 MB -> 70.335 MB. [2025-05-28T20:57:43.488Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1968.841 ms) ====== [2025-05-28T20:57:43.488Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-28T20:57:43.488Z] GC before operation: completed in 227.757 ms, heap usage 268.542 MB -> 70.444 MB. [2025-05-28T20:57:45.269Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1915.172 ms) ====== [2025-05-28T20:57:45.269Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-28T20:57:45.269Z] GC before operation: completed in 233.531 ms, heap usage 278.579 MB -> 70.572 MB. [2025-05-28T20:57:47.773Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1792.990 ms) ====== [2025-05-28T20:57:47.773Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-28T20:57:47.773Z] GC before operation: completed in 236.660 ms, heap usage 393.386 MB -> 70.714 MB. [2025-05-28T20:57:49.350Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1825.452 ms) ====== [2025-05-28T20:57:49.350Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-28T20:57:49.350Z] GC before operation: completed in 224.726 ms, heap usage 338.288 MB -> 70.814 MB. [2025-05-28T20:57:51.884Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1933.033 ms) ====== [2025-05-28T20:57:51.884Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-28T20:57:51.884Z] GC before operation: completed in 226.723 ms, heap usage 305.674 MB -> 70.897 MB. [2025-05-28T20:57:53.463Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1936.151 ms) ====== [2025-05-28T20:57:53.463Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-28T20:57:54.306Z] GC before operation: completed in 250.937 ms, heap usage 310.907 MB -> 70.956 MB. [2025-05-28T20:57:55.917Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1847.187 ms) ====== [2025-05-28T20:57:55.917Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-28T20:57:55.917Z] GC before operation: completed in 258.192 ms, heap usage 293.069 MB -> 71.234 MB. [2025-05-28T20:57:58.386Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1892.693 ms) ====== [2025-05-28T20:57:58.386Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-28T20:57:58.386Z] GC before operation: completed in 252.679 ms, heap usage 272.836 MB -> 71.223 MB. [2025-05-28T20:57:59.975Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1840.420 ms) ====== [2025-05-28T20:57:59.975Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-28T20:57:59.975Z] GC before operation: completed in 292.679 ms, heap usage 251.346 MB -> 71.351 MB. [2025-05-28T20:58:02.458Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1940.656 ms) ====== [2025-05-28T20:58:02.458Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-28T20:58:02.458Z] GC before operation: completed in 247.717 ms, heap usage 256.444 MB -> 99.126 MB. [2025-05-28T20:58:02.458Z] 20:58:02.102 WARN [block-manager-storage-async-thread-pool-33] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1149_1, which does not exist [2025-05-28T20:58:04.942Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2147.404 ms) ====== [2025-05-28T20:58:04.942Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-28T20:58:04.942Z] GC before operation: completed in 251.544 ms, heap usage 344.077 MB -> 71.541 MB. [2025-05-28T20:58:06.546Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1853.873 ms) ====== [2025-05-28T20:58:06.546Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-28T20:58:07.313Z] GC before operation: completed in 241.692 ms, heap usage 353.647 MB -> 71.783 MB. [2025-05-28T20:58:08.915Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1703.913 ms) ====== [2025-05-28T20:58:08.915Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-28T20:58:08.915Z] GC before operation: completed in 276.719 ms, heap usage 297.211 MB -> 99.401 MB. [2025-05-28T20:58:10.499Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1817.827 ms) ====== [2025-05-28T20:58:10.499Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-28T20:58:11.266Z] GC before operation: completed in 247.250 ms, heap usage 343.500 MB -> 72.051 MB. [2025-05-28T20:58:12.846Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1774.596 ms) ====== [2025-05-28T20:58:12.846Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-28T20:58:12.846Z] GC before operation: completed in 259.151 ms, heap usage 299.658 MB -> 72.028 MB. [2025-05-28T20:58:15.304Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1743.312 ms) ====== [2025-05-28T20:58:15.304Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-28T20:58:15.304Z] GC before operation: completed in 254.123 ms, heap usage 261.142 MB -> 72.098 MB. [2025-05-28T20:58:16.883Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1741.017 ms) ====== [2025-05-28T20:58:16.883Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-28T20:58:16.883Z] GC before operation: completed in 258.075 ms, heap usage 255.421 MB -> 72.239 MB. [2025-05-28T20:58:19.342Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1821.187 ms) ====== [2025-05-28T20:58:19.342Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-28T20:58:19.342Z] GC before operation: completed in 262.258 ms, heap usage 254.318 MB -> 72.388 MB. [2025-05-28T20:58:20.927Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1748.430 ms) ====== [2025-05-28T20:58:20.927Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-28T20:58:20.927Z] GC before operation: completed in 281.372 ms, heap usage 254.489 MB -> 72.451 MB. [2025-05-28T20:58:22.603Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1720.416 ms) ====== [2025-05-28T20:58:22.603Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-28T20:58:23.382Z] GC before operation: completed in 289.522 ms, heap usage 215.046 MB -> 72.573 MB. [2025-05-28T20:58:24.959Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1848.486 ms) ====== [2025-05-28T20:58:24.959Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-28T20:58:25.727Z] GC before operation: completed in 289.890 ms, heap usage 217.159 MB -> 72.689 MB. [2025-05-28T20:58:27.301Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1890.582 ms) ====== [2025-05-28T20:58:27.301Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-28T20:58:27.301Z] GC before operation: completed in 256.002 ms, heap usage 212.276 MB -> 72.689 MB. [2025-05-28T20:58:29.752Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1887.991 ms) ====== [2025-05-28T20:58:29.752Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-28T20:58:29.752Z] GC before operation: completed in 254.222 ms, heap usage 226.297 MB -> 72.939 MB. [2025-05-28T20:58:31.327Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1784.705 ms) ====== [2025-05-28T20:58:31.327Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-28T20:58:32.112Z] GC before operation: completed in 258.849 ms, heap usage 224.560 MB -> 73.057 MB. [2025-05-28T20:58:33.839Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1867.919 ms) ====== [2025-05-28T20:58:33.839Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-28T20:58:33.839Z] GC before operation: completed in 265.208 ms, heap usage 540.047 MB -> 73.237 MB. [2025-05-28T20:58:35.423Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1703.535 ms) ====== [2025-05-28T20:58:36.218Z] ----------------------------------- [2025-05-28T20:58:36.218Z] renaissance-dec-tree_0_PASSED [2025-05-28T20:58:36.218Z] ----------------------------------- [2025-05-28T20:58:36.218Z] [2025-05-28T20:58:36.218Z] TEST TEARDOWN: [2025-05-28T20:58:36.218Z] Nothing to be done for teardown. [2025-05-28T20:58:36.218Z] renaissance-dec-tree_0 Finish Time: Wed May 28 20:58:35 2025 Epoch Time (ms): 1748465915908