[2025-08-30T03:05:31.451Z] Running test renaissance-log-regression_0 ... [2025-08-30T03:05:31.451Z] =============================================== [2025-08-30T03:05:31.451Z] renaissance-log-regression_0 Start Time: Sat Aug 30 03:05:30 2025 Epoch Time (ms): 1756523130331 [2025-08-30T03:05:31.451Z] variation: NoOptions [2025-08-30T03:05:31.451Z] JVM_OPTIONS: [2025-08-30T03:05:31.451Z] { \ [2025-08-30T03:05:31.451Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T03:05:31.451Z] echo "Nothing to be done for setup."; \ [2025-08-30T03:05:31.451Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/renaissance-log-regression_0"; \ [2025-08-30T03:05:31.451Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/renaissance-log-regression_0"; \ [2025-08-30T03:05:31.451Z] echo ""; echo "TESTING:"; \ [2025-08-30T03:05:31.452Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-30T03:05:31.452Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T03:05:31.452Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T03:05:31.452Z] echo "Nothing to be done for teardown."; \ [2025-08-30T03:05:31.452Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/TestTargetResult"; [2025-08-30T03:05:31.452Z] [2025-08-30T03:05:31.452Z] TEST SETUP: [2025-08-30T03:05:31.452Z] Nothing to be done for setup. [2025-08-30T03:05:31.452Z] [2025-08-30T03:05:31.452Z] TESTING: [2025-08-30T03:05:43.989Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-30T03:05:49.749Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-30T03:05:49.749Z] GC before operation: completed in 105.147 ms, heap usage 74.597 MB -> 35.071 MB. [2025-08-30T03:06:16.011Z] ====== log-regression (apache-spark) [default], iteration 0 completed (25588.773 ms) ====== [2025-08-30T03:06:16.011Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-30T03:06:16.011Z] GC before operation: completed in 201.231 ms, heap usage 429.733 MB -> 119.893 MB. [2025-08-30T03:06:20.615Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5647.811 ms) ====== [2025-08-30T03:06:20.615Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-30T03:06:21.410Z] GC before operation: completed in 159.304 ms, heap usage 313.503 MB -> 120.078 MB. [2025-08-30T03:06:26.010Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4815.068 ms) ====== [2025-08-30T03:06:26.010Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-30T03:06:26.010Z] GC before operation: completed in 331.072 ms, heap usage 516.398 MB -> 122.306 MB. [2025-08-30T03:06:30.586Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3745.724 ms) ====== [2025-08-30T03:06:30.586Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-30T03:06:30.586Z] GC before operation: completed in 180.228 ms, heap usage 695.519 MB -> 122.067 MB. [2025-08-30T03:06:35.201Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4495.655 ms) ====== [2025-08-30T03:06:35.202Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-30T03:06:35.202Z] GC before operation: completed in 202.329 ms, heap usage 678.616 MB -> 122.203 MB. [2025-08-30T03:06:38.717Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3904.769 ms) ====== [2025-08-30T03:06:38.717Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-30T03:06:38.717Z] GC before operation: completed in 174.101 ms, heap usage 401.806 MB -> 121.657 MB. [2025-08-30T03:06:42.468Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3514.687 ms) ====== [2025-08-30T03:06:42.468Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-30T03:06:42.468Z] GC before operation: completed in 204.840 ms, heap usage 603.152 MB -> 122.407 MB. [2025-08-30T03:06:46.095Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3189.419 ms) ====== [2025-08-30T03:06:46.095Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-30T03:06:46.095Z] GC before operation: completed in 199.320 ms, heap usage 574.199 MB -> 122.574 MB. [2025-08-30T03:06:49.618Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3270.782 ms) ====== [2025-08-30T03:06:49.618Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-30T03:06:49.618Z] GC before operation: completed in 238.093 ms, heap usage 566.070 MB -> 122.831 MB. [2025-08-30T03:06:53.179Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3696.091 ms) ====== [2025-08-30T03:06:53.179Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-30T03:06:53.179Z] GC before operation: completed in 260.520 ms, heap usage 739.503 MB -> 123.567 MB. [2025-08-30T03:06:57.883Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3802.591 ms) ====== [2025-08-30T03:06:57.883Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-30T03:06:57.883Z] GC before operation: completed in 227.685 ms, heap usage 340.483 MB -> 122.416 MB. [2025-08-30T03:07:01.405Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3864.768 ms) ====== [2025-08-30T03:07:01.405Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-30T03:07:01.405Z] GC before operation: completed in 230.279 ms, heap usage 662.814 MB -> 123.605 MB. [2025-08-30T03:07:04.917Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3567.020 ms) ====== [2025-08-30T03:07:04.917Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-30T03:07:05.709Z] GC before operation: completed in 285.745 ms, heap usage 608.495 MB -> 123.670 MB. [2025-08-30T03:07:09.258Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3377.519 ms) ====== [2025-08-30T03:07:09.258Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-30T03:07:09.258Z] GC before operation: completed in 252.695 ms, heap usage 517.924 MB -> 123.653 MB. [2025-08-30T03:07:12.789Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3307.541 ms) ====== [2025-08-30T03:07:12.789Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-30T03:07:12.789Z] GC before operation: completed in 214.116 ms, heap usage 490.288 MB -> 123.815 MB. [2025-08-30T03:07:16.278Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3581.472 ms) ====== [2025-08-30T03:07:16.279Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-30T03:07:16.279Z] GC before operation: completed in 297.864 ms, heap usage 277.826 MB -> 123.385 MB. [2025-08-30T03:07:19.951Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3294.017 ms) ====== [2025-08-30T03:07:19.951Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-30T03:07:19.951Z] GC before operation: completed in 248.798 ms, heap usage 663.136 MB -> 124.864 MB. [2025-08-30T03:07:23.477Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3262.905 ms) ====== [2025-08-30T03:07:23.477Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-30T03:07:23.477Z] GC before operation: completed in 258.563 ms, heap usage 684.982 MB -> 124.963 MB. [2025-08-30T03:07:27.581Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3163.606 ms) ====== [2025-08-30T03:07:27.581Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-30T03:07:27.581Z] GC before operation: completed in 275.825 ms, heap usage 379.548 MB -> 124.286 MB. [2025-08-30T03:07:30.112Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3290.498 ms) ====== [2025-08-30T03:07:30.902Z] ----------------------------------- [2025-08-30T03:07:30.902Z] renaissance-log-regression_0_PASSED [2025-08-30T03:07:30.902Z] ----------------------------------- [2025-08-30T03:07:30.902Z] [2025-08-30T03:07:30.902Z] TEST TEARDOWN: [2025-08-30T03:07:30.902Z] Nothing to be done for teardown. [2025-08-30T03:07:30.902Z] renaissance-log-regression_0 Finish Time: Sat Aug 30 03:07:30 2025 Epoch Time (ms): 1756523250554