[2026-04-15T14:48:34.941Z] Running test renaissance-als_0 ... [2026-04-15T14:48:34.941Z] =============================================== [2026-04-15T14:48:34.941Z] renaissance-als_0 Start Time: Wed Apr 15 14:48:37 2026 Epoch Time (ms): 1776264517857 [2026-04-15T14:48:34.941Z] variation: NoOptions [2026-04-15T14:48:34.941Z] JVM_OPTIONS: [2026-04-15T14:48:34.941Z] { \ [2026-04-15T14:48:34.941Z] echo ""; echo "TEST SETUP:"; \ [2026-04-15T14:48:34.941Z] echo "Nothing to be done for setup."; \ [2026-04-15T14:48:34.941Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1776264406453/renaissance-als_0"; \ [2026-04-15T14:48:34.941Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1776264406453/renaissance-als_0"; \ [2026-04-15T14:48:34.941Z] echo ""; echo "TESTING:"; \ [2026-04-15T14:48:34.941Z] "/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_1776264406453/renaissance-als_0"/als.json" als; \ [2026-04-15T14:48:34.941Z] 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_1776264406453/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-15T14:48:34.941Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-15T14:48:34.941Z] echo "Nothing to be done for teardown."; \ [2026-04-15T14:48:34.941Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1776264406453/TestTargetResult"; [2026-04-15T14:48:34.941Z] [2026-04-15T14:48:34.941Z] TEST SETUP: [2026-04-15T14:48:34.941Z] Nothing to be done for setup. [2026-04-15T14:48:34.941Z] [2026-04-15T14:48:34.941Z] TESTING: [2026-04-15T14:48:37.031Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-04-15T14:48:37.031Z] 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_1776264406453/renaissance-als_0/launcher-144838-710825266199172649/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-04-15T14:48:37.031Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-04-15T14:48:37.031Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-04-15T14:49:01.594Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2026-04-15T14:49:06.985Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-15T14:49:06.985Z] GC before operation: completed in 61.004 ms, heap usage 418.741 MB -> 34.604 MB. [2026-04-15T14:49:31.601Z] ====== als (apache-spark) [default], iteration 0 completed (21810.321 ms) ====== [2026-04-15T14:49:31.601Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-15T14:49:31.601Z] GC before operation: completed in 137.881 ms, heap usage 204.988 MB -> 61.441 MB. [2026-04-15T14:49:43.231Z] ====== als (apache-spark) [default], iteration 1 completed (12776.566 ms) ====== [2026-04-15T14:49:43.231Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-15T14:49:43.231Z] GC before operation: completed in 120.318 ms, heap usage 399.912 MB -> 62.302 MB. [2026-04-15T14:49:56.731Z] ====== als (apache-spark) [default], iteration 2 completed (13556.156 ms) ====== [2026-04-15T14:49:56.731Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-15T14:49:56.731Z] GC before operation: completed in 121.724 ms, heap usage 147.960 MB -> 62.549 MB. [2026-04-15T14:50:10.225Z] ====== als (apache-spark) [default], iteration 3 completed (13668.022 ms) ====== [2026-04-15T14:50:10.225Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-15T14:50:10.225Z] GC before operation: completed in 130.148 ms, heap usage 516.404 MB -> 63.721 MB. [2026-04-15T14:50:23.785Z] ====== als (apache-spark) [default], iteration 4 completed (12990.669 ms) ====== [2026-04-15T14:50:23.785Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-15T14:50:23.785Z] GC before operation: completed in 101.929 ms, heap usage 238.342 MB -> 63.969 MB. [2026-04-15T14:50:39.112Z] ====== als (apache-spark) [default], iteration 5 completed (14933.053 ms) ====== [2026-04-15T14:50:39.112Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-15T14:50:39.112Z] GC before operation: completed in 105.336 ms, heap usage 739.409 MB -> 65.236 MB. [2026-04-15T14:50:54.919Z] ====== als (apache-spark) [default], iteration 6 completed (14056.542 ms) ====== [2026-04-15T14:50:54.919Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-15T14:50:54.919Z] GC before operation: completed in 124.443 ms, heap usage 456.683 MB -> 65.513 MB. [2026-04-15T14:51:12.198Z] ====== als (apache-spark) [default], iteration 7 completed (13368.732 ms) ====== [2026-04-15T14:51:12.198Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-15T14:51:12.198Z] GC before operation: completed in 109.968 ms, heap usage 707.012 MB -> 66.221 MB. [2026-04-15T14:51:30.017Z] ====== als (apache-spark) [default], iteration 8 completed (13173.478 ms) ====== [2026-04-15T14:51:33.938Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-15T14:51:33.938Z] GC before operation: completed in 103.058 ms, heap usage 556.509 MB -> 66.610 MB. [2026-04-15T14:51:50.573Z] ====== als (apache-spark) [default], iteration 9 completed (13180.003 ms) ====== [2026-04-15T14:51:50.573Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-15T14:51:50.573Z] GC before operation: completed in 127.448 ms, heap usage 645.038 MB -> 66.964 MB. [2026-04-15T14:52:00.566Z] ====== als (apache-spark) [default], iteration 10 completed (14254.112 ms) ====== [2026-04-15T14:52:00.566Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-15T14:52:00.566Z] GC before operation: completed in 111.506 ms, heap usage 405.538 MB -> 67.023 MB. [2026-04-15T14:52:02.584Z] ====== als (apache-spark) [default], iteration 11 completed (14110.856 ms) ====== [2026-04-15T14:52:02.584Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-15T14:52:03.585Z] GC before operation: completed in 96.656 ms, heap usage 807.439 MB -> 67.797 MB. [2026-04-15T14:52:17.190Z] ====== als (apache-spark) [default], iteration 12 completed (13806.618 ms) ====== [2026-04-15T14:52:17.190Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-15T14:52:17.190Z] GC before operation: completed in 103.149 ms, heap usage 1.190 GB -> 68.548 MB. [2026-04-15T14:52:30.726Z] ====== als (apache-spark) [default], iteration 13 completed (13394.788 ms) ====== [2026-04-15T14:52:30.726Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-15T14:52:30.726Z] GC before operation: completed in 116.618 ms, heap usage 332.696 MB -> 67.672 MB. [2026-04-15T14:52:44.330Z] ====== als (apache-spark) [default], iteration 14 completed (13177.113 ms) ====== [2026-04-15T14:52:44.330Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-15T14:52:44.330Z] GC before operation: completed in 123.678 ms, heap usage 488.198 MB -> 68.146 MB. [2026-04-15T14:53:00.065Z] ====== als (apache-spark) [default], iteration 15 completed (14612.633 ms) ====== [2026-04-15T14:53:00.065Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-15T14:53:00.065Z] GC before operation: completed in 97.633 ms, heap usage 437.991 MB -> 68.391 MB. [2026-04-15T14:53:13.631Z] ====== als (apache-spark) [default], iteration 16 completed (14065.990 ms) ====== [2026-04-15T14:53:13.631Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-15T14:53:13.631Z] GC before operation: completed in 100.710 ms, heap usage 799.926 MB -> 69.062 MB. [2026-04-15T14:53:27.163Z] ====== als (apache-spark) [default], iteration 17 completed (13504.437 ms) ====== [2026-04-15T14:53:27.163Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-15T14:53:27.163Z] GC before operation: completed in 128.370 ms, heap usage 278.864 MB -> 68.553 MB. [2026-04-15T14:53:40.631Z] ====== als (apache-spark) [default], iteration 18 completed (12873.893 ms) ====== [2026-04-15T14:53:40.631Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-15T14:53:40.631Z] GC before operation: completed in 104.762 ms, heap usage 595.333 MB -> 69.336 MB. [2026-04-15T14:53:54.197Z] ====== als (apache-spark) [default], iteration 19 completed (13383.540 ms) ====== [2026-04-15T14:53:54.197Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-15T14:53:54.197Z] GC before operation: completed in 122.845 ms, heap usage 1.731 GB -> 70.872 MB. [2026-04-15T14:54:09.425Z] ====== als (apache-spark) [default], iteration 20 completed (14071.929 ms) ====== [2026-04-15T14:54:09.425Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-15T14:54:09.425Z] GC before operation: completed in 133.815 ms, heap usage 1023.182 MB -> 70.321 MB. [2026-04-15T14:54:31.172Z] ====== als (apache-spark) [default], iteration 21 completed (14475.709 ms) ====== [2026-04-15T14:54:31.172Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-15T14:54:31.172Z] GC before operation: completed in 123.891 ms, heap usage 668.505 MB -> 70.141 MB. [2026-04-15T14:54:36.736Z] ====== als (apache-spark) [default], iteration 22 completed (13534.946 ms) ====== [2026-04-15T14:54:36.736Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-15T14:54:36.736Z] GC before operation: completed in 101.629 ms, heap usage 906.624 MB -> 70.700 MB. [2026-04-15T14:54:50.315Z] ====== als (apache-spark) [default], iteration 23 completed (12899.337 ms) ====== [2026-04-15T14:54:50.315Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-15T14:54:50.315Z] GC before operation: completed in 109.401 ms, heap usage 511.421 MB -> 70.543 MB. [2026-04-15T14:55:03.947Z] ====== als (apache-spark) [default], iteration 24 completed (13477.021 ms) ====== [2026-04-15T14:55:03.947Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-15T14:55:03.947Z] GC before operation: completed in 105.834 ms, heap usage 902.693 MB -> 71.169 MB. [2026-04-15T14:55:17.653Z] ====== als (apache-spark) [default], iteration 25 completed (13742.835 ms) ====== [2026-04-15T14:55:17.653Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-15T14:55:18.652Z] GC before operation: completed in 110.655 ms, heap usage 662.187 MB -> 71.172 MB. [2026-04-15T14:55:34.459Z] ====== als (apache-spark) [default], iteration 26 completed (14805.326 ms) ====== [2026-04-15T14:55:34.459Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-15T14:55:34.459Z] GC before operation: completed in 108.361 ms, heap usage 544.829 MB -> 71.291 MB. [2026-04-15T14:55:48.196Z] ====== als (apache-spark) [default], iteration 27 completed (13623.891 ms) ====== [2026-04-15T14:55:48.196Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-15T14:55:48.196Z] GC before operation: completed in 112.333 ms, heap usage 1.682 GB -> 72.426 MB. [2026-04-15T14:56:01.747Z] ====== als (apache-spark) [default], iteration 28 completed (13380.739 ms) ====== [2026-04-15T14:56:01.747Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-15T14:56:01.747Z] GC before operation: completed in 140.065 ms, heap usage 2.434 GB -> 72.951 MB. [2026-04-15T14:56:15.384Z] ====== als (apache-spark) [default], iteration 29 completed (13177.643 ms) ====== [2026-04-15T14:56:15.384Z] ----------------------------------- [2026-04-15T14:56:15.384Z] renaissance-als_0_PASSED [2026-04-15T14:56:15.384Z] ----------------------------------- [2026-04-15T14:56:15.384Z] [2026-04-15T14:56:15.384Z] TEST TEARDOWN: [2026-04-15T14:56:15.384Z] Nothing to be done for teardown. [2026-04-15T14:56:15.384Z] renaissance-als_0 Finish Time: Wed Apr 15 14:56:17 2026 Epoch Time (ms): 1776264977396