[2025-11-27T00:59:20.036Z] Running test renaissance-log-regression_0 ... [2025-11-27T00:59:20.036Z] =============================================== [2025-11-27T00:59:20.036Z] renaissance-log-regression_0 Start Time: Thu Nov 27 00:59:19 2025 Epoch Time (ms): 1764205159953 [2025-11-27T00:59:20.036Z] variation: NoOptions [2025-11-27T00:59:20.036Z] JVM_OPTIONS: [2025-11-27T00:59:20.036Z] { \ [2025-11-27T00:59:20.036Z] echo ""; echo "TEST SETUP:"; \ [2025-11-27T00:59:20.036Z] echo "Nothing to be done for setup."; \ [2025-11-27T00:59:20.036Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17642044505963/renaissance-log-regression_0"; \ [2025-11-27T00:59:20.036Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17642044505963/renaissance-log-regression_0"; \ [2025-11-27T00:59:20.036Z] echo ""; echo "TESTING:"; \ [2025-11-27T00:59:20.036Z] "/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_17642044505963/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-27T00:59:20.036Z] 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_17642044505963/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-27T00:59:20.036Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-27T00:59:20.036Z] echo "Nothing to be done for teardown."; \ [2025-11-27T00:59:20.036Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17642044505963/TestTargetResult"; [2025-11-27T00:59:20.036Z] [2025-11-27T00:59:20.036Z] TEST SETUP: [2025-11-27T00:59:20.036Z] Nothing to be done for setup. [2025-11-27T00:59:20.036Z] [2025-11-27T00:59:20.036Z] TESTING: [2025-11-27T00:59:35.784Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-27T00:59:42.756Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-27T00:59:42.756Z] GC before operation: completed in 105.904 ms, heap usage 70.500 MB -> 35.075 MB. [2025-11-27T01:00:12.600Z] ====== log-regression (apache-spark) [default], iteration 0 completed (27625.538 ms) ====== [2025-11-27T01:00:12.600Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-27T01:00:12.600Z] GC before operation: completed in 231.909 ms, heap usage 191.295 MB -> 119.535 MB. [2025-11-27T01:00:18.081Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7770.390 ms) ====== [2025-11-27T01:00:18.081Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-27T01:00:18.081Z] GC before operation: completed in 270.808 ms, heap usage 447.550 MB -> 120.844 MB. [2025-11-27T01:00:23.535Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5758.312 ms) ====== [2025-11-27T01:00:23.535Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-27T01:00:23.535Z] GC before operation: completed in 261.783 ms, heap usage 294.283 MB -> 120.603 MB. [2025-11-27T01:00:28.948Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4747.828 ms) ====== [2025-11-27T01:00:28.948Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-27T01:00:28.948Z] GC before operation: completed in 197.593 ms, heap usage 709.262 MB -> 122.302 MB. [2025-11-27T01:00:32.272Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3958.748 ms) ====== [2025-11-27T01:00:32.272Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-27T01:00:33.004Z] GC before operation: completed in 212.068 ms, heap usage 537.194 MB -> 121.806 MB. [2025-11-27T01:00:37.327Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4190.721 ms) ====== [2025-11-27T01:00:37.327Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-27T01:00:37.327Z] GC before operation: completed in 195.634 ms, heap usage 451.634 MB -> 121.837 MB. [2025-11-27T01:00:41.632Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4020.773 ms) ====== [2025-11-27T01:00:41.632Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-27T01:00:41.632Z] GC before operation: completed in 184.032 ms, heap usage 324.670 MB -> 121.580 MB. [2025-11-27T01:00:44.965Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3475.094 ms) ====== [2025-11-27T01:00:44.965Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-27T01:00:44.965Z] GC before operation: completed in 187.511 ms, heap usage 684.242 MB -> 123.442 MB. [2025-11-27T01:00:48.760Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3659.030 ms) ====== [2025-11-27T01:00:48.760Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-27T01:00:48.760Z] GC before operation: completed in 190.599 ms, heap usage 506.990 MB -> 122.587 MB. [2025-11-27T01:00:52.124Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3408.288 ms) ====== [2025-11-27T01:00:52.124Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-27T01:00:52.124Z] GC before operation: completed in 204.793 ms, heap usage 663.930 MB -> 123.235 MB. [2025-11-27T01:00:56.461Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4140.413 ms) ====== [2025-11-27T01:00:56.461Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-27T01:00:57.200Z] GC before operation: completed in 293.637 ms, heap usage 490.463 MB -> 122.958 MB. [2025-11-27T01:01:00.509Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3860.393 ms) ====== [2025-11-27T01:01:00.509Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-27T01:01:01.255Z] GC before operation: completed in 248.571 ms, heap usage 663.118 MB -> 123.667 MB. [2025-11-27T01:01:05.575Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4226.563 ms) ====== [2025-11-27T01:01:05.575Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-27T01:01:05.575Z] GC before operation: completed in 225.828 ms, heap usage 386.848 MB -> 123.077 MB. [2025-11-27T01:01:08.889Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3638.627 ms) ====== [2025-11-27T01:01:08.889Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-27T01:01:09.644Z] GC before operation: completed in 229.808 ms, heap usage 561.099 MB -> 123.817 MB. [2025-11-27T01:01:13.970Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4201.714 ms) ====== [2025-11-27T01:01:13.970Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-27T01:01:13.970Z] GC before operation: completed in 241.643 ms, heap usage 364.769 MB -> 123.374 MB. [2025-11-27T01:01:18.391Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4395.594 ms) ====== [2025-11-27T01:01:18.391Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-27T01:01:18.391Z] GC before operation: completed in 249.348 ms, heap usage 603.178 MB -> 124.326 MB. [2025-11-27T01:01:23.850Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5069.372 ms) ====== [2025-11-27T01:01:23.850Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-27T01:01:23.850Z] GC before operation: completed in 284.565 ms, heap usage 568.141 MB -> 124.481 MB. [2025-11-27T01:01:28.148Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4261.426 ms) ====== [2025-11-27T01:01:28.148Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-27T01:01:28.148Z] GC before operation: completed in 340.720 ms, heap usage 597.755 MB -> 124.723 MB. [2025-11-27T01:01:32.965Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3853.939 ms) ====== [2025-11-27T01:01:32.965Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-27T01:01:32.965Z] GC before operation: completed in 329.975 ms, heap usage 765.385 MB -> 125.461 MB. [2025-11-27T01:01:37.298Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4275.618 ms) ====== [2025-11-27T01:01:37.298Z] ----------------------------------- [2025-11-27T01:01:37.298Z] renaissance-log-regression_0_PASSED [2025-11-27T01:01:37.298Z] ----------------------------------- [2025-11-27T01:01:37.298Z] [2025-11-27T01:01:37.298Z] TEST TEARDOWN: [2025-11-27T01:01:37.298Z] Nothing to be done for teardown. [2025-11-27T01:01:37.298Z] renaissance-log-regression_0 Finish Time: Thu Nov 27 01:01:37 2025 Epoch Time (ms): 1764205297099