[2025-06-18T23:49:07.398Z] Running test renaissance-log-regression_0 ... [2025-06-18T23:49:07.398Z] =============================================== [2025-06-18T23:49:07.398Z] renaissance-log-regression_0 Start Time: Wed Jun 18 23:49:06 2025 Epoch Time (ms): 1750290546717 [2025-06-18T23:49:07.398Z] variation: NoOptions [2025-06-18T23:49:07.398Z] JVM_OPTIONS: [2025-06-18T23:49:07.398Z] { \ [2025-06-18T23:49:07.398Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T23:49:07.398Z] echo "Nothing to be done for setup."; \ [2025-06-18T23:49:07.399Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/renaissance-log-regression_0"; \ [2025-06-18T23:49:07.399Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/renaissance-log-regression_0"; \ [2025-06-18T23:49:07.399Z] echo ""; echo "TESTING:"; \ [2025-06-18T23:49:07.399Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-18T23:49:07.399Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T23:49:07.399Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T23:49:07.399Z] echo "Nothing to be done for teardown."; \ [2025-06-18T23:49:07.399Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/TestTargetResult"; [2025-06-18T23:49:07.399Z] [2025-06-18T23:49:07.399Z] TEST SETUP: [2025-06-18T23:49:07.399Z] Nothing to be done for setup. [2025-06-18T23:49:07.399Z] [2025-06-18T23:49:07.399Z] TESTING: [2025-06-18T23:49:12.716Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-18T23:49:15.703Z] WARNING: An illegal reflective access operation has occurred [2025-06-18T23:49:15.703Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17502892034604/renaissance-log-regression_0/harness-234907-14552312401858303106/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-06-18T23:49:15.703Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-06-18T23:49:15.703Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-06-18T23:49:15.703Z] WARNING: All illegal access operations will be denied in a future release [2025-06-18T23:49:16.804Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-18T23:49:16.804Z] GC before operation: completed in 47.519 ms, heap usage 53.310 MB -> 34.378 MB. [2025-06-18T23:49:28.288Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11798.251 ms) ====== [2025-06-18T23:49:28.288Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-18T23:49:28.288Z] GC before operation: completed in 122.584 ms, heap usage 309.220 MB -> 118.499 MB. [2025-06-18T23:49:30.222Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2546.291 ms) ====== [2025-06-18T23:49:30.222Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-18T23:49:31.164Z] GC before operation: completed in 92.226 ms, heap usage 245.182 MB -> 118.830 MB. [2025-06-18T23:49:33.103Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2133.456 ms) ====== [2025-06-18T23:49:33.103Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-18T23:49:33.103Z] GC before operation: completed in 92.325 ms, heap usage 239.102 MB -> 119.220 MB. [2025-06-18T23:49:35.039Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1991.550 ms) ====== [2025-06-18T23:49:35.039Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-18T23:49:35.039Z] GC before operation: completed in 90.399 ms, heap usage 371.711 MB -> 119.883 MB. [2025-06-18T23:49:36.973Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1925.017 ms) ====== [2025-06-18T23:49:36.973Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-18T23:49:36.973Z] GC before operation: completed in 86.951 ms, heap usage 329.082 MB -> 119.915 MB. [2025-06-18T23:49:38.906Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1805.242 ms) ====== [2025-06-18T23:49:38.906Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-18T23:49:38.906Z] GC before operation: completed in 89.508 ms, heap usage 264.108 MB -> 119.948 MB. [2025-06-18T23:49:40.838Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1812.931 ms) ====== [2025-06-18T23:49:40.838Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-18T23:49:40.838Z] GC before operation: completed in 88.765 ms, heap usage 310.836 MB -> 120.318 MB. [2025-06-18T23:49:42.774Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1833.964 ms) ====== [2025-06-18T23:49:42.774Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-18T23:49:42.774Z] GC before operation: completed in 92.264 ms, heap usage 245.161 MB -> 120.282 MB. [2025-06-18T23:49:44.707Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1854.349 ms) ====== [2025-06-18T23:49:44.707Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-18T23:49:44.707Z] GC before operation: completed in 87.037 ms, heap usage 382.614 MB -> 120.926 MB. [2025-06-18T23:49:46.641Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1828.158 ms) ====== [2025-06-18T23:49:46.641Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-18T23:49:46.641Z] GC before operation: completed in 95.669 ms, heap usage 292.623 MB -> 120.854 MB. [2025-06-18T23:49:48.576Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1779.480 ms) ====== [2025-06-18T23:49:48.576Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-18T23:49:48.576Z] GC before operation: completed in 103.273 ms, heap usage 310.034 MB -> 121.051 MB. [2025-06-18T23:49:50.514Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1794.249 ms) ====== [2025-06-18T23:49:50.514Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-18T23:49:50.514Z] GC before operation: completed in 94.929 ms, heap usage 300.592 MB -> 121.248 MB. [2025-06-18T23:49:52.446Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1846.436 ms) ====== [2025-06-18T23:49:52.446Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-18T23:49:52.446Z] GC before operation: completed in 95.991 ms, heap usage 344.808 MB -> 121.618 MB. [2025-06-18T23:49:54.382Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1798.708 ms) ====== [2025-06-18T23:49:54.382Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-18T23:49:54.382Z] GC before operation: completed in 102.544 ms, heap usage 300.222 MB -> 121.643 MB. [2025-06-18T23:49:56.432Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1728.566 ms) ====== [2025-06-18T23:49:56.432Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-18T23:49:56.432Z] GC before operation: completed in 95.681 ms, heap usage 339.834 MB -> 121.975 MB. [2025-06-18T23:49:58.367Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1763.275 ms) ====== [2025-06-18T23:49:58.367Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-18T23:49:58.367Z] GC before operation: completed in 94.000 ms, heap usage 293.224 MB -> 122.048 MB. [2025-06-18T23:49:59.319Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1759.840 ms) ====== [2025-06-18T23:49:59.319Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-18T23:50:00.261Z] GC before operation: completed in 101.574 ms, heap usage 259.317 MB -> 122.178 MB. [2025-06-18T23:50:01.201Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1687.825 ms) ====== [2025-06-18T23:50:01.201Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-18T23:50:01.201Z] GC before operation: completed in 96.244 ms, heap usage 373.801 MB -> 122.716 MB. [2025-06-18T23:50:03.163Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1757.883 ms) ====== [2025-06-18T23:50:03.163Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-18T23:50:03.163Z] GC before operation: completed in 101.204 ms, heap usage 246.552 MB -> 122.495 MB. [2025-06-18T23:50:05.120Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1663.490 ms) ====== [2025-06-18T23:50:05.120Z] ----------------------------------- [2025-06-18T23:50:05.120Z] renaissance-log-regression_0_PASSED [2025-06-18T23:50:05.120Z] ----------------------------------- [2025-06-18T23:50:05.120Z] [2025-06-18T23:50:05.120Z] TEST TEARDOWN: [2025-06-18T23:50:05.120Z] Nothing to be done for teardown. [2025-06-18T23:50:05.120Z] renaissance-log-regression_0 Finish Time: Wed Jun 18 23:50:04 2025 Epoch Time (ms): 1750290604928