[2025-07-01T20:17:42.670Z] Running test renaissance-log-regression_0 ... [2025-07-01T20:17:42.670Z] =============================================== [2025-07-01T20:17:42.670Z] renaissance-log-regression_0 Start Time: Tue Jul 1 20:17:42 2025 Epoch Time (ms): 1751401062653 [2025-07-01T20:17:42.670Z] variation: NoOptions [2025-07-01T20:17:43.002Z] JVM_OPTIONS: [2025-07-01T20:17:43.002Z] { \ [2025-07-01T20:17:43.002Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T20:17:43.002Z] echo "Nothing to be done for setup."; \ [2025-07-01T20:17:43.002Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17513947085849/renaissance-log-regression_0"; \ [2025-07-01T20:17:43.002Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17513947085849/renaissance-log-regression_0"; \ [2025-07-01T20:17:43.002Z] echo ""; echo "TESTING:"; \ [2025-07-01T20:17:43.002Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/jdkbinary/j2sdk-image/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 "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17513947085849/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-01T20:17:43.002Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17513947085849/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T20:17:43.002Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T20:17:43.002Z] echo "Nothing to be done for teardown."; \ [2025-07-01T20:17:43.002Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17513947085849/TestTargetResult"; [2025-07-01T20:17:43.002Z] [2025-07-01T20:17:43.002Z] TEST SETUP: [2025-07-01T20:17:43.002Z] Nothing to be done for setup. [2025-07-01T20:17:43.002Z] [2025-07-01T20:17:43.002Z] TESTING: [2025-07-01T20:18:06.008Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T20:18:17.051Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-01T20:18:17.377Z] GC before operation: completed in 256.832 ms, heap usage 54.403 MB -> 35.426 MB. [2025-07-01T20:19:15.159Z] ====== log-regression (apache-spark) [default], iteration 0 completed (55196.790 ms) ====== [2025-07-01T20:19:15.159Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-01T20:19:15.159Z] GC before operation: completed in 600.691 ms, heap usage 456.739 MB -> 123.596 MB. [2025-07-01T20:19:25.988Z] ====== log-regression (apache-spark) [default], iteration 1 completed (11757.116 ms) ====== [2025-07-01T20:19:25.988Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-01T20:19:25.988Z] GC before operation: completed in 665.742 ms, heap usage 354.579 MB -> 123.671 MB. [2025-07-01T20:19:36.834Z] ====== log-regression (apache-spark) [default], iteration 2 completed (9779.948 ms) ====== [2025-07-01T20:19:36.834Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-01T20:19:36.834Z] GC before operation: completed in 678.704 ms, heap usage 175.353 MB -> 123.756 MB. [2025-07-01T20:19:44.097Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8209.218 ms) ====== [2025-07-01T20:19:44.097Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-01T20:19:44.820Z] GC before operation: completed in 696.534 ms, heap usage 228.092 MB -> 124.132 MB. [2025-07-01T20:19:54.341Z] ====== log-regression (apache-spark) [default], iteration 4 completed (8422.781 ms) ====== [2025-07-01T20:19:54.341Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-01T20:19:54.341Z] GC before operation: completed in 704.576 ms, heap usage 805.069 MB -> 125.958 MB. [2025-07-01T20:20:01.652Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7460.299 ms) ====== [2025-07-01T20:20:01.652Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-01T20:20:02.359Z] GC before operation: completed in 728.048 ms, heap usage 681.156 MB -> 125.902 MB. [2025-07-01T20:20:09.630Z] ====== log-regression (apache-spark) [default], iteration 6 completed (7030.704 ms) ====== [2025-07-01T20:20:09.630Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-01T20:20:09.962Z] GC before operation: completed in 744.886 ms, heap usage 680.647 MB -> 126.114 MB. [2025-07-01T20:20:17.231Z] ====== log-regression (apache-spark) [default], iteration 7 completed (7004.496 ms) ====== [2025-07-01T20:20:17.231Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-01T20:20:17.947Z] GC before operation: completed in 783.941 ms, heap usage 556.868 MB -> 125.996 MB. [2025-07-01T20:20:25.232Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7094.782 ms) ====== [2025-07-01T20:20:25.232Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-01T20:20:25.564Z] GC before operation: completed in 811.769 ms, heap usage 319.935 MB -> 125.467 MB. [2025-07-01T20:20:32.833Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6807.232 ms) ====== [2025-07-01T20:20:32.833Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-01T20:20:33.164Z] GC before operation: completed in 795.167 ms, heap usage 390.579 MB -> 125.738 MB. [2025-07-01T20:20:40.594Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6408.577 ms) ====== [2025-07-01T20:20:40.594Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-01T20:20:40.594Z] GC before operation: completed in 824.433 ms, heap usage 256.122 MB -> 125.803 MB. [2025-07-01T20:20:47.864Z] ====== log-regression (apache-spark) [default], iteration 11 completed (7288.107 ms) ====== [2025-07-01T20:20:47.864Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-01T20:20:48.593Z] GC before operation: completed in 880.475 ms, heap usage 786.374 MB -> 127.507 MB. [2025-07-01T20:20:55.862Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6454.655 ms) ====== [2025-07-01T20:20:55.862Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-01T20:20:55.862Z] GC before operation: completed in 841.601 ms, heap usage 820.539 MB -> 127.804 MB. [2025-07-01T20:21:03.136Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6964.451 ms) ====== [2025-07-01T20:21:03.136Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-01T20:21:03.860Z] GC before operation: completed in 866.367 ms, heap usage 1.191 GB -> 128.005 MB. [2025-07-01T20:21:11.119Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6755.594 ms) ====== [2025-07-01T20:21:11.119Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-01T20:21:11.451Z] GC before operation: completed in 906.042 ms, heap usage 490.989 MB -> 127.050 MB. [2025-07-01T20:21:18.896Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6683.250 ms) ====== [2025-07-01T20:21:18.896Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-01T20:21:18.896Z] GC before operation: completed in 858.810 ms, heap usage 323.514 MB -> 126.920 MB. [2025-07-01T20:21:26.161Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6568.078 ms) ====== [2025-07-01T20:21:26.161Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-01T20:21:26.494Z] GC before operation: completed in 879.189 ms, heap usage 506.169 MB -> 127.718 MB. [2025-07-01T20:21:33.745Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6367.688 ms) ====== [2025-07-01T20:21:33.745Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-01T20:21:33.745Z] GC before operation: completed in 906.001 ms, heap usage 447.752 MB -> 128.770 MB. [2025-07-01T20:21:40.999Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6316.158 ms) ====== [2025-07-01T20:21:40.999Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-01T20:21:40.999Z] GC before operation: completed in 926.377 ms, heap usage 423.411 MB -> 127.809 MB. [2025-07-01T20:21:48.275Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6104.614 ms) ====== [2025-07-01T20:21:48.604Z] ----------------------------------- [2025-07-01T20:21:48.604Z] renaissance-log-regression_0_PASSED [2025-07-01T20:21:48.604Z] ----------------------------------- [2025-07-01T20:21:48.946Z] [2025-07-01T20:21:48.946Z] TEST TEARDOWN: [2025-07-01T20:21:48.946Z] Nothing to be done for teardown. [2025-07-01T20:21:48.946Z] renaissance-log-regression_0 Finish Time: Tue Jul 1 20:21:48 2025 Epoch Time (ms): 1751401308721