[2026-04-21T22:32:19.215Z] Running test renaissance-log-regression_0 ... [2026-04-21T22:32:19.215Z] =============================================== [2026-04-21T22:32:19.215Z] renaissance-log-regression_0 Start Time: Tue Apr 21 22:32:19 2026 Epoch Time (ms): 1776810739063 [2026-04-21T22:32:19.215Z] variation: NoOptions [2026-04-21T22:32:19.215Z] JVM_OPTIONS: [2026-04-21T22:32:19.215Z] { \ [2026-04-21T22:32:19.215Z] echo ""; echo "TEST SETUP:"; \ [2026-04-21T22:32:19.215Z] echo "Nothing to be done for setup."; \ [2026-04-21T22:32:19.215Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1776809920337/renaissance-log-regression_0"; \ [2026-04-21T22:32:19.215Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1776809920337/renaissance-log-regression_0"; \ [2026-04-21T22:32:19.215Z] echo ""; echo "TESTING:"; \ [2026-04-21T22:32:19.215Z] "/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_1776809920337/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2026-04-21T22:32:19.215Z] 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_1776809920337/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-21T22:32:19.215Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-21T22:32:19.215Z] echo "Nothing to be done for teardown."; \ [2026-04-21T22:32:19.215Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1776809920337/TestTargetResult"; [2026-04-21T22:32:19.215Z] [2026-04-21T22:32:19.215Z] TEST SETUP: [2026-04-21T22:32:19.215Z] Nothing to be done for setup. [2026-04-21T22:32:19.215Z] [2026-04-21T22:32:19.215Z] TESTING: [2026-04-21T22:32:23.335Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-21T22:32:24.633Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2026-04-21T22:32:24.633Z] GC before operation: completed in 91.965 ms, heap usage 79.695 MB -> 35.064 MB. [2026-04-21T22:32:33.206Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8924.927 ms) ====== [2026-04-21T22:32:33.206Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2026-04-21T22:32:33.831Z] GC before operation: completed in 107.958 ms, heap usage 286.310 MB -> 116.779 MB. [2026-04-21T22:32:36.679Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2609.450 ms) ====== [2026-04-21T22:32:36.679Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2026-04-21T22:32:36.679Z] GC before operation: completed in 73.705 ms, heap usage 275.490 MB -> 117.336 MB. [2026-04-21T22:32:38.828Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2536.317 ms) ====== [2026-04-21T22:32:38.828Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2026-04-21T22:32:38.828Z] GC before operation: completed in 71.665 ms, heap usage 264.605 MB -> 117.647 MB. [2026-04-21T22:32:40.861Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2113.992 ms) ====== [2026-04-21T22:32:40.861Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2026-04-21T22:32:40.861Z] GC before operation: completed in 66.870 ms, heap usage 250.221 MB -> 117.925 MB. [2026-04-21T22:32:42.906Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1895.481 ms) ====== [2026-04-21T22:32:42.906Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2026-04-21T22:32:42.906Z] GC before operation: completed in 81.594 ms, heap usage 296.543 MB -> 118.280 MB. [2026-04-21T22:32:44.947Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1692.461 ms) ====== [2026-04-21T22:32:44.947Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2026-04-21T22:32:44.948Z] GC before operation: completed in 71.247 ms, heap usage 274.358 MB -> 118.432 MB. [2026-04-21T22:32:46.259Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1722.654 ms) ====== [2026-04-21T22:32:46.259Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2026-04-21T22:32:46.884Z] GC before operation: completed in 78.905 ms, heap usage 282.523 MB -> 118.662 MB. [2026-04-21T22:32:48.183Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1703.586 ms) ====== [2026-04-21T22:32:48.183Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2026-04-21T22:32:48.183Z] GC before operation: completed in 93.436 ms, heap usage 269.105 MB -> 118.807 MB. [2026-04-21T22:32:50.226Z] 22:32:49.552 WARN [block-manager-storage-async-thread-pool-91] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2026-04-21T22:32:50.226Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1677.160 ms) ====== [2026-04-21T22:32:50.226Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2026-04-21T22:32:50.226Z] GC before operation: completed in 79.723 ms, heap usage 258.201 MB -> 118.986 MB. [2026-04-21T22:32:51.523Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1501.625 ms) ====== [2026-04-21T22:32:51.523Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2026-04-21T22:32:51.523Z] GC before operation: completed in 80.323 ms, heap usage 228.341 MB -> 119.048 MB. [2026-04-21T22:32:53.555Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1628.435 ms) ====== [2026-04-21T22:32:53.555Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2026-04-21T22:32:53.555Z] GC before operation: completed in 78.646 ms, heap usage 281.034 MB -> 119.441 MB. [2026-04-21T22:32:55.269Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1682.413 ms) ====== [2026-04-21T22:32:55.269Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2026-04-21T22:32:55.269Z] GC before operation: completed in 79.364 ms, heap usage 260.206 MB -> 119.492 MB. [2026-04-21T22:32:56.578Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1591.808 ms) ====== [2026-04-21T22:32:56.578Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2026-04-21T22:32:57.206Z] GC before operation: completed in 92.598 ms, heap usage 359.914 MB -> 119.954 MB. [2026-04-21T22:32:58.512Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1621.166 ms) ====== [2026-04-21T22:32:58.512Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2026-04-21T22:32:58.512Z] GC before operation: completed in 83.935 ms, heap usage 253.431 MB -> 119.911 MB. [2026-04-21T22:33:00.548Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1524.083 ms) ====== [2026-04-21T22:33:00.548Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2026-04-21T22:33:00.548Z] GC before operation: completed in 82.178 ms, heap usage 260.103 MB -> 120.075 MB. [2026-04-21T22:33:01.852Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1525.728 ms) ====== [2026-04-21T22:33:01.852Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2026-04-21T22:33:01.852Z] GC before operation: completed in 74.347 ms, heap usage 261.101 MB -> 120.348 MB. [2026-04-21T22:33:03.150Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1265.976 ms) ====== [2026-04-21T22:33:03.150Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2026-04-21T22:33:03.150Z] GC before operation: completed in 75.351 ms, heap usage 259.897 MB -> 120.536 MB. [2026-04-21T22:33:04.448Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1334.438 ms) ====== [2026-04-21T22:33:04.448Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2026-04-21T22:33:04.448Z] GC before operation: completed in 95.172 ms, heap usage 240.236 MB -> 120.509 MB. [2026-04-21T22:33:06.484Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1627.441 ms) ====== [2026-04-21T22:33:06.484Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2026-04-21T22:33:06.484Z] GC before operation: completed in 82.375 ms, heap usage 247.613 MB -> 120.711 MB. [2026-04-21T22:33:07.811Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1662.453 ms) ====== [2026-04-21T22:33:08.435Z] ----------------------------------- [2026-04-21T22:33:08.435Z] renaissance-log-regression_0_PASSED [2026-04-21T22:33:08.435Z] ----------------------------------- [2026-04-21T22:33:08.435Z] [2026-04-21T22:33:08.435Z] TEST TEARDOWN: [2026-04-21T22:33:08.435Z] Nothing to be done for teardown. [2026-04-21T22:33:08.435Z] renaissance-log-regression_0 Finish Time: Tue Apr 21 22:33:07 2026 Epoch Time (ms): 1776810787859