[2025-08-28T06:26:52.851Z] Running test renaissance-log-regression_0 ... [2025-08-28T06:26:52.851Z] =============================================== [2025-08-28T06:26:52.851Z] renaissance-log-regression_0 Start Time: Thu Aug 28 06:26:52 2025 Epoch Time (ms): 1756362412266 [2025-08-28T06:26:52.851Z] variation: NoOptions [2025-08-28T06:26:52.851Z] JVM_OPTIONS: [2025-08-28T06:26:52.851Z] { \ [2025-08-28T06:26:52.851Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T06:26:52.851Z] echo "Nothing to be done for setup."; \ [2025-08-28T06:26:52.851Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/renaissance-log-regression_0"; \ [2025-08-28T06:26:52.851Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/renaissance-log-regression_0"; \ [2025-08-28T06:26:52.851Z] echo ""; echo "TESTING:"; \ [2025-08-28T06:26:52.851Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-28T06:26:52.851Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T06:26:52.851Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T06:26:52.852Z] echo "Nothing to be done for teardown."; \ [2025-08-28T06:26:52.852Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/TestTargetResult"; [2025-08-28T06:26:52.852Z] [2025-08-28T06:26:52.852Z] TEST SETUP: [2025-08-28T06:26:52.852Z] Nothing to be done for setup. [2025-08-28T06:26:52.852Z] [2025-08-28T06:26:52.852Z] TESTING: [2025-08-28T06:26:57.520Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-28T06:26:59.530Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-28T06:26:59.530Z] GC before operation: completed in 66.426 ms, heap usage 48.616 MB -> 34.950 MB. [2025-08-28T06:27:16.395Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14773.087 ms) ====== [2025-08-28T06:27:16.395Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-28T06:27:16.395Z] GC before operation: completed in 214.878 ms, heap usage 295.907 MB -> 116.650 MB. [2025-08-28T06:27:18.412Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3703.852 ms) ====== [2025-08-28T06:27:18.412Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-28T06:27:18.412Z] GC before operation: completed in 125.946 ms, heap usage 551.985 MB -> 118.155 MB. [2025-08-28T06:27:22.086Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3478.907 ms) ====== [2025-08-28T06:27:22.086Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-28T06:27:22.086Z] GC before operation: completed in 149.542 ms, heap usage 494.779 MB -> 118.298 MB. [2025-08-28T06:27:25.728Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3555.823 ms) ====== [2025-08-28T06:27:25.728Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-28T06:27:25.728Z] GC before operation: completed in 103.554 ms, heap usage 323.405 MB -> 117.982 MB. [2025-08-28T06:27:30.701Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4003.145 ms) ====== [2025-08-28T06:27:30.701Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-28T06:27:30.701Z] GC before operation: completed in 168.970 ms, heap usage 273.996 MB -> 118.070 MB. [2025-08-28T06:27:33.802Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4128.270 ms) ====== [2025-08-28T06:27:33.802Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-28T06:27:34.426Z] GC before operation: completed in 153.311 ms, heap usage 522.485 MB -> 119.237 MB. [2025-08-28T06:27:37.189Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2866.598 ms) ====== [2025-08-28T06:27:37.189Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-28T06:27:37.189Z] GC before operation: completed in 97.045 ms, heap usage 554.897 MB -> 119.493 MB. [2025-08-28T06:27:40.057Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2752.373 ms) ====== [2025-08-28T06:27:40.057Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-28T06:27:40.057Z] GC before operation: completed in 135.882 ms, heap usage 128.158 MB -> 119.747 MB. [2025-08-28T06:27:43.807Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3242.897 ms) ====== [2025-08-28T06:27:43.807Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-28T06:27:43.807Z] GC before operation: completed in 148.540 ms, heap usage 295.872 MB -> 118.974 MB. [2025-08-28T06:27:45.786Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2360.560 ms) ====== [2025-08-28T06:27:45.786Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-28T06:27:45.786Z] GC before operation: completed in 90.859 ms, heap usage 253.143 MB -> 118.942 MB. [2025-08-28T06:27:49.451Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3365.349 ms) ====== [2025-08-28T06:27:49.451Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-28T06:27:49.451Z] GC before operation: completed in 139.244 ms, heap usage 317.582 MB -> 119.339 MB. [2025-08-28T06:27:52.209Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2880.512 ms) ====== [2025-08-28T06:27:52.209Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-28T06:27:52.209Z] GC before operation: completed in 151.058 ms, heap usage 552.711 MB -> 120.501 MB. [2025-08-28T06:27:54.957Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2516.418 ms) ====== [2025-08-28T06:27:54.957Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-28T06:27:54.957Z] GC before operation: completed in 145.490 ms, heap usage 253.100 MB -> 119.498 MB. [2025-08-28T06:27:58.583Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2962.480 ms) ====== [2025-08-28T06:27:58.583Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-28T06:27:58.583Z] GC before operation: completed in 114.397 ms, heap usage 400.253 MB -> 120.326 MB. [2025-08-28T06:28:01.342Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2827.233 ms) ====== [2025-08-28T06:28:01.342Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-28T06:28:01.342Z] GC before operation: completed in 146.325 ms, heap usage 291.035 MB -> 120.032 MB. [2025-08-28T06:28:04.136Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2840.041 ms) ====== [2025-08-28T06:28:04.136Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-28T06:28:04.136Z] GC before operation: completed in 225.567 ms, heap usage 137.121 MB -> 120.647 MB. [2025-08-28T06:28:07.842Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3082.803 ms) ====== [2025-08-28T06:28:07.842Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-28T06:28:07.842Z] GC before operation: completed in 161.877 ms, heap usage 628.882 MB -> 121.682 MB. [2025-08-28T06:28:10.653Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3022.656 ms) ====== [2025-08-28T06:28:10.653Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-28T06:28:10.653Z] GC before operation: completed in 134.809 ms, heap usage 357.523 MB -> 120.905 MB. [2025-08-28T06:28:13.551Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3095.747 ms) ====== [2025-08-28T06:28:13.551Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-28T06:28:14.169Z] GC before operation: completed in 126.729 ms, heap usage 460.605 MB -> 121.428 MB. [2025-08-28T06:28:16.959Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2847.462 ms) ====== [2025-08-28T06:28:16.959Z] ----------------------------------- [2025-08-28T06:28:16.959Z] renaissance-log-regression_0_PASSED [2025-08-28T06:28:16.959Z] ----------------------------------- [2025-08-28T06:28:16.959Z] [2025-08-28T06:28:16.959Z] TEST TEARDOWN: [2025-08-28T06:28:16.959Z] Nothing to be done for teardown. [2025-08-28T06:28:16.960Z] renaissance-log-regression_0 Finish Time: Thu Aug 28 06:28:16 2025 Epoch Time (ms): 1756362496719