[2025-12-24T23:40:45.791Z] Running test renaissance-log-regression_0 ... [2025-12-24T23:40:45.791Z] =============================================== [2025-12-24T23:40:45.791Z] renaissance-log-regression_0 Start Time: Wed Dec 24 23:40:45 2025 Epoch Time (ms): 1766619645219 [2025-12-24T23:40:45.791Z] variation: NoOptions [2025-12-24T23:40:45.791Z] JVM_OPTIONS: [2025-12-24T23:40:45.791Z] { \ [2025-12-24T23:40:45.791Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T23:40:45.791Z] echo "Nothing to be done for setup."; \ [2025-12-24T23:40:45.791Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/renaissance-log-regression_0"; \ [2025-12-24T23:40:45.791Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/renaissance-log-regression_0"; \ [2025-12-24T23:40:45.791Z] echo ""; echo "TESTING:"; \ [2025-12-24T23:40:45.791Z] "/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_17666147635981/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-24T23:40:45.791Z] 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_17666147635981/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T23:40:45.791Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T23:40:45.791Z] echo "Nothing to be done for teardown."; \ [2025-12-24T23:40:45.792Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/TestTargetResult"; [2025-12-24T23:40:45.792Z] [2025-12-24T23:40:45.792Z] TEST SETUP: [2025-12-24T23:40:45.792Z] Nothing to be done for setup. [2025-12-24T23:40:45.792Z] [2025-12-24T23:40:45.792Z] TESTING: [2025-12-24T23:41:08.918Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-24T23:41:19.876Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-24T23:41:19.876Z] GC before operation: completed in 247.351 ms, heap usage 55.593 MB -> 35.418 MB. [2025-12-24T23:42:17.744Z] ====== log-regression (apache-spark) [default], iteration 0 completed (52651.166 ms) ====== [2025-12-24T23:42:17.744Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-24T23:42:17.744Z] GC before operation: completed in 549.842 ms, heap usage 220.085 MB -> 122.824 MB. [2025-12-24T23:42:23.811Z] ====== log-regression (apache-spark) [default], iteration 1 completed (10776.405 ms) ====== [2025-12-24T23:42:23.811Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-24T23:42:24.572Z] GC before operation: completed in 554.916 ms, heap usage 292.316 MB -> 123.543 MB. [2025-12-24T23:42:33.568Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8769.524 ms) ====== [2025-12-24T23:42:33.568Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-24T23:42:33.902Z] GC before operation: completed in 601.111 ms, heap usage 487.936 MB -> 124.458 MB. [2025-12-24T23:42:42.970Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7779.132 ms) ====== [2025-12-24T23:42:42.970Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-24T23:42:42.970Z] GC before operation: completed in 659.371 ms, heap usage 372.783 MB -> 124.472 MB. [2025-12-24T23:42:50.350Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7856.942 ms) ====== [2025-12-24T23:42:50.350Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-24T23:42:50.689Z] GC before operation: completed in 716.369 ms, heap usage 251.904 MB -> 124.441 MB. [2025-12-24T23:42:58.050Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7137.846 ms) ====== [2025-12-24T23:42:58.050Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-24T23:42:58.788Z] GC before operation: completed in 689.186 ms, heap usage 292.806 MB -> 124.840 MB. [2025-12-24T23:43:06.138Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6845.924 ms) ====== [2025-12-24T23:43:06.138Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-24T23:43:06.138Z] GC before operation: completed in 734.408 ms, heap usage 277.904 MB -> 130.920 MB. [2025-12-24T23:43:13.479Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6551.842 ms) ====== [2025-12-24T23:43:13.479Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-24T23:43:13.479Z] GC before operation: completed in 726.415 ms, heap usage 262.528 MB -> 124.943 MB. [2025-12-24T23:43:20.980Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6472.246 ms) ====== [2025-12-24T23:43:20.980Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-24T23:43:20.980Z] GC before operation: completed in 765.488 ms, heap usage 461.120 MB -> 125.823 MB. [2025-12-24T23:43:28.313Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6596.210 ms) ====== [2025-12-24T23:43:28.313Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-24T23:43:28.313Z] GC before operation: completed in 779.651 ms, heap usage 301.763 MB -> 125.620 MB. [2025-12-24T23:43:35.740Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6717.572 ms) ====== [2025-12-24T23:43:35.740Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-24T23:43:35.740Z] GC before operation: completed in 777.205 ms, heap usage 850.944 MB -> 127.313 MB. [2025-12-24T23:43:43.179Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6451.462 ms) ====== [2025-12-24T23:43:43.179Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-24T23:43:43.179Z] GC before operation: completed in 821.842 ms, heap usage 568.160 MB -> 126.903 MB. [2025-12-24T23:43:50.517Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6561.901 ms) ====== [2025-12-24T23:43:50.517Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-24T23:43:50.517Z] GC before operation: completed in 802.455 ms, heap usage 480.206 MB -> 126.675 MB. [2025-12-24T23:43:56.569Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6230.242 ms) ====== [2025-12-24T23:43:56.569Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-24T23:43:57.303Z] GC before operation: completed in 817.638 ms, heap usage 325.641 MB -> 126.547 MB. [2025-12-24T23:44:04.632Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6399.747 ms) ====== [2025-12-24T23:44:04.632Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-24T23:44:04.632Z] GC before operation: completed in 833.748 ms, heap usage 508.096 MB -> 127.168 MB. [2025-12-24T23:44:11.955Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6834.571 ms) ====== [2025-12-24T23:44:11.955Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-24T23:44:12.291Z] GC before operation: completed in 822.969 ms, heap usage 415.240 MB -> 127.031 MB. [2025-12-24T23:44:19.614Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6311.634 ms) ====== [2025-12-24T23:44:19.614Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-24T23:44:19.614Z] GC before operation: completed in 830.659 ms, heap usage 294.679 MB -> 126.918 MB. [2025-12-24T23:44:25.576Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6063.656 ms) ====== [2025-12-24T23:44:25.576Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-24T23:44:26.311Z] GC before operation: completed in 843.215 ms, heap usage 723.582 MB -> 128.370 MB. [2025-12-24T23:44:33.718Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6375.185 ms) ====== [2025-12-24T23:44:33.718Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-24T23:44:33.718Z] GC before operation: completed in 838.456 ms, heap usage 383.796 MB -> 127.593 MB. [2025-12-24T23:44:39.703Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6263.490 ms) ====== [2025-12-24T23:44:41.394Z] ----------------------------------- [2025-12-24T23:44:41.394Z] renaissance-log-regression_0_PASSED [2025-12-24T23:44:41.394Z] ----------------------------------- [2025-12-24T23:44:41.735Z] [2025-12-24T23:44:41.735Z] TEST TEARDOWN: [2025-12-24T23:44:41.735Z] Nothing to be done for teardown. [2025-12-24T23:44:41.735Z] renaissance-log-regression_0 Finish Time: Wed Dec 24 23:44:41 2025 Epoch Time (ms): 1766619881421