[2026-01-18T14:24:51.829Z] Running test renaissance-dec-tree_0 ... [2026-01-18T14:24:51.829Z] =============================================== [2026-01-18T14:24:51.829Z] renaissance-dec-tree_0 Start Time: Sun Jan 18 09:24:50 2026 Epoch Time (ms): 1768746290703 [2026-01-18T14:24:51.829Z] variation: NoOptions [2026-01-18T14:24:51.829Z] JVM_OPTIONS: [2026-01-18T14:24:51.829Z] { \ [2026-01-18T14:24:51.829Z] echo ""; echo "TEST SETUP:"; \ [2026-01-18T14:24:51.829Z] echo "Nothing to be done for setup."; \ [2026-01-18T14:24:51.829Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/../TKG/output_1768745760253/renaissance-dec-tree_0"; \ [2026-01-18T14:24:51.829Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/../TKG/output_1768745760253/renaissance-dec-tree_0"; \ [2026-01-18T14:24:51.829Z] echo ""; echo "TESTING:"; \ [2026-01-18T14:24:51.829Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/../TKG/output_1768745760253/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-01-18T14:24:51.830Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/../TKG/output_1768745760253/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-18T14:24:51.830Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-18T14:24:51.830Z] echo "Nothing to be done for teardown."; \ [2026-01-18T14:24:51.830Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/../TKG/output_1768745760253/TestTargetResult"; [2026-01-18T14:24:51.830Z] [2026-01-18T14:24:51.830Z] TEST SETUP: [2026-01-18T14:24:51.830Z] Nothing to be done for setup. [2026-01-18T14:24:51.830Z] [2026-01-18T14:24:51.830Z] TESTING: [2026-01-18T14:25:09.363Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-18T14:25:13.198Z] WARNING: An illegal reflective access operation has occurred [2026-01-18T14:25:13.198Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1_rerun/aqa-tests/TKG/output_1768745760253/renaissance-dec-tree_0/harness-092452-12704560892639966033/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-18T14:25:13.198Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-18T14:25:13.198Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-18T14:25:13.198Z] WARNING: All illegal access operations will be denied in a future release [2026-01-18T14:25:17.192Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-01-18T14:25:17.192Z] GC before operation: completed in 146.097 ms, heap usage 140.226 MB -> 34.637 MB. [2026-01-18T14:25:47.583Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (27658.760 ms) ====== [2026-01-18T14:25:47.583Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-01-18T14:25:47.583Z] GC before operation: completed in 367.454 ms, heap usage 381.975 MB -> 68.482 MB. [2026-01-18T14:25:53.552Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (8087.100 ms) ====== [2026-01-18T14:25:53.552Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-01-18T14:25:53.552Z] GC before operation: completed in 166.389 ms, heap usage 159.107 MB -> 68.934 MB. [2026-01-18T14:26:00.476Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6622.520 ms) ====== [2026-01-18T14:26:00.476Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-01-18T14:26:00.476Z] GC before operation: completed in 295.396 ms, heap usage 99.807 MB -> 69.119 MB. [2026-01-18T14:26:06.492Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5517.741 ms) ====== [2026-01-18T14:26:06.492Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-01-18T14:26:06.492Z] GC before operation: completed in 222.329 ms, heap usage 239.123 MB -> 69.437 MB. [2026-01-18T14:26:12.291Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5744.940 ms) ====== [2026-01-18T14:26:12.291Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-01-18T14:26:12.291Z] GC before operation: completed in 166.261 ms, heap usage 220.627 MB -> 69.645 MB. [2026-01-18T14:26:16.094Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4134.916 ms) ====== [2026-01-18T14:26:16.094Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-01-18T14:26:16.550Z] GC before operation: completed in 156.619 ms, heap usage 238.253 MB -> 97.468 MB. [2026-01-18T14:26:21.435Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4265.670 ms) ====== [2026-01-18T14:26:21.435Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-01-18T14:26:21.435Z] GC before operation: completed in 243.540 ms, heap usage 405.693 MB -> 70.324 MB. [2026-01-18T14:26:26.134Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4473.570 ms) ====== [2026-01-18T14:26:26.134Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-01-18T14:26:26.134Z] GC before operation: completed in 228.289 ms, heap usage 223.069 MB -> 70.174 MB. [2026-01-18T14:26:30.896Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4536.817 ms) ====== [2026-01-18T14:26:30.896Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-01-18T14:26:30.896Z] GC before operation: completed in 251.907 ms, heap usage 258.256 MB -> 70.114 MB. [2026-01-18T14:26:35.567Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4809.249 ms) ====== [2026-01-18T14:26:35.567Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-01-18T14:26:35.567Z] GC before operation: completed in 195.139 ms, heap usage 317.490 MB -> 70.224 MB. [2026-01-18T14:26:40.366Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (4465.919 ms) ====== [2026-01-18T14:26:40.366Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-01-18T14:26:40.366Z] GC before operation: completed in 175.374 ms, heap usage 320.111 MB -> 97.943 MB. [2026-01-18T14:26:44.172Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4025.156 ms) ====== [2026-01-18T14:26:44.173Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-01-18T14:26:44.668Z] GC before operation: completed in 312.503 ms, heap usage 416.858 MB -> 98.111 MB. [2026-01-18T14:26:44.668Z] 09:26:44.108 WARN [block-manager-storage-async-thread-pool-89] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_163_piece0, which does not exist [2026-01-18T14:26:44.668Z] 09:26:44.113 WARN [block-manager-storage-async-thread-pool-89] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_163, which does not exist [2026-01-18T14:26:49.379Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4252.934 ms) ====== [2026-01-18T14:26:49.379Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-01-18T14:26:49.379Z] GC before operation: completed in 251.970 ms, heap usage 187.286 MB -> 70.276 MB. [2026-01-18T14:26:53.091Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3670.082 ms) ====== [2026-01-18T14:26:53.091Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-01-18T14:26:53.091Z] GC before operation: completed in 432.980 ms, heap usage 195.208 MB -> 70.416 MB. [2026-01-18T14:26:56.981Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4113.733 ms) ====== [2026-01-18T14:26:56.981Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-01-18T14:26:57.958Z] GC before operation: completed in 606.841 ms, heap usage 162.634 MB -> 70.912 MB. [2026-01-18T14:27:01.696Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3594.402 ms) ====== [2026-01-18T14:27:01.696Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-01-18T14:27:01.696Z] GC before operation: completed in 182.980 ms, heap usage 125.654 MB -> 70.516 MB. [2026-01-18T14:27:04.924Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3276.105 ms) ====== [2026-01-18T14:27:04.924Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-01-18T14:27:05.402Z] GC before operation: completed in 540.889 ms, heap usage 301.311 MB -> 71.472 MB. [2026-01-18T14:27:09.130Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3619.035 ms) ====== [2026-01-18T14:27:09.130Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-01-18T14:27:09.130Z] GC before operation: completed in 237.883 ms, heap usage 264.678 MB -> 71.615 MB. [2026-01-18T14:27:12.875Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3752.332 ms) ====== [2026-01-18T14:27:12.875Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-01-18T14:27:13.291Z] GC before operation: completed in 274.531 ms, heap usage 297.056 MB -> 93.285 MB. [2026-01-18T14:27:13.291Z] 09:27:12.948 WARN [block-manager-storage-async-thread-pool-77] org.apache.spark.storage.BlockManager - Asked to remove block rdd_909_1, which does not exist [2026-01-18T14:27:16.991Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3272.554 ms) ====== [2026-01-18T14:27:16.991Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-01-18T14:27:16.991Z] GC before operation: completed in 614.440 ms, heap usage 485.858 MB -> 71.451 MB. [2026-01-18T14:27:20.760Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3807.689 ms) ====== [2026-01-18T14:27:20.760Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-01-18T14:27:21.214Z] GC before operation: completed in 197.462 ms, heap usage 265.305 MB -> 71.955 MB. [2026-01-18T14:27:24.922Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3420.725 ms) ====== [2026-01-18T14:27:24.923Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-01-18T14:27:25.420Z] GC before operation: completed in 947.835 ms, heap usage 174.609 MB -> 71.727 MB. [2026-01-18T14:27:29.182Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3208.276 ms) ====== [2026-01-18T14:27:29.182Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-01-18T14:27:29.182Z] GC before operation: completed in 272.247 ms, heap usage 348.858 MB -> 71.738 MB. [2026-01-18T14:27:33.981Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4422.298 ms) ====== [2026-01-18T14:27:33.981Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-01-18T14:27:33.981Z] GC before operation: completed in 390.714 ms, heap usage 418.404 MB -> 71.840 MB. [2026-01-18T14:27:36.729Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3094.877 ms) ====== [2026-01-18T14:27:36.729Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-01-18T14:27:37.143Z] GC before operation: completed in 203.001 ms, heap usage 207.908 MB -> 72.096 MB. [2026-01-18T14:27:41.823Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4458.845 ms) ====== [2026-01-18T14:27:41.823Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-01-18T14:27:41.823Z] GC before operation: completed in 317.525 ms, heap usage 363.345 MB -> 72.050 MB. [2026-01-18T14:27:45.580Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3171.144 ms) ====== [2026-01-18T14:27:45.580Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-01-18T14:27:45.580Z] GC before operation: completed in 207.711 ms, heap usage 230.120 MB -> 99.761 MB. [2026-01-18T14:27:49.654Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3887.844 ms) ====== [2026-01-18T14:27:49.654Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-01-18T14:27:49.654Z] GC before operation: completed in 274.668 ms, heap usage 462.818 MB -> 88.976 MB. [2026-01-18T14:27:52.589Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3180.978 ms) ====== [2026-01-18T14:27:52.589Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-01-18T14:27:53.549Z] GC before operation: completed in 812.514 ms, heap usage 407.447 MB -> 72.521 MB. [2026-01-18T14:27:57.272Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3677.473 ms) ====== [2026-01-18T14:27:57.272Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-01-18T14:27:57.272Z] GC before operation: completed in 272.569 ms, heap usage 356.661 MB -> 72.698 MB. [2026-01-18T14:28:01.093Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3918.529 ms) ====== [2026-01-18T14:28:01.093Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-01-18T14:28:01.506Z] GC before operation: completed in 238.071 ms, heap usage 232.238 MB -> 72.737 MB. [2026-01-18T14:28:05.406Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3537.977 ms) ====== [2026-01-18T14:28:05.406Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-01-18T14:28:05.406Z] GC before operation: completed in 386.793 ms, heap usage 162.607 MB -> 72.367 MB. [2026-01-18T14:28:10.084Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (4000.021 ms) ====== [2026-01-18T14:28:10.084Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-01-18T14:28:10.084Z] GC before operation: completed in 791.867 ms, heap usage 380.072 MB -> 89.478 MB. [2026-01-18T14:28:13.021Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2970.489 ms) ====== [2026-01-18T14:28:13.021Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-01-18T14:28:13.513Z] GC before operation: completed in 286.973 ms, heap usage 188.350 MB -> 72.732 MB. [2026-01-18T14:28:18.132Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3931.503 ms) ====== [2026-01-18T14:28:18.132Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-01-18T14:28:18.132Z] GC before operation: completed in 459.683 ms, heap usage 278.273 MB -> 73.165 MB. [2026-01-18T14:28:21.027Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2854.559 ms) ====== [2026-01-18T14:28:21.027Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-01-18T14:28:21.027Z] GC before operation: completed in 231.649 ms, heap usage 347.257 MB -> 100.728 MB. [2026-01-18T14:28:24.723Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3464.237 ms) ====== [2026-01-18T14:28:24.723Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-01-18T14:28:24.723Z] GC before operation: completed in 349.188 ms, heap usage 420.319 MB -> 73.372 MB. [2026-01-18T14:28:28.755Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3443.350 ms) ====== [2026-01-18T14:28:28.755Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-01-18T14:28:28.755Z] GC before operation: completed in 251.930 ms, heap usage 244.938 MB -> 73.475 MB. [2026-01-18T14:28:31.713Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3350.197 ms) ====== [2026-01-18T14:28:31.713Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-01-18T14:28:32.107Z] GC before operation: completed in 215.958 ms, heap usage 206.704 MB -> 101.048 MB. [2026-01-18T14:28:35.885Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4016.233 ms) ====== [2026-01-18T14:28:36.745Z] ----------------------------------- [2026-01-18T14:28:36.745Z] renaissance-dec-tree_0_PASSED [2026-01-18T14:28:36.745Z] ----------------------------------- [2026-01-18T14:28:37.158Z] [2026-01-18T14:28:37.158Z] TEST TEARDOWN: [2026-01-18T14:28:37.158Z] Nothing to be done for teardown. [2026-01-18T14:28:37.158Z] renaissance-dec-tree_0 Finish Time: Sun Jan 18 09:28:36 2026 Epoch Time (ms): 1768746516697