[2026-04-14T12:31:51.849Z] Running test renaissance-log-regression_0 ... [2026-04-14T12:31:51.849Z] =============================================== [2026-04-14T12:31:51.849Z] renaissance-log-regression_0 Start Time: Tue Apr 14 12:31:49 2026 Epoch Time (ms): 1776169909700 [2026-04-14T12:31:51.849Z] variation: NoOptions [2026-04-14T12:31:51.849Z] JVM_OPTIONS: [2026-04-14T12:31:51.849Z] { \ [2026-04-14T12:31:51.849Z] echo ""; echo "TEST SETUP:"; \ [2026-04-14T12:31:51.849Z] echo "Nothing to be done for setup."; \ [2026-04-14T12:31:51.849Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761684555319/renaissance-log-regression_0"; \ [2026-04-14T12:31:51.849Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761684555319/renaissance-log-regression_0"; \ [2026-04-14T12:31:51.849Z] echo ""; echo "TESTING:"; \ [2026-04-14T12:31:51.849Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761684555319/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2026-04-14T12:31:51.849Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761684555319/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-14T12:31:51.849Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-14T12:31:51.849Z] echo "Nothing to be done for teardown."; \ [2026-04-14T12:31:51.849Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761684555319/TestTargetResult"; [2026-04-14T12:31:51.849Z] [2026-04-14T12:31:51.849Z] TEST SETUP: [2026-04-14T12:31:51.849Z] Nothing to be done for setup. [2026-04-14T12:31:51.849Z] [2026-04-14T12:31:51.849Z] TESTING: [2026-04-14T12:32:01.632Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-14T12:32:05.802Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2026-04-14T12:32:05.802Z] GC before operation: completed in 81.045 ms, heap usage 65.049 MB -> 35.189 MB. [2026-04-14T12:32:30.527Z] ====== log-regression (apache-spark) [default], iteration 0 completed (24889.712 ms) ====== [2026-04-14T12:32:30.528Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2026-04-14T12:32:30.528Z] GC before operation: completed in 237.908 ms, heap usage 308.107 MB -> 119.739 MB. [2026-04-14T12:32:35.962Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5431.023 ms) ====== [2026-04-14T12:32:35.962Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2026-04-14T12:32:36.930Z] GC before operation: completed in 204.903 ms, heap usage 241.907 MB -> 120.109 MB. [2026-04-14T12:32:41.379Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4711.191 ms) ====== [2026-04-14T12:32:41.379Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2026-04-14T12:32:41.379Z] GC before operation: completed in 189.059 ms, heap usage 376.274 MB -> 120.802 MB. [2026-04-14T12:32:45.592Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3598.014 ms) ====== [2026-04-14T12:32:45.592Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2026-04-14T12:32:45.592Z] GC before operation: completed in 214.120 ms, heap usage 239.628 MB -> 120.869 MB. [2026-04-14T12:32:48.620Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3499.956 ms) ====== [2026-04-14T12:32:48.620Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2026-04-14T12:32:48.620Z] GC before operation: completed in 210.595 ms, heap usage 309.436 MB -> 121.270 MB. [2026-04-14T12:32:52.795Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3256.941 ms) ====== [2026-04-14T12:32:52.795Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2026-04-14T12:32:52.795Z] GC before operation: completed in 181.624 ms, heap usage 342.941 MB -> 121.429 MB. [2026-04-14T12:32:55.838Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3075.383 ms) ====== [2026-04-14T12:32:55.838Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2026-04-14T12:32:55.838Z] GC before operation: completed in 176.190 ms, heap usage 346.428 MB -> 121.762 MB. [2026-04-14T12:32:58.542Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3247.742 ms) ====== [2026-04-14T12:32:58.542Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2026-04-14T12:32:59.511Z] GC before operation: completed in 209.205 ms, heap usage 286.429 MB -> 121.746 MB. [2026-04-14T12:33:02.573Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2881.157 ms) ====== [2026-04-14T12:33:02.573Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2026-04-14T12:33:02.573Z] GC before operation: completed in 197.041 ms, heap usage 345.740 MB -> 122.220 MB. [2026-04-14T12:33:05.618Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2909.311 ms) ====== [2026-04-14T12:33:05.618Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2026-04-14T12:33:05.618Z] GC before operation: completed in 215.414 ms, heap usage 288.394 MB -> 122.248 MB. [2026-04-14T12:33:08.661Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2759.949 ms) ====== [2026-04-14T12:33:08.661Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2026-04-14T12:33:08.661Z] GC before operation: completed in 252.894 ms, heap usage 386.918 MB -> 122.714 MB. [2026-04-14T12:33:10.630Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2664.940 ms) ====== [2026-04-14T12:33:10.630Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2026-04-14T12:33:11.598Z] GC before operation: completed in 219.862 ms, heap usage 317.112 MB -> 122.784 MB. [2026-04-14T12:33:14.643Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2901.674 ms) ====== [2026-04-14T12:33:14.643Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2026-04-14T12:33:14.643Z] GC before operation: completed in 260.395 ms, heap usage 313.771 MB -> 122.898 MB. [2026-04-14T12:33:16.609Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2597.314 ms) ====== [2026-04-14T12:33:16.609Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2026-04-14T12:33:17.574Z] GC before operation: completed in 207.083 ms, heap usage 394.727 MB -> 123.343 MB. [2026-04-14T12:33:19.541Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2769.715 ms) ====== [2026-04-14T12:33:19.541Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2026-04-14T12:33:20.501Z] GC before operation: completed in 238.524 ms, heap usage 292.525 MB -> 123.193 MB. [2026-04-14T12:33:21.462Z] 12:33:21.053 WARN [block-manager-storage-async-thread-pool-67] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_718, which does not exist [2026-04-14T12:33:22.428Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2548.994 ms) ====== [2026-04-14T12:33:22.428Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2026-04-14T12:33:23.378Z] GC before operation: completed in 228.784 ms, heap usage 365.784 MB -> 123.640 MB. [2026-04-14T12:33:26.411Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2849.612 ms) ====== [2026-04-14T12:33:26.411Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2026-04-14T12:33:26.411Z] GC before operation: completed in 211.392 ms, heap usage 384.471 MB -> 123.931 MB. [2026-04-14T12:33:28.388Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2691.457 ms) ====== [2026-04-14T12:33:28.388Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2026-04-14T12:33:29.340Z] GC before operation: completed in 281.699 ms, heap usage 372.883 MB -> 124.147 MB. [2026-04-14T12:33:31.321Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2718.502 ms) ====== [2026-04-14T12:33:31.321Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2026-04-14T12:33:32.277Z] GC before operation: completed in 217.018 ms, heap usage 316.885 MB -> 124.253 MB. [2026-04-14T12:33:34.251Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2701.989 ms) ====== [2026-04-14T12:33:35.211Z] ----------------------------------- [2026-04-14T12:33:35.211Z] renaissance-log-regression_0_PASSED [2026-04-14T12:33:35.211Z] ----------------------------------- [2026-04-14T12:33:35.211Z] [2026-04-14T12:33:35.211Z] TEST TEARDOWN: [2026-04-14T12:33:35.211Z] Nothing to be done for teardown. [2026-04-14T12:33:35.211Z] renaissance-log-regression_0 Finish Time: Tue Apr 14 12:33:34 2026 Epoch Time (ms): 1776170014981