[2025-08-21T05:09:33.732Z] Running test renaissance-log-regression_0 ... [2025-08-21T05:09:33.732Z] =============================================== [2025-08-21T05:09:33.732Z] renaissance-log-regression_0 Start Time: Thu Aug 21 05:09:33 2025 Epoch Time (ms): 1755752973641 [2025-08-21T05:09:33.732Z] variation: NoOptions [2025-08-21T05:09:33.732Z] JVM_OPTIONS: [2025-08-21T05:09:33.732Z] { \ [2025-08-21T05:09:33.732Z] echo ""; echo "TEST SETUP:"; \ [2025-08-21T05:09:33.732Z] echo "Nothing to be done for setup."; \ [2025-08-21T05:09:33.732Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17557523512630/renaissance-log-regression_0"; \ [2025-08-21T05:09:33.732Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17557523512630/renaissance-log-regression_0"; \ [2025-08-21T05:09:33.732Z] echo ""; echo "TESTING:"; \ [2025-08-21T05:09:33.732Z] "/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_17557523512630/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-21T05:09:33.732Z] 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_17557523512630/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-21T05:09:33.732Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-21T05:09:33.732Z] echo "Nothing to be done for teardown."; \ [2025-08-21T05:09:33.732Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17557523512630/TestTargetResult"; [2025-08-21T05:09:33.732Z] [2025-08-21T05:09:33.732Z] TEST SETUP: [2025-08-21T05:09:33.732Z] Nothing to be done for setup. [2025-08-21T05:09:33.732Z] [2025-08-21T05:09:33.732Z] TESTING: [2025-08-21T05:09:39.279Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-21T05:09:40.850Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-21T05:09:40.850Z] GC before operation: completed in 47.389 ms, heap usage 38.490 MB -> 35.108 MB. [2025-08-21T05:09:52.461Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10253.952 ms) ====== [2025-08-21T05:09:52.462Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-21T05:09:52.462Z] GC before operation: completed in 74.814 ms, heap usage 426.651 MB -> 119.964 MB. [2025-08-21T05:09:53.221Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2179.648 ms) ====== [2025-08-21T05:09:53.221Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-21T05:09:53.221Z] GC before operation: completed in 69.789 ms, heap usage 533.231 MB -> 120.813 MB. [2025-08-21T05:09:54.796Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1712.062 ms) ====== [2025-08-21T05:09:54.796Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-21T05:09:55.556Z] GC before operation: completed in 77.981 ms, heap usage 126.864 MB -> 121.893 MB. [2025-08-21T05:09:57.127Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1520.653 ms) ====== [2025-08-21T05:09:57.127Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-21T05:09:57.127Z] GC before operation: completed in 72.967 ms, heap usage 478.566 MB -> 121.308 MB. [2025-08-21T05:09:57.890Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1464.339 ms) ====== [2025-08-21T05:09:57.890Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-21T05:09:58.649Z] GC before operation: completed in 84.983 ms, heap usage 341.586 MB -> 121.278 MB. [2025-08-21T05:09:59.412Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1435.898 ms) ====== [2025-08-21T05:09:59.412Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-21T05:10:00.177Z] GC before operation: completed in 77.803 ms, heap usage 520.690 MB -> 122.145 MB. [2025-08-21T05:10:00.951Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1286.733 ms) ====== [2025-08-21T05:10:00.951Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-21T05:10:00.951Z] GC before operation: completed in 81.869 ms, heap usage 591.976 MB -> 122.486 MB. [2025-08-21T05:10:02.520Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1266.034 ms) ====== [2025-08-21T05:10:02.520Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-21T05:10:02.520Z] GC before operation: completed in 88.209 ms, heap usage 638.982 MB -> 122.783 MB. [2025-08-21T05:10:04.634Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1324.100 ms) ====== [2025-08-21T05:10:04.634Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-21T05:10:04.634Z] GC before operation: completed in 88.430 ms, heap usage 547.971 MB -> 122.733 MB. [2025-08-21T05:10:05.404Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1324.308 ms) ====== [2025-08-21T05:10:05.404Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-21T05:10:05.404Z] GC before operation: completed in 81.162 ms, heap usage 716.128 MB -> 123.475 MB. [2025-08-21T05:10:06.976Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1299.571 ms) ====== [2025-08-21T05:10:06.976Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-21T05:10:06.976Z] GC before operation: completed in 80.629 ms, heap usage 662.117 MB -> 123.471 MB. [2025-08-21T05:10:07.742Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1311.557 ms) ====== [2025-08-21T05:10:07.742Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-21T05:10:08.506Z] GC before operation: completed in 91.721 ms, heap usage 302.528 MB -> 123.099 MB. [2025-08-21T05:10:09.264Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1370.191 ms) ====== [2025-08-21T05:10:09.264Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-21T05:10:09.264Z] GC before operation: completed in 82.305 ms, heap usage 219.552 MB -> 122.591 MB. [2025-08-21T05:10:10.846Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1337.193 ms) ====== [2025-08-21T05:10:10.846Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-21T05:10:10.846Z] GC before operation: completed in 83.531 ms, heap usage 412.941 MB -> 123.315 MB. [2025-08-21T05:10:12.575Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1314.808 ms) ====== [2025-08-21T05:10:12.575Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-21T05:10:12.575Z] GC before operation: completed in 85.227 ms, heap usage 433.221 MB -> 123.616 MB. [2025-08-21T05:10:13.338Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1249.317 ms) ====== [2025-08-21T05:10:13.338Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-21T05:10:14.101Z] GC before operation: completed in 87.544 ms, heap usage 631.424 MB -> 124.375 MB. [2025-08-21T05:10:14.868Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1220.815 ms) ====== [2025-08-21T05:10:14.868Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-21T05:10:14.868Z] GC before operation: completed in 93.421 ms, heap usage 479.979 MB -> 124.705 MB. [2025-08-21T05:10:16.440Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1334.314 ms) ====== [2025-08-21T05:10:16.440Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-21T05:10:16.440Z] GC before operation: completed in 87.153 ms, heap usage 246.522 MB -> 123.663 MB. [2025-08-21T05:10:18.019Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1219.251 ms) ====== [2025-08-21T05:10:18.019Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-21T05:10:18.019Z] GC before operation: completed in 88.941 ms, heap usage 457.160 MB -> 124.535 MB. [2025-08-21T05:10:18.784Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1256.071 ms) ====== [2025-08-21T05:10:23.195Z] ----------------------------------- [2025-08-21T05:10:23.196Z] renaissance-log-regression_0_PASSED [2025-08-21T05:10:23.196Z] ----------------------------------- [2025-08-21T05:10:23.196Z] [2025-08-21T05:10:23.196Z] TEST TEARDOWN: [2025-08-21T05:10:23.196Z] Nothing to be done for teardown. [2025-08-21T05:10:23.196Z] renaissance-log-regression_0 Finish Time: Thu Aug 21 05:10:22 2025 Epoch Time (ms): 1755753022591