[2025-05-07T22:45:34.870Z] Running test renaissance-dec-tree_0 ... [2025-05-07T22:45:34.870Z] =============================================== [2025-05-07T22:45:34.870Z] renaissance-dec-tree_0 Start Time: Wed May 7 15:45:34 2025 Epoch Time (ms): 1746657934285 [2025-05-07T22:45:34.870Z] variation: NoOptions [2025-05-07T22:45:34.870Z] JVM_OPTIONS: [2025-05-07T22:45:34.870Z] { \ [2025-05-07T22:45:34.870Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T22:45:34.870Z] echo "Nothing to be done for setup."; \ [2025-05-07T22:45:34.870Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17466578783808/renaissance-dec-tree_0"; \ [2025-05-07T22:45:34.870Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17466578783808/renaissance-dec-tree_0"; \ [2025-05-07T22:45:34.870Z] echo ""; echo "TESTING:"; \ [2025-05-07T22:45:34.870Z] "/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_17466578783808/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-07T22:45:34.870Z] 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_17466578783808/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T22:45:34.870Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T22:45:34.870Z] echo "Nothing to be done for teardown."; \ [2025-05-07T22:45:34.870Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17466578783808/TestTargetResult"; [2025-05-07T22:45:34.870Z] [2025-05-07T22:45:34.870Z] TEST SETUP: [2025-05-07T22:45:34.870Z] Nothing to be done for setup. [2025-05-07T22:45:34.870Z] [2025-05-07T22:45:34.870Z] TESTING: [2025-05-07T22:45:40.308Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-07T22:45:42.270Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-07T22:45:42.270Z] GC before operation: completed in 35.707 ms, heap usage 110.485 MB -> 34.860 MB. [2025-05-07T22:45:52.164Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8459.517 ms) ====== [2025-05-07T22:45:52.165Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-07T22:45:52.165Z] GC before operation: completed in 57.879 ms, heap usage 166.556 MB -> 68.501 MB. [2025-05-07T22:45:52.608Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1962.759 ms) ====== [2025-05-07T22:45:52.608Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-07T22:45:52.608Z] GC before operation: completed in 66.519 ms, heap usage 280.742 MB -> 69.301 MB. [2025-05-07T22:45:54.601Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1583.232 ms) ====== [2025-05-07T22:45:54.601Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-07T22:45:54.601Z] GC before operation: completed in 55.023 ms, heap usage 125.663 MB -> 68.936 MB. [2025-05-07T22:45:55.995Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1621.242 ms) ====== [2025-05-07T22:45:55.996Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-07T22:45:55.996Z] GC before operation: completed in 58.104 ms, heap usage 243.038 MB -> 69.959 MB. [2025-05-07T22:45:57.353Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1406.866 ms) ====== [2025-05-07T22:45:57.353Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-07T22:45:57.771Z] GC before operation: completed in 63.158 ms, heap usage 378.014 MB -> 70.063 MB. [2025-05-07T22:45:59.203Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1333.636 ms) ====== [2025-05-07T22:45:59.203Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-07T22:45:59.203Z] GC before operation: completed in 72.693 ms, heap usage 370.357 MB -> 70.477 MB. [2025-05-07T22:46:00.618Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1292.227 ms) ====== [2025-05-07T22:46:00.618Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-07T22:46:00.618Z] GC before operation: completed in 81.072 ms, heap usage 356.221 MB -> 70.686 MB. [2025-05-07T22:46:01.494Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1316.564 ms) ====== [2025-05-07T22:46:01.494Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-07T22:46:01.936Z] GC before operation: completed in 61.226 ms, heap usage 384.820 MB -> 70.682 MB. [2025-05-07T22:46:03.309Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1268.184 ms) ====== [2025-05-07T22:46:03.309Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-07T22:46:03.309Z] GC before operation: completed in 68.210 ms, heap usage 219.799 MB -> 70.574 MB. [2025-05-07T22:46:04.194Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1259.988 ms) ====== [2025-05-07T22:46:04.194Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-07T22:46:04.632Z] GC before operation: completed in 75.166 ms, heap usage 181.504 MB -> 70.541 MB. [2025-05-07T22:46:06.012Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1282.987 ms) ====== [2025-05-07T22:46:06.012Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-07T22:46:06.012Z] GC before operation: completed in 66.950 ms, heap usage 175.412 MB -> 70.657 MB. [2025-05-07T22:46:06.868Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1278.684 ms) ====== [2025-05-07T22:46:06.868Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-07T22:46:07.283Z] GC before operation: completed in 62.908 ms, heap usage 334.469 MB -> 71.308 MB. [2025-05-07T22:46:08.151Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1219.126 ms) ====== [2025-05-07T22:46:08.151Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-07T22:46:08.567Z] GC before operation: completed in 63.360 ms, heap usage 220.256 MB -> 71.061 MB. [2025-05-07T22:46:09.448Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1241.270 ms) ====== [2025-05-07T22:46:09.448Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-07T22:46:09.852Z] GC before operation: completed in 67.680 ms, heap usage 340.430 MB -> 71.182 MB. [2025-05-07T22:46:10.716Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1210.098 ms) ====== [2025-05-07T22:46:10.716Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-07T22:46:11.123Z] GC before operation: completed in 66.786 ms, heap usage 351.412 MB -> 71.412 MB. [2025-05-07T22:46:11.991Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1196.839 ms) ====== [2025-05-07T22:46:11.992Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-07T22:46:12.417Z] GC before operation: completed in 68.855 ms, heap usage 335.599 MB -> 71.653 MB. [2025-05-07T22:46:13.307Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1127.180 ms) ====== [2025-05-07T22:46:13.307Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-07T22:46:13.307Z] GC before operation: completed in 65.229 ms, heap usage 218.363 MB -> 71.795 MB. [2025-05-07T22:46:14.738Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1158.876 ms) ====== [2025-05-07T22:46:14.738Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-07T22:46:14.738Z] GC before operation: completed in 68.807 ms, heap usage 179.180 MB -> 84.598 MB. [2025-05-07T22:46:16.117Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1273.831 ms) ====== [2025-05-07T22:46:16.117Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-07T22:46:16.117Z] GC before operation: completed in 68.605 ms, heap usage 261.667 MB -> 84.596 MB. [2025-05-07T22:46:17.501Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1215.743 ms) ====== [2025-05-07T22:46:17.501Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-07T22:46:17.501Z] GC before operation: completed in 63.835 ms, heap usage 175.248 MB -> 72.079 MB. [2025-05-07T22:46:17.501Z] 15:46:16.811 WARN [block-manager-storage-async-thread-pool-65] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_275_piece0, which does not exist [2025-05-07T22:46:18.366Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1208.476 ms) ====== [2025-05-07T22:46:18.366Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-07T22:46:18.804Z] GC before operation: completed in 70.784 ms, heap usage 266.995 MB -> 72.198 MB. [2025-05-07T22:46:19.670Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1194.212 ms) ====== [2025-05-07T22:46:19.670Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-07T22:46:19.670Z] GC before operation: completed in 74.901 ms, heap usage 176.429 MB -> 72.168 MB. [2025-05-07T22:46:21.076Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1175.051 ms) ====== [2025-05-07T22:46:21.076Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-07T22:46:21.076Z] GC before operation: completed in 64.285 ms, heap usage 116.402 MB -> 71.822 MB. [2025-05-07T22:46:22.444Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1289.669 ms) ====== [2025-05-07T22:46:22.444Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-07T22:46:22.444Z] GC before operation: completed in 82.997 ms, heap usage 540.918 MB -> 87.728 MB. [2025-05-07T22:46:23.844Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1232.996 ms) ====== [2025-05-07T22:46:23.844Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-07T22:46:23.844Z] GC before operation: completed in 76.278 ms, heap usage 438.411 MB -> 72.706 MB. [2025-05-07T22:46:24.728Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1146.663 ms) ====== [2025-05-07T22:46:24.728Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-07T22:46:25.143Z] GC before operation: completed in 71.387 ms, heap usage 123.528 MB -> 84.945 MB. [2025-05-07T22:46:26.535Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1327.694 ms) ====== [2025-05-07T22:46:26.535Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-07T22:46:26.535Z] GC before operation: completed in 79.128 ms, heap usage 397.295 MB -> 72.715 MB. [2025-05-07T22:46:27.382Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1080.314 ms) ====== [2025-05-07T22:46:27.382Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-07T22:46:27.382Z] GC before operation: completed in 72.443 ms, heap usage 436.360 MB -> 72.863 MB. [2025-05-07T22:46:28.769Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1151.474 ms) ====== [2025-05-07T22:46:28.769Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-07T22:46:28.769Z] GC before operation: completed in 84.436 ms, heap usage 422.523 MB -> 85.886 MB. [2025-05-07T22:46:30.146Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1156.804 ms) ====== [2025-05-07T22:46:30.146Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-07T22:46:30.146Z] GC before operation: completed in 76.863 ms, heap usage 304.408 MB -> 73.287 MB. [2025-05-07T22:46:31.009Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1150.527 ms) ====== [2025-05-07T22:46:31.009Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-07T22:46:31.414Z] GC before operation: completed in 72.626 ms, heap usage 112.102 MB -> 72.644 MB. [2025-05-07T22:46:32.809Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1389.357 ms) ====== [2025-05-07T22:46:32.809Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-07T22:46:32.809Z] GC before operation: completed in 72.425 ms, heap usage 362.360 MB -> 73.552 MB. [2025-05-07T22:46:33.685Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1115.307 ms) ====== [2025-05-07T22:46:33.685Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-07T22:46:34.141Z] GC before operation: completed in 77.599 ms, heap usage 307.158 MB -> 75.744 MB. [2025-05-07T22:46:35.009Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1099.440 ms) ====== [2025-05-07T22:46:35.009Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-07T22:46:35.009Z] GC before operation: completed in 83.253 ms, heap usage 321.701 MB -> 88.407 MB. [2025-05-07T22:46:36.403Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1199.248 ms) ====== [2025-05-07T22:46:36.403Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-07T22:46:36.403Z] GC before operation: completed in 89.073 ms, heap usage 130.371 MB -> 73.876 MB. [2025-05-07T22:46:37.805Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1287.253 ms) ====== [2025-05-07T22:46:37.805Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-07T22:46:37.805Z] GC before operation: completed in 74.118 ms, heap usage 260.654 MB -> 73.912 MB. [2025-05-07T22:46:39.166Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1130.054 ms) ====== [2025-05-07T22:46:39.166Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-07T22:46:39.166Z] GC before operation: completed in 72.754 ms, heap usage 109.913 MB -> 73.171 MB. [2025-05-07T22:46:40.022Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1286.132 ms) ====== [2025-05-07T22:46:40.022Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-07T22:46:40.420Z] GC before operation: completed in 78.246 ms, heap usage 211.270 MB -> 73.892 MB. [2025-05-07T22:46:41.281Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1108.024 ms) ====== [2025-05-07T22:46:41.281Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-07T22:46:41.705Z] GC before operation: completed in 83.333 ms, heap usage 415.560 MB -> 101.713 MB. [2025-05-07T22:46:42.586Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1203.560 ms) ====== [2025-05-07T22:46:42.979Z] ----------------------------------- [2025-05-07T22:46:42.979Z] renaissance-dec-tree_0_PASSED [2025-05-07T22:46:42.979Z] ----------------------------------- [2025-05-07T22:46:42.979Z] [2025-05-07T22:46:42.979Z] TEST TEARDOWN: [2025-05-07T22:46:42.979Z] Nothing to be done for teardown. [2025-05-07T22:46:42.979Z] renaissance-dec-tree_0 Finish Time: Wed May 7 15:46:42 2025 Epoch Time (ms): 1746658002510