[2025-09-24T23:17:17.964Z] Running test renaissance-log-regression_0 ... [2025-09-24T23:17:17.964Z] =============================================== [2025-09-24T23:17:17.964Z] renaissance-log-regression_0 Start Time: Wed Sep 24 23:17:17 2025 Epoch Time (ms): 1758755837112 [2025-09-24T23:17:17.964Z] variation: NoOptions [2025-09-24T23:17:17.964Z] JVM_OPTIONS: [2025-09-24T23:17:17.964Z] { \ [2025-09-24T23:17:17.964Z] echo ""; echo "TEST SETUP:"; \ [2025-09-24T23:17:17.964Z] echo "Nothing to be done for setup."; \ [2025-09-24T23:17:17.964Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587544633630/renaissance-log-regression_0"; \ [2025-09-24T23:17:17.964Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587544633630/renaissance-log-regression_0"; \ [2025-09-24T23:17:17.964Z] echo ""; echo "TESTING:"; \ [2025-09-24T23:17:17.964Z] "/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_17587544633630/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-09-24T23:17:17.964Z] 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_17587544633630/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-24T23:17:17.964Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-24T23:17:17.964Z] echo "Nothing to be done for teardown."; \ [2025-09-24T23:17:17.964Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587544633630/TestTargetResult"; [2025-09-24T23:17:17.964Z] [2025-09-24T23:17:17.964Z] TEST SETUP: [2025-09-24T23:17:17.964Z] Nothing to be done for setup. [2025-09-24T23:17:17.964Z] [2025-09-24T23:17:17.964Z] TESTING: [2025-09-24T23:17:23.289Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-24T23:17:25.231Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-09-24T23:17:25.231Z] GC before operation: completed in 64.402 ms, heap usage 45.827 MB -> 34.948 MB. [2025-09-24T23:17:35.331Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9865.475 ms) ====== [2025-09-24T23:17:35.331Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-09-24T23:17:35.331Z] GC before operation: completed in 86.655 ms, heap usage 360.165 MB -> 119.808 MB. [2025-09-24T23:17:37.270Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2143.868 ms) ====== [2025-09-24T23:17:37.270Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-09-24T23:17:37.270Z] GC before operation: completed in 75.935 ms, heap usage 258.478 MB -> 119.986 MB. [2025-09-24T23:17:39.208Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1786.498 ms) ====== [2025-09-24T23:17:39.208Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-09-24T23:17:39.208Z] GC before operation: completed in 81.556 ms, heap usage 362.847 MB -> 120.730 MB. [2025-09-24T23:17:41.146Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1668.315 ms) ====== [2025-09-24T23:17:41.146Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-09-24T23:17:41.146Z] GC before operation: completed in 81.521 ms, heap usage 368.443 MB -> 121.036 MB. [2025-09-24T23:17:43.085Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1587.999 ms) ====== [2025-09-24T23:17:43.085Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-09-24T23:17:43.085Z] GC before operation: completed in 95.006 ms, heap usage 302.249 MB -> 121.184 MB. [2025-09-24T23:17:45.023Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1641.909 ms) ====== [2025-09-24T23:17:45.023Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-09-24T23:17:45.023Z] GC before operation: completed in 82.411 ms, heap usage 299.343 MB -> 121.296 MB. [2025-09-24T23:17:45.968Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1514.534 ms) ====== [2025-09-24T23:17:45.968Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-09-24T23:17:45.968Z] GC before operation: completed in 85.597 ms, heap usage 290.465 MB -> 121.419 MB. [2025-09-24T23:17:47.903Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1525.467 ms) ====== [2025-09-24T23:17:47.903Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-09-24T23:17:47.903Z] GC before operation: completed in 81.435 ms, heap usage 290.751 MB -> 121.745 MB. [2025-09-24T23:17:49.841Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1502.701 ms) ====== [2025-09-24T23:17:49.841Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-09-24T23:17:49.841Z] GC before operation: completed in 85.233 ms, heap usage 293.510 MB -> 121.932 MB. [2025-09-24T23:17:50.786Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1494.443 ms) ====== [2025-09-24T23:17:50.786Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-09-24T23:17:50.786Z] GC before operation: completed in 87.276 ms, heap usage 287.127 MB -> 122.094 MB. [2025-09-24T23:17:52.897Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1425.005 ms) ====== [2025-09-24T23:17:52.897Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-09-24T23:17:52.897Z] GC before operation: completed in 80.909 ms, heap usage 258.425 MB -> 122.231 MB. [2025-09-24T23:17:53.841Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1474.349 ms) ====== [2025-09-24T23:17:53.841Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-09-24T23:17:53.841Z] GC before operation: completed in 82.599 ms, heap usage 380.062 MB -> 122.824 MB. [2025-09-24T23:17:55.779Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1415.437 ms) ====== [2025-09-24T23:17:55.779Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-09-24T23:17:55.779Z] GC before operation: completed in 86.822 ms, heap usage 239.991 MB -> 122.627 MB. [2025-09-24T23:17:56.722Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1362.342 ms) ====== [2025-09-24T23:17:56.722Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-09-24T23:17:56.722Z] GC before operation: completed in 82.795 ms, heap usage 294.455 MB -> 123.014 MB. [2025-09-24T23:17:58.659Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1414.416 ms) ====== [2025-09-24T23:17:58.659Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-09-24T23:17:58.659Z] GC before operation: completed in 83.823 ms, heap usage 313.843 MB -> 123.151 MB. [2025-09-24T23:17:59.609Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1434.361 ms) ====== [2025-09-24T23:17:59.609Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-09-24T23:17:59.609Z] GC before operation: completed in 81.300 ms, heap usage 419.554 MB -> 123.722 MB. [2025-09-24T23:18:01.549Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1423.449 ms) ====== [2025-09-24T23:18:01.549Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-09-24T23:18:01.549Z] GC before operation: completed in 83.850 ms, heap usage 288.799 MB -> 123.671 MB. [2025-09-24T23:18:02.493Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1365.846 ms) ====== [2025-09-24T23:18:02.493Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-09-24T23:18:03.435Z] GC before operation: completed in 81.894 ms, heap usage 418.334 MB -> 124.178 MB. [2025-09-24T23:18:04.378Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1343.089 ms) ====== [2025-09-24T23:18:04.378Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-09-24T23:18:04.378Z] GC before operation: completed in 90.741 ms, heap usage 237.845 MB -> 123.823 MB. [2025-09-24T23:18:06.314Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1379.404 ms) ====== [2025-09-24T23:18:06.314Z] ----------------------------------- [2025-09-24T23:18:06.314Z] renaissance-log-regression_0_PASSED [2025-09-24T23:18:06.314Z] ----------------------------------- [2025-09-24T23:18:06.314Z] [2025-09-24T23:18:06.314Z] TEST TEARDOWN: [2025-09-24T23:18:06.314Z] Nothing to be done for teardown. [2025-09-24T23:18:06.314Z] renaissance-log-regression_0 Finish Time: Wed Sep 24 23:18:05 2025 Epoch Time (ms): 1758755885527