[2025-08-01T21:20:29.540Z] Running test renaissance-dec-tree_0 ... [2025-08-01T21:20:29.540Z] =============================================== [2025-08-01T21:20:29.540Z] renaissance-dec-tree_0 Start Time: Fri Aug 1 16:20:27 2025 Epoch Time (ms): 1754083227185 [2025-08-01T21:20:29.540Z] variation: NoOptions [2025-08-01T21:20:29.540Z] JVM_OPTIONS: [2025-08-01T21:20:29.540Z] { \ [2025-08-01T21:20:29.540Z] echo ""; echo "TEST SETUP:"; \ [2025-08-01T21:20:29.540Z] echo "Nothing to be done for setup."; \ [2025-08-01T21:20:29.540Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17540827195805/renaissance-dec-tree_0"; \ [2025-08-01T21:20:29.540Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17540827195805/renaissance-dec-tree_0"; \ [2025-08-01T21:20:29.540Z] echo ""; echo "TESTING:"; \ [2025-08-01T21:20:29.540Z] "/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_17540827195805/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-01T21:20:29.540Z] 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_17540827195805/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-01T21:20:29.540Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-01T21:20:29.540Z] echo "Nothing to be done for teardown."; \ [2025-08-01T21:20:29.540Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17540827195805/TestTargetResult"; [2025-08-01T21:20:29.540Z] [2025-08-01T21:20:29.540Z] TEST SETUP: [2025-08-01T21:20:29.540Z] Nothing to be done for setup. [2025-08-01T21:20:29.540Z] [2025-08-01T21:20:29.540Z] TESTING: [2025-08-01T21:20:35.651Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2025-08-01T21:20:37.025Z] WARNING: An illegal reflective access operation has occurred [2025-08-01T21:20:37.025Z] 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_17540827195805/renaissance-dec-tree_0/harness-162027-7727870970243689325/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-01T21:20:37.025Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-01T21:20:37.025Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-01T21:20:37.025Z] WARNING: All illegal access operations will be denied in a future release [2025-08-01T21:20:38.402Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-01T21:20:38.402Z] GC before operation: completed in 60.990 ms, heap usage 82.692 MB -> 34.895 MB. [2025-08-01T21:20:48.427Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9814.074 ms) ====== [2025-08-01T21:20:48.427Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-01T21:20:48.427Z] GC before operation: completed in 91.288 ms, heap usage 241.919 MB -> 68.831 MB. [2025-08-01T21:20:50.585Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2319.540 ms) ====== [2025-08-01T21:20:50.585Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-01T21:20:50.585Z] GC before operation: completed in 81.023 ms, heap usage 209.643 MB -> 74.766 MB. [2025-08-01T21:20:51.961Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1651.500 ms) ====== [2025-08-01T21:20:51.961Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-01T21:20:52.641Z] GC before operation: completed in 86.317 ms, heap usage 207.810 MB -> 85.979 MB. [2025-08-01T21:20:54.048Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1462.958 ms) ====== [2025-08-01T21:20:54.048Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-01T21:20:54.048Z] GC before operation: completed in 112.038 ms, heap usage 232.252 MB -> 80.766 MB. [2025-08-01T21:20:55.427Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1541.247 ms) ====== [2025-08-01T21:20:55.427Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-01T21:20:55.427Z] GC before operation: completed in 128.669 ms, heap usage 232.842 MB -> 70.054 MB. [2025-08-01T21:20:57.607Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1656.444 ms) ====== [2025-08-01T21:20:57.607Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-01T21:20:57.607Z] GC before operation: completed in 134.117 ms, heap usage 272.012 MB -> 92.280 MB. [2025-08-01T21:20:58.987Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1585.558 ms) ====== [2025-08-01T21:20:58.987Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-01T21:20:58.987Z] GC before operation: completed in 113.042 ms, heap usage 282.583 MB -> 70.546 MB. [2025-08-01T21:21:00.367Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1465.511 ms) ====== [2025-08-01T21:21:00.367Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-01T21:21:00.367Z] GC before operation: completed in 106.569 ms, heap usage 175.101 MB -> 92.639 MB. [2025-08-01T21:21:01.742Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1359.755 ms) ====== [2025-08-01T21:21:01.743Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-01T21:21:02.407Z] GC before operation: completed in 117.313 ms, heap usage 245.929 MB -> 76.108 MB. [2025-08-01T21:21:03.827Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1573.508 ms) ====== [2025-08-01T21:21:03.827Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-01T21:21:03.827Z] GC before operation: completed in 125.816 ms, heap usage 277.794 MB -> 87.122 MB. [2025-08-01T21:21:05.203Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1381.140 ms) ====== [2025-08-01T21:21:05.203Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-01T21:21:05.203Z] GC before operation: completed in 130.604 ms, heap usage 163.018 MB -> 70.459 MB. [2025-08-01T21:21:06.582Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1410.109 ms) ====== [2025-08-01T21:21:06.582Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-01T21:21:06.582Z] GC before operation: completed in 124.429 ms, heap usage 108.346 MB -> 81.317 MB. [2025-08-01T21:21:08.755Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1507.188 ms) ====== [2025-08-01T21:21:08.755Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-01T21:21:08.755Z] GC before operation: completed in 109.193 ms, heap usage 257.259 MB -> 81.917 MB. [2025-08-01T21:21:10.141Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1402.257 ms) ====== [2025-08-01T21:21:10.141Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-01T21:21:10.141Z] GC before operation: completed in 130.841 ms, heap usage 238.388 MB -> 71.139 MB. [2025-08-01T21:21:11.513Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1359.131 ms) ====== [2025-08-01T21:21:11.513Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-01T21:21:11.513Z] GC before operation: completed in 112.311 ms, heap usage 239.362 MB -> 71.411 MB. [2025-08-01T21:21:12.888Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1336.376 ms) ====== [2025-08-01T21:21:12.888Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-01T21:21:12.888Z] GC before operation: completed in 119.999 ms, heap usage 144.332 MB -> 71.244 MB. [2025-08-01T21:21:14.269Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1434.721 ms) ====== [2025-08-01T21:21:14.269Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-01T21:21:14.269Z] GC before operation: completed in 137.536 ms, heap usage 290.172 MB -> 71.676 MB. [2025-08-01T21:21:15.641Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1307.111 ms) ====== [2025-08-01T21:21:15.641Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-01T21:21:15.641Z] GC before operation: completed in 119.167 ms, heap usage 148.993 MB -> 82.518 MB. [2025-08-01T21:21:17.792Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1638.963 ms) ====== [2025-08-01T21:21:17.792Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-01T21:21:17.792Z] GC before operation: completed in 120.527 ms, heap usage 241.064 MB -> 71.928 MB. [2025-08-01T21:21:19.166Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1514.221 ms) ====== [2025-08-01T21:21:19.166Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-01T21:21:19.166Z] GC before operation: completed in 127.726 ms, heap usage 203.985 MB -> 83.106 MB. [2025-08-01T21:21:20.543Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1491.890 ms) ====== [2025-08-01T21:21:20.543Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-01T21:21:21.203Z] GC before operation: completed in 137.571 ms, heap usage 230.610 MB -> 82.845 MB. [2025-08-01T21:21:22.581Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1478.346 ms) ====== [2025-08-01T21:21:22.581Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-01T21:21:22.581Z] GC before operation: completed in 116.920 ms, heap usage 285.045 MB -> 99.654 MB. [2025-08-01T21:21:23.956Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1216.725 ms) ====== [2025-08-01T21:21:23.956Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-01T21:21:23.956Z] GC before operation: completed in 123.519 ms, heap usage 310.327 MB -> 83.465 MB. [2025-08-01T21:21:25.327Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1491.798 ms) ====== [2025-08-01T21:21:25.327Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-01T21:21:25.327Z] GC before operation: completed in 149.654 ms, heap usage 135.343 MB -> 72.006 MB. [2025-08-01T21:21:26.700Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1317.987 ms) ====== [2025-08-01T21:21:26.700Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-01T21:21:26.701Z] GC before operation: completed in 139.770 ms, heap usage 322.248 MB -> 88.867 MB. [2025-08-01T21:21:28.076Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1288.931 ms) ====== [2025-08-01T21:21:28.076Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-01T21:21:28.740Z] GC before operation: completed in 134.260 ms, heap usage 179.102 MB -> 99.756 MB. [2025-08-01T21:21:29.400Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1231.619 ms) ====== [2025-08-01T21:21:29.400Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-01T21:21:30.063Z] GC before operation: completed in 161.884 ms, heap usage 362.869 MB -> 89.298 MB. [2025-08-01T21:21:30.063Z] 16:21:27.404 WARN [block-manager-storage-async-thread-pool-25] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1293_1, which does not exist [2025-08-01T21:21:31.445Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1267.164 ms) ====== [2025-08-01T21:21:31.445Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-01T21:21:31.445Z] GC before operation: completed in 143.111 ms, heap usage 139.948 MB -> 83.292 MB. [2025-08-01T21:21:32.820Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1218.378 ms) ====== [2025-08-01T21:21:32.820Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-01T21:21:32.820Z] GC before operation: completed in 129.484 ms, heap usage 127.880 MB -> 83.565 MB. [2025-08-01T21:21:34.201Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1327.693 ms) ====== [2025-08-01T21:21:34.201Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-01T21:21:34.201Z] GC before operation: completed in 138.474 ms, heap usage 144.905 MB -> 83.880 MB. [2025-08-01T21:21:35.574Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1441.165 ms) ====== [2025-08-01T21:21:35.574Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-01T21:21:35.574Z] GC before operation: completed in 132.762 ms, heap usage 253.720 MB -> 89.732 MB. [2025-08-01T21:21:36.956Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1435.691 ms) ====== [2025-08-01T21:21:36.956Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-01T21:21:36.956Z] GC before operation: completed in 128.655 ms, heap usage 196.815 MB -> 89.327 MB. [2025-08-01T21:21:38.388Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1380.142 ms) ====== [2025-08-01T21:21:38.388Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-01T21:21:39.050Z] GC before operation: completed in 180.907 ms, heap usage 388.508 MB -> 89.924 MB. [2025-08-01T21:21:39.050Z] 16:21:36.329 WARN [block-manager-storage-async-thread-pool-33] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1581_2, which does not exist [2025-08-01T21:21:40.428Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1382.131 ms) ====== [2025-08-01T21:21:40.428Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-01T21:21:40.428Z] GC before operation: completed in 128.322 ms, heap usage 315.014 MB -> 89.899 MB. [2025-08-01T21:21:41.804Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1462.564 ms) ====== [2025-08-01T21:21:41.804Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-01T21:21:41.804Z] GC before operation: completed in 133.065 ms, heap usage 297.896 MB -> 89.772 MB. [2025-08-01T21:21:43.187Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1312.453 ms) ====== [2025-08-01T21:21:43.187Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-01T21:21:43.187Z] GC before operation: completed in 123.333 ms, heap usage 144.840 MB -> 95.373 MB. [2025-08-01T21:21:44.561Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1241.743 ms) ====== [2025-08-01T21:21:44.561Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-01T21:21:44.561Z] GC before operation: completed in 122.674 ms, heap usage 256.871 MB -> 90.311 MB. [2025-08-01T21:21:45.933Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1289.056 ms) ====== [2025-08-01T21:21:45.933Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-01T21:21:45.933Z] GC before operation: completed in 127.721 ms, heap usage 277.465 MB -> 95.722 MB. [2025-08-01T21:21:47.305Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1270.963 ms) ====== [2025-08-01T21:21:47.305Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-01T21:21:47.305Z] GC before operation: completed in 128.388 ms, heap usage 268.039 MB -> 101.278 MB. [2025-08-01T21:21:48.677Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1333.380 ms) ====== [2025-08-01T21:21:49.341Z] ----------------------------------- [2025-08-01T21:21:49.341Z] renaissance-dec-tree_0_PASSED [2025-08-01T21:21:49.341Z] ----------------------------------- [2025-08-01T21:21:49.341Z] [2025-08-01T21:21:49.341Z] TEST TEARDOWN: [2025-08-01T21:21:49.341Z] Nothing to be done for teardown. [2025-08-01T21:21:49.341Z] renaissance-dec-tree_0 Finish Time: Fri Aug 1 16:21:46 2025 Epoch Time (ms): 1754083306880