[2025-05-21T21:07:40.496Z] Running test renaissance-dec-tree_0 ... [2025-05-21T21:07:40.496Z] =============================================== [2025-05-21T21:07:40.889Z] renaissance-dec-tree_0 Start Time: Wed May 21 17:07:40 2025 Epoch Time (ms): 1747861660458 [2025-05-21T21:07:40.889Z] variation: NoOptions [2025-05-21T21:07:40.889Z] JVM_OPTIONS: [2025-05-21T21:07:40.889Z] { \ [2025-05-21T21:07:40.889Z] echo ""; echo "TEST SETUP:"; \ [2025-05-21T21:07:40.889Z] echo "Nothing to be done for setup."; \ [2025-05-21T21:07:40.889Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17478614876908/renaissance-dec-tree_0"; \ [2025-05-21T21:07:40.889Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17478614876908/renaissance-dec-tree_0"; \ [2025-05-21T21:07:40.889Z] echo ""; echo "TESTING:"; \ [2025-05-21T21:07:40.889Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17478614876908/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-21T21:07:40.889Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17478614876908/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-21T21:07:40.889Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-21T21:07:40.889Z] echo "Nothing to be done for teardown."; \ [2025-05-21T21:07:40.889Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17478614876908/TestTargetResult"; [2025-05-21T21:07:40.889Z] [2025-05-21T21:07:40.889Z] TEST SETUP: [2025-05-21T21:07:40.889Z] Nothing to be done for setup. [2025-05-21T21:07:40.889Z] [2025-05-21T21:07:40.889Z] TESTING: [2025-05-21T21:07:43.514Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-21T21:07:44.844Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-21T21:07:44.844Z] GC before operation: completed in 22.708 ms, heap usage 42.038 MB -> 34.976 MB. [2025-05-21T21:07:50.139Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (4946.299 ms) ====== [2025-05-21T21:07:50.139Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-21T21:07:50.139Z] GC before operation: completed in 40.284 ms, heap usage 270.419 MB -> 69.214 MB. [2025-05-21T21:07:51.026Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1228.697 ms) ====== [2025-05-21T21:07:51.026Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-21T21:07:51.026Z] GC before operation: completed in 52.292 ms, heap usage 104.012 MB -> 69.199 MB. [2025-05-21T21:07:52.422Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (992.580 ms) ====== [2025-05-21T21:07:52.422Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-21T21:07:52.422Z] GC before operation: completed in 38.244 ms, heap usage 246.274 MB -> 69.659 MB. [2025-05-21T21:07:53.244Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (935.743 ms) ====== [2025-05-21T21:07:53.244Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-21T21:07:53.244Z] GC before operation: completed in 50.474 ms, heap usage 209.171 MB -> 69.885 MB. [2025-05-21T21:07:54.094Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (837.429 ms) ====== [2025-05-21T21:07:54.094Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-21T21:07:54.094Z] GC before operation: completed in 43.023 ms, heap usage 383.782 MB -> 70.088 MB. [2025-05-21T21:07:54.934Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (883.127 ms) ====== [2025-05-21T21:07:54.935Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-21T21:07:54.935Z] GC before operation: completed in 47.381 ms, heap usage 149.004 MB -> 70.124 MB. [2025-05-21T21:07:55.767Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (782.872 ms) ====== [2025-05-21T21:07:55.767Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-21T21:07:55.767Z] GC before operation: completed in 56.386 ms, heap usage 340.642 MB -> 83.661 MB. [2025-05-21T21:07:56.597Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (820.896 ms) ====== [2025-05-21T21:07:56.597Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-21T21:07:56.597Z] GC before operation: completed in 43.777 ms, heap usage 214.904 MB -> 70.640 MB. [2025-05-21T21:07:57.427Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (771.163 ms) ====== [2025-05-21T21:07:57.427Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-21T21:07:57.427Z] GC before operation: completed in 41.822 ms, heap usage 374.215 MB -> 70.820 MB. [2025-05-21T21:07:58.250Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (780.134 ms) ====== [2025-05-21T21:07:58.250Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-21T21:07:58.250Z] GC before operation: completed in 45.260 ms, heap usage 217.480 MB -> 70.902 MB. [2025-05-21T21:07:59.138Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (819.636 ms) ====== [2025-05-21T21:07:59.138Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-21T21:07:59.138Z] GC before operation: completed in 45.148 ms, heap usage 244.791 MB -> 71.199 MB. [2025-05-21T21:08:00.012Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (729.771 ms) ====== [2025-05-21T21:08:00.012Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-21T21:08:00.012Z] GC before operation: completed in 50.180 ms, heap usage 377.695 MB -> 71.612 MB. [2025-05-21T21:08:00.900Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (917.086 ms) ====== [2025-05-21T21:08:00.900Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-21T21:08:00.900Z] GC before operation: completed in 50.526 ms, heap usage 351.210 MB -> 86.219 MB. [2025-05-21T21:08:02.241Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (985.135 ms) ====== [2025-05-21T21:08:02.241Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-21T21:08:02.241Z] GC before operation: completed in 52.714 ms, heap usage 173.845 MB -> 84.103 MB. [2025-05-21T21:08:02.652Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (808.220 ms) ====== [2025-05-21T21:08:02.652Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-21T21:08:03.071Z] GC before operation: completed in 48.830 ms, heap usage 455.606 MB -> 71.565 MB. [2025-05-21T21:08:03.929Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (913.204 ms) ====== [2025-05-21T21:08:03.929Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-21T21:08:03.929Z] GC before operation: completed in 43.762 ms, heap usage 364.030 MB -> 71.915 MB. [2025-05-21T21:08:04.758Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (694.942 ms) ====== [2025-05-21T21:08:04.758Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-21T21:08:04.758Z] GC before operation: completed in 42.057 ms, heap usage 121.068 MB -> 71.411 MB. [2025-05-21T21:08:05.147Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (663.198 ms) ====== [2025-05-21T21:08:05.147Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-21T21:08:05.147Z] GC before operation: completed in 46.497 ms, heap usage 211.068 MB -> 71.971 MB. [2025-05-21T21:08:05.981Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (648.029 ms) ====== [2025-05-21T21:08:05.981Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-21T21:08:05.981Z] GC before operation: completed in 47.674 ms, heap usage 332.105 MB -> 72.269 MB. [2025-05-21T21:08:06.809Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (652.938 ms) ====== [2025-05-21T21:08:06.809Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-21T21:08:06.809Z] GC before operation: completed in 51.814 ms, heap usage 350.483 MB -> 98.127 MB. [2025-05-21T21:08:07.200Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (643.040 ms) ====== [2025-05-21T21:08:07.200Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-21T21:08:07.200Z] GC before operation: completed in 47.791 ms, heap usage 296.680 MB -> 72.566 MB. [2025-05-21T21:08:08.023Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (721.863 ms) ====== [2025-05-21T21:08:08.023Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-21T21:08:08.023Z] GC before operation: completed in 52.998 ms, heap usage 242.447 MB -> 72.507 MB. [2025-05-21T21:08:08.862Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (650.578 ms) ====== [2025-05-21T21:08:08.862Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-21T21:08:08.862Z] GC before operation: completed in 49.999 ms, heap usage 216.543 MB -> 98.442 MB. [2025-05-21T21:08:08.862Z] 17:08:08.602 WARN [block-manager-storage-async-thread-pool-60] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1101_0, which does not exist [2025-05-21T21:08:09.694Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (652.371 ms) ====== [2025-05-21T21:08:09.694Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-21T21:08:09.694Z] GC before operation: completed in 48.856 ms, heap usage 307.643 MB -> 85.429 MB. [2025-05-21T21:08:10.087Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (645.355 ms) ====== [2025-05-21T21:08:10.087Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-21T21:08:10.087Z] GC before operation: completed in 54.579 ms, heap usage 399.088 MB -> 72.809 MB. [2025-05-21T21:08:10.935Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (649.672 ms) ====== [2025-05-21T21:08:10.935Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-21T21:08:10.935Z] GC before operation: completed in 51.638 ms, heap usage 337.055 MB -> 73.080 MB. [2025-05-21T21:08:11.771Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (663.128 ms) ====== [2025-05-21T21:08:11.771Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-21T21:08:11.771Z] GC before operation: completed in 51.840 ms, heap usage 339.930 MB -> 86.010 MB. [2025-05-21T21:08:12.162Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (642.078 ms) ====== [2025-05-21T21:08:12.162Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-21T21:08:12.162Z] GC before operation: completed in 51.809 ms, heap usage 173.168 MB -> 85.794 MB. [2025-05-21T21:08:12.987Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (624.883 ms) ====== [2025-05-21T21:08:12.987Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-21T21:08:12.987Z] GC before operation: completed in 54.392 ms, heap usage 428.593 MB -> 73.434 MB. [2025-05-21T21:08:13.839Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (653.529 ms) ====== [2025-05-21T21:08:13.839Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-21T21:08:13.839Z] GC before operation: completed in 51.623 ms, heap usage 326.925 MB -> 73.355 MB. [2025-05-21T21:08:14.234Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (673.194 ms) ====== [2025-05-21T21:08:14.235Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-21T21:08:14.624Z] GC before operation: completed in 50.724 ms, heap usage 340.241 MB -> 73.487 MB. [2025-05-21T21:08:15.024Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (670.239 ms) ====== [2025-05-21T21:08:15.024Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-21T21:08:15.024Z] GC before operation: completed in 53.004 ms, heap usage 220.704 MB -> 73.558 MB. [2025-05-21T21:08:15.858Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (649.190 ms) ====== [2025-05-21T21:08:15.858Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-21T21:08:15.858Z] GC before operation: completed in 54.897 ms, heap usage 152.049 MB -> 73.357 MB. [2025-05-21T21:08:16.747Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (643.592 ms) ====== [2025-05-21T21:08:16.747Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-21T21:08:16.747Z] GC before operation: completed in 50.339 ms, heap usage 352.972 MB -> 73.817 MB. [2025-05-21T21:08:17.134Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (689.634 ms) ====== [2025-05-21T21:08:17.134Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-21T21:08:17.533Z] GC before operation: completed in 54.184 ms, heap usage 219.272 MB -> 73.951 MB. [2025-05-21T21:08:17.931Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (632.293 ms) ====== [2025-05-21T21:08:17.931Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-21T21:08:17.931Z] GC before operation: completed in 59.751 ms, heap usage 166.902 MB -> 74.113 MB. [2025-05-21T21:08:18.819Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (690.351 ms) ====== [2025-05-21T21:08:18.819Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-21T21:08:18.819Z] GC before operation: completed in 51.752 ms, heap usage 375.040 MB -> 74.413 MB. [2025-05-21T21:08:19.646Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (683.956 ms) ====== [2025-05-21T21:08:19.646Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-21T21:08:19.646Z] GC before operation: completed in 59.088 ms, heap usage 327.751 MB -> 100.212 MB. [2025-05-21T21:08:20.053Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (664.136 ms) ====== [2025-05-21T21:08:20.053Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-21T21:08:20.053Z] GC before operation: completed in 60.708 ms, heap usage 283.965 MB -> 102.054 MB. [2025-05-21T21:08:20.923Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (753.637 ms) ====== [2025-05-21T21:08:21.316Z] ----------------------------------- [2025-05-21T21:08:21.316Z] renaissance-dec-tree_0_PASSED [2025-05-21T21:08:21.316Z] ----------------------------------- [2025-05-21T21:08:21.316Z] [2025-05-21T21:08:21.316Z] TEST TEARDOWN: [2025-05-21T21:08:21.316Z] Nothing to be done for teardown. [2025-05-21T21:08:21.316Z] renaissance-dec-tree_0 Finish Time: Wed May 21 17:08:20 2025 Epoch Time (ms): 1747861700925