[2026-02-12T01:10:40.742Z] Running test renaissance-als_0 ... [2026-02-12T01:10:40.742Z] =============================================== [2026-02-12T01:10:40.742Z] renaissance-als_0 Start Time: Thu Feb 12 01:10:39 2026 Epoch Time (ms): 1770858639747 [2026-02-12T01:10:40.742Z] variation: NoOptions [2026-02-12T01:10:40.742Z] JVM_OPTIONS: [2026-02-12T01:10:40.742Z] { \ [2026-02-12T01:10:40.742Z] echo ""; echo "TEST SETUP:"; \ [2026-02-12T01:10:40.742Z] echo "Nothing to be done for setup."; \ [2026-02-12T01:10:40.742Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17708584592104/renaissance-als_0"; \ [2026-02-12T01:10:40.742Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17708584592104/renaissance-als_0"; \ [2026-02-12T01:10:40.742Z] echo ""; echo "TESTING:"; \ [2026-02-12T01:10:40.742Z] "/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_17708584592104/renaissance-als_0"/als.json" als; \ [2026-02-12T01:10:40.742Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_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_17708584592104/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-12T01:10:40.742Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-12T01:10:40.742Z] echo "Nothing to be done for teardown."; \ [2026-02-12T01:10:40.742Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17708584592104/TestTargetResult"; [2026-02-12T01:10:40.742Z] [2026-02-12T01:10:40.742Z] TEST SETUP: [2026-02-12T01:10:40.742Z] Nothing to be done for setup. [2026-02-12T01:10:40.742Z] [2026-02-12T01:10:40.742Z] TESTING: [2026-02-12T01:10:46.144Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-12T01:10:50.335Z] WARNING: An illegal reflective access operation has occurred [2026-02-12T01:10:50.336Z] 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_17708584592104/renaissance-als_0/harness-011040-13824974772161909109/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-12T01:10:50.336Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-12T01:10:50.336Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-12T01:10:50.336Z] WARNING: All illegal access operations will be denied in a future release [2026-02-12T01:10:51.295Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-12T01:10:51.295Z] GC before operation: completed in 60.371 ms, heap usage 252.000 MB -> 34.441 MB. [2026-02-12T01:11:12.647Z] ====== als (apache-spark) [default], iteration 0 completed (19635.172 ms) ====== [2026-02-12T01:11:12.647Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-12T01:11:12.647Z] GC before operation: completed in 113.003 ms, heap usage 411.855 MB -> 60.698 MB. [2026-02-12T01:11:20.859Z] ====== als (apache-spark) [default], iteration 1 completed (9103.821 ms) ====== [2026-02-12T01:11:20.859Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-12T01:11:20.859Z] GC before operation: completed in 123.781 ms, heap usage 383.259 MB -> 61.369 MB. [2026-02-12T01:11:31.551Z] ====== als (apache-spark) [default], iteration 2 completed (8404.479 ms) ====== [2026-02-12T01:11:31.551Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-12T01:11:31.551Z] GC before operation: completed in 107.442 ms, heap usage 503.822 MB -> 62.146 MB. [2026-02-12T01:11:39.048Z] ====== als (apache-spark) [default], iteration 3 completed (8248.111 ms) ====== [2026-02-12T01:11:39.048Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-12T01:11:39.048Z] GC before operation: completed in 109.799 ms, heap usage 315.166 MB -> 62.462 MB. [2026-02-12T01:11:47.273Z] ====== als (apache-spark) [default], iteration 4 completed (7873.958 ms) ====== [2026-02-12T01:11:47.274Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-12T01:11:47.274Z] GC before operation: completed in 113.284 ms, heap usage 238.316 MB -> 63.082 MB. [2026-02-12T01:11:55.474Z] ====== als (apache-spark) [default], iteration 5 completed (7961.385 ms) ====== [2026-02-12T01:11:55.474Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-12T01:11:55.474Z] GC before operation: completed in 117.350 ms, heap usage 158.621 MB -> 63.285 MB. [2026-02-12T01:12:03.673Z] ====== als (apache-spark) [default], iteration 6 completed (8133.977 ms) ====== [2026-02-12T01:12:03.673Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-12T01:12:03.673Z] GC before operation: completed in 122.592 ms, heap usage 119.499 MB -> 63.815 MB. [2026-02-12T01:12:11.935Z] ====== als (apache-spark) [default], iteration 7 completed (8395.115 ms) ====== [2026-02-12T01:12:11.935Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-12T01:12:12.896Z] GC before operation: completed in 109.426 ms, heap usage 273.371 MB -> 64.459 MB. [2026-02-12T01:12:21.088Z] ====== als (apache-spark) [default], iteration 8 completed (7980.043 ms) ====== [2026-02-12T01:12:21.088Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-12T01:12:21.088Z] GC before operation: completed in 109.861 ms, heap usage 112.766 MB -> 65.209 MB. [2026-02-12T01:12:29.293Z] ====== als (apache-spark) [default], iteration 9 completed (8122.902 ms) ====== [2026-02-12T01:12:29.294Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-12T01:12:29.294Z] GC before operation: completed in 127.147 ms, heap usage 272.838 MB -> 65.409 MB. [2026-02-12T01:12:39.085Z] ====== als (apache-spark) [default], iteration 10 completed (8392.547 ms) ====== [2026-02-12T01:12:39.085Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-12T01:12:39.085Z] GC before operation: completed in 121.366 ms, heap usage 110.766 MB -> 65.384 MB. [2026-02-12T01:12:46.001Z] ====== als (apache-spark) [default], iteration 11 completed (7909.986 ms) ====== [2026-02-12T01:12:46.001Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-12T01:12:46.001Z] GC before operation: completed in 116.018 ms, heap usage 183.044 MB -> 65.647 MB. [2026-02-12T01:12:55.801Z] ====== als (apache-spark) [default], iteration 12 completed (8066.785 ms) ====== [2026-02-12T01:12:55.801Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-12T01:12:55.801Z] GC before operation: completed in 114.083 ms, heap usage 146.024 MB -> 65.895 MB. [2026-02-12T01:13:03.996Z] ====== als (apache-spark) [default], iteration 13 completed (8161.818 ms) ====== [2026-02-12T01:13:03.996Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-12T01:13:03.996Z] GC before operation: completed in 113.323 ms, heap usage 98.373 MB -> 66.110 MB. [2026-02-12T01:13:11.645Z] ====== als (apache-spark) [default], iteration 14 completed (7948.316 ms) ====== [2026-02-12T01:13:11.645Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-12T01:13:11.645Z] GC before operation: completed in 118.025 ms, heap usage 188.477 MB -> 66.354 MB. [2026-02-12T01:13:19.841Z] ====== als (apache-spark) [default], iteration 15 completed (7850.329 ms) ====== [2026-02-12T01:13:19.841Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-12T01:13:19.841Z] GC before operation: completed in 113.622 ms, heap usage 128.661 MB -> 66.617 MB. [2026-02-12T01:13:28.031Z] ====== als (apache-spark) [default], iteration 16 completed (8072.989 ms) ====== [2026-02-12T01:13:28.031Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-12T01:13:28.031Z] GC before operation: completed in 114.072 ms, heap usage 281.376 MB -> 66.980 MB. [2026-02-12T01:13:36.240Z] ====== als (apache-spark) [default], iteration 17 completed (8120.754 ms) ====== [2026-02-12T01:13:36.240Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-12T01:13:37.269Z] GC before operation: completed in 114.408 ms, heap usage 147.446 MB -> 67.339 MB. [2026-02-12T01:13:45.468Z] ====== als (apache-spark) [default], iteration 18 completed (8247.963 ms) ====== [2026-02-12T01:13:45.468Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-12T01:13:45.468Z] GC before operation: completed in 117.442 ms, heap usage 226.139 MB -> 67.386 MB. [2026-02-12T01:13:53.655Z] ====== als (apache-spark) [default], iteration 19 completed (7722.780 ms) ====== [2026-02-12T01:13:53.655Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-12T01:13:53.655Z] GC before operation: completed in 125.934 ms, heap usage 285.902 MB -> 67.754 MB. [2026-02-12T01:14:01.848Z] ====== als (apache-spark) [default], iteration 20 completed (8004.770 ms) ====== [2026-02-12T01:14:01.848Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-12T01:14:01.848Z] GC before operation: completed in 114.619 ms, heap usage 116.755 MB -> 67.880 MB. [2026-02-12T01:14:10.036Z] ====== als (apache-spark) [default], iteration 21 completed (8058.479 ms) ====== [2026-02-12T01:14:10.036Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-12T01:14:10.036Z] GC before operation: completed in 114.909 ms, heap usage 220.337 MB -> 68.240 MB. [2026-02-12T01:14:18.228Z] ====== als (apache-spark) [default], iteration 22 completed (8131.709 ms) ====== [2026-02-12T01:14:18.228Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-12T01:14:19.187Z] GC before operation: completed in 119.492 ms, heap usage 320.189 MB -> 68.622 MB. [2026-02-12T01:14:27.387Z] ====== als (apache-spark) [default], iteration 23 completed (7737.920 ms) ====== [2026-02-12T01:14:27.387Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-12T01:14:27.387Z] GC before operation: completed in 120.011 ms, heap usage 274.507 MB -> 68.797 MB. [2026-02-12T01:14:35.576Z] ====== als (apache-spark) [default], iteration 24 completed (7745.991 ms) ====== [2026-02-12T01:14:35.576Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-12T01:14:35.576Z] GC before operation: completed in 124.322 ms, heap usage 280.296 MB -> 69.019 MB. [2026-02-12T01:14:43.767Z] ====== als (apache-spark) [default], iteration 25 completed (8070.899 ms) ====== [2026-02-12T01:14:43.767Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-12T01:14:43.767Z] GC before operation: completed in 118.439 ms, heap usage 228.497 MB -> 69.264 MB. [2026-02-12T01:14:52.113Z] ====== als (apache-spark) [default], iteration 26 completed (7791.062 ms) ====== [2026-02-12T01:14:52.113Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-12T01:14:52.113Z] GC before operation: completed in 122.282 ms, heap usage 106.935 MB -> 69.393 MB. [2026-02-12T01:15:00.307Z] ====== als (apache-spark) [default], iteration 27 completed (7911.518 ms) ====== [2026-02-12T01:15:00.307Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-12T01:15:00.307Z] GC before operation: completed in 118.779 ms, heap usage 120.914 MB -> 69.659 MB. [2026-02-12T01:15:08.499Z] ====== als (apache-spark) [default], iteration 28 completed (8062.094 ms) ====== [2026-02-12T01:15:08.499Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-12T01:15:08.499Z] GC before operation: completed in 113.221 ms, heap usage 220.930 MB -> 69.558 MB. [2026-02-12T01:15:16.695Z] ====== als (apache-spark) [default], iteration 29 completed (7841.104 ms) ====== [2026-02-12T01:15:16.695Z] ----------------------------------- [2026-02-12T01:15:16.695Z] renaissance-als_0_PASSED [2026-02-12T01:15:16.695Z] ----------------------------------- [2026-02-12T01:15:16.695Z] [2026-02-12T01:15:16.695Z] TEST TEARDOWN: [2026-02-12T01:15:16.695Z] Nothing to be done for teardown. [2026-02-12T01:15:16.695Z] renaissance-als_0 Finish Time: Thu Feb 12 01:15:16 2026 Epoch Time (ms): 1770858916185