[2026-01-21T12:30:56.905Z] Running test renaissance-als_0 ... [2026-01-21T12:30:56.905Z] =============================================== [2026-01-21T12:30:56.905Z] renaissance-als_0 Start Time: Wed Jan 21 12:30:56 2026 Epoch Time (ms): 1768998656494 [2026-01-21T12:30:56.905Z] variation: NoOptions [2026-01-21T12:30:56.905Z] JVM_OPTIONS: [2026-01-21T12:30:56.905Z] { \ [2026-01-21T12:30:56.905Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T12:30:56.905Z] echo "Nothing to be done for setup."; \ [2026-01-21T12:30:56.905Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17689986563054/renaissance-als_0"; \ [2026-01-21T12:30:56.905Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17689986563054/renaissance-als_0"; \ [2026-01-21T12:30:56.905Z] echo ""; echo "TESTING:"; \ [2026-01-21T12:30:56.905Z] "/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_17689986563054/renaissance-als_0"/als.json" als; \ [2026-01-21T12:30:56.905Z] 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_17689986563054/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T12:30:56.905Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T12:30:56.905Z] echo "Nothing to be done for teardown."; \ [2026-01-21T12:30:56.905Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17689986563054/TestTargetResult"; [2026-01-21T12:30:56.905Z] [2026-01-21T12:30:56.905Z] TEST SETUP: [2026-01-21T12:30:56.905Z] Nothing to be done for setup. [2026-01-21T12:30:56.905Z] [2026-01-21T12:30:56.905Z] TESTING: [2026-01-21T12:30:58.863Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-21T12:30:58.863Z] 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_17689986563054/renaissance-als_0/launcher-123056-15613447068042118753/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-21T12:30:58.863Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-21T12:30:58.863Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-21T12:31:10.405Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-21T12:31:18.577Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T12:31:18.577Z] GC before operation: completed in 89.567 ms, heap usage 54.300 MB -> 35.122 MB. [2026-01-21T12:31:47.210Z] ====== als (apache-spark) [default], iteration 0 completed (28783.977 ms) ====== [2026-01-21T12:31:47.210Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T12:31:48.171Z] GC before operation: completed in 172.954 ms, heap usage 287.911 MB -> 61.849 MB. [2026-01-21T12:31:58.669Z] ====== als (apache-spark) [default], iteration 1 completed (9951.424 ms) ====== [2026-01-21T12:31:58.669Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T12:31:58.669Z] GC before operation: completed in 175.124 ms, heap usage 288.435 MB -> 62.663 MB. [2026-01-21T12:32:06.854Z] ====== als (apache-spark) [default], iteration 2 completed (8453.503 ms) ====== [2026-01-21T12:32:06.854Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T12:32:07.810Z] GC before operation: completed in 171.601 ms, heap usage 154.434 MB -> 63.124 MB. [2026-01-21T12:32:16.009Z] ====== als (apache-spark) [default], iteration 3 completed (7997.655 ms) ====== [2026-01-21T12:32:16.009Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T12:32:16.009Z] GC before operation: completed in 166.391 ms, heap usage 162.813 MB -> 63.677 MB. [2026-01-21T12:32:24.209Z] ====== als (apache-spark) [default], iteration 4 completed (7871.392 ms) ====== [2026-01-21T12:32:24.209Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T12:32:24.209Z] GC before operation: completed in 174.785 ms, heap usage 328.029 MB -> 64.511 MB. [2026-01-21T12:32:32.404Z] ====== als (apache-spark) [default], iteration 5 completed (7979.631 ms) ====== [2026-01-21T12:32:32.404Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T12:32:33.402Z] GC before operation: completed in 187.116 ms, heap usage 546.993 MB -> 65.377 MB. [2026-01-21T12:32:41.675Z] ====== als (apache-spark) [default], iteration 6 completed (7821.271 ms) ====== [2026-01-21T12:32:41.675Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T12:32:41.675Z] GC before operation: completed in 171.446 ms, heap usage 262.346 MB -> 65.547 MB. [2026-01-21T12:32:49.859Z] ====== als (apache-spark) [default], iteration 7 completed (7898.287 ms) ====== [2026-01-21T12:32:49.859Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T12:32:49.859Z] GC before operation: completed in 192.767 ms, heap usage 181.478 MB -> 65.994 MB. [2026-01-21T12:32:58.048Z] ====== als (apache-spark) [default], iteration 8 completed (7182.262 ms) ====== [2026-01-21T12:32:58.048Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T12:32:58.048Z] GC before operation: completed in 205.943 ms, heap usage 484.202 MB -> 66.895 MB. [2026-01-21T12:33:04.776Z] ====== als (apache-spark) [default], iteration 9 completed (7283.488 ms) ====== [2026-01-21T12:33:04.776Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T12:33:04.776Z] GC before operation: completed in 197.754 ms, heap usage 391.392 MB -> 66.949 MB. [2026-01-21T12:33:13.037Z] ====== als (apache-spark) [default], iteration 10 completed (7291.359 ms) ====== [2026-01-21T12:33:13.038Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T12:33:13.038Z] GC before operation: completed in 193.044 ms, heap usage 475.801 MB -> 67.377 MB. [2026-01-21T12:33:21.227Z] ====== als (apache-spark) [default], iteration 11 completed (7319.195 ms) ====== [2026-01-21T12:33:21.227Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T12:33:21.227Z] GC before operation: completed in 191.969 ms, heap usage 105.625 MB -> 66.891 MB. [2026-01-21T12:33:28.688Z] ====== als (apache-spark) [default], iteration 12 completed (7490.468 ms) ====== [2026-01-21T12:33:28.688Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T12:33:28.688Z] GC before operation: completed in 213.136 ms, heap usage 194.633 MB -> 67.497 MB. [2026-01-21T12:33:36.908Z] ====== als (apache-spark) [default], iteration 13 completed (6971.662 ms) ====== [2026-01-21T12:33:36.908Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T12:33:36.908Z] GC before operation: completed in 184.150 ms, heap usage 215.802 MB -> 67.878 MB. [2026-01-21T12:33:43.653Z] ====== als (apache-spark) [default], iteration 14 completed (7550.580 ms) ====== [2026-01-21T12:33:43.653Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T12:33:44.616Z] GC before operation: completed in 213.272 ms, heap usage 254.545 MB -> 68.192 MB. [2026-01-21T12:33:52.846Z] ====== als (apache-spark) [default], iteration 15 completed (8203.661 ms) ====== [2026-01-21T12:33:52.846Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T12:33:52.846Z] GC before operation: completed in 207.413 ms, heap usage 502.675 MB -> 68.700 MB. [2026-01-21T12:34:01.048Z] ====== als (apache-spark) [default], iteration 16 completed (7872.224 ms) ====== [2026-01-21T12:34:01.048Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T12:34:01.048Z] GC before operation: completed in 234.571 ms, heap usage 337.125 MB -> 68.718 MB. [2026-01-21T12:34:10.857Z] ====== als (apache-spark) [default], iteration 17 completed (8231.260 ms) ====== [2026-01-21T12:34:10.857Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T12:34:10.857Z] GC before operation: completed in 233.094 ms, heap usage 409.774 MB -> 69.080 MB. [2026-01-21T12:34:17.599Z] ====== als (apache-spark) [default], iteration 18 completed (7421.811 ms) ====== [2026-01-21T12:34:17.599Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T12:34:17.599Z] GC before operation: completed in 188.072 ms, heap usage 284.165 MB -> 69.245 MB. [2026-01-21T12:34:25.789Z] ====== als (apache-spark) [default], iteration 19 completed (7183.426 ms) ====== [2026-01-21T12:34:25.789Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T12:34:25.789Z] GC before operation: completed in 232.853 ms, heap usage 505.584 MB -> 69.773 MB. [2026-01-21T12:34:33.992Z] ====== als (apache-spark) [default], iteration 20 completed (7765.947 ms) ====== [2026-01-21T12:34:33.992Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T12:34:33.992Z] GC before operation: completed in 228.890 ms, heap usage 265.511 MB -> 69.639 MB. [2026-01-21T12:34:42.771Z] ====== als (apache-spark) [default], iteration 21 completed (8010.577 ms) ====== [2026-01-21T12:34:42.771Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T12:34:42.771Z] GC before operation: completed in 216.292 ms, heap usage 481.138 MB -> 70.268 MB. [2026-01-21T12:34:52.631Z] ====== als (apache-spark) [default], iteration 22 completed (8840.066 ms) ====== [2026-01-21T12:34:52.631Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T12:34:52.631Z] GC before operation: completed in 217.992 ms, heap usage 264.566 MB -> 70.332 MB. [2026-01-21T12:35:02.454Z] ====== als (apache-spark) [default], iteration 23 completed (9387.859 ms) ====== [2026-01-21T12:35:02.454Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T12:35:02.454Z] GC before operation: completed in 236.448 ms, heap usage 336.520 MB -> 70.638 MB. [2026-01-21T12:35:12.424Z] ====== als (apache-spark) [default], iteration 24 completed (9594.681 ms) ====== [2026-01-21T12:35:12.424Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T12:35:12.424Z] GC before operation: completed in 229.847 ms, heap usage 620.396 MB -> 71.180 MB. [2026-01-21T12:35:20.633Z] ====== als (apache-spark) [default], iteration 25 completed (8232.355 ms) ====== [2026-01-21T12:35:20.633Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T12:35:20.633Z] GC before operation: completed in 217.858 ms, heap usage 547.826 MB -> 71.428 MB. [2026-01-21T12:35:28.883Z] ====== als (apache-spark) [default], iteration 26 completed (7918.050 ms) ====== [2026-01-21T12:35:28.883Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T12:35:28.883Z] GC before operation: completed in 205.887 ms, heap usage 246.780 MB -> 71.329 MB. [2026-01-21T12:35:37.160Z] ====== als (apache-spark) [default], iteration 27 completed (7114.338 ms) ====== [2026-01-21T12:35:37.160Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T12:35:37.160Z] GC before operation: completed in 229.289 ms, heap usage 239.611 MB -> 71.586 MB. [2026-01-21T12:35:45.449Z] ====== als (apache-spark) [default], iteration 28 completed (7734.702 ms) ====== [2026-01-21T12:35:45.449Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T12:35:45.449Z] GC before operation: completed in 233.046 ms, heap usage 302.892 MB -> 71.515 MB. [2026-01-21T12:35:53.677Z] ====== als (apache-spark) [default], iteration 29 completed (8077.365 ms) ====== [2026-01-21T12:35:54.639Z] ----------------------------------- [2026-01-21T12:35:54.639Z] renaissance-als_0_PASSED [2026-01-21T12:35:54.639Z] ----------------------------------- [2026-01-21T12:35:54.639Z] [2026-01-21T12:35:54.639Z] TEST TEARDOWN: [2026-01-21T12:35:54.639Z] Nothing to be done for teardown. [2026-01-21T12:35:54.639Z] renaissance-als_0 Finish Time: Wed Jan 21 12:35:53 2026 Epoch Time (ms): 1768998953912