[2025-07-01T13:05:43.667Z] Running test renaissance-log-regression_0 ... [2025-07-01T13:05:43.667Z] =============================================== [2025-07-01T13:05:43.667Z] renaissance-log-regression_0 Start Time: Tue Jul 1 13:05:43 2025 Epoch Time (ms): 1751375143259 [2025-07-01T13:05:43.667Z] variation: NoOptions [2025-07-01T13:05:43.667Z] JVM_OPTIONS: [2025-07-01T13:05:43.667Z] { \ [2025-07-01T13:05:43.667Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T13:05:43.667Z] echo "Nothing to be done for setup."; \ [2025-07-01T13:05:43.667Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-log-regression_0"; \ [2025-07-01T13:05:43.667Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-log-regression_0"; \ [2025-07-01T13:05:43.667Z] echo ""; echo "TESTING:"; \ [2025-07-01T13:05:43.667Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-01T13:05:43.667Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T13:05:43.667Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T13:05:43.667Z] echo "Nothing to be done for teardown."; \ [2025-07-01T13:05:43.667Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/TestTargetResult"; [2025-07-01T13:05:43.667Z] [2025-07-01T13:05:43.667Z] TEST SETUP: [2025-07-01T13:05:43.667Z] Nothing to be done for setup. [2025-07-01T13:05:43.667Z] [2025-07-01T13:05:43.667Z] TESTING: [2025-07-01T13:05:49.459Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T13:05:53.473Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-01T13:05:53.473Z] GC before operation: completed in 72.442 ms, heap usage 70.474 MB -> 35.177 MB. [2025-07-01T13:06:12.691Z] ====== log-regression (apache-spark) [default], iteration 0 completed (19202.036 ms) ====== [2025-07-01T13:06:12.692Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-01T13:06:12.692Z] GC before operation: completed in 233.996 ms, heap usage 504.576 MB -> 120.242 MB. [2025-07-01T13:06:18.467Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5749.069 ms) ====== [2025-07-01T13:06:18.467Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-01T13:06:18.467Z] GC before operation: completed in 170.659 ms, heap usage 397.559 MB -> 120.367 MB. [2025-07-01T13:06:21.980Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3517.828 ms) ====== [2025-07-01T13:06:21.980Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-01T13:06:21.980Z] GC before operation: completed in 149.266 ms, heap usage 320.618 MB -> 120.637 MB. [2025-07-01T13:06:25.493Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3486.301 ms) ====== [2025-07-01T13:06:25.493Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-01T13:06:25.493Z] GC before operation: completed in 163.869 ms, heap usage 269.963 MB -> 120.673 MB. [2025-07-01T13:06:29.012Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3231.287 ms) ====== [2025-07-01T13:06:29.012Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-01T13:06:29.012Z] GC before operation: completed in 228.247 ms, heap usage 724.556 MB -> 122.374 MB. [2025-07-01T13:06:33.628Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3890.183 ms) ====== [2025-07-01T13:06:33.628Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-01T13:06:33.628Z] GC before operation: completed in 199.175 ms, heap usage 351.943 MB -> 121.395 MB. [2025-07-01T13:06:37.166Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3527.013 ms) ====== [2025-07-01T13:06:37.166Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-01T13:06:37.166Z] GC before operation: completed in 159.792 ms, heap usage 368.726 MB -> 121.714 MB. [2025-07-01T13:06:39.713Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3064.237 ms) ====== [2025-07-01T13:06:39.713Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-01T13:06:40.505Z] GC before operation: completed in 211.773 ms, heap usage 693.794 MB -> 122.872 MB. [2025-07-01T13:06:44.020Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3319.542 ms) ====== [2025-07-01T13:06:44.020Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-01T13:06:44.020Z] GC before operation: completed in 205.854 ms, heap usage 157.807 MB -> 121.692 MB. [2025-07-01T13:06:48.063Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3353.176 ms) ====== [2025-07-01T13:06:48.063Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-01T13:06:48.063Z] GC before operation: completed in 177.019 ms, heap usage 367.525 MB -> 122.359 MB. [2025-07-01T13:06:51.604Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3886.641 ms) ====== [2025-07-01T13:06:51.604Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-01T13:06:51.604Z] GC before operation: completed in 202.203 ms, heap usage 986.108 MB -> 123.910 MB. [2025-07-01T13:06:55.116Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3459.152 ms) ====== [2025-07-01T13:06:55.116Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-01T13:06:55.116Z] GC before operation: completed in 234.908 ms, heap usage 416.735 MB -> 122.867 MB. [2025-07-01T13:06:58.610Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3078.663 ms) ====== [2025-07-01T13:06:58.610Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-01T13:06:58.610Z] GC before operation: completed in 192.206 ms, heap usage 465.194 MB -> 123.274 MB. [2025-07-01T13:07:01.159Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3197.791 ms) ====== [2025-07-01T13:07:01.159Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-01T13:07:01.950Z] GC before operation: completed in 192.222 ms, heap usage 416.883 MB -> 123.333 MB. [2025-07-01T13:07:04.488Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2984.245 ms) ====== [2025-07-01T13:07:04.488Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-01T13:07:04.488Z] GC before operation: completed in 155.365 ms, heap usage 579.633 MB -> 124.050 MB. [2025-07-01T13:07:07.013Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2509.964 ms) ====== [2025-07-01T13:07:07.013Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-01T13:07:07.800Z] GC before operation: completed in 161.496 ms, heap usage 378.219 MB -> 124.034 MB. [2025-07-01T13:07:09.428Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1581.784 ms) ====== [2025-07-01T13:07:09.428Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-01T13:07:09.428Z] GC before operation: completed in 102.246 ms, heap usage 657.369 MB -> 124.683 MB. [2025-07-01T13:07:11.053Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1627.918 ms) ====== [2025-07-01T13:07:11.053Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-01T13:07:11.053Z] GC before operation: completed in 107.670 ms, heap usage 239.273 MB -> 123.664 MB. [2025-07-01T13:07:12.685Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1619.688 ms) ====== [2025-07-01T13:07:12.685Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-01T13:07:12.685Z] GC before operation: completed in 99.065 ms, heap usage 728.758 MB -> 125.379 MB. [2025-07-01T13:07:14.310Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1818.162 ms) ====== [2025-07-01T13:07:15.096Z] ----------------------------------- [2025-07-01T13:07:15.096Z] renaissance-log-regression_0_PASSED [2025-07-01T13:07:15.096Z] ----------------------------------- [2025-07-01T13:07:15.096Z] [2025-07-01T13:07:15.096Z] TEST TEARDOWN: [2025-07-01T13:07:15.096Z] Nothing to be done for teardown. [2025-07-01T13:07:15.096Z] renaissance-log-regression_0 Finish Time: Tue Jul 1 13:07:14 2025 Epoch Time (ms): 1751375234457