[2025-08-02T01:16:43.134Z] Running test renaissance-log-regression_0 ... [2025-08-02T01:16:43.134Z] =============================================== [2025-08-02T01:16:43.134Z] renaissance-log-regression_0 Start Time: Sat Aug 2 01:16:42 2025 Epoch Time (ms): 1754097402872 [2025-08-02T01:16:43.134Z] variation: NoOptions [2025-08-02T01:16:43.134Z] JVM_OPTIONS: [2025-08-02T01:16:43.134Z] { \ [2025-08-02T01:16:43.134Z] echo ""; echo "TEST SETUP:"; \ [2025-08-02T01:16:43.134Z] echo "Nothing to be done for setup."; \ [2025-08-02T01:16:43.134Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17540869947190/renaissance-log-regression_0"; \ [2025-08-02T01:16:43.134Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17540869947190/renaissance-log-regression_0"; \ [2025-08-02T01:16:43.134Z] echo ""; echo "TESTING:"; \ [2025-08-02T01:16:43.134Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17540869947190/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-02T01:16:43.134Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17540869947190/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-02T01:16:43.134Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-02T01:16:43.134Z] echo "Nothing to be done for teardown."; \ [2025-08-02T01:16:43.134Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17540869947190/TestTargetResult"; [2025-08-02T01:16:43.134Z] [2025-08-02T01:16:43.134Z] TEST SETUP: [2025-08-02T01:16:43.134Z] Nothing to be done for setup. [2025-08-02T01:16:43.134Z] [2025-08-02T01:16:43.134Z] TESTING: [2025-08-02T01:17:18.830Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-02T01:17:24.561Z] WARNING: An illegal reflective access operation has occurred [2025-08-02T01:17:24.561Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17540869947190/renaissance-log-regression_0/harness-011646-4375327607757064095/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-02T01:17:24.561Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-02T01:17:24.561Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-02T01:17:24.561Z] WARNING: All illegal access operations will be denied in a future release [2025-08-02T01:17:34.586Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-02T01:17:34.586Z] GC before operation: completed in 382.435 ms, heap usage 87.173 MB -> 34.442 MB. [2025-08-02T01:18:55.073Z] ====== log-regression (apache-spark) [default], iteration 0 completed (78753.077 ms) ====== [2025-08-02T01:18:55.074Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-02T01:18:55.074Z] GC before operation: completed in 898.058 ms, heap usage 354.325 MB -> 118.597 MB. [2025-08-02T01:19:22.000Z] ====== log-regression (apache-spark) [default], iteration 1 completed (24933.042 ms) ====== [2025-08-02T01:19:22.000Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-02T01:19:22.000Z] GC before operation: completed in 995.510 ms, heap usage 236.316 MB -> 118.780 MB. [2025-08-02T01:19:44.784Z] ====== log-regression (apache-spark) [default], iteration 2 completed (23775.903 ms) ====== [2025-08-02T01:19:44.784Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-02T01:19:44.784Z] GC before operation: completed in 802.082 ms, heap usage 247.346 MB -> 119.154 MB. [2025-08-02T01:20:04.046Z] ====== log-regression (apache-spark) [default], iteration 3 completed (19176.441 ms) ====== [2025-08-02T01:20:04.046Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-02T01:20:04.046Z] GC before operation: completed in 848.879 ms, heap usage 262.763 MB -> 119.481 MB. [2025-08-02T01:20:23.508Z] ====== log-regression (apache-spark) [default], iteration 4 completed (17444.933 ms) ====== [2025-08-02T01:20:23.508Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-02T01:20:23.508Z] GC before operation: completed in 1110.550 ms, heap usage 244.523 MB -> 119.592 MB. [2025-08-02T01:20:40.057Z] ====== log-regression (apache-spark) [default], iteration 5 completed (16438.362 ms) ====== [2025-08-02T01:20:40.057Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-02T01:20:40.057Z] GC before operation: completed in 769.379 ms, heap usage 290.858 MB -> 119.954 MB. [2025-08-02T01:20:54.390Z] ====== log-regression (apache-spark) [default], iteration 6 completed (13732.143 ms) ====== [2025-08-02T01:20:54.390Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-02T01:20:54.390Z] GC before operation: completed in 790.239 ms, heap usage 326.665 MB -> 120.234 MB. [2025-08-02T01:21:08.665Z] ====== log-regression (apache-spark) [default], iteration 7 completed (13980.621 ms) ====== [2025-08-02T01:21:08.665Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-02T01:21:09.473Z] GC before operation: completed in 803.645 ms, heap usage 298.400 MB -> 120.345 MB. [2025-08-02T01:21:23.673Z] ====== log-regression (apache-spark) [default], iteration 8 completed (13115.747 ms) ====== [2025-08-02T01:21:23.673Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-02T01:21:23.673Z] GC before operation: completed in 697.105 ms, heap usage 318.286 MB -> 120.626 MB. [2025-08-02T01:21:35.814Z] ====== log-regression (apache-spark) [default], iteration 9 completed (11465.269 ms) ====== [2025-08-02T01:21:35.814Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-02T01:21:35.814Z] GC before operation: completed in 805.152 ms, heap usage 344.479 MB -> 120.914 MB. [2025-08-02T01:21:48.606Z] ====== log-regression (apache-spark) [default], iteration 10 completed (13138.561 ms) ====== [2025-08-02T01:21:48.606Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-02T01:21:49.413Z] GC before operation: completed in 752.572 ms, heap usage 272.916 MB -> 120.880 MB. [2025-08-02T01:22:01.671Z] ====== log-regression (apache-spark) [default], iteration 11 completed (12491.403 ms) ====== [2025-08-02T01:22:01.671Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-02T01:22:02.498Z] GC before operation: completed in 726.942 ms, heap usage 317.393 MB -> 121.305 MB. [2025-08-02T01:22:16.842Z] ====== log-regression (apache-spark) [default], iteration 12 completed (12952.864 ms) ====== [2025-08-02T01:22:16.842Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-02T01:22:16.842Z] GC before operation: completed in 722.267 ms, heap usage 312.614 MB -> 121.445 MB. [2025-08-02T01:22:31.176Z] ====== log-regression (apache-spark) [default], iteration 13 completed (13571.181 ms) ====== [2025-08-02T01:22:31.176Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-02T01:22:31.176Z] GC before operation: completed in 746.907 ms, heap usage 337.460 MB -> 121.629 MB. [2025-08-02T01:22:43.954Z] ====== log-regression (apache-spark) [default], iteration 14 completed (12846.361 ms) ====== [2025-08-02T01:22:43.954Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-02T01:22:44.767Z] GC before operation: completed in 843.077 ms, heap usage 291.874 MB -> 121.745 MB. [2025-08-02T01:22:59.157Z] ====== log-regression (apache-spark) [default], iteration 15 completed (13097.970 ms) ====== [2025-08-02T01:22:59.157Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-02T01:22:59.157Z] GC before operation: completed in 749.758 ms, heap usage 326.737 MB -> 122.057 MB. [2025-08-02T01:23:11.274Z] ====== log-regression (apache-spark) [default], iteration 16 completed (11767.778 ms) ====== [2025-08-02T01:23:11.274Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-02T01:23:11.274Z] GC before operation: completed in 850.387 ms, heap usage 327.982 MB -> 122.416 MB. [2025-08-02T01:23:23.382Z] ====== log-regression (apache-spark) [default], iteration 17 completed (11485.267 ms) ====== [2025-08-02T01:23:23.383Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-02T01:23:23.383Z] GC before operation: completed in 825.708 ms, heap usage 273.535 MB -> 122.292 MB. [2025-08-02T01:23:35.596Z] ====== log-regression (apache-spark) [default], iteration 18 completed (11703.310 ms) ====== [2025-08-02T01:23:35.596Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-02T01:23:35.596Z] GC before operation: completed in 772.024 ms, heap usage 264.673 MB -> 122.490 MB. [2025-08-02T01:23:48.548Z] ====== log-regression (apache-spark) [default], iteration 19 completed (12604.011 ms) ====== [2025-08-02T01:23:51.125Z] ----------------------------------- [2025-08-02T01:23:51.125Z] renaissance-log-regression_0_PASSED [2025-08-02T01:23:51.125Z] ----------------------------------- [2025-08-02T01:23:51.125Z] [2025-08-02T01:23:51.125Z] TEST TEARDOWN: [2025-08-02T01:23:51.125Z] Nothing to be done for teardown. [2025-08-02T01:23:51.125Z] renaissance-log-regression_0 Finish Time: Sat Aug 2 01:23:50 2025 Epoch Time (ms): 1754097830587