[2025-05-07T11:19:21.661Z] Running test renaissance-dec-tree_0 ... [2025-05-07T11:19:21.661Z] =============================================== [2025-05-07T11:19:21.661Z] renaissance-dec-tree_0 Start Time: Wed May 7 11:19:21 2025 Epoch Time (ms): 1746616761379 [2025-05-07T11:19:21.661Z] variation: NoOptions [2025-05-07T11:19:21.661Z] JVM_OPTIONS: [2025-05-07T11:19:21.661Z] { \ [2025-05-07T11:19:21.661Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T11:19:21.661Z] echo "Nothing to be done for setup."; \ [2025-05-07T11:19:21.661Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466161283742/renaissance-dec-tree_0"; \ [2025-05-07T11:19:21.661Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466161283742/renaissance-dec-tree_0"; \ [2025-05-07T11:19:21.661Z] echo ""; echo "TESTING:"; \ [2025-05-07T11:19:21.661Z] "/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_17466161283742/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-07T11:19:21.661Z] 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_17466161283742/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T11:19:21.661Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T11:19:21.661Z] echo "Nothing to be done for teardown."; \ [2025-05-07T11:19:21.661Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466161283742/TestTargetResult"; [2025-05-07T11:19:21.661Z] [2025-05-07T11:19:21.661Z] TEST SETUP: [2025-05-07T11:19:21.661Z] Nothing to be done for setup. [2025-05-07T11:19:21.661Z] [2025-05-07T11:19:21.661Z] TESTING: [2025-05-07T11:19:30.256Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-07T11:19:30.883Z] WARNING: An illegal reflective access operation has occurred [2025-05-07T11:19:30.883Z] 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_17466161283742/renaissance-dec-tree_0/harness-111922-12377744427330308230/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-07T11:19:30.883Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-07T11:19:30.883Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-07T11:19:30.883Z] WARNING: All illegal access operations will be denied in a future release [2025-05-07T11:19:32.192Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-07T11:19:32.192Z] GC before operation: completed in 54.267 ms, heap usage 77.410 MB -> 34.375 MB. [2025-05-07T11:19:42.470Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10571.283 ms) ====== [2025-05-07T11:19:42.470Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-07T11:19:42.470Z] GC before operation: completed in 139.667 ms, heap usage 157.356 MB -> 67.402 MB. [2025-05-07T11:19:46.241Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3506.726 ms) ====== [2025-05-07T11:19:46.241Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-07T11:19:46.241Z] GC before operation: completed in 107.415 ms, heap usage 215.946 MB -> 67.698 MB. [2025-05-07T11:19:49.104Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2299.074 ms) ====== [2025-05-07T11:19:49.104Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-07T11:19:49.104Z] GC before operation: completed in 110.996 ms, heap usage 111.182 MB -> 67.456 MB. [2025-05-07T11:19:51.156Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2131.642 ms) ====== [2025-05-07T11:19:51.156Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-07T11:19:51.156Z] GC before operation: completed in 108.846 ms, heap usage 231.404 MB -> 68.006 MB. [2025-05-07T11:19:53.204Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2161.767 ms) ====== [2025-05-07T11:19:53.204Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-07T11:19:53.204Z] GC before operation: completed in 107.753 ms, heap usage 165.028 MB -> 68.195 MB. [2025-05-07T11:19:55.260Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1800.669 ms) ====== [2025-05-07T11:19:55.260Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-07T11:19:55.260Z] GC before operation: completed in 108.037 ms, heap usage 104.988 MB -> 68.315 MB. [2025-05-07T11:19:57.313Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1809.979 ms) ====== [2025-05-07T11:19:57.314Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-07T11:19:57.314Z] GC before operation: completed in 106.830 ms, heap usage 231.569 MB -> 68.566 MB. [2025-05-07T11:19:59.366Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1868.145 ms) ====== [2025-05-07T11:19:59.366Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-07T11:19:59.366Z] GC before operation: completed in 111.236 ms, heap usage 103.073 MB -> 68.432 MB. [2025-05-07T11:20:00.677Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1776.248 ms) ====== [2025-05-07T11:20:00.677Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-07T11:20:00.677Z] GC before operation: completed in 114.106 ms, heap usage 196.066 MB -> 68.861 MB. [2025-05-07T11:20:02.762Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1738.511 ms) ====== [2025-05-07T11:20:02.762Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-07T11:20:02.762Z] GC before operation: completed in 112.200 ms, heap usage 126.349 MB -> 68.961 MB. [2025-05-07T11:20:04.841Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1747.795 ms) ====== [2025-05-07T11:20:04.841Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-07T11:20:04.841Z] GC before operation: completed in 113.095 ms, heap usage 199.924 MB -> 69.100 MB. [2025-05-07T11:20:06.150Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1646.939 ms) ====== [2025-05-07T11:20:06.150Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-07T11:20:06.150Z] GC before operation: completed in 115.382 ms, heap usage 246.633 MB -> 69.205 MB. [2025-05-07T11:20:08.203Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1593.387 ms) ====== [2025-05-07T11:20:08.204Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-07T11:20:08.204Z] GC before operation: completed in 115.463 ms, heap usage 243.564 MB -> 69.316 MB. [2025-05-07T11:20:09.530Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1568.608 ms) ====== [2025-05-07T11:20:09.530Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-07T11:20:10.188Z] GC before operation: completed in 119.312 ms, heap usage 112.604 MB -> 69.111 MB. [2025-05-07T11:20:11.496Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1626.002 ms) ====== [2025-05-07T11:20:11.496Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-07T11:20:11.496Z] GC before operation: completed in 118.066 ms, heap usage 203.196 MB -> 69.545 MB. [2025-05-07T11:20:13.546Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1606.197 ms) ====== [2025-05-07T11:20:13.546Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-07T11:20:13.546Z] GC before operation: completed in 122.490 ms, heap usage 239.967 MB -> 69.720 MB. [2025-05-07T11:20:14.853Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1574.681 ms) ====== [2025-05-07T11:20:14.853Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-07T11:20:14.853Z] GC before operation: completed in 129.160 ms, heap usage 216.895 MB -> 69.828 MB. [2025-05-07T11:20:16.904Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1499.996 ms) ====== [2025-05-07T11:20:16.904Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-07T11:20:16.904Z] GC before operation: completed in 122.707 ms, heap usage 111.554 MB -> 69.604 MB. [2025-05-07T11:20:18.216Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1567.691 ms) ====== [2025-05-07T11:20:18.216Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-07T11:20:18.216Z] GC before operation: completed in 122.342 ms, heap usage 189.191 MB -> 70.036 MB. [2025-05-07T11:20:20.267Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1551.422 ms) ====== [2025-05-07T11:20:20.267Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-07T11:20:20.267Z] GC before operation: completed in 124.467 ms, heap usage 232.516 MB -> 70.144 MB. [2025-05-07T11:20:21.580Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1564.654 ms) ====== [2025-05-07T11:20:21.580Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-07T11:20:21.580Z] GC before operation: completed in 122.024 ms, heap usage 228.202 MB -> 70.254 MB. [2025-05-07T11:20:22.888Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1431.123 ms) ====== [2025-05-07T11:20:22.888Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-07T11:20:23.521Z] GC before operation: completed in 124.235 ms, heap usage 222.104 MB -> 70.357 MB. [2025-05-07T11:20:24.828Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1465.457 ms) ====== [2025-05-07T11:20:24.828Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-07T11:20:24.828Z] GC before operation: completed in 123.587 ms, heap usage 228.948 MB -> 70.458 MB. [2025-05-07T11:20:26.139Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1520.370 ms) ====== [2025-05-07T11:20:26.139Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-07T11:20:26.770Z] GC before operation: completed in 130.885 ms, heap usage 221.444 MB -> 70.399 MB. [2025-05-07T11:20:28.077Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1424.145 ms) ====== [2025-05-07T11:20:28.077Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-07T11:20:28.077Z] GC before operation: completed in 127.306 ms, heap usage 254.016 MB -> 70.647 MB. [2025-05-07T11:20:29.390Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1496.618 ms) ====== [2025-05-07T11:20:29.390Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-07T11:20:29.390Z] GC before operation: completed in 133.080 ms, heap usage 271.887 MB -> 98.201 MB. [2025-05-07T11:20:31.442Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1322.129 ms) ====== [2025-05-07T11:20:31.442Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-07T11:20:31.442Z] GC before operation: completed in 126.136 ms, heap usage 294.084 MB -> 98.310 MB. [2025-05-07T11:20:31.442Z] 11:20:30.793 WARN [block-manager-storage-async-thread-pool-70] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1293_0, which does not exist [2025-05-07T11:20:32.746Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1699.600 ms) ====== [2025-05-07T11:20:32.746Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-07T11:20:32.746Z] GC before operation: completed in 151.764 ms, heap usage 147.291 MB -> 70.202 MB. [2025-05-07T11:20:34.796Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1472.275 ms) ====== [2025-05-07T11:20:34.796Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-07T11:20:34.796Z] GC before operation: completed in 127.530 ms, heap usage 110.010 MB -> 70.618 MB. [2025-05-07T11:20:36.104Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1610.042 ms) ====== [2025-05-07T11:20:36.104Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-07T11:20:36.104Z] GC before operation: completed in 132.387 ms, heap usage 145.709 MB -> 70.838 MB. [2025-05-07T11:20:38.158Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1453.337 ms) ====== [2025-05-07T11:20:38.158Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-07T11:20:38.158Z] GC before operation: completed in 127.042 ms, heap usage 226.726 MB -> 71.133 MB. [2025-05-07T11:20:39.466Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1475.502 ms) ====== [2025-05-07T11:20:39.466Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-07T11:20:39.466Z] GC before operation: completed in 133.726 ms, heap usage 188.239 MB -> 71.250 MB. [2025-05-07T11:20:40.776Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1397.729 ms) ====== [2025-05-07T11:20:40.776Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-07T11:20:40.776Z] GC before operation: completed in 129.632 ms, heap usage 217.689 MB -> 71.349 MB. [2025-05-07T11:20:42.825Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1415.720 ms) ====== [2025-05-07T11:20:42.825Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-07T11:20:42.825Z] GC before operation: completed in 136.647 ms, heap usage 210.779 MB -> 71.459 MB. [2025-05-07T11:20:44.140Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1548.221 ms) ====== [2025-05-07T11:20:44.140Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-07T11:20:44.140Z] GC before operation: completed in 133.529 ms, heap usage 167.892 MB -> 71.710 MB. [2025-05-07T11:20:45.446Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1438.001 ms) ====== [2025-05-07T11:20:45.446Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-07T11:20:46.074Z] GC before operation: completed in 138.818 ms, heap usage 258.617 MB -> 72.070 MB. [2025-05-07T11:20:47.383Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1409.196 ms) ====== [2025-05-07T11:20:47.383Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-07T11:20:47.383Z] GC before operation: completed in 133.696 ms, heap usage 191.064 MB -> 71.897 MB. [2025-05-07T11:20:48.688Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1369.127 ms) ====== [2025-05-07T11:20:48.688Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-07T11:20:48.688Z] GC before operation: completed in 136.134 ms, heap usage 107.944 MB -> 71.531 MB. [2025-05-07T11:20:49.997Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1353.481 ms) ====== [2025-05-07T11:20:49.997Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-07T11:20:50.627Z] GC before operation: completed in 136.205 ms, heap usage 151.285 MB -> 71.945 MB. [2025-05-07T11:20:51.936Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1334.902 ms) ====== [2025-05-07T11:20:51.936Z] ----------------------------------- [2025-05-07T11:20:51.936Z] renaissance-dec-tree_0_PASSED [2025-05-07T11:20:51.936Z] ----------------------------------- [2025-05-07T11:20:51.936Z] [2025-05-07T11:20:51.936Z] TEST TEARDOWN: [2025-05-07T11:20:51.936Z] Nothing to be done for teardown. [2025-05-07T11:20:51.936Z] renaissance-dec-tree_0 Finish Time: Wed May 7 11:20:51 2025 Epoch Time (ms): 1746616851704