[2025-08-13T20:06:06.727Z] Running test renaissance-log-regression_0 ... [2025-08-13T20:06:06.727Z] =============================================== [2025-08-13T20:06:06.727Z] renaissance-log-regression_0 Start Time: Wed Aug 13 16:06:06 2025 Epoch Time (ms): 1755115566382 [2025-08-13T20:06:06.727Z] variation: NoOptions [2025-08-13T20:06:06.727Z] JVM_OPTIONS: [2025-08-13T20:06:06.727Z] { \ [2025-08-13T20:06:06.727Z] echo ""; echo "TEST SETUP:"; \ [2025-08-13T20:06:06.727Z] echo "Nothing to be done for setup."; \ [2025-08-13T20:06:06.727Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/renaissance-log-regression_0"; \ [2025-08-13T20:06:06.727Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/renaissance-log-regression_0"; \ [2025-08-13T20:06:06.727Z] echo ""; echo "TESTING:"; \ [2025-08-13T20:06:06.727Z] "/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_17551149705909/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-13T20:06:06.727Z] 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_17551149705909/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-13T20:06:06.727Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-13T20:06:06.727Z] echo "Nothing to be done for teardown."; \ [2025-08-13T20:06:06.727Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/TestTargetResult"; [2025-08-13T20:06:06.727Z] [2025-08-13T20:06:06.727Z] TEST SETUP: [2025-08-13T20:06:06.727Z] Nothing to be done for setup. [2025-08-13T20:06:06.727Z] [2025-08-13T20:06:06.727Z] TESTING: [2025-08-13T20:06:09.830Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-13T20:06:11.037Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-13T20:06:11.037Z] GC before operation: completed in 36.271 ms, heap usage 63.667 MB -> 35.170 MB. [2025-08-13T20:06:16.820Z] ====== log-regression (apache-spark) [default], iteration 0 completed (5381.737 ms) ====== [2025-08-13T20:06:16.820Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-13T20:06:16.820Z] GC before operation: completed in 49.211 ms, heap usage 343.225 MB -> 117.207 MB. [2025-08-13T20:06:17.628Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1140.740 ms) ====== [2025-08-13T20:06:17.628Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-13T20:06:17.628Z] GC before operation: completed in 49.956 ms, heap usage 414.396 MB -> 117.961 MB. [2025-08-13T20:06:18.413Z] ====== log-regression (apache-spark) [default], iteration 2 completed (980.953 ms) ====== [2025-08-13T20:06:18.413Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-13T20:06:18.413Z] GC before operation: completed in 41.497 ms, heap usage 355.382 MB -> 118.143 MB. [2025-08-13T20:06:19.682Z] ====== log-regression (apache-spark) [default], iteration 3 completed (890.911 ms) ====== [2025-08-13T20:06:19.682Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-13T20:06:19.682Z] GC before operation: completed in 48.337 ms, heap usage 556.593 MB -> 119.170 MB. [2025-08-13T20:06:21.852Z] ====== log-regression (apache-spark) [default], iteration 4 completed (861.283 ms) ====== [2025-08-13T20:06:21.852Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-13T20:06:21.852Z] GC before operation: completed in 46.960 ms, heap usage 400.882 MB -> 118.908 MB. [2025-08-13T20:06:21.852Z] ====== log-regression (apache-spark) [default], iteration 5 completed (790.652 ms) ====== [2025-08-13T20:06:21.852Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-13T20:06:21.852Z] GC before operation: completed in 51.423 ms, heap usage 355.305 MB -> 118.950 MB. [2025-08-13T20:06:22.205Z] ====== log-regression (apache-spark) [default], iteration 6 completed (775.657 ms) ====== [2025-08-13T20:06:22.205Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-13T20:06:22.205Z] GC before operation: completed in 52.748 ms, heap usage 300.160 MB -> 118.975 MB. [2025-08-13T20:06:22.989Z] ====== log-regression (apache-spark) [default], iteration 7 completed (820.672 ms) ====== [2025-08-13T20:06:22.989Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-13T20:06:22.989Z] GC before operation: completed in 56.233 ms, heap usage 312.288 MB -> 119.252 MB. [2025-08-13T20:06:23.737Z] ====== log-regression (apache-spark) [default], iteration 8 completed (841.804 ms) ====== [2025-08-13T20:06:23.737Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-13T20:06:23.737Z] GC before operation: completed in 53.458 ms, heap usage 320.038 MB -> 119.457 MB. [2025-08-13T20:06:24.505Z] ====== log-regression (apache-spark) [default], iteration 9 completed (756.622 ms) ====== [2025-08-13T20:06:24.505Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-13T20:06:24.505Z] GC before operation: completed in 50.247 ms, heap usage 481.184 MB -> 120.295 MB. [2025-08-13T20:06:25.295Z] ====== log-regression (apache-spark) [default], iteration 10 completed (755.944 ms) ====== [2025-08-13T20:06:25.295Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-13T20:06:25.645Z] GC before operation: completed in 53.383 ms, heap usage 607.932 MB -> 120.882 MB. [2025-08-13T20:06:26.410Z] ====== log-regression (apache-spark) [default], iteration 11 completed (735.934 ms) ====== [2025-08-13T20:06:26.410Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-13T20:06:26.410Z] GC before operation: completed in 51.981 ms, heap usage 561.625 MB -> 120.877 MB. [2025-08-13T20:06:27.206Z] ====== log-regression (apache-spark) [default], iteration 12 completed (726.010 ms) ====== [2025-08-13T20:06:27.206Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-13T20:06:27.206Z] GC before operation: completed in 48.095 ms, heap usage 262.487 MB -> 120.122 MB. [2025-08-13T20:06:27.979Z] ====== log-regression (apache-spark) [default], iteration 13 completed (741.808 ms) ====== [2025-08-13T20:06:27.979Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-13T20:06:27.979Z] GC before operation: completed in 54.034 ms, heap usage 269.311 MB -> 120.353 MB. [2025-08-13T20:06:28.778Z] ====== log-regression (apache-spark) [default], iteration 14 completed (701.784 ms) ====== [2025-08-13T20:06:28.778Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-13T20:06:28.778Z] GC before operation: completed in 56.846 ms, heap usage 694.634 MB -> 122.046 MB. [2025-08-13T20:06:29.545Z] ====== log-regression (apache-spark) [default], iteration 15 completed (716.795 ms) ====== [2025-08-13T20:06:29.545Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-13T20:06:29.545Z] GC before operation: completed in 48.674 ms, heap usage 426.980 MB -> 121.310 MB. [2025-08-13T20:06:30.317Z] ====== log-regression (apache-spark) [default], iteration 16 completed (736.892 ms) ====== [2025-08-13T20:06:30.317Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-13T20:06:30.317Z] GC before operation: completed in 53.077 ms, heap usage 293.575 MB -> 120.993 MB. [2025-08-13T20:06:31.090Z] ====== log-regression (apache-spark) [default], iteration 17 completed (768.849 ms) ====== [2025-08-13T20:06:31.090Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-13T20:06:31.090Z] GC before operation: completed in 57.703 ms, heap usage 289.497 MB -> 121.100 MB. [2025-08-13T20:06:31.847Z] ====== log-regression (apache-spark) [default], iteration 18 completed (720.846 ms) ====== [2025-08-13T20:06:31.847Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-13T20:06:31.847Z] GC before operation: completed in 58.664 ms, heap usage 606.280 MB -> 122.378 MB. [2025-08-13T20:06:32.603Z] ====== log-regression (apache-spark) [default], iteration 19 completed (745.861 ms) ====== [2025-08-13T20:06:32.603Z] ----------------------------------- [2025-08-13T20:06:32.603Z] renaissance-log-regression_0_PASSED [2025-08-13T20:06:32.603Z] ----------------------------------- [2025-08-13T20:06:32.603Z] [2025-08-13T20:06:32.603Z] TEST TEARDOWN: [2025-08-13T20:06:32.603Z] Nothing to be done for teardown. [2025-08-13T20:06:32.603Z] renaissance-log-regression_0 Finish Time: Wed Aug 13 16:06:32 2025 Epoch Time (ms): 1755115592428