[2025-09-03T21:32:11.445Z] Running test renaissance-dec-tree_0 ... [2025-09-03T21:32:11.445Z] =============================================== [2025-09-03T21:32:11.907Z] renaissance-dec-tree_0 Start Time: Wed Sep 3 14:32:11 2025 Epoch Time (ms): 1756935131393 [2025-09-03T21:32:11.907Z] variation: NoOptions [2025-09-03T21:32:11.907Z] JVM_OPTIONS: [2025-09-03T21:32:11.907Z] { \ [2025-09-03T21:32:11.907Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T21:32:11.907Z] echo "Nothing to be done for setup."; \ [2025-09-03T21:32:11.907Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-dec-tree_0"; \ [2025-09-03T21:32:11.907Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-dec-tree_0"; \ [2025-09-03T21:32:11.907Z] echo ""; echo "TESTING:"; \ [2025-09-03T21:32:11.907Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/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_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-09-03T21:32:11.907Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T21:32:11.907Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T21:32:11.907Z] echo "Nothing to be done for teardown."; \ [2025-09-03T21:32:11.907Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/TestTargetResult"; [2025-09-03T21:32:11.907Z] [2025-09-03T21:32:11.907Z] TEST SETUP: [2025-09-03T21:32:11.907Z] Nothing to be done for setup. [2025-09-03T21:32:11.907Z] [2025-09-03T21:32:11.907Z] TESTING: [2025-09-03T21:32:30.011Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-09-03T21:32:37.080Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-09-03T21:32:37.080Z] GC before operation: completed in 151.353 ms, heap usage 80.692 MB -> 34.795 MB. [2025-09-03T21:33:07.955Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (30065.350 ms) ====== [2025-09-03T21:33:07.955Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-09-03T21:33:07.955Z] GC before operation: completed in 231.240 ms, heap usage 273.802 MB -> 96.287 MB. [2025-09-03T21:33:15.657Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7890.126 ms) ====== [2025-09-03T21:33:15.657Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-09-03T21:33:15.657Z] GC before operation: completed in 269.508 ms, heap usage 270.179 MB -> 69.117 MB. [2025-09-03T21:33:22.205Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6534.424 ms) ====== [2025-09-03T21:33:22.205Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-09-03T21:33:22.205Z] GC before operation: completed in 202.638 ms, heap usage 196.126 MB -> 69.075 MB. [2025-09-03T21:33:28.318Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (6486.615 ms) ====== [2025-09-03T21:33:28.318Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-09-03T21:33:28.318Z] GC before operation: completed in 257.220 ms, heap usage 119.066 MB -> 96.778 MB. [2025-09-03T21:33:35.096Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (6852.597 ms) ====== [2025-09-03T21:33:35.096Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-09-03T21:33:35.622Z] GC before operation: completed in 216.823 ms, heap usage 455.522 MB -> 69.810 MB. [2025-09-03T21:33:42.075Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (5837.429 ms) ====== [2025-09-03T21:33:42.076Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-09-03T21:33:42.076Z] GC before operation: completed in 337.534 ms, heap usage 327.381 MB -> 70.163 MB. [2025-09-03T21:33:48.230Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5618.670 ms) ====== [2025-09-03T21:33:48.230Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-09-03T21:33:48.230Z] GC before operation: completed in 208.451 ms, heap usage 178.589 MB -> 70.094 MB. [2025-09-03T21:33:53.591Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (5662.611 ms) ====== [2025-09-03T21:33:53.591Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-09-03T21:33:53.591Z] GC before operation: completed in 179.059 ms, heap usage 282.028 MB -> 70.526 MB. [2025-09-03T21:33:58.975Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5404.376 ms) ====== [2025-09-03T21:33:58.975Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-09-03T21:33:58.975Z] GC before operation: completed in 189.152 ms, heap usage 336.644 MB -> 70.904 MB. [2025-09-03T21:34:04.115Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (5035.612 ms) ====== [2025-09-03T21:34:04.115Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-09-03T21:34:04.599Z] GC before operation: completed in 337.420 ms, heap usage 176.575 MB -> 98.097 MB. [2025-09-03T21:34:10.866Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (6161.672 ms) ====== [2025-09-03T21:34:10.866Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-09-03T21:34:10.866Z] GC before operation: completed in 438.961 ms, heap usage 414.538 MB -> 71.115 MB. [2025-09-03T21:34:16.853Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (5163.290 ms) ====== [2025-09-03T21:34:16.853Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-09-03T21:34:16.853Z] GC before operation: completed in 398.477 ms, heap usage 225.959 MB -> 70.867 MB. [2025-09-03T21:34:21.250Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4680.723 ms) ====== [2025-09-03T21:34:21.250Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-09-03T21:34:21.250Z] GC before operation: completed in 274.764 ms, heap usage 243.458 MB -> 98.977 MB. [2025-09-03T21:34:21.694Z] 14:34:21.092 WARN [block-manager-storage-async-thread-pool-57] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_177_piece0, which does not exist [2025-09-03T21:34:28.103Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (5734.266 ms) ====== [2025-09-03T21:34:28.104Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-09-03T21:34:28.104Z] GC before operation: completed in 311.376 ms, heap usage 188.361 MB -> 73.364 MB. [2025-09-03T21:34:33.522Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (5300.858 ms) ====== [2025-09-03T21:34:33.522Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-09-03T21:34:33.522Z] GC before operation: completed in 321.012 ms, heap usage 150.336 MB -> 70.979 MB. [2025-09-03T21:34:38.819Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (5614.546 ms) ====== [2025-09-03T21:34:38.819Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-09-03T21:34:39.309Z] GC before operation: completed in 310.829 ms, heap usage 254.355 MB -> 71.801 MB. [2025-09-03T21:34:44.329Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4549.707 ms) ====== [2025-09-03T21:34:44.329Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-09-03T21:34:44.329Z] GC before operation: completed in 246.342 ms, heap usage 218.885 MB -> 71.705 MB. [2025-09-03T21:34:48.612Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4749.042 ms) ====== [2025-09-03T21:34:48.612Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-09-03T21:34:49.138Z] GC before operation: completed in 229.828 ms, heap usage 371.765 MB -> 71.891 MB. [2025-09-03T21:34:52.890Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4185.957 ms) ====== [2025-09-03T21:34:52.890Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-09-03T21:34:53.389Z] GC before operation: completed in 290.267 ms, heap usage 276.570 MB -> 72.278 MB. [2025-09-03T21:34:58.648Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4688.255 ms) ====== [2025-09-03T21:34:58.648Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-09-03T21:34:58.648Z] GC before operation: completed in 281.834 ms, heap usage 102.896 MB -> 99.637 MB. [2025-09-03T21:35:04.579Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (5579.980 ms) ====== [2025-09-03T21:35:04.579Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-09-03T21:35:04.579Z] GC before operation: completed in 277.377 ms, heap usage 639.810 MB -> 99.901 MB. [2025-09-03T21:35:09.442Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (5149.753 ms) ====== [2025-09-03T21:35:09.442Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-09-03T21:35:09.442Z] GC before operation: completed in 218.678 ms, heap usage 170.826 MB -> 72.213 MB. [2025-09-03T21:35:15.372Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (5025.525 ms) ====== [2025-09-03T21:35:15.372Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-09-03T21:35:15.372Z] GC before operation: completed in 156.635 ms, heap usage 149.244 MB -> 72.030 MB. [2025-09-03T21:35:19.263Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3827.931 ms) ====== [2025-09-03T21:35:19.264Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-09-03T21:35:19.264Z] GC before operation: completed in 377.561 ms, heap usage 341.257 MB -> 100.069 MB. [2025-09-03T21:35:24.250Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4927.682 ms) ====== [2025-09-03T21:35:24.250Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-09-03T21:35:24.250Z] GC before operation: completed in 217.666 ms, heap usage 401.940 MB -> 100.166 MB. [2025-09-03T21:35:28.204Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4223.279 ms) ====== [2025-09-03T21:35:28.205Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-09-03T21:35:28.642Z] GC before operation: completed in 251.172 ms, heap usage 436.271 MB -> 72.760 MB. [2025-09-03T21:35:33.565Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4717.424 ms) ====== [2025-09-03T21:35:33.565Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-09-03T21:35:33.565Z] GC before operation: completed in 316.964 ms, heap usage 233.389 MB -> 100.596 MB. [2025-09-03T21:35:37.353Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3867.093 ms) ====== [2025-09-03T21:35:37.353Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-09-03T21:35:37.837Z] GC before operation: completed in 253.285 ms, heap usage 535.913 MB -> 85.743 MB. [2025-09-03T21:35:41.826Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3838.992 ms) ====== [2025-09-03T21:35:41.826Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-09-03T21:35:41.826Z] GC before operation: completed in 310.521 ms, heap usage 274.090 MB -> 98.841 MB. [2025-09-03T21:35:46.518Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (4052.082 ms) ====== [2025-09-03T21:35:46.518Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-09-03T21:35:46.518Z] GC before operation: completed in 357.303 ms, heap usage 616.685 MB -> 73.641 MB. [2025-09-03T21:35:50.473Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4104.743 ms) ====== [2025-09-03T21:35:50.473Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-09-03T21:35:50.473Z] GC before operation: completed in 248.860 ms, heap usage 192.367 MB -> 73.220 MB. [2025-09-03T21:35:55.479Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4769.951 ms) ====== [2025-09-03T21:35:55.479Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-09-03T21:35:55.479Z] GC before operation: completed in 295.306 ms, heap usage 160.862 MB -> 73.360 MB. [2025-09-03T21:35:59.521Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3776.582 ms) ====== [2025-09-03T21:35:59.521Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-09-03T21:35:59.986Z] GC before operation: completed in 323.001 ms, heap usage 537.388 MB -> 73.599 MB. [2025-09-03T21:36:04.526Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4662.788 ms) ====== [2025-09-03T21:36:04.526Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-09-03T21:36:04.989Z] GC before operation: completed in 352.466 ms, heap usage 222.532 MB -> 73.471 MB. [2025-09-03T21:36:09.909Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (4399.328 ms) ====== [2025-09-03T21:36:09.909Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-09-03T21:36:09.909Z] GC before operation: completed in 262.887 ms, heap usage 218.777 MB -> 101.481 MB. [2025-09-03T21:36:13.973Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4318.748 ms) ====== [2025-09-03T21:36:13.974Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-09-03T21:36:13.974Z] GC before operation: completed in 370.465 ms, heap usage 384.662 MB -> 73.629 MB. [2025-09-03T21:36:19.313Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (4761.801 ms) ====== [2025-09-03T21:36:19.313Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-09-03T21:36:19.313Z] GC before operation: completed in 337.898 ms, heap usage 473.384 MB -> 73.926 MB. [2025-09-03T21:36:22.364Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3095.952 ms) ====== [2025-09-03T21:36:22.364Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-09-03T21:36:22.771Z] GC before operation: completed in 279.128 ms, heap usage 231.658 MB -> 73.902 MB. [2025-09-03T21:36:27.818Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4483.591 ms) ====== [2025-09-03T21:36:27.818Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-09-03T21:36:27.818Z] GC before operation: completed in 290.546 ms, heap usage 448.883 MB -> 73.946 MB. [2025-09-03T21:36:31.743Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3655.016 ms) ====== [2025-09-03T21:36:31.743Z] ----------------------------------- [2025-09-03T21:36:31.743Z] renaissance-dec-tree_0_PASSED [2025-09-03T21:36:31.743Z] ----------------------------------- [2025-09-03T21:36:31.743Z] [2025-09-03T21:36:31.743Z] TEST TEARDOWN: [2025-09-03T21:36:31.743Z] Nothing to be done for teardown. [2025-09-03T21:36:31.743Z] renaissance-dec-tree_0 Finish Time: Wed Sep 3 14:36:31 2025 Epoch Time (ms): 1756935391549