[2025-04-16T04:15:21.679Z] Running test renaissance-dec-tree_0 ... [2025-04-16T04:15:21.679Z] =============================================== [2025-04-16T04:15:21.679Z] renaissance-dec-tree_0 Start Time: Wed Apr 16 04:15:21 2025 Epoch Time (ms): 1744776921484 [2025-04-16T04:15:21.679Z] variation: NoOptions [2025-04-16T04:15:21.679Z] JVM_OPTIONS: [2025-04-16T04:15:21.679Z] { \ [2025-04-16T04:15:21.679Z] echo ""; echo "TEST SETUP:"; \ [2025-04-16T04:15:21.679Z] echo "Nothing to be done for setup."; \ [2025-04-16T04:15:21.679Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17447764578791/renaissance-dec-tree_0"; \ [2025-04-16T04:15:21.679Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17447764578791/renaissance-dec-tree_0"; \ [2025-04-16T04:15:21.679Z] echo ""; echo "TESTING:"; \ [2025-04-16T04:15:21.679Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17447764578791/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-04-16T04:15:21.679Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17447764578791/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-16T04:15:21.679Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-16T04:15:21.679Z] echo "Nothing to be done for teardown."; \ [2025-04-16T04:15:21.679Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17447764578791/TestTargetResult"; [2025-04-16T04:15:21.679Z] [2025-04-16T04:15:21.679Z] TEST SETUP: [2025-04-16T04:15:21.679Z] Nothing to be done for setup. [2025-04-16T04:15:21.679Z] [2025-04-16T04:15:21.679Z] TESTING: [2025-04-16T04:15:28.356Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-04-16T04:15:29.305Z] WARNING: An illegal reflective access operation has occurred [2025-04-16T04:15:29.305Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17447764578791/renaissance-dec-tree_0/harness-041522-6031581461655620989/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-04-16T04:15:29.305Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-04-16T04:15:29.305Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-04-16T04:15:29.305Z] WARNING: All illegal access operations will be denied in a future release [2025-04-16T04:15:31.281Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-04-16T04:15:31.281Z] GC before operation: completed in 58.194 ms, heap usage 62.450 MB -> 34.375 MB. [2025-04-16T04:15:41.067Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9390.274 ms) ====== [2025-04-16T04:15:41.067Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-04-16T04:15:41.067Z] GC before operation: completed in 120.365 ms, heap usage 281.452 MB -> 68.294 MB. [2025-04-16T04:15:43.014Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2319.808 ms) ====== [2025-04-16T04:15:43.014Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-04-16T04:15:43.014Z] GC before operation: completed in 121.777 ms, heap usage 187.512 MB -> 68.610 MB. [2025-04-16T04:15:44.959Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2056.817 ms) ====== [2025-04-16T04:15:44.959Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-04-16T04:15:44.959Z] GC before operation: completed in 100.579 ms, heap usage 106.863 MB -> 68.697 MB. [2025-04-16T04:15:46.974Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2015.573 ms) ====== [2025-04-16T04:15:46.975Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-04-16T04:15:46.975Z] GC before operation: completed in 97.527 ms, heap usage 159.841 MB -> 68.949 MB. [2025-04-16T04:15:48.921Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1911.975 ms) ====== [2025-04-16T04:15:48.921Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-04-16T04:15:48.921Z] GC before operation: completed in 99.551 ms, heap usage 211.076 MB -> 69.488 MB. [2025-04-16T04:15:50.868Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1932.897 ms) ====== [2025-04-16T04:15:50.868Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-04-16T04:15:50.868Z] GC before operation: completed in 104.467 ms, heap usage 211.647 MB -> 69.706 MB. [2025-04-16T04:15:52.817Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1805.925 ms) ====== [2025-04-16T04:15:52.818Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-04-16T04:15:52.818Z] GC before operation: completed in 103.120 ms, heap usage 293.637 MB -> 69.764 MB. [2025-04-16T04:15:54.765Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1825.485 ms) ====== [2025-04-16T04:15:54.765Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-04-16T04:15:54.765Z] GC before operation: completed in 104.862 ms, heap usage 259.266 MB -> 70.279 MB. [2025-04-16T04:15:56.713Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1775.047 ms) ====== [2025-04-16T04:15:56.714Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-04-16T04:15:56.714Z] GC before operation: completed in 132.100 ms, heap usage 223.284 MB -> 75.543 MB. [2025-04-16T04:15:58.662Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2142.920 ms) ====== [2025-04-16T04:15:58.662Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-04-16T04:15:59.612Z] GC before operation: completed in 129.853 ms, heap usage 249.910 MB -> 70.248 MB. [2025-04-16T04:16:00.571Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1779.934 ms) ====== [2025-04-16T04:16:00.571Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-04-16T04:16:01.523Z] GC before operation: completed in 117.249 ms, heap usage 213.620 MB -> 76.004 MB. [2025-04-16T04:16:01.523Z] 04:16:00.601 WARN [block-manager-storage-async-thread-pool-35] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_149, which does not exist [2025-04-16T04:16:03.513Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1785.345 ms) ====== [2025-04-16T04:16:03.513Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-04-16T04:16:03.513Z] GC before operation: completed in 123.830 ms, heap usage 128.901 MB -> 80.648 MB. [2025-04-16T04:16:04.644Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1796.552 ms) ====== [2025-04-16T04:16:04.644Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-04-16T04:16:04.644Z] GC before operation: completed in 121.215 ms, heap usage 189.868 MB -> 70.191 MB. [2025-04-16T04:16:06.600Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1768.581 ms) ====== [2025-04-16T04:16:06.600Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-04-16T04:16:06.600Z] GC before operation: completed in 114.853 ms, heap usage 254.846 MB -> 81.888 MB. [2025-04-16T04:16:08.550Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1650.491 ms) ====== [2025-04-16T04:16:08.550Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-04-16T04:16:08.550Z] GC before operation: completed in 120.052 ms, heap usage 230.399 MB -> 70.857 MB. [2025-04-16T04:16:10.501Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1763.919 ms) ====== [2025-04-16T04:16:10.501Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-04-16T04:16:10.501Z] GC before operation: completed in 121.634 ms, heap usage 148.027 MB -> 70.711 MB. [2025-04-16T04:16:12.448Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1738.934 ms) ====== [2025-04-16T04:16:12.448Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-04-16T04:16:12.448Z] GC before operation: completed in 139.429 ms, heap usage 214.039 MB -> 82.031 MB. [2025-04-16T04:16:14.402Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1788.099 ms) ====== [2025-04-16T04:16:14.402Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-04-16T04:16:14.403Z] GC before operation: completed in 132.076 ms, heap usage 113.292 MB -> 70.938 MB. [2025-04-16T04:16:16.351Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1772.867 ms) ====== [2025-04-16T04:16:16.351Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-04-16T04:16:16.351Z] GC before operation: completed in 130.407 ms, heap usage 190.966 MB -> 71.051 MB. [2025-04-16T04:16:18.314Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1809.834 ms) ====== [2025-04-16T04:16:18.314Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-04-16T04:16:18.314Z] GC before operation: completed in 130.746 ms, heap usage 114.354 MB -> 71.147 MB. [2025-04-16T04:16:20.274Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1766.027 ms) ====== [2025-04-16T04:16:20.274Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-04-16T04:16:20.274Z] GC before operation: completed in 127.921 ms, heap usage 252.892 MB -> 71.427 MB. [2025-04-16T04:16:22.223Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1774.270 ms) ====== [2025-04-16T04:16:22.223Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-04-16T04:16:22.223Z] GC before operation: completed in 127.304 ms, heap usage 263.579 MB -> 71.688 MB. [2025-04-16T04:16:24.185Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1687.539 ms) ====== [2025-04-16T04:16:24.185Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-04-16T04:16:24.185Z] GC before operation: completed in 123.672 ms, heap usage 181.617 MB -> 99.253 MB. [2025-04-16T04:16:25.134Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1690.780 ms) ====== [2025-04-16T04:16:25.134Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-04-16T04:16:25.134Z] GC before operation: completed in 123.242 ms, heap usage 172.951 MB -> 71.562 MB. [2025-04-16T04:16:27.091Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1655.073 ms) ====== [2025-04-16T04:16:27.092Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-04-16T04:16:27.092Z] GC before operation: completed in 135.234 ms, heap usage 184.731 MB -> 71.998 MB. [2025-04-16T04:16:29.042Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1695.691 ms) ====== [2025-04-16T04:16:29.042Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-04-16T04:16:29.042Z] GC before operation: completed in 124.916 ms, heap usage 179.699 MB -> 99.555 MB. [2025-04-16T04:16:30.990Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1599.868 ms) ====== [2025-04-16T04:16:30.990Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-04-16T04:16:30.990Z] GC before operation: completed in 121.060 ms, heap usage 266.689 MB -> 72.283 MB. [2025-04-16T04:16:33.065Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1664.124 ms) ====== [2025-04-16T04:16:33.065Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-04-16T04:16:33.065Z] GC before operation: completed in 119.876 ms, heap usage 110.237 MB -> 71.998 MB. [2025-04-16T04:16:34.015Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1650.883 ms) ====== [2025-04-16T04:16:34.015Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-04-16T04:16:34.964Z] GC before operation: completed in 122.797 ms, heap usage 273.253 MB -> 94.221 MB. [2025-04-16T04:16:35.916Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1595.023 ms) ====== [2025-04-16T04:16:35.916Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-04-16T04:16:35.916Z] GC before operation: completed in 143.436 ms, heap usage 290.845 MB -> 72.342 MB. [2025-04-16T04:16:37.885Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1661.646 ms) ====== [2025-04-16T04:16:37.885Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-04-16T04:16:37.885Z] GC before operation: completed in 123.390 ms, heap usage 185.035 MB -> 72.449 MB. [2025-04-16T04:16:39.841Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1637.475 ms) ====== [2025-04-16T04:16:39.841Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-04-16T04:16:39.841Z] GC before operation: completed in 141.778 ms, heap usage 221.563 MB -> 72.561 MB. [2025-04-16T04:16:41.915Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1675.577 ms) ====== [2025-04-16T04:16:41.915Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-04-16T04:16:41.915Z] GC before operation: completed in 140.530 ms, heap usage 306.149 MB -> 72.805 MB. [2025-04-16T04:16:42.862Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1711.573 ms) ====== [2025-04-16T04:16:42.862Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-04-16T04:16:43.811Z] GC before operation: completed in 138.110 ms, heap usage 149.912 MB -> 72.473 MB. [2025-04-16T04:16:44.760Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1682.095 ms) ====== [2025-04-16T04:16:44.760Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-04-16T04:16:45.711Z] GC before operation: completed in 142.028 ms, heap usage 266.714 MB -> 78.727 MB. [2025-04-16T04:16:46.663Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1685.830 ms) ====== [2025-04-16T04:16:46.663Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-04-16T04:16:46.663Z] GC before operation: completed in 138.797 ms, heap usage 224.349 MB -> 84.114 MB. [2025-04-16T04:16:48.613Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1635.310 ms) ====== [2025-04-16T04:16:48.614Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-04-16T04:16:48.614Z] GC before operation: completed in 133.935 ms, heap usage 254.278 MB -> 73.250 MB. [2025-04-16T04:16:50.561Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1648.364 ms) ====== [2025-04-16T04:16:50.561Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-04-16T04:16:50.561Z] GC before operation: completed in 130.853 ms, heap usage 261.690 MB -> 73.359 MB. [2025-04-16T04:16:52.507Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1614.607 ms) ====== [2025-04-16T04:16:52.507Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-04-16T04:16:52.507Z] GC before operation: completed in 125.139 ms, heap usage 216.500 MB -> 84.216 MB. [2025-04-16T04:16:54.456Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1567.154 ms) ====== [2025-04-16T04:16:54.456Z] ----------------------------------- [2025-04-16T04:16:54.456Z] renaissance-dec-tree_0_PASSED [2025-04-16T04:16:54.456Z] ----------------------------------- [2025-04-16T04:16:54.456Z] [2025-04-16T04:16:54.456Z] TEST TEARDOWN: [2025-04-16T04:16:54.456Z] Nothing to be done for teardown. [2025-04-16T04:16:54.456Z] renaissance-dec-tree_0 Finish Time: Wed Apr 16 04:16:53 2025 Epoch Time (ms): 1744777013723