[2025-08-25T22:00:15.553Z] Running test renaissance-log-regression_0 ... [2025-08-25T22:00:15.553Z] =============================================== [2025-08-25T22:00:15.553Z] renaissance-log-regression_0 Start Time: Mon Aug 25 22:00:15 2025 Epoch Time (ms): 1756159215203 [2025-08-25T22:00:15.553Z] variation: NoOptions [2025-08-25T22:00:15.553Z] JVM_OPTIONS: [2025-08-25T22:00:15.553Z] { \ [2025-08-25T22:00:15.553Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T22:00:15.553Z] echo "Nothing to be done for setup."; \ [2025-08-25T22:00:15.553Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-log-regression_0"; \ [2025-08-25T22:00:15.553Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-log-regression_0"; \ [2025-08-25T22:00:15.553Z] echo ""; echo "TESTING:"; \ [2025-08-25T22:00:15.553Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-25T22:00:15.553Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T22:00:15.553Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T22:00:15.553Z] echo "Nothing to be done for teardown."; \ [2025-08-25T22:00:15.553Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/TestTargetResult"; [2025-08-25T22:00:15.553Z] [2025-08-25T22:00:15.553Z] TEST SETUP: [2025-08-25T22:00:15.553Z] Nothing to be done for setup. [2025-08-25T22:00:15.553Z] [2025-08-25T22:00:15.553Z] TESTING: [2025-08-25T22:00:25.321Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-25T22:00:26.268Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T22:00:26.268Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17561569457495/renaissance-log-regression_0/harness-220016-14675539137756786855/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T22:00:26.268Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T22:00:26.268Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T22:00:26.268Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T22:00:28.207Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-25T22:00:28.207Z] GC before operation: completed in 92.480 ms, heap usage 102.705 MB -> 34.595 MB. [2025-08-25T22:00:49.390Z] ====== log-regression (apache-spark) [default], iteration 0 completed (19761.056 ms) ====== [2025-08-25T22:00:49.390Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-25T22:00:49.390Z] GC before operation: completed in 181.425 ms, heap usage 215.689 MB -> 118.435 MB. [2025-08-25T22:00:53.540Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4327.052 ms) ====== [2025-08-25T22:00:53.540Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-25T22:00:53.540Z] GC before operation: completed in 140.760 ms, heap usage 262.066 MB -> 119.101 MB. [2025-08-25T22:00:56.558Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3494.331 ms) ====== [2025-08-25T22:00:56.558Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-25T22:00:56.558Z] GC before operation: completed in 173.280 ms, heap usage 272.525 MB -> 119.502 MB. [2025-08-25T22:00:59.569Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3141.687 ms) ====== [2025-08-25T22:00:59.569Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-25T22:01:00.538Z] GC before operation: completed in 151.864 ms, heap usage 281.156 MB -> 119.749 MB. [2025-08-25T22:01:02.492Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2803.740 ms) ====== [2025-08-25T22:01:02.492Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-25T22:01:03.439Z] GC before operation: completed in 146.700 ms, heap usage 253.777 MB -> 119.879 MB. [2025-08-25T22:01:05.394Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2638.780 ms) ====== [2025-08-25T22:01:05.394Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-25T22:01:05.394Z] GC before operation: completed in 155.727 ms, heap usage 282.598 MB -> 120.160 MB. [2025-08-25T22:01:08.394Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2591.745 ms) ====== [2025-08-25T22:01:08.394Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-25T22:01:08.394Z] GC before operation: completed in 159.926 ms, heap usage 290.085 MB -> 120.443 MB. [2025-08-25T22:01:11.033Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2461.080 ms) ====== [2025-08-25T22:01:11.033Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-25T22:01:11.033Z] GC before operation: completed in 140.865 ms, heap usage 380.888 MB -> 120.894 MB. [2025-08-25T22:01:14.045Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2448.389 ms) ====== [2025-08-25T22:01:14.045Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-25T22:01:14.045Z] GC before operation: completed in 151.890 ms, heap usage 336.768 MB -> 120.920 MB. [2025-08-25T22:01:16.000Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2559.141 ms) ====== [2025-08-25T22:01:16.000Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-25T22:01:16.949Z] GC before operation: completed in 164.531 ms, heap usage 272.050 MB -> 121.190 MB. [2025-08-25T22:01:18.907Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2505.019 ms) ====== [2025-08-25T22:01:18.907Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-25T22:01:18.907Z] GC before operation: completed in 140.026 ms, heap usage 298.498 MB -> 121.251 MB. [2025-08-25T22:01:21.914Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2396.623 ms) ====== [2025-08-25T22:01:21.914Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-25T22:01:21.914Z] GC before operation: completed in 151.120 ms, heap usage 298.876 MB -> 121.453 MB. [2025-08-25T22:01:23.861Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2230.560 ms) ====== [2025-08-25T22:01:23.861Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-25T22:01:23.861Z] GC before operation: completed in 173.288 ms, heap usage 343.078 MB -> 121.800 MB. [2025-08-25T22:01:26.975Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2250.188 ms) ====== [2025-08-25T22:01:26.975Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-25T22:01:26.975Z] GC before operation: completed in 174.281 ms, heap usage 272.582 MB -> 121.887 MB. [2025-08-25T22:01:28.923Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2267.490 ms) ====== [2025-08-25T22:01:28.923Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-25T22:01:28.923Z] GC before operation: completed in 177.054 ms, heap usage 422.267 MB -> 122.446 MB. [2025-08-25T22:01:30.884Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2373.822 ms) ====== [2025-08-25T22:01:30.884Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-25T22:01:31.830Z] GC before operation: completed in 171.491 ms, heap usage 318.150 MB -> 122.353 MB. [2025-08-25T22:01:33.776Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2397.670 ms) ====== [2025-08-25T22:01:33.776Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-25T22:01:33.776Z] GC before operation: completed in 148.646 ms, heap usage 336.605 MB -> 122.544 MB. [2025-08-25T22:01:36.783Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2572.197 ms) ====== [2025-08-25T22:01:36.783Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-25T22:01:36.783Z] GC before operation: completed in 155.622 ms, heap usage 254.905 MB -> 122.463 MB. [2025-08-25T22:01:38.730Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2298.552 ms) ====== [2025-08-25T22:01:38.730Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-25T22:01:39.678Z] GC before operation: completed in 163.087 ms, heap usage 320.070 MB -> 122.908 MB. [2025-08-25T22:01:41.622Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2521.073 ms) ====== [2025-08-25T22:01:42.567Z] ----------------------------------- [2025-08-25T22:01:42.567Z] renaissance-log-regression_0_PASSED [2025-08-25T22:01:42.567Z] ----------------------------------- [2025-08-25T22:01:42.567Z] [2025-08-25T22:01:42.567Z] TEST TEARDOWN: [2025-08-25T22:01:42.567Z] Nothing to be done for teardown. [2025-08-25T22:01:42.567Z] renaissance-log-regression_0 Finish Time: Mon Aug 25 22:01:41 2025 Epoch Time (ms): 1756159301860