[2026-02-20T22:15:32.903Z] Running test renaissance-als_0 ... [2026-02-20T22:15:32.903Z] =============================================== [2026-02-20T22:15:32.903Z] renaissance-als_0 Start Time: Fri Feb 20 22:15:32 2026 Epoch Time (ms): 1771625732459 [2026-02-20T22:15:32.903Z] variation: NoOptions [2026-02-20T22:15:32.903Z] JVM_OPTIONS: [2026-02-20T22:15:32.903Z] { \ [2026-02-20T22:15:32.903Z] echo ""; echo "TEST SETUP:"; \ [2026-02-20T22:15:32.903Z] echo "Nothing to be done for setup."; \ [2026-02-20T22:15:32.903Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17716256165381/renaissance-als_0"; \ [2026-02-20T22:15:32.903Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17716256165381/renaissance-als_0"; \ [2026-02-20T22:15:32.903Z] echo ""; echo "TESTING:"; \ [2026-02-20T22:15:32.903Z] "/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_17716256165381/renaissance-als_0"/als.json" als; \ [2026-02-20T22:15:32.903Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_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_17716256165381/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-20T22:15:32.903Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-20T22:15:32.903Z] echo "Nothing to be done for teardown."; \ [2026-02-20T22:15:32.903Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17716256165381/TestTargetResult"; [2026-02-20T22:15:32.903Z] [2026-02-20T22:15:32.903Z] TEST SETUP: [2026-02-20T22:15:32.903Z] Nothing to be done for setup. [2026-02-20T22:15:32.903Z] [2026-02-20T22:15:32.903Z] TESTING: [2026-02-20T22:15:42.857Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2026-02-20T22:15:46.290Z] WARNING: An illegal reflective access operation has occurred [2026-02-20T22:15:46.290Z] 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_17716256165381/renaissance-als_0/harness-221533-17672912699061454339/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-20T22:15:46.290Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-20T22:15:46.290Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-20T22:15:46.290Z] WARNING: All illegal access operations will be denied in a future release [2026-02-20T22:15:47.889Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-20T22:15:47.889Z] GC before operation: completed in 81.628 ms, heap usage 259.714 MB -> 35.385 MB. [2026-02-20T22:16:04.580Z] ====== als (apache-spark) [default], iteration 0 completed (15848.495 ms) ====== [2026-02-20T22:16:04.580Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-20T22:16:04.580Z] GC before operation: completed in 124.986 ms, heap usage 502.985 MB -> 61.661 MB. [2026-02-20T22:16:11.618Z] ====== als (apache-spark) [default], iteration 1 completed (6262.631 ms) ====== [2026-02-20T22:16:11.618Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-20T22:16:11.618Z] GC before operation: completed in 138.722 ms, heap usage 839.069 MB -> 63.071 MB. [2026-02-20T22:16:17.304Z] ====== als (apache-spark) [default], iteration 2 completed (5750.291 ms) ====== [2026-02-20T22:16:17.304Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-20T22:16:17.304Z] GC before operation: completed in 130.940 ms, heap usage 1.056 GB -> 63.676 MB. [2026-02-20T22:16:23.006Z] ====== als (apache-spark) [default], iteration 3 completed (5705.346 ms) ====== [2026-02-20T22:16:23.006Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-20T22:16:23.786Z] GC before operation: completed in 127.769 ms, heap usage 742.319 MB -> 63.935 MB. [2026-02-20T22:16:29.495Z] ====== als (apache-spark) [default], iteration 4 completed (5588.100 ms) ====== [2026-02-20T22:16:29.495Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-20T22:16:29.495Z] GC before operation: completed in 145.489 ms, heap usage 731.154 MB -> 64.450 MB. [2026-02-20T22:16:35.164Z] ====== als (apache-spark) [default], iteration 5 completed (5527.673 ms) ====== [2026-02-20T22:16:35.164Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-20T22:16:35.164Z] GC before operation: completed in 130.099 ms, heap usage 642.554 MB -> 65.091 MB. [2026-02-20T22:16:40.842Z] ====== als (apache-spark) [default], iteration 6 completed (5577.722 ms) ====== [2026-02-20T22:16:40.842Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-20T22:16:41.617Z] GC before operation: completed in 134.831 ms, heap usage 248.018 MB -> 65.114 MB. [2026-02-20T22:16:47.303Z] ====== als (apache-spark) [default], iteration 7 completed (5463.741 ms) ====== [2026-02-20T22:16:47.303Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-20T22:16:47.303Z] GC before operation: completed in 133.237 ms, heap usage 668.372 MB -> 65.980 MB. [2026-02-20T22:16:53.091Z] ====== als (apache-spark) [default], iteration 8 completed (5490.952 ms) ====== [2026-02-20T22:16:53.091Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-20T22:16:53.091Z] GC before operation: completed in 144.750 ms, heap usage 276.767 MB -> 66.039 MB. [2026-02-20T22:16:58.770Z] ====== als (apache-spark) [default], iteration 9 completed (5404.431 ms) ====== [2026-02-20T22:16:58.770Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-20T22:16:58.770Z] GC before operation: completed in 154.259 ms, heap usage 637.024 MB -> 66.898 MB. [2026-02-20T22:17:04.589Z] ====== als (apache-spark) [default], iteration 10 completed (5549.299 ms) ====== [2026-02-20T22:17:04.589Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-20T22:17:04.589Z] GC before operation: completed in 139.909 ms, heap usage 767.008 MB -> 67.153 MB. [2026-02-20T22:17:10.324Z] ====== als (apache-spark) [default], iteration 11 completed (5437.237 ms) ====== [2026-02-20T22:17:10.324Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-20T22:17:10.324Z] GC before operation: completed in 150.284 ms, heap usage 867.771 MB -> 67.460 MB. [2026-02-20T22:17:17.307Z] ====== als (apache-spark) [default], iteration 12 completed (5627.090 ms) ====== [2026-02-20T22:17:17.307Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-20T22:17:17.307Z] GC before operation: completed in 163.393 ms, heap usage 1.331 GB -> 68.231 MB. [2026-02-20T22:17:23.003Z] ====== als (apache-spark) [default], iteration 13 completed (5470.091 ms) ====== [2026-02-20T22:17:23.003Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-20T22:17:23.003Z] GC before operation: completed in 136.426 ms, heap usage 299.633 MB -> 67.370 MB. [2026-02-20T22:17:28.676Z] ====== als (apache-spark) [default], iteration 14 completed (5358.776 ms) ====== [2026-02-20T22:17:28.676Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-20T22:17:28.676Z] GC before operation: completed in 170.575 ms, heap usage 553.686 MB -> 67.780 MB. [2026-02-20T22:17:34.344Z] ====== als (apache-spark) [default], iteration 15 completed (5454.114 ms) ====== [2026-02-20T22:17:34.344Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-20T22:17:34.344Z] GC before operation: completed in 174.862 ms, heap usage 730.022 MB -> 68.368 MB. [2026-02-20T22:17:40.034Z] ====== als (apache-spark) [default], iteration 16 completed (5479.662 ms) ====== [2026-02-20T22:17:40.034Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-20T22:17:40.034Z] GC before operation: completed in 165.906 ms, heap usage 611.506 MB -> 68.532 MB. [2026-02-20T22:17:45.698Z] ====== als (apache-spark) [default], iteration 17 completed (5478.499 ms) ====== [2026-02-20T22:17:45.698Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-20T22:17:45.698Z] GC before operation: completed in 160.887 ms, heap usage 590.732 MB -> 68.696 MB. [2026-02-20T22:17:51.360Z] ====== als (apache-spark) [default], iteration 18 completed (5321.541 ms) ====== [2026-02-20T22:17:51.360Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-20T22:17:51.360Z] GC before operation: completed in 153.537 ms, heap usage 443.851 MB -> 68.715 MB. [2026-02-20T22:17:58.283Z] ====== als (apache-spark) [default], iteration 19 completed (5738.320 ms) ====== [2026-02-20T22:17:58.283Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-20T22:17:58.283Z] GC before operation: completed in 188.165 ms, heap usage 652.893 MB -> 69.471 MB. [2026-02-20T22:18:02.984Z] ====== als (apache-spark) [default], iteration 20 completed (5436.169 ms) ====== [2026-02-20T22:18:02.985Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-20T22:18:03.759Z] GC before operation: completed in 159.059 ms, heap usage 210.444 MB -> 69.202 MB. [2026-02-20T22:18:09.404Z] ====== als (apache-spark) [default], iteration 21 completed (5415.251 ms) ====== [2026-02-20T22:18:09.404Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-20T22:18:09.404Z] GC before operation: completed in 174.014 ms, heap usage 252.528 MB -> 69.381 MB. [2026-02-20T22:18:15.049Z] ====== als (apache-spark) [default], iteration 22 completed (5459.129 ms) ====== [2026-02-20T22:18:15.049Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-20T22:18:15.049Z] GC before operation: completed in 183.039 ms, heap usage 357.702 MB -> 69.739 MB. [2026-02-20T22:18:20.760Z] ====== als (apache-spark) [default], iteration 23 completed (5469.370 ms) ====== [2026-02-20T22:18:20.760Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-20T22:18:20.760Z] GC before operation: completed in 183.426 ms, heap usage 1.490 GB -> 71.181 MB. [2026-02-20T22:18:26.417Z] ====== als (apache-spark) [default], iteration 24 completed (5375.712 ms) ====== [2026-02-20T22:18:26.417Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-20T22:18:27.196Z] GC before operation: completed in 181.607 ms, heap usage 1.203 GB -> 71.246 MB. [2026-02-20T22:18:32.816Z] ====== als (apache-spark) [default], iteration 25 completed (5271.453 ms) ====== [2026-02-20T22:18:32.816Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-20T22:18:32.816Z] GC before operation: completed in 162.936 ms, heap usage 979.790 MB -> 71.135 MB. [2026-02-20T22:18:38.613Z] ====== als (apache-spark) [default], iteration 26 completed (5492.794 ms) ====== [2026-02-20T22:18:38.613Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-20T22:18:38.613Z] GC before operation: completed in 165.074 ms, heap usage 796.955 MB -> 71.334 MB. [2026-02-20T22:18:44.242Z] ====== als (apache-spark) [default], iteration 27 completed (5246.552 ms) ====== [2026-02-20T22:18:44.242Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-20T22:18:44.242Z] GC before operation: completed in 174.153 ms, heap usage 539.512 MB -> 71.323 MB. [2026-02-20T22:18:49.896Z] ====== als (apache-spark) [default], iteration 28 completed (5387.144 ms) ====== [2026-02-20T22:18:49.896Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-20T22:18:49.896Z] GC before operation: completed in 172.050 ms, heap usage 1.389 GB -> 72.330 MB. [2026-02-20T22:18:55.754Z] ====== als (apache-spark) [default], iteration 29 completed (5397.358 ms) ====== [2026-02-20T22:18:55.754Z] ----------------------------------- [2026-02-20T22:18:55.754Z] renaissance-als_0_PASSED [2026-02-20T22:18:55.754Z] ----------------------------------- [2026-02-20T22:18:55.754Z] [2026-02-20T22:18:55.754Z] TEST TEARDOWN: [2026-02-20T22:18:55.754Z] Nothing to be done for teardown. [2026-02-20T22:18:55.754Z] renaissance-als_0 Finish Time: Fri Feb 20 22:18:55 2026 Epoch Time (ms): 1771625935373