[2026-02-25T22:44:18.536Z] Running test renaissance-log-regression_0 ... [2026-02-25T22:44:18.536Z] =============================================== [2026-02-25T22:44:18.536Z] renaissance-log-regression_0 Start Time: Wed Feb 25 16:44:17 2026 Epoch Time (ms): 1772059457840 [2026-02-25T22:44:18.536Z] variation: NoOptions [2026-02-25T22:44:18.536Z] JVM_OPTIONS: [2026-02-25T22:44:18.536Z] { \ [2026-02-25T22:44:18.536Z] echo ""; echo "TEST SETUP:"; \ [2026-02-25T22:44:18.536Z] echo "Nothing to be done for setup."; \ [2026-02-25T22:44:18.536Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17720580082828/renaissance-log-regression_0"; \ [2026-02-25T22:44:18.536Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17720580082828/renaissance-log-regression_0"; \ [2026-02-25T22:44:18.536Z] echo ""; echo "TESTING:"; \ [2026-02-25T22:44:18.536Z] "/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_17720580082828/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2026-02-25T22:44:18.536Z] 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_17720580082828/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-25T22:44:18.536Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-25T22:44:18.536Z] echo "Nothing to be done for teardown."; \ [2026-02-25T22:44:18.536Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17720580082828/TestTargetResult"; [2026-02-25T22:44:18.536Z] [2026-02-25T22:44:18.536Z] TEST SETUP: [2026-02-25T22:44:18.536Z] Nothing to be done for setup. [2026-02-25T22:44:18.536Z] [2026-02-25T22:44:18.536Z] TESTING: [2026-02-25T22:44:26.787Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-02-25T22:44:30.048Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2026-02-25T22:44:30.836Z] GC before operation: completed in 107.679 ms, heap usage 78.022 MB -> 34.937 MB. [2026-02-25T22:44:48.847Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17123.252 ms) ====== [2026-02-25T22:44:48.847Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2026-02-25T22:44:48.847Z] GC before operation: completed in 191.552 ms, heap usage 300.886 MB -> 116.723 MB. [2026-02-25T22:44:53.225Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4967.319 ms) ====== [2026-02-25T22:44:53.225Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2026-02-25T22:44:53.225Z] GC before operation: completed in 232.718 ms, heap usage 297.685 MB -> 117.275 MB. [2026-02-25T22:44:57.691Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4163.294 ms) ====== [2026-02-25T22:44:57.691Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2026-02-25T22:44:57.691Z] GC before operation: completed in 142.720 ms, heap usage 282.772 MB -> 117.603 MB. [2026-02-25T22:44:59.152Z] 16:44:58.656 WARN [block-manager-storage-async-thread-pool-75] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_152, which does not exist [2026-02-25T22:45:00.622Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3615.331 ms) ====== [2026-02-25T22:45:00.622Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2026-02-25T22:45:01.366Z] GC before operation: completed in 131.872 ms, heap usage 294.722 MB -> 117.984 MB. [2026-02-25T22:45:05.442Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3921.535 ms) ====== [2026-02-25T22:45:05.442Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2026-02-25T22:45:05.442Z] GC before operation: completed in 180.434 ms, heap usage 256.987 MB -> 118.117 MB. [2026-02-25T22:45:09.624Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4023.014 ms) ====== [2026-02-25T22:45:09.624Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2026-02-25T22:45:09.624Z] GC before operation: completed in 166.673 ms, heap usage 294.037 MB -> 118.469 MB. [2026-02-25T22:45:12.928Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3385.598 ms) ====== [2026-02-25T22:45:12.928Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2026-02-25T22:45:12.928Z] GC before operation: completed in 157.924 ms, heap usage 293.470 MB -> 118.710 MB. [2026-02-25T22:45:17.128Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3942.614 ms) ====== [2026-02-25T22:45:17.128Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2026-02-25T22:45:17.128Z] GC before operation: completed in 121.598 ms, heap usage 316.226 MB -> 118.904 MB. [2026-02-25T22:45:20.287Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3200.056 ms) ====== [2026-02-25T22:45:20.287Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2026-02-25T22:45:20.287Z] GC before operation: completed in 188.064 ms, heap usage 260.803 MB -> 118.930 MB. [2026-02-25T22:45:23.471Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3475.665 ms) ====== [2026-02-25T22:45:23.471Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2026-02-25T22:45:24.207Z] GC before operation: completed in 154.412 ms, heap usage 294.021 MB -> 119.206 MB. [2026-02-25T22:45:27.542Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3357.423 ms) ====== [2026-02-25T22:45:27.542Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2026-02-25T22:45:27.542Z] GC before operation: completed in 145.508 ms, heap usage 261.501 MB -> 119.274 MB. [2026-02-25T22:45:31.917Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3931.990 ms) ====== [2026-02-25T22:45:31.917Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2026-02-25T22:45:31.917Z] GC before operation: completed in 185.752 ms, heap usage 237.834 MB -> 119.360 MB. [2026-02-25T22:45:35.207Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3334.036 ms) ====== [2026-02-25T22:45:35.207Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2026-02-25T22:45:35.207Z] GC before operation: completed in 151.914 ms, heap usage 291.214 MB -> 119.768 MB. [2026-02-25T22:45:38.447Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3441.040 ms) ====== [2026-02-25T22:45:38.447Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2026-02-25T22:45:38.447Z] GC before operation: completed in 162.489 ms, heap usage 270.608 MB -> 119.733 MB. [2026-02-25T22:45:43.054Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3330.660 ms) ====== [2026-02-25T22:45:43.054Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2026-02-25T22:45:43.054Z] GC before operation: completed in 163.380 ms, heap usage 271.512 MB -> 120.054 MB. [2026-02-25T22:45:46.317Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3667.538 ms) ====== [2026-02-25T22:45:46.317Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2026-02-25T22:45:46.317Z] GC before operation: completed in 205.559 ms, heap usage 297.702 MB -> 120.418 MB. [2026-02-25T22:45:49.837Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3486.209 ms) ====== [2026-02-25T22:45:49.837Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2026-02-25T22:45:49.837Z] GC before operation: completed in 159.127 ms, heap usage 319.225 MB -> 120.529 MB. [2026-02-25T22:45:53.167Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3756.945 ms) ====== [2026-02-25T22:45:53.167Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2026-02-25T22:45:53.885Z] GC before operation: completed in 141.971 ms, heap usage 275.515 MB -> 120.696 MB. [2026-02-25T22:45:57.117Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3212.323 ms) ====== [2026-02-25T22:45:57.117Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2026-02-25T22:45:57.117Z] GC before operation: completed in 179.744 ms, heap usage 307.627 MB -> 120.872 MB. [2026-02-25T22:46:00.452Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3806.262 ms) ====== [2026-02-25T22:46:01.160Z] ----------------------------------- [2026-02-25T22:46:01.160Z] renaissance-log-regression_0_PASSED [2026-02-25T22:46:01.160Z] ----------------------------------- [2026-02-25T22:46:01.160Z] [2026-02-25T22:46:01.160Z] TEST TEARDOWN: [2026-02-25T22:46:01.160Z] Nothing to be done for teardown. [2026-02-25T22:46:01.160Z] renaissance-log-regression_0 Finish Time: Wed Feb 25 16:46:01 2026 Epoch Time (ms): 1772059561053