[2025-08-13T21:00:17.895Z] Running test renaissance-dec-tree_0 ... [2025-08-13T21:00:17.895Z] =============================================== [2025-08-13T21:00:17.895Z] renaissance-dec-tree_0 Start Time: Wed Aug 13 14:00:17 2025 Epoch Time (ms): 1755118817527 [2025-08-13T21:00:17.895Z] variation: NoOptions [2025-08-13T21:00:17.895Z] JVM_OPTIONS: [2025-08-13T21:00:17.895Z] { \ [2025-08-13T21:00:17.895Z] echo ""; echo "TEST SETUP:"; \ [2025-08-13T21:00:17.895Z] echo "Nothing to be done for setup."; \ [2025-08-13T21:00:17.895Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/renaissance-dec-tree_0"; \ [2025-08-13T21:00:17.895Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/renaissance-dec-tree_0"; \ [2025-08-13T21:00:17.895Z] echo ""; echo "TESTING:"; \ [2025-08-13T21:00:17.895Z] "/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_17551174266516/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-13T21:00:17.895Z] 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_17551174266516/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-13T21:00:17.895Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-13T21:00:17.895Z] echo "Nothing to be done for teardown."; \ [2025-08-13T21:00:17.895Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/TestTargetResult"; [2025-08-13T21:00:17.895Z] [2025-08-13T21:00:17.895Z] TEST SETUP: [2025-08-13T21:00:17.895Z] Nothing to be done for setup. [2025-08-13T21:00:17.895Z] [2025-08-13T21:00:17.895Z] TESTING: [2025-08-13T21:00:32.318Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-13T21:00:37.416Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-13T21:00:37.416Z] GC before operation: completed in 117.850 ms, heap usage 51.995 MB -> 34.978 MB. [2025-08-13T21:01:07.955Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (27101.419 ms) ====== [2025-08-13T21:01:07.955Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-13T21:01:07.955Z] GC before operation: completed in 308.727 ms, heap usage 458.601 MB -> 68.990 MB. [2025-08-13T21:01:11.104Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (6502.531 ms) ====== [2025-08-13T21:01:11.104Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-13T21:01:11.495Z] GC before operation: completed in 250.599 ms, heap usage 139.019 MB -> 69.362 MB. [2025-08-13T21:01:17.416Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6032.100 ms) ====== [2025-08-13T21:01:17.416Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-13T21:01:17.843Z] GC before operation: completed in 219.662 ms, heap usage 247.071 MB -> 69.612 MB. [2025-08-13T21:01:22.840Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5106.951 ms) ====== [2025-08-13T21:01:22.840Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-13T21:01:23.314Z] GC before operation: completed in 312.931 ms, heap usage 384.476 MB -> 97.452 MB. [2025-08-13T21:01:28.323Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5117.000 ms) ====== [2025-08-13T21:01:28.323Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-13T21:01:28.866Z] GC before operation: completed in 317.832 ms, heap usage 186.581 MB -> 69.854 MB. [2025-08-13T21:01:33.807Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4962.008 ms) ====== [2025-08-13T21:01:33.807Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-13T21:01:33.807Z] GC before operation: completed in 142.688 ms, heap usage 273.743 MB -> 70.385 MB. [2025-08-13T21:01:38.742Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4580.938 ms) ====== [2025-08-13T21:01:38.742Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-13T21:01:38.742Z] GC before operation: completed in 225.645 ms, heap usage 354.450 MB -> 70.465 MB. [2025-08-13T21:01:43.323Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4556.950 ms) ====== [2025-08-13T21:01:43.323Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-13T21:01:43.323Z] GC before operation: completed in 203.040 ms, heap usage 164.758 MB -> 97.790 MB. [2025-08-13T21:01:48.257Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4799.101 ms) ====== [2025-08-13T21:01:48.257Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-13T21:01:48.257Z] GC before operation: completed in 186.825 ms, heap usage 252.850 MB -> 71.011 MB. [2025-08-13T21:01:53.300Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4931.290 ms) ====== [2025-08-13T21:01:53.300Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-13T21:01:53.715Z] GC before operation: completed in 255.496 ms, heap usage 116.123 MB -> 70.303 MB. [2025-08-13T21:01:57.611Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3797.318 ms) ====== [2025-08-13T21:01:57.611Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-13T21:01:58.222Z] GC before operation: completed in 744.815 ms, heap usage 279.375 MB -> 70.940 MB. [2025-08-13T21:02:02.091Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4031.377 ms) ====== [2025-08-13T21:02:02.091Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-13T21:02:02.590Z] GC before operation: completed in 249.991 ms, heap usage 518.330 MB -> 71.433 MB. [2025-08-13T21:02:06.752Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4570.391 ms) ====== [2025-08-13T21:02:06.752Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-13T21:02:07.167Z] GC before operation: completed in 239.091 ms, heap usage 365.159 MB -> 98.831 MB. [2025-08-13T21:02:12.186Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4474.089 ms) ====== [2025-08-13T21:02:12.186Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-13T21:02:12.186Z] GC before operation: completed in 238.300 ms, heap usage 256.369 MB -> 99.028 MB. [2025-08-13T21:02:17.186Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4771.153 ms) ====== [2025-08-13T21:02:17.186Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-13T21:02:17.186Z] GC before operation: completed in 303.210 ms, heap usage 223.906 MB -> 71.759 MB. [2025-08-13T21:02:20.897Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3814.928 ms) ====== [2025-08-13T21:02:20.897Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-13T21:02:21.507Z] GC before operation: completed in 772.159 ms, heap usage 404.556 MB -> 71.657 MB. [2025-08-13T21:02:26.129Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4210.710 ms) ====== [2025-08-13T21:02:26.129Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-13T21:02:26.129Z] GC before operation: completed in 119.611 ms, heap usage 325.859 MB -> 71.676 MB. [2025-08-13T21:02:29.987Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3793.854 ms) ====== [2025-08-13T21:02:29.987Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-13T21:02:29.987Z] GC before operation: completed in 325.290 ms, heap usage 107.058 MB -> 98.728 MB. [2025-08-13T21:02:34.585Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4322.826 ms) ====== [2025-08-13T21:02:34.585Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-13T21:02:34.585Z] GC before operation: completed in 246.713 ms, heap usage 305.062 MB -> 71.965 MB. [2025-08-13T21:02:39.274Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4496.840 ms) ====== [2025-08-13T21:02:39.274Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-13T21:02:39.274Z] GC before operation: completed in 244.414 ms, heap usage 268.487 MB -> 71.946 MB. [2025-08-13T21:02:43.402Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3950.055 ms) ====== [2025-08-13T21:02:43.402Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-13T21:02:43.402Z] GC before operation: completed in 271.388 ms, heap usage 254.025 MB -> 99.756 MB. [2025-08-13T21:02:48.313Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4458.935 ms) ====== [2025-08-13T21:02:48.314Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-13T21:02:48.314Z] GC before operation: completed in 293.492 ms, heap usage 128.921 MB -> 100.035 MB. [2025-08-13T21:02:52.984Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (4146.092 ms) ====== [2025-08-13T21:02:52.984Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-13T21:02:52.984Z] GC before operation: completed in 241.967 ms, heap usage 581.158 MB -> 100.125 MB. [2025-08-13T21:02:58.066Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4866.182 ms) ====== [2025-08-13T21:02:58.066Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-13T21:02:58.066Z] GC before operation: completed in 189.951 ms, heap usage 190.117 MB -> 72.232 MB. [2025-08-13T21:03:03.026Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4498.910 ms) ====== [2025-08-13T21:03:03.026Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-13T21:03:03.026Z] GC before operation: completed in 199.730 ms, heap usage 283.367 MB -> 72.952 MB. [2025-08-13T21:03:03.026Z] 14:03:02.106 WARN [block-manager-storage-async-thread-pool-85] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_345, which does not exist [2025-08-13T21:03:06.333Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3864.345 ms) ====== [2025-08-13T21:03:06.333Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-13T21:03:06.913Z] GC before operation: completed in 292.468 ms, heap usage 549.638 MB -> 100.521 MB. [2025-08-13T21:03:10.845Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4233.711 ms) ====== [2025-08-13T21:03:10.845Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-13T21:03:11.244Z] GC before operation: completed in 183.854 ms, heap usage 433.438 MB -> 100.415 MB. [2025-08-13T21:03:16.051Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4258.153 ms) ====== [2025-08-13T21:03:16.052Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-13T21:03:16.052Z] GC before operation: completed in 165.797 ms, heap usage 459.703 MB -> 72.956 MB. [2025-08-13T21:03:18.793Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3173.289 ms) ====== [2025-08-13T21:03:18.793Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-13T21:03:18.793Z] GC before operation: completed in 180.906 ms, heap usage 239.099 MB -> 73.036 MB. [2025-08-13T21:03:22.586Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3246.442 ms) ====== [2025-08-13T21:03:22.586Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-13T21:03:22.586Z] GC before operation: completed in 255.338 ms, heap usage 286.536 MB -> 100.827 MB. [2025-08-13T21:03:27.522Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4418.460 ms) ====== [2025-08-13T21:03:27.522Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-13T21:03:27.522Z] GC before operation: completed in 305.109 ms, heap usage 601.356 MB -> 73.491 MB. [2025-08-13T21:03:32.562Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4627.812 ms) ====== [2025-08-13T21:03:32.562Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-13T21:03:32.562Z] GC before operation: completed in 304.846 ms, heap usage 257.471 MB -> 101.055 MB. [2025-08-13T21:03:35.476Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3601.239 ms) ====== [2025-08-13T21:03:35.476Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-13T21:03:35.989Z] GC before operation: completed in 214.748 ms, heap usage 501.639 MB -> 73.592 MB. [2025-08-13T21:03:39.931Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4120.881 ms) ====== [2025-08-13T21:03:39.931Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-13T21:03:40.389Z] GC before operation: completed in 320.535 ms, heap usage 310.704 MB -> 73.694 MB. [2025-08-13T21:03:44.264Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3676.542 ms) ====== [2025-08-13T21:03:44.264Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-13T21:03:44.264Z] GC before operation: completed in 237.380 ms, heap usage 306.181 MB -> 73.730 MB. [2025-08-13T21:03:48.114Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4005.744 ms) ====== [2025-08-13T21:03:48.114Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-13T21:03:48.585Z] GC before operation: completed in 384.153 ms, heap usage 357.359 MB -> 74.129 MB. [2025-08-13T21:03:52.227Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3770.081 ms) ====== [2025-08-13T21:03:52.227Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-13T21:03:52.684Z] GC before operation: completed in 373.004 ms, heap usage 373.852 MB -> 73.891 MB. [2025-08-13T21:03:57.398Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4324.022 ms) ====== [2025-08-13T21:03:57.398Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-13T21:03:57.398Z] GC before operation: completed in 303.219 ms, heap usage 371.175 MB -> 101.629 MB. [2025-08-13T21:04:01.249Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3652.539 ms) ====== [2025-08-13T21:04:01.249Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-13T21:04:01.249Z] GC before operation: completed in 321.479 ms, heap usage 502.472 MB -> 74.196 MB. [2025-08-13T21:04:05.237Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3918.725 ms) ====== [2025-08-13T21:04:06.123Z] ----------------------------------- [2025-08-13T21:04:06.123Z] renaissance-dec-tree_0_PASSED [2025-08-13T21:04:06.123Z] ----------------------------------- [2025-08-13T21:04:06.123Z] [2025-08-13T21:04:06.123Z] TEST TEARDOWN: [2025-08-13T21:04:06.123Z] Nothing to be done for teardown. [2025-08-13T21:04:06.123Z] renaissance-dec-tree_0 Finish Time: Wed Aug 13 14:04:05 2025 Epoch Time (ms): 1755119045664