[2025-12-04T02:07:56.107Z] Running test renaissance-log-regression_0 ... [2025-12-04T02:07:56.107Z] =============================================== [2025-12-04T02:07:56.107Z] renaissance-log-regression_0 Start Time: Thu Dec 4 02:07:55 2025 Epoch Time (ms): 1764814075350 [2025-12-04T02:07:56.107Z] variation: NoOptions [2025-12-04T02:07:56.107Z] JVM_OPTIONS: [2025-12-04T02:07:56.107Z] { \ [2025-12-04T02:07:56.107Z] echo ""; echo "TEST SETUP:"; \ [2025-12-04T02:07:56.107Z] echo "Nothing to be done for setup."; \ [2025-12-04T02:07:56.107Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17648128479666/renaissance-log-regression_0"; \ [2025-12-04T02:07:56.107Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17648128479666/renaissance-log-regression_0"; \ [2025-12-04T02:07:56.107Z] echo ""; echo "TESTING:"; \ [2025-12-04T02:07:56.107Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17648128479666/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-04T02:07:56.107Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17648128479666/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-04T02:07:56.107Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-04T02:07:56.107Z] echo "Nothing to be done for teardown."; \ [2025-12-04T02:07:56.107Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17648128479666/TestTargetResult"; [2025-12-04T02:07:56.107Z] [2025-12-04T02:07:56.107Z] TEST SETUP: [2025-12-04T02:07:56.107Z] Nothing to be done for setup. [2025-12-04T02:07:56.107Z] [2025-12-04T02:07:56.107Z] TESTING: [2025-12-04T02:08:01.596Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-04T02:08:03.580Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-04T02:08:03.581Z] GC before operation: completed in 58.065 ms, heap usage 63.236 MB -> 34.961 MB. [2025-12-04T02:08:13.745Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9957.789 ms) ====== [2025-12-04T02:08:13.745Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-04T02:08:13.745Z] GC before operation: completed in 92.746 ms, heap usage 346.545 MB -> 119.639 MB. [2025-12-04T02:08:15.698Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2058.229 ms) ====== [2025-12-04T02:08:15.698Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-04T02:08:15.698Z] GC before operation: completed in 97.013 ms, heap usage 329.468 MB -> 120.073 MB. [2025-12-04T02:08:17.863Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1824.231 ms) ====== [2025-12-04T02:08:17.863Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-04T02:08:17.863Z] GC before operation: completed in 98.240 ms, heap usage 317.243 MB -> 120.586 MB. [2025-12-04T02:08:19.815Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1738.359 ms) ====== [2025-12-04T02:08:19.815Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-04T02:08:19.815Z] GC before operation: completed in 101.873 ms, heap usage 248.256 MB -> 120.641 MB. [2025-12-04T02:08:20.767Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1611.597 ms) ====== [2025-12-04T02:08:20.767Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-04T02:08:20.767Z] GC before operation: completed in 112.730 ms, heap usage 443.311 MB -> 121.416 MB. [2025-12-04T02:08:22.720Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1565.797 ms) ====== [2025-12-04T02:08:22.720Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-04T02:08:22.720Z] GC before operation: completed in 97.256 ms, heap usage 289.937 MB -> 121.243 MB. [2025-12-04T02:08:24.675Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1534.764 ms) ====== [2025-12-04T02:08:24.675Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-04T02:08:24.675Z] GC before operation: completed in 94.239 ms, heap usage 269.339 MB -> 121.394 MB. [2025-12-04T02:08:25.638Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1456.026 ms) ====== [2025-12-04T02:08:25.638Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-04T02:08:25.638Z] GC before operation: completed in 97.436 ms, heap usage 434.886 MB -> 122.145 MB. [2025-12-04T02:08:27.654Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1518.656 ms) ====== [2025-12-04T02:08:27.654Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-04T02:08:27.654Z] GC before operation: completed in 104.247 ms, heap usage 289.862 MB -> 121.916 MB. [2025-12-04T02:08:29.608Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1562.439 ms) ====== [2025-12-04T02:08:29.608Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-04T02:08:29.608Z] GC before operation: completed in 86.374 ms, heap usage 427.349 MB -> 122.317 MB. [2025-12-04T02:08:30.560Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1444.439 ms) ====== [2025-12-04T02:08:30.560Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-04T02:08:30.560Z] GC before operation: completed in 86.846 ms, heap usage 276.316 MB -> 122.173 MB. [2025-12-04T02:08:32.527Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1482.651 ms) ====== [2025-12-04T02:08:32.527Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-04T02:08:32.527Z] GC before operation: completed in 104.465 ms, heap usage 311.826 MB -> 122.511 MB. [2025-12-04T02:08:33.479Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1430.952 ms) ====== [2025-12-04T02:08:33.479Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-04T02:08:33.479Z] GC before operation: completed in 100.626 ms, heap usage 358.839 MB -> 122.927 MB. [2025-12-04T02:08:35.435Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1420.889 ms) ====== [2025-12-04T02:08:35.436Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-04T02:08:35.436Z] GC before operation: completed in 118.224 ms, heap usage 293.594 MB -> 122.887 MB. [2025-12-04T02:08:37.392Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1446.404 ms) ====== [2025-12-04T02:08:37.392Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-04T02:08:37.392Z] GC before operation: completed in 95.965 ms, heap usage 401.610 MB -> 123.398 MB. [2025-12-04T02:08:38.343Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1402.968 ms) ====== [2025-12-04T02:08:38.343Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-04T02:08:38.343Z] GC before operation: completed in 90.345 ms, heap usage 303.660 MB -> 123.311 MB. [2025-12-04T02:08:40.301Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1416.499 ms) ====== [2025-12-04T02:08:40.301Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-04T02:08:40.301Z] GC before operation: completed in 84.629 ms, heap usage 395.929 MB -> 123.920 MB. [2025-12-04T02:08:41.253Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1420.561 ms) ====== [2025-12-04T02:08:41.253Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-04T02:08:41.253Z] GC before operation: completed in 92.230 ms, heap usage 425.611 MB -> 124.206 MB. [2025-12-04T02:08:43.378Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1327.305 ms) ====== [2025-12-04T02:08:43.378Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-04T02:08:43.378Z] GC before operation: completed in 87.091 ms, heap usage 287.861 MB -> 124.001 MB. [2025-12-04T02:08:44.331Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1471.113 ms) ====== [2025-12-04T02:08:44.331Z] ----------------------------------- [2025-12-04T02:08:44.331Z] renaissance-log-regression_0_PASSED [2025-12-04T02:08:44.331Z] ----------------------------------- [2025-12-04T02:08:44.331Z] [2025-12-04T02:08:44.331Z] TEST TEARDOWN: [2025-12-04T02:08:44.331Z] Nothing to be done for teardown. [2025-12-04T02:08:44.331Z] renaissance-log-regression_0 Finish Time: Thu Dec 4 02:08:44 2025 Epoch Time (ms): 1764814124139