[2025-05-21T21:19:46.241Z] Running test renaissance-dec-tree_0 ... [2025-05-21T21:19:46.241Z] =============================================== [2025-05-21T21:19:46.241Z] renaissance-dec-tree_0 Start Time: Wed May 21 14:19:45 2025 Epoch Time (ms): 1747862385886 [2025-05-21T21:19:46.241Z] variation: NoOptions [2025-05-21T21:19:46.241Z] JVM_OPTIONS: [2025-05-21T21:19:46.241Z] { \ [2025-05-21T21:19:46.241Z] echo ""; echo "TEST SETUP:"; \ [2025-05-21T21:19:46.241Z] echo "Nothing to be done for setup."; \ [2025-05-21T21:19:46.241Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17478621781767/renaissance-dec-tree_0"; \ [2025-05-21T21:19:46.241Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17478621781767/renaissance-dec-tree_0"; \ [2025-05-21T21:19:46.241Z] echo ""; echo "TESTING:"; \ [2025-05-21T21:19:46.241Z] "/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_17478621781767/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-21T21:19:46.241Z] 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_17478621781767/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-21T21:19:46.241Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-21T21:19:46.241Z] echo "Nothing to be done for teardown."; \ [2025-05-21T21:19:46.241Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17478621781767/TestTargetResult"; [2025-05-21T21:19:46.241Z] [2025-05-21T21:19:46.241Z] TEST SETUP: [2025-05-21T21:19:46.241Z] Nothing to be done for setup. [2025-05-21T21:19:46.241Z] [2025-05-21T21:19:46.241Z] TESTING: [2025-05-21T21:20:00.890Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-21T21:20:07.754Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-21T21:20:07.754Z] GC before operation: completed in 74.813 ms, heap usage 53.546 MB -> 34.929 MB. [2025-05-21T21:20:33.203Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (25543.401 ms) ====== [2025-05-21T21:20:33.203Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-21T21:20:33.203Z] GC before operation: completed in 420.039 ms, heap usage 480.177 MB -> 83.890 MB. [2025-05-21T21:20:41.403Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7703.515 ms) ====== [2025-05-21T21:20:41.403Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-21T21:20:41.403Z] GC before operation: completed in 137.195 ms, heap usage 200.174 MB -> 68.931 MB. [2025-05-21T21:20:47.244Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6029.487 ms) ====== [2025-05-21T21:20:47.244Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-21T21:20:47.244Z] GC before operation: completed in 190.292 ms, heap usage 383.780 MB -> 97.174 MB. [2025-05-21T21:20:51.202Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4499.359 ms) ====== [2025-05-21T21:20:51.202Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-21T21:20:51.635Z] GC before operation: completed in 185.016 ms, heap usage 340.186 MB -> 69.771 MB. [2025-05-21T21:20:56.381Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (4865.926 ms) ====== [2025-05-21T21:20:56.381Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-21T21:20:56.835Z] GC before operation: completed in 175.025 ms, heap usage 345.907 MB -> 70.140 MB. [2025-05-21T21:21:00.701Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4239.382 ms) ====== [2025-05-21T21:21:00.701Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-21T21:21:01.159Z] GC before operation: completed in 213.377 ms, heap usage 369.591 MB -> 70.195 MB. [2025-05-21T21:21:06.062Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4311.906 ms) ====== [2025-05-21T21:21:06.062Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-21T21:21:06.062Z] GC before operation: completed in 226.800 ms, heap usage 367.021 MB -> 70.553 MB. [2025-05-21T21:21:10.702Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4538.821 ms) ====== [2025-05-21T21:21:10.702Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-21T21:21:10.702Z] GC before operation: completed in 231.743 ms, heap usage 179.968 MB -> 70.258 MB. [2025-05-21T21:21:14.828Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3962.800 ms) ====== [2025-05-21T21:21:14.828Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-21T21:21:14.828Z] GC before operation: completed in 194.038 ms, heap usage 122.545 MB -> 98.237 MB. [2025-05-21T21:21:19.645Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4820.700 ms) ====== [2025-05-21T21:21:19.645Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-21T21:21:19.645Z] GC before operation: completed in 224.115 ms, heap usage 138.450 MB -> 72.746 MB. [2025-05-21T21:21:23.442Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3993.034 ms) ====== [2025-05-21T21:21:23.442Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-21T21:21:23.873Z] GC before operation: completed in 219.243 ms, heap usage 537.015 MB -> 71.488 MB. [2025-05-21T21:21:23.873Z] 14:21:23.479 WARN [block-manager-storage-async-thread-pool-18] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_149_piece0, which does not exist [2025-05-21T21:21:23.873Z] 14:21:23.521 WARN [block-manager-storage-async-thread-pool-18] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_149, which does not exist [2025-05-21T21:21:27.707Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3956.431 ms) ====== [2025-05-21T21:21:27.707Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-21T21:21:28.188Z] GC before operation: completed in 211.841 ms, heap usage 186.704 MB -> 83.856 MB. [2025-05-21T21:21:28.188Z] 14:21:27.647 WARN [block-manager-storage-async-thread-pool-2] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_163, which does not exist [2025-05-21T21:21:32.145Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4129.516 ms) ====== [2025-05-21T21:21:32.145Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-21T21:21:32.145Z] GC before operation: completed in 190.681 ms, heap usage 129.860 MB -> 70.511 MB. [2025-05-21T21:21:36.818Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4202.428 ms) ====== [2025-05-21T21:21:36.818Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-21T21:21:36.818Z] GC before operation: completed in 223.849 ms, heap usage 263.081 MB -> 71.460 MB. [2025-05-21T21:21:40.632Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3886.713 ms) ====== [2025-05-21T21:21:40.632Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-21T21:21:40.632Z] GC before operation: completed in 186.975 ms, heap usage 122.624 MB -> 70.749 MB. [2025-05-21T21:21:44.624Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3835.513 ms) ====== [2025-05-21T21:21:44.624Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-21T21:21:45.208Z] GC before operation: completed in 228.288 ms, heap usage 143.944 MB -> 99.023 MB. [2025-05-21T21:21:49.097Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3906.941 ms) ====== [2025-05-21T21:21:49.097Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-21T21:21:49.097Z] GC before operation: completed in 220.884 ms, heap usage 643.965 MB -> 72.010 MB. [2025-05-21T21:21:52.861Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3354.745 ms) ====== [2025-05-21T21:21:52.861Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-21T21:21:52.861Z] GC before operation: completed in 205.289 ms, heap usage 216.651 MB -> 99.941 MB. [2025-05-21T21:21:56.629Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4123.013 ms) ====== [2025-05-21T21:21:56.629Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-21T21:21:57.119Z] GC before operation: completed in 261.350 ms, heap usage 413.209 MB -> 72.045 MB. [2025-05-21T21:22:00.287Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3316.867 ms) ====== [2025-05-21T21:22:00.287Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-21T21:22:00.287Z] GC before operation: completed in 217.775 ms, heap usage 336.461 MB -> 99.778 MB. [2025-05-21T21:22:04.108Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3097.382 ms) ====== [2025-05-21T21:22:04.108Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-21T21:22:04.108Z] GC before operation: completed in 250.126 ms, heap usage 342.837 MB -> 72.344 MB. [2025-05-21T21:22:07.077Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3244.675 ms) ====== [2025-05-21T21:22:07.077Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-21T21:22:07.077Z] GC before operation: completed in 213.878 ms, heap usage 420.103 MB -> 72.335 MB. [2025-05-21T21:22:11.371Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3870.371 ms) ====== [2025-05-21T21:22:11.371Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-21T21:22:11.371Z] GC before operation: completed in 194.394 ms, heap usage 275.584 MB -> 72.617 MB. [2025-05-21T21:22:14.237Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2970.719 ms) ====== [2025-05-21T21:22:14.237Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-21T21:22:14.691Z] GC before operation: completed in 271.701 ms, heap usage 225.317 MB -> 100.101 MB. [2025-05-21T21:22:19.328Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4091.924 ms) ====== [2025-05-21T21:22:19.328Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-21T21:22:19.328Z] GC before operation: completed in 176.534 ms, heap usage 383.389 MB -> 100.195 MB. [2025-05-21T21:22:22.289Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3094.210 ms) ====== [2025-05-21T21:22:22.289Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-21T21:22:22.289Z] GC before operation: completed in 194.271 ms, heap usage 541.289 MB -> 72.963 MB. [2025-05-21T21:22:26.025Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3442.804 ms) ====== [2025-05-21T21:22:26.025Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-21T21:22:26.025Z] GC before operation: completed in 223.654 ms, heap usage 232.632 MB -> 100.597 MB. [2025-05-21T21:22:28.913Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3188.608 ms) ====== [2025-05-21T21:22:28.913Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-21T21:22:29.366Z] GC before operation: completed in 359.585 ms, heap usage 552.479 MB -> 100.869 MB. [2025-05-21T21:22:33.032Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3789.100 ms) ====== [2025-05-21T21:22:33.032Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-21T21:22:33.501Z] GC before operation: completed in 232.154 ms, heap usage 284.751 MB -> 73.348 MB. [2025-05-21T21:22:36.421Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2984.047 ms) ====== [2025-05-21T21:22:36.421Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-21T21:22:36.421Z] GC before operation: completed in 227.798 ms, heap usage 386.319 MB -> 73.218 MB. [2025-05-21T21:22:40.219Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3209.053 ms) ====== [2025-05-21T21:22:40.219Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-21T21:22:40.219Z] GC before operation: completed in 207.898 ms, heap usage 269.598 MB -> 73.628 MB. [2025-05-21T21:22:43.051Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2940.876 ms) ====== [2025-05-21T21:22:43.051Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-21T21:22:43.051Z] GC before operation: completed in 180.075 ms, heap usage 278.774 MB -> 101.082 MB. [2025-05-21T21:22:46.858Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3557.913 ms) ====== [2025-05-21T21:22:46.858Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-21T21:22:46.858Z] GC before operation: completed in 327.315 ms, heap usage 413.829 MB -> 73.516 MB. [2025-05-21T21:22:49.682Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2658.897 ms) ====== [2025-05-21T21:22:49.682Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-21T21:22:49.682Z] GC before operation: completed in 196.420 ms, heap usage 311.720 MB -> 73.653 MB. [2025-05-21T21:22:53.528Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3185.556 ms) ====== [2025-05-21T21:22:53.528Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-21T21:22:53.528Z] GC before operation: completed in 200.208 ms, heap usage 361.380 MB -> 73.840 MB. [2025-05-21T21:22:56.340Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3194.536 ms) ====== [2025-05-21T21:22:56.340Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-21T21:22:56.756Z] GC before operation: completed in 192.556 ms, heap usage 280.745 MB -> 73.970 MB. [2025-05-21T21:22:59.894Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3263.169 ms) ====== [2025-05-21T21:22:59.894Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-21T21:23:00.335Z] GC before operation: completed in 271.270 ms, heap usage 410.695 MB -> 73.966 MB. [2025-05-21T21:23:03.157Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2703.016 ms) ====== [2025-05-21T21:23:03.157Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-21T21:23:03.157Z] GC before operation: completed in 243.212 ms, heap usage 307.702 MB -> 74.240 MB. [2025-05-21T21:23:07.008Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3649.026 ms) ====== [2025-05-21T21:23:07.008Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-21T21:23:07.008Z] GC before operation: completed in 211.683 ms, heap usage 442.118 MB -> 74.369 MB. [2025-05-21T21:23:09.958Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2955.115 ms) ====== [2025-05-21T21:23:10.875Z] ----------------------------------- [2025-05-21T21:23:10.875Z] renaissance-dec-tree_0_PASSED [2025-05-21T21:23:10.875Z] ----------------------------------- [2025-05-21T21:23:10.875Z] [2025-05-21T21:23:10.875Z] TEST TEARDOWN: [2025-05-21T21:23:10.875Z] Nothing to be done for teardown. [2025-05-21T21:23:10.875Z] renaissance-dec-tree_0 Finish Time: Wed May 21 14:23:10 2025 Epoch Time (ms): 1747862590408