[2025-11-13T01:05:23.688Z] Running test renaissance-log-regression_0 ... [2025-11-13T01:05:23.688Z] =============================================== [2025-11-13T01:05:23.688Z] renaissance-log-regression_0 Start Time: Thu Nov 13 01:05:23 2025 Epoch Time (ms): 1762995923386 [2025-11-13T01:05:23.688Z] variation: NoOptions [2025-11-13T01:05:23.688Z] JVM_OPTIONS: [2025-11-13T01:05:23.688Z] { \ [2025-11-13T01:05:23.688Z] echo ""; echo "TEST SETUP:"; \ [2025-11-13T01:05:23.688Z] echo "Nothing to be done for setup."; \ [2025-11-13T01:05:23.688Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/renaissance-log-regression_0"; \ [2025-11-13T01:05:23.688Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/renaissance-log-regression_0"; \ [2025-11-13T01:05:23.688Z] echo ""; echo "TESTING:"; \ [2025-11-13T01:05:23.688Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-13T01:05:23.688Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-13T01:05:23.688Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-13T01:05:23.688Z] echo "Nothing to be done for teardown."; \ [2025-11-13T01:05:23.688Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/TestTargetResult"; [2025-11-13T01:05:23.688Z] [2025-11-13T01:05:23.688Z] TEST SETUP: [2025-11-13T01:05:23.688Z] Nothing to be done for setup. [2025-11-13T01:05:23.688Z] [2025-11-13T01:05:23.688Z] TESTING: [2025-11-13T01:05:28.469Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-13T01:05:31.442Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-13T01:05:31.442Z] GC before operation: completed in 53.619 ms, heap usage 71.984 MB -> 34.904 MB. [2025-11-13T01:05:44.617Z] ====== log-regression (apache-spark) [default], iteration 0 completed (13065.061 ms) ====== [2025-11-13T01:05:44.617Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-13T01:05:44.617Z] GC before operation: completed in 118.747 ms, heap usage 855.741 MB -> 118.090 MB. [2025-11-13T01:05:47.610Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3289.687 ms) ====== [2025-11-13T01:05:47.610Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-13T01:05:47.976Z] GC before operation: completed in 124.184 ms, heap usage 151.891 MB -> 116.732 MB. [2025-11-13T01:05:50.960Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2704.116 ms) ====== [2025-11-13T01:05:50.960Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-13T01:05:50.960Z] GC before operation: completed in 100.549 ms, heap usage 383.613 MB -> 117.622 MB. [2025-11-13T01:05:53.242Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2391.199 ms) ====== [2025-11-13T01:05:53.242Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-13T01:05:53.242Z] GC before operation: completed in 100.296 ms, heap usage 586.576 MB -> 118.858 MB. [2025-11-13T01:05:55.513Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2278.766 ms) ====== [2025-11-13T01:05:55.513Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-13T01:05:55.513Z] GC before operation: completed in 112.534 ms, heap usage 503.355 MB -> 118.756 MB. [2025-11-13T01:05:57.798Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2004.818 ms) ====== [2025-11-13T01:05:57.798Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-13T01:05:57.798Z] GC before operation: completed in 108.420 ms, heap usage 283.776 MB -> 118.103 MB. [2025-11-13T01:06:00.102Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1998.403 ms) ====== [2025-11-13T01:06:00.102Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-13T01:06:00.102Z] GC before operation: completed in 106.787 ms, heap usage 257.315 MB -> 118.198 MB. [2025-11-13T01:06:01.786Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2003.666 ms) ====== [2025-11-13T01:06:01.786Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-13T01:06:01.786Z] GC before operation: completed in 108.475 ms, heap usage 611.181 MB -> 119.743 MB. [2025-11-13T01:06:04.057Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1942.943 ms) ====== [2025-11-13T01:06:04.057Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-13T01:06:04.057Z] GC before operation: completed in 109.198 ms, heap usage 329.066 MB -> 118.878 MB. [2025-11-13T01:06:05.736Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1785.894 ms) ====== [2025-11-13T01:06:05.736Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-13T01:06:05.736Z] GC before operation: completed in 108.759 ms, heap usage 618.054 MB -> 120.192 MB. [2025-11-13T01:06:08.010Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1911.394 ms) ====== [2025-11-13T01:06:08.010Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-13T01:06:08.010Z] GC before operation: completed in 113.448 ms, heap usage 279.226 MB -> 119.027 MB. [2025-11-13T01:06:09.704Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1973.836 ms) ====== [2025-11-13T01:06:09.704Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-13T01:06:10.036Z] GC before operation: completed in 107.196 ms, heap usage 286.275 MB -> 119.347 MB. [2025-11-13T01:06:11.721Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1738.975 ms) ====== [2025-11-13T01:06:11.721Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-13T01:06:11.721Z] GC before operation: completed in 107.892 ms, heap usage 461.457 MB -> 120.187 MB. [2025-11-13T01:06:13.994Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1826.733 ms) ====== [2025-11-13T01:06:13.994Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-13T01:06:13.994Z] GC before operation: completed in 110.123 ms, heap usage 318.162 MB -> 119.706 MB. [2025-11-13T01:06:15.670Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1908.314 ms) ====== [2025-11-13T01:06:15.670Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-13T01:06:15.670Z] GC before operation: completed in 113.219 ms, heap usage 286.705 MB -> 119.837 MB. [2025-11-13T01:06:17.352Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1725.552 ms) ====== [2025-11-13T01:06:17.353Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-13T01:06:17.689Z] GC before operation: completed in 118.272 ms, heap usage 261.369 MB -> 119.925 MB. [2025-11-13T01:06:19.369Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1826.678 ms) ====== [2025-11-13T01:06:19.370Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-13T01:06:19.702Z] GC before operation: completed in 119.130 ms, heap usage 437.526 MB -> 120.857 MB. [2025-11-13T01:06:21.384Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1718.960 ms) ====== [2025-11-13T01:06:21.384Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-13T01:06:21.384Z] GC before operation: completed in 120.081 ms, heap usage 592.066 MB -> 121.587 MB. [2025-11-13T01:06:23.061Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1714.237 ms) ====== [2025-11-13T01:06:23.061Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-13T01:06:23.394Z] GC before operation: completed in 120.582 ms, heap usage 441.322 MB -> 121.156 MB. [2025-11-13T01:06:25.067Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1748.675 ms) ====== [2025-11-13T01:06:25.067Z] ----------------------------------- [2025-11-13T01:06:25.067Z] renaissance-log-regression_0_PASSED [2025-11-13T01:06:25.067Z] ----------------------------------- [2025-11-13T01:06:25.067Z] [2025-11-13T01:06:25.067Z] TEST TEARDOWN: [2025-11-13T01:06:25.067Z] Nothing to be done for teardown. [2025-11-13T01:06:25.402Z] renaissance-log-regression_0 Finish Time: Thu Nov 13 01:06:25 2025 Epoch Time (ms): 1762995985063