[2025-08-25T22:56:46.500Z] Running test renaissance-log-regression_0 ... [2025-08-25T22:56:46.500Z] =============================================== [2025-08-25T22:56:46.500Z] renaissance-log-regression_0 Start Time: Mon Aug 25 22:56:45 2025 Epoch Time (ms): 1756162605883 [2025-08-25T22:56:46.500Z] variation: NoOptions [2025-08-25T22:56:46.500Z] JVM_OPTIONS: [2025-08-25T22:56:46.500Z] { \ [2025-08-25T22:56:46.500Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T22:56:46.500Z] echo "Nothing to be done for setup."; \ [2025-08-25T22:56:46.500Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17561603909544/renaissance-log-regression_0"; \ [2025-08-25T22:56:46.500Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17561603909544/renaissance-log-regression_0"; \ [2025-08-25T22:56:46.500Z] echo ""; echo "TESTING:"; \ [2025-08-25T22:56:46.500Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17561603909544/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-25T22:56:46.501Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17561603909544/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T22:56:46.501Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T22:56:46.501Z] echo "Nothing to be done for teardown."; \ [2025-08-25T22:56:46.501Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17561603909544/TestTargetResult"; [2025-08-25T22:56:46.501Z] [2025-08-25T22:56:46.501Z] TEST SETUP: [2025-08-25T22:56:46.501Z] Nothing to be done for setup. [2025-08-25T22:56:46.501Z] [2025-08-25T22:56:46.501Z] TESTING: [2025-08-25T22:56:52.347Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-25T22:56:53.605Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T22:56:53.605Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17561603909544/renaissance-log-regression_0/harness-225647-6778961100100456469/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T22:56:53.605Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T22:56:53.605Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T22:56:53.605Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T22:56:54.869Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-25T22:56:54.869Z] GC before operation: completed in 115.955 ms, heap usage 38.919 MB -> 34.367 MB. [2025-08-25T22:57:11.646Z] ====== log-regression (apache-spark) [default], iteration 0 completed (16370.577 ms) ====== [2025-08-25T22:57:11.647Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-25T22:57:11.647Z] GC before operation: completed in 238.181 ms, heap usage 326.922 MB -> 115.848 MB. [2025-08-25T22:57:16.285Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4304.044 ms) ====== [2025-08-25T22:57:16.285Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-25T22:57:16.285Z] GC before operation: completed in 106.977 ms, heap usage 327.147 MB -> 116.225 MB. [2025-08-25T22:57:20.959Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4283.835 ms) ====== [2025-08-25T22:57:20.960Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-25T22:57:20.960Z] GC before operation: completed in 181.644 ms, heap usage 259.989 MB -> 116.374 MB. [2025-08-25T22:57:24.612Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3441.441 ms) ====== [2025-08-25T22:57:24.612Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-25T22:57:24.612Z] GC before operation: completed in 154.371 ms, heap usage 260.147 MB -> 116.675 MB. [2025-08-25T22:57:28.321Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3525.373 ms) ====== [2025-08-25T22:57:28.321Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-25T22:57:28.321Z] GC before operation: completed in 170.733 ms, heap usage 294.152 MB -> 117.034 MB. [2025-08-25T22:57:32.109Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3655.465 ms) ====== [2025-08-25T22:57:32.109Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-25T22:57:32.109Z] GC before operation: completed in 151.789 ms, heap usage 259.422 MB -> 117.048 MB. [2025-08-25T22:57:35.835Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4072.561 ms) ====== [2025-08-25T22:57:35.835Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-25T22:57:35.835Z] GC before operation: completed in 189.201 ms, heap usage 228.737 MB -> 117.176 MB. [2025-08-25T22:57:39.726Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3581.484 ms) ====== [2025-08-25T22:57:39.726Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-25T22:57:39.726Z] GC before operation: completed in 176.387 ms, heap usage 279.800 MB -> 117.527 MB. [2025-08-25T22:57:43.505Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3988.585 ms) ====== [2025-08-25T22:57:43.505Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-25T22:57:44.157Z] GC before operation: completed in 173.549 ms, heap usage 269.402 MB -> 117.621 MB. [2025-08-25T22:57:47.914Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3899.697 ms) ====== [2025-08-25T22:57:47.914Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-25T22:57:47.914Z] GC before operation: completed in 213.817 ms, heap usage 295.082 MB -> 117.961 MB. [2025-08-25T22:57:51.635Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3815.471 ms) ====== [2025-08-25T22:57:51.635Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-25T22:57:52.263Z] GC before operation: completed in 266.197 ms, heap usage 281.416 MB -> 118.071 MB. [2025-08-25T22:57:56.054Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3919.594 ms) ====== [2025-08-25T22:57:56.054Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-25T22:57:56.054Z] GC before operation: completed in 161.006 ms, heap usage 357.686 MB -> 118.591 MB. [2025-08-25T22:57:59.787Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3383.150 ms) ====== [2025-08-25T22:57:59.787Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-25T22:57:59.787Z] GC before operation: completed in 164.873 ms, heap usage 316.117 MB -> 118.615 MB. [2025-08-25T22:58:02.576Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3134.018 ms) ====== [2025-08-25T22:58:02.576Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-25T22:58:03.194Z] GC before operation: completed in 253.261 ms, heap usage 230.863 MB -> 118.445 MB. [2025-08-25T22:58:06.922Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3810.184 ms) ====== [2025-08-25T22:58:06.922Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-25T22:58:06.922Z] GC before operation: completed in 234.010 ms, heap usage 290.904 MB -> 118.865 MB. [2025-08-25T22:58:10.646Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3478.444 ms) ====== [2025-08-25T22:58:10.646Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-25T22:58:10.646Z] GC before operation: completed in 202.468 ms, heap usage 313.391 MB -> 119.154 MB. [2025-08-25T22:58:15.552Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4124.688 ms) ====== [2025-08-25T22:58:15.552Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-25T22:58:15.552Z] GC before operation: completed in 242.564 ms, heap usage 230.249 MB -> 119.015 MB. [2025-08-25T22:58:19.301Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3791.973 ms) ====== [2025-08-25T22:58:19.301Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-25T22:58:19.301Z] GC before operation: completed in 291.711 ms, heap usage 253.271 MB -> 119.271 MB. [2025-08-25T22:58:23.014Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3486.136 ms) ====== [2025-08-25T22:58:23.014Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-25T22:58:23.014Z] GC before operation: completed in 227.126 ms, heap usage 248.019 MB -> 119.431 MB. [2025-08-25T22:58:27.912Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4391.508 ms) ====== [2025-08-25T22:58:27.912Z] ----------------------------------- [2025-08-25T22:58:27.912Z] renaissance-log-regression_0_PASSED [2025-08-25T22:58:27.912Z] ----------------------------------- [2025-08-25T22:58:27.912Z] [2025-08-25T22:58:27.912Z] TEST TEARDOWN: [2025-08-25T22:58:27.912Z] Nothing to be done for teardown. [2025-08-25T22:58:28.537Z] renaissance-log-regression_0 Finish Time: Mon Aug 25 22:58:27 2025 Epoch Time (ms): 1756162707878