[2026-01-21T21:42:59.257Z] Running test renaissance-dec-tree_0 ... [2026-01-21T21:42:59.257Z] =============================================== [2026-01-21T21:42:59.257Z] renaissance-dec-tree_0 Start Time: Wed Jan 21 16:42:59 2026 Epoch Time (ms): 1769031779108 [2026-01-21T21:42:59.257Z] variation: NoOptions [2026-01-21T21:42:59.784Z] JVM_OPTIONS: [2026-01-21T21:42:59.784Z] { \ [2026-01-21T21:42:59.784Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T21:42:59.784Z] echo "Nothing to be done for setup."; \ [2026-01-21T21:42:59.784Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-dec-tree_0"; \ [2026-01-21T21:42:59.784Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-dec-tree_0"; \ [2026-01-21T21:42:59.784Z] echo ""; echo "TESTING:"; \ [2026-01-21T21:42:59.784Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/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_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2026-01-21T21:42:59.785Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T21:42:59.785Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T21:42:59.785Z] echo "Nothing to be done for teardown."; \ [2026-01-21T21:42:59.785Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/TestTargetResult"; [2026-01-21T21:42:59.785Z] [2026-01-21T21:42:59.785Z] TEST SETUP: [2026-01-21T21:42:59.785Z] Nothing to be done for setup. [2026-01-21T21:42:59.785Z] [2026-01-21T21:42:59.785Z] TESTING: [2026-01-21T21:43:25.810Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-21T21:43:35.227Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2026-01-21T21:43:35.685Z] GC before operation: completed in 308.738 ms, heap usage 46.118 MB -> 34.135 MB. [2026-01-21T21:44:19.590Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (38863.135 ms) ====== [2026-01-21T21:44:19.590Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2026-01-21T21:44:19.590Z] GC before operation: completed in 1003.369 ms, heap usage 392.781 MB -> 70.323 MB. [2026-01-21T21:44:27.881Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (12197.865 ms) ====== [2026-01-21T21:44:27.881Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2026-01-21T21:44:27.881Z] GC before operation: completed in 307.019 ms, heap usage 267.294 MB -> 70.651 MB. [2026-01-21T21:44:37.771Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (9300.353 ms) ====== [2026-01-21T21:44:37.771Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2026-01-21T21:44:37.771Z] GC before operation: completed in 501.518 ms, heap usage 242.696 MB -> 71.184 MB. [2026-01-21T21:44:47.275Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (9209.573 ms) ====== [2026-01-21T21:44:47.275Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2026-01-21T21:44:47.726Z] GC before operation: completed in 566.820 ms, heap usage 289.684 MB -> 71.370 MB. [2026-01-21T21:44:53.876Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (6117.741 ms) ====== [2026-01-21T21:44:53.876Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2026-01-21T21:44:53.876Z] GC before operation: completed in 280.546 ms, heap usage 116.531 MB -> 71.286 MB. [2026-01-21T21:45:01.849Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (7175.946 ms) ====== [2026-01-21T21:45:01.849Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2026-01-21T21:45:01.849Z] GC before operation: completed in 325.926 ms, heap usage 315.584 MB -> 71.544 MB. [2026-01-21T21:45:09.827Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (7266.818 ms) ====== [2026-01-21T21:45:09.827Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2026-01-21T21:45:09.827Z] GC before operation: completed in 327.399 ms, heap usage 438.036 MB -> 71.967 MB. [2026-01-21T21:45:15.364Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (6475.056 ms) ====== [2026-01-21T21:45:15.364Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2026-01-21T21:45:15.891Z] GC before operation: completed in 314.436 ms, heap usage 396.574 MB -> 71.812 MB. [2026-01-21T21:45:25.134Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (8128.623 ms) ====== [2026-01-21T21:45:25.134Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2026-01-21T21:45:25.134Z] GC before operation: completed in 387.365 ms, heap usage 129.369 MB -> 71.324 MB. [2026-01-21T21:45:31.939Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (7118.879 ms) ====== [2026-01-21T21:45:31.939Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2026-01-21T21:45:33.032Z] GC before operation: completed in 1271.572 ms, heap usage 114.764 MB -> 72.324 MB. [2026-01-21T21:45:40.822Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (7312.430 ms) ====== [2026-01-21T21:45:40.822Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2026-01-21T21:45:40.822Z] GC before operation: completed in 480.695 ms, heap usage 246.724 MB -> 72.388 MB. [2026-01-21T21:45:47.008Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (6195.837 ms) ====== [2026-01-21T21:45:47.008Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2026-01-21T21:45:47.008Z] GC before operation: completed in 326.992 ms, heap usage 239.358 MB -> 99.893 MB. [2026-01-21T21:45:54.975Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (7166.035 ms) ====== [2026-01-21T21:45:54.975Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2026-01-21T21:45:54.975Z] GC before operation: completed in 294.845 ms, heap usage 132.229 MB -> 72.749 MB. [2026-01-21T21:46:03.000Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (7335.838 ms) ====== [2026-01-21T21:46:03.000Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2026-01-21T21:46:03.000Z] GC before operation: completed in 308.600 ms, heap usage 356.570 MB -> 72.495 MB. [2026-01-21T21:46:09.721Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (6745.091 ms) ====== [2026-01-21T21:46:09.721Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2026-01-21T21:46:09.721Z] GC before operation: completed in 404.254 ms, heap usage 312.828 MB -> 100.208 MB. [2026-01-21T21:46:17.585Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (7631.398 ms) ====== [2026-01-21T21:46:17.585Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2026-01-21T21:46:17.585Z] GC before operation: completed in 290.759 ms, heap usage 193.778 MB -> 72.426 MB. [2026-01-21T21:46:22.987Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (5708.583 ms) ====== [2026-01-21T21:46:22.987Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2026-01-21T21:46:23.567Z] GC before operation: completed in 221.632 ms, heap usage 428.777 MB -> 72.843 MB. [2026-01-21T21:46:28.703Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (5175.852 ms) ====== [2026-01-21T21:46:28.703Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2026-01-21T21:46:28.703Z] GC before operation: completed in 340.161 ms, heap usage 296.904 MB -> 72.951 MB. [2026-01-21T21:46:34.912Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (5342.172 ms) ====== [2026-01-21T21:46:34.912Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2026-01-21T21:46:34.912Z] GC before operation: completed in 243.501 ms, heap usage 464.671 MB -> 73.151 MB. [2026-01-21T21:46:41.143Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (6204.428 ms) ====== [2026-01-21T21:46:41.143Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2026-01-21T21:46:41.143Z] GC before operation: completed in 309.487 ms, heap usage 487.505 MB -> 73.303 MB. [2026-01-21T21:46:47.218Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (5891.856 ms) ====== [2026-01-21T21:46:47.218Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2026-01-21T21:46:47.218Z] GC before operation: completed in 267.241 ms, heap usage 184.437 MB -> 73.422 MB. [2026-01-21T21:46:53.663Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (5993.117 ms) ====== [2026-01-21T21:46:53.663Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2026-01-21T21:46:53.663Z] GC before operation: completed in 239.021 ms, heap usage 384.875 MB -> 101.106 MB. [2026-01-21T21:46:59.974Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (5881.131 ms) ====== [2026-01-21T21:46:59.974Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2026-01-21T21:46:59.974Z] GC before operation: completed in 260.179 ms, heap usage 182.829 MB -> 73.288 MB. [2026-01-21T21:47:05.106Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (5875.854 ms) ====== [2026-01-21T21:47:05.106Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2026-01-21T21:47:05.696Z] GC before operation: completed in 231.718 ms, heap usage 298.257 MB -> 74.100 MB. [2026-01-21T21:47:12.288Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (6283.216 ms) ====== [2026-01-21T21:47:12.288Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2026-01-21T21:47:12.840Z] GC before operation: completed in 1101.126 ms, heap usage 177.446 MB -> 73.662 MB. [2026-01-21T21:47:19.252Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (6408.532 ms) ====== [2026-01-21T21:47:19.252Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2026-01-21T21:47:19.757Z] GC before operation: completed in 224.334 ms, heap usage 245.002 MB -> 73.938 MB. [2026-01-21T21:47:25.296Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (5833.246 ms) ====== [2026-01-21T21:47:25.296Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2026-01-21T21:47:25.758Z] GC before operation: completed in 375.644 ms, heap usage 254.867 MB -> 74.189 MB. [2026-01-21T21:47:30.943Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (5017.001 ms) ====== [2026-01-21T21:47:30.943Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2026-01-21T21:47:30.943Z] GC before operation: completed in 259.440 ms, heap usage 177.391 MB -> 101.609 MB. [2026-01-21T21:47:38.723Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (6809.633 ms) ====== [2026-01-21T21:47:38.723Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2026-01-21T21:47:39.231Z] GC before operation: completed in 1146.967 ms, heap usage 628.538 MB -> 102.108 MB. [2026-01-21T21:47:39.231Z] 16:47:38.828 WARN [block-manager-storage-async-thread-pool-124] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_401, which does not exist [2026-01-21T21:47:45.664Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (6175.587 ms) ====== [2026-01-21T21:47:45.664Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2026-01-21T21:47:45.664Z] GC before operation: completed in 296.848 ms, heap usage 271.439 MB -> 74.412 MB. [2026-01-21T21:47:51.392Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (5547.594 ms) ====== [2026-01-21T21:47:51.392Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2026-01-21T21:47:51.392Z] GC before operation: completed in 242.472 ms, heap usage 464.063 MB -> 102.108 MB. [2026-01-21T21:47:57.591Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (5989.585 ms) ====== [2026-01-21T21:47:57.591Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2026-01-21T21:47:57.591Z] GC before operation: completed in 296.298 ms, heap usage 289.701 MB -> 74.771 MB. [2026-01-21T21:48:04.033Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (5875.578 ms) ====== [2026-01-21T21:48:04.033Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2026-01-21T21:48:04.033Z] GC before operation: completed in 384.726 ms, heap usage 309.475 MB -> 102.458 MB. [2026-01-21T21:48:10.843Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (7019.079 ms) ====== [2026-01-21T21:48:10.843Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2026-01-21T21:48:12.075Z] GC before operation: completed in 991.727 ms, heap usage 387.177 MB -> 74.812 MB. [2026-01-21T21:48:18.452Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (5635.839 ms) ====== [2026-01-21T21:48:18.452Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2026-01-21T21:48:18.452Z] GC before operation: completed in 291.666 ms, heap usage 361.572 MB -> 74.802 MB. [2026-01-21T21:48:23.575Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (5370.122 ms) ====== [2026-01-21T21:48:23.575Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2026-01-21T21:48:23.575Z] GC before operation: completed in 254.194 ms, heap usage 240.568 MB -> 75.036 MB. [2026-01-21T21:48:30.053Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (5888.250 ms) ====== [2026-01-21T21:48:30.053Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2026-01-21T21:48:30.053Z] GC before operation: completed in 257.201 ms, heap usage 112.009 MB -> 101.949 MB. [2026-01-21T21:48:36.387Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (5944.499 ms) ====== [2026-01-21T21:48:36.387Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2026-01-21T21:48:36.387Z] GC before operation: completed in 332.125 ms, heap usage 731.771 MB -> 75.373 MB. [2026-01-21T21:48:40.524Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4699.721 ms) ====== [2026-01-21T21:48:40.524Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2026-01-21T21:48:41.619Z] GC before operation: completed in 971.049 ms, heap usage 373.807 MB -> 75.349 MB. [2026-01-21T21:48:46.578Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (5314.712 ms) ====== [2026-01-21T21:48:49.077Z] ----------------------------------- [2026-01-21T21:48:49.077Z] renaissance-dec-tree_0_PASSED [2026-01-21T21:48:49.077Z] ----------------------------------- [2026-01-21T21:48:49.077Z] [2026-01-21T21:48:49.077Z] TEST TEARDOWN: [2026-01-21T21:48:49.077Z] Nothing to be done for teardown. [2026-01-21T21:48:49.491Z] renaissance-dec-tree_0 Finish Time: Wed Jan 21 16:48:49 2026 Epoch Time (ms): 1769032129019