[2025-12-24T22:24:45.692Z] Running test renaissance-log-regression_0 ... [2025-12-24T22:24:45.692Z] =============================================== [2025-12-24T22:24:45.692Z] renaissance-log-regression_0 Start Time: Wed Dec 24 22:24:45 2025 Epoch Time (ms): 1766615085404 [2025-12-24T22:24:45.692Z] variation: NoOptions [2025-12-24T22:24:45.692Z] JVM_OPTIONS: [2025-12-24T22:24:45.692Z] { \ [2025-12-24T22:24:45.692Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T22:24:45.692Z] echo "Nothing to be done for setup."; \ [2025-12-24T22:24:45.692Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-log-regression_0"; \ [2025-12-24T22:24:45.692Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-log-regression_0"; \ [2025-12-24T22:24:45.692Z] echo ""; echo "TESTING:"; \ [2025-12-24T22:24:45.692Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-24T22:24:45.692Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T22:24:45.692Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T22:24:45.692Z] echo "Nothing to be done for teardown."; \ [2025-12-24T22:24:45.692Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/TestTargetResult"; [2025-12-24T22:24:45.692Z] [2025-12-24T22:24:45.692Z] TEST SETUP: [2025-12-24T22:24:45.692Z] Nothing to be done for setup. [2025-12-24T22:24:45.692Z] [2025-12-24T22:24:45.692Z] TESTING: [2025-12-24T22:24:51.090Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-24T22:24:53.056Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-24T22:24:53.056Z] GC before operation: completed in 59.093 ms, heap usage 54.682 MB -> 35.031 MB. [2025-12-24T22:25:02.866Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9838.204 ms) ====== [2025-12-24T22:25:02.866Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-24T22:25:02.866Z] GC before operation: completed in 73.965 ms, heap usage 556.902 MB -> 120.218 MB. [2025-12-24T22:25:04.828Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1981.016 ms) ====== [2025-12-24T22:25:04.828Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-24T22:25:04.828Z] GC before operation: completed in 66.839 ms, heap usage 354.999 MB -> 120.128 MB. [2025-12-24T22:25:07.206Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1725.423 ms) ====== [2025-12-24T22:25:07.206Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-24T22:25:07.206Z] GC before operation: completed in 89.353 ms, heap usage 141.268 MB -> 121.319 MB. [2025-12-24T22:25:08.171Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1485.707 ms) ====== [2025-12-24T22:25:08.171Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-24T22:25:09.136Z] GC before operation: completed in 73.221 ms, heap usage 1016.023 MB -> 122.404 MB. [2025-12-24T22:25:10.092Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1502.163 ms) ====== [2025-12-24T22:25:10.092Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-24T22:25:10.092Z] GC before operation: completed in 70.039 ms, heap usage 368.378 MB -> 121.305 MB. [2025-12-24T22:25:12.057Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1363.125 ms) ====== [2025-12-24T22:25:12.057Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-24T22:25:12.057Z] GC before operation: completed in 73.927 ms, heap usage 893.364 MB -> 122.923 MB. [2025-12-24T22:25:13.013Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1435.378 ms) ====== [2025-12-24T22:25:13.013Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-24T22:25:13.013Z] GC before operation: completed in 77.679 ms, heap usage 465.226 MB -> 122.042 MB. [2025-12-24T22:25:13.969Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1249.661 ms) ====== [2025-12-24T22:25:13.969Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-24T22:25:14.926Z] GC before operation: completed in 74.598 ms, heap usage 655.706 MB -> 122.847 MB. [2025-12-24T22:25:15.890Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1352.225 ms) ====== [2025-12-24T22:25:15.890Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-24T22:25:15.890Z] GC before operation: completed in 74.059 ms, heap usage 257.991 MB -> 121.858 MB. [2025-12-24T22:25:16.847Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1316.447 ms) ====== [2025-12-24T22:25:16.847Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-24T22:25:17.806Z] GC before operation: completed in 77.654 ms, heap usage 655.814 MB -> 123.250 MB. [2025-12-24T22:25:18.784Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1302.377 ms) ====== [2025-12-24T22:25:18.784Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-24T22:25:18.784Z] GC before operation: completed in 88.411 ms, heap usage 280.236 MB -> 122.334 MB. [2025-12-24T22:25:19.755Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1327.122 ms) ====== [2025-12-24T22:25:19.755Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-24T22:25:19.755Z] GC before operation: completed in 88.087 ms, heap usage 324.670 MB -> 122.696 MB. [2025-12-24T22:25:21.719Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1285.642 ms) ====== [2025-12-24T22:25:21.719Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-24T22:25:21.719Z] GC before operation: completed in 77.682 ms, heap usage 600.030 MB -> 123.762 MB. [2025-12-24T22:25:22.677Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1270.317 ms) ====== [2025-12-24T22:25:22.677Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-24T22:25:22.677Z] GC before operation: completed in 81.351 ms, heap usage 270.750 MB -> 122.897 MB. [2025-12-24T22:25:23.635Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1148.709 ms) ====== [2025-12-24T22:25:23.635Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-24T22:25:23.635Z] GC before operation: completed in 76.869 ms, heap usage 552.891 MB -> 123.892 MB. [2025-12-24T22:25:25.601Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1300.990 ms) ====== [2025-12-24T22:25:25.601Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-24T22:25:25.601Z] GC before operation: completed in 79.399 ms, heap usage 726.866 MB -> 124.680 MB. [2025-12-24T22:25:26.565Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1244.387 ms) ====== [2025-12-24T22:25:26.565Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-24T22:25:26.565Z] GC before operation: completed in 89.317 ms, heap usage 846.787 MB -> 125.154 MB. [2025-12-24T22:25:28.528Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1240.181 ms) ====== [2025-12-24T22:25:28.528Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-24T22:25:28.528Z] GC before operation: completed in 91.142 ms, heap usage 135.245 MB -> 123.899 MB. [2025-12-24T22:25:29.492Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1211.842 ms) ====== [2025-12-24T22:25:29.492Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-24T22:25:29.492Z] GC before operation: completed in 82.824 ms, heap usage 972.400 MB -> 125.581 MB. [2025-12-24T22:25:30.451Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1256.570 ms) ====== [2025-12-24T22:25:30.451Z] ----------------------------------- [2025-12-24T22:25:30.451Z] renaissance-log-regression_0_PASSED [2025-12-24T22:25:30.451Z] ----------------------------------- [2025-12-24T22:25:30.451Z] [2025-12-24T22:25:30.451Z] TEST TEARDOWN: [2025-12-24T22:25:30.451Z] Nothing to be done for teardown. [2025-12-24T22:25:30.451Z] renaissance-log-regression_0 Finish Time: Wed Dec 24 22:25:30 2025 Epoch Time (ms): 1766615130358