[2025-09-04T02:15:37.853Z] Running test renaissance-log-regression_0 ... [2025-09-04T02:15:37.853Z] =============================================== [2025-09-04T02:15:37.853Z] renaissance-log-regression_0 Start Time: Thu Sep 4 02:15:37 2025 Epoch Time (ms): 1756952137712 [2025-09-04T02:15:37.853Z] variation: NoOptions [2025-09-04T02:15:37.853Z] JVM_OPTIONS: [2025-09-04T02:15:37.853Z] { \ [2025-09-04T02:15:37.853Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T02:15:37.853Z] echo "Nothing to be done for setup."; \ [2025-09-04T02:15:37.853Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-log-regression_0"; \ [2025-09-04T02:15:37.853Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-log-regression_0"; \ [2025-09-04T02:15:37.853Z] echo ""; echo "TESTING:"; \ [2025-09-04T02:15:37.853Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-09-04T02:15:37.853Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T02:15:37.853Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T02:15:37.853Z] echo "Nothing to be done for teardown."; \ [2025-09-04T02:15:37.853Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/TestTargetResult"; [2025-09-04T02:15:37.853Z] [2025-09-04T02:15:37.853Z] TEST SETUP: [2025-09-04T02:15:37.853Z] Nothing to be done for setup. [2025-09-04T02:15:37.853Z] [2025-09-04T02:15:37.853Z] TESTING: [2025-09-04T02:15:48.827Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-04T02:15:49.969Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-09-04T02:15:49.969Z] GC before operation: completed in 73.556 ms, heap usage 65.301 MB -> 35.213 MB. [2025-09-04T02:16:08.535Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17621.863 ms) ====== [2025-09-04T02:16:08.535Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-09-04T02:16:08.535Z] GC before operation: completed in 119.867 ms, heap usage 290.566 MB -> 119.835 MB. [2025-09-04T02:16:14.528Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3677.444 ms) ====== [2025-09-04T02:16:14.528Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-09-04T02:16:14.528Z] GC before operation: completed in 101.100 ms, heap usage 305.081 MB -> 120.437 MB. [2025-09-04T02:16:14.528Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3069.838 ms) ====== [2025-09-04T02:16:14.528Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-09-04T02:16:14.528Z] GC before operation: completed in 93.140 ms, heap usage 449.699 MB -> 121.189 MB. [2025-09-04T02:16:18.351Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2401.107 ms) ====== [2025-09-04T02:16:18.351Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-09-04T02:16:18.351Z] GC before operation: completed in 105.201 ms, heap usage 348.220 MB -> 121.101 MB. [2025-09-04T02:16:20.591Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2342.090 ms) ====== [2025-09-04T02:16:20.591Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-09-04T02:16:20.591Z] GC before operation: completed in 116.097 ms, heap usage 509.330 MB -> 121.836 MB. [2025-09-04T02:16:21.755Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2249.277 ms) ====== [2025-09-04T02:16:21.755Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-09-04T02:16:21.755Z] GC before operation: completed in 104.064 ms, heap usage 390.210 MB -> 121.677 MB. [2025-09-04T02:16:24.848Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2273.181 ms) ====== [2025-09-04T02:16:24.848Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-09-04T02:16:24.848Z] GC before operation: completed in 112.750 ms, heap usage 253.051 MB -> 121.532 MB. [2025-09-04T02:16:26.980Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2343.343 ms) ====== [2025-09-04T02:16:26.980Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-09-04T02:16:26.980Z] GC before operation: completed in 110.077 ms, heap usage 280.017 MB -> 121.776 MB. [2025-09-04T02:16:28.925Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2078.550 ms) ====== [2025-09-04T02:16:28.925Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-09-04T02:16:28.925Z] GC before operation: completed in 116.200 ms, heap usage 432.135 MB -> 122.488 MB. [2025-09-04T02:16:31.928Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2180.511 ms) ====== [2025-09-04T02:16:31.928Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-09-04T02:16:31.929Z] GC before operation: completed in 114.255 ms, heap usage 420.527 MB -> 122.811 MB. [2025-09-04T02:16:33.876Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2100.268 ms) ====== [2025-09-04T02:16:33.877Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-09-04T02:16:33.877Z] GC before operation: completed in 116.846 ms, heap usage 234.975 MB -> 122.340 MB. [2025-09-04T02:16:35.825Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2181.773 ms) ====== [2025-09-04T02:16:35.825Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-09-04T02:16:35.825Z] GC before operation: completed in 127.524 ms, heap usage 292.500 MB -> 122.703 MB. [2025-09-04T02:16:37.772Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2141.862 ms) ====== [2025-09-04T02:16:37.772Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-09-04T02:16:38.721Z] GC before operation: completed in 114.309 ms, heap usage 338.477 MB -> 123.064 MB. [2025-09-04T02:16:40.678Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2122.406 ms) ====== [2025-09-04T02:16:40.678Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-09-04T02:16:40.678Z] GC before operation: completed in 115.353 ms, heap usage 283.900 MB -> 123.067 MB. [2025-09-04T02:16:42.629Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2053.307 ms) ====== [2025-09-04T02:16:42.629Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-09-04T02:16:42.629Z] GC before operation: completed in 121.752 ms, heap usage 266.754 MB -> 123.257 MB. [2025-09-04T02:16:44.579Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2067.173 ms) ====== [2025-09-04T02:16:44.579Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-09-04T02:16:44.579Z] GC before operation: completed in 126.179 ms, heap usage 259.927 MB -> 123.485 MB. [2025-09-04T02:16:47.589Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2184.827 ms) ====== [2025-09-04T02:16:47.589Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-09-04T02:16:47.589Z] GC before operation: completed in 123.560 ms, heap usage 259.016 MB -> 123.679 MB. [2025-09-04T02:16:49.538Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1989.024 ms) ====== [2025-09-04T02:16:49.538Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-09-04T02:16:49.539Z] GC before operation: completed in 122.479 ms, heap usage 239.173 MB -> 123.783 MB. [2025-09-04T02:16:51.486Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1924.977 ms) ====== [2025-09-04T02:16:51.486Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-09-04T02:16:51.486Z] GC before operation: completed in 130.698 ms, heap usage 335.098 MB -> 124.254 MB. [2025-09-04T02:16:53.427Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2011.968 ms) ====== [2025-09-04T02:16:55.059Z] ----------------------------------- [2025-09-04T02:16:55.059Z] renaissance-log-regression_0_PASSED [2025-09-04T02:16:55.059Z] ----------------------------------- [2025-09-04T02:16:55.059Z] [2025-09-04T02:16:55.059Z] TEST TEARDOWN: [2025-09-04T02:16:55.059Z] Nothing to be done for teardown. [2025-09-04T02:16:55.059Z] renaissance-log-regression_0 Finish Time: Thu Sep 4 02:16:53 2025 Epoch Time (ms): 1756952213429