[2026-02-05T22:17:16.708Z] Running test renaissance-als_0 ... [2026-02-05T22:17:16.708Z] =============================================== [2026-02-05T22:17:16.708Z] renaissance-als_0 Start Time: Thu Feb 5 22:17:16 2026 Epoch Time (ms): 1770329836483 [2026-02-05T22:17:16.708Z] variation: NoOptions [2026-02-05T22:17:16.708Z] JVM_OPTIONS: [2026-02-05T22:17:16.708Z] { \ [2026-02-05T22:17:16.708Z] echo ""; echo "TEST SETUP:"; \ [2026-02-05T22:17:16.708Z] echo "Nothing to be done for setup."; \ [2026-02-05T22:17:16.708Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_177032968229/renaissance-als_0"; \ [2026-02-05T22:17:16.708Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_177032968229/renaissance-als_0"; \ [2026-02-05T22:17:16.708Z] echo ""; echo "TESTING:"; \ [2026-02-05T22:17:16.708Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-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_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_177032968229/renaissance-als_0"/als.json" als; \ [2026-02-05T22:17:16.708Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_177032968229/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-05T22:17:16.708Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-05T22:17:16.708Z] echo "Nothing to be done for teardown."; \ [2026-02-05T22:17:16.708Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_177032968229/TestTargetResult"; [2026-02-05T22:17:16.708Z] [2026-02-05T22:17:16.708Z] TEST SETUP: [2026-02-05T22:17:16.708Z] Nothing to be done for setup. [2026-02-05T22:17:16.708Z] [2026-02-05T22:17:16.708Z] TESTING: [2026-02-05T22:17:17.932Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-05T22:17:17.932Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_177032968229/renaissance-als_0/launcher-221716-7173142416779417240/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-05T22:17:17.932Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-05T22:17:17.932Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-05T22:17:24.794Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-05T22:17:30.973Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-05T22:17:30.973Z] GC before operation: completed in 58.699 ms, heap usage 73.500 MB -> 35.105 MB. [2026-02-05T22:17:52.892Z] ====== als (apache-spark) [default], iteration 0 completed (19855.574 ms) ====== [2026-02-05T22:17:52.892Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-05T22:17:52.892Z] GC before operation: completed in 109.171 ms, heap usage 291.407 MB -> 61.738 MB. [2026-02-05T22:18:02.021Z] ====== als (apache-spark) [default], iteration 1 completed (7937.879 ms) ====== [2026-02-05T22:18:02.021Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-05T22:18:02.021Z] GC before operation: completed in 113.569 ms, heap usage 165.480 MB -> 62.491 MB. [2026-02-05T22:18:07.498Z] ====== als (apache-spark) [default], iteration 2 completed (7008.528 ms) ====== [2026-02-05T22:18:07.498Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-05T22:18:07.498Z] GC before operation: completed in 111.177 ms, heap usage 385.815 MB -> 63.362 MB. [2026-02-05T22:18:15.791Z] ====== als (apache-spark) [default], iteration 3 completed (6880.620 ms) ====== [2026-02-05T22:18:15.791Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-05T22:18:15.791Z] GC before operation: completed in 124.487 ms, heap usage 205.938 MB -> 63.775 MB. [2026-02-05T22:18:22.630Z] ====== als (apache-spark) [default], iteration 4 completed (7068.658 ms) ====== [2026-02-05T22:18:22.630Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-05T22:18:22.630Z] GC before operation: completed in 127.906 ms, heap usage 481.359 MB -> 64.700 MB. [2026-02-05T22:18:29.452Z] ====== als (apache-spark) [default], iteration 5 completed (6699.652 ms) ====== [2026-02-05T22:18:29.452Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-05T22:18:29.452Z] GC before operation: completed in 121.067 ms, heap usage 288.145 MB -> 64.947 MB. [2026-02-05T22:18:36.277Z] ====== als (apache-spark) [default], iteration 6 completed (6606.111 ms) ====== [2026-02-05T22:18:36.277Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-05T22:18:36.277Z] GC before operation: completed in 123.138 ms, heap usage 284.978 MB -> 65.520 MB. [2026-02-05T22:18:43.108Z] ====== als (apache-spark) [default], iteration 7 completed (6436.209 ms) ====== [2026-02-05T22:18:43.108Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-05T22:18:43.108Z] GC before operation: completed in 140.827 ms, heap usage 96.816 MB -> 65.465 MB. [2026-02-05T22:18:49.926Z] ====== als (apache-spark) [default], iteration 8 completed (6474.955 ms) ====== [2026-02-05T22:18:49.926Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-05T22:18:49.926Z] GC before operation: completed in 130.686 ms, heap usage 457.162 MB -> 66.920 MB. [2026-02-05T22:18:56.765Z] ====== als (apache-spark) [default], iteration 9 completed (6721.389 ms) ====== [2026-02-05T22:18:56.765Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-05T22:18:56.765Z] GC before operation: completed in 132.830 ms, heap usage 493.899 MB -> 67.107 MB. [2026-02-05T22:19:04.316Z] ====== als (apache-spark) [default], iteration 10 completed (6520.381 ms) ====== [2026-02-05T22:19:04.316Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-05T22:19:04.316Z] GC before operation: completed in 136.088 ms, heap usage 201.807 MB -> 66.889 MB. [2026-02-05T22:19:09.800Z] ====== als (apache-spark) [default], iteration 11 completed (5970.802 ms) ====== [2026-02-05T22:19:09.800Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-05T22:19:09.800Z] GC before operation: completed in 128.397 ms, heap usage 208.249 MB -> 67.295 MB. [2026-02-05T22:19:16.625Z] ====== als (apache-spark) [default], iteration 12 completed (5979.471 ms) ====== [2026-02-05T22:19:16.625Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-05T22:19:16.625Z] GC before operation: completed in 131.851 ms, heap usage 287.371 MB -> 67.625 MB. [2026-02-05T22:19:23.442Z] ====== als (apache-spark) [default], iteration 13 completed (5989.260 ms) ====== [2026-02-05T22:19:23.442Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-05T22:19:23.442Z] GC before operation: completed in 146.346 ms, heap usage 315.093 MB -> 67.971 MB. [2026-02-05T22:19:28.915Z] ====== als (apache-spark) [default], iteration 14 completed (5858.595 ms) ====== [2026-02-05T22:19:28.915Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-05T22:19:28.915Z] GC before operation: completed in 146.795 ms, heap usage 347.736 MB -> 68.178 MB. [2026-02-05T22:19:35.714Z] ====== als (apache-spark) [default], iteration 15 completed (5937.345 ms) ====== [2026-02-05T22:19:35.714Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-05T22:19:35.714Z] GC before operation: completed in 136.640 ms, heap usage 502.235 MB -> 68.649 MB. [2026-02-05T22:19:41.181Z] ====== als (apache-spark) [default], iteration 16 completed (5889.881 ms) ====== [2026-02-05T22:19:41.181Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-05T22:19:41.181Z] GC before operation: completed in 140.621 ms, heap usage 492.380 MB -> 68.882 MB. [2026-02-05T22:19:48.161Z] ====== als (apache-spark) [default], iteration 17 completed (5820.928 ms) ====== [2026-02-05T22:19:48.161Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-05T22:19:48.161Z] GC before operation: completed in 141.433 ms, heap usage 99.264 MB -> 69.194 MB. [2026-02-05T22:19:53.662Z] ====== als (apache-spark) [default], iteration 18 completed (5712.573 ms) ====== [2026-02-05T22:19:53.662Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-05T22:19:53.662Z] GC before operation: completed in 132.951 ms, heap usage 149.623 MB -> 68.899 MB. [2026-02-05T22:20:00.472Z] ====== als (apache-spark) [default], iteration 19 completed (5878.944 ms) ====== [2026-02-05T22:20:00.472Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-05T22:20:00.472Z] GC before operation: completed in 147.468 ms, heap usage 448.236 MB -> 69.693 MB. [2026-02-05T22:20:05.941Z] ====== als (apache-spark) [default], iteration 20 completed (5938.607 ms) ====== [2026-02-05T22:20:05.941Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-05T22:20:05.941Z] GC before operation: completed in 136.690 ms, heap usage 285.535 MB -> 69.624 MB. [2026-02-05T22:20:12.749Z] ====== als (apache-spark) [default], iteration 21 completed (5764.291 ms) ====== [2026-02-05T22:20:12.749Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-05T22:20:12.749Z] GC before operation: completed in 144.451 ms, heap usage 560.213 MB -> 70.304 MB. [2026-02-05T22:20:18.214Z] ====== als (apache-spark) [default], iteration 22 completed (5951.456 ms) ====== [2026-02-05T22:20:18.214Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-05T22:20:18.214Z] GC before operation: completed in 144.710 ms, heap usage 513.590 MB -> 70.473 MB. [2026-02-05T22:20:25.037Z] ====== als (apache-spark) [default], iteration 23 completed (5815.191 ms) ====== [2026-02-05T22:20:25.037Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-05T22:20:25.037Z] GC before operation: completed in 152.419 ms, heap usage 450.360 MB -> 70.749 MB. [2026-02-05T22:20:30.514Z] ====== als (apache-spark) [default], iteration 24 completed (6392.581 ms) ====== [2026-02-05T22:20:30.514Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-05T22:20:31.484Z] GC before operation: completed in 156.904 ms, heap usage 209.537 MB -> 70.557 MB. [2026-02-05T22:20:36.954Z] ====== als (apache-spark) [default], iteration 25 completed (5688.311 ms) ====== [2026-02-05T22:20:36.954Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-05T22:20:36.954Z] GC before operation: completed in 143.360 ms, heap usage 279.925 MB -> 70.913 MB. [2026-02-05T22:20:43.335Z] ====== als (apache-spark) [default], iteration 26 completed (5796.804 ms) ====== [2026-02-05T22:20:43.335Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-05T22:20:43.335Z] GC before operation: completed in 145.697 ms, heap usage 377.475 MB -> 71.264 MB. [2026-02-05T22:20:48.807Z] ====== als (apache-spark) [default], iteration 27 completed (5572.506 ms) ====== [2026-02-05T22:20:48.807Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-05T22:20:48.807Z] GC before operation: completed in 158.274 ms, heap usage 211.263 MB -> 71.433 MB. [2026-02-05T22:20:55.615Z] ====== als (apache-spark) [default], iteration 28 completed (5963.346 ms) ====== [2026-02-05T22:20:55.615Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-05T22:20:55.615Z] GC before operation: completed in 173.903 ms, heap usage 353.165 MB -> 71.775 MB. [2026-02-05T22:21:01.264Z] ====== als (apache-spark) [default], iteration 29 completed (5618.329 ms) ====== [2026-02-05T22:21:02.234Z] ----------------------------------- [2026-02-05T22:21:02.234Z] renaissance-als_0_PASSED [2026-02-05T22:21:02.234Z] ----------------------------------- [2026-02-05T22:21:02.234Z] [2026-02-05T22:21:02.234Z] TEST TEARDOWN: [2026-02-05T22:21:02.234Z] Nothing to be done for teardown. [2026-02-05T22:21:02.234Z] renaissance-als_0 Finish Time: Thu Feb 5 22:21:01 2026 Epoch Time (ms): 1770330061288