[2025-06-12T14:57:36.478Z] Running test renaissance-log-regression_0 ... [2025-06-12T14:57:36.478Z] =============================================== [2025-06-12T14:57:36.478Z] renaissance-log-regression_0 Start Time: Thu Jun 12 14:57:35 2025 Epoch Time (ms): 1749740256001 [2025-06-12T14:57:36.478Z] variation: NoOptions [2025-06-12T14:57:36.478Z] JVM_OPTIONS: [2025-06-12T14:57:36.478Z] { \ [2025-06-12T14:57:36.478Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T14:57:36.478Z] echo "Nothing to be done for setup."; \ [2025-06-12T14:57:36.478Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17497362412280/renaissance-log-regression_0"; \ [2025-06-12T14:57:36.478Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17497362412280/renaissance-log-regression_0"; \ [2025-06-12T14:57:36.478Z] echo ""; echo "TESTING:"; \ [2025-06-12T14:57:36.478Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17497362412280/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-12T14:57:36.478Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17497362412280/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T14:57:36.478Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T14:57:36.478Z] echo "Nothing to be done for teardown."; \ [2025-06-12T14:57:36.478Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17497362412280/TestTargetResult"; [2025-06-12T14:57:36.478Z] [2025-06-12T14:57:36.478Z] TEST SETUP: [2025-06-12T14:57:36.478Z] Nothing to be done for setup. [2025-06-12T14:57:36.478Z] [2025-06-12T14:57:36.478Z] TESTING: [2025-06-12T14:57:59.466Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-12T14:58:10.269Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-12T14:58:10.269Z] GC before operation: completed in 225.410 ms, heap usage 39.969 MB -> 36.269 MB. [2025-06-12T14:59:07.968Z] ====== log-regression (apache-spark) [default], iteration 0 completed (56500.653 ms) ====== [2025-06-12T14:59:07.969Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-12T14:59:07.969Z] GC before operation: completed in 475.147 ms, heap usage 455.411 MB -> 124.358 MB. [2025-06-12T14:59:21.083Z] ====== log-regression (apache-spark) [default], iteration 1 completed (11632.796 ms) ====== [2025-06-12T14:59:21.083Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-12T14:59:21.083Z] GC before operation: completed in 509.543 ms, heap usage 277.232 MB -> 124.503 MB. [2025-06-12T14:59:29.964Z] ====== log-regression (apache-spark) [default], iteration 2 completed (9510.401 ms) ====== [2025-06-12T14:59:29.964Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-12T14:59:29.964Z] GC before operation: completed in 551.101 ms, heap usage 344.823 MB -> 125.072 MB. [2025-06-12T14:59:38.849Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8278.579 ms) ====== [2025-06-12T14:59:38.849Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-12T14:59:38.849Z] GC before operation: completed in 587.492 ms, heap usage 458.482 MB -> 125.732 MB. [2025-06-12T14:59:46.118Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7240.777 ms) ====== [2025-06-12T14:59:46.118Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-12T14:59:46.448Z] GC before operation: completed in 663.851 ms, heap usage 511.431 MB -> 126.173 MB. [2025-06-12T14:59:53.810Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7053.849 ms) ====== [2025-06-12T14:59:53.810Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-12T14:59:54.157Z] GC before operation: completed in 689.130 ms, heap usage 324.499 MB -> 125.844 MB. [2025-06-12T15:00:01.441Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6666.068 ms) ====== [2025-06-12T15:00:01.442Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-12T15:00:01.442Z] GC before operation: completed in 712.624 ms, heap usage 328.051 MB -> 125.999 MB. [2025-06-12T15:00:08.710Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6800.889 ms) ====== [2025-06-12T15:00:08.710Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-12T15:00:09.043Z] GC before operation: completed in 702.628 ms, heap usage 301.133 MB -> 126.112 MB. [2025-06-12T15:00:16.321Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6560.491 ms) ====== [2025-06-12T15:00:16.321Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-12T15:00:16.321Z] GC before operation: completed in 720.332 ms, heap usage 481.934 MB -> 126.842 MB. [2025-06-12T15:00:23.661Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6606.894 ms) ====== [2025-06-12T15:00:23.661Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-12T15:00:23.661Z] GC before operation: completed in 735.840 ms, heap usage 329.160 MB -> 126.641 MB. [2025-06-12T15:00:31.035Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6481.684 ms) ====== [2025-06-12T15:00:31.035Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-12T15:00:31.035Z] GC before operation: completed in 751.334 ms, heap usage 283.301 MB -> 126.759 MB. [2025-06-12T15:00:38.292Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6506.861 ms) ====== [2025-06-12T15:00:38.292Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-12T15:00:38.292Z] GC before operation: completed in 771.222 ms, heap usage 549.763 MB -> 127.664 MB. [2025-06-12T15:00:45.561Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6287.156 ms) ====== [2025-06-12T15:00:45.561Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-12T15:00:45.561Z] GC before operation: completed in 795.796 ms, heap usage 481.321 MB -> 127.644 MB. [2025-06-12T15:00:51.476Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6196.760 ms) ====== [2025-06-12T15:00:51.477Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-12T15:00:52.205Z] GC before operation: completed in 804.420 ms, heap usage 312.639 MB -> 127.435 MB. [2025-06-12T15:00:59.464Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6017.147 ms) ====== [2025-06-12T15:00:59.465Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-12T15:00:59.465Z] GC before operation: completed in 814.153 ms, heap usage 526.412 MB -> 128.153 MB. [2025-06-12T15:01:06.733Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6458.756 ms) ====== [2025-06-12T15:01:06.733Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-12T15:01:06.733Z] GC before operation: completed in 825.004 ms, heap usage 481.474 MB -> 128.286 MB. [2025-06-12T15:01:12.701Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6185.852 ms) ====== [2025-06-12T15:01:12.701Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-12T15:01:15.584Z] GC before operation: completed in 835.936 ms, heap usage 473.606 MB -> 128.500 MB. [2025-06-12T15:01:19.373Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6006.570 ms) ====== [2025-06-12T15:01:19.373Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-12T15:01:20.539Z] GC before operation: completed in 855.893 ms, heap usage 279.828 MB -> 128.198 MB. [2025-06-12T15:01:26.457Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6109.116 ms) ====== [2025-06-12T15:01:26.457Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-12T15:01:27.185Z] GC before operation: completed in 866.977 ms, heap usage 340.242 MB -> 128.482 MB. [2025-06-12T15:01:34.436Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6059.073 ms) ====== [2025-06-12T15:01:35.134Z] ----------------------------------- [2025-06-12T15:01:35.134Z] renaissance-log-regression_0_PASSED [2025-06-12T15:01:35.134Z] ----------------------------------- [2025-06-12T15:01:35.134Z] [2025-06-12T15:01:35.134Z] TEST TEARDOWN: [2025-06-12T15:01:35.134Z] Nothing to be done for teardown. [2025-06-12T15:01:35.134Z] renaissance-log-regression_0 Finish Time: Thu Jun 12 15:01:35 2025 Epoch Time (ms): 1749740495101