[2025-05-29T05:12:42.462Z] Running test renaissance-log-regression_0 ... [2025-05-29T05:12:42.462Z] =============================================== [2025-05-29T05:12:42.462Z] renaissance-log-regression_0 Start Time: Thu May 29 05:12:42 2025 Epoch Time (ms): 1748495562254 [2025-05-29T05:12:42.462Z] variation: NoOptions [2025-05-29T05:12:42.462Z] JVM_OPTIONS: [2025-05-29T05:12:42.462Z] { \ [2025-05-29T05:12:42.462Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T05:12:42.462Z] echo "Nothing to be done for setup."; \ [2025-05-29T05:12:42.462Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17484928925221/renaissance-log-regression_0"; \ [2025-05-29T05:12:42.462Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17484928925221/renaissance-log-regression_0"; \ [2025-05-29T05:12:42.462Z] echo ""; echo "TESTING:"; \ [2025-05-29T05:12:42.462Z] "/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_17484928925221/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-29T05:12:42.462Z] 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_17484928925221/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T05:12:42.462Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T05:12:42.462Z] echo "Nothing to be done for teardown."; \ [2025-05-29T05:12:42.462Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17484928925221/TestTargetResult"; [2025-05-29T05:12:42.462Z] [2025-05-29T05:12:42.462Z] TEST SETUP: [2025-05-29T05:12:42.462Z] Nothing to be done for setup. [2025-05-29T05:12:42.462Z] [2025-05-29T05:12:42.462Z] TESTING: [2025-05-29T05:12:52.847Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-29T05:12:53.481Z] WARNING: An illegal reflective access operation has occurred [2025-05-29T05:12:53.482Z] 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_17484928925221/renaissance-log-regression_0/harness-051243-17772019951020540948/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-29T05:12:53.482Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-29T05:12:53.482Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-29T05:12:53.482Z] WARNING: All illegal access operations will be denied in a future release [2025-05-29T05:12:54.819Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-29T05:12:54.819Z] GC before operation: completed in 86.016 ms, heap usage 51.548 MB -> 34.367 MB. [2025-05-29T05:13:16.259Z] ====== log-regression (apache-spark) [default], iteration 0 completed (18636.570 ms) ====== [2025-05-29T05:13:16.259Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-29T05:13:16.259Z] GC before operation: completed in 249.316 ms, heap usage 244.630 MB -> 115.811 MB. [2025-05-29T05:13:18.479Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4421.196 ms) ====== [2025-05-29T05:13:18.479Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-29T05:13:18.479Z] GC before operation: completed in 177.788 ms, heap usage 258.549 MB -> 115.974 MB. [2025-05-29T05:13:22.913Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4480.334 ms) ====== [2025-05-29T05:13:22.913Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-29T05:13:23.687Z] GC before operation: completed in 180.750 ms, heap usage 272.713 MB -> 116.458 MB. [2025-05-29T05:13:26.812Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3800.490 ms) ====== [2025-05-29T05:13:26.812Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-29T05:13:27.492Z] GC before operation: completed in 180.036 ms, heap usage 272.263 MB -> 116.756 MB. [2025-05-29T05:13:30.833Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3834.323 ms) ====== [2025-05-29T05:13:30.833Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-29T05:13:31.587Z] GC before operation: completed in 123.539 ms, heap usage 247.659 MB -> 116.799 MB. [2025-05-29T05:13:35.052Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3925.846 ms) ====== [2025-05-29T05:13:35.052Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-29T05:13:35.052Z] GC before operation: completed in 170.678 ms, heap usage 294.022 MB -> 117.814 MB. [2025-05-29T05:13:39.364Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3516.777 ms) ====== [2025-05-29T05:13:39.364Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-29T05:13:39.364Z] GC before operation: completed in 232.677 ms, heap usage 248.721 MB -> 117.252 MB. [2025-05-29T05:13:42.458Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3253.160 ms) ====== [2025-05-29T05:13:42.458Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-29T05:13:42.458Z] GC before operation: completed in 170.208 ms, heap usage 280.451 MB -> 117.522 MB. [2025-05-29T05:13:46.578Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3707.282 ms) ====== [2025-05-29T05:13:46.578Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-29T05:13:46.578Z] GC before operation: completed in 218.734 ms, heap usage 251.043 MB -> 117.641 MB. [2025-05-29T05:13:50.468Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3612.530 ms) ====== [2025-05-29T05:13:50.468Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-29T05:13:50.468Z] GC before operation: completed in 150.288 ms, heap usage 245.764 MB -> 117.704 MB. [2025-05-29T05:13:54.358Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3632.773 ms) ====== [2025-05-29T05:13:54.358Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-29T05:13:54.358Z] GC before operation: completed in 282.499 ms, heap usage 235.656 MB -> 117.889 MB. [2025-05-29T05:13:57.713Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3787.979 ms) ====== [2025-05-29T05:13:57.713Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-29T05:13:58.498Z] GC before operation: completed in 219.900 ms, heap usage 251.243 MB -> 118.142 MB. [2025-05-29T05:14:02.589Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3984.688 ms) ====== [2025-05-29T05:14:02.589Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-29T05:14:02.589Z] GC before operation: completed in 152.139 ms, heap usage 292.486 MB -> 118.486 MB. [2025-05-29T05:14:05.198Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3241.403 ms) ====== [2025-05-29T05:14:05.198Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-29T05:14:05.936Z] GC before operation: completed in 169.689 ms, heap usage 258.859 MB -> 118.493 MB. [2025-05-29T05:14:08.947Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3179.764 ms) ====== [2025-05-29T05:14:08.947Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-29T05:14:08.947Z] GC before operation: completed in 133.240 ms, heap usage 248.153 MB -> 118.664 MB. [2025-05-29T05:14:12.776Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3508.650 ms) ====== [2025-05-29T05:14:12.776Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-29T05:14:12.777Z] GC before operation: completed in 199.980 ms, heap usage 294.145 MB -> 119.052 MB. [2025-05-29T05:14:16.671Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3579.802 ms) ====== [2025-05-29T05:14:16.671Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-29T05:14:16.671Z] GC before operation: completed in 216.207 ms, heap usage 269.234 MB -> 119.077 MB. [2025-05-29T05:14:19.593Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3060.232 ms) ====== [2025-05-29T05:14:19.593Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-29T05:14:19.593Z] GC before operation: completed in 216.711 ms, heap usage 316.394 MB -> 119.496 MB. [2025-05-29T05:14:22.877Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3341.572 ms) ====== [2025-05-29T05:14:22.877Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-29T05:14:23.627Z] GC before operation: completed in 148.878 ms, heap usage 249.608 MB -> 119.410 MB. [2025-05-29T05:14:26.585Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3632.258 ms) ====== [2025-05-29T05:14:27.272Z] ----------------------------------- [2025-05-29T05:14:27.272Z] renaissance-log-regression_0_PASSED [2025-05-29T05:14:27.272Z] ----------------------------------- [2025-05-29T05:14:27.272Z] [2025-05-29T05:14:27.272Z] TEST TEARDOWN: [2025-05-29T05:14:27.272Z] Nothing to be done for teardown. [2025-05-29T05:14:27.272Z] renaissance-log-regression_0 Finish Time: Thu May 29 05:14:27 2025 Epoch Time (ms): 1748495667185