[2025-06-12T20:10:17.561Z] Running test renaissance-log-regression_0 ... [2025-06-12T20:10:17.561Z] =============================================== [2025-06-12T20:10:17.561Z] renaissance-log-regression_0 Start Time: Thu Jun 12 20:10:16 2025 Epoch Time (ms): 1749759016793 [2025-06-12T20:10:17.561Z] variation: NoOptions [2025-06-12T20:10:17.561Z] JVM_OPTIONS: [2025-06-12T20:10:17.561Z] { \ [2025-06-12T20:10:17.561Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T20:10:17.561Z] echo "Nothing to be done for setup."; \ [2025-06-12T20:10:17.561Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-log-regression_0"; \ [2025-06-12T20:10:17.561Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-log-regression_0"; \ [2025-06-12T20:10:17.561Z] echo ""; echo "TESTING:"; \ [2025-06-12T20:10:17.561Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-12T20:10:17.561Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T20:10:17.561Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T20:10:17.561Z] echo "Nothing to be done for teardown."; \ [2025-06-12T20:10:17.561Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/TestTargetResult"; [2025-06-12T20:10:17.561Z] [2025-06-12T20:10:17.561Z] TEST SETUP: [2025-06-12T20:10:17.561Z] Nothing to be done for setup. [2025-06-12T20:10:17.561Z] [2025-06-12T20:10:17.561Z] TESTING: [2025-06-12T20:10:29.177Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-12T20:10:34.743Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-12T20:10:34.743Z] GC before operation: completed in 111.546 ms, heap usage 64.760 MB -> 35.001 MB. [2025-06-12T20:11:04.716Z] ====== log-regression (apache-spark) [default], iteration 0 completed (27437.021 ms) ====== [2025-06-12T20:11:04.716Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-12T20:11:04.716Z] GC before operation: completed in 292.333 ms, heap usage 359.972 MB -> 119.632 MB. [2025-06-12T20:11:09.193Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6140.241 ms) ====== [2025-06-12T20:11:09.193Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-12T20:11:09.193Z] GC before operation: completed in 264.140 ms, heap usage 281.512 MB -> 120.047 MB. [2025-06-12T20:11:14.818Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5620.323 ms) ====== [2025-06-12T20:11:14.818Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-12T20:11:14.818Z] GC before operation: completed in 175.211 ms, heap usage 536.376 MB -> 121.161 MB. [2025-06-12T20:11:20.460Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4977.188 ms) ====== [2025-06-12T20:11:20.460Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-12T20:11:20.460Z] GC before operation: completed in 163.856 ms, heap usage 363.998 MB -> 121.389 MB. [2025-06-12T20:11:24.943Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4996.619 ms) ====== [2025-06-12T20:11:24.943Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-12T20:11:24.943Z] GC before operation: completed in 234.642 ms, heap usage 345.156 MB -> 121.220 MB. [2025-06-12T20:11:29.427Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3723.326 ms) ====== [2025-06-12T20:11:29.427Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-12T20:11:29.427Z] GC before operation: completed in 206.442 ms, heap usage 427.024 MB -> 122.852 MB. [2025-06-12T20:11:32.843Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3593.992 ms) ====== [2025-06-12T20:11:32.843Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-12T20:11:32.843Z] GC before operation: completed in 179.494 ms, heap usage 284.700 MB -> 121.431 MB. [2025-06-12T20:11:36.264Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3350.165 ms) ====== [2025-06-12T20:11:36.264Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-12T20:11:36.264Z] GC before operation: completed in 234.728 ms, heap usage 322.973 MB -> 121.699 MB. [2025-06-12T20:11:40.726Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3766.709 ms) ====== [2025-06-12T20:11:40.726Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-12T20:11:40.726Z] GC before operation: completed in 230.634 ms, heap usage 289.546 MB -> 121.922 MB. [2025-06-12T20:11:44.153Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3908.653 ms) ====== [2025-06-12T20:11:44.153Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-12T20:11:44.921Z] GC before operation: completed in 220.372 ms, heap usage 236.279 MB -> 121.875 MB. [2025-06-12T20:11:48.328Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3943.075 ms) ====== [2025-06-12T20:11:48.328Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-12T20:11:49.098Z] GC before operation: completed in 241.459 ms, heap usage 395.564 MB -> 122.541 MB. [2025-06-12T20:11:52.523Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3878.743 ms) ====== [2025-06-12T20:11:52.523Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-12T20:11:53.284Z] GC before operation: completed in 255.166 ms, heap usage 316.104 MB -> 122.564 MB. [2025-06-12T20:11:56.962Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3627.940 ms) ====== [2025-06-12T20:11:56.962Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-12T20:11:56.962Z] GC before operation: completed in 221.507 ms, heap usage 275.146 MB -> 122.582 MB. [2025-06-12T20:12:00.401Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3688.299 ms) ====== [2025-06-12T20:12:00.401Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-12T20:12:00.401Z] GC before operation: completed in 244.130 ms, heap usage 232.210 MB -> 122.766 MB. [2025-06-12T20:12:04.860Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3911.034 ms) ====== [2025-06-12T20:12:04.860Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-12T20:12:04.860Z] GC before operation: completed in 198.495 ms, heap usage 448.761 MB -> 123.594 MB. [2025-06-12T20:12:08.304Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3783.637 ms) ====== [2025-06-12T20:12:08.305Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-12T20:12:09.077Z] GC before operation: completed in 238.068 ms, heap usage 275.785 MB -> 123.197 MB. [2025-06-12T20:12:13.531Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4531.010 ms) ====== [2025-06-12T20:12:13.531Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-12T20:12:13.531Z] GC before operation: completed in 294.579 ms, heap usage 356.864 MB -> 123.706 MB. [2025-06-12T20:12:16.907Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3395.983 ms) ====== [2025-06-12T20:12:16.907Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-12T20:12:17.688Z] GC before operation: completed in 241.048 ms, heap usage 339.098 MB -> 123.761 MB. [2025-06-12T20:12:21.117Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3567.114 ms) ====== [2025-06-12T20:12:21.117Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-12T20:12:21.117Z] GC before operation: completed in 188.927 ms, heap usage 321.749 MB -> 124.007 MB. [2025-06-12T20:12:24.512Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3276.293 ms) ====== [2025-06-12T20:12:25.271Z] ----------------------------------- [2025-06-12T20:12:25.271Z] renaissance-log-regression_0_PASSED [2025-06-12T20:12:25.271Z] ----------------------------------- [2025-06-12T20:12:25.271Z] [2025-06-12T20:12:25.271Z] TEST TEARDOWN: [2025-06-12T20:12:25.271Z] Nothing to be done for teardown. [2025-06-12T20:12:25.271Z] renaissance-log-regression_0 Finish Time: Thu Jun 12 20:12:24 2025 Epoch Time (ms): 1749759144490