[2025-11-06T00:47:26.641Z] Running test renaissance-log-regression_0 ... [2025-11-06T00:47:26.641Z] =============================================== [2025-11-06T00:47:26.641Z] renaissance-log-regression_0 Start Time: Thu Nov 6 00:47:26 2025 Epoch Time (ms): 1762390046178 [2025-11-06T00:47:26.641Z] variation: NoOptions [2025-11-06T00:47:26.641Z] JVM_OPTIONS: [2025-11-06T00:47:26.641Z] { \ [2025-11-06T00:47:26.641Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T00:47:26.641Z] echo "Nothing to be done for setup."; \ [2025-11-06T00:47:26.641Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-log-regression_0"; \ [2025-11-06T00:47:26.641Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-log-regression_0"; \ [2025-11-06T00:47:26.641Z] echo ""; echo "TESTING:"; \ [2025-11-06T00:47:26.641Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-06T00:47:26.641Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T00:47:26.641Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T00:47:26.641Z] echo "Nothing to be done for teardown."; \ [2025-11-06T00:47:26.641Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/TestTargetResult"; [2025-11-06T00:47:26.641Z] [2025-11-06T00:47:26.641Z] TEST SETUP: [2025-11-06T00:47:26.641Z] Nothing to be done for setup. [2025-11-06T00:47:26.641Z] [2025-11-06T00:47:26.641Z] TESTING: [2025-11-06T00:47:31.165Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-06T00:47:33.112Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-06T00:47:33.112Z] GC before operation: completed in 49.941 ms, heap usage 50.971 MB -> 35.042 MB. [2025-11-06T00:47:45.319Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11104.989 ms) ====== [2025-11-06T00:47:45.319Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-06T00:47:45.319Z] GC before operation: completed in 160.173 ms, heap usage 264.412 MB -> 116.818 MB. [2025-11-06T00:47:48.090Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3388.217 ms) ====== [2025-11-06T00:47:48.090Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-06T00:47:48.090Z] GC before operation: completed in 72.241 ms, heap usage 286.324 MB -> 117.416 MB. [2025-11-06T00:47:50.840Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2871.705 ms) ====== [2025-11-06T00:47:50.840Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-06T00:47:50.840Z] GC before operation: completed in 92.450 ms, heap usage 250.995 MB -> 117.662 MB. [2025-11-06T00:47:53.610Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2781.085 ms) ====== [2025-11-06T00:47:53.610Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-06T00:47:53.610Z] GC before operation: completed in 141.874 ms, heap usage 294.208 MB -> 118.176 MB. [2025-11-06T00:47:56.331Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2456.752 ms) ====== [2025-11-06T00:47:56.331Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-06T00:47:56.331Z] GC before operation: completed in 103.547 ms, heap usage 295.678 MB -> 118.339 MB. [2025-11-06T00:47:59.047Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2547.820 ms) ====== [2025-11-06T00:47:59.047Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-06T00:47:59.047Z] GC before operation: completed in 80.903 ms, heap usage 229.030 MB -> 118.351 MB. [2025-11-06T00:48:00.997Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2118.385 ms) ====== [2025-11-06T00:48:00.997Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-06T00:48:00.998Z] GC before operation: completed in 116.781 ms, heap usage 292.983 MB -> 118.739 MB. [2025-11-06T00:48:02.944Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2127.481 ms) ====== [2025-11-06T00:48:02.944Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-06T00:48:03.536Z] GC before operation: completed in 86.763 ms, heap usage 307.048 MB -> 119.078 MB. [2025-11-06T00:48:05.490Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2289.229 ms) ====== [2025-11-06T00:48:05.490Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-06T00:48:05.490Z] GC before operation: completed in 96.300 ms, heap usage 253.701 MB -> 118.997 MB. [2025-11-06T00:48:08.210Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2331.001 ms) ====== [2025-11-06T00:48:08.210Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-06T00:48:08.210Z] GC before operation: completed in 106.854 ms, heap usage 271.976 MB -> 119.290 MB. [2025-11-06T00:48:10.199Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2059.881 ms) ====== [2025-11-06T00:48:10.199Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-06T00:48:10.199Z] GC before operation: completed in 96.707 ms, heap usage 293.641 MB -> 119.622 MB. [2025-11-06T00:48:12.953Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2228.855 ms) ====== [2025-11-06T00:48:12.953Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-06T00:48:12.953Z] GC before operation: completed in 119.164 ms, heap usage 254.354 MB -> 119.637 MB. [2025-11-06T00:48:14.888Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2116.058 ms) ====== [2025-11-06T00:48:14.888Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-06T00:48:14.888Z] GC before operation: completed in 89.036 ms, heap usage 271.954 MB -> 119.829 MB. [2025-11-06T00:48:16.826Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1926.820 ms) ====== [2025-11-06T00:48:16.826Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-06T00:48:16.826Z] GC before operation: completed in 94.224 ms, heap usage 271.103 MB -> 119.929 MB. [2025-11-06T00:48:18.761Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1775.149 ms) ====== [2025-11-06T00:48:18.761Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-06T00:48:18.761Z] GC before operation: completed in 92.439 ms, heap usage 270.690 MB -> 120.149 MB. [2025-11-06T00:48:21.083Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2038.390 ms) ====== [2025-11-06T00:48:21.083Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-06T00:48:21.083Z] GC before operation: completed in 108.302 ms, heap usage 230.493 MB -> 120.287 MB. [2025-11-06T00:48:23.017Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1888.646 ms) ====== [2025-11-06T00:48:23.017Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-06T00:48:23.017Z] GC before operation: completed in 81.396 ms, heap usage 260.924 MB -> 120.546 MB. [2025-11-06T00:48:24.947Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2065.201 ms) ====== [2025-11-06T00:48:24.947Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-06T00:48:24.947Z] GC before operation: completed in 91.190 ms, heap usage 261.621 MB -> 120.764 MB. [2025-11-06T00:48:26.896Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2085.913 ms) ====== [2025-11-06T00:48:26.896Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-06T00:48:26.896Z] GC before operation: completed in 124.561 ms, heap usage 260.123 MB -> 120.960 MB. [2025-11-06T00:48:29.623Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2121.327 ms) ====== [2025-11-06T00:48:29.623Z] ----------------------------------- [2025-11-06T00:48:29.623Z] renaissance-log-regression_0_PASSED [2025-11-06T00:48:29.623Z] ----------------------------------- [2025-11-06T00:48:29.623Z] [2025-11-06T00:48:29.623Z] TEST TEARDOWN: [2025-11-06T00:48:29.623Z] Nothing to be done for teardown. [2025-11-06T00:48:29.623Z] renaissance-log-regression_0 Finish Time: Thu Nov 6 00:48:29 2025 Epoch Time (ms): 1762390109212