[2025-05-22T19:58:00.763Z] Running test renaissance-log-regression_0 ... [2025-05-22T19:58:00.763Z] =============================================== [2025-05-22T19:58:00.763Z] renaissance-log-regression_0 Start Time: Thu May 22 19:58:00 2025 Epoch Time (ms): 1747943880507 [2025-05-22T19:58:00.763Z] variation: NoOptions [2025-05-22T19:58:00.763Z] JVM_OPTIONS: [2025-05-22T19:58:00.763Z] { \ [2025-05-22T19:58:00.763Z] echo ""; echo "TEST SETUP:"; \ [2025-05-22T19:58:00.763Z] echo "Nothing to be done for setup."; \ [2025-05-22T19:58:00.763Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17479401791604/renaissance-log-regression_0"; \ [2025-05-22T19:58:00.763Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17479401791604/renaissance-log-regression_0"; \ [2025-05-22T19:58:00.763Z] echo ""; echo "TESTING:"; \ [2025-05-22T19:58:00.763Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17479401791604/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-22T19:58:00.763Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17479401791604/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-22T19:58:00.763Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-22T19:58:00.763Z] echo "Nothing to be done for teardown."; \ [2025-05-22T19:58:00.763Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17479401791604/TestTargetResult"; [2025-05-22T19:58:00.763Z] [2025-05-22T19:58:00.763Z] TEST SETUP: [2025-05-22T19:58:00.763Z] Nothing to be done for setup. [2025-05-22T19:58:00.763Z] [2025-05-22T19:58:00.763Z] TESTING: [2025-05-22T19:58:23.795Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-22T19:58:34.581Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-22T19:58:34.581Z] GC before operation: completed in 227.234 ms, heap usage 40.999 MB -> 36.054 MB. [2025-05-22T19:59:32.240Z] ====== log-regression (apache-spark) [default], iteration 0 completed (52925.423 ms) ====== [2025-05-22T19:59:32.240Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-22T19:59:32.240Z] GC before operation: completed in 484.825 ms, heap usage 484.107 MB -> 124.398 MB. [2025-05-22T19:59:39.504Z] ====== log-regression (apache-spark) [default], iteration 1 completed (11335.609 ms) ====== [2025-05-22T19:59:39.504Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-22T19:59:40.220Z] GC before operation: completed in 564.935 ms, heap usage 311.439 MB -> 124.471 MB. [2025-05-22T19:59:51.156Z] ====== log-regression (apache-spark) [default], iteration 2 completed (9876.422 ms) ====== [2025-05-22T19:59:51.156Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-22T19:59:51.157Z] GC before operation: completed in 581.235 ms, heap usage 404.095 MB -> 125.073 MB. [2025-05-22T20:00:00.050Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8206.817 ms) ====== [2025-05-22T20:00:00.050Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-22T20:00:00.050Z] GC before operation: completed in 619.741 ms, heap usage 530.573 MB -> 125.689 MB. [2025-05-22T20:00:07.297Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7173.523 ms) ====== [2025-05-22T20:00:07.297Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-22T20:00:07.297Z] GC before operation: completed in 659.351 ms, heap usage 230.026 MB -> 125.194 MB. [2025-05-22T20:00:14.573Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7106.386 ms) ====== [2025-05-22T20:00:14.573Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-22T20:00:14.902Z] GC before operation: completed in 657.936 ms, heap usage 477.841 MB -> 126.065 MB. [2025-05-22T20:00:22.174Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6742.556 ms) ====== [2025-05-22T20:00:22.174Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-22T20:00:22.502Z] GC before operation: completed in 716.557 ms, heap usage 508.385 MB -> 126.351 MB. [2025-05-22T20:00:29.803Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6625.059 ms) ====== [2025-05-22T20:00:29.803Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-22T20:00:29.803Z] GC before operation: completed in 736.295 ms, heap usage 327.961 MB -> 126.064 MB. [2025-05-22T20:00:37.191Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6870.140 ms) ====== [2025-05-22T20:00:37.191Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-22T20:00:37.191Z] GC before operation: completed in 709.132 ms, heap usage 311.287 MB -> 126.353 MB. [2025-05-22T20:00:44.460Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6519.243 ms) ====== [2025-05-22T20:00:44.460Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-22T20:00:44.460Z] GC before operation: completed in 718.321 ms, heap usage 295.672 MB -> 126.394 MB. [2025-05-22T20:00:51.725Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6282.922 ms) ====== [2025-05-22T20:00:51.725Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-22T20:00:51.725Z] GC before operation: completed in 746.231 ms, heap usage 472.042 MB -> 127.098 MB. [2025-05-22T20:00:58.978Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6173.134 ms) ====== [2025-05-22T20:00:58.978Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-22T20:00:58.978Z] GC before operation: completed in 769.485 ms, heap usage 325.318 MB -> 126.900 MB. [2025-05-22T20:01:04.902Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6228.511 ms) ====== [2025-05-22T20:01:04.902Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-22T20:01:05.626Z] GC before operation: completed in 782.921 ms, heap usage 471.553 MB -> 127.518 MB. [2025-05-22T20:01:13.012Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6272.211 ms) ====== [2025-05-22T20:01:13.012Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-22T20:01:13.012Z] GC before operation: completed in 783.382 ms, heap usage 335.640 MB -> 127.389 MB. [2025-05-22T20:01:18.917Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6167.846 ms) ====== [2025-05-22T20:01:18.917Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-22T20:01:19.648Z] GC before operation: completed in 810.444 ms, heap usage 221.452 MB -> 127.263 MB. [2025-05-22T20:01:26.921Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6182.318 ms) ====== [2025-05-22T20:01:26.921Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-22T20:01:26.921Z] GC before operation: completed in 816.534 ms, heap usage 367.403 MB -> 127.827 MB. [2025-05-22T20:01:32.824Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6060.374 ms) ====== [2025-05-22T20:01:32.824Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-22T20:01:33.549Z] GC before operation: completed in 849.246 ms, heap usage 442.808 MB -> 128.241 MB. [2025-05-22T20:01:40.795Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6176.898 ms) ====== [2025-05-22T20:01:40.795Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-22T20:01:40.795Z] GC before operation: completed in 891.756 ms, heap usage 575.898 MB -> 128.892 MB. [2025-05-22T20:01:46.759Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5858.578 ms) ====== [2025-05-22T20:01:46.759Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-22T20:01:47.558Z] GC before operation: completed in 901.451 ms, heap usage 482.213 MB -> 128.794 MB. [2025-05-22T20:01:53.445Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5980.589 ms) ====== [2025-05-22T20:01:55.675Z] ----------------------------------- [2025-05-22T20:01:55.675Z] renaissance-log-regression_0_PASSED [2025-05-22T20:01:55.675Z] ----------------------------------- [2025-05-22T20:01:55.675Z] [2025-05-22T20:01:55.675Z] TEST TEARDOWN: [2025-05-22T20:01:55.675Z] Nothing to be done for teardown. [2025-05-22T20:01:55.675Z] renaissance-log-regression_0 Finish Time: Thu May 22 20:01:55 2025 Epoch Time (ms): 1747944115148