[2025-08-21T01:12:08.486Z] Running test renaissance-log-regression_0 ... [2025-08-21T01:12:08.486Z] =============================================== [2025-08-21T01:12:08.486Z] renaissance-log-regression_0 Start Time: Thu Aug 21 01:12:08 2025 Epoch Time (ms): 1755738728220 [2025-08-21T01:12:08.486Z] variation: NoOptions [2025-08-21T01:12:08.486Z] JVM_OPTIONS: [2025-08-21T01:12:08.486Z] { \ [2025-08-21T01:12:08.486Z] echo ""; echo "TEST SETUP:"; \ [2025-08-21T01:12:08.486Z] echo "Nothing to be done for setup."; \ [2025-08-21T01:12:08.486Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-log-regression_0"; \ [2025-08-21T01:12:08.486Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-log-regression_0"; \ [2025-08-21T01:12:08.486Z] echo ""; echo "TESTING:"; \ [2025-08-21T01:12:08.486Z] "/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_17557377207327/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-21T01:12:08.486Z] 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_17557377207327/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-21T01:12:08.486Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-21T01:12:08.486Z] echo "Nothing to be done for teardown."; \ [2025-08-21T01:12:08.486Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/TestTargetResult"; [2025-08-21T01:12:08.486Z] [2025-08-21T01:12:08.486Z] TEST SETUP: [2025-08-21T01:12:08.486Z] Nothing to be done for setup. [2025-08-21T01:12:08.486Z] [2025-08-21T01:12:08.486Z] TESTING: [2025-08-21T01:12:16.308Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-21T01:12:19.595Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-21T01:12:19.595Z] GC before operation: completed in 61.100 ms, heap usage 55.762 MB -> 34.995 MB. [2025-08-21T01:12:36.503Z] ====== log-regression (apache-spark) [default], iteration 0 completed (15942.258 ms) ====== [2025-08-21T01:12:36.503Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-21T01:12:36.504Z] GC before operation: completed in 100.485 ms, heap usage 594.959 MB -> 120.951 MB. [2025-08-21T01:12:39.143Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3333.786 ms) ====== [2025-08-21T01:12:39.143Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-21T01:12:39.143Z] GC before operation: completed in 77.091 ms, heap usage 506.210 MB -> 120.700 MB. [2025-08-21T01:12:41.736Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2599.860 ms) ====== [2025-08-21T01:12:41.736Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-21T01:12:41.736Z] GC before operation: completed in 80.575 ms, heap usage 726.692 MB -> 121.706 MB. [2025-08-21T01:12:44.411Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2433.499 ms) ====== [2025-08-21T01:12:44.411Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-21T01:12:44.411Z] GC before operation: completed in 91.772 ms, heap usage 156.517 MB -> 120.822 MB. [2025-08-21T01:12:46.397Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2239.015 ms) ====== [2025-08-21T01:12:46.397Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-21T01:12:46.397Z] GC before operation: completed in 87.688 ms, heap usage 651.413 MB -> 122.020 MB. [2025-08-21T01:12:48.993Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2226.401 ms) ====== [2025-08-21T01:12:48.993Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-21T01:12:48.993Z] GC before operation: completed in 90.961 ms, heap usage 595.828 MB -> 122.020 MB. [2025-08-21T01:12:50.922Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1993.229 ms) ====== [2025-08-21T01:12:50.922Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-21T01:12:50.922Z] GC before operation: completed in 107.348 ms, heap usage 549.881 MB -> 122.113 MB. [2025-08-21T01:12:52.843Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2071.989 ms) ====== [2025-08-21T01:12:52.843Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-21T01:12:52.843Z] GC before operation: completed in 92.455 ms, heap usage 749.945 MB -> 123.027 MB. [2025-08-21T01:12:54.816Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2058.266 ms) ====== [2025-08-21T01:12:54.816Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-21T01:12:55.209Z] GC before operation: completed in 92.524 ms, heap usage 551.945 MB -> 122.529 MB. [2025-08-21T01:12:57.143Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2074.504 ms) ====== [2025-08-21T01:12:57.143Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-21T01:12:57.143Z] GC before operation: completed in 93.335 ms, heap usage 371.809 MB -> 122.186 MB. [2025-08-21T01:12:59.065Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1981.754 ms) ====== [2025-08-21T01:12:59.065Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-21T01:12:59.460Z] GC before operation: completed in 92.910 ms, heap usage 305.279 MB -> 122.247 MB. [2025-08-21T01:13:01.392Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1923.797 ms) ====== [2025-08-21T01:13:01.392Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-21T01:13:01.392Z] GC before operation: completed in 102.792 ms, heap usage 154.284 MB -> 122.319 MB. [2025-08-21T01:13:03.376Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2040.204 ms) ====== [2025-08-21T01:13:03.376Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-21T01:13:03.376Z] GC before operation: completed in 97.064 ms, heap usage 540.073 MB -> 123.362 MB. [2025-08-21T01:13:05.303Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2033.821 ms) ====== [2025-08-21T01:13:05.303Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-21T01:13:05.749Z] GC before operation: completed in 132.494 ms, heap usage 408.836 MB -> 123.170 MB. [2025-08-21T01:13:07.682Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2017.346 ms) ====== [2025-08-21T01:13:07.682Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-21T01:13:07.682Z] GC before operation: completed in 96.445 ms, heap usage 328.653 MB -> 123.139 MB. [2025-08-21T01:13:09.615Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1891.904 ms) ====== [2025-08-21T01:13:09.615Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-21T01:13:09.615Z] GC before operation: completed in 98.382 ms, heap usage 321.018 MB -> 123.304 MB. [2025-08-21T01:13:11.554Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1983.559 ms) ====== [2025-08-21T01:13:11.554Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-21T01:13:11.960Z] GC before operation: completed in 100.177 ms, heap usage 710.517 MB -> 124.747 MB. [2025-08-21T01:13:13.942Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1947.598 ms) ====== [2025-08-21T01:13:13.942Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-21T01:13:13.942Z] GC before operation: completed in 106.777 ms, heap usage 300.806 MB -> 123.661 MB. [2025-08-21T01:13:15.923Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1786.175 ms) ====== [2025-08-21T01:13:15.923Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-21T01:13:15.923Z] GC before operation: completed in 100.633 ms, heap usage 333.310 MB -> 123.965 MB. [2025-08-21T01:13:17.895Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1850.468 ms) ====== [2025-08-21T01:13:17.895Z] ----------------------------------- [2025-08-21T01:13:17.895Z] renaissance-log-regression_0_PASSED [2025-08-21T01:13:17.895Z] ----------------------------------- [2025-08-21T01:13:17.895Z] [2025-08-21T01:13:17.895Z] TEST TEARDOWN: [2025-08-21T01:13:17.895Z] Nothing to be done for teardown. [2025-08-21T01:13:17.895Z] renaissance-log-regression_0 Finish Time: Thu Aug 21 01:13:17 2025 Epoch Time (ms): 1755738797740