[2025-05-07T13:35:49.752Z] Running test renaissance-dec-tree_0 ... [2025-05-07T13:35:49.752Z] =============================================== [2025-05-07T13:35:49.752Z] renaissance-dec-tree_0 Start Time: Wed May 7 13:35:49 2025 Epoch Time (ms): 1746624949492 [2025-05-07T13:35:49.752Z] variation: NoOptions [2025-05-07T13:35:49.752Z] JVM_OPTIONS: [2025-05-07T13:35:49.752Z] { \ [2025-05-07T13:35:49.752Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T13:35:49.752Z] echo "Nothing to be done for setup."; \ [2025-05-07T13:35:49.752Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-dec-tree_0"; \ [2025-05-07T13:35:49.752Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-dec-tree_0"; \ [2025-05-07T13:35:49.752Z] echo ""; echo "TESTING:"; \ [2025-05-07T13:35:49.753Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-07T13:35:49.753Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T13:35:49.753Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T13:35:49.753Z] echo "Nothing to be done for teardown."; \ [2025-05-07T13:35:49.753Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/TestTargetResult"; [2025-05-07T13:35:49.753Z] [2025-05-07T13:35:49.753Z] TEST SETUP: [2025-05-07T13:35:49.753Z] Nothing to be done for setup. [2025-05-07T13:35:49.753Z] [2025-05-07T13:35:49.753Z] TESTING: [2025-05-07T13:36:15.657Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 16) threads. [2025-05-07T13:36:17.265Z] WARNING: An illegal reflective access operation has occurred [2025-05-07T13:36:17.265Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_1746623986768/renaissance-dec-tree_0/harness-133551-12103217288203380224/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-07T13:36:17.265Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-07T13:36:17.265Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-07T13:36:17.265Z] WARNING: All illegal access operations will be denied in a future release [2025-05-07T13:36:19.761Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-07T13:36:19.761Z] GC before operation: completed in 88.721 ms, heap usage 91.361 MB -> 34.877 MB. [2025-05-07T13:36:31.552Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11059.468 ms) ====== [2025-05-07T13:36:31.552Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-07T13:36:31.552Z] GC before operation: completed in 128.028 ms, heap usage 342.928 MB -> 69.027 MB. [2025-05-07T13:36:33.165Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2491.277 ms) ====== [2025-05-07T13:36:33.165Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-07T13:36:33.165Z] GC before operation: completed in 99.044 ms, heap usage 107.626 MB -> 69.027 MB. [2025-05-07T13:36:35.661Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2131.675 ms) ====== [2025-05-07T13:36:35.661Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-07T13:36:35.661Z] GC before operation: completed in 105.698 ms, heap usage 208.631 MB -> 69.597 MB. [2025-05-07T13:36:38.163Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2308.315 ms) ====== [2025-05-07T13:36:38.163Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-07T13:36:38.163Z] GC before operation: completed in 122.017 ms, heap usage 122.378 MB -> 69.204 MB. [2025-05-07T13:36:39.772Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2115.357 ms) ====== [2025-05-07T13:36:39.772Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-07T13:36:39.772Z] GC before operation: completed in 115.550 ms, heap usage 242.253 MB -> 97.514 MB. [2025-05-07T13:36:42.270Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1947.881 ms) ====== [2025-05-07T13:36:42.270Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-07T13:36:42.270Z] GC before operation: completed in 119.770 ms, heap usage 268.900 MB -> 81.410 MB. [2025-05-07T13:36:43.894Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1990.615 ms) ====== [2025-05-07T13:36:43.894Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-07T13:36:44.673Z] GC before operation: completed in 139.257 ms, heap usage 287.487 MB -> 70.709 MB. [2025-05-07T13:36:46.279Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2182.176 ms) ====== [2025-05-07T13:36:46.279Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-07T13:36:47.061Z] GC before operation: completed in 142.949 ms, heap usage 319.503 MB -> 70.824 MB. [2025-05-07T13:36:47.061Z] 13:36:46.214 WARN [block-manager-storage-async-thread-pool-23] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_107_piece0, which does not exist [2025-05-07T13:36:48.676Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1990.499 ms) ====== [2025-05-07T13:36:48.676Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-07T13:36:48.676Z] GC before operation: completed in 139.751 ms, heap usage 107.218 MB -> 70.396 MB. [2025-05-07T13:36:51.180Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1926.021 ms) ====== [2025-05-07T13:36:51.180Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-07T13:36:51.180Z] GC before operation: completed in 130.160 ms, heap usage 120.183 MB -> 70.043 MB. [2025-05-07T13:36:52.959Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1893.703 ms) ====== [2025-05-07T13:36:52.959Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-07T13:36:52.959Z] GC before operation: completed in 140.925 ms, heap usage 244.540 MB -> 70.805 MB. [2025-05-07T13:36:54.579Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1966.404 ms) ====== [2025-05-07T13:36:54.579Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-07T13:36:55.358Z] GC before operation: completed in 179.081 ms, heap usage 162.520 MB -> 70.743 MB. [2025-05-07T13:36:56.992Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1921.384 ms) ====== [2025-05-07T13:36:56.992Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-07T13:36:56.992Z] GC before operation: completed in 140.359 ms, heap usage 283.002 MB -> 71.251 MB. [2025-05-07T13:36:59.498Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1915.253 ms) ====== [2025-05-07T13:36:59.498Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-07T13:36:59.498Z] GC before operation: completed in 165.622 ms, heap usage 310.380 MB -> 71.144 MB. [2025-05-07T13:37:01.122Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1914.701 ms) ====== [2025-05-07T13:37:01.122Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-07T13:37:01.122Z] GC before operation: completed in 173.876 ms, heap usage 327.172 MB -> 71.395 MB. [2025-05-07T13:37:03.635Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2026.077 ms) ====== [2025-05-07T13:37:03.635Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-07T13:37:03.635Z] GC before operation: completed in 182.183 ms, heap usage 348.189 MB -> 71.570 MB. [2025-05-07T13:37:05.244Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2043.884 ms) ====== [2025-05-07T13:37:05.244Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-07T13:37:06.031Z] GC before operation: completed in 169.540 ms, heap usage 123.886 MB -> 71.050 MB. [2025-05-07T13:37:07.653Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1933.692 ms) ====== [2025-05-07T13:37:07.653Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-07T13:37:07.653Z] GC before operation: completed in 202.913 ms, heap usage 108.563 MB -> 71.171 MB. [2025-05-07T13:37:10.152Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2043.091 ms) ====== [2025-05-07T13:37:10.152Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-07T13:37:10.152Z] GC before operation: completed in 184.108 ms, heap usage 173.858 MB -> 71.446 MB. [2025-05-07T13:37:11.770Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1987.756 ms) ====== [2025-05-07T13:37:11.770Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-07T13:37:12.549Z] GC before operation: completed in 165.389 ms, heap usage 230.579 MB -> 82.963 MB. [2025-05-07T13:37:14.170Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1828.420 ms) ====== [2025-05-07T13:37:14.170Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-07T13:37:14.170Z] GC before operation: completed in 185.537 ms, heap usage 131.263 MB -> 71.338 MB. [2025-05-07T13:37:15.794Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1921.638 ms) ====== [2025-05-07T13:37:15.794Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-07T13:37:16.580Z] GC before operation: completed in 186.359 ms, heap usage 144.618 MB -> 71.754 MB. [2025-05-07T13:37:18.196Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2013.981 ms) ====== [2025-05-07T13:37:18.196Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-07T13:37:18.196Z] GC before operation: completed in 207.086 ms, heap usage 294.323 MB -> 72.185 MB. [2025-05-07T13:37:20.693Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1906.511 ms) ====== [2025-05-07T13:37:20.693Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-07T13:37:20.693Z] GC before operation: completed in 203.340 ms, heap usage 355.255 MB -> 72.427 MB. [2025-05-07T13:37:22.311Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1874.671 ms) ====== [2025-05-07T13:37:22.311Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-07T13:37:23.089Z] GC before operation: completed in 179.868 ms, heap usage 342.436 MB -> 72.389 MB. [2025-05-07T13:37:24.697Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1906.293 ms) ====== [2025-05-07T13:37:24.697Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-07T13:37:24.697Z] GC before operation: completed in 168.030 ms, heap usage 149.451 MB -> 72.279 MB. [2025-05-07T13:37:27.393Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1926.626 ms) ====== [2025-05-07T13:37:27.393Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-07T13:37:27.393Z] GC before operation: completed in 171.216 ms, heap usage 275.884 MB -> 72.933 MB. [2025-05-07T13:37:29.026Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1934.466 ms) ====== [2025-05-07T13:37:29.026Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-07T13:37:29.026Z] GC before operation: completed in 180.688 ms, heap usage 181.896 MB -> 83.259 MB. [2025-05-07T13:37:30.643Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1905.662 ms) ====== [2025-05-07T13:37:30.643Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-07T13:37:31.425Z] GC before operation: completed in 195.453 ms, heap usage 373.848 MB -> 72.765 MB. [2025-05-07T13:37:33.044Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1834.128 ms) ====== [2025-05-07T13:37:33.044Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-07T13:37:33.044Z] GC before operation: completed in 185.573 ms, heap usage 232.344 MB -> 73.089 MB. [2025-05-07T13:37:34.663Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1825.303 ms) ====== [2025-05-07T13:37:34.663Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-07T13:37:35.444Z] GC before operation: completed in 183.773 ms, heap usage 191.306 MB -> 72.821 MB. [2025-05-07T13:37:37.051Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1827.454 ms) ====== [2025-05-07T13:37:37.051Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-07T13:37:37.051Z] GC before operation: completed in 184.718 ms, heap usage 301.398 MB -> 73.093 MB. [2025-05-07T13:37:39.564Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1892.842 ms) ====== [2025-05-07T13:37:39.564Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-07T13:37:39.564Z] GC before operation: completed in 202.558 ms, heap usage 301.053 MB -> 79.009 MB. [2025-05-07T13:37:41.180Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1941.697 ms) ====== [2025-05-07T13:37:41.180Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-07T13:37:41.180Z] GC before operation: completed in 221.674 ms, heap usage 255.351 MB -> 73.291 MB. [2025-05-07T13:37:43.683Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1874.971 ms) ====== [2025-05-07T13:37:43.683Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-07T13:37:43.683Z] GC before operation: completed in 197.922 ms, heap usage 272.976 MB -> 73.549 MB. [2025-05-07T13:37:45.294Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1836.614 ms) ====== [2025-05-07T13:37:45.294Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-07T13:37:45.294Z] GC before operation: completed in 215.141 ms, heap usage 328.904 MB -> 84.809 MB. [2025-05-07T13:37:47.802Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1759.780 ms) ====== [2025-05-07T13:37:47.802Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-07T13:37:47.802Z] GC before operation: completed in 190.703 ms, heap usage 252.958 MB -> 73.767 MB. [2025-05-07T13:37:49.410Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1896.323 ms) ====== [2025-05-07T13:37:49.410Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-07T13:37:49.410Z] GC before operation: completed in 190.657 ms, heap usage 124.205 MB -> 73.109 MB. [2025-05-07T13:37:51.910Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1832.657 ms) ====== [2025-05-07T13:37:51.910Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-07T13:37:51.910Z] GC before operation: completed in 193.940 ms, heap usage 164.169 MB -> 90.208 MB. [2025-05-07T13:37:53.533Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1732.387 ms) ====== [2025-05-07T13:37:53.533Z] ----------------------------------- [2025-05-07T13:37:53.533Z] renaissance-dec-tree_0_PASSED [2025-05-07T13:37:53.533Z] ----------------------------------- [2025-05-07T13:37:53.533Z] [2025-05-07T13:37:53.533Z] TEST TEARDOWN: [2025-05-07T13:37:53.533Z] Nothing to be done for teardown. [2025-05-07T13:37:53.533Z] renaissance-dec-tree_0 Finish Time: Wed May 7 13:37:53 2025 Epoch Time (ms): 1746625073311