[2025-10-03T21:20:26.940Z] Running test renaissance-log-regression_0 ... [2025-10-03T21:20:26.940Z] =============================================== [2025-10-03T21:20:26.940Z] renaissance-log-regression_0 Start Time: Fri Oct 3 21:20:26 2025 Epoch Time (ms): 1759526426862 [2025-10-03T21:20:26.940Z] variation: NoOptions [2025-10-03T21:20:26.940Z] JVM_OPTIONS: [2025-10-03T21:20:26.940Z] { \ [2025-10-03T21:20:26.940Z] echo ""; echo "TEST SETUP:"; \ [2025-10-03T21:20:26.940Z] echo "Nothing to be done for setup."; \ [2025-10-03T21:20:26.940Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/renaissance-log-regression_0"; \ [2025-10-03T21:20:26.940Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/renaissance-log-regression_0"; \ [2025-10-03T21:20:26.940Z] echo ""; echo "TESTING:"; \ [2025-10-03T21:20:26.940Z] "/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_17595235605636/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-03T21:20:26.940Z] 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_17595235605636/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-03T21:20:26.940Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-03T21:20:26.940Z] echo "Nothing to be done for teardown."; \ [2025-10-03T21:20:26.940Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17595235605636/TestTargetResult"; [2025-10-03T21:20:26.940Z] [2025-10-03T21:20:26.940Z] TEST SETUP: [2025-10-03T21:20:26.940Z] Nothing to be done for setup. [2025-10-03T21:20:26.940Z] [2025-10-03T21:20:26.940Z] TESTING: [2025-10-03T21:20:34.583Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-10-03T21:20:37.695Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-03T21:20:37.695Z] GC before operation: completed in 126.346 ms, heap usage 47.465 MB -> 35.005 MB. [2025-10-03T21:20:59.328Z] ====== log-regression (apache-spark) [default], iteration 0 completed (20093.886 ms) ====== [2025-10-03T21:20:59.328Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-03T21:20:59.328Z] GC before operation: completed in 290.510 ms, heap usage 267.605 MB -> 116.633 MB. [2025-10-03T21:21:03.371Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5250.739 ms) ====== [2025-10-03T21:21:03.371Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-03T21:21:03.371Z] GC before operation: completed in 157.230 ms, heap usage 417.797 MB -> 117.628 MB. [2025-10-03T21:21:08.353Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4809.580 ms) ====== [2025-10-03T21:21:08.353Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-03T21:21:08.353Z] GC before operation: completed in 215.340 ms, heap usage 565.105 MB -> 118.624 MB. [2025-10-03T21:21:14.762Z] ====== log-regression (apache-spark) [default], iteration 3 completed (5803.215 ms) ====== [2025-10-03T21:21:14.762Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-03T21:21:14.762Z] GC before operation: completed in 200.377 ms, heap usage 269.959 MB -> 117.815 MB. [2025-10-03T21:21:20.038Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5646.936 ms) ====== [2025-10-03T21:21:20.038Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-03T21:21:20.038Z] GC before operation: completed in 305.172 ms, heap usage 261.028 MB -> 118.033 MB. [2025-10-03T21:21:25.136Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5122.431 ms) ====== [2025-10-03T21:21:25.136Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-03T21:21:25.136Z] GC before operation: completed in 134.179 ms, heap usage 241.783 MB -> 118.143 MB. [2025-10-03T21:21:30.387Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4887.735 ms) ====== [2025-10-03T21:21:30.387Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-03T21:21:30.387Z] GC before operation: completed in 136.534 ms, heap usage 252.136 MB -> 118.386 MB. [2025-10-03T21:21:34.771Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4282.753 ms) ====== [2025-10-03T21:21:34.771Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-03T21:21:34.771Z] GC before operation: completed in 179.203 ms, heap usage 247.795 MB -> 118.637 MB. [2025-10-03T21:21:39.862Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5076.561 ms) ====== [2025-10-03T21:21:39.862Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-03T21:21:39.862Z] GC before operation: completed in 168.658 ms, heap usage 252.085 MB -> 118.854 MB. [2025-10-03T21:21:44.817Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4808.475 ms) ====== [2025-10-03T21:21:44.818Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-03T21:21:45.479Z] GC before operation: completed in 181.760 ms, heap usage 599.123 MB -> 120.283 MB. [2025-10-03T21:21:49.471Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4426.895 ms) ====== [2025-10-03T21:21:49.471Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-03T21:21:49.471Z] GC before operation: completed in 177.361 ms, heap usage 264.888 MB -> 119.287 MB. [2025-10-03T21:21:54.950Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4608.296 ms) ====== [2025-10-03T21:21:54.951Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-03T21:21:54.951Z] GC before operation: completed in 421.709 ms, heap usage 522.224 MB -> 120.438 MB. [2025-10-03T21:21:59.991Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4683.831 ms) ====== [2025-10-03T21:21:59.991Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-03T21:21:59.991Z] GC before operation: completed in 269.297 ms, heap usage 542.966 MB -> 120.681 MB. [2025-10-03T21:22:03.958Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4112.630 ms) ====== [2025-10-03T21:22:03.958Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-03T21:22:03.958Z] GC before operation: completed in 291.250 ms, heap usage 273.994 MB -> 119.913 MB. [2025-10-03T21:22:08.059Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3831.557 ms) ====== [2025-10-03T21:22:08.059Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-03T21:22:08.059Z] GC before operation: completed in 131.684 ms, heap usage 466.600 MB -> 120.716 MB. [2025-10-03T21:22:12.085Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3604.320 ms) ====== [2025-10-03T21:22:12.085Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-03T21:22:12.085Z] GC before operation: completed in 174.138 ms, heap usage 237.299 MB -> 119.982 MB. [2025-10-03T21:22:17.022Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4779.505 ms) ====== [2025-10-03T21:22:17.023Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-03T21:22:17.023Z] GC before operation: completed in 175.388 ms, heap usage 260.436 MB -> 120.321 MB. [2025-10-03T21:22:20.480Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3659.345 ms) ====== [2025-10-03T21:22:20.480Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-03T21:22:20.480Z] GC before operation: completed in 243.936 ms, heap usage 294.147 MB -> 120.559 MB. [2025-10-03T21:22:24.570Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4039.712 ms) ====== [2025-10-03T21:22:24.570Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-03T21:22:25.261Z] GC before operation: completed in 224.532 ms, heap usage 354.333 MB -> 121.045 MB. [2025-10-03T21:22:30.446Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4678.476 ms) ====== [2025-10-03T21:22:30.446Z] ----------------------------------- [2025-10-03T21:22:30.446Z] renaissance-log-regression_0_PASSED [2025-10-03T21:22:30.446Z] ----------------------------------- [2025-10-03T21:22:30.446Z] [2025-10-03T21:22:30.446Z] TEST TEARDOWN: [2025-10-03T21:22:30.446Z] Nothing to be done for teardown. [2025-10-03T21:22:30.446Z] renaissance-log-regression_0 Finish Time: Fri Oct 3 21:22:29 2025 Epoch Time (ms): 1759526549721