[2025-08-28T05:58:09.461Z] Running test renaissance-log-regression_0 ... [2025-08-28T05:58:09.461Z] =============================================== [2025-08-28T05:58:09.461Z] renaissance-log-regression_0 Start Time: Thu Aug 28 05:58:09 2025 Epoch Time (ms): 1756360689139 [2025-08-28T05:58:09.461Z] variation: NoOptions [2025-08-28T05:58:09.461Z] JVM_OPTIONS: [2025-08-28T05:58:09.461Z] { \ [2025-08-28T05:58:09.461Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T05:58:09.461Z] echo "Nothing to be done for setup."; \ [2025-08-28T05:58:09.461Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-log-regression_0"; \ [2025-08-28T05:58:09.461Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-log-regression_0"; \ [2025-08-28T05:58:09.461Z] echo ""; echo "TESTING:"; \ [2025-08-28T05:58:09.461Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-28T05:58:09.461Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T05:58:09.461Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T05:58:09.461Z] echo "Nothing to be done for teardown."; \ [2025-08-28T05:58:09.461Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/TestTargetResult"; [2025-08-28T05:58:09.461Z] [2025-08-28T05:58:09.461Z] TEST SETUP: [2025-08-28T05:58:09.461Z] Nothing to be done for setup. [2025-08-28T05:58:09.461Z] [2025-08-28T05:58:09.461Z] TESTING: [2025-08-28T05:58:20.817Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-28T05:58:26.175Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-28T05:58:26.175Z] GC before operation: completed in 96.997 ms, heap usage 66.325 MB -> 35.284 MB. [2025-08-28T05:58:51.022Z] ====== log-regression (apache-spark) [default], iteration 0 completed (22384.612 ms) ====== [2025-08-28T05:58:51.022Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-28T05:58:51.022Z] GC before operation: completed in 146.619 ms, heap usage 456.292 MB -> 120.275 MB. [2025-08-28T05:58:52.957Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4035.193 ms) ====== [2025-08-28T05:58:52.957Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-28T05:58:52.957Z] GC before operation: completed in 150.241 ms, heap usage 321.955 MB -> 120.703 MB. [2025-08-28T05:58:55.944Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3564.141 ms) ====== [2025-08-28T05:58:55.945Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-28T05:58:55.945Z] GC before operation: completed in 147.591 ms, heap usage 351.418 MB -> 120.957 MB. [2025-08-28T05:58:58.920Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3007.508 ms) ====== [2025-08-28T05:58:58.920Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-28T05:58:59.857Z] GC before operation: completed in 157.541 ms, heap usage 591.248 MB -> 121.997 MB. [2025-08-28T05:59:02.843Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2872.312 ms) ====== [2025-08-28T05:59:02.843Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-28T05:59:02.843Z] GC before operation: completed in 174.590 ms, heap usage 272.254 MB -> 121.260 MB. [2025-08-28T05:59:05.833Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2785.553 ms) ====== [2025-08-28T05:59:05.833Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-28T05:59:05.833Z] GC before operation: completed in 181.215 ms, heap usage 718.263 MB -> 122.858 MB. [2025-08-28T05:59:08.811Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2831.062 ms) ====== [2025-08-28T05:59:08.811Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-28T05:59:08.811Z] GC before operation: completed in 168.718 ms, heap usage 420.397 MB -> 122.199 MB. [2025-08-28T05:59:11.795Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2756.381 ms) ====== [2025-08-28T05:59:11.795Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-28T05:59:11.795Z] GC before operation: completed in 175.068 ms, heap usage 154.925 MB -> 121.806 MB. [2025-08-28T05:59:13.718Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2738.466 ms) ====== [2025-08-28T05:59:13.718Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-28T05:59:14.669Z] GC before operation: completed in 178.513 ms, heap usage 259.033 MB -> 122.115 MB. [2025-08-28T05:59:16.609Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2554.535 ms) ====== [2025-08-28T05:59:16.609Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-28T05:59:16.609Z] GC before operation: completed in 175.620 ms, heap usage 631.397 MB -> 123.491 MB. [2025-08-28T05:59:19.584Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2567.480 ms) ====== [2025-08-28T05:59:19.584Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-28T05:59:19.584Z] GC before operation: completed in 190.857 ms, heap usage 341.639 MB -> 122.860 MB. [2025-08-28T05:59:22.557Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2486.587 ms) ====== [2025-08-28T05:59:22.557Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-28T05:59:22.557Z] GC before operation: completed in 186.639 ms, heap usage 277.458 MB -> 123.075 MB. [2025-08-28T05:59:25.534Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2459.049 ms) ====== [2025-08-28T05:59:25.534Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-28T05:59:25.534Z] GC before operation: completed in 190.512 ms, heap usage 404.091 MB -> 123.479 MB. [2025-08-28T05:59:27.459Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2363.735 ms) ====== [2025-08-28T05:59:27.459Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-28T05:59:27.459Z] GC before operation: completed in 188.577 ms, heap usage 329.116 MB -> 123.359 MB. [2025-08-28T05:59:30.450Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2388.684 ms) ====== [2025-08-28T05:59:30.450Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-28T05:59:30.450Z] GC before operation: completed in 202.420 ms, heap usage 720.138 MB -> 124.666 MB. [2025-08-28T05:59:32.377Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2299.688 ms) ====== [2025-08-28T05:59:32.377Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-28T05:59:32.377Z] GC before operation: completed in 205.202 ms, heap usage 850.014 MB -> 125.208 MB. [2025-08-28T05:59:35.360Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2336.790 ms) ====== [2025-08-28T05:59:35.360Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-28T05:59:35.360Z] GC before operation: completed in 205.680 ms, heap usage 454.872 MB -> 124.375 MB. [2025-08-28T05:59:37.294Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2381.505 ms) ====== [2025-08-28T05:59:37.294Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-28T05:59:38.666Z] GC before operation: completed in 200.787 ms, heap usage 251.796 MB -> 123.938 MB. [2025-08-28T05:59:40.599Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2235.770 ms) ====== [2025-08-28T05:59:40.599Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-28T05:59:40.599Z] GC before operation: completed in 217.319 ms, heap usage 139.546 MB -> 123.993 MB. [2025-08-28T05:59:42.527Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2328.751 ms) ====== [2025-08-28T05:59:43.462Z] ----------------------------------- [2025-08-28T05:59:43.462Z] renaissance-log-regression_0_PASSED [2025-08-28T05:59:43.462Z] ----------------------------------- [2025-08-28T05:59:43.462Z] [2025-08-28T05:59:43.462Z] TEST TEARDOWN: [2025-08-28T05:59:43.462Z] Nothing to be done for teardown. [2025-08-28T05:59:43.462Z] renaissance-log-regression_0 Finish Time: Thu Aug 28 05:59:43 2025 Epoch Time (ms): 1756360783015