[2025-03-27T06:01:05.418Z] Running test renaissance-log-regression_0 ... [2025-03-27T06:01:05.418Z] =============================================== [2025-03-27T06:01:05.418Z] renaissance-log-regression_0 Start Time: Thu Mar 27 06:01:04 2025 Epoch Time (ms): 1743055264734 [2025-03-27T06:01:05.418Z] variation: NoOptions [2025-03-27T06:01:05.418Z] JVM_OPTIONS: [2025-03-27T06:01:05.418Z] { \ [2025-03-27T06:01:05.418Z] echo ""; echo "TEST SETUP:"; \ [2025-03-27T06:01:05.418Z] echo "Nothing to be done for setup."; \ [2025-03-27T06:01:05.418Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-log-regression_0"; \ [2025-03-27T06:01:05.418Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-log-regression_0"; \ [2025-03-27T06:01:05.418Z] echo ""; echo "TESTING:"; \ [2025-03-27T06:01:05.418Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-03-27T06:01:05.419Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-27T06:01:05.419Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-27T06:01:05.419Z] echo "Nothing to be done for teardown."; \ [2025-03-27T06:01:05.419Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/TestTargetResult"; [2025-03-27T06:01:05.419Z] [2025-03-27T06:01:05.419Z] TEST SETUP: [2025-03-27T06:01:05.419Z] Nothing to be done for setup. [2025-03-27T06:01:05.419Z] [2025-03-27T06:01:05.419Z] TESTING: [2025-03-27T06:01:11.560Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-03-27T06:01:15.103Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-03-27T06:01:15.103Z] GC before operation: completed in 73.271 ms, heap usage 40.399 MB -> 34.998 MB. [2025-03-27T06:01:40.862Z] ====== log-regression (apache-spark) [default], iteration 0 completed (24243.689 ms) ====== [2025-03-27T06:01:40.862Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-03-27T06:01:40.862Z] GC before operation: completed in 187.626 ms, heap usage 348.444 MB -> 117.893 MB. [2025-03-27T06:01:47.485Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7082.752 ms) ====== [2025-03-27T06:01:47.485Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-03-27T06:01:47.485Z] GC before operation: completed in 169.497 ms, heap usage 230.392 MB -> 117.258 MB. [2025-03-27T06:01:52.730Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5116.111 ms) ====== [2025-03-27T06:01:52.730Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-03-27T06:01:52.730Z] GC before operation: completed in 171.516 ms, heap usage 321.759 MB -> 117.818 MB. [2025-03-27T06:01:57.819Z] ====== log-regression (apache-spark) [default], iteration 3 completed (5122.577 ms) ====== [2025-03-27T06:01:57.819Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-03-27T06:01:57.819Z] GC before operation: completed in 404.371 ms, heap usage 241.885 MB -> 117.951 MB. [2025-03-27T06:02:03.112Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5247.375 ms) ====== [2025-03-27T06:02:03.112Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-03-27T06:02:03.112Z] GC before operation: completed in 228.675 ms, heap usage 305.929 MB -> 118.419 MB. [2025-03-27T06:02:08.298Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4946.721 ms) ====== [2025-03-27T06:02:08.298Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-03-27T06:02:08.298Z] GC before operation: completed in 129.641 ms, heap usage 260.945 MB -> 118.505 MB. [2025-03-27T06:02:13.636Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5277.782 ms) ====== [2025-03-27T06:02:13.636Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-03-27T06:02:13.636Z] GC before operation: completed in 262.938 ms, heap usage 364.570 MB -> 119.095 MB. [2025-03-27T06:02:20.067Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5672.061 ms) ====== [2025-03-27T06:02:20.067Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-03-27T06:02:20.067Z] GC before operation: completed in 119.350 ms, heap usage 233.150 MB -> 118.745 MB. [2025-03-27T06:02:23.057Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3373.087 ms) ====== [2025-03-27T06:02:23.057Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-03-27T06:02:23.057Z] GC before operation: completed in 189.929 ms, heap usage 319.472 MB -> 119.337 MB. [2025-03-27T06:02:26.175Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3222.428 ms) ====== [2025-03-27T06:02:26.175Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-03-27T06:02:26.885Z] GC before operation: completed in 103.184 ms, heap usage 319.301 MB -> 119.450 MB. [2025-03-27T06:02:32.767Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6221.192 ms) ====== [2025-03-27T06:02:32.767Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-03-27T06:02:32.767Z] GC before operation: completed in 169.736 ms, heap usage 305.944 MB -> 119.580 MB. [2025-03-27T06:02:36.757Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3852.137 ms) ====== [2025-03-27T06:02:36.757Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-03-27T06:02:36.757Z] GC before operation: completed in 165.942 ms, heap usage 318.495 MB -> 119.888 MB. [2025-03-27T06:02:40.824Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3566.871 ms) ====== [2025-03-27T06:02:40.824Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-03-27T06:02:40.824Z] GC before operation: completed in 126.392 ms, heap usage 314.341 MB -> 119.998 MB. [2025-03-27T06:02:45.173Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4402.582 ms) ====== [2025-03-27T06:02:45.173Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-03-27T06:02:45.173Z] GC before operation: completed in 144.003 ms, heap usage 270.320 MB -> 120.008 MB. [2025-03-27T06:02:49.377Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4272.523 ms) ====== [2025-03-27T06:02:49.377Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-03-27T06:02:49.377Z] GC before operation: completed in 131.155 ms, heap usage 247.673 MB -> 120.100 MB. [2025-03-27T06:02:53.411Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3429.295 ms) ====== [2025-03-27T06:02:53.411Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-03-27T06:02:53.411Z] GC before operation: completed in 216.654 ms, heap usage 237.834 MB -> 120.396 MB. [2025-03-27T06:02:57.594Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3631.632 ms) ====== [2025-03-27T06:02:57.594Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-03-27T06:02:57.594Z] GC before operation: completed in 132.117 ms, heap usage 247.280 MB -> 120.520 MB. [2025-03-27T06:03:01.709Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4526.605 ms) ====== [2025-03-27T06:03:01.709Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-03-27T06:03:01.709Z] GC before operation: completed in 213.622 ms, heap usage 304.169 MB -> 120.962 MB. [2025-03-27T06:03:05.776Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3497.173 ms) ====== [2025-03-27T06:03:05.776Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-03-27T06:03:05.776Z] GC before operation: completed in 276.714 ms, heap usage 239.315 MB -> 120.894 MB. [2025-03-27T06:03:09.345Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4066.355 ms) ====== [2025-03-27T06:03:10.156Z] ----------------------------------- [2025-03-27T06:03:10.156Z] renaissance-log-regression_0_PASSED [2025-03-27T06:03:10.156Z] ----------------------------------- [2025-03-27T06:03:10.156Z] [2025-03-27T06:03:10.156Z] TEST TEARDOWN: [2025-03-27T06:03:10.156Z] Nothing to be done for teardown. [2025-03-27T06:03:10.156Z] renaissance-log-regression_0 Finish Time: Thu Mar 27 06:03:10 2025 Epoch Time (ms): 1743055390014