[2025-08-25T21:54:04.188Z] Running test renaissance-log-regression_0 ... [2025-08-25T21:54:04.188Z] =============================================== [2025-08-25T21:54:04.188Z] renaissance-log-regression_0 Start Time: Mon Aug 25 21:54:03 2025 Epoch Time (ms): 1756158843713 [2025-08-25T21:54:04.188Z] variation: NoOptions [2025-08-25T21:54:04.188Z] JVM_OPTIONS: [2025-08-25T21:54:04.188Z] { \ [2025-08-25T21:54:04.188Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T21:54:04.188Z] echo "Nothing to be done for setup."; \ [2025-08-25T21:54:04.188Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-log-regression_0"; \ [2025-08-25T21:54:04.189Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-log-regression_0"; \ [2025-08-25T21:54:04.189Z] echo ""; echo "TESTING:"; \ [2025-08-25T21:54:04.189Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-25T21:54:04.189Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T21:54:04.189Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T21:54:04.189Z] echo "Nothing to be done for teardown."; \ [2025-08-25T21:54:04.189Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/TestTargetResult"; [2025-08-25T21:54:04.189Z] [2025-08-25T21:54:04.189Z] TEST SETUP: [2025-08-25T21:54:04.189Z] Nothing to be done for setup. [2025-08-25T21:54:04.189Z] [2025-08-25T21:54:04.189Z] TESTING: [2025-08-25T21:54:12.520Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-08-25T21:54:15.049Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T21:54:15.049Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17561573814621/renaissance-log-regression_0/harness-215404-12863612630729863276/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T21:54:15.049Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T21:54:15.049Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T21:54:15.049Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T21:54:16.665Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-25T21:54:16.665Z] GC before operation: completed in 83.976 ms, heap usage 91.662 MB -> 35.255 MB. [2025-08-25T21:54:32.857Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14599.727 ms) ====== [2025-08-25T21:54:32.857Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-25T21:54:32.857Z] GC before operation: completed in 149.928 ms, heap usage 1.058 GB -> 133.446 MB. [2025-08-25T21:54:34.465Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3098.277 ms) ====== [2025-08-25T21:54:34.465Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-25T21:54:35.245Z] GC before operation: completed in 113.904 ms, heap usage 411.376 MB -> 132.567 MB. [2025-08-25T21:54:37.745Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2793.193 ms) ====== [2025-08-25T21:54:37.745Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-25T21:54:37.745Z] GC before operation: completed in 114.982 ms, heap usage 240.289 MB -> 132.561 MB. [2025-08-25T21:54:40.235Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2542.867 ms) ====== [2025-08-25T21:54:40.235Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-25T21:54:40.235Z] GC before operation: completed in 126.034 ms, heap usage 342.453 MB -> 133.206 MB. [2025-08-25T21:54:43.038Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2434.527 ms) ====== [2025-08-25T21:54:43.038Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-25T21:54:43.038Z] GC before operation: completed in 136.660 ms, heap usage 250.798 MB -> 133.400 MB. [2025-08-25T21:54:45.744Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2458.663 ms) ====== [2025-08-25T21:54:45.744Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-25T21:54:45.744Z] GC before operation: completed in 155.349 ms, heap usage 599.561 MB -> 134.680 MB. [2025-08-25T21:54:48.233Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2352.531 ms) ====== [2025-08-25T21:54:48.233Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-25T21:54:48.233Z] GC before operation: completed in 130.067 ms, heap usage 562.853 MB -> 134.979 MB. [2025-08-25T21:54:50.716Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2236.311 ms) ====== [2025-08-25T21:54:50.716Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-25T21:54:50.716Z] GC before operation: completed in 166.234 ms, heap usage 591.810 MB -> 135.454 MB. [2025-08-25T21:54:53.204Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2329.929 ms) ====== [2025-08-25T21:54:53.204Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-25T21:54:53.205Z] GC before operation: completed in 175.622 ms, heap usage 607.137 MB -> 135.796 MB. [2025-08-25T21:54:55.693Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2358.006 ms) ====== [2025-08-25T21:54:55.693Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-25T21:54:55.693Z] GC before operation: completed in 189.322 ms, heap usage 818.985 MB -> 136.804 MB. [2025-08-25T21:54:58.183Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2144.941 ms) ====== [2025-08-25T21:54:58.183Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-25T21:54:58.183Z] GC before operation: completed in 162.962 ms, heap usage 835.051 MB -> 137.267 MB. [2025-08-25T21:55:00.677Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2231.365 ms) ====== [2025-08-25T21:55:00.678Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-25T21:55:00.678Z] GC before operation: completed in 137.599 ms, heap usage 281.451 MB -> 136.097 MB. [2025-08-25T21:55:02.279Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2211.085 ms) ====== [2025-08-25T21:55:02.279Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-25T21:55:03.055Z] GC before operation: completed in 146.728 ms, heap usage 228.451 MB -> 136.345 MB. [2025-08-25T21:55:04.657Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2026.546 ms) ====== [2025-08-25T21:55:04.657Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-25T21:55:04.657Z] GC before operation: completed in 189.938 ms, heap usage 755.417 MB -> 138.026 MB. [2025-08-25T21:55:07.147Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2188.562 ms) ====== [2025-08-25T21:55:07.147Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-25T21:55:07.147Z] GC before operation: completed in 163.616 ms, heap usage 753.304 MB -> 138.411 MB. [2025-08-25T21:55:09.639Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2136.432 ms) ====== [2025-08-25T21:55:09.639Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-25T21:55:09.639Z] GC before operation: completed in 163.325 ms, heap usage 910.386 MB -> 139.355 MB. [2025-08-25T21:55:12.122Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2114.802 ms) ====== [2025-08-25T21:55:12.122Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-25T21:55:12.122Z] GC before operation: completed in 186.292 ms, heap usage 707.358 MB -> 139.169 MB. [2025-08-25T21:55:13.724Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2158.168 ms) ====== [2025-08-25T21:55:13.724Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-25T21:55:14.514Z] GC before operation: completed in 150.005 ms, heap usage 688.860 MB -> 139.378 MB. [2025-08-25T21:55:16.117Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2107.734 ms) ====== [2025-08-25T21:55:16.117Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-25T21:55:16.117Z] GC before operation: completed in 158.906 ms, heap usage 771.833 MB -> 140.107 MB. [2025-08-25T21:55:18.608Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2066.294 ms) ====== [2025-08-25T21:55:19.387Z] ----------------------------------- [2025-08-25T21:55:19.387Z] renaissance-log-regression_0_PASSED [2025-08-25T21:55:19.387Z] ----------------------------------- [2025-08-25T21:55:19.387Z] [2025-08-25T21:55:19.387Z] TEST TEARDOWN: [2025-08-25T21:55:19.387Z] Nothing to be done for teardown. [2025-08-25T21:55:19.387Z] renaissance-log-regression_0 Finish Time: Mon Aug 25 21:55:18 2025 Epoch Time (ms): 1756158918605