[2025-06-28T02:16:32.098Z] Running test renaissance-log-regression_0 ... [2025-06-28T02:16:32.098Z] =============================================== [2025-06-28T02:16:32.098Z] renaissance-log-regression_0 Start Time: Sat Jun 28 02:16:31 2025 Epoch Time (ms): 1751076991548 [2025-06-28T02:16:32.098Z] variation: NoOptions [2025-06-28T02:16:32.098Z] JVM_OPTIONS: [2025-06-28T02:16:32.098Z] { \ [2025-06-28T02:16:32.098Z] echo ""; echo "TEST SETUP:"; \ [2025-06-28T02:16:32.098Z] echo "Nothing to be done for setup."; \ [2025-06-28T02:16:32.098Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17510726135491/renaissance-log-regression_0"; \ [2025-06-28T02:16:32.098Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17510726135491/renaissance-log-regression_0"; \ [2025-06-28T02:16:32.098Z] echo ""; echo "TESTING:"; \ [2025-06-28T02:16:32.098Z] "/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_17510726135491/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-28T02:16:32.098Z] 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_17510726135491/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-28T02:16:32.098Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-28T02:16:32.098Z] echo "Nothing to be done for teardown."; \ [2025-06-28T02:16:32.098Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17510726135491/TestTargetResult"; [2025-06-28T02:16:32.098Z] [2025-06-28T02:16:32.098Z] TEST SETUP: [2025-06-28T02:16:32.098Z] Nothing to be done for setup. [2025-06-28T02:16:32.098Z] [2025-06-28T02:16:32.098Z] TESTING: [2025-06-28T02:16:55.114Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-28T02:17:05.906Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-28T02:17:05.906Z] GC before operation: completed in 264.683 ms, heap usage 62.584 MB -> 35.372 MB. [2025-06-28T02:18:05.370Z] ====== log-regression (apache-spark) [default], iteration 0 completed (53444.547 ms) ====== [2025-06-28T02:18:05.370Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-28T02:18:05.370Z] GC before operation: completed in 486.254 ms, heap usage 608.688 MB -> 123.652 MB. [2025-06-28T02:18:11.266Z] ====== log-regression (apache-spark) [default], iteration 1 completed (10873.319 ms) ====== [2025-06-28T02:18:11.266Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-28T02:18:11.266Z] GC before operation: completed in 576.141 ms, heap usage 614.678 MB -> 124.288 MB. [2025-06-28T02:18:20.153Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8943.818 ms) ====== [2025-06-28T02:18:20.153Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-28T02:18:20.484Z] GC before operation: completed in 606.324 ms, heap usage 597.111 MB -> 124.611 MB. [2025-06-28T02:18:29.358Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7748.194 ms) ====== [2025-06-28T02:18:29.358Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-28T02:18:29.358Z] GC before operation: completed in 671.099 ms, heap usage 281.930 MB -> 124.144 MB. [2025-06-28T02:18:36.628Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7692.191 ms) ====== [2025-06-28T02:18:36.628Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-28T02:18:37.338Z] GC before operation: completed in 725.762 ms, heap usage 295.091 MB -> 124.300 MB. [2025-06-28T02:18:44.717Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7104.405 ms) ====== [2025-06-28T02:18:44.717Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-28T02:18:45.438Z] GC before operation: completed in 736.279 ms, heap usage 325.444 MB -> 124.678 MB. [2025-06-28T02:18:52.731Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6936.981 ms) ====== [2025-06-28T02:18:52.731Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-28T02:18:53.069Z] GC before operation: completed in 737.705 ms, heap usage 239.655 MB -> 124.636 MB. [2025-06-28T02:19:00.415Z] ====== log-regression (apache-spark) [default], iteration 7 completed (7127.523 ms) ====== [2025-06-28T02:19:00.415Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-28T02:19:01.129Z] GC before operation: completed in 796.010 ms, heap usage 903.662 MB -> 126.492 MB. [2025-06-28T02:19:08.406Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7055.161 ms) ====== [2025-06-28T02:19:08.406Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-28T02:19:08.743Z] GC before operation: completed in 795.672 ms, heap usage 236.090 MB -> 125.059 MB. [2025-06-28T02:19:16.018Z] ====== log-regression (apache-spark) [default], iteration 9 completed (7221.988 ms) ====== [2025-06-28T02:19:16.018Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-28T02:19:16.744Z] GC before operation: completed in 793.631 ms, heap usage 526.595 MB -> 125.986 MB. [2025-06-28T02:19:24.164Z] ====== log-regression (apache-spark) [default], iteration 10 completed (7022.326 ms) ====== [2025-06-28T02:19:24.164Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-28T02:19:24.907Z] GC before operation: completed in 1040.529 ms, heap usage 829.721 MB -> 127.117 MB. [2025-06-28T02:19:32.199Z] ====== log-regression (apache-spark) [default], iteration 11 completed (7059.185 ms) ====== [2025-06-28T02:19:32.199Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-28T02:19:32.938Z] GC before operation: completed in 867.398 ms, heap usage 474.897 MB -> 126.267 MB. [2025-06-28T02:19:40.232Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6935.230 ms) ====== [2025-06-28T02:19:40.232Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-28T02:19:40.565Z] GC before operation: completed in 832.848 ms, heap usage 366.395 MB -> 126.264 MB. [2025-06-28T02:19:47.855Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6697.971 ms) ====== [2025-06-28T02:19:47.855Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-28T02:19:48.187Z] GC before operation: completed in 862.956 ms, heap usage 227.059 MB -> 126.096 MB. [2025-06-28T02:19:55.448Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6315.473 ms) ====== [2025-06-28T02:19:55.448Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-28T02:19:55.448Z] GC before operation: completed in 837.054 ms, heap usage 445.047 MB -> 126.893 MB. [2025-06-28T02:20:02.946Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6476.337 ms) ====== [2025-06-28T02:20:02.946Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-28T02:20:02.946Z] GC before operation: completed in 881.320 ms, heap usage 398.254 MB -> 127.028 MB. [2025-06-28T02:20:10.210Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6853.451 ms) ====== [2025-06-28T02:20:10.210Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-28T02:20:10.535Z] GC before operation: completed in 890.317 ms, heap usage 858.715 MB -> 128.423 MB. [2025-06-28T02:20:17.799Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6464.195 ms) ====== [2025-06-28T02:20:17.799Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-28T02:20:17.799Z] GC before operation: completed in 895.838 ms, heap usage 302.272 MB -> 127.047 MB. [2025-06-28T02:20:25.065Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6342.816 ms) ====== [2025-06-28T02:20:25.065Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-28T02:20:25.065Z] GC before operation: completed in 909.393 ms, heap usage 411.068 MB -> 127.576 MB. [2025-06-28T02:20:32.329Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6147.397 ms) ====== [2025-06-28T02:20:33.032Z] ----------------------------------- [2025-06-28T02:20:33.032Z] renaissance-log-regression_0_PASSED [2025-06-28T02:20:33.032Z] ----------------------------------- [2025-06-28T02:20:33.032Z] [2025-06-28T02:20:33.032Z] TEST TEARDOWN: [2025-06-28T02:20:33.032Z] Nothing to be done for teardown. [2025-06-28T02:20:33.032Z] renaissance-log-regression_0 Finish Time: Sat Jun 28 02:20:32 2025 Epoch Time (ms): 1751077232941