[2025-12-24T22:16:41.956Z] Running test renaissance-log-regression_0 ... [2025-12-24T22:16:41.956Z] =============================================== [2025-12-24T22:16:41.956Z] renaissance-log-regression_0 Start Time: Wed Dec 24 22:16:41 2025 Epoch Time (ms): 1766614601676 [2025-12-24T22:16:41.956Z] variation: NoOptions [2025-12-24T22:16:41.956Z] JVM_OPTIONS: [2025-12-24T22:16:41.956Z] { \ [2025-12-24T22:16:41.956Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T22:16:41.956Z] echo "Nothing to be done for setup."; \ [2025-12-24T22:16:41.956Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17666130134767/renaissance-log-regression_0"; \ [2025-12-24T22:16:41.956Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17666130134767/renaissance-log-regression_0"; \ [2025-12-24T22:16:41.956Z] echo ""; echo "TESTING:"; \ [2025-12-24T22:16:41.956Z] "/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_17666130134767/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-24T22:16:41.956Z] 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_17666130134767/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T22:16:41.956Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T22:16:41.956Z] echo "Nothing to be done for teardown."; \ [2025-12-24T22:16:41.956Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17666130134767/TestTargetResult"; [2025-12-24T22:16:41.956Z] [2025-12-24T22:16:41.956Z] TEST SETUP: [2025-12-24T22:16:41.956Z] Nothing to be done for setup. [2025-12-24T22:16:41.956Z] [2025-12-24T22:16:41.956Z] TESTING: [2025-12-24T22:16:46.649Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-24T22:16:47.933Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-24T22:16:47.933Z] GC before operation: completed in 36.293 ms, heap usage 49.951 MB -> 34.972 MB. [2025-12-24T22:16:58.145Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9750.203 ms) ====== [2025-12-24T22:16:58.145Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-24T22:16:58.145Z] GC before operation: completed in 104.513 ms, heap usage 427.207 MB -> 117.213 MB. [2025-12-24T22:17:01.019Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3280.063 ms) ====== [2025-12-24T22:17:01.019Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-24T22:17:01.019Z] GC before operation: completed in 94.644 ms, heap usage 279.148 MB -> 117.238 MB. [2025-12-24T22:17:04.780Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3453.558 ms) ====== [2025-12-24T22:17:04.780Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-24T22:17:04.780Z] GC before operation: completed in 80.653 ms, heap usage 279.440 MB -> 117.646 MB. [2025-12-24T22:17:06.918Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2467.222 ms) ====== [2025-12-24T22:17:06.918Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-24T22:17:06.918Z] GC before operation: completed in 100.903 ms, heap usage 258.700 MB -> 117.872 MB. [2025-12-24T22:17:09.814Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2857.440 ms) ====== [2025-12-24T22:17:09.814Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-24T22:17:09.814Z] GC before operation: completed in 150.314 ms, heap usage 277.860 MB -> 118.186 MB. [2025-12-24T22:17:12.650Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2237.406 ms) ====== [2025-12-24T22:17:12.650Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-24T22:17:12.650Z] GC before operation: completed in 87.743 ms, heap usage 668.532 MB -> 119.901 MB. [2025-12-24T22:17:14.780Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2060.289 ms) ====== [2025-12-24T22:17:14.780Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-24T22:17:14.780Z] GC before operation: completed in 118.055 ms, heap usage 472.656 MB -> 119.221 MB. [2025-12-24T22:17:16.810Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2035.780 ms) ====== [2025-12-24T22:17:16.810Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-24T22:17:16.810Z] GC before operation: completed in 107.440 ms, heap usage 259.409 MB -> 118.664 MB. [2025-12-24T22:17:18.888Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1920.849 ms) ====== [2025-12-24T22:17:18.888Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-24T22:17:18.888Z] GC before operation: completed in 104.981 ms, heap usage 145.911 MB -> 119.762 MB. [2025-12-24T22:17:21.011Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2167.947 ms) ====== [2025-12-24T22:17:21.011Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-24T22:17:21.011Z] GC before operation: completed in 102.210 ms, heap usage 708.311 MB -> 120.718 MB. [2025-12-24T22:17:23.041Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2290.143 ms) ====== [2025-12-24T22:17:23.041Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-24T22:17:23.661Z] GC before operation: completed in 125.785 ms, heap usage 567.302 MB -> 120.428 MB. [2025-12-24T22:17:25.680Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2424.814 ms) ====== [2025-12-24T22:17:25.680Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-24T22:17:25.680Z] GC before operation: completed in 112.881 ms, heap usage 636.807 MB -> 120.894 MB. [2025-12-24T22:17:28.519Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2244.550 ms) ====== [2025-12-24T22:17:28.519Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-24T22:17:28.519Z] GC before operation: completed in 126.006 ms, heap usage 382.187 MB -> 120.069 MB. [2025-12-24T22:17:30.551Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2038.677 ms) ====== [2025-12-24T22:17:30.552Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-24T22:17:30.552Z] GC before operation: completed in 110.472 ms, heap usage 603.777 MB -> 121.648 MB. [2025-12-24T22:17:32.584Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2252.652 ms) ====== [2025-12-24T22:17:32.584Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-24T22:17:32.584Z] GC before operation: completed in 96.587 ms, heap usage 460.366 MB -> 120.714 MB. [2025-12-24T22:17:35.420Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2539.731 ms) ====== [2025-12-24T22:17:35.420Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-24T22:17:35.420Z] GC before operation: completed in 119.563 ms, heap usage 229.316 MB -> 120.112 MB. [2025-12-24T22:17:38.252Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2570.575 ms) ====== [2025-12-24T22:17:38.252Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-24T22:17:38.252Z] GC before operation: completed in 105.458 ms, heap usage 559.723 MB -> 121.527 MB. [2025-12-24T22:17:40.289Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2017.139 ms) ====== [2025-12-24T22:17:40.289Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-24T22:17:40.289Z] GC before operation: completed in 115.085 ms, heap usage 387.712 MB -> 121.134 MB. [2025-12-24T22:17:43.128Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2474.204 ms) ====== [2025-12-24T22:17:43.128Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-24T22:17:43.128Z] GC before operation: completed in 122.386 ms, heap usage 274.819 MB -> 120.746 MB. [2025-12-24T22:17:45.157Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2275.772 ms) ====== [2025-12-24T22:17:45.157Z] ----------------------------------- [2025-12-24T22:17:45.157Z] renaissance-log-regression_0_PASSED [2025-12-24T22:17:45.157Z] ----------------------------------- [2025-12-24T22:17:45.157Z] [2025-12-24T22:17:45.157Z] TEST TEARDOWN: [2025-12-24T22:17:45.157Z] Nothing to be done for teardown. [2025-12-24T22:17:45.157Z] renaissance-log-regression_0 Finish Time: Wed Dec 24 22:17:45 2025 Epoch Time (ms): 1766614665086