[2025-11-19T22:59:12.292Z] Running test renaissance-log-regression_0 ... [2025-11-19T22:59:12.292Z] =============================================== [2025-11-19T22:59:12.292Z] renaissance-log-regression_0 Start Time: Wed Nov 19 22:59:11 2025 Epoch Time (ms): 1763593151548 [2025-11-19T22:59:12.292Z] variation: NoOptions [2025-11-19T22:59:12.292Z] JVM_OPTIONS: [2025-11-19T22:59:12.292Z] { \ [2025-11-19T22:59:12.292Z] echo ""; echo "TEST SETUP:"; \ [2025-11-19T22:59:12.292Z] echo "Nothing to be done for setup."; \ [2025-11-19T22:59:12.292Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17635918785997/renaissance-log-regression_0"; \ [2025-11-19T22:59:12.292Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17635918785997/renaissance-log-regression_0"; \ [2025-11-19T22:59:12.292Z] echo ""; echo "TESTING:"; \ [2025-11-19T22:59:12.292Z] "/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_17635918785997/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-19T22:59:12.293Z] 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_17635918785997/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-19T22:59:12.293Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-19T22:59:12.293Z] echo "Nothing to be done for teardown."; \ [2025-11-19T22:59:12.293Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17635918785997/TestTargetResult"; [2025-11-19T22:59:12.293Z] [2025-11-19T22:59:12.293Z] TEST SETUP: [2025-11-19T22:59:12.293Z] Nothing to be done for setup. [2025-11-19T22:59:12.293Z] [2025-11-19T22:59:12.293Z] TESTING: [2025-11-19T22:59:17.812Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-19T22:59:19.867Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-19T22:59:19.867Z] GC before operation: completed in 64.755 ms, heap usage 55.931 MB -> 35.134 MB. [2025-11-19T22:59:29.851Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9681.078 ms) ====== [2025-11-19T22:59:29.851Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-19T22:59:29.851Z] GC before operation: completed in 83.394 ms, heap usage 782.127 MB -> 120.948 MB. [2025-11-19T22:59:31.843Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2009.121 ms) ====== [2025-11-19T22:59:31.843Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-19T22:59:31.843Z] GC before operation: completed in 73.762 ms, heap usage 348.980 MB -> 120.314 MB. [2025-11-19T22:59:33.876Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1768.335 ms) ====== [2025-11-19T22:59:33.876Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-19T22:59:33.876Z] GC before operation: completed in 65.918 ms, heap usage 794.508 MB -> 121.965 MB. [2025-11-19T22:59:34.837Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1620.953 ms) ====== [2025-11-19T22:59:34.837Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-19T22:59:34.837Z] GC before operation: completed in 103.162 ms, heap usage 245.702 MB -> 120.690 MB. [2025-11-19T22:59:36.827Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1458.382 ms) ====== [2025-11-19T22:59:36.827Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-19T22:59:36.827Z] GC before operation: completed in 81.104 ms, heap usage 804.854 MB -> 122.583 MB. [2025-11-19T22:59:37.808Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1417.893 ms) ====== [2025-11-19T22:59:37.809Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-19T22:59:37.809Z] GC before operation: completed in 71.930 ms, heap usage 172.052 MB -> 121.123 MB. [2025-11-19T22:59:39.799Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1360.187 ms) ====== [2025-11-19T22:59:39.799Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-19T22:59:39.799Z] GC before operation: completed in 80.441 ms, heap usage 487.779 MB -> 122.048 MB. [2025-11-19T22:59:40.768Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1318.043 ms) ====== [2025-11-19T22:59:40.768Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-19T22:59:40.768Z] GC before operation: completed in 78.147 ms, heap usage 888.879 MB -> 123.867 MB. [2025-11-19T22:59:42.744Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1404.602 ms) ====== [2025-11-19T22:59:42.744Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-19T22:59:42.744Z] GC before operation: completed in 80.878 ms, heap usage 592.256 MB -> 122.799 MB. [2025-11-19T22:59:43.701Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1354.183 ms) ====== [2025-11-19T22:59:43.701Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-19T22:59:43.701Z] GC before operation: completed in 78.362 ms, heap usage 444.888 MB -> 122.621 MB. [2025-11-19T22:59:45.669Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1318.464 ms) ====== [2025-11-19T22:59:45.669Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-19T22:59:45.669Z] GC before operation: completed in 79.028 ms, heap usage 336.144 MB -> 122.515 MB. [2025-11-19T22:59:46.644Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1451.860 ms) ====== [2025-11-19T22:59:46.644Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-19T22:59:46.644Z] GC before operation: completed in 82.162 ms, heap usage 498.093 MB -> 124.155 MB. [2025-11-19T22:59:49.518Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1340.547 ms) ====== [2025-11-19T22:59:49.518Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-19T22:59:49.518Z] GC before operation: completed in 83.702 ms, heap usage 835.517 MB -> 124.396 MB. [2025-11-19T22:59:50.824Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1389.019 ms) ====== [2025-11-19T22:59:50.824Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-19T22:59:50.824Z] GC before operation: completed in 83.295 ms, heap usage 330.725 MB -> 123.139 MB. [2025-11-19T22:59:50.824Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1243.660 ms) ====== [2025-11-19T22:59:50.824Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-19T22:59:50.824Z] GC before operation: completed in 88.281 ms, heap usage 424.468 MB -> 123.507 MB. [2025-11-19T22:59:52.862Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1352.492 ms) ====== [2025-11-19T22:59:52.862Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-19T22:59:52.862Z] GC before operation: completed in 97.628 ms, heap usage 820.787 MB -> 124.962 MB. [2025-11-19T22:59:53.837Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1424.978 ms) ====== [2025-11-19T22:59:53.837Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-19T22:59:53.837Z] GC before operation: completed in 93.296 ms, heap usage 555.748 MB -> 124.380 MB. [2025-11-19T22:59:55.820Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1244.676 ms) ====== [2025-11-19T22:59:55.820Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-19T22:59:55.820Z] GC before operation: completed in 91.114 ms, heap usage 404.556 MB -> 124.198 MB. [2025-11-19T22:59:56.799Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1233.205 ms) ====== [2025-11-19T22:59:56.799Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-19T22:59:56.799Z] GC before operation: completed in 85.786 ms, heap usage 654.975 MB -> 125.099 MB. [2025-11-19T22:59:57.767Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1231.267 ms) ====== [2025-11-19T22:59:58.741Z] ----------------------------------- [2025-11-19T22:59:58.741Z] renaissance-log-regression_0_PASSED [2025-11-19T22:59:58.741Z] ----------------------------------- [2025-11-19T22:59:58.741Z] [2025-11-19T22:59:58.741Z] TEST TEARDOWN: [2025-11-19T22:59:58.741Z] Nothing to be done for teardown. [2025-11-19T22:59:58.741Z] renaissance-log-regression_0 Finish Time: Wed Nov 19 22:59:57 2025 Epoch Time (ms): 1763593197725