[2025-08-28T09:13:49.499Z] Running test renaissance-log-regression_0 ... [2025-08-28T09:13:49.499Z] =============================================== [2025-08-28T09:13:49.499Z] renaissance-log-regression_0 Start Time: Thu Aug 28 09:13:49 2025 Epoch Time (ms): 1756372429421 [2025-08-28T09:13:49.499Z] variation: NoOptions [2025-08-28T09:13:49.499Z] JVM_OPTIONS: [2025-08-28T09:13:49.499Z] { \ [2025-08-28T09:13:49.499Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T09:13:49.499Z] echo "Nothing to be done for setup."; \ [2025-08-28T09:13:49.499Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/renaissance-log-regression_0"; \ [2025-08-28T09:13:49.499Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/renaissance-log-regression_0"; \ [2025-08-28T09:13:49.499Z] echo ""; echo "TESTING:"; \ [2025-08-28T09:13:49.499Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/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_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-28T09:13:49.499Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T09:13:49.499Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T09:13:49.499Z] echo "Nothing to be done for teardown."; \ [2025-08-28T09:13:49.499Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/TestTargetResult"; [2025-08-28T09:13:49.499Z] [2025-08-28T09:13:49.499Z] TEST SETUP: [2025-08-28T09:13:49.499Z] Nothing to be done for setup. [2025-08-28T09:13:49.499Z] [2025-08-28T09:13:49.499Z] TESTING: [2025-08-28T09:14:12.532Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-28T09:14:25.603Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-28T09:14:25.603Z] GC before operation: completed in 251.421 ms, heap usage 57.388 MB -> 35.340 MB. [2025-08-28T09:15:23.248Z] ====== log-regression (apache-spark) [default], iteration 0 completed (52590.958 ms) ====== [2025-08-28T09:15:23.248Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-28T09:15:23.248Z] GC before operation: completed in 496.568 ms, heap usage 304.328 MB -> 123.015 MB. [2025-08-28T09:15:29.280Z] ====== log-regression (apache-spark) [default], iteration 1 completed (11456.113 ms) ====== [2025-08-28T09:15:29.280Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-28T09:15:29.280Z] GC before operation: completed in 572.582 ms, heap usage 337.411 MB -> 123.758 MB. [2025-08-28T09:15:38.175Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8973.186 ms) ====== [2025-08-28T09:15:38.175Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-28T09:15:38.507Z] GC before operation: completed in 630.391 ms, heap usage 316.992 MB -> 124.102 MB. [2025-08-28T09:15:47.384Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7809.325 ms) ====== [2025-08-28T09:15:47.384Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-28T09:15:47.384Z] GC before operation: completed in 674.739 ms, heap usage 243.030 MB -> 124.226 MB. [2025-08-28T09:15:54.719Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7364.975 ms) ====== [2025-08-28T09:15:54.719Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-28T09:15:55.049Z] GC before operation: completed in 694.818 ms, heap usage 238.897 MB -> 124.478 MB. [2025-08-28T09:16:02.317Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6976.877 ms) ====== [2025-08-28T09:16:02.317Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-28T09:16:03.038Z] GC before operation: completed in 720.589 ms, heap usage 288.460 MB -> 124.785 MB. [2025-08-28T09:16:10.307Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6669.726 ms) ====== [2025-08-28T09:16:10.307Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-28T09:16:10.307Z] GC before operation: completed in 736.342 ms, heap usage 289.368 MB -> 125.074 MB. [2025-08-28T09:16:17.682Z] ====== log-regression (apache-spark) [default], iteration 7 completed (7060.150 ms) ====== [2025-08-28T09:16:17.682Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-28T09:16:18.017Z] GC before operation: completed in 750.144 ms, heap usage 265.262 MB -> 125.198 MB. [2025-08-28T09:16:25.279Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6639.266 ms) ====== [2025-08-28T09:16:25.279Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-28T09:16:25.602Z] GC before operation: completed in 771.441 ms, heap usage 365.869 MB -> 125.658 MB. [2025-08-28T09:16:32.856Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6561.053 ms) ====== [2025-08-28T09:16:32.856Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-28T09:16:32.856Z] GC before operation: completed in 783.171 ms, heap usage 387.922 MB -> 125.945 MB. [2025-08-28T09:16:40.139Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6494.071 ms) ====== [2025-08-28T09:16:40.139Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-28T09:16:40.139Z] GC before operation: completed in 790.682 ms, heap usage 612.359 MB -> 126.794 MB. [2025-08-28T09:16:47.392Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6593.366 ms) ====== [2025-08-28T09:16:47.392Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-28T09:16:47.392Z] GC before operation: completed in 827.440 ms, heap usage 348.615 MB -> 126.265 MB. [2025-08-28T09:16:54.631Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6456.312 ms) ====== [2025-08-28T09:16:54.631Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-28T09:16:54.956Z] GC before operation: completed in 846.429 ms, heap usage 257.175 MB -> 126.208 MB. [2025-08-28T09:17:01.006Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6267.232 ms) ====== [2025-08-28T09:17:01.006Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-28T09:17:02.172Z] GC before operation: completed in 866.534 ms, heap usage 345.996 MB -> 126.652 MB. [2025-08-28T09:17:08.212Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6133.122 ms) ====== [2025-08-28T09:17:08.212Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-28T09:17:08.944Z] GC before operation: completed in 843.285 ms, heap usage 355.520 MB -> 126.846 MB. [2025-08-28T09:17:16.199Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6261.805 ms) ====== [2025-08-28T09:17:16.199Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-28T09:17:16.199Z] GC before operation: completed in 865.636 ms, heap usage 380.570 MB -> 127.127 MB. [2025-08-28T09:17:23.437Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6376.936 ms) ====== [2025-08-28T09:17:23.437Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-28T09:17:23.437Z] GC before operation: completed in 861.532 ms, heap usage 548.657 MB -> 127.854 MB. [2025-08-28T09:17:29.320Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6035.264 ms) ====== [2025-08-28T09:17:29.320Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-28T09:17:30.483Z] GC before operation: completed in 897.033 ms, heap usage 341.104 MB -> 127.388 MB. [2025-08-28T09:17:36.399Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6031.870 ms) ====== [2025-08-28T09:17:36.399Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-28T09:17:37.132Z] GC before operation: completed in 882.389 ms, heap usage 591.434 MB -> 128.348 MB. [2025-08-28T09:17:44.469Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6185.898 ms) ====== [2025-08-28T09:17:45.169Z] ----------------------------------- [2025-08-28T09:17:45.170Z] renaissance-log-regression_0_PASSED [2025-08-28T09:17:45.170Z] ----------------------------------- [2025-08-28T09:17:45.170Z] [2025-08-28T09:17:45.170Z] TEST TEARDOWN: [2025-08-28T09:17:45.170Z] Nothing to be done for teardown. [2025-08-28T09:17:45.505Z] renaissance-log-regression_0 Finish Time: Thu Aug 28 09:17:45 2025 Epoch Time (ms): 1756372665174