[2025-12-04T06:55:40.151Z] Running test renaissance-log-regression_0 ... [2025-12-04T06:55:40.151Z] =============================================== [2025-12-04T06:55:40.151Z] renaissance-log-regression_0 Start Time: Thu Dec 4 06:55:39 2025 Epoch Time (ms): 1764831339682 [2025-12-04T06:55:40.151Z] variation: NoOptions [2025-12-04T06:55:40.151Z] JVM_OPTIONS: [2025-12-04T06:55:40.151Z] { \ [2025-12-04T06:55:40.151Z] echo ""; echo "TEST SETUP:"; \ [2025-12-04T06:55:40.151Z] echo "Nothing to be done for setup."; \ [2025-12-04T06:55:40.151Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17648310072886/renaissance-log-regression_0"; \ [2025-12-04T06:55:40.151Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17648310072886/renaissance-log-regression_0"; \ [2025-12-04T06:55:40.151Z] echo ""; echo "TESTING:"; \ [2025-12-04T06:55:40.151Z] "/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_17648310072886/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-04T06:55:40.151Z] 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_17648310072886/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-04T06:55:40.151Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-04T06:55:40.151Z] echo "Nothing to be done for teardown."; \ [2025-12-04T06:55:40.151Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17648310072886/TestTargetResult"; [2025-12-04T06:55:40.151Z] [2025-12-04T06:55:40.151Z] TEST SETUP: [2025-12-04T06:55:40.151Z] Nothing to be done for setup. [2025-12-04T06:55:40.151Z] [2025-12-04T06:55:40.151Z] TESTING: [2025-12-04T06:55:45.661Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-04T06:55:48.080Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-04T06:55:48.080Z] GC before operation: completed in 57.197 ms, heap usage 68.297 MB -> 35.063 MB. [2025-12-04T06:55:59.624Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11059.130 ms) ====== [2025-12-04T06:55:59.624Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-04T06:55:59.624Z] GC before operation: completed in 80.483 ms, heap usage 444.030 MB -> 120.016 MB. [2025-12-04T06:56:01.213Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2160.384 ms) ====== [2025-12-04T06:56:01.213Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-04T06:56:01.213Z] GC before operation: completed in 73.130 ms, heap usage 585.968 MB -> 120.935 MB. [2025-12-04T06:56:02.775Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1921.374 ms) ====== [2025-12-04T06:56:02.775Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-04T06:56:02.775Z] GC before operation: completed in 75.955 ms, heap usage 304.367 MB -> 120.568 MB. [2025-12-04T06:56:05.216Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1638.797 ms) ====== [2025-12-04T06:56:05.216Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-04T06:56:05.216Z] GC before operation: completed in 80.815 ms, heap usage 758.804 MB -> 122.218 MB. [2025-12-04T06:56:06.778Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1606.073 ms) ====== [2025-12-04T06:56:06.778Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-04T06:56:06.778Z] GC before operation: completed in 83.377 ms, heap usage 496.471 MB -> 122.241 MB. [2025-12-04T06:56:09.716Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2795.720 ms) ====== [2025-12-04T06:56:09.716Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-04T06:56:09.716Z] GC before operation: completed in 77.591 ms, heap usage 536.744 MB -> 122.023 MB. [2025-12-04T06:56:10.470Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1400.535 ms) ====== [2025-12-04T06:56:10.470Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-04T06:56:11.229Z] GC before operation: completed in 86.949 ms, heap usage 742.605 MB -> 122.932 MB. [2025-12-04T06:56:11.985Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1395.461 ms) ====== [2025-12-04T06:56:11.985Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-04T06:56:12.742Z] GC before operation: completed in 87.724 ms, heap usage 723.814 MB -> 123.043 MB. [2025-12-04T06:56:13.495Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1361.972 ms) ====== [2025-12-04T06:56:13.495Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-04T06:56:13.495Z] GC before operation: completed in 83.991 ms, heap usage 638.519 MB -> 122.968 MB. [2025-12-04T06:56:15.058Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1374.710 ms) ====== [2025-12-04T06:56:15.058Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-04T06:56:15.058Z] GC before operation: completed in 82.783 ms, heap usage 522.224 MB -> 122.892 MB. [2025-12-04T06:56:16.626Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1397.559 ms) ====== [2025-12-04T06:56:16.626Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-04T06:56:16.626Z] GC before operation: completed in 86.540 ms, heap usage 417.195 MB -> 122.774 MB. [2025-12-04T06:56:18.189Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1382.493 ms) ====== [2025-12-04T06:56:18.189Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-04T06:56:18.189Z] GC before operation: completed in 98.246 ms, heap usage 774.471 MB -> 124.044 MB. [2025-12-04T06:56:19.819Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1476.396 ms) ====== [2025-12-04T06:56:19.819Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-04T06:56:19.819Z] GC before operation: completed in 90.628 ms, heap usage 151.574 MB -> 122.627 MB. [2025-12-04T06:56:21.382Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1387.688 ms) ====== [2025-12-04T06:56:21.382Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-04T06:56:21.382Z] GC before operation: completed in 86.465 ms, heap usage 128.928 MB -> 124.470 MB. [2025-12-04T06:56:22.953Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1390.448 ms) ====== [2025-12-04T06:56:22.953Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-04T06:56:22.953Z] GC before operation: completed in 86.821 ms, heap usage 823.633 MB -> 124.797 MB. [2025-12-04T06:56:24.524Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1358.751 ms) ====== [2025-12-04T06:56:24.524Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-04T06:56:24.524Z] GC before operation: completed in 89.024 ms, heap usage 540.790 MB -> 124.062 MB. [2025-12-04T06:56:26.090Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1430.905 ms) ====== [2025-12-04T06:56:26.090Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-04T06:56:26.090Z] GC before operation: completed in 91.996 ms, heap usage 305.021 MB -> 123.644 MB. [2025-12-04T06:56:26.845Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1359.744 ms) ====== [2025-12-04T06:56:26.845Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-04T06:56:26.845Z] GC before operation: completed in 91.134 ms, heap usage 317.304 MB -> 123.916 MB. [2025-12-04T06:56:28.434Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1359.088 ms) ====== [2025-12-04T06:56:28.434Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-04T06:56:28.434Z] GC before operation: completed in 90.754 ms, heap usage 818.751 MB -> 125.706 MB. [2025-12-04T06:56:29.997Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1304.888 ms) ====== [2025-12-04T06:56:29.997Z] ----------------------------------- [2025-12-04T06:56:29.997Z] renaissance-log-regression_0_PASSED [2025-12-04T06:56:29.997Z] ----------------------------------- [2025-12-04T06:56:29.997Z] [2025-12-04T06:56:29.997Z] TEST TEARDOWN: [2025-12-04T06:56:29.997Z] Nothing to be done for teardown. [2025-12-04T06:56:29.997Z] renaissance-log-regression_0 Finish Time: Thu Dec 4 06:56:29 2025 Epoch Time (ms): 1764831389823