[2025-01-22T01:31:42.073Z] Running test renaissance-log-regression_0 ... [2025-01-22T01:31:42.073Z] =============================================== [2025-01-22T01:31:42.073Z] renaissance-log-regression_0 Start Time: Wed Jan 22 01:31:41 2025 Epoch Time (ms): 1737509501453 [2025-01-22T01:31:42.073Z] variation: NoOptions [2025-01-22T01:31:42.073Z] JVM_OPTIONS: [2025-01-22T01:31:42.073Z] { \ [2025-01-22T01:31:42.073Z] echo ""; echo "TEST SETUP:"; \ [2025-01-22T01:31:42.073Z] echo "Nothing to be done for setup."; \ [2025-01-22T01:31:42.073Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17375086968837/renaissance-log-regression_0"; \ [2025-01-22T01:31:42.073Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17375086968837/renaissance-log-regression_0"; \ [2025-01-22T01:31:42.073Z] echo ""; echo "TESTING:"; \ [2025-01-22T01:31:42.073Z] "/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_17375086968837/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-01-22T01:31:42.073Z] 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_17375086968837/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-22T01:31:42.073Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-22T01:31:42.073Z] echo "Nothing to be done for teardown."; \ [2025-01-22T01:31:42.073Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17375086968837/TestTargetResult"; [2025-01-22T01:31:42.073Z] [2025-01-22T01:31:42.073Z] TEST SETUP: [2025-01-22T01:31:42.073Z] Nothing to be done for setup. [2025-01-22T01:31:42.073Z] [2025-01-22T01:31:42.073Z] TESTING: [2025-01-22T01:31:44.937Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-22T01:31:46.270Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-01-22T01:31:49.144Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-01-22T01:31:49.144Z] GC before operation: completed in 55.615 ms, heap usage 68.883 MB -> 35.055 MB. [2025-01-22T01:31:57.793Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8112.269 ms) ====== [2025-01-22T01:31:57.793Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-01-22T01:31:57.793Z] GC before operation: completed in 188.606 ms, heap usage 360.029 MB -> 110.856 MB. [2025-01-22T01:31:59.876Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3043.889 ms) ====== [2025-01-22T01:31:59.876Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-01-22T01:32:00.529Z] GC before operation: completed in 105.870 ms, heap usage 276.150 MB -> 110.918 MB. [2025-01-22T01:32:02.619Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2439.045 ms) ====== [2025-01-22T01:32:02.619Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-01-22T01:32:02.619Z] GC before operation: completed in 75.858 ms, heap usage 268.371 MB -> 111.533 MB. [2025-01-22T01:32:05.492Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2338.587 ms) ====== [2025-01-22T01:32:05.492Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-01-22T01:32:05.492Z] GC before operation: completed in 86.880 ms, heap usage 277.256 MB -> 111.669 MB. [2025-01-22T01:32:07.550Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2472.728 ms) ====== [2025-01-22T01:32:07.550Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-01-22T01:32:07.550Z] GC before operation: completed in 84.753 ms, heap usage 238.894 MB -> 111.740 MB. [2025-01-22T01:32:09.658Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1972.720 ms) ====== [2025-01-22T01:32:09.658Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-01-22T01:32:09.658Z] GC before operation: completed in 79.678 ms, heap usage 291.328 MB -> 112.102 MB. [2025-01-22T01:32:11.703Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2134.414 ms) ====== [2025-01-22T01:32:11.703Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-01-22T01:32:12.341Z] GC before operation: completed in 107.625 ms, heap usage 263.013 MB -> 112.256 MB. [2025-01-22T01:32:14.401Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2015.283 ms) ====== [2025-01-22T01:32:14.402Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-01-22T01:32:14.402Z] GC before operation: completed in 102.343 ms, heap usage 273.518 MB -> 112.465 MB. [2025-01-22T01:32:16.499Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2052.173 ms) ====== [2025-01-22T01:32:16.499Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-01-22T01:32:16.499Z] GC before operation: completed in 84.416 ms, heap usage 227.067 MB -> 112.470 MB. [2025-01-22T01:32:18.554Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1809.414 ms) ====== [2025-01-22T01:32:18.554Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-01-22T01:32:18.554Z] GC before operation: completed in 76.379 ms, heap usage 273.806 MB -> 112.772 MB. [2025-01-22T01:32:19.873Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1879.564 ms) ====== [2025-01-22T01:32:19.873Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-01-22T01:32:20.504Z] GC before operation: completed in 99.323 ms, heap usage 225.952 MB -> 112.890 MB. [2025-01-22T01:32:22.609Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2053.126 ms) ====== [2025-01-22T01:32:22.609Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-01-22T01:32:22.609Z] GC before operation: completed in 97.873 ms, heap usage 311.509 MB -> 113.226 MB. [2025-01-22T01:32:23.978Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1885.569 ms) ====== [2025-01-22T01:32:23.978Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-01-22T01:32:24.607Z] GC before operation: completed in 76.058 ms, heap usage 273.038 MB -> 113.285 MB. [2025-01-22T01:32:26.134Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1862.705 ms) ====== [2025-01-22T01:32:26.134Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-01-22T01:32:26.134Z] GC before operation: completed in 100.909 ms, heap usage 299.254 MB -> 113.601 MB. [2025-01-22T01:32:28.193Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1796.943 ms) ====== [2025-01-22T01:32:28.193Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-01-22T01:32:28.193Z] GC before operation: completed in 94.185 ms, heap usage 244.605 MB -> 113.597 MB. [2025-01-22T01:32:29.762Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1675.343 ms) ====== [2025-01-22T01:32:29.762Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-01-22T01:32:29.762Z] GC before operation: completed in 101.890 ms, heap usage 246.260 MB -> 113.864 MB. [2025-01-22T01:32:31.910Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2029.969 ms) ====== [2025-01-22T01:32:31.910Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-01-22T01:32:31.910Z] GC before operation: completed in 114.171 ms, heap usage 227.435 MB -> 113.952 MB. [2025-01-22T01:32:34.006Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1807.990 ms) ====== [2025-01-22T01:32:34.006Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-01-22T01:32:34.006Z] GC before operation: completed in 95.154 ms, heap usage 245.715 MB -> 114.162 MB. [2025-01-22T01:32:36.100Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1866.250 ms) ====== [2025-01-22T01:32:36.100Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-01-22T01:32:36.100Z] GC before operation: completed in 90.867 ms, heap usage 255.875 MB -> 114.413 MB. [2025-01-22T01:32:38.170Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1870.647 ms) ====== [2025-01-22T01:32:38.170Z] ----------------------------------- [2025-01-22T01:32:38.170Z] renaissance-log-regression_0_PASSED [2025-01-22T01:32:38.170Z] ----------------------------------- [2025-01-22T01:32:38.170Z] [2025-01-22T01:32:38.170Z] TEST TEARDOWN: [2025-01-22T01:32:38.170Z] Nothing to be done for teardown. [2025-01-22T01:32:38.170Z] renaissance-log-regression_0 Finish Time: Wed Jan 22 01:32:37 2025 Epoch Time (ms): 1737509557772