[2025-05-28T22:48:52.499Z] Running test renaissance-log-regression_0 ... [2025-05-28T22:48:52.499Z] =============================================== [2025-05-28T22:48:52.499Z] renaissance-log-regression_0 Start Time: Wed May 28 22:48:52 2025 Epoch Time (ms): 1748472532418 [2025-05-28T22:48:52.499Z] variation: NoOptions [2025-05-28T22:48:52.499Z] JVM_OPTIONS: [2025-05-28T22:48:52.499Z] { \ [2025-05-28T22:48:52.499Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T22:48:52.499Z] echo "Nothing to be done for setup."; \ [2025-05-28T22:48:52.499Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17484688397500/renaissance-log-regression_0"; \ [2025-05-28T22:48:52.499Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17484688397500/renaissance-log-regression_0"; \ [2025-05-28T22:48:52.499Z] echo ""; echo "TESTING:"; \ [2025-05-28T22:48:52.499Z] "/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_17484688397500/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-28T22:48:52.499Z] 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_17484688397500/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T22:48:52.499Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T22:48:52.499Z] echo "Nothing to be done for teardown."; \ [2025-05-28T22:48:52.499Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17484688397500/TestTargetResult"; [2025-05-28T22:48:52.499Z] [2025-05-28T22:48:52.499Z] TEST SETUP: [2025-05-28T22:48:52.499Z] Nothing to be done for setup. [2025-05-28T22:48:52.499Z] [2025-05-28T22:48:52.499Z] TESTING: [2025-05-28T22:49:15.991Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-28T22:49:29.050Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-28T22:49:29.050Z] GC before operation: completed in 276.636 ms, heap usage 58.067 MB -> 35.435 MB. [2025-05-28T22:50:26.732Z] ====== log-regression (apache-spark) [default], iteration 0 completed (52057.696 ms) ====== [2025-05-28T22:50:26.732Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-28T22:50:26.732Z] GC before operation: completed in 498.025 ms, heap usage 620.074 MB -> 123.909 MB. [2025-05-28T22:50:30.548Z] ====== log-regression (apache-spark) [default], iteration 1 completed (10547.628 ms) ====== [2025-05-28T22:50:30.548Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-28T22:50:31.251Z] GC before operation: completed in 571.977 ms, heap usage 260.619 MB -> 123.413 MB. [2025-05-28T22:50:40.975Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8607.543 ms) ====== [2025-05-28T22:50:40.975Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-28T22:50:40.975Z] GC before operation: completed in 627.662 ms, heap usage 297.932 MB -> 124.090 MB. [2025-05-28T22:50:48.255Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7367.863 ms) ====== [2025-05-28T22:50:48.255Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-28T22:50:48.255Z] GC before operation: completed in 637.261 ms, heap usage 310.399 MB -> 124.383 MB. [2025-05-28T22:50:55.550Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7078.960 ms) ====== [2025-05-28T22:50:55.550Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-28T22:50:56.280Z] GC before operation: completed in 703.291 ms, heap usage 355.685 MB -> 124.703 MB. [2025-05-28T22:51:03.530Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6752.622 ms) ====== [2025-05-28T22:51:03.530Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-28T22:51:03.530Z] GC before operation: completed in 721.778 ms, heap usage 340.859 MB -> 124.856 MB. [2025-05-28T22:51:10.933Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6649.797 ms) ====== [2025-05-28T22:51:10.933Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-28T22:51:10.933Z] GC before operation: completed in 703.601 ms, heap usage 303.138 MB -> 124.999 MB. [2025-05-28T22:51:18.226Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6369.245 ms) ====== [2025-05-28T22:51:18.226Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-28T22:51:18.226Z] GC before operation: completed in 722.491 ms, heap usage 377.704 MB -> 125.373 MB. [2025-05-28T22:51:25.504Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6576.202 ms) ====== [2025-05-28T22:51:25.504Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-28T22:51:25.504Z] GC before operation: completed in 747.028 ms, heap usage 576.150 MB -> 126.167 MB. [2025-05-28T22:51:32.813Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6558.566 ms) ====== [2025-05-28T22:51:32.813Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-28T22:51:32.813Z] GC before operation: completed in 769.205 ms, heap usage 247.708 MB -> 125.502 MB. [2025-05-28T22:51:40.052Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6313.671 ms) ====== [2025-05-28T22:51:40.052Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-28T22:51:40.052Z] GC before operation: completed in 780.338 ms, heap usage 552.439 MB -> 126.480 MB. [2025-05-28T22:51:45.945Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6094.362 ms) ====== [2025-05-28T22:51:45.945Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-28T22:51:46.666Z] GC before operation: completed in 805.235 ms, heap usage 260.958 MB -> 125.945 MB. [2025-05-28T22:51:53.145Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6477.142 ms) ====== [2025-05-28T22:51:53.145Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-28T22:51:54.300Z] GC before operation: completed in 827.320 ms, heap usage 540.710 MB -> 127.911 MB. [2025-05-28T22:52:00.221Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5921.013 ms) ====== [2025-05-28T22:52:00.221Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-28T22:52:00.935Z] GC before operation: completed in 829.509 ms, heap usage 331.843 MB -> 126.506 MB. [2025-05-28T22:52:08.167Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6179.647 ms) ====== [2025-05-28T22:52:08.167Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-28T22:52:08.167Z] GC before operation: completed in 825.037 ms, heap usage 340.150 MB -> 126.708 MB. [2025-05-28T22:52:14.061Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6193.283 ms) ====== [2025-05-28T22:52:14.061Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-28T22:52:14.781Z] GC before operation: completed in 866.236 ms, heap usage 350.843 MB -> 126.894 MB. [2025-05-28T22:52:22.034Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6253.873 ms) ====== [2025-05-28T22:52:22.034Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-28T22:52:22.034Z] GC before operation: completed in 853.771 ms, heap usage 357.327 MB -> 127.192 MB. [2025-05-28T22:52:28.710Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5920.602 ms) ====== [2025-05-28T22:52:28.710Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-28T22:52:28.710Z] GC before operation: completed in 861.210 ms, heap usage 434.410 MB -> 127.679 MB. [2025-05-28T22:52:34.593Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5925.972 ms) ====== [2025-05-28T22:52:34.593Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-28T22:52:35.769Z] GC before operation: completed in 879.239 ms, heap usage 307.430 MB -> 127.417 MB. [2025-05-28T22:52:41.655Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5844.328 ms) ====== [2025-05-28T22:52:43.292Z] ----------------------------------- [2025-05-28T22:52:43.292Z] renaissance-log-regression_0_PASSED [2025-05-28T22:52:43.292Z] ----------------------------------- [2025-05-28T22:52:43.292Z] [2025-05-28T22:52:43.292Z] TEST TEARDOWN: [2025-05-28T22:52:43.292Z] Nothing to be done for teardown. [2025-05-28T22:52:43.627Z] renaissance-log-regression_0 Finish Time: Wed May 28 22:52:43 2025 Epoch Time (ms): 1748472763309