[2025-08-30T00:58:14.783Z] Running test renaissance-log-regression_0 ... [2025-08-30T00:58:14.783Z] =============================================== [2025-08-30T00:58:14.783Z] renaissance-log-regression_0 Start Time: Sat Aug 30 00:58:14 2025 Epoch Time (ms): 1756515494612 [2025-08-30T00:58:14.783Z] variation: NoOptions [2025-08-30T00:58:14.783Z] JVM_OPTIONS: [2025-08-30T00:58:14.783Z] { \ [2025-08-30T00:58:14.783Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T00:58:14.783Z] echo "Nothing to be done for setup."; \ [2025-08-30T00:58:14.783Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17565132981818/renaissance-log-regression_0"; \ [2025-08-30T00:58:14.783Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17565132981818/renaissance-log-regression_0"; \ [2025-08-30T00:58:14.783Z] echo ""; echo "TESTING:"; \ [2025-08-30T00:58:14.783Z] "/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_17565132981818/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-30T00:58:14.783Z] 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_17565132981818/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T00:58:14.783Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T00:58:14.783Z] echo "Nothing to be done for teardown."; \ [2025-08-30T00:58:14.783Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17565132981818/TestTargetResult"; [2025-08-30T00:58:14.783Z] [2025-08-30T00:58:14.783Z] TEST SETUP: [2025-08-30T00:58:14.783Z] Nothing to be done for setup. [2025-08-30T00:58:14.783Z] [2025-08-30T00:58:14.783Z] TESTING: [2025-08-30T00:58:21.935Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-30T00:58:24.846Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-30T00:58:24.846Z] GC before operation: completed in 132.792 ms, heap usage 46.901 MB -> 35.070 MB. [2025-08-30T00:58:45.596Z] ====== log-regression (apache-spark) [default], iteration 0 completed (19695.190 ms) ====== [2025-08-30T00:58:45.596Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-30T00:58:45.596Z] GC before operation: completed in 175.311 ms, heap usage 574.666 MB -> 117.947 MB. [2025-08-30T00:58:50.437Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5031.852 ms) ====== [2025-08-30T00:58:50.437Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-30T00:58:50.437Z] GC before operation: completed in 153.612 ms, heap usage 508.818 MB -> 118.304 MB. [2025-08-30T00:58:56.415Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5674.699 ms) ====== [2025-08-30T00:58:56.415Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-30T00:58:56.415Z] GC before operation: completed in 145.555 ms, heap usage 245.752 MB -> 117.581 MB. [2025-08-30T00:59:00.349Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4651.372 ms) ====== [2025-08-30T00:59:00.349Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-30T00:59:01.069Z] GC before operation: completed in 229.151 ms, heap usage 436.277 MB -> 118.756 MB. [2025-08-30T00:59:06.047Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4898.879 ms) ====== [2025-08-30T00:59:06.047Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-30T00:59:06.047Z] GC before operation: completed in 171.569 ms, heap usage 244.092 MB -> 118.083 MB. [2025-08-30T00:59:10.945Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4745.460 ms) ====== [2025-08-30T00:59:10.945Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-30T00:59:10.945Z] GC before operation: completed in 266.035 ms, heap usage 143.295 MB -> 119.723 MB. [2025-08-30T00:59:15.687Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4505.307 ms) ====== [2025-08-30T00:59:15.687Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-30T00:59:15.687Z] GC before operation: completed in 262.421 ms, heap usage 294.260 MB -> 118.794 MB. [2025-08-30T00:59:19.901Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3910.988 ms) ====== [2025-08-30T00:59:19.901Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-30T00:59:19.901Z] GC before operation: completed in 165.182 ms, heap usage 123.705 MB -> 119.763 MB. [2025-08-30T00:59:23.811Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3826.890 ms) ====== [2025-08-30T00:59:23.811Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-30T00:59:23.811Z] GC before operation: completed in 443.984 ms, heap usage 293.640 MB -> 119.102 MB. [2025-08-30T00:59:27.642Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3681.710 ms) ====== [2025-08-30T00:59:27.642Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-30T00:59:27.642Z] GC before operation: completed in 228.755 ms, heap usage 490.482 MB -> 120.073 MB. [2025-08-30T00:59:31.415Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3625.858 ms) ====== [2025-08-30T00:59:31.416Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-30T00:59:31.416Z] GC before operation: completed in 130.505 ms, heap usage 452.606 MB -> 120.058 MB. [2025-08-30T00:59:35.301Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3789.439 ms) ====== [2025-08-30T00:59:35.301Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-30T00:59:35.301Z] GC before operation: completed in 142.689 ms, heap usage 490.944 MB -> 120.450 MB. [2025-08-30T00:59:40.119Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4042.931 ms) ====== [2025-08-30T00:59:40.119Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-30T00:59:40.119Z] GC before operation: completed in 172.915 ms, heap usage 225.365 MB -> 119.681 MB. [2025-08-30T00:59:42.957Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3066.642 ms) ====== [2025-08-30T00:59:42.957Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-30T00:59:42.957Z] GC before operation: completed in 236.071 ms, heap usage 122.547 MB -> 121.588 MB. [2025-08-30T00:59:46.907Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3331.555 ms) ====== [2025-08-30T00:59:46.907Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-30T00:59:46.907Z] GC before operation: completed in 179.607 ms, heap usage 467.901 MB -> 120.932 MB. [2025-08-30T00:59:49.901Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3368.264 ms) ====== [2025-08-30T00:59:49.901Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-30T00:59:50.542Z] GC before operation: completed in 265.950 ms, heap usage 503.802 MB -> 121.251 MB. [2025-08-30T00:59:54.379Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3737.485 ms) ====== [2025-08-30T00:59:54.379Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-30T00:59:54.379Z] GC before operation: completed in 181.092 ms, heap usage 625.548 MB -> 121.975 MB. [2025-08-30T00:59:58.287Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4364.230 ms) ====== [2025-08-30T00:59:58.287Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-30T00:59:58.928Z] GC before operation: completed in 196.204 ms, heap usage 232.882 MB -> 120.564 MB. [2025-08-30T01:00:02.872Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3620.811 ms) ====== [2025-08-30T01:00:02.872Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-30T01:00:02.872Z] GC before operation: completed in 180.766 ms, heap usage 273.863 MB -> 120.978 MB. [2025-08-30T01:00:07.774Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4866.563 ms) ====== [2025-08-30T01:00:07.774Z] ----------------------------------- [2025-08-30T01:00:07.774Z] renaissance-log-regression_0_PASSED [2025-08-30T01:00:07.774Z] ----------------------------------- [2025-08-30T01:00:07.774Z] [2025-08-30T01:00:07.774Z] TEST TEARDOWN: [2025-08-30T01:00:07.774Z] Nothing to be done for teardown. [2025-08-30T01:00:07.774Z] renaissance-log-regression_0 Finish Time: Sat Aug 30 01:00:07 2025 Epoch Time (ms): 1756515607465