[2025-06-11T22:19:08.339Z] Running test renaissance-log-regression_0 ... [2025-06-11T22:19:08.339Z] =============================================== [2025-06-11T22:19:08.339Z] renaissance-log-regression_0 Start Time: Wed Jun 11 22:19:08 2025 Epoch Time (ms): 1749680348322 [2025-06-11T22:19:08.671Z] variation: NoOptions [2025-06-11T22:19:08.671Z] JVM_OPTIONS: [2025-06-11T22:19:08.671Z] { \ [2025-06-11T22:19:08.671Z] echo ""; echo "TEST SETUP:"; \ [2025-06-11T22:19:08.671Z] echo "Nothing to be done for setup."; \ [2025-06-11T22:19:08.671Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17496769855803/renaissance-log-regression_0"; \ [2025-06-11T22:19:08.671Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17496769855803/renaissance-log-regression_0"; \ [2025-06-11T22:19:08.671Z] echo ""; echo "TESTING:"; \ [2025-06-11T22:19:08.671Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_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_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17496769855803/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-11T22:19:08.671Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17496769855803/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-11T22:19:08.671Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-11T22:19:08.671Z] echo "Nothing to be done for teardown."; \ [2025-06-11T22:19:08.671Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17496769855803/TestTargetResult"; [2025-06-11T22:19:08.671Z] [2025-06-11T22:19:08.671Z] TEST SETUP: [2025-06-11T22:19:08.671Z] Nothing to be done for setup. [2025-06-11T22:19:08.671Z] [2025-06-11T22:19:08.671Z] TESTING: [2025-06-11T22:19:31.685Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-11T22:19:44.255Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-11T22:19:44.255Z] GC before operation: completed in 262.666 ms, heap usage 55.742 MB -> 35.357 MB. [2025-06-11T22:20:41.912Z] ====== log-regression (apache-spark) [default], iteration 0 completed (53522.735 ms) ====== [2025-06-11T22:20:41.912Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-11T22:20:41.912Z] GC before operation: completed in 500.471 ms, heap usage 417.102 MB -> 123.017 MB. [2025-06-11T22:20:47.831Z] ====== log-regression (apache-spark) [default], iteration 1 completed (10975.074 ms) ====== [2025-06-11T22:20:47.831Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-11T22:20:48.550Z] GC before operation: completed in 559.119 ms, heap usage 432.874 MB -> 123.732 MB. [2025-06-11T22:20:59.519Z] ====== log-regression (apache-spark) [default], iteration 2 completed (9429.895 ms) ====== [2025-06-11T22:20:59.519Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-11T22:20:59.519Z] GC before operation: completed in 613.425 ms, heap usage 320.954 MB -> 123.867 MB. [2025-06-11T22:21:06.787Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8166.020 ms) ====== [2025-06-11T22:21:06.787Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-11T22:21:07.515Z] GC before operation: completed in 631.708 ms, heap usage 464.739 MB -> 124.477 MB. [2025-06-11T22:21:14.799Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7434.767 ms) ====== [2025-06-11T22:21:14.799Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-11T22:21:15.520Z] GC before operation: completed in 704.805 ms, heap usage 382.289 MB -> 124.567 MB. [2025-06-11T22:21:22.771Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7234.510 ms) ====== [2025-06-11T22:21:22.771Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-11T22:21:23.511Z] GC before operation: completed in 726.070 ms, heap usage 476.703 MB -> 124.860 MB. [2025-06-11T22:21:30.777Z] ====== log-regression (apache-spark) [default], iteration 6 completed (7255.832 ms) ====== [2025-06-11T22:21:30.777Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-11T22:21:31.504Z] GC before operation: completed in 748.316 ms, heap usage 238.160 MB -> 124.508 MB. [2025-06-11T22:21:38.787Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6812.489 ms) ====== [2025-06-11T22:21:38.787Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-11T22:21:39.119Z] GC before operation: completed in 783.545 ms, heap usage 615.097 MB -> 125.866 MB. [2025-06-11T22:21:46.461Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7002.059 ms) ====== [2025-06-11T22:21:46.461Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-11T22:21:46.792Z] GC before operation: completed in 773.542 ms, heap usage 445.062 MB -> 125.504 MB. [2025-06-11T22:21:54.309Z] ====== log-regression (apache-spark) [default], iteration 9 completed (7071.499 ms) ====== [2025-06-11T22:21:54.309Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-11T22:21:54.641Z] GC before operation: completed in 800.582 ms, heap usage 355.084 MB -> 125.535 MB. [2025-06-11T22:22:01.908Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6868.725 ms) ====== [2025-06-11T22:22:01.908Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-11T22:22:02.618Z] GC before operation: completed in 816.428 ms, heap usage 483.813 MB -> 126.097 MB. [2025-06-11T22:22:09.904Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6460.362 ms) ====== [2025-06-11T22:22:09.904Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-11T22:22:09.904Z] GC before operation: completed in 830.699 ms, heap usage 410.424 MB -> 126.240 MB. [2025-06-11T22:22:17.168Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6731.161 ms) ====== [2025-06-11T22:22:17.168Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-11T22:22:17.168Z] GC before operation: completed in 832.975 ms, heap usage 282.105 MB -> 125.866 MB. [2025-06-11T22:22:24.432Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6763.064 ms) ====== [2025-06-11T22:22:24.432Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-11T22:22:24.771Z] GC before operation: completed in 844.787 ms, heap usage 614.486 MB -> 127.087 MB. [2025-06-11T22:22:32.006Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6237.784 ms) ====== [2025-06-11T22:22:32.006Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-11T22:22:32.006Z] GC before operation: completed in 849.467 ms, heap usage 466.703 MB -> 126.914 MB. [2025-06-11T22:22:39.331Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6249.431 ms) ====== [2025-06-11T22:22:39.331Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-11T22:22:39.331Z] GC before operation: completed in 859.371 ms, heap usage 540.845 MB -> 127.290 MB. [2025-06-11T22:22:46.713Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6491.083 ms) ====== [2025-06-11T22:22:46.713Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-11T22:22:46.713Z] GC before operation: completed in 901.731 ms, heap usage 659.578 MB -> 127.750 MB. [2025-06-11T22:22:52.697Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6314.619 ms) ====== [2025-06-11T22:22:52.697Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-11T22:22:53.858Z] GC before operation: completed in 921.816 ms, heap usage 504.876 MB -> 127.482 MB. [2025-06-11T22:23:01.093Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6444.172 ms) ====== [2025-06-11T22:23:01.093Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-11T22:23:01.093Z] GC before operation: completed in 896.226 ms, heap usage 460.446 MB -> 127.764 MB. [2025-06-11T22:23:08.410Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6341.445 ms) ====== [2025-06-11T22:23:09.111Z] ----------------------------------- [2025-06-11T22:23:09.111Z] renaissance-log-regression_0_PASSED [2025-06-11T22:23:09.111Z] ----------------------------------- [2025-06-11T22:23:09.111Z] [2025-06-11T22:23:09.111Z] TEST TEARDOWN: [2025-06-11T22:23:09.111Z] Nothing to be done for teardown. [2025-06-11T22:23:09.111Z] renaissance-log-regression_0 Finish Time: Wed Jun 11 22:23:09 2025 Epoch Time (ms): 1749680589035