[2025-06-29T20:28:44.252Z] Running test renaissance-dec-tree_0 ... [2025-06-29T20:28:44.252Z] =============================================== [2025-06-29T20:28:44.686Z] renaissance-dec-tree_0 Start Time: Sun Jun 29 13:28:44 2025 Epoch Time (ms): 1751228924185 [2025-06-29T20:28:44.686Z] variation: NoOptions [2025-06-29T20:28:44.686Z] JVM_OPTIONS: [2025-06-29T20:28:44.686Z] { \ [2025-06-29T20:28:44.686Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T20:28:44.686Z] echo "Nothing to be done for setup."; \ [2025-06-29T20:28:44.686Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17512272867334/renaissance-dec-tree_0"; \ [2025-06-29T20:28:44.686Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17512272867334/renaissance-dec-tree_0"; \ [2025-06-29T20:28:44.686Z] echo ""; echo "TESTING:"; \ [2025-06-29T20:28:44.686Z] "/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_17512272867334/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-29T20:28:44.686Z] 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_17512272867334/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T20:28:44.686Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T20:28:44.686Z] echo "Nothing to be done for teardown."; \ [2025-06-29T20:28:44.686Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17512272867334/TestTargetResult"; [2025-06-29T20:28:44.686Z] [2025-06-29T20:28:44.686Z] TEST SETUP: [2025-06-29T20:28:44.686Z] Nothing to be done for setup. [2025-06-29T20:28:44.686Z] [2025-06-29T20:28:44.686Z] TESTING: [2025-06-29T20:29:03.090Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-29T20:29:08.953Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-29T20:29:09.468Z] GC before operation: completed in 113.845 ms, heap usage 75.683 MB -> 35.153 MB. [2025-06-29T20:29:40.272Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (30127.920 ms) ====== [2025-06-29T20:29:40.272Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-29T20:29:40.272Z] GC before operation: completed in 296.330 ms, heap usage 177.524 MB -> 69.386 MB. [2025-06-29T20:29:47.829Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7308.789 ms) ====== [2025-06-29T20:29:47.829Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-29T20:29:47.829Z] GC before operation: completed in 254.219 ms, heap usage 385.308 MB -> 97.251 MB. [2025-06-29T20:29:53.954Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6227.327 ms) ====== [2025-06-29T20:29:53.955Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-29T20:29:53.955Z] GC before operation: completed in 486.895 ms, heap usage 376.734 MB -> 69.898 MB. [2025-06-29T20:30:00.066Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5498.598 ms) ====== [2025-06-29T20:30:00.066Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-29T20:30:00.066Z] GC before operation: completed in 180.977 ms, heap usage 285.667 MB -> 70.116 MB. [2025-06-29T20:30:05.128Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5653.392 ms) ====== [2025-06-29T20:30:05.128Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-29T20:30:05.682Z] GC before operation: completed in 282.489 ms, heap usage 407.094 MB -> 70.326 MB. [2025-06-29T20:30:10.843Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4969.992 ms) ====== [2025-06-29T20:30:10.843Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-29T20:30:10.843Z] GC before operation: completed in 290.483 ms, heap usage 419.711 MB -> 70.539 MB. [2025-06-29T20:30:17.378Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5740.194 ms) ====== [2025-06-29T20:30:17.378Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-29T20:30:17.378Z] GC before operation: completed in 239.415 ms, heap usage 413.575 MB -> 98.339 MB. [2025-06-29T20:30:21.544Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4739.244 ms) ====== [2025-06-29T20:30:21.544Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-29T20:30:22.076Z] GC before operation: completed in 296.903 ms, heap usage 320.541 MB -> 70.918 MB. [2025-06-29T20:30:28.308Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5634.498 ms) ====== [2025-06-29T20:30:28.308Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-29T20:30:28.308Z] GC before operation: completed in 194.861 ms, heap usage 374.649 MB -> 71.171 MB. [2025-06-29T20:30:33.249Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (5317.144 ms) ====== [2025-06-29T20:30:33.249Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-29T20:30:33.249Z] GC before operation: completed in 368.861 ms, heap usage 331.908 MB -> 98.754 MB. [2025-06-29T20:30:39.167Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (5077.623 ms) ====== [2025-06-29T20:30:39.167Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-29T20:30:39.167Z] GC before operation: completed in 258.483 ms, heap usage 265.809 MB -> 71.312 MB. [2025-06-29T20:30:44.130Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4974.830 ms) ====== [2025-06-29T20:30:44.130Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-29T20:30:44.130Z] GC before operation: completed in 394.626 ms, heap usage 460.093 MB -> 99.099 MB. [2025-06-29T20:30:44.130Z] 13:30:43.675 WARN [block-manager-storage-async-thread-pool-78] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_163_piece0, which does not exist [2025-06-29T20:30:48.980Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4662.354 ms) ====== [2025-06-29T20:30:48.980Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-29T20:30:48.980Z] GC before operation: completed in 276.939 ms, heap usage 530.943 MB -> 72.197 MB. [2025-06-29T20:30:53.873Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (5025.019 ms) ====== [2025-06-29T20:30:53.873Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-29T20:30:54.340Z] GC before operation: completed in 254.298 ms, heap usage 351.733 MB -> 72.114 MB. [2025-06-29T20:30:58.973Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4348.969 ms) ====== [2025-06-29T20:30:58.973Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-29T20:30:58.973Z] GC before operation: completed in 259.727 ms, heap usage 398.149 MB -> 72.285 MB. [2025-06-29T20:31:03.978Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (5065.611 ms) ====== [2025-06-29T20:31:03.978Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-29T20:31:04.557Z] GC before operation: completed in 313.090 ms, heap usage 349.685 MB -> 72.301 MB. [2025-06-29T20:31:08.666Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4457.038 ms) ====== [2025-06-29T20:31:08.666Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-29T20:31:09.170Z] GC before operation: completed in 236.558 ms, heap usage 305.381 MB -> 72.334 MB. [2025-06-29T20:31:14.247Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4979.173 ms) ====== [2025-06-29T20:31:14.247Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-29T20:31:14.247Z] GC before operation: completed in 251.103 ms, heap usage 392.246 MB -> 72.236 MB. [2025-06-29T20:31:18.288Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4270.161 ms) ====== [2025-06-29T20:31:18.288Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-29T20:31:18.704Z] GC before operation: completed in 268.139 ms, heap usage 243.696 MB -> 99.895 MB. [2025-06-29T20:31:23.826Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4284.972 ms) ====== [2025-06-29T20:31:23.826Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-29T20:31:23.826Z] GC before operation: completed in 317.137 ms, heap usage 604.794 MB -> 72.620 MB. [2025-06-29T20:31:28.602Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (4439.865 ms) ====== [2025-06-29T20:31:28.602Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-29T20:31:28.602Z] GC before operation: completed in 311.906 ms, heap usage 415.939 MB -> 72.668 MB. [2025-06-29T20:31:33.218Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4456.341 ms) ====== [2025-06-29T20:31:33.218Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-29T20:31:33.218Z] GC before operation: completed in 205.501 ms, heap usage 393.775 MB -> 73.001 MB. [2025-06-29T20:31:37.203Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (4373.246 ms) ====== [2025-06-29T20:31:37.203Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-29T20:31:37.706Z] GC before operation: completed in 335.073 ms, heap usage 349.229 MB -> 72.780 MB. [2025-06-29T20:31:42.571Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (5210.238 ms) ====== [2025-06-29T20:31:42.571Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-29T20:31:43.087Z] GC before operation: completed in 246.597 ms, heap usage 417.950 MB -> 73.026 MB. [2025-06-29T20:31:47.088Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4109.867 ms) ====== [2025-06-29T20:31:47.088Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-29T20:31:47.590Z] GC before operation: completed in 325.882 ms, heap usage 302.230 MB -> 72.934 MB. [2025-06-29T20:31:51.379Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4123.251 ms) ====== [2025-06-29T20:31:51.379Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-29T20:31:51.803Z] GC before operation: completed in 250.075 ms, heap usage 215.019 MB -> 73.434 MB. [2025-06-29T20:31:56.656Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4266.379 ms) ====== [2025-06-29T20:31:56.656Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-29T20:31:56.656Z] GC before operation: completed in 259.502 ms, heap usage 211.069 MB -> 100.784 MB. [2025-06-29T20:32:01.635Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4627.218 ms) ====== [2025-06-29T20:32:01.635Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-29T20:32:01.635Z] GC before operation: completed in 358.196 ms, heap usage 292.030 MB -> 73.484 MB. [2025-06-29T20:32:05.435Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4337.472 ms) ====== [2025-06-29T20:32:05.435Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-29T20:32:05.930Z] GC before operation: completed in 353.853 ms, heap usage 276.307 MB -> 73.507 MB. [2025-06-29T20:32:10.682Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (4221.825 ms) ====== [2025-06-29T20:32:10.682Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-29T20:32:10.682Z] GC before operation: completed in 298.655 ms, heap usage 215.316 MB -> 101.313 MB. [2025-06-29T20:32:15.776Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (5219.343 ms) ====== [2025-06-29T20:32:15.776Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-29T20:32:16.347Z] GC before operation: completed in 292.954 ms, heap usage 350.176 MB -> 101.286 MB. [2025-06-29T20:32:20.033Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3796.831 ms) ====== [2025-06-29T20:32:20.033Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-29T20:32:20.033Z] GC before operation: completed in 406.766 ms, heap usage 387.060 MB -> 73.967 MB. [2025-06-29T20:32:25.056Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (4367.650 ms) ====== [2025-06-29T20:32:25.056Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-29T20:32:25.056Z] GC before operation: completed in 368.894 ms, heap usage 407.060 MB -> 74.104 MB. [2025-06-29T20:32:29.821Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4191.830 ms) ====== [2025-06-29T20:32:29.821Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-29T20:32:29.821Z] GC before operation: completed in 365.325 ms, heap usage 214.728 MB -> 101.744 MB. [2025-06-29T20:32:34.834Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (5058.093 ms) ====== [2025-06-29T20:32:34.834Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-29T20:32:34.834Z] GC before operation: completed in 396.476 ms, heap usage 553.891 MB -> 74.493 MB. [2025-06-29T20:32:38.931Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3999.681 ms) ====== [2025-06-29T20:32:38.931Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-29T20:32:39.461Z] GC before operation: completed in 415.360 ms, heap usage 371.174 MB -> 74.252 MB. [2025-06-29T20:32:45.931Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (6680.587 ms) ====== [2025-06-29T20:32:45.931Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-29T20:32:46.481Z] GC before operation: completed in 493.968 ms, heap usage 327.810 MB -> 74.275 MB. [2025-06-29T20:32:51.388Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4946.261 ms) ====== [2025-06-29T20:32:51.388Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-29T20:32:51.860Z] GC before operation: completed in 372.665 ms, heap usage 162.237 MB -> 74.198 MB. [2025-06-29T20:32:55.859Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3788.749 ms) ====== [2025-06-29T20:32:55.859Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-29T20:32:55.859Z] GC before operation: completed in 299.540 ms, heap usage 342.624 MB -> 74.919 MB. [2025-06-29T20:33:00.861Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4607.501 ms) ====== [2025-06-29T20:33:01.265Z] ----------------------------------- [2025-06-29T20:33:01.265Z] renaissance-dec-tree_0_PASSED [2025-06-29T20:33:01.265Z] ----------------------------------- [2025-06-29T20:33:01.265Z] [2025-06-29T20:33:01.265Z] TEST TEARDOWN: [2025-06-29T20:33:01.265Z] Nothing to be done for teardown. [2025-06-29T20:33:01.265Z] renaissance-dec-tree_0 Finish Time: Sun Jun 29 13:33:01 2025 Epoch Time (ms): 1751229181024