[2025-09-25T05:35:10.620Z] Running test renaissance-dec-tree_0 ... [2025-09-25T05:35:10.620Z] =============================================== [2025-09-25T05:35:10.620Z] renaissance-dec-tree_0 Start Time: Thu Sep 25 01:35:10 2025 Epoch Time (ms): 1758778510294 [2025-09-25T05:35:10.620Z] variation: NoOptions [2025-09-25T05:35:10.620Z] JVM_OPTIONS: [2025-09-25T05:35:10.620Z] { \ [2025-09-25T05:35:10.620Z] echo ""; echo "TEST SETUP:"; \ [2025-09-25T05:35:10.620Z] echo "Nothing to be done for setup."; \ [2025-09-25T05:35:10.621Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17587774876375/renaissance-dec-tree_0"; \ [2025-09-25T05:35:10.621Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17587774876375/renaissance-dec-tree_0"; \ [2025-09-25T05:35:10.621Z] echo ""; echo "TESTING:"; \ [2025-09-25T05:35:10.621Z] "/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_17587774876375/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-09-25T05:35:10.621Z] 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_17587774876375/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-25T05:35:10.621Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-25T05:35:10.621Z] echo "Nothing to be done for teardown."; \ [2025-09-25T05:35:10.621Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17587774876375/TestTargetResult"; [2025-09-25T05:35:10.621Z] [2025-09-25T05:35:10.621Z] TEST SETUP: [2025-09-25T05:35:10.621Z] Nothing to be done for setup. [2025-09-25T05:35:10.621Z] [2025-09-25T05:35:10.621Z] TESTING: [2025-09-25T05:35:36.766Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-09-25T05:35:48.225Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-09-25T05:35:48.225Z] GC before operation: completed in 252.615 ms, heap usage 57.613 MB -> 34.897 MB. [2025-09-25T05:36:32.945Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (43685.828 ms) ====== [2025-09-25T05:36:32.945Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-09-25T05:36:32.945Z] GC before operation: completed in 446.751 ms, heap usage 324.087 MB -> 68.993 MB. [2025-09-25T05:36:44.300Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (12575.207 ms) ====== [2025-09-25T05:36:44.300Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-09-25T05:36:44.300Z] GC before operation: completed in 218.308 ms, heap usage 272.794 MB -> 69.572 MB. [2025-09-25T05:36:52.123Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (7785.174 ms) ====== [2025-09-25T05:36:52.123Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-09-25T05:36:52.724Z] GC before operation: completed in 322.243 ms, heap usage 291.426 MB -> 97.364 MB. [2025-09-25T05:37:02.168Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (9211.084 ms) ====== [2025-09-25T05:37:02.168Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-09-25T05:37:02.168Z] GC before operation: completed in 407.291 ms, heap usage 134.531 MB -> 97.736 MB. [2025-09-25T05:37:02.168Z] 01:37:01.782 WARN [block-manager-storage-async-thread-pool-46] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_51, which does not exist [2025-09-25T05:37:09.716Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (7810.451 ms) ====== [2025-09-25T05:37:09.716Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-09-25T05:37:10.229Z] GC before operation: completed in 301.934 ms, heap usage 327.897 MB -> 70.293 MB. [2025-09-25T05:37:18.245Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (7436.996 ms) ====== [2025-09-25T05:37:18.245Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-09-25T05:37:18.245Z] GC before operation: completed in 393.319 ms, heap usage 221.763 MB -> 70.502 MB. [2025-09-25T05:37:24.979Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (6567.373 ms) ====== [2025-09-25T05:37:24.979Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-09-25T05:37:25.473Z] GC before operation: completed in 816.895 ms, heap usage 311.188 MB -> 70.623 MB. [2025-09-25T05:37:33.361Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (7078.080 ms) ====== [2025-09-25T05:37:33.361Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-09-25T05:37:33.361Z] GC before operation: completed in 488.048 ms, heap usage 185.548 MB -> 98.260 MB. [2025-09-25T05:37:41.290Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (8104.287 ms) ====== [2025-09-25T05:37:41.290Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-09-25T05:37:41.815Z] GC before operation: completed in 624.312 ms, heap usage 586.314 MB -> 70.975 MB. [2025-09-25T05:37:48.306Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (6519.675 ms) ====== [2025-09-25T05:37:48.306Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-09-25T05:37:48.857Z] GC before operation: completed in 394.385 ms, heap usage 315.945 MB -> 70.916 MB. [2025-09-25T05:37:54.983Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (5732.127 ms) ====== [2025-09-25T05:37:54.983Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-09-25T05:37:54.983Z] GC before operation: completed in 361.968 ms, heap usage 334.389 MB -> 98.635 MB. [2025-09-25T05:38:01.642Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (6078.593 ms) ====== [2025-09-25T05:38:01.642Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-09-25T05:38:01.642Z] GC before operation: completed in 359.793 ms, heap usage 254.528 MB -> 71.216 MB. [2025-09-25T05:38:08.193Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (7233.372 ms) ====== [2025-09-25T05:38:08.193Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-09-25T05:38:08.637Z] GC before operation: completed in 400.485 ms, heap usage 271.966 MB -> 71.650 MB. [2025-09-25T05:38:16.856Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (7017.320 ms) ====== [2025-09-25T05:38:16.856Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-09-25T05:38:16.856Z] GC before operation: completed in 639.068 ms, heap usage 308.387 MB -> 71.466 MB. [2025-09-25T05:38:22.388Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (6114.249 ms) ====== [2025-09-25T05:38:22.388Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-09-25T05:38:23.001Z] GC before operation: completed in 356.640 ms, heap usage 217.818 MB -> 71.507 MB. [2025-09-25T05:38:30.931Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (7320.584 ms) ====== [2025-09-25T05:38:30.931Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-09-25T05:38:30.931Z] GC before operation: completed in 421.203 ms, heap usage 223.193 MB -> 99.567 MB. [2025-09-25T05:38:37.454Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (7055.971 ms) ====== [2025-09-25T05:38:37.454Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-09-25T05:38:38.080Z] GC before operation: completed in 446.839 ms, heap usage 415.387 MB -> 71.918 MB. [2025-09-25T05:38:44.831Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (6534.022 ms) ====== [2025-09-25T05:38:44.832Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-09-25T05:38:44.832Z] GC before operation: completed in 324.509 ms, heap usage 191.902 MB -> 71.642 MB. [2025-09-25T05:38:50.568Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (5629.258 ms) ====== [2025-09-25T05:38:50.568Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-09-25T05:38:51.087Z] GC before operation: completed in 381.633 ms, heap usage 416.095 MB -> 72.118 MB. [2025-09-25T05:38:57.561Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (5758.900 ms) ====== [2025-09-25T05:38:57.561Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-09-25T05:38:57.561Z] GC before operation: completed in 231.901 ms, heap usage 270.116 MB -> 72.126 MB. [2025-09-25T05:39:03.092Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (5563.853 ms) ====== [2025-09-25T05:39:03.092Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-09-25T05:39:03.092Z] GC before operation: completed in 388.229 ms, heap usage 118.857 MB -> 71.634 MB. [2025-09-25T05:39:09.620Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (5833.845 ms) ====== [2025-09-25T05:39:09.620Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-09-25T05:39:09.620Z] GC before operation: completed in 338.552 ms, heap usage 325.092 MB -> 74.607 MB. [2025-09-25T05:39:14.927Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (5217.409 ms) ====== [2025-09-25T05:39:14.927Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-09-25T05:39:14.927Z] GC before operation: completed in 308.751 ms, heap usage 246.176 MB -> 72.703 MB. [2025-09-25T05:39:19.451Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4949.368 ms) ====== [2025-09-25T05:39:19.451Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-09-25T05:39:19.942Z] GC before operation: completed in 435.659 ms, heap usage 220.782 MB -> 72.677 MB. [2025-09-25T05:39:26.290Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (5426.125 ms) ====== [2025-09-25T05:39:26.290Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-09-25T05:39:26.290Z] GC before operation: completed in 203.174 ms, heap usage 145.347 MB -> 72.517 MB. [2025-09-25T05:39:31.640Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (5861.105 ms) ====== [2025-09-25T05:39:31.640Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-09-25T05:39:31.640Z] GC before operation: completed in 311.687 ms, heap usage 317.267 MB -> 72.802 MB. [2025-09-25T05:39:37.912Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (5958.364 ms) ====== [2025-09-25T05:39:37.912Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-09-25T05:39:38.391Z] GC before operation: completed in 566.291 ms, heap usage 411.410 MB -> 100.597 MB. [2025-09-25T05:39:45.175Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (6262.612 ms) ====== [2025-09-25T05:39:45.175Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-09-25T05:39:45.175Z] GC before operation: completed in 483.462 ms, heap usage 378.453 MB -> 73.044 MB. [2025-09-25T05:39:51.535Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (5845.705 ms) ====== [2025-09-25T05:39:51.535Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-09-25T05:39:51.535Z] GC before operation: completed in 531.833 ms, heap usage 413.428 MB -> 73.325 MB. [2025-09-25T05:39:56.847Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (5438.037 ms) ====== [2025-09-25T05:39:56.847Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-09-25T05:39:57.344Z] GC before operation: completed in 375.969 ms, heap usage 243.880 MB -> 73.226 MB. [2025-09-25T05:40:04.095Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (6244.806 ms) ====== [2025-09-25T05:40:04.095Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-09-25T05:40:04.095Z] GC before operation: completed in 414.173 ms, heap usage 399.182 MB -> 73.740 MB. [2025-09-25T05:40:10.601Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (6472.885 ms) ====== [2025-09-25T05:40:10.601Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-09-25T05:40:10.601Z] GC before operation: completed in 357.893 ms, heap usage 180.165 MB -> 73.120 MB. [2025-09-25T05:40:18.093Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (6511.408 ms) ====== [2025-09-25T05:40:18.093Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-09-25T05:40:18.093Z] GC before operation: completed in 520.047 ms, heap usage 122.133 MB -> 73.256 MB. [2025-09-25T05:40:24.963Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (6372.622 ms) ====== [2025-09-25T05:40:24.964Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-09-25T05:40:24.964Z] GC before operation: completed in 459.802 ms, heap usage 230.663 MB -> 88.670 MB. [2025-09-25T05:40:31.854Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (6704.798 ms) ====== [2025-09-25T05:40:31.854Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-09-25T05:40:31.854Z] GC before operation: completed in 455.869 ms, heap usage 277.764 MB -> 73.771 MB. [2025-09-25T05:40:37.382Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (5712.801 ms) ====== [2025-09-25T05:40:37.382Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-09-25T05:40:37.928Z] GC before operation: completed in 436.944 ms, heap usage 388.009 MB -> 74.036 MB. [2025-09-25T05:40:43.678Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (5859.759 ms) ====== [2025-09-25T05:40:43.678Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-09-25T05:40:44.213Z] GC before operation: completed in 317.544 ms, heap usage 221.506 MB -> 74.036 MB. [2025-09-25T05:40:51.103Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (6286.434 ms) ====== [2025-09-25T05:40:51.103Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-09-25T05:40:51.103Z] GC before operation: completed in 417.333 ms, heap usage 352.556 MB -> 101.819 MB. [2025-09-25T05:40:57.356Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (6584.781 ms) ====== [2025-09-25T05:40:57.356Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-09-25T05:40:57.833Z] GC before operation: completed in 354.449 ms, heap usage 246.017 MB -> 74.468 MB. [2025-09-25T05:41:03.103Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (5365.834 ms) ====== [2025-09-25T05:41:04.763Z] ----------------------------------- [2025-09-25T05:41:04.763Z] renaissance-dec-tree_0_PASSED [2025-09-25T05:41:04.763Z] ----------------------------------- [2025-09-25T05:41:04.763Z] [2025-09-25T05:41:04.763Z] TEST TEARDOWN: [2025-09-25T05:41:04.763Z] Nothing to be done for teardown. [2025-09-25T05:41:04.763Z] renaissance-dec-tree_0 Finish Time: Thu Sep 25 01:41:04 2025 Epoch Time (ms): 1758778864370