[2025-04-16T05:07:08.809Z] Running test renaissance-dec-tree_0 ... [2025-04-16T05:07:08.809Z] =============================================== [2025-04-16T05:07:09.498Z] renaissance-dec-tree_0 Start Time: Wed Apr 16 00:07:08 2025 Epoch Time (ms): 1744780028384 [2025-04-16T05:07:09.498Z] variation: NoOptions [2025-04-16T05:07:09.498Z] JVM_OPTIONS: [2025-04-16T05:07:09.498Z] { \ [2025-04-16T05:07:09.498Z] echo ""; echo "TEST SETUP:"; \ [2025-04-16T05:07:09.498Z] echo "Nothing to be done for setup."; \ [2025-04-16T05:07:09.498Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17447794933/renaissance-dec-tree_0"; \ [2025-04-16T05:07:09.498Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17447794933/renaissance-dec-tree_0"; \ [2025-04-16T05:07:09.498Z] echo ""; echo "TESTING:"; \ [2025-04-16T05:07:09.498Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17447794933/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-04-16T05:07:09.498Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17447794933/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-16T05:07:09.498Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-16T05:07:09.498Z] echo "Nothing to be done for teardown."; \ [2025-04-16T05:07:09.498Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17447794933/TestTargetResult"; [2025-04-16T05:07:09.498Z] [2025-04-16T05:07:09.498Z] TEST SETUP: [2025-04-16T05:07:09.498Z] Nothing to be done for setup. [2025-04-16T05:07:09.498Z] [2025-04-16T05:07:09.498Z] TESTING: [2025-04-16T05:07:17.129Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2025-04-16T05:07:17.815Z] WARNING: An illegal reflective access operation has occurred [2025-04-16T05:07:17.815Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17447794933/renaissance-dec-tree_0/harness-000709-941371856548056097/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-04-16T05:07:17.815Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-04-16T05:07:17.815Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-04-16T05:07:17.815Z] WARNING: All illegal access operations will be denied in a future release [2025-04-16T05:07:19.242Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-04-16T05:07:19.242Z] GC before operation: completed in 79.196 ms, heap usage 79.903 MB -> 34.882 MB. [2025-04-16T05:07:30.078Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10293.526 ms) ====== [2025-04-16T05:07:30.078Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-04-16T05:07:30.078Z] GC before operation: completed in 187.673 ms, heap usage 374.540 MB -> 68.952 MB. [2025-04-16T05:07:32.301Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2240.370 ms) ====== [2025-04-16T05:07:32.301Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-04-16T05:07:32.301Z] GC before operation: completed in 177.818 ms, heap usage 216.932 MB -> 69.143 MB. [2025-04-16T05:07:34.524Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2053.912 ms) ====== [2025-04-16T05:07:34.524Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-04-16T05:07:34.524Z] GC before operation: completed in 144.423 ms, heap usage 205.519 MB -> 69.406 MB. [2025-04-16T05:07:35.948Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1848.571 ms) ====== [2025-04-16T05:07:35.948Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-04-16T05:07:36.633Z] GC before operation: completed in 152.307 ms, heap usage 158.690 MB -> 69.340 MB. [2025-04-16T05:07:38.052Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1654.855 ms) ====== [2025-04-16T05:07:38.052Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-04-16T05:07:38.052Z] GC before operation: completed in 160.221 ms, heap usage 174.569 MB -> 69.741 MB. [2025-04-16T05:07:40.295Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1840.242 ms) ====== [2025-04-16T05:07:40.295Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-04-16T05:07:40.295Z] GC before operation: completed in 160.984 ms, heap usage 306.102 MB -> 81.378 MB. [2025-04-16T05:07:42.511Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1975.655 ms) ====== [2025-04-16T05:07:42.511Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-04-16T05:07:42.511Z] GC before operation: completed in 118.650 ms, heap usage 169.898 MB -> 81.100 MB. [2025-04-16T05:07:43.931Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1521.157 ms) ====== [2025-04-16T05:07:43.932Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-04-16T05:07:43.932Z] GC before operation: completed in 117.459 ms, heap usage 149.709 MB -> 81.495 MB. [2025-04-16T05:07:43.932Z] 00:07:43.195 WARN [block-manager-storage-async-thread-pool-24] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_107_piece0, which does not exist [2025-04-16T05:07:45.354Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1581.032 ms) ====== [2025-04-16T05:07:45.354Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-04-16T05:07:46.039Z] GC before operation: completed in 163.276 ms, heap usage 171.541 MB -> 70.381 MB. [2025-04-16T05:07:47.463Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1575.763 ms) ====== [2025-04-16T05:07:47.463Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-04-16T05:07:47.463Z] GC before operation: completed in 151.089 ms, heap usage 317.607 MB -> 70.680 MB. [2025-04-16T05:07:48.884Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1512.452 ms) ====== [2025-04-16T05:07:48.884Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-04-16T05:07:48.884Z] GC before operation: completed in 150.898 ms, heap usage 174.994 MB -> 76.128 MB. [2025-04-16T05:07:50.305Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1425.984 ms) ====== [2025-04-16T05:07:50.305Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-04-16T05:07:50.992Z] GC before operation: completed in 166.475 ms, heap usage 305.012 MB -> 70.917 MB. [2025-04-16T05:07:52.418Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1471.674 ms) ====== [2025-04-16T05:07:52.418Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-04-16T05:07:52.418Z] GC before operation: completed in 128.624 ms, heap usage 275.979 MB -> 82.137 MB. [2025-04-16T05:07:53.944Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1523.237 ms) ====== [2025-04-16T05:07:53.944Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-04-16T05:07:53.944Z] GC before operation: completed in 171.599 ms, heap usage 345.928 MB -> 71.289 MB. [2025-04-16T05:07:56.166Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1735.582 ms) ====== [2025-04-16T05:07:56.166Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-04-16T05:07:56.166Z] GC before operation: completed in 152.613 ms, heap usage 258.315 MB -> 71.246 MB. [2025-04-16T05:07:57.589Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1542.646 ms) ====== [2025-04-16T05:07:57.589Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-04-16T05:07:57.589Z] GC before operation: completed in 189.940 ms, heap usage 229.396 MB -> 71.567 MB. [2025-04-16T05:07:59.011Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1696.805 ms) ====== [2025-04-16T05:07:59.011Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-04-16T05:07:59.698Z] GC before operation: completed in 187.991 ms, heap usage 234.895 MB -> 71.687 MB. [2025-04-16T05:08:01.122Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1508.474 ms) ====== [2025-04-16T05:08:01.122Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-04-16T05:08:01.122Z] GC before operation: completed in 148.321 ms, heap usage 127.356 MB -> 87.519 MB. [2025-04-16T05:08:02.548Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1378.977 ms) ====== [2025-04-16T05:08:02.548Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-04-16T05:08:02.548Z] GC before operation: completed in 177.881 ms, heap usage 188.484 MB -> 71.458 MB. [2025-04-16T05:08:04.768Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1697.099 ms) ====== [2025-04-16T05:08:04.768Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-04-16T05:08:04.768Z] GC before operation: completed in 149.308 ms, heap usage 232.954 MB -> 71.866 MB. [2025-04-16T05:08:06.194Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1377.417 ms) ====== [2025-04-16T05:08:06.194Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-04-16T05:08:06.194Z] GC before operation: completed in 136.343 ms, heap usage 265.061 MB -> 71.983 MB. [2025-04-16T05:08:07.616Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1704.824 ms) ====== [2025-04-16T05:08:07.616Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-04-16T05:08:07.616Z] GC before operation: completed in 166.817 ms, heap usage 168.357 MB -> 71.940 MB. [2025-04-16T05:08:09.036Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1414.857 ms) ====== [2025-04-16T05:08:09.036Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-04-16T05:08:09.720Z] GC before operation: completed in 137.344 ms, heap usage 144.690 MB -> 82.781 MB. [2025-04-16T05:08:11.143Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1591.790 ms) ====== [2025-04-16T05:08:11.143Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-04-16T05:08:11.143Z] GC before operation: completed in 217.243 ms, heap usage 197.760 MB -> 72.008 MB. [2025-04-16T05:08:13.366Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1657.034 ms) ====== [2025-04-16T05:08:13.366Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-04-16T05:08:13.366Z] GC before operation: completed in 154.230 ms, heap usage 244.918 MB -> 88.907 MB. [2025-04-16T05:08:14.789Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1369.794 ms) ====== [2025-04-16T05:08:14.789Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-04-16T05:08:14.789Z] GC before operation: completed in 171.061 ms, heap usage 294.258 MB -> 72.525 MB. [2025-04-16T05:08:16.323Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1487.269 ms) ====== [2025-04-16T05:08:16.323Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-04-16T05:08:16.323Z] GC before operation: completed in 174.124 ms, heap usage 162.171 MB -> 72.286 MB. [2025-04-16T05:08:17.754Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1500.337 ms) ====== [2025-04-16T05:08:17.754Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-04-16T05:08:17.754Z] GC before operation: completed in 228.894 ms, heap usage 202.787 MB -> 72.861 MB. [2025-04-16T05:08:19.978Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1611.013 ms) ====== [2025-04-16T05:08:19.978Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-04-16T05:08:19.978Z] GC before operation: completed in 151.906 ms, heap usage 285.135 MB -> 72.829 MB. [2025-04-16T05:08:21.399Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1505.935 ms) ====== [2025-04-16T05:08:21.399Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-04-16T05:08:21.399Z] GC before operation: completed in 184.558 ms, heap usage 232.421 MB -> 72.934 MB. [2025-04-16T05:08:22.821Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1380.879 ms) ====== [2025-04-16T05:08:22.822Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-04-16T05:08:22.822Z] GC before operation: completed in 129.858 ms, heap usage 156.675 MB -> 89.214 MB. [2025-04-16T05:08:24.253Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1321.254 ms) ====== [2025-04-16T05:08:24.253Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-04-16T05:08:24.253Z] GC before operation: completed in 202.138 ms, heap usage 194.830 MB -> 72.967 MB. [2025-04-16T05:08:26.472Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1641.979 ms) ====== [2025-04-16T05:08:26.472Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-04-16T05:08:26.472Z] GC before operation: completed in 188.488 ms, heap usage 243.487 MB -> 73.573 MB. [2025-04-16T05:08:27.896Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1520.599 ms) ====== [2025-04-16T05:08:27.896Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-04-16T05:08:27.896Z] GC before operation: completed in 158.701 ms, heap usage 248.317 MB -> 89.844 MB. [2025-04-16T05:08:29.319Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1393.697 ms) ====== [2025-04-16T05:08:29.319Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-04-16T05:08:29.319Z] GC before operation: completed in 166.265 ms, heap usage 304.523 MB -> 73.579 MB. [2025-04-16T05:08:31.535Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1619.911 ms) ====== [2025-04-16T05:08:31.535Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-04-16T05:08:31.535Z] GC before operation: completed in 184.407 ms, heap usage 231.675 MB -> 84.798 MB. [2025-04-16T05:08:32.954Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1724.056 ms) ====== [2025-04-16T05:08:32.954Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-04-16T05:08:33.645Z] GC before operation: completed in 241.366 ms, heap usage 302.265 MB -> 73.643 MB. [2025-04-16T05:08:35.069Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1642.783 ms) ====== [2025-04-16T05:08:35.069Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-04-16T05:08:35.069Z] GC before operation: completed in 183.558 ms, heap usage 144.057 MB -> 73.575 MB. [2025-04-16T05:08:36.492Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1455.382 ms) ====== [2025-04-16T05:08:36.492Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-04-16T05:08:37.178Z] GC before operation: completed in 153.764 ms, heap usage 123.651 MB -> 84.503 MB. [2025-04-16T05:08:38.601Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1414.807 ms) ====== [2025-04-16T05:08:38.601Z] ----------------------------------- [2025-04-16T05:08:38.601Z] renaissance-dec-tree_0_PASSED [2025-04-16T05:08:38.601Z] ----------------------------------- [2025-04-16T05:08:38.601Z] [2025-04-16T05:08:38.601Z] TEST TEARDOWN: [2025-04-16T05:08:38.601Z] Nothing to be done for teardown. [2025-04-16T05:08:38.601Z] renaissance-dec-tree_0 Finish Time: Wed Apr 16 00:08:38 2025 Epoch Time (ms): 1744780118128