[2025-05-17T19:24:38.511Z] Running test renaissance-dec-tree_0 ... [2025-05-17T19:24:38.511Z] =============================================== [2025-05-17T19:24:38.511Z] renaissance-dec-tree_0 Start Time: Sat May 17 19:24:37 2025 Epoch Time (ms): 1747509877880 [2025-05-17T19:24:38.511Z] variation: NoOptions [2025-05-17T19:24:38.511Z] JVM_OPTIONS: [2025-05-17T19:24:38.511Z] { \ [2025-05-17T19:24:38.511Z] echo ""; echo "TEST SETUP:"; \ [2025-05-17T19:24:38.511Z] echo "Nothing to be done for setup."; \ [2025-05-17T19:24:38.511Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17475090318038/renaissance-dec-tree_0"; \ [2025-05-17T19:24:38.511Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17475090318038/renaissance-dec-tree_0"; \ [2025-05-17T19:24:38.511Z] echo ""; echo "TESTING:"; \ [2025-05-17T19:24:38.511Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17475090318038/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-17T19:24:38.511Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17475090318038/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-17T19:24:38.511Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-17T19:24:38.511Z] echo "Nothing to be done for teardown."; \ [2025-05-17T19:24:38.511Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17475090318038/TestTargetResult"; [2025-05-17T19:24:38.511Z] [2025-05-17T19:24:38.511Z] TEST SETUP: [2025-05-17T19:24:38.511Z] Nothing to be done for setup. [2025-05-17T19:24:38.511Z] [2025-05-17T19:24:38.511Z] TESTING: [2025-05-17T19:24:53.304Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-17T19:24:56.075Z] WARNING: An illegal reflective access operation has occurred [2025-05-17T19:24:56.075Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17475090318038/renaissance-dec-tree_0/harness-192439-11535365715298434344/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-17T19:24:56.075Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-17T19:24:56.075Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-17T19:24:56.075Z] WARNING: All illegal access operations will be denied in a future release [2025-05-17T19:25:00.107Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-17T19:25:00.107Z] GC before operation: completed in 128.932 ms, heap usage 39.776 MB -> 34.348 MB. [2025-05-17T19:25:37.164Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (32768.543 ms) ====== [2025-05-17T19:25:37.164Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-17T19:25:37.164Z] GC before operation: completed in 442.150 ms, heap usage 273.697 MB -> 68.265 MB. [2025-05-17T19:25:42.145Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (8242.747 ms) ====== [2025-05-17T19:25:42.146Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-17T19:25:42.146Z] GC before operation: completed in 333.238 ms, heap usage 232.031 MB -> 68.592 MB. [2025-05-17T19:25:48.366Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6168.793 ms) ====== [2025-05-17T19:25:48.366Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-17T19:25:48.366Z] GC before operation: completed in 343.068 ms, heap usage 236.877 MB -> 69.245 MB. [2025-05-17T19:25:54.563Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5498.747 ms) ====== [2025-05-17T19:25:54.563Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-17T19:25:54.563Z] GC before operation: completed in 282.279 ms, heap usage 230.980 MB -> 69.088 MB. [2025-05-17T19:25:59.531Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5210.337 ms) ====== [2025-05-17T19:25:59.531Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-17T19:25:59.531Z] GC before operation: completed in 307.716 ms, heap usage 173.015 MB -> 69.293 MB. [2025-05-17T19:26:04.490Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4310.505 ms) ====== [2025-05-17T19:26:04.490Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-17T19:26:04.490Z] GC before operation: completed in 315.370 ms, heap usage 226.690 MB -> 69.506 MB. [2025-05-17T19:26:09.458Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4668.059 ms) ====== [2025-05-17T19:26:09.458Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-17T19:26:09.458Z] GC before operation: completed in 315.211 ms, heap usage 220.684 MB -> 69.842 MB. [2025-05-17T19:26:14.400Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4824.377 ms) ====== [2025-05-17T19:26:14.400Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-17T19:26:14.400Z] GC before operation: completed in 341.145 ms, heap usage 114.738 MB -> 69.630 MB. [2025-05-17T19:26:19.350Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4216.684 ms) ====== [2025-05-17T19:26:19.350Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-17T19:26:19.350Z] GC before operation: completed in 312.917 ms, heap usage 222.109 MB -> 70.066 MB. [2025-05-17T19:26:23.752Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3895.915 ms) ====== [2025-05-17T19:26:23.752Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-17T19:26:23.752Z] GC before operation: completed in 334.255 ms, heap usage 142.079 MB -> 69.856 MB. [2025-05-17T19:26:27.531Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3816.978 ms) ====== [2025-05-17T19:26:27.531Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-17T19:26:27.531Z] GC before operation: completed in 304.501 ms, heap usage 257.122 MB -> 70.306 MB. [2025-05-17T19:26:31.331Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3526.026 ms) ====== [2025-05-17T19:26:31.331Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-17T19:26:31.331Z] GC before operation: completed in 274.551 ms, heap usage 218.863 MB -> 70.628 MB. [2025-05-17T19:26:35.137Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3591.515 ms) ====== [2025-05-17T19:26:35.137Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-17T19:26:35.137Z] GC before operation: completed in 322.771 ms, heap usage 215.343 MB -> 70.380 MB. [2025-05-17T19:26:38.967Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3793.326 ms) ====== [2025-05-17T19:26:38.967Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-17T19:26:39.848Z] GC before operation: completed in 361.396 ms, heap usage 114.557 MB -> 70.004 MB. [2025-05-17T19:26:43.667Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4053.880 ms) ====== [2025-05-17T19:26:43.667Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-17T19:26:43.667Z] GC before operation: completed in 368.827 ms, heap usage 213.707 MB -> 70.750 MB. [2025-05-17T19:26:47.475Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3734.439 ms) ====== [2025-05-17T19:26:47.475Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-17T19:26:48.337Z] GC before operation: completed in 366.403 ms, heap usage 309.165 MB -> 70.931 MB. [2025-05-17T19:26:51.097Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3433.340 ms) ====== [2025-05-17T19:26:51.097Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-17T19:26:51.952Z] GC before operation: completed in 340.341 ms, heap usage 145.491 MB -> 71.015 MB. [2025-05-17T19:26:55.744Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3579.240 ms) ====== [2025-05-17T19:26:55.744Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-17T19:26:55.744Z] GC before operation: completed in 325.011 ms, heap usage 301.255 MB -> 71.139 MB. [2025-05-17T19:26:58.499Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2855.047 ms) ====== [2025-05-17T19:26:58.499Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-17T19:26:58.499Z] GC before operation: completed in 356.859 ms, heap usage 269.599 MB -> 71.246 MB. [2025-05-17T19:27:02.294Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3454.054 ms) ====== [2025-05-17T19:27:02.294Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-17T19:27:03.169Z] GC before operation: completed in 496.972 ms, heap usage 115.843 MB -> 71.037 MB. [2025-05-17T19:27:05.937Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3346.731 ms) ====== [2025-05-17T19:27:05.937Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-17T19:27:06.799Z] GC before operation: completed in 391.246 ms, heap usage 223.103 MB -> 71.463 MB. [2025-05-17T19:27:10.618Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3429.761 ms) ====== [2025-05-17T19:27:10.618Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-17T19:27:10.618Z] GC before operation: completed in 302.385 ms, heap usage 260.544 MB -> 71.565 MB. [2025-05-17T19:27:13.379Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3067.038 ms) ====== [2025-05-17T19:27:13.380Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-17T19:27:13.380Z] GC before operation: completed in 327.354 ms, heap usage 227.069 MB -> 71.696 MB. [2025-05-17T19:27:17.186Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2972.632 ms) ====== [2025-05-17T19:27:17.186Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-17T19:27:17.186Z] GC before operation: completed in 408.596 ms, heap usage 176.799 MB -> 71.392 MB. [2025-05-17T19:27:20.541Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2505.895 ms) ====== [2025-05-17T19:27:20.541Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-17T19:27:20.541Z] GC before operation: completed in 218.207 ms, heap usage 255.292 MB -> 71.717 MB. [2025-05-17T19:27:22.317Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2045.662 ms) ====== [2025-05-17T19:27:22.317Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-17T19:27:22.317Z] GC before operation: completed in 242.524 ms, heap usage 312.479 MB -> 71.969 MB. [2025-05-17T19:27:24.079Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2206.100 ms) ====== [2025-05-17T19:27:24.079Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-17T19:27:24.947Z] GC before operation: completed in 253.982 ms, heap usage 248.098 MB -> 72.081 MB. [2025-05-17T19:27:27.567Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2882.235 ms) ====== [2025-05-17T19:27:27.567Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-17T19:27:27.567Z] GC before operation: completed in 230.546 ms, heap usage 184.724 MB -> 72.191 MB. [2025-05-17T19:27:30.131Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2573.281 ms) ====== [2025-05-17T19:27:30.131Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-17T19:27:30.131Z] GC before operation: completed in 212.122 ms, heap usage 152.517 MB -> 72.130 MB. [2025-05-17T19:27:33.668Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2943.899 ms) ====== [2025-05-17T19:27:33.668Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-17T19:27:33.668Z] GC before operation: completed in 390.616 ms, heap usage 239.208 MB -> 72.407 MB. [2025-05-17T19:27:37.255Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3532.472 ms) ====== [2025-05-17T19:27:37.255Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-17T19:27:37.255Z] GC before operation: completed in 263.710 ms, heap usage 272.437 MB -> 72.363 MB. [2025-05-17T19:27:40.793Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2728.748 ms) ====== [2025-05-17T19:27:40.793Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-17T19:27:40.793Z] GC before operation: completed in 229.801 ms, heap usage 234.887 MB -> 72.610 MB. [2025-05-17T19:27:42.445Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2079.269 ms) ====== [2025-05-17T19:27:42.445Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-17T19:27:43.237Z] GC before operation: completed in 263.331 ms, heap usage 252.283 MB -> 72.931 MB. [2025-05-17T19:27:44.873Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2315.169 ms) ====== [2025-05-17T19:27:44.873Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-17T19:27:45.665Z] GC before operation: completed in 213.437 ms, heap usage 115.970 MB -> 72.193 MB. [2025-05-17T19:27:47.318Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2160.422 ms) ====== [2025-05-17T19:27:47.318Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-17T19:27:48.114Z] GC before operation: completed in 234.849 ms, heap usage 214.124 MB -> 72.937 MB. [2025-05-17T19:27:49.768Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2290.458 ms) ====== [2025-05-17T19:27:49.768Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-17T19:27:50.566Z] GC before operation: completed in 312.303 ms, heap usage 336.958 MB -> 73.033 MB. [2025-05-17T19:27:53.106Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2402.542 ms) ====== [2025-05-17T19:27:53.107Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-17T19:27:53.107Z] GC before operation: completed in 290.493 ms, heap usage 122.014 MB -> 72.675 MB. [2025-05-17T19:27:55.661Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2406.464 ms) ====== [2025-05-17T19:27:55.661Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-17T19:27:55.662Z] GC before operation: completed in 309.974 ms, heap usage 264.304 MB -> 73.240 MB. [2025-05-17T19:27:58.203Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2520.922 ms) ====== [2025-05-17T19:27:58.203Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-17T19:27:59.000Z] GC before operation: completed in 272.438 ms, heap usage 181.209 MB -> 89.664 MB. [2025-05-17T19:27:59.000Z] 19:27:58.161 WARN [block-manager-storage-async-thread-pool-76] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_541_piece0, which does not exist [2025-05-17T19:28:00.641Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2179.650 ms) ====== [2025-05-17T19:28:01.436Z] ----------------------------------- [2025-05-17T19:28:01.436Z] renaissance-dec-tree_0_PASSED [2025-05-17T19:28:01.436Z] ----------------------------------- [2025-05-17T19:28:01.436Z] [2025-05-17T19:28:01.436Z] TEST TEARDOWN: [2025-05-17T19:28:01.436Z] Nothing to be done for teardown. [2025-05-17T19:28:01.436Z] renaissance-dec-tree_0 Finish Time: Sat May 17 19:28:00 2025 Epoch Time (ms): 1747510080745