[2025-10-01T21:48:06.325Z] Running test renaissance-dec-tree_0 ... [2025-10-01T21:48:06.325Z] =============================================== [2025-10-01T21:48:06.325Z] renaissance-dec-tree_0 Start Time: Wed Oct 1 21:48:05 2025 Epoch Time (ms): 1759355285936 [2025-10-01T21:48:06.325Z] variation: NoOptions [2025-10-01T21:48:06.325Z] JVM_OPTIONS: [2025-10-01T21:48:06.325Z] { \ [2025-10-01T21:48:06.325Z] echo ""; echo "TEST SETUP:"; \ [2025-10-01T21:48:06.325Z] echo "Nothing to be done for setup."; \ [2025-10-01T21:48:06.325Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17593547507424/renaissance-dec-tree_0"; \ [2025-10-01T21:48:06.325Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17593547507424/renaissance-dec-tree_0"; \ [2025-10-01T21:48:06.325Z] echo ""; echo "TESTING:"; \ [2025-10-01T21:48:06.325Z] "/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_17593547507424/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-10-01T21:48:06.325Z] 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_17593547507424/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-01T21:48:06.325Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-01T21:48:06.325Z] echo "Nothing to be done for teardown."; \ [2025-10-01T21:48:06.325Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17593547507424/TestTargetResult"; [2025-10-01T21:48:06.325Z] [2025-10-01T21:48:06.325Z] TEST SETUP: [2025-10-01T21:48:06.325Z] Nothing to be done for setup. [2025-10-01T21:48:06.325Z] [2025-10-01T21:48:06.325Z] TESTING: [2025-10-01T21:48:14.629Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2025-10-01T21:48:17.106Z] WARNING: An illegal reflective access operation has occurred [2025-10-01T21:48:17.106Z] 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_17593547507424/renaissance-dec-tree_0/harness-214807-13737354879571130574/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-10-01T21:48:17.106Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-10-01T21:48:17.106Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-10-01T21:48:17.106Z] WARNING: All illegal access operations will be denied in a future release [2025-10-01T21:48:18.705Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-10-01T21:48:18.705Z] GC before operation: completed in 94.141 ms, heap usage 103.677 MB -> 35.136 MB. [2025-10-01T21:48:32.651Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11957.482 ms) ====== [2025-10-01T21:48:32.651Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-10-01T21:48:32.651Z] GC before operation: completed in 105.270 ms, heap usage 419.870 MB -> 71.277 MB. [2025-10-01T21:48:33.590Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2643.929 ms) ====== [2025-10-01T21:48:33.590Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-10-01T21:48:33.590Z] GC before operation: completed in 96.104 ms, heap usage 230.919 MB -> 71.587 MB. [2025-10-01T21:48:36.066Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2356.658 ms) ====== [2025-10-01T21:48:36.066Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-10-01T21:48:36.066Z] GC before operation: completed in 104.672 ms, heap usage 293.466 MB -> 72.062 MB. [2025-10-01T21:48:38.541Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2289.953 ms) ====== [2025-10-01T21:48:38.541Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-10-01T21:48:38.541Z] GC before operation: completed in 112.846 ms, heap usage 276.816 MB -> 72.088 MB. [2025-10-01T21:48:41.015Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2192.229 ms) ====== [2025-10-01T21:48:41.015Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-10-01T21:48:41.015Z] GC before operation: completed in 117.072 ms, heap usage 223.234 MB -> 72.305 MB. [2025-10-01T21:48:43.499Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2112.475 ms) ====== [2025-10-01T21:48:43.499Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-10-01T21:48:43.499Z] GC before operation: completed in 120.003 ms, heap usage 264.359 MB -> 72.524 MB. [2025-10-01T21:48:45.095Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2077.721 ms) ====== [2025-10-01T21:48:45.095Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-10-01T21:48:45.095Z] GC before operation: completed in 126.945 ms, heap usage 255.090 MB -> 72.938 MB. [2025-10-01T21:48:47.573Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2122.342 ms) ====== [2025-10-01T21:48:47.573Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-10-01T21:48:47.573Z] GC before operation: completed in 132.137 ms, heap usage 164.824 MB -> 72.360 MB. [2025-10-01T21:48:50.045Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1998.081 ms) ====== [2025-10-01T21:48:50.045Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-10-01T21:48:50.045Z] GC before operation: completed in 134.581 ms, heap usage 218.402 MB -> 78.610 MB. [2025-10-01T21:48:51.637Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2026.612 ms) ====== [2025-10-01T21:48:51.637Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-10-01T21:48:52.409Z] GC before operation: completed in 137.239 ms, heap usage 114.527 MB -> 72.687 MB. [2025-10-01T21:48:53.999Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2063.597 ms) ====== [2025-10-01T21:48:53.999Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-10-01T21:48:53.999Z] GC before operation: completed in 170.782 ms, heap usage 185.797 MB -> 72.891 MB. [2025-10-01T21:48:56.486Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1972.353 ms) ====== [2025-10-01T21:48:56.486Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-10-01T21:48:56.486Z] GC before operation: completed in 154.167 ms, heap usage 303.562 MB -> 73.313 MB. [2025-10-01T21:48:58.077Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1931.915 ms) ====== [2025-10-01T21:48:58.077Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-10-01T21:48:58.077Z] GC before operation: completed in 133.771 ms, heap usage 162.676 MB -> 72.943 MB. [2025-10-01T21:49:00.548Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1929.075 ms) ====== [2025-10-01T21:49:00.548Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-10-01T21:49:00.548Z] GC before operation: completed in 154.959 ms, heap usage 161.972 MB -> 73.204 MB. [2025-10-01T21:49:02.142Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1940.412 ms) ====== [2025-10-01T21:49:02.142Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-10-01T21:49:02.924Z] GC before operation: completed in 171.723 ms, heap usage 218.307 MB -> 73.649 MB. [2025-10-01T21:49:04.525Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1979.572 ms) ====== [2025-10-01T21:49:04.525Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-10-01T21:49:04.525Z] GC before operation: completed in 191.195 ms, heap usage 168.797 MB -> 73.497 MB. [2025-10-01T21:49:07.002Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1940.807 ms) ====== [2025-10-01T21:49:07.002Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-10-01T21:49:07.002Z] GC before operation: completed in 155.845 ms, heap usage 313.993 MB -> 74.149 MB. [2025-10-01T21:49:08.596Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1931.614 ms) ====== [2025-10-01T21:49:08.596Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-10-01T21:49:09.366Z] GC before operation: completed in 181.451 ms, heap usage 152.982 MB -> 73.558 MB. [2025-10-01T21:49:11.140Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1901.074 ms) ====== [2025-10-01T21:49:11.140Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-10-01T21:49:11.140Z] GC before operation: completed in 166.165 ms, heap usage 236.570 MB -> 74.137 MB. [2025-10-01T21:49:12.731Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1932.609 ms) ====== [2025-10-01T21:49:12.731Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-10-01T21:49:13.503Z] GC before operation: completed in 174.751 ms, heap usage 112.725 MB -> 73.473 MB. [2025-10-01T21:49:15.093Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1913.867 ms) ====== [2025-10-01T21:49:15.093Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-10-01T21:49:15.093Z] GC before operation: completed in 163.500 ms, heap usage 124.817 MB -> 84.624 MB. [2025-10-01T21:49:17.634Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1935.684 ms) ====== [2025-10-01T21:49:17.634Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-10-01T21:49:17.634Z] GC before operation: completed in 191.647 ms, heap usage 308.918 MB -> 74.470 MB. [2025-10-01T21:49:19.240Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1889.730 ms) ====== [2025-10-01T21:49:19.240Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-10-01T21:49:19.240Z] GC before operation: completed in 171.160 ms, heap usage 305.140 MB -> 96.602 MB. [2025-10-01T21:49:21.043Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1794.781 ms) ====== [2025-10-01T21:49:21.043Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-10-01T21:49:21.813Z] GC before operation: completed in 166.305 ms, heap usage 250.200 MB -> 85.562 MB. [2025-10-01T21:49:23.404Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1933.310 ms) ====== [2025-10-01T21:49:23.404Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-10-01T21:49:23.404Z] GC before operation: completed in 173.649 ms, heap usage 306.814 MB -> 80.195 MB. [2025-10-01T21:49:25.876Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1874.140 ms) ====== [2025-10-01T21:49:25.876Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-10-01T21:49:25.876Z] GC before operation: completed in 167.949 ms, heap usage 168.742 MB -> 85.663 MB. [2025-10-01T21:49:27.475Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1909.692 ms) ====== [2025-10-01T21:49:27.475Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-10-01T21:49:27.475Z] GC before operation: completed in 184.706 ms, heap usage 297.692 MB -> 86.104 MB. [2025-10-01T21:49:29.947Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1827.794 ms) ====== [2025-10-01T21:49:29.947Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-10-01T21:49:29.947Z] GC before operation: completed in 214.455 ms, heap usage 252.048 MB -> 74.909 MB. [2025-10-01T21:49:31.540Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1838.185 ms) ====== [2025-10-01T21:49:31.540Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-10-01T21:49:31.540Z] GC before operation: completed in 186.442 ms, heap usage 318.643 MB -> 75.207 MB. [2025-10-01T21:49:34.061Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1831.805 ms) ====== [2025-10-01T21:49:34.061Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-10-01T21:49:34.061Z] GC before operation: completed in 165.247 ms, heap usage 118.739 MB -> 74.858 MB. [2025-10-01T21:49:34.061Z] 21:49:33.292 WARN [block-manager-storage-async-thread-pool-53] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-10-01T21:49:35.653Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1864.839 ms) ====== [2025-10-01T21:49:35.653Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-10-01T21:49:35.653Z] GC before operation: completed in 176.970 ms, heap usage 380.322 MB -> 80.811 MB. [2025-10-01T21:49:38.132Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1879.204 ms) ====== [2025-10-01T21:49:38.132Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-10-01T21:49:38.132Z] GC before operation: completed in 159.274 ms, heap usage 195.867 MB -> 75.198 MB. [2025-10-01T21:49:39.727Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1910.980 ms) ====== [2025-10-01T21:49:39.727Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-10-01T21:49:39.727Z] GC before operation: completed in 190.333 ms, heap usage 190.057 MB -> 75.152 MB. [2025-10-01T21:49:42.204Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1859.773 ms) ====== [2025-10-01T21:49:42.204Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-10-01T21:49:42.204Z] GC before operation: completed in 226.350 ms, heap usage 282.748 MB -> 86.890 MB. [2025-10-01T21:49:43.793Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1815.485 ms) ====== [2025-10-01T21:49:43.793Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-10-01T21:49:43.793Z] GC before operation: completed in 221.428 ms, heap usage 122.580 MB -> 75.077 MB. [2025-10-01T21:49:46.267Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1831.706 ms) ====== [2025-10-01T21:49:46.267Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-10-01T21:49:46.267Z] GC before operation: completed in 194.967 ms, heap usage 313.043 MB -> 87.106 MB. [2025-10-01T21:49:47.857Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1831.437 ms) ====== [2025-10-01T21:49:47.857Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-10-01T21:49:47.857Z] GC before operation: completed in 210.090 ms, heap usage 295.880 MB -> 86.794 MB. [2025-10-01T21:49:50.337Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1807.330 ms) ====== [2025-10-01T21:49:50.337Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-10-01T21:49:50.337Z] GC before operation: completed in 194.328 ms, heap usage 376.947 MB -> 76.163 MB. [2025-10-01T21:49:51.937Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1785.408 ms) ====== [2025-10-01T21:49:51.937Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-10-01T21:49:51.937Z] GC before operation: completed in 185.674 ms, heap usage 111.258 MB -> 75.488 MB. [2025-10-01T21:49:54.409Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1805.026 ms) ====== [2025-10-01T21:49:54.409Z] ----------------------------------- [2025-10-01T21:49:54.409Z] renaissance-dec-tree_0_PASSED [2025-10-01T21:49:54.409Z] ----------------------------------- [2025-10-01T21:49:54.409Z] [2025-10-01T21:49:54.409Z] TEST TEARDOWN: [2025-10-01T21:49:54.409Z] Nothing to be done for teardown. [2025-10-01T21:49:54.409Z] renaissance-dec-tree_0 Finish Time: Wed Oct 1 21:49:53 2025 Epoch Time (ms): 1759355393825