[2025-11-19T22:05:31.832Z] Running test renaissance-dec-tree_0 ... [2025-11-19T22:05:31.832Z] =============================================== [2025-11-19T22:05:31.832Z] renaissance-dec-tree_0 Start Time: Wed Nov 19 17:05:31 2025 Epoch Time (ms): 1763589931504 [2025-11-19T22:05:31.832Z] variation: NoOptions [2025-11-19T22:05:31.832Z] JVM_OPTIONS: [2025-11-19T22:05:31.832Z] { \ [2025-11-19T22:05:31.832Z] echo ""; echo "TEST SETUP:"; \ [2025-11-19T22:05:31.832Z] echo "Nothing to be done for setup."; \ [2025-11-19T22:05:31.833Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635896256067/renaissance-dec-tree_0"; \ [2025-11-19T22:05:31.833Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635896256067/renaissance-dec-tree_0"; \ [2025-11-19T22:05:31.833Z] echo ""; echo "TESTING:"; \ [2025-11-19T22:05:31.833Z] "/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_17635896256067/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-11-19T22:05:31.833Z] 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_17635896256067/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-19T22:05:31.833Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-19T22:05:31.833Z] echo "Nothing to be done for teardown."; \ [2025-11-19T22:05:31.833Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635896256067/TestTargetResult"; [2025-11-19T22:05:31.833Z] [2025-11-19T22:05:31.833Z] TEST SETUP: [2025-11-19T22:05:31.833Z] Nothing to be done for setup. [2025-11-19T22:05:31.833Z] [2025-11-19T22:05:31.833Z] TESTING: [2025-11-19T22:05:36.955Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-19T22:05:38.769Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-11-19T22:05:38.769Z] GC before operation: completed in 29.040 ms, heap usage 43.252 MB -> 35.072 MB. [2025-11-19T22:05:45.189Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6637.950 ms) ====== [2025-11-19T22:05:45.189Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-11-19T22:05:45.191Z] GC before operation: completed in 65.607 ms, heap usage 278.632 MB -> 69.152 MB. [2025-11-19T22:05:47.136Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1664.015 ms) ====== [2025-11-19T22:05:47.136Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-11-19T22:05:47.136Z] GC before operation: completed in 48.897 ms, heap usage 260.781 MB -> 69.602 MB. [2025-11-19T22:05:47.940Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1216.268 ms) ====== [2025-11-19T22:05:47.940Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-11-19T22:05:48.309Z] GC before operation: completed in 57.670 ms, heap usage 144.125 MB -> 69.419 MB. [2025-11-19T22:05:49.583Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1197.154 ms) ====== [2025-11-19T22:05:49.583Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-11-19T22:05:49.583Z] GC before operation: completed in 64.442 ms, heap usage 359.811 MB -> 70.125 MB. [2025-11-19T22:05:50.833Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1155.828 ms) ====== [2025-11-19T22:05:50.833Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-11-19T22:05:50.833Z] GC before operation: completed in 49.651 ms, heap usage 342.182 MB -> 70.351 MB. [2025-11-19T22:05:51.604Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1073.360 ms) ====== [2025-11-19T22:05:51.604Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-11-19T22:05:51.604Z] GC before operation: completed in 69.206 ms, heap usage 157.530 MB -> 70.100 MB. [2025-11-19T22:05:52.874Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1143.726 ms) ====== [2025-11-19T22:05:52.874Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-11-19T22:05:52.874Z] GC before operation: completed in 64.064 ms, heap usage 363.509 MB -> 70.806 MB. [2025-11-19T22:05:54.161Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1135.414 ms) ====== [2025-11-19T22:05:54.161Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-11-19T22:05:54.161Z] GC before operation: completed in 63.862 ms, heap usage 179.703 MB -> 70.520 MB. [2025-11-19T22:05:55.418Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (958.447 ms) ====== [2025-11-19T22:05:55.418Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-11-19T22:05:55.418Z] GC before operation: completed in 68.376 ms, heap usage 381.849 MB -> 71.125 MB. [2025-11-19T22:05:56.216Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (951.167 ms) ====== [2025-11-19T22:05:56.216Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-11-19T22:05:56.216Z] GC before operation: completed in 47.593 ms, heap usage 116.949 MB -> 70.486 MB. [2025-11-19T22:05:57.010Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (815.003 ms) ====== [2025-11-19T22:05:57.010Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-11-19T22:05:57.010Z] GC before operation: completed in 50.702 ms, heap usage 247.768 MB -> 71.208 MB. [2025-11-19T22:05:57.788Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (823.138 ms) ====== [2025-11-19T22:05:57.789Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-11-19T22:05:57.789Z] GC before operation: completed in 52.371 ms, heap usage 313.884 MB -> 71.466 MB. [2025-11-19T22:05:59.103Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1079.369 ms) ====== [2025-11-19T22:05:59.103Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-11-19T22:05:59.103Z] GC before operation: completed in 63.125 ms, heap usage 122.968 MB -> 70.992 MB. [2025-11-19T22:05:59.887Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (970.307 ms) ====== [2025-11-19T22:05:59.887Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-11-19T22:06:00.271Z] GC before operation: completed in 63.828 ms, heap usage 248.390 MB -> 71.672 MB. [2025-11-19T22:06:01.107Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (998.159 ms) ====== [2025-11-19T22:06:01.107Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-11-19T22:06:01.107Z] GC before operation: completed in 61.011 ms, heap usage 311.289 MB -> 71.828 MB. [2025-11-19T22:06:02.430Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (926.125 ms) ====== [2025-11-19T22:06:02.430Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-11-19T22:06:02.430Z] GC before operation: completed in 70.510 ms, heap usage 122.891 MB -> 71.405 MB. [2025-11-19T22:06:03.326Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (956.572 ms) ====== [2025-11-19T22:06:03.326Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-11-19T22:06:03.326Z] GC before operation: completed in 74.711 ms, heap usage 243.686 MB -> 83.125 MB. [2025-11-19T22:06:04.135Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (944.267 ms) ====== [2025-11-19T22:06:04.135Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-11-19T22:06:04.135Z] GC before operation: completed in 70.105 ms, heap usage 267.498 MB -> 72.146 MB. [2025-11-19T22:06:04.953Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (889.459 ms) ====== [2025-11-19T22:06:04.953Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-11-19T22:06:05.334Z] GC before operation: completed in 58.559 ms, heap usage 299.231 MB -> 72.358 MB. [2025-11-19T22:06:06.120Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (971.262 ms) ====== [2025-11-19T22:06:06.120Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-11-19T22:06:06.120Z] GC before operation: completed in 69.569 ms, heap usage 107.234 MB -> 72.063 MB. [2025-11-19T22:06:07.466Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (930.337 ms) ====== [2025-11-19T22:06:07.466Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-11-19T22:06:07.466Z] GC before operation: completed in 79.085 ms, heap usage 161.072 MB -> 72.318 MB. [2025-11-19T22:06:08.319Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (990.883 ms) ====== [2025-11-19T22:06:08.319Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-11-19T22:06:08.319Z] GC before operation: completed in 82.080 ms, heap usage 190.955 MB -> 88.898 MB. [2025-11-19T22:06:09.161Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (829.960 ms) ====== [2025-11-19T22:06:09.161Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-11-19T22:06:09.161Z] GC before operation: completed in 66.586 ms, heap usage 380.593 MB -> 72.812 MB. [2025-11-19T22:06:10.459Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (951.829 ms) ====== [2025-11-19T22:06:10.459Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-11-19T22:06:10.459Z] GC before operation: completed in 85.199 ms, heap usage 294.224 MB -> 83.909 MB. [2025-11-19T22:06:11.283Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (928.681 ms) ====== [2025-11-19T22:06:11.283Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-11-19T22:06:11.283Z] GC before operation: completed in 85.833 ms, heap usage 219.052 MB -> 72.928 MB. [2025-11-19T22:06:12.107Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (926.544 ms) ====== [2025-11-19T22:06:12.107Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-11-19T22:06:12.107Z] GC before operation: completed in 75.173 ms, heap usage 348.548 MB -> 73.199 MB. [2025-11-19T22:06:13.411Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (959.449 ms) ====== [2025-11-19T22:06:13.411Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-11-19T22:06:13.411Z] GC before operation: completed in 66.174 ms, heap usage 285.751 MB -> 84.165 MB. [2025-11-19T22:06:14.189Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (845.235 ms) ====== [2025-11-19T22:06:14.189Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-11-19T22:06:14.189Z] GC before operation: completed in 60.945 ms, heap usage 238.728 MB -> 73.396 MB. [2025-11-19T22:06:14.993Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (783.165 ms) ====== [2025-11-19T22:06:14.993Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-11-19T22:06:14.993Z] GC before operation: completed in 52.452 ms, heap usage 412.740 MB -> 73.472 MB. [2025-11-19T22:06:15.851Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (906.434 ms) ====== [2025-11-19T22:06:15.851Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-11-19T22:06:15.851Z] GC before operation: completed in 82.591 ms, heap usage 399.880 MB -> 84.719 MB. [2025-11-19T22:06:17.126Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (886.672 ms) ====== [2025-11-19T22:06:17.126Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-11-19T22:06:17.126Z] GC before operation: completed in 77.469 ms, heap usage 261.300 MB -> 79.147 MB. [2025-11-19T22:06:17.126Z] 17:06:16.774 WARN [block-manager-storage-async-thread-pool-9] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1485_2, which does not exist [2025-11-19T22:06:17.920Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (868.527 ms) ====== [2025-11-19T22:06:17.920Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-11-19T22:06:17.920Z] GC before operation: completed in 64.193 ms, heap usage 329.890 MB -> 73.744 MB. [2025-11-19T22:06:18.703Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (809.920 ms) ====== [2025-11-19T22:06:18.703Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-11-19T22:06:18.703Z] GC before operation: completed in 76.976 ms, heap usage 393.479 MB -> 79.227 MB. [2025-11-19T22:06:19.487Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (804.274 ms) ====== [2025-11-19T22:06:19.487Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-11-19T22:06:19.487Z] GC before operation: completed in 49.755 ms, heap usage 330.409 MB -> 73.878 MB. [2025-11-19T22:06:20.275Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (740.194 ms) ====== [2025-11-19T22:06:20.275Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-11-19T22:06:20.275Z] GC before operation: completed in 53.288 ms, heap usage 256.720 MB -> 73.952 MB. [2025-11-19T22:06:21.053Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (632.493 ms) ====== [2025-11-19T22:06:21.053Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-11-19T22:06:21.053Z] GC before operation: completed in 48.417 ms, heap usage 231.756 MB -> 74.103 MB. [2025-11-19T22:06:21.858Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (620.003 ms) ====== [2025-11-19T22:06:21.858Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-11-19T22:06:21.858Z] GC before operation: completed in 54.128 ms, heap usage 352.067 MB -> 74.198 MB. [2025-11-19T22:06:22.630Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (616.630 ms) ====== [2025-11-19T22:06:22.630Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-11-19T22:06:22.630Z] GC before operation: completed in 57.505 ms, heap usage 394.349 MB -> 74.322 MB. [2025-11-19T22:06:22.996Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (605.294 ms) ====== [2025-11-19T22:06:22.996Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-11-19T22:06:22.996Z] GC before operation: completed in 55.790 ms, heap usage 399.687 MB -> 74.593 MB. [2025-11-19T22:06:23.763Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (612.482 ms) ====== [2025-11-19T22:06:23.763Z] ----------------------------------- [2025-11-19T22:06:23.763Z] renaissance-dec-tree_0_PASSED [2025-11-19T22:06:23.763Z] ----------------------------------- [2025-11-19T22:06:23.763Z] [2025-11-19T22:06:23.763Z] TEST TEARDOWN: [2025-11-19T22:06:23.763Z] Nothing to be done for teardown. [2025-11-19T22:06:23.763Z] renaissance-dec-tree_0 Finish Time: Wed Nov 19 17:06:23 2025 Epoch Time (ms): 1763589983690