[2025-10-03T20:40:47.981Z] Running test renaissance-log-regression_0 ... [2025-10-03T20:40:47.981Z] =============================================== [2025-10-03T20:40:47.981Z] renaissance-log-regression_0 Start Time: Fri Oct 3 16:40:47 2025 Epoch Time (ms): 1759524047879 [2025-10-03T20:40:47.981Z] variation: NoOptions [2025-10-03T20:40:47.981Z] JVM_OPTIONS: [2025-10-03T20:40:47.981Z] { \ [2025-10-03T20:40:47.981Z] echo ""; echo "TEST SETUP:"; \ [2025-10-03T20:40:47.981Z] echo "Nothing to be done for setup."; \ [2025-10-03T20:40:47.981Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17595234413722/renaissance-log-regression_0"; \ [2025-10-03T20:40:47.981Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17595234413722/renaissance-log-regression_0"; \ [2025-10-03T20:40:47.981Z] echo ""; echo "TESTING:"; \ [2025-10-03T20:40:47.981Z] "/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17595234413722/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-03T20:40:47.981Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17595234413722/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-03T20:40:47.981Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-03T20:40:47.981Z] echo "Nothing to be done for teardown."; \ [2025-10-03T20:40:47.981Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17595234413722/TestTargetResult"; [2025-10-03T20:40:47.981Z] [2025-10-03T20:40:47.981Z] TEST SETUP: [2025-10-03T20:40:47.981Z] Nothing to be done for setup. [2025-10-03T20:40:47.981Z] [2025-10-03T20:40:47.981Z] TESTING: [2025-10-03T20:40:51.132Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-03T20:40:52.363Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-03T20:40:52.363Z] GC before operation: completed in 28.226 ms, heap usage 74.510 MB -> 35.218 MB. [2025-10-03T20:40:58.602Z] ====== log-regression (apache-spark) [default], iteration 0 completed (5487.904 ms) ====== [2025-10-03T20:40:58.602Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-03T20:40:58.602Z] GC before operation: completed in 41.806 ms, heap usage 300.966 MB -> 117.021 MB. [2025-10-03T20:40:59.422Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1166.121 ms) ====== [2025-10-03T20:40:59.422Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-03T20:40:59.422Z] GC before operation: completed in 42.570 ms, heap usage 160.551 MB -> 117.343 MB. [2025-10-03T20:41:00.195Z] ====== log-regression (apache-spark) [default], iteration 2 completed (991.948 ms) ====== [2025-10-03T20:41:00.195Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-03T20:41:00.195Z] GC before operation: completed in 46.086 ms, heap usage 632.008 MB -> 119.085 MB. [2025-10-03T20:41:01.497Z] ====== log-regression (apache-spark) [default], iteration 3 completed (987.859 ms) ====== [2025-10-03T20:41:01.497Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-03T20:41:01.497Z] GC before operation: completed in 49.026 ms, heap usage 309.396 MB -> 118.406 MB. [2025-10-03T20:41:02.279Z] ====== log-regression (apache-spark) [default], iteration 4 completed (886.414 ms) ====== [2025-10-03T20:41:02.279Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-03T20:41:02.279Z] GC before operation: completed in 48.970 ms, heap usage 679.227 MB -> 119.772 MB. [2025-10-03T20:41:03.080Z] ====== log-regression (apache-spark) [default], iteration 5 completed (892.575 ms) ====== [2025-10-03T20:41:03.080Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-03T20:41:03.080Z] GC before operation: completed in 48.769 ms, heap usage 429.282 MB -> 119.177 MB. [2025-10-03T20:41:03.874Z] ====== log-regression (apache-spark) [default], iteration 6 completed (818.349 ms) ====== [2025-10-03T20:41:03.874Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-03T20:41:03.874Z] GC before operation: completed in 49.245 ms, heap usage 632.690 MB -> 120.090 MB. [2025-10-03T20:41:05.120Z] ====== log-regression (apache-spark) [default], iteration 7 completed (813.567 ms) ====== [2025-10-03T20:41:05.120Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-03T20:41:05.120Z] GC before operation: completed in 47.572 ms, heap usage 635.314 MB -> 120.349 MB. [2025-10-03T20:41:05.896Z] ====== log-regression (apache-spark) [default], iteration 8 completed (858.468 ms) ====== [2025-10-03T20:41:05.896Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-03T20:41:05.896Z] GC before operation: completed in 51.637 ms, heap usage 729.085 MB -> 121.441 MB. [2025-10-03T20:41:06.668Z] ====== log-regression (apache-spark) [default], iteration 9 completed (794.841 ms) ====== [2025-10-03T20:41:06.668Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-03T20:41:06.668Z] GC before operation: completed in 56.495 ms, heap usage 703.813 MB -> 121.077 MB. [2025-10-03T20:41:07.465Z] ====== log-regression (apache-spark) [default], iteration 10 completed (810.721 ms) ====== [2025-10-03T20:41:07.465Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-03T20:41:07.465Z] GC before operation: completed in 48.651 ms, heap usage 469.903 MB -> 120.368 MB. [2025-10-03T20:41:08.254Z] ====== log-regression (apache-spark) [default], iteration 11 completed (782.532 ms) ====== [2025-10-03T20:41:08.254Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-03T20:41:08.254Z] GC before operation: completed in 51.881 ms, heap usage 292.684 MB -> 120.018 MB. [2025-10-03T20:41:09.020Z] ====== log-regression (apache-spark) [default], iteration 12 completed (794.244 ms) ====== [2025-10-03T20:41:09.020Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-03T20:41:09.377Z] GC before operation: completed in 54.304 ms, heap usage 562.489 MB -> 121.086 MB. [2025-10-03T20:41:10.151Z] ====== log-regression (apache-spark) [default], iteration 13 completed (780.860 ms) ====== [2025-10-03T20:41:10.151Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-03T20:41:10.151Z] GC before operation: completed in 47.246 ms, heap usage 147.717 MB -> 120.165 MB. [2025-10-03T20:41:10.913Z] ====== log-regression (apache-spark) [default], iteration 14 completed (808.825 ms) ====== [2025-10-03T20:41:10.913Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-03T20:41:10.913Z] GC before operation: completed in 55.434 ms, heap usage 268.794 MB -> 120.365 MB. [2025-10-03T20:41:11.683Z] ====== log-regression (apache-spark) [default], iteration 15 completed (831.970 ms) ====== [2025-10-03T20:41:11.683Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-03T20:41:11.683Z] GC before operation: completed in 54.479 ms, heap usage 473.264 MB -> 121.323 MB. [2025-10-03T20:41:12.447Z] ====== log-regression (apache-spark) [default], iteration 16 completed (769.989 ms) ====== [2025-10-03T20:41:12.447Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-03T20:41:12.447Z] GC before operation: completed in 52.320 ms, heap usage 466.023 MB -> 121.502 MB. [2025-10-03T20:41:13.241Z] ====== log-regression (apache-spark) [default], iteration 17 completed (790.766 ms) ====== [2025-10-03T20:41:13.241Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-03T20:41:13.603Z] GC before operation: completed in 56.639 ms, heap usage 515.822 MB -> 121.892 MB. [2025-10-03T20:41:14.364Z] ====== log-regression (apache-spark) [default], iteration 18 completed (763.716 ms) ====== [2025-10-03T20:41:14.364Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-03T20:41:14.364Z] GC before operation: completed in 51.069 ms, heap usage 406.564 MB -> 121.726 MB. [2025-10-03T20:41:15.174Z] ====== log-regression (apache-spark) [default], iteration 19 completed (747.790 ms) ====== [2025-10-03T20:41:15.174Z] ----------------------------------- [2025-10-03T20:41:15.174Z] renaissance-log-regression_0_PASSED [2025-10-03T20:41:15.174Z] ----------------------------------- [2025-10-03T20:41:15.174Z] [2025-10-03T20:41:15.174Z] TEST TEARDOWN: [2025-10-03T20:41:15.174Z] Nothing to be done for teardown. [2025-10-03T20:41:15.174Z] renaissance-log-regression_0 Finish Time: Fri Oct 3 16:41:14 2025 Epoch Time (ms): 1759524074947