[2025-08-20T21:43:36.252Z] Running test renaissance-log-regression_0 ... [2025-08-20T21:43:36.252Z] =============================================== [2025-08-20T21:43:36.252Z] renaissance-log-regression_0 Start Time: Wed Aug 20 21:43:36 2025 Epoch Time (ms): 1755726216182 [2025-08-20T21:43:36.252Z] variation: NoOptions [2025-08-20T21:43:36.885Z] JVM_OPTIONS: [2025-08-20T21:43:36.885Z] { \ [2025-08-20T21:43:36.885Z] echo ""; echo "TEST SETUP:"; \ [2025-08-20T21:43:36.885Z] echo "Nothing to be done for setup."; \ [2025-08-20T21:43:36.885Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/renaissance-log-regression_0"; \ [2025-08-20T21:43:36.885Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/renaissance-log-regression_0"; \ [2025-08-20T21:43:36.885Z] echo ""; echo "TESTING:"; \ [2025-08-20T21:43:36.885Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-20T21:43:36.885Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-20T21:43:36.885Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-20T21:43:36.885Z] echo "Nothing to be done for teardown."; \ [2025-08-20T21:43:36.885Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/TestTargetResult"; [2025-08-20T21:43:36.885Z] [2025-08-20T21:43:36.885Z] TEST SETUP: [2025-08-20T21:43:36.885Z] Nothing to be done for setup. [2025-08-20T21:43:36.885Z] [2025-08-20T21:43:36.885Z] TESTING: [2025-08-20T21:43:44.102Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-20T21:43:58.509Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-20T21:43:58.509Z] GC before operation: completed in 597.440 ms, heap usage 47.000 MB -> 34.904 MB. [2025-08-20T21:44:32.728Z] ====== log-regression (apache-spark) [default], iteration 0 completed (31518.866 ms) ====== [2025-08-20T21:44:32.728Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-20T21:44:32.728Z] GC before operation: completed in 257.288 ms, heap usage 479.021 MB -> 117.421 MB. [2025-08-20T21:44:34.806Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5426.876 ms) ====== [2025-08-20T21:44:34.806Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-20T21:44:34.807Z] GC before operation: completed in 232.317 ms, heap usage 167.725 MB -> 116.884 MB. [2025-08-20T21:44:42.701Z] ====== log-regression (apache-spark) [default], iteration 2 completed (6850.667 ms) ====== [2025-08-20T21:44:42.701Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-20T21:44:42.701Z] GC before operation: completed in 423.194 ms, heap usage 343.146 MB -> 117.701 MB. [2025-08-20T21:44:46.637Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4389.317 ms) ====== [2025-08-20T21:44:46.637Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-20T21:44:47.299Z] GC before operation: completed in 250.535 ms, heap usage 267.439 MB -> 117.752 MB. [2025-08-20T21:44:53.408Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5962.495 ms) ====== [2025-08-20T21:44:53.408Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-20T21:44:53.408Z] GC before operation: completed in 314.648 ms, heap usage 167.167 MB -> 117.799 MB. [2025-08-20T21:44:59.830Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5888.123 ms) ====== [2025-08-20T21:44:59.830Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-20T21:44:59.830Z] GC before operation: completed in 204.074 ms, heap usage 540.129 MB -> 119.259 MB. [2025-08-20T21:45:05.984Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6582.315 ms) ====== [2025-08-20T21:45:05.984Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-20T21:45:06.598Z] GC before operation: completed in 497.240 ms, heap usage 570.874 MB -> 119.574 MB. [2025-08-20T21:45:11.595Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4735.001 ms) ====== [2025-08-20T21:45:11.595Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-20T21:45:11.595Z] GC before operation: completed in 336.262 ms, heap usage 420.074 MB -> 119.245 MB. [2025-08-20T21:45:16.518Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4612.893 ms) ====== [2025-08-20T21:45:16.518Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-20T21:45:16.518Z] GC before operation: completed in 247.651 ms, heap usage 410.718 MB -> 119.258 MB. [2025-08-20T21:45:22.554Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5492.439 ms) ====== [2025-08-20T21:45:22.554Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-20T21:45:22.554Z] GC before operation: completed in 203.968 ms, heap usage 338.013 MB -> 119.303 MB. [2025-08-20T21:45:29.019Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6704.531 ms) ====== [2025-08-20T21:45:29.019Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-20T21:45:29.019Z] GC before operation: completed in 419.132 ms, heap usage 523.203 MB -> 120.176 MB. [2025-08-20T21:45:33.909Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4394.048 ms) ====== [2025-08-20T21:45:33.909Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-20T21:45:33.909Z] GC before operation: completed in 183.477 ms, heap usage 563.663 MB -> 120.464 MB. [2025-08-20T21:45:37.808Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3818.729 ms) ====== [2025-08-20T21:45:37.808Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-20T21:45:37.808Z] GC before operation: completed in 170.236 ms, heap usage 327.641 MB -> 119.795 MB. [2025-08-20T21:45:43.293Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5174.026 ms) ====== [2025-08-20T21:45:43.294Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-20T21:45:43.294Z] GC before operation: completed in 240.707 ms, heap usage 607.361 MB -> 121.022 MB. [2025-08-20T21:45:47.389Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3645.998 ms) ====== [2025-08-20T21:45:47.389Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-20T21:45:47.389Z] GC before operation: completed in 167.617 ms, heap usage 132.513 MB -> 120.697 MB. [2025-08-20T21:45:51.273Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3776.200 ms) ====== [2025-08-20T21:45:51.273Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-20T21:45:51.273Z] GC before operation: completed in 166.237 ms, heap usage 493.672 MB -> 121.002 MB. [2025-08-20T21:45:55.356Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4196.317 ms) ====== [2025-08-20T21:45:55.356Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-20T21:45:55.356Z] GC before operation: completed in 280.822 ms, heap usage 599.068 MB -> 121.503 MB. [2025-08-20T21:46:00.358Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4570.622 ms) ====== [2025-08-20T21:46:00.358Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-20T21:46:00.358Z] GC before operation: completed in 500.597 ms, heap usage 240.995 MB -> 120.323 MB. [2025-08-20T21:46:05.283Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4901.289 ms) ====== [2025-08-20T21:46:05.283Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-20T21:46:05.283Z] GC before operation: completed in 160.243 ms, heap usage 361.228 MB -> 121.036 MB. [2025-08-20T21:46:09.052Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3716.052 ms) ====== [2025-08-20T21:46:09.769Z] ----------------------------------- [2025-08-20T21:46:09.769Z] renaissance-log-regression_0_PASSED [2025-08-20T21:46:09.769Z] ----------------------------------- [2025-08-20T21:46:09.769Z] [2025-08-20T21:46:09.769Z] TEST TEARDOWN: [2025-08-20T21:46:09.769Z] Nothing to be done for teardown. [2025-08-20T21:46:09.769Z] renaissance-log-regression_0 Finish Time: Wed Aug 20 21:46:09 2025 Epoch Time (ms): 1755726369355