[2025-06-30T17:46:50.377Z] Running test renaissance-dec-tree_0 ... [2025-06-30T17:46:50.377Z] =============================================== [2025-06-30T17:46:50.377Z] renaissance-dec-tree_0 Start Time: Mon Jun 30 10:46:50 2025 Epoch Time (ms): 1751305610051 [2025-06-30T17:46:50.800Z] variation: NoOptions [2025-06-30T17:46:50.800Z] JVM_OPTIONS: [2025-06-30T17:46:50.800Z] { \ [2025-06-30T17:46:50.800Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T17:46:50.800Z] echo "Nothing to be done for setup."; \ [2025-06-30T17:46:50.800Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/renaissance-dec-tree_0"; \ [2025-06-30T17:46:50.800Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/renaissance-dec-tree_0"; \ [2025-06-30T17:46:50.800Z] echo ""; echo "TESTING:"; \ [2025-06-30T17:46:50.800Z] "/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_17513039444718/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-30T17:46:50.800Z] 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_17513039444718/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T17:46:50.800Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T17:46:50.800Z] echo "Nothing to be done for teardown."; \ [2025-06-30T17:46:50.800Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/TestTargetResult"; [2025-06-30T17:46:50.800Z] [2025-06-30T17:46:50.800Z] TEST SETUP: [2025-06-30T17:46:50.800Z] Nothing to be done for setup. [2025-06-30T17:46:50.800Z] [2025-06-30T17:46:50.800Z] TESTING: [2025-06-30T17:47:08.483Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-30T17:47:15.508Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-30T17:47:15.508Z] GC before operation: completed in 143.767 ms, heap usage 45.236 MB -> 34.961 MB. [2025-06-30T17:47:45.983Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (28550.405 ms) ====== [2025-06-30T17:47:45.983Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-30T17:47:45.983Z] GC before operation: completed in 368.641 ms, heap usage 388.263 MB -> 69.037 MB. [2025-06-30T17:47:52.171Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7529.917 ms) ====== [2025-06-30T17:47:52.171Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-30T17:47:52.171Z] GC before operation: completed in 249.406 ms, heap usage 370.622 MB -> 69.409 MB. [2025-06-30T17:47:57.169Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (5189.779 ms) ====== [2025-06-30T17:47:57.169Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-30T17:47:57.718Z] GC before operation: completed in 249.941 ms, heap usage 188.985 MB -> 69.343 MB. [2025-06-30T17:48:03.955Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5969.246 ms) ====== [2025-06-30T17:48:03.955Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-30T17:48:03.955Z] GC before operation: completed in 328.883 ms, heap usage 124.055 MB -> 69.250 MB. [2025-06-30T17:48:09.728Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5304.363 ms) ====== [2025-06-30T17:48:09.728Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-30T17:48:09.728Z] GC before operation: completed in 277.689 ms, heap usage 231.362 MB -> 70.232 MB. [2025-06-30T17:48:14.840Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (5488.446 ms) ====== [2025-06-30T17:48:14.840Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-30T17:48:15.306Z] GC before operation: completed in 191.760 ms, heap usage 252.234 MB -> 97.937 MB. [2025-06-30T17:48:20.657Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4995.229 ms) ====== [2025-06-30T17:48:20.657Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-30T17:48:20.657Z] GC before operation: completed in 257.265 ms, heap usage 448.635 MB -> 85.408 MB. [2025-06-30T17:48:26.840Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (6127.235 ms) ====== [2025-06-30T17:48:26.840Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-30T17:48:26.840Z] GC before operation: completed in 350.506 ms, heap usage 390.055 MB -> 70.557 MB. [2025-06-30T17:48:31.535Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4354.687 ms) ====== [2025-06-30T17:48:31.535Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-30T17:48:31.535Z] GC before operation: completed in 181.181 ms, heap usage 318.282 MB -> 70.825 MB. [2025-06-30T17:48:35.702Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4343.446 ms) ====== [2025-06-30T17:48:35.702Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-30T17:48:36.113Z] GC before operation: completed in 261.853 ms, heap usage 266.231 MB -> 70.797 MB. [2025-06-30T17:48:40.525Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (4297.412 ms) ====== [2025-06-30T17:48:40.525Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-30T17:48:40.525Z] GC before operation: completed in 248.173 ms, heap usage 236.571 MB -> 73.021 MB. [2025-06-30T17:48:45.790Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (5196.014 ms) ====== [2025-06-30T17:48:45.790Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-30T17:48:46.217Z] GC before operation: completed in 397.511 ms, heap usage 239.051 MB -> 98.808 MB. [2025-06-30T17:48:50.918Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4776.812 ms) ====== [2025-06-30T17:48:50.918Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-30T17:48:51.350Z] GC before operation: completed in 340.042 ms, heap usage 342.542 MB -> 98.880 MB. [2025-06-30T17:48:55.829Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4267.160 ms) ====== [2025-06-30T17:48:55.829Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-30T17:48:55.829Z] GC before operation: completed in 315.090 ms, heap usage 590.496 MB -> 71.572 MB. [2025-06-30T17:49:00.682Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4747.840 ms) ====== [2025-06-30T17:49:00.682Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-30T17:49:00.682Z] GC before operation: completed in 282.604 ms, heap usage 268.244 MB -> 71.600 MB. [2025-06-30T17:49:05.585Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3940.199 ms) ====== [2025-06-30T17:49:05.585Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-30T17:49:05.585Z] GC before operation: completed in 353.828 ms, heap usage 151.570 MB -> 71.258 MB. [2025-06-30T17:49:09.387Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4178.666 ms) ====== [2025-06-30T17:49:09.387Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-30T17:49:09.387Z] GC before operation: completed in 236.490 ms, heap usage 276.046 MB -> 99.351 MB. [2025-06-30T17:49:14.067Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4093.998 ms) ====== [2025-06-30T17:49:14.067Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-30T17:49:14.067Z] GC before operation: completed in 245.782 ms, heap usage 579.435 MB -> 72.072 MB. [2025-06-30T17:49:18.039Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3809.358 ms) ====== [2025-06-30T17:49:18.039Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-30T17:49:18.509Z] GC before operation: completed in 801.338 ms, heap usage 405.069 MB -> 72.314 MB. [2025-06-30T17:49:22.525Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4175.938 ms) ====== [2025-06-30T17:49:22.525Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-30T17:49:22.995Z] GC before operation: completed in 281.184 ms, heap usage 377.950 MB -> 72.048 MB. [2025-06-30T17:49:27.609Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (4137.586 ms) ====== [2025-06-30T17:49:27.609Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-30T17:49:27.609Z] GC before operation: completed in 182.703 ms, heap usage 160.586 MB -> 71.826 MB. [2025-06-30T17:49:31.637Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4204.885 ms) ====== [2025-06-30T17:49:31.637Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-30T17:49:31.637Z] GC before operation: completed in 294.618 ms, heap usage 232.019 MB -> 72.278 MB. [2025-06-30T17:49:36.439Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (4014.892 ms) ====== [2025-06-30T17:49:36.439Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-30T17:49:36.439Z] GC before operation: completed in 234.646 ms, heap usage 259.502 MB -> 72.420 MB. [2025-06-30T17:49:40.218Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3821.679 ms) ====== [2025-06-30T17:49:40.218Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-30T17:49:40.218Z] GC before operation: completed in 401.471 ms, heap usage 303.563 MB -> 72.944 MB. [2025-06-30T17:49:44.035Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3429.542 ms) ====== [2025-06-30T17:49:44.035Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-30T17:49:44.035Z] GC before operation: completed in 282.734 ms, heap usage 268.035 MB -> 100.312 MB. [2025-06-30T17:49:44.035Z] 10:49:43.562 WARN [block-manager-storage-async-thread-pool-106] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_345, which does not exist [2025-06-30T17:49:47.709Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3864.463 ms) ====== [2025-06-30T17:49:47.709Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-30T17:49:48.172Z] GC before operation: completed in 311.444 ms, heap usage 451.341 MB -> 72.735 MB. [2025-06-30T17:49:53.065Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4529.559 ms) ====== [2025-06-30T17:49:53.065Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-30T17:49:53.065Z] GC before operation: completed in 168.657 ms, heap usage 237.269 MB -> 72.843 MB. [2025-06-30T17:49:56.908Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3455.838 ms) ====== [2025-06-30T17:49:56.908Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-30T17:49:56.908Z] GC before operation: completed in 338.449 ms, heap usage 367.543 MB -> 100.556 MB. [2025-06-30T17:50:00.627Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4046.293 ms) ====== [2025-06-30T17:50:00.627Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-30T17:50:01.093Z] GC before operation: completed in 366.185 ms, heap usage 510.937 MB -> 73.065 MB. [2025-06-30T17:50:05.826Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (4081.123 ms) ====== [2025-06-30T17:50:05.826Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-30T17:50:05.826Z] GC before operation: completed in 218.891 ms, heap usage 225.612 MB -> 73.216 MB. [2025-06-30T17:50:08.849Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3594.599 ms) ====== [2025-06-30T17:50:08.850Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-30T17:50:09.315Z] GC before operation: completed in 216.865 ms, heap usage 234.328 MB -> 73.278 MB. [2025-06-30T17:50:13.108Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3500.102 ms) ====== [2025-06-30T17:50:13.108Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-30T17:50:13.108Z] GC before operation: completed in 290.562 ms, heap usage 431.444 MB -> 100.978 MB. [2025-06-30T17:50:17.007Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3495.821 ms) ====== [2025-06-30T17:50:17.007Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-30T17:50:17.007Z] GC before operation: completed in 259.669 ms, heap usage 513.254 MB -> 73.648 MB. [2025-06-30T17:50:23.316Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (5864.661 ms) ====== [2025-06-30T17:50:23.316Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-30T17:50:23.316Z] GC before operation: completed in 322.655 ms, heap usage 193.207 MB -> 73.624 MB. [2025-06-30T17:50:27.998Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (4707.158 ms) ====== [2025-06-30T17:50:27.998Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-30T17:50:27.998Z] GC before operation: completed in 314.891 ms, heap usage 277.317 MB -> 73.707 MB. [2025-06-30T17:50:32.676Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4331.966 ms) ====== [2025-06-30T17:50:32.676Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-30T17:50:32.676Z] GC before operation: completed in 240.212 ms, heap usage 384.766 MB -> 74.008 MB. [2025-06-30T17:50:36.626Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (4036.956 ms) ====== [2025-06-30T17:50:36.626Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-30T17:50:37.040Z] GC before operation: completed in 310.768 ms, heap usage 296.092 MB -> 101.593 MB. [2025-06-30T17:50:41.994Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4756.355 ms) ====== [2025-06-30T17:50:41.994Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-30T17:50:41.994Z] GC before operation: completed in 266.237 ms, heap usage 525.498 MB -> 74.138 MB. [2025-06-30T17:50:46.863Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4085.151 ms) ====== [2025-06-30T17:50:46.863Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-30T17:50:46.863Z] GC before operation: completed in 354.210 ms, heap usage 196.280 MB -> 101.542 MB. [2025-06-30T17:50:51.659Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4978.852 ms) ====== [2025-06-30T17:50:52.155Z] ----------------------------------- [2025-06-30T17:50:52.155Z] renaissance-dec-tree_0_PASSED [2025-06-30T17:50:52.155Z] ----------------------------------- [2025-06-30T17:50:52.155Z] [2025-06-30T17:50:52.155Z] TEST TEARDOWN: [2025-06-30T17:50:52.155Z] Nothing to be done for teardown. [2025-06-30T17:50:52.155Z] renaissance-dec-tree_0 Finish Time: Mon Jun 30 10:50:51 2025 Epoch Time (ms): 1751305851826