[2025-05-21T21:23:41.748Z] Running test renaissance-log-regression_0 ... [2025-05-21T21:23:41.748Z] =============================================== [2025-05-21T21:23:41.748Z] renaissance-log-regression_0 Start Time: Wed May 21 16:23:40 2025 Epoch Time (ms): 1747862620281 [2025-05-21T21:23:41.748Z] variation: NoOptions [2025-05-21T21:23:41.748Z] JVM_OPTIONS: [2025-05-21T21:23:41.748Z] { \ [2025-05-21T21:23:41.748Z] echo ""; echo "TEST SETUP:"; \ [2025-05-21T21:23:41.748Z] echo "Nothing to be done for setup."; \ [2025-05-21T21:23:41.748Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478616752310/renaissance-log-regression_0"; \ [2025-05-21T21:23:41.748Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478616752310/renaissance-log-regression_0"; \ [2025-05-21T21:23:41.748Z] echo ""; echo "TESTING:"; \ [2025-05-21T21:23:41.748Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478616752310/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-21T21:23:41.748Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478616752310/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-21T21:23:41.748Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-21T21:23:41.748Z] echo "Nothing to be done for teardown."; \ [2025-05-21T21:23:41.748Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478616752310/TestTargetResult"; [2025-05-21T21:23:41.748Z] [2025-05-21T21:23:41.748Z] TEST SETUP: [2025-05-21T21:23:41.748Z] Nothing to be done for setup. [2025-05-21T21:23:41.748Z] [2025-05-21T21:23:41.748Z] TESTING: [2025-05-21T21:23:48.006Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-05-21T21:23:51.557Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-21T21:23:51.557Z] GC before operation: completed in 64.220 ms, heap usage 49.516 MB -> 35.453 MB. [2025-05-21T21:24:02.399Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11365.118 ms) ====== [2025-05-21T21:24:02.399Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-21T21:24:02.399Z] GC before operation: completed in 94.383 ms, heap usage 281.116 MB -> 125.238 MB. [2025-05-21T21:24:04.626Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2489.063 ms) ====== [2025-05-21T21:24:04.626Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-21T21:24:05.310Z] GC before operation: completed in 92.019 ms, heap usage 500.567 MB -> 126.476 MB. [2025-05-21T21:24:07.522Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2294.176 ms) ====== [2025-05-21T21:24:07.522Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-21T21:24:07.522Z] GC before operation: completed in 84.434 ms, heap usage 317.295 MB -> 126.340 MB. [2025-05-21T21:24:09.732Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2143.348 ms) ====== [2025-05-21T21:24:09.732Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-21T21:24:09.732Z] GC before operation: completed in 78.264 ms, heap usage 250.672 MB -> 126.680 MB. [2025-05-21T21:24:11.943Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2174.842 ms) ====== [2025-05-21T21:24:11.943Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-21T21:24:11.943Z] GC before operation: completed in 103.663 ms, heap usage 300.012 MB -> 126.966 MB. [2025-05-21T21:24:14.152Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2102.510 ms) ====== [2025-05-21T21:24:14.152Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-21T21:24:14.152Z] GC before operation: completed in 89.000 ms, heap usage 407.857 MB -> 127.743 MB. [2025-05-21T21:24:16.362Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2028.122 ms) ====== [2025-05-21T21:24:16.362Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-21T21:24:16.362Z] GC before operation: completed in 99.160 ms, heap usage 323.535 MB -> 127.962 MB. [2025-05-21T21:24:18.576Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1948.835 ms) ====== [2025-05-21T21:24:18.576Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-21T21:24:18.576Z] GC before operation: completed in 90.370 ms, heap usage 360.878 MB -> 128.491 MB. [2025-05-21T21:24:19.996Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1986.449 ms) ====== [2025-05-21T21:24:19.996Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-21T21:24:20.678Z] GC before operation: completed in 95.992 ms, heap usage 417.662 MB -> 129.068 MB. [2025-05-21T21:24:22.096Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1905.932 ms) ====== [2025-05-21T21:24:22.096Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-21T21:24:22.096Z] GC before operation: completed in 91.755 ms, heap usage 448.899 MB -> 129.346 MB. [2025-05-21T21:24:24.308Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2061.878 ms) ====== [2025-05-21T21:24:24.308Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-21T21:24:24.308Z] GC before operation: completed in 83.381 ms, heap usage 464.515 MB -> 129.839 MB. [2025-05-21T21:24:26.521Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1950.465 ms) ====== [2025-05-21T21:24:26.521Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-21T21:24:26.521Z] GC before operation: completed in 86.335 ms, heap usage 257.007 MB -> 129.618 MB. [2025-05-21T21:24:28.827Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2058.558 ms) ====== [2025-05-21T21:24:28.827Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-21T21:24:28.827Z] GC before operation: completed in 80.850 ms, heap usage 503.948 MB -> 130.604 MB. [2025-05-21T21:24:31.039Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1899.010 ms) ====== [2025-05-21T21:24:31.039Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-21T21:24:31.039Z] GC before operation: completed in 105.272 ms, heap usage 364.011 MB -> 130.678 MB. [2025-05-21T21:24:32.463Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1939.340 ms) ====== [2025-05-21T21:24:32.463Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-21T21:24:32.463Z] GC before operation: completed in 93.200 ms, heap usage 264.348 MB -> 130.759 MB. [2025-05-21T21:24:34.672Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1938.082 ms) ====== [2025-05-21T21:24:34.672Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-21T21:24:34.672Z] GC before operation: completed in 86.092 ms, heap usage 474.087 MB -> 131.750 MB. [2025-05-21T21:24:36.886Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1896.310 ms) ====== [2025-05-21T21:24:36.886Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-21T21:24:36.886Z] GC before operation: completed in 107.285 ms, heap usage 433.204 MB -> 132.017 MB. [2025-05-21T21:24:39.097Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1989.879 ms) ====== [2025-05-21T21:24:39.097Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-21T21:24:39.097Z] GC before operation: completed in 97.446 ms, heap usage 412.684 MB -> 132.251 MB. [2025-05-21T21:24:40.515Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1894.960 ms) ====== [2025-05-21T21:24:40.515Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-21T21:24:40.515Z] GC before operation: completed in 88.921 ms, heap usage 392.080 MB -> 132.554 MB. [2025-05-21T21:24:42.724Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1888.406 ms) ====== [2025-05-21T21:24:43.408Z] ----------------------------------- [2025-05-21T21:24:43.408Z] renaissance-log-regression_0_PASSED [2025-05-21T21:24:43.408Z] ----------------------------------- [2025-05-21T21:24:43.408Z] [2025-05-21T21:24:43.408Z] TEST TEARDOWN: [2025-05-21T21:24:43.408Z] Nothing to be done for teardown. [2025-05-21T21:24:43.408Z] renaissance-log-regression_0 Finish Time: Wed May 21 16:24:41 2025 Epoch Time (ms): 1747862681954