[2025-09-04T06:17:21.638Z] Running test renaissance-log-regression_0 ... [2025-09-04T06:17:21.638Z] =============================================== [2025-09-04T06:17:21.638Z] renaissance-log-regression_0 Start Time: Thu Sep 4 06:17:21 2025 Epoch Time (ms): 1756966641366 [2025-09-04T06:17:21.638Z] variation: NoOptions [2025-09-04T06:17:21.638Z] JVM_OPTIONS: [2025-09-04T06:17:21.638Z] { \ [2025-09-04T06:17:21.638Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T06:17:21.638Z] echo "Nothing to be done for setup."; \ [2025-09-04T06:17:21.638Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/renaissance-log-regression_0"; \ [2025-09-04T06:17:21.638Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/renaissance-log-regression_0"; \ [2025-09-04T06:17:21.638Z] echo ""; echo "TESTING:"; \ [2025-09-04T06:17:21.638Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-09-04T06:17:21.639Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T06:17:21.639Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T06:17:21.639Z] echo "Nothing to be done for teardown."; \ [2025-09-04T06:17:21.639Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/TestTargetResult"; [2025-09-04T06:17:21.639Z] [2025-09-04T06:17:21.639Z] TEST SETUP: [2025-09-04T06:17:21.639Z] Nothing to be done for setup. [2025-09-04T06:17:21.639Z] [2025-09-04T06:17:21.639Z] TESTING: [2025-09-04T06:17:44.716Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-04T06:17:55.515Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-09-04T06:17:55.842Z] GC before operation: completed in 257.074 ms, heap usage 61.489 MB -> 35.359 MB. [2025-09-04T06:18:53.700Z] ====== log-regression (apache-spark) [default], iteration 0 completed (52292.673 ms) ====== [2025-09-04T06:18:53.700Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-09-04T06:18:53.700Z] GC before operation: completed in 519.572 ms, heap usage 571.345 MB -> 123.687 MB. [2025-09-04T06:19:00.960Z] ====== log-regression (apache-spark) [default], iteration 1 completed (11370.981 ms) ====== [2025-09-04T06:19:00.960Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-09-04T06:19:00.960Z] GC before operation: completed in 552.161 ms, heap usage 388.092 MB -> 123.798 MB. [2025-09-04T06:19:09.849Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8773.721 ms) ====== [2025-09-04T06:19:09.849Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-09-04T06:19:10.174Z] GC before operation: completed in 617.244 ms, heap usage 614.271 MB -> 124.951 MB. [2025-09-04T06:19:19.059Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7702.966 ms) ====== [2025-09-04T06:19:19.059Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-09-04T06:19:19.059Z] GC before operation: completed in 642.843 ms, heap usage 727.950 MB -> 125.431 MB. [2025-09-04T06:19:26.376Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7114.828 ms) ====== [2025-09-04T06:19:26.376Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-09-04T06:19:26.376Z] GC before operation: completed in 670.246 ms, heap usage 737.500 MB -> 125.744 MB. [2025-09-04T06:19:33.647Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7231.748 ms) ====== [2025-09-04T06:19:33.647Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-09-04T06:19:34.359Z] GC before operation: completed in 715.007 ms, heap usage 416.734 MB -> 124.975 MB. [2025-09-04T06:19:41.732Z] ====== log-regression (apache-spark) [default], iteration 6 completed (7303.707 ms) ====== [2025-09-04T06:19:41.732Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-09-04T06:19:42.063Z] GC before operation: completed in 737.044 ms, heap usage 617.841 MB -> 125.748 MB. [2025-09-04T06:19:49.315Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6721.056 ms) ====== [2025-09-04T06:19:49.315Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-09-04T06:19:49.644Z] GC before operation: completed in 746.151 ms, heap usage 1.097 GB -> 126.638 MB. [2025-09-04T06:19:56.945Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7002.589 ms) ====== [2025-09-04T06:19:56.945Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-09-04T06:19:57.653Z] GC before operation: completed in 767.522 ms, heap usage 505.956 MB -> 125.889 MB. [2025-09-04T06:20:04.915Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6557.971 ms) ====== [2025-09-04T06:20:04.915Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-09-04T06:20:04.915Z] GC before operation: completed in 786.543 ms, heap usage 549.246 MB -> 127.461 MB. [2025-09-04T06:20:12.164Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6645.862 ms) ====== [2025-09-04T06:20:12.164Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-09-04T06:20:12.164Z] GC before operation: completed in 815.313 ms, heap usage 531.795 MB -> 126.399 MB. [2025-09-04T06:20:19.435Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6571.148 ms) ====== [2025-09-04T06:20:19.435Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-09-04T06:20:19.760Z] GC before operation: completed in 817.284 ms, heap usage 470.089 MB -> 126.346 MB. [2025-09-04T06:20:27.144Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6700.992 ms) ====== [2025-09-04T06:20:27.144Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-09-04T06:20:27.144Z] GC before operation: completed in 838.410 ms, heap usage 589.247 MB -> 126.887 MB. [2025-09-04T06:20:34.403Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6059.432 ms) ====== [2025-09-04T06:20:34.403Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-09-04T06:20:34.403Z] GC before operation: completed in 842.010 ms, heap usage 216.103 MB -> 126.182 MB. [2025-09-04T06:20:41.646Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6437.289 ms) ====== [2025-09-04T06:20:41.646Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-09-04T06:20:41.646Z] GC before operation: completed in 858.963 ms, heap usage 356.092 MB -> 126.655 MB. [2025-09-04T06:20:48.895Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6657.653 ms) ====== [2025-09-04T06:20:48.895Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-09-04T06:20:48.895Z] GC before operation: completed in 861.382 ms, heap usage 418.695 MB -> 127.040 MB. [2025-09-04T06:20:56.159Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6273.698 ms) ====== [2025-09-04T06:20:56.159Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-09-04T06:20:56.159Z] GC before operation: completed in 880.548 ms, heap usage 405.529 MB -> 127.281 MB. [2025-09-04T06:21:03.416Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6195.816 ms) ====== [2025-09-04T06:21:03.416Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-09-04T06:21:03.416Z] GC before operation: completed in 893.741 ms, heap usage 460.149 MB -> 127.476 MB. [2025-09-04T06:21:09.410Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6326.713 ms) ====== [2025-09-04T06:21:09.410Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-09-04T06:21:10.585Z] GC before operation: completed in 928.490 ms, heap usage 247.018 MB -> 127.215 MB. [2025-09-04T06:21:17.850Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6607.975 ms) ====== [2025-09-04T06:21:18.990Z] ----------------------------------- [2025-09-04T06:21:18.990Z] renaissance-log-regression_0_PASSED [2025-09-04T06:21:18.990Z] ----------------------------------- [2025-09-04T06:21:18.990Z] [2025-09-04T06:21:18.990Z] TEST TEARDOWN: [2025-09-04T06:21:18.990Z] Nothing to be done for teardown. [2025-09-04T06:21:18.990Z] renaissance-log-regression_0 Finish Time: Thu Sep 4 06:21:18 2025 Epoch Time (ms): 1756966878739