[2025-06-27T20:51:50.016Z] Running test renaissance-log-regression_0 ... [2025-06-27T20:51:50.016Z] =============================================== [2025-06-27T20:51:50.479Z] renaissance-log-regression_0 Start Time: Fri Jun 27 13:51:49 2025 Epoch Time (ms): 1751057509888 [2025-06-27T20:51:50.479Z] variation: NoOptions [2025-06-27T20:51:50.479Z] JVM_OPTIONS: [2025-06-27T20:51:50.479Z] { \ [2025-06-27T20:51:50.479Z] echo ""; echo "TEST SETUP:"; \ [2025-06-27T20:51:50.479Z] echo "Nothing to be done for setup."; \ [2025-06-27T20:51:50.479Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17510549304772/renaissance-log-regression_0"; \ [2025-06-27T20:51:50.479Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17510549304772/renaissance-log-regression_0"; \ [2025-06-27T20:51:50.479Z] echo ""; echo "TESTING:"; \ [2025-06-27T20:51:50.479Z] "/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_17510549304772/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-27T20:51:50.479Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_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_17510549304772/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-27T20:51:50.479Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-27T20:51:50.479Z] echo "Nothing to be done for teardown."; \ [2025-06-27T20:51:50.479Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17510549304772/TestTargetResult"; [2025-06-27T20:51:50.479Z] [2025-06-27T20:51:50.479Z] TEST SETUP: [2025-06-27T20:51:50.479Z] Nothing to be done for setup. [2025-06-27T20:51:50.479Z] [2025-06-27T20:51:50.479Z] TESTING: [2025-06-27T20:52:08.497Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-27T20:52:20.574Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-27T20:52:20.574Z] GC before operation: completed in 64.243 ms, heap usage 56.774 MB -> 35.068 MB. [2025-06-27T20:52:51.350Z] ====== log-regression (apache-spark) [default], iteration 0 completed (30974.692 ms) ====== [2025-06-27T20:52:51.350Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-27T20:52:51.350Z] GC before operation: completed in 403.193 ms, heap usage 461.485 MB -> 117.613 MB. [2025-06-27T20:52:57.379Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7186.101 ms) ====== [2025-06-27T20:52:57.379Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-27T20:52:57.834Z] GC before operation: completed in 267.879 ms, heap usage 317.797 MB -> 117.748 MB. [2025-06-27T20:53:05.566Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7443.683 ms) ====== [2025-06-27T20:53:05.566Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-27T20:53:05.566Z] GC before operation: completed in 214.665 ms, heap usage 295.202 MB -> 118.099 MB. [2025-06-27T20:53:11.478Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6038.747 ms) ====== [2025-06-27T20:53:11.478Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-27T20:53:11.929Z] GC before operation: completed in 416.836 ms, heap usage 238.625 MB -> 118.255 MB. [2025-06-27T20:53:19.372Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6891.892 ms) ====== [2025-06-27T20:53:19.372Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-27T20:53:19.372Z] GC before operation: completed in 339.579 ms, heap usage 436.077 MB -> 126.060 MB. [2025-06-27T20:53:25.810Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6838.813 ms) ====== [2025-06-27T20:53:25.810Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-27T20:53:26.250Z] GC before operation: completed in 327.807 ms, heap usage 364.253 MB -> 123.261 MB. [2025-06-27T20:53:32.158Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5414.842 ms) ====== [2025-06-27T20:53:32.158Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-27T20:53:32.158Z] GC before operation: completed in 197.192 ms, heap usage 495.586 MB -> 119.756 MB. [2025-06-27T20:53:37.209Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4937.810 ms) ====== [2025-06-27T20:53:37.209Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-27T20:53:37.209Z] GC before operation: completed in 331.297 ms, heap usage 428.270 MB -> 119.589 MB. [2025-06-27T20:53:43.321Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5535.673 ms) ====== [2025-06-27T20:53:43.321Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-27T20:53:43.321Z] GC before operation: completed in 257.036 ms, heap usage 311.271 MB -> 119.601 MB. [2025-06-27T20:53:48.155Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5382.563 ms) ====== [2025-06-27T20:53:48.155Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-27T20:53:48.917Z] GC before operation: completed in 281.882 ms, heap usage 492.232 MB -> 120.389 MB. [2025-06-27T20:53:54.903Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5851.436 ms) ====== [2025-06-27T20:53:54.903Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-27T20:53:54.903Z] GC before operation: completed in 253.521 ms, heap usage 625.113 MB -> 120.940 MB. [2025-06-27T20:53:58.638Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4040.745 ms) ====== [2025-06-27T20:53:58.638Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-27T20:53:59.120Z] GC before operation: completed in 130.887 ms, heap usage 245.381 MB -> 119.882 MB. [2025-06-27T20:54:03.896Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4523.772 ms) ====== [2025-06-27T20:54:03.896Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-27T20:54:03.896Z] GC before operation: completed in 671.271 ms, heap usage 328.741 MB -> 120.392 MB. [2025-06-27T20:54:08.926Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5003.841 ms) ====== [2025-06-27T20:54:08.926Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-27T20:54:09.364Z] GC before operation: completed in 250.899 ms, heap usage 498.445 MB -> 121.069 MB. [2025-06-27T20:54:14.070Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4914.851 ms) ====== [2025-06-27T20:54:14.070Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-27T20:54:14.513Z] GC before operation: completed in 263.470 ms, heap usage 456.830 MB -> 121.103 MB. [2025-06-27T20:54:19.490Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4525.821 ms) ====== [2025-06-27T20:54:19.490Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-27T20:54:19.490Z] GC before operation: completed in 250.818 ms, heap usage 306.551 MB -> 120.871 MB. [2025-06-27T20:54:25.574Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6368.684 ms) ====== [2025-06-27T20:54:25.574Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-27T20:54:25.975Z] GC before operation: completed in 404.652 ms, heap usage 286.776 MB -> 121.023 MB. [2025-06-27T20:54:30.818Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4417.083 ms) ====== [2025-06-27T20:54:30.818Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-27T20:54:30.818Z] GC before operation: completed in 237.552 ms, heap usage 412.600 MB -> 121.562 MB. [2025-06-27T20:54:35.492Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4749.744 ms) ====== [2025-06-27T20:54:35.492Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-27T20:54:35.940Z] GC before operation: completed in 284.302 ms, heap usage 448.977 MB -> 121.970 MB. [2025-06-27T20:54:40.719Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5135.023 ms) ====== [2025-06-27T20:54:41.590Z] ----------------------------------- [2025-06-27T20:54:41.590Z] renaissance-log-regression_0_PASSED [2025-06-27T20:54:41.590Z] ----------------------------------- [2025-06-27T20:54:41.590Z] [2025-06-27T20:54:41.590Z] TEST TEARDOWN: [2025-06-27T20:54:41.590Z] Nothing to be done for teardown. [2025-06-27T20:54:41.590Z] renaissance-log-regression_0 Finish Time: Fri Jun 27 13:54:41 2025 Epoch Time (ms): 1751057681384