[2025-06-27T21:00:52.698Z] Running test renaissance-log-regression_0 ... [2025-06-27T21:00:52.698Z] =============================================== [2025-06-27T21:00:52.698Z] renaissance-log-regression_0 Start Time: Fri Jun 27 21:00:51 2025 Epoch Time (ms): 1751058051784 [2025-06-27T21:00:52.698Z] variation: NoOptions [2025-06-27T21:00:52.698Z] JVM_OPTIONS: [2025-06-27T21:00:52.698Z] { \ [2025-06-27T21:00:52.698Z] echo ""; echo "TEST SETUP:"; \ [2025-06-27T21:00:52.698Z] echo "Nothing to be done for setup."; \ [2025-06-27T21:00:52.698Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17510568032680/renaissance-log-regression_0"; \ [2025-06-27T21:00:52.698Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17510568032680/renaissance-log-regression_0"; \ [2025-06-27T21:00:52.698Z] echo ""; echo "TESTING:"; \ [2025-06-27T21:00:52.698Z] "/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_17510568032680/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-27T21:00:52.698Z] 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_17510568032680/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-27T21:00:52.698Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-27T21:00:52.698Z] echo "Nothing to be done for teardown."; \ [2025-06-27T21:00:52.698Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17510568032680/TestTargetResult"; [2025-06-27T21:00:52.698Z] [2025-06-27T21:00:52.698Z] TEST SETUP: [2025-06-27T21:00:52.698Z] Nothing to be done for setup. [2025-06-27T21:00:52.698Z] [2025-06-27T21:00:52.698Z] TESTING: [2025-06-27T21:00:58.015Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-27T21:01:00.680Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-27T21:01:00.680Z] GC before operation: completed in 61.944 ms, heap usage 51.470 MB -> 35.160 MB. [2025-06-27T21:01:10.388Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10232.035 ms) ====== [2025-06-27T21:01:10.388Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-27T21:01:10.388Z] GC before operation: completed in 92.483 ms, heap usage 417.218 MB -> 119.989 MB. [2025-06-27T21:01:12.311Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2023.845 ms) ====== [2025-06-27T21:01:12.311Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-27T21:01:12.311Z] GC before operation: completed in 75.579 ms, heap usage 653.151 MB -> 121.218 MB. [2025-06-27T21:01:14.249Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1991.373 ms) ====== [2025-06-27T21:01:14.249Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-27T21:01:14.249Z] GC before operation: completed in 93.368 ms, heap usage 365.383 MB -> 120.827 MB. [2025-06-27T21:01:16.183Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1618.766 ms) ====== [2025-06-27T21:01:16.183Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-27T21:01:16.183Z] GC before operation: completed in 89.581 ms, heap usage 910.109 MB -> 122.506 MB. [2025-06-27T21:01:18.117Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1623.207 ms) ====== [2025-06-27T21:01:18.117Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-27T21:01:18.117Z] GC before operation: completed in 96.703 ms, heap usage 738.264 MB -> 122.495 MB. [2025-06-27T21:01:19.057Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1575.722 ms) ====== [2025-06-27T21:01:19.057Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-27T21:01:19.997Z] GC before operation: completed in 87.345 ms, heap usage 664.512 MB -> 122.484 MB. [2025-06-27T21:01:20.943Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1595.425 ms) ====== [2025-06-27T21:01:20.943Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-27T21:01:20.943Z] GC before operation: completed in 84.759 ms, heap usage 150.151 MB -> 121.431 MB. [2025-06-27T21:01:22.881Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1540.595 ms) ====== [2025-06-27T21:01:22.881Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-27T21:01:22.881Z] GC before operation: completed in 90.898 ms, heap usage 860.431 MB -> 123.428 MB. [2025-06-27T21:01:24.802Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1518.859 ms) ====== [2025-06-27T21:01:24.802Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-27T21:01:24.802Z] GC before operation: completed in 88.269 ms, heap usage 391.488 MB -> 122.358 MB. [2025-06-27T21:01:25.757Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1547.344 ms) ====== [2025-06-27T21:01:25.757Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-27T21:01:25.757Z] GC before operation: completed in 94.940 ms, heap usage 635.534 MB -> 123.328 MB. [2025-06-27T21:01:27.685Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1463.116 ms) ====== [2025-06-27T21:01:27.685Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-27T21:01:27.685Z] GC before operation: completed in 95.144 ms, heap usage 702.525 MB -> 123.746 MB. [2025-06-27T21:01:29.615Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1467.887 ms) ====== [2025-06-27T21:01:29.615Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-27T21:01:29.615Z] GC before operation: completed in 102.191 ms, heap usage 360.849 MB -> 122.783 MB. [2025-06-27T21:01:30.559Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1386.219 ms) ====== [2025-06-27T21:01:30.559Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-27T21:01:30.559Z] GC before operation: completed in 103.275 ms, heap usage 681.790 MB -> 124.016 MB. [2025-06-27T21:01:32.479Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1436.597 ms) ====== [2025-06-27T21:01:32.479Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-27T21:01:32.479Z] GC before operation: completed in 94.215 ms, heap usage 430.703 MB -> 123.513 MB. [2025-06-27T21:01:33.420Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1436.388 ms) ====== [2025-06-27T21:01:33.420Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-27T21:01:33.420Z] GC before operation: completed in 94.680 ms, heap usage 413.238 MB -> 123.601 MB. [2025-06-27T21:01:35.366Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1488.706 ms) ====== [2025-06-27T21:01:35.366Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-27T21:01:35.366Z] GC before operation: completed in 109.525 ms, heap usage 562.046 MB -> 124.262 MB. [2025-06-27T21:01:36.318Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1322.692 ms) ====== [2025-06-27T21:01:36.318Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-27T21:01:37.255Z] GC before operation: completed in 99.471 ms, heap usage 648.179 MB -> 124.807 MB. [2025-06-27T21:01:38.190Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1400.506 ms) ====== [2025-06-27T21:01:38.190Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-27T21:01:38.190Z] GC before operation: completed in 102.601 ms, heap usage 731.617 MB -> 125.261 MB. [2025-06-27T21:01:40.113Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1357.914 ms) ====== [2025-06-27T21:01:40.113Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-27T21:01:40.113Z] GC before operation: completed in 107.734 ms, heap usage 509.532 MB -> 124.723 MB. [2025-06-27T21:01:41.060Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1347.509 ms) ====== [2025-06-27T21:01:41.060Z] ----------------------------------- [2025-06-27T21:01:41.060Z] renaissance-log-regression_0_PASSED [2025-06-27T21:01:41.060Z] ----------------------------------- [2025-06-27T21:01:41.060Z] [2025-06-27T21:01:41.060Z] TEST TEARDOWN: [2025-06-27T21:01:41.060Z] Nothing to be done for teardown. [2025-06-27T21:01:41.060Z] renaissance-log-regression_0 Finish Time: Fri Jun 27 21:01:40 2025 Epoch Time (ms): 1751058100874