[2026-01-22T18:58:44.824Z] Running test renaissance-als_0 ... [2026-01-22T18:58:44.824Z] =============================================== [2026-01-22T18:58:44.824Z] renaissance-als_0 Start Time: Thu Jan 22 18:58:44 2026 Epoch Time (ms): 1769108324170 [2026-01-22T18:58:44.824Z] variation: NoOptions [2026-01-22T18:58:44.824Z] JVM_OPTIONS: [2026-01-22T18:58:44.824Z] { \ [2026-01-22T18:58:44.824Z] echo ""; echo "TEST SETUP:"; \ [2026-01-22T18:58:44.824Z] echo "Nothing to be done for setup."; \ [2026-01-22T18:58:44.824Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17691082126297/renaissance-als_0"; \ [2026-01-22T18:58:44.824Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17691082126297/renaissance-als_0"; \ [2026-01-22T18:58:44.824Z] echo ""; echo "TESTING:"; \ [2026-01-22T18:58:44.824Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17691082126297/renaissance-als_0"/als.json" als; \ [2026-01-22T18:58:44.824Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17691082126297/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-22T18:58:44.824Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-22T18:58:44.824Z] echo "Nothing to be done for teardown."; \ [2026-01-22T18:58:44.824Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17691082126297/TestTargetResult"; [2026-01-22T18:58:44.824Z] [2026-01-22T18:58:44.824Z] TEST SETUP: [2026-01-22T18:58:44.824Z] Nothing to be done for setup. [2026-01-22T18:58:44.824Z] [2026-01-22T18:58:44.824Z] TESTING: [2026-01-22T18:58:46.432Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-22T18:58:46.432Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17691082126297/renaissance-als_0/launcher-185844-9405640936124946636/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-22T18:58:46.432Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-22T18:58:46.432Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-22T18:59:12.360Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2026-01-22T18:59:18.023Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-22T18:59:18.023Z] GC before operation: completed in 65.946 ms, heap usage 414.035 MB -> 34.704 MB. [2026-01-22T18:59:44.072Z] ====== als (apache-spark) [default], iteration 0 completed (22472.572 ms) ====== [2026-01-22T18:59:44.072Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-22T18:59:44.072Z] GC before operation: completed in 146.595 ms, heap usage 906.094 MB -> 62.401 MB. [2026-01-22T18:59:54.120Z] ====== als (apache-spark) [default], iteration 1 completed (12872.258 ms) ====== [2026-01-22T18:59:54.120Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-22T18:59:54.120Z] GC before operation: completed in 136.143 ms, heap usage 609.739 MB -> 62.981 MB. [2026-01-22T19:00:07.998Z] ====== als (apache-spark) [default], iteration 2 completed (13588.698 ms) ====== [2026-01-22T19:00:07.998Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-22T19:00:07.998Z] GC before operation: completed in 117.163 ms, heap usage 655.483 MB -> 63.719 MB. [2026-01-22T19:00:21.878Z] ====== als (apache-spark) [default], iteration 3 completed (13620.230 ms) ====== [2026-01-22T19:00:21.878Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-22T19:00:21.878Z] GC before operation: completed in 116.807 ms, heap usage 946.200 MB -> 64.678 MB. [2026-01-22T19:00:38.139Z] ====== als (apache-spark) [default], iteration 4 completed (14109.191 ms) ====== [2026-01-22T19:00:38.139Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-22T19:00:38.139Z] GC before operation: completed in 116.646 ms, heap usage 1.182 GB -> 65.532 MB. [2026-01-22T19:00:52.051Z] ====== als (apache-spark) [default], iteration 5 completed (14891.437 ms) ====== [2026-01-22T19:00:52.051Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-22T19:00:52.051Z] GC before operation: completed in 131.649 ms, heap usage 279.098 MB -> 65.174 MB. [2026-01-22T19:01:08.318Z] ====== als (apache-spark) [default], iteration 6 completed (14446.319 ms) ====== [2026-01-22T19:01:08.318Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-22T19:01:08.318Z] GC before operation: completed in 119.291 ms, heap usage 755.096 MB -> 66.072 MB. [2026-01-22T19:01:20.350Z] ====== als (apache-spark) [default], iteration 7 completed (13338.019 ms) ====== [2026-01-22T19:01:20.350Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-22T19:01:20.350Z] GC before operation: completed in 134.289 ms, heap usage 527.028 MB -> 66.649 MB. [2026-01-22T19:01:34.229Z] ====== als (apache-spark) [default], iteration 8 completed (13529.421 ms) ====== [2026-01-22T19:01:34.229Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-22T19:01:34.229Z] GC before operation: completed in 125.383 ms, heap usage 802.054 MB -> 67.475 MB. [2026-01-22T19:01:50.457Z] ====== als (apache-spark) [default], iteration 9 completed (14201.838 ms) ====== [2026-01-22T19:01:50.457Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-22T19:01:50.457Z] GC before operation: completed in 119.875 ms, heap usage 1.242 GB -> 68.368 MB. [2026-01-22T19:02:04.562Z] ====== als (apache-spark) [default], iteration 10 completed (14714.425 ms) ====== [2026-01-22T19:02:04.562Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-22T19:02:04.562Z] GC before operation: completed in 121.735 ms, heap usage 914.386 MB -> 67.851 MB. [2026-01-22T19:02:18.436Z] ====== als (apache-spark) [default], iteration 11 completed (13264.420 ms) ====== [2026-01-22T19:02:18.436Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-22T19:02:18.436Z] GC before operation: completed in 98.879 ms, heap usage 375.881 MB -> 67.578 MB. [2026-01-22T19:02:32.329Z] ====== als (apache-spark) [default], iteration 12 completed (13751.315 ms) ====== [2026-01-22T19:02:32.329Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-22T19:02:32.329Z] GC before operation: completed in 125.769 ms, heap usage 806.399 MB -> 68.428 MB. [2026-01-22T19:02:46.263Z] ====== als (apache-spark) [default], iteration 13 completed (13307.862 ms) ====== [2026-01-22T19:02:46.263Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-22T19:02:46.263Z] GC before operation: completed in 101.432 ms, heap usage 329.637 MB -> 68.177 MB. [2026-01-22T19:02:58.167Z] ====== als (apache-spark) [default], iteration 14 completed (13321.530 ms) ====== [2026-01-22T19:02:58.167Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-22T19:02:58.956Z] GC before operation: completed in 102.493 ms, heap usage 825.732 MB -> 68.934 MB. [2026-01-22T19:03:13.014Z] ====== als (apache-spark) [default], iteration 15 completed (14586.588 ms) ====== [2026-01-22T19:03:13.014Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-22T19:03:13.793Z] GC before operation: completed in 110.242 ms, heap usage 539.981 MB -> 68.855 MB. [2026-01-22T19:03:27.736Z] ====== als (apache-spark) [default], iteration 16 completed (14211.007 ms) ====== [2026-01-22T19:03:27.736Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-22T19:03:27.736Z] GC before operation: completed in 120.817 ms, heap usage 659.369 MB -> 69.245 MB. [2026-01-22T19:03:41.832Z] ====== als (apache-spark) [default], iteration 17 completed (13189.879 ms) ====== [2026-01-22T19:03:41.832Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-22T19:03:41.832Z] GC before operation: completed in 101.895 ms, heap usage 784.328 MB -> 69.755 MB. [2026-01-22T19:03:55.707Z] ====== als (apache-spark) [default], iteration 18 completed (13944.284 ms) ====== [2026-01-22T19:03:55.707Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-22T19:03:55.707Z] GC before operation: completed in 103.795 ms, heap usage 751.749 MB -> 69.819 MB. [2026-01-22T19:04:09.592Z] ====== als (apache-spark) [default], iteration 19 completed (13562.861 ms) ====== [2026-01-22T19:04:09.592Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-22T19:04:09.592Z] GC before operation: completed in 113.568 ms, heap usage 1.197 GB -> 70.810 MB. [2026-01-22T19:04:25.904Z] ====== als (apache-spark) [default], iteration 20 completed (14965.883 ms) ====== [2026-01-22T19:04:25.904Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-22T19:04:25.904Z] GC before operation: completed in 111.149 ms, heap usage 1.607 GB -> 71.757 MB. [2026-01-22T19:04:39.779Z] ====== als (apache-spark) [default], iteration 21 completed (13844.435 ms) ====== [2026-01-22T19:04:39.779Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-22T19:04:39.779Z] GC before operation: completed in 111.654 ms, heap usage 1.238 GB -> 71.298 MB. [2026-01-22T19:04:53.666Z] ====== als (apache-spark) [default], iteration 22 completed (14104.443 ms) ====== [2026-01-22T19:04:53.666Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-22T19:04:53.666Z] GC before operation: completed in 128.103 ms, heap usage 156.700 MB -> 70.276 MB. [2026-01-22T19:05:07.622Z] ====== als (apache-spark) [default], iteration 23 completed (12545.171 ms) ====== [2026-01-22T19:05:07.622Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-22T19:05:07.622Z] GC before operation: completed in 104.443 ms, heap usage 872.781 MB -> 71.226 MB. [2026-01-22T19:05:19.414Z] ====== als (apache-spark) [default], iteration 24 completed (13430.342 ms) ====== [2026-01-22T19:05:19.414Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-22T19:05:20.196Z] GC before operation: completed in 107.332 ms, heap usage 916.115 MB -> 71.820 MB. [2026-01-22T19:05:36.479Z] ====== als (apache-spark) [default], iteration 25 completed (15281.544 ms) ====== [2026-01-22T19:05:36.479Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-22T19:05:36.479Z] GC before operation: completed in 120.882 ms, heap usage 237.816 MB -> 71.083 MB. [2026-01-22T19:05:50.394Z] ====== als (apache-spark) [default], iteration 26 completed (14517.892 ms) ====== [2026-01-22T19:05:50.394Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-22T19:05:50.394Z] GC before operation: completed in 133.293 ms, heap usage 390.344 MB -> 71.768 MB. [2026-01-22T19:06:04.661Z] ====== als (apache-spark) [default], iteration 27 completed (13719.220 ms) ====== [2026-01-22T19:06:04.661Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-22T19:06:04.661Z] GC before operation: completed in 132.658 ms, heap usage 543.436 MB -> 71.509 MB. [2026-01-22T19:06:18.606Z] ====== als (apache-spark) [default], iteration 28 completed (13931.321 ms) ====== [2026-01-22T19:06:18.606Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-22T19:06:18.606Z] GC before operation: completed in 131.590 ms, heap usage 413.534 MB -> 71.660 MB. [2026-01-22T19:06:32.484Z] ====== als (apache-spark) [default], iteration 29 completed (14034.333 ms) ====== [2026-01-22T19:06:33.264Z] ----------------------------------- [2026-01-22T19:06:33.264Z] renaissance-als_0_PASSED [2026-01-22T19:06:33.264Z] ----------------------------------- [2026-01-22T19:06:33.264Z] [2026-01-22T19:06:33.264Z] TEST TEARDOWN: [2026-01-22T19:06:33.264Z] Nothing to be done for teardown. [2026-01-22T19:06:33.264Z] renaissance-als_0 Finish Time: Thu Jan 22 19:06:32 2026 Epoch Time (ms): 1769108792848