[2025-01-10T20:47:41.361Z] Running test renaissance-dec-tree_0 ... [2025-01-10T20:47:41.361Z] =============================================== [2025-01-10T20:47:41.361Z] renaissance-dec-tree_0 Start Time: Fri Jan 10 20:47:41 2025 Epoch Time (ms): 1736542061208 [2025-01-10T20:47:41.361Z] variation: NoOptions [2025-01-10T20:47:41.361Z] JVM_OPTIONS: [2025-01-10T20:47:41.361Z] { \ [2025-01-10T20:47:41.361Z] echo ""; echo "TEST SETUP:"; \ [2025-01-10T20:47:41.361Z] echo "Nothing to be done for setup."; \ [2025-01-10T20:47:41.361Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17365416068485/renaissance-dec-tree_0"; \ [2025-01-10T20:47:41.361Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17365416068485/renaissance-dec-tree_0"; \ [2025-01-10T20:47:41.361Z] echo ""; echo "TESTING:"; \ [2025-01-10T20:47:41.361Z] "/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_17365416068485/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-01-10T20:47:41.361Z] 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_17365416068485/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-10T20:47:41.361Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-10T20:47:41.361Z] echo "Nothing to be done for teardown."; \ [2025-01-10T20:47:41.361Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17365416068485/TestTargetResult"; [2025-01-10T20:47:41.361Z] [2025-01-10T20:47:41.361Z] TEST SETUP: [2025-01-10T20:47:41.361Z] Nothing to be done for setup. [2025-01-10T20:47:41.361Z] [2025-01-10T20:47:41.361Z] TESTING: [2025-01-10T20:47:46.971Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-10T20:47:48.561Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2025-01-10T20:47:51.036Z] WARNING: An illegal reflective access operation has occurred [2025-01-10T20:47:51.036Z] 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_17365416068485/renaissance-dec-tree_0/harness-204742-388991132780055113/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-01-10T20:47:51.036Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-01-10T20:47:51.036Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-01-10T20:47:51.036Z] WARNING: All illegal access operations will be denied in a future release [2025-01-10T20:47:53.586Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-01-10T20:47:53.586Z] GC before operation: completed in 66.310 ms, heap usage 66.143 MB -> 35.237 MB. [2025-01-10T20:48:03.472Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9395.630 ms) ====== [2025-01-10T20:48:03.472Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-01-10T20:48:03.472Z] GC before operation: completed in 93.377 ms, heap usage 343.436 MB -> 67.217 MB. [2025-01-10T20:48:05.941Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2561.579 ms) ====== [2025-01-10T20:48:05.941Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-01-10T20:48:05.941Z] GC before operation: completed in 91.849 ms, heap usage 163.203 MB -> 67.390 MB. [2025-01-10T20:48:08.427Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2167.660 ms) ====== [2025-01-10T20:48:08.427Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-01-10T20:48:08.427Z] GC before operation: completed in 102.923 ms, heap usage 240.138 MB -> 67.663 MB. [2025-01-10T20:48:10.023Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2069.314 ms) ====== [2025-01-10T20:48:10.024Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-01-10T20:48:10.024Z] GC before operation: completed in 101.705 ms, heap usage 178.677 MB -> 67.765 MB. [2025-01-10T20:48:12.489Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2019.932 ms) ====== [2025-01-10T20:48:12.490Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-01-10T20:48:12.490Z] GC before operation: completed in 103.717 ms, heap usage 247.804 MB -> 68.254 MB. [2025-01-10T20:48:14.083Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1987.533 ms) ====== [2025-01-10T20:48:14.083Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-01-10T20:48:14.862Z] GC before operation: completed in 110.562 ms, heap usage 243.224 MB -> 79.621 MB. [2025-01-10T20:48:16.451Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1931.616 ms) ====== [2025-01-10T20:48:16.451Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-01-10T20:48:16.451Z] GC before operation: completed in 109.636 ms, heap usage 250.652 MB -> 68.682 MB. [2025-01-10T20:48:18.042Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1882.670 ms) ====== [2025-01-10T20:48:18.042Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-01-10T20:48:18.811Z] GC before operation: completed in 108.687 ms, heap usage 208.083 MB -> 69.021 MB. [2025-01-10T20:48:20.402Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1870.940 ms) ====== [2025-01-10T20:48:20.402Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-01-10T20:48:20.402Z] GC before operation: completed in 107.439 ms, heap usage 148.861 MB -> 68.451 MB. [2025-01-10T20:48:22.512Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1868.276 ms) ====== [2025-01-10T20:48:22.512Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-01-10T20:48:22.512Z] GC before operation: completed in 113.820 ms, heap usage 284.218 MB -> 69.031 MB. [2025-01-10T20:48:24.112Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1797.203 ms) ====== [2025-01-10T20:48:24.112Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-01-10T20:48:24.112Z] GC before operation: completed in 124.534 ms, heap usage 147.119 MB -> 68.849 MB. [2025-01-10T20:48:26.587Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1828.888 ms) ====== [2025-01-10T20:48:26.587Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-01-10T20:48:26.587Z] GC before operation: completed in 125.885 ms, heap usage 189.296 MB -> 69.316 MB. [2025-01-10T20:48:28.174Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1859.977 ms) ====== [2025-01-10T20:48:28.174Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-01-10T20:48:28.175Z] GC before operation: completed in 126.526 ms, heap usage 137.406 MB -> 69.325 MB. [2025-01-10T20:48:30.649Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1809.466 ms) ====== [2025-01-10T20:48:30.649Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-01-10T20:48:30.649Z] GC before operation: completed in 119.284 ms, heap usage 119.710 MB -> 68.749 MB. [2025-01-10T20:48:32.236Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1804.274 ms) ====== [2025-01-10T20:48:32.236Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-01-10T20:48:32.236Z] GC before operation: completed in 160.717 ms, heap usage 246.375 MB -> 69.782 MB. [2025-01-10T20:48:33.831Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1866.453 ms) ====== [2025-01-10T20:48:33.831Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-01-10T20:48:34.609Z] GC before operation: completed in 124.434 ms, heap usage 146.144 MB -> 69.445 MB. [2025-01-10T20:48:36.202Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1744.001 ms) ====== [2025-01-10T20:48:36.202Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-01-10T20:48:36.202Z] GC before operation: completed in 134.751 ms, heap usage 178.196 MB -> 69.422 MB. [2025-01-10T20:48:37.797Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1791.668 ms) ====== [2025-01-10T20:48:37.797Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-01-10T20:48:37.797Z] GC before operation: completed in 128.566 ms, heap usage 244.814 MB -> 69.795 MB. [2025-01-10T20:48:40.269Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1760.343 ms) ====== [2025-01-10T20:48:40.269Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-01-10T20:48:40.269Z] GC before operation: completed in 129.519 ms, heap usage 118.966 MB -> 69.471 MB. [2025-01-10T20:48:41.856Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1874.575 ms) ====== [2025-01-10T20:48:41.857Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-01-10T20:48:41.857Z] GC before operation: completed in 137.954 ms, heap usage 293.283 MB -> 81.269 MB. [2025-01-10T20:48:43.448Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1781.838 ms) ====== [2025-01-10T20:48:43.448Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-01-10T20:48:44.217Z] GC before operation: completed in 144.582 ms, heap usage 338.775 MB -> 75.904 MB. [2025-01-10T20:48:45.804Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1776.933 ms) ====== [2025-01-10T20:48:45.804Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-01-10T20:48:45.804Z] GC before operation: completed in 142.216 ms, heap usage 304.700 MB -> 70.379 MB. [2025-01-10T20:48:47.398Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1712.708 ms) ====== [2025-01-10T20:48:47.398Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-01-10T20:48:47.398Z] GC before operation: completed in 135.540 ms, heap usage 180.780 MB -> 97.650 MB. [2025-01-10T20:48:49.886Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1766.948 ms) ====== [2025-01-10T20:48:49.886Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-01-10T20:48:49.886Z] GC before operation: completed in 140.852 ms, heap usage 158.987 MB -> 70.313 MB. [2025-01-10T20:48:51.478Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1725.628 ms) ====== [2025-01-10T20:48:51.478Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-01-10T20:48:51.478Z] GC before operation: completed in 163.673 ms, heap usage 117.350 MB -> 70.088 MB. [2025-01-10T20:48:53.076Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1732.964 ms) ====== [2025-01-10T20:48:53.077Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-01-10T20:48:53.077Z] GC before operation: completed in 157.178 ms, heap usage 231.337 MB -> 81.890 MB. [2025-01-10T20:48:55.542Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1871.751 ms) ====== [2025-01-10T20:48:55.542Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-01-10T20:48:55.542Z] GC before operation: completed in 144.769 ms, heap usage 273.105 MB -> 71.062 MB. [2025-01-10T20:48:57.129Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1848.125 ms) ====== [2025-01-10T20:48:57.129Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-01-10T20:48:57.129Z] GC before operation: completed in 142.243 ms, heap usage 135.579 MB -> 70.691 MB. [2025-01-10T20:48:58.719Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1715.231 ms) ====== [2025-01-10T20:48:58.719Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-01-10T20:48:59.494Z] GC before operation: completed in 151.215 ms, heap usage 110.758 MB -> 70.337 MB. [2025-01-10T20:49:01.090Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1709.755 ms) ====== [2025-01-10T20:49:01.090Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-01-10T20:49:01.090Z] GC before operation: completed in 159.648 ms, heap usage 188.549 MB -> 71.127 MB. [2025-01-10T20:49:02.756Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1767.316 ms) ====== [2025-01-10T20:49:02.756Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-01-10T20:49:02.756Z] GC before operation: completed in 158.681 ms, heap usage 219.178 MB -> 82.370 MB. [2025-01-10T20:49:05.221Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1734.217 ms) ====== [2025-01-10T20:49:05.221Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-01-10T20:49:05.221Z] GC before operation: completed in 156.288 ms, heap usage 203.001 MB -> 71.171 MB. [2025-01-10T20:49:06.809Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1702.621 ms) ====== [2025-01-10T20:49:06.809Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-01-10T20:49:06.809Z] GC before operation: completed in 161.447 ms, heap usage 181.758 MB -> 82.078 MB. [2025-01-10T20:49:08.399Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1734.033 ms) ====== [2025-01-10T20:49:08.399Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-01-10T20:49:08.399Z] GC before operation: completed in 143.366 ms, heap usage 350.116 MB -> 87.877 MB. [2025-01-10T20:49:09.987Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1727.017 ms) ====== [2025-01-10T20:49:09.987Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-01-10T20:49:10.754Z] GC before operation: completed in 150.412 ms, heap usage 333.275 MB -> 87.998 MB. [2025-01-10T20:49:12.346Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1680.256 ms) ====== [2025-01-10T20:49:12.346Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-01-10T20:49:12.346Z] GC before operation: completed in 177.910 ms, heap usage 148.293 MB -> 71.434 MB. [2025-01-10T20:49:14.067Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1711.305 ms) ====== [2025-01-10T20:49:14.067Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-01-10T20:49:14.067Z] GC before operation: completed in 172.034 ms, heap usage 151.221 MB -> 82.430 MB. [2025-01-10T20:49:16.558Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1794.463 ms) ====== [2025-01-10T20:49:16.558Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-01-10T20:49:16.558Z] GC before operation: completed in 206.310 ms, heap usage 220.656 MB -> 77.371 MB. [2025-01-10T20:49:18.147Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1701.858 ms) ====== [2025-01-10T20:49:18.147Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-01-10T20:49:18.147Z] GC before operation: completed in 155.380 ms, heap usage 117.770 MB -> 71.312 MB. [2025-01-10T20:49:19.741Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1744.583 ms) ====== [2025-01-10T20:49:20.510Z] ----------------------------------- [2025-01-10T20:49:20.510Z] renaissance-dec-tree_0_PASSED [2025-01-10T20:49:20.510Z] ----------------------------------- [2025-01-10T20:49:20.510Z] [2025-01-10T20:49:20.510Z] TEST TEARDOWN: [2025-01-10T20:49:20.510Z] Nothing to be done for teardown. [2025-01-10T20:49:20.510Z] renaissance-dec-tree_0 Finish Time: Fri Jan 10 20:49:19 2025 Epoch Time (ms): 1736542159817