[2025-10-22T18:08:47.945Z] Running test renaissance-log-regression_0 ... [2025-10-22T18:08:47.945Z] =============================================== [2025-10-22T18:08:47.945Z] renaissance-log-regression_0 Start Time: Wed Oct 22 18:08:47 2025 Epoch Time (ms): 1761156527324 [2025-10-22T18:08:47.945Z] variation: NoOptions [2025-10-22T18:08:47.945Z] JVM_OPTIONS: [2025-10-22T18:08:47.945Z] { \ [2025-10-22T18:08:47.945Z] echo ""; echo "TEST SETUP:"; \ [2025-10-22T18:08:47.945Z] echo "Nothing to be done for setup."; \ [2025-10-22T18:08:47.945Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-log-regression_0"; \ [2025-10-22T18:08:47.945Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-log-regression_0"; \ [2025-10-22T18:08:47.945Z] echo ""; echo "TESTING:"; \ [2025-10-22T18:08:47.945Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/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_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-22T18:08:47.945Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-22T18:08:47.945Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-22T18:08:47.945Z] echo "Nothing to be done for teardown."; \ [2025-10-22T18:08:47.945Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/TestTargetResult"; [2025-10-22T18:08:47.945Z] [2025-10-22T18:08:47.945Z] TEST SETUP: [2025-10-22T18:08:47.945Z] Nothing to be done for setup. [2025-10-22T18:08:47.945Z] [2025-10-22T18:08:47.945Z] TESTING: [2025-10-22T18:08:47.945Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-22T18:08:47.945Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/output_17611555014445/renaissance-log-regression_0/launcher-180847-4163631951116703217/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-22T18:08:47.945Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-22T18:08:47.945Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-22T18:08:51.819Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-10-22T18:08:53.197Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-22T18:08:53.197Z] GC before operation: completed in 47.926 ms, heap usage 53.839 MB -> 35.154 MB. [2025-10-22T18:09:05.341Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10943.629 ms) ====== [2025-10-22T18:09:05.341Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-22T18:09:05.341Z] GC before operation: completed in 89.919 ms, heap usage 549.022 MB -> 117.482 MB. [2025-10-22T18:09:06.726Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2402.466 ms) ====== [2025-10-22T18:09:06.726Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-22T18:09:06.726Z] GC before operation: completed in 84.693 ms, heap usage 247.593 MB -> 117.018 MB. [2025-10-22T18:09:09.560Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2773.632 ms) ====== [2025-10-22T18:09:09.561Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-22T18:09:09.561Z] GC before operation: completed in 74.851 ms, heap usage 232.089 MB -> 117.389 MB. [2025-10-22T18:09:12.416Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2202.159 ms) ====== [2025-10-22T18:09:12.416Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-22T18:09:12.416Z] GC before operation: completed in 72.026 ms, heap usage 272.356 MB -> 117.876 MB. [2025-10-22T18:09:13.714Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1876.950 ms) ====== [2025-10-22T18:09:13.714Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-22T18:09:13.714Z] GC before operation: completed in 94.917 ms, heap usage 264.084 MB -> 118.030 MB. [2025-10-22T18:09:23.497Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2082.831 ms) ====== [2025-10-22T18:09:23.497Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-22T18:09:23.497Z] GC before operation: completed in 109.627 ms, heap usage 241.243 MB -> 118.173 MB. [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2122.566 ms) ====== [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-22T18:09:31.592Z] GC before operation: completed in 86.021 ms, heap usage 266.555 MB -> 118.461 MB. [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1890.385 ms) ====== [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-22T18:09:31.592Z] GC before operation: completed in 72.940 ms, heap usage 231.400 MB -> 118.526 MB. [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1782.162 ms) ====== [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-22T18:09:31.592Z] GC before operation: completed in 89.623 ms, heap usage 461.193 MB -> 119.641 MB. [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1462.637 ms) ====== [2025-10-22T18:09:31.592Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-22T18:09:31.593Z] GC before operation: completed in 101.216 ms, heap usage 428.635 MB -> 119.588 MB. [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1794.983 ms) ====== [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-22T18:09:31.593Z] GC before operation: completed in 125.219 ms, heap usage 329.062 MB -> 119.506 MB. [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1840.470 ms) ====== [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-22T18:09:31.593Z] GC before operation: completed in 87.542 ms, heap usage 235.988 MB -> 119.236 MB. [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1565.305 ms) ====== [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-22T18:09:31.593Z] GC before operation: completed in 93.617 ms, heap usage 262.512 MB -> 119.653 MB. [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1755.056 ms) ====== [2025-10-22T18:09:31.593Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-22T18:09:31.593Z] GC before operation: completed in 125.244 ms, heap usage 566.850 MB -> 120.953 MB. [2025-10-22T18:09:32.898Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1856.031 ms) ====== [2025-10-22T18:09:32.898Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-22T18:09:32.898Z] GC before operation: completed in 113.732 ms, heap usage 274.913 MB -> 119.930 MB. [2025-10-22T18:09:34.930Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1888.956 ms) ====== [2025-10-22T18:09:34.930Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-22T18:09:34.930Z] GC before operation: completed in 140.601 ms, heap usage 236.191 MB -> 119.985 MB. [2025-10-22T18:09:36.948Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1492.543 ms) ====== [2025-10-22T18:09:36.949Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-22T18:09:36.949Z] GC before operation: completed in 96.021 ms, heap usage 447.195 MB -> 121.046 MB. [2025-10-22T18:09:38.242Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1622.957 ms) ====== [2025-10-22T18:09:38.242Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-22T18:09:38.242Z] GC before operation: completed in 107.382 ms, heap usage 522.565 MB -> 121.522 MB. [2025-10-22T18:09:40.278Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1825.087 ms) ====== [2025-10-22T18:09:40.278Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-22T18:09:40.278Z] GC before operation: completed in 135.711 ms, heap usage 468.111 MB -> 121.428 MB. [2025-10-22T18:09:42.299Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1596.205 ms) ====== [2025-10-22T18:09:42.299Z] ----------------------------------- [2025-10-22T18:09:42.299Z] renaissance-log-regression_0_PASSED [2025-10-22T18:09:42.299Z] ----------------------------------- [2025-10-22T18:09:42.299Z] [2025-10-22T18:09:42.299Z] TEST TEARDOWN: [2025-10-22T18:09:42.299Z] Nothing to be done for teardown. [2025-10-22T18:09:42.299Z] renaissance-log-regression_0 Finish Time: Wed Oct 22 18:09:41 2025 Epoch Time (ms): 1761156581945