[2025-11-26T22:34:45.311Z] Running test renaissance-log-regression_0 ... [2025-11-26T22:34:45.312Z] =============================================== [2025-11-26T22:34:45.312Z] renaissance-log-regression_0 Start Time: Wed Nov 26 22:34:45 2025 Epoch Time (ms): 1764196485141 [2025-11-26T22:34:45.312Z] variation: NoOptions [2025-11-26T22:34:45.312Z] JVM_OPTIONS: [2025-11-26T22:34:45.312Z] { \ [2025-11-26T22:34:45.312Z] echo ""; echo "TEST SETUP:"; \ [2025-11-26T22:34:45.312Z] echo "Nothing to be done for setup."; \ [2025-11-26T22:34:45.312Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-log-regression_0"; \ [2025-11-26T22:34:45.312Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-log-regression_0"; \ [2025-11-26T22:34:45.312Z] echo ""; echo "TESTING:"; \ [2025-11-26T22:34:45.312Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-26T22:34:45.312Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-26T22:34:45.312Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-26T22:34:45.312Z] echo "Nothing to be done for teardown."; \ [2025-11-26T22:34:45.312Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/TestTargetResult"; [2025-11-26T22:34:45.312Z] [2025-11-26T22:34:45.312Z] TEST SETUP: [2025-11-26T22:34:45.312Z] Nothing to be done for setup. [2025-11-26T22:34:45.312Z] [2025-11-26T22:34:45.312Z] TESTING: [2025-11-26T22:34:52.315Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-11-26T22:34:55.804Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-26T22:34:55.804Z] GC before operation: completed in 78.536 ms, heap usage 88.641 MB -> 34.882 MB. [2025-11-26T22:35:07.727Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11401.532 ms) ====== [2025-11-26T22:35:07.727Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-26T22:35:07.727Z] GC before operation: completed in 119.516 ms, heap usage 337.124 MB -> 132.259 MB. [2025-11-26T22:35:09.408Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2011.739 ms) ====== [2025-11-26T22:35:09.408Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-26T22:35:09.408Z] GC before operation: completed in 114.135 ms, heap usage 652.640 MB -> 133.814 MB. [2025-11-26T22:35:11.025Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1744.992 ms) ====== [2025-11-26T22:35:11.025Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-26T22:35:11.800Z] GC before operation: completed in 123.075 ms, heap usage 1.123 GB -> 135.849 MB. [2025-11-26T22:35:13.425Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1635.112 ms) ====== [2025-11-26T22:35:13.425Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-26T22:35:13.425Z] GC before operation: completed in 106.713 ms, heap usage 419.146 MB -> 134.270 MB. [2025-11-26T22:35:15.034Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1601.618 ms) ====== [2025-11-26T22:35:15.034Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-26T22:35:15.034Z] GC before operation: completed in 114.379 ms, heap usage 544.087 MB -> 135.020 MB. [2025-11-26T22:35:16.636Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1602.107 ms) ====== [2025-11-26T22:35:16.636Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-26T22:35:16.636Z] GC before operation: completed in 127.906 ms, heap usage 549.239 MB -> 135.420 MB. [2025-11-26T22:35:18.415Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1577.501 ms) ====== [2025-11-26T22:35:18.415Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-26T22:35:18.415Z] GC before operation: completed in 127.404 ms, heap usage 1022.552 MB -> 137.119 MB. [2025-11-26T22:35:20.007Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1528.747 ms) ====== [2025-11-26T22:35:20.007Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-26T22:35:20.007Z] GC before operation: completed in 124.003 ms, heap usage 235.774 MB -> 135.333 MB. [2025-11-26T22:35:21.599Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1571.719 ms) ====== [2025-11-26T22:35:21.599Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-26T22:35:21.599Z] GC before operation: completed in 130.073 ms, heap usage 1.171 GB -> 138.431 MB. [2025-11-26T22:35:23.282Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1515.817 ms) ====== [2025-11-26T22:35:23.282Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-26T22:35:23.282Z] GC before operation: completed in 124.788 ms, heap usage 525.685 MB -> 136.825 MB. [2025-11-26T22:35:24.898Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1479.159 ms) ====== [2025-11-26T22:35:24.898Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-26T22:35:24.898Z] GC before operation: completed in 121.146 ms, heap usage 543.791 MB -> 137.302 MB. [2025-11-26T22:35:26.494Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1507.567 ms) ====== [2025-11-26T22:35:26.494Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-26T22:35:26.494Z] GC before operation: completed in 126.183 ms, heap usage 1022.890 MB -> 139.102 MB. [2025-11-26T22:35:28.103Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1447.646 ms) ====== [2025-11-26T22:35:28.103Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-26T22:35:28.103Z] GC before operation: completed in 135.949 ms, heap usage 770.805 MB -> 138.796 MB. [2025-11-26T22:35:29.695Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1464.202 ms) ====== [2025-11-26T22:35:29.695Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-26T22:35:29.695Z] GC before operation: completed in 129.768 ms, heap usage 587.454 MB -> 138.574 MB. [2025-11-26T22:35:31.319Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1413.337 ms) ====== [2025-11-26T22:35:31.319Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-26T22:35:31.319Z] GC before operation: completed in 123.534 ms, heap usage 1.002 GB -> 140.251 MB. [2025-11-26T22:35:32.920Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1454.836 ms) ====== [2025-11-26T22:35:32.920Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-26T22:35:32.920Z] GC before operation: completed in 125.067 ms, heap usage 272.047 MB -> 138.369 MB. [2025-11-26T22:35:34.533Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1439.575 ms) ====== [2025-11-26T22:35:34.533Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-26T22:35:34.533Z] GC before operation: completed in 124.777 ms, heap usage 1.270 GB -> 141.761 MB. [2025-11-26T22:35:36.125Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1412.670 ms) ====== [2025-11-26T22:35:36.125Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-26T22:35:36.125Z] GC before operation: completed in 123.653 ms, heap usage 538.149 MB -> 139.966 MB. [2025-11-26T22:35:37.750Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1443.223 ms) ====== [2025-11-26T22:35:37.750Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-26T22:35:37.750Z] GC before operation: completed in 143.775 ms, heap usage 540.692 MB -> 140.346 MB. [2025-11-26T22:35:39.367Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1459.137 ms) ====== [2025-11-26T22:35:39.367Z] ----------------------------------- [2025-11-26T22:35:39.367Z] renaissance-log-regression_0_PASSED [2025-11-26T22:35:39.367Z] ----------------------------------- [2025-11-26T22:35:39.367Z] [2025-11-26T22:35:39.367Z] TEST TEARDOWN: [2025-11-26T22:35:39.367Z] Nothing to be done for teardown. [2025-11-26T22:35:39.367Z] renaissance-log-regression_0 Finish Time: Wed Nov 26 22:35:39 2025 Epoch Time (ms): 1764196539019