[2025-11-26T22:32:20.526Z] Running test renaissance-log-regression_0 ... [2025-11-26T22:32:20.526Z] =============================================== [2025-11-26T22:32:20.526Z] renaissance-log-regression_0 Start Time: Wed Nov 26 22:32:20 2025 Epoch Time (ms): 1764196340355 [2025-11-26T22:32:20.526Z] variation: NoOptions [2025-11-26T22:32:20.526Z] JVM_OPTIONS: [2025-11-26T22:32:20.526Z] { \ [2025-11-26T22:32:20.526Z] echo ""; echo "TEST SETUP:"; \ [2025-11-26T22:32:20.526Z] echo "Nothing to be done for setup."; \ [2025-11-26T22:32:20.526Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1764194822349/renaissance-log-regression_0"; \ [2025-11-26T22:32:20.526Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1764194822349/renaissance-log-regression_0"; \ [2025-11-26T22:32:20.526Z] echo ""; echo "TESTING:"; \ [2025-11-26T22:32:20.526Z] "/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_1764194822349/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-26T22:32:20.526Z] 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_1764194822349/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-26T22:32:20.526Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-26T22:32:20.526Z] echo "Nothing to be done for teardown."; \ [2025-11-26T22:32:20.526Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1764194822349/TestTargetResult"; [2025-11-26T22:32:20.526Z] [2025-11-26T22:32:20.526Z] TEST SETUP: [2025-11-26T22:32:20.526Z] Nothing to be done for setup. [2025-11-26T22:32:20.526Z] [2025-11-26T22:32:20.526Z] TESTING: [2025-11-26T22:32:26.755Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-26T22:32:28.144Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-26T22:32:28.144Z] GC before operation: completed in 53.966 ms, heap usage 51.277 MB -> 34.999 MB. [2025-11-26T22:32:43.291Z] ====== log-regression (apache-spark) [default], iteration 0 completed (13237.216 ms) ====== [2025-11-26T22:32:43.291Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-26T22:32:43.291Z] GC before operation: completed in 192.643 ms, heap usage 525.433 MB -> 117.619 MB. [2025-11-26T22:32:46.347Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4790.425 ms) ====== [2025-11-26T22:32:46.347Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-26T22:32:46.984Z] GC before operation: completed in 120.660 ms, heap usage 406.259 MB -> 117.816 MB. [2025-11-26T22:32:50.007Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3338.608 ms) ====== [2025-11-26T22:32:50.007Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-26T22:32:50.007Z] GC before operation: completed in 111.695 ms, heap usage 512.910 MB -> 118.453 MB. [2025-11-26T22:32:54.066Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3562.182 ms) ====== [2025-11-26T22:32:54.066Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-26T22:32:54.066Z] GC before operation: completed in 157.563 ms, heap usage 529.666 MB -> 118.964 MB. [2025-11-26T22:32:57.604Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3229.306 ms) ====== [2025-11-26T22:32:57.604Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-26T22:32:57.604Z] GC before operation: completed in 104.561 ms, heap usage 487.924 MB -> 119.075 MB. [2025-11-26T22:32:59.779Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2861.051 ms) ====== [2025-11-26T22:32:59.779Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-26T22:33:00.496Z] GC before operation: completed in 135.103 ms, heap usage 147.869 MB -> 118.975 MB. [2025-11-26T22:33:02.791Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2663.574 ms) ====== [2025-11-26T22:33:02.792Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-26T22:33:03.486Z] GC before operation: completed in 248.574 ms, heap usage 403.007 MB -> 119.132 MB. [2025-11-26T22:33:05.686Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2817.016 ms) ====== [2025-11-26T22:33:05.686Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-26T22:33:06.381Z] GC before operation: completed in 113.294 ms, heap usage 404.581 MB -> 119.246 MB. [2025-11-26T22:33:09.422Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2928.308 ms) ====== [2025-11-26T22:33:09.422Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-26T22:33:09.422Z] GC before operation: completed in 171.134 ms, heap usage 550.829 MB -> 120.072 MB. [2025-11-26T22:33:11.588Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2692.650 ms) ====== [2025-11-26T22:33:11.588Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-26T22:33:12.308Z] GC before operation: completed in 122.415 ms, heap usage 318.724 MB -> 119.480 MB. [2025-11-26T22:33:15.415Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3156.135 ms) ====== [2025-11-26T22:33:15.415Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-26T22:33:15.415Z] GC before operation: completed in 113.107 ms, heap usage 296.666 MB -> 119.538 MB. [2025-11-26T22:33:18.460Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2873.337 ms) ====== [2025-11-26T22:33:18.460Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-26T22:33:18.460Z] GC before operation: completed in 154.583 ms, heap usage 274.504 MB -> 119.657 MB. [2025-11-26T22:33:22.472Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3958.389 ms) ====== [2025-11-26T22:33:22.472Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-26T22:33:22.472Z] GC before operation: completed in 127.581 ms, heap usage 277.484 MB -> 119.827 MB. [2025-11-26T22:33:25.501Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2770.052 ms) ====== [2025-11-26T22:33:25.501Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-26T22:33:25.501Z] GC before operation: completed in 252.652 ms, heap usage 623.633 MB -> 121.229 MB. [2025-11-26T22:33:28.583Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2942.357 ms) ====== [2025-11-26T22:33:28.583Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-26T22:33:28.583Z] GC before operation: completed in 175.722 ms, heap usage 345.672 MB -> 120.403 MB. [2025-11-26T22:33:31.646Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3169.435 ms) ====== [2025-11-26T22:33:31.646Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-26T22:33:31.646Z] GC before operation: completed in 119.370 ms, heap usage 254.402 MB -> 120.225 MB. [2025-11-26T22:33:34.625Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3117.706 ms) ====== [2025-11-26T22:33:34.625Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-26T22:33:35.591Z] GC before operation: completed in 152.253 ms, heap usage 252.051 MB -> 120.413 MB. [2025-11-26T22:33:38.741Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3397.613 ms) ====== [2025-11-26T22:33:38.741Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-26T22:33:38.741Z] GC before operation: completed in 231.556 ms, heap usage 296.398 MB -> 120.746 MB. [2025-11-26T22:33:40.913Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2394.741 ms) ====== [2025-11-26T22:33:40.913Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-26T22:33:40.913Z] GC before operation: completed in 129.775 ms, heap usage 142.221 MB -> 120.600 MB. [2025-11-26T22:33:43.990Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2886.478 ms) ====== [2025-11-26T22:33:44.685Z] ----------------------------------- [2025-11-26T22:33:44.685Z] renaissance-log-regression_0_PASSED [2025-11-26T22:33:44.685Z] ----------------------------------- [2025-11-26T22:33:44.685Z] [2025-11-26T22:33:44.685Z] TEST TEARDOWN: [2025-11-26T22:33:44.685Z] Nothing to be done for teardown. [2025-11-26T22:33:44.685Z] renaissance-log-regression_0 Finish Time: Wed Nov 26 22:33:44 2025 Epoch Time (ms): 1764196424098