[2026-01-03T13:42:15.071Z] Running test renaissance-als_0 ... [2026-01-03T13:42:15.071Z] =============================================== [2026-01-03T13:42:15.071Z] renaissance-als_0 Start Time: Sat Jan 3 13:42:14 2026 Epoch Time (ms): 1767447734911 [2026-01-03T13:42:15.071Z] variation: NoOptions [2026-01-03T13:42:15.071Z] JVM_OPTIONS: [2026-01-03T13:42:15.071Z] { \ [2026-01-03T13:42:15.071Z] echo ""; echo "TEST SETUP:"; \ [2026-01-03T13:42:15.071Z] echo "Nothing to be done for setup."; \ [2026-01-03T13:42:15.071Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17674476247689/renaissance-als_0"; \ [2026-01-03T13:42:15.071Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17674476247689/renaissance-als_0"; \ [2026-01-03T13:42:15.071Z] echo ""; echo "TESTING:"; \ [2026-01-03T13:42:15.071Z] "/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_17674476247689/renaissance-als_0"/als.json" als; \ [2026-01-03T13:42:15.071Z] 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_17674476247689/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-03T13:42:15.071Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-03T13:42:15.071Z] echo "Nothing to be done for teardown."; \ [2026-01-03T13:42:15.071Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17674476247689/TestTargetResult"; [2026-01-03T13:42:15.071Z] [2026-01-03T13:42:15.071Z] TEST SETUP: [2026-01-03T13:42:15.071Z] Nothing to be done for setup. [2026-01-03T13:42:15.071Z] [2026-01-03T13:42:15.071Z] TESTING: [2026-01-03T13:42:16.688Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-03T13:42:16.688Z] 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_17674476247689/renaissance-als_0/launcher-134215-18104105799652713136/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-03T13:42:16.688Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-03T13:42:16.688Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-03T13:42:42.866Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2026-01-03T13:42:48.560Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-03T13:42:48.560Z] GC before operation: completed in 66.350 ms, heap usage 419.777 MB -> 34.454 MB. [2026-01-03T13:43:14.528Z] ====== als (apache-spark) [default], iteration 0 completed (22738.184 ms) ====== [2026-01-03T13:43:14.528Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-03T13:43:14.528Z] GC before operation: completed in 131.224 ms, heap usage 217.387 MB -> 61.423 MB. [2026-01-03T13:43:26.337Z] ====== als (apache-spark) [default], iteration 1 completed (13605.035 ms) ====== [2026-01-03T13:43:26.337Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-03T13:43:26.337Z] GC before operation: completed in 123.434 ms, heap usage 798.547 MB -> 63.135 MB. [2026-01-03T13:43:40.298Z] ====== als (apache-spark) [default], iteration 2 completed (13888.977 ms) ====== [2026-01-03T13:43:40.298Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-03T13:43:40.298Z] GC before operation: completed in 118.390 ms, heap usage 1.157 GB -> 64.176 MB. [2026-01-03T13:43:54.163Z] ====== als (apache-spark) [default], iteration 3 completed (14295.024 ms) ====== [2026-01-03T13:43:54.163Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-03T13:43:54.947Z] GC before operation: completed in 109.610 ms, heap usage 914.768 MB -> 64.485 MB. [2026-01-03T13:44:08.929Z] ====== als (apache-spark) [default], iteration 4 completed (13913.600 ms) ====== [2026-01-03T13:44:08.929Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-03T13:44:08.929Z] GC before operation: completed in 104.687 ms, heap usage 920.276 MB -> 64.895 MB. [2026-01-03T13:44:25.728Z] ====== als (apache-spark) [default], iteration 5 completed (15649.014 ms) ====== [2026-01-03T13:44:25.728Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-03T13:44:25.728Z] GC before operation: completed in 113.135 ms, heap usage 1.185 GB -> 65.938 MB. [2026-01-03T13:44:39.614Z] ====== als (apache-spark) [default], iteration 6 completed (13731.938 ms) ====== [2026-01-03T13:44:39.614Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-03T13:44:39.614Z] GC before operation: completed in 110.369 ms, heap usage 720.746 MB -> 65.791 MB. [2026-01-03T13:44:53.471Z] ====== als (apache-spark) [default], iteration 7 completed (13515.517 ms) ====== [2026-01-03T13:44:53.471Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-03T13:44:53.471Z] GC before operation: completed in 117.828 ms, heap usage 661.161 MB -> 66.328 MB. [2026-01-03T13:45:07.412Z] ====== als (apache-spark) [default], iteration 8 completed (13716.507 ms) ====== [2026-01-03T13:45:07.412Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-03T13:45:07.412Z] GC before operation: completed in 122.832 ms, heap usage 393.748 MB -> 66.393 MB. [2026-01-03T13:45:21.420Z] ====== als (apache-spark) [default], iteration 9 completed (13457.549 ms) ====== [2026-01-03T13:45:21.420Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-03T13:45:21.420Z] GC before operation: completed in 128.585 ms, heap usage 831.610 MB -> 67.269 MB. [2026-01-03T13:45:35.635Z] ====== als (apache-spark) [default], iteration 10 completed (14610.085 ms) ====== [2026-01-03T13:45:35.635Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-03T13:45:35.635Z] GC before operation: completed in 127.996 ms, heap usage 538.817 MB -> 67.299 MB. [2026-01-03T13:45:49.690Z] ====== als (apache-spark) [default], iteration 11 completed (13941.796 ms) ====== [2026-01-03T13:45:49.691Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-03T13:45:49.691Z] GC before operation: completed in 119.752 ms, heap usage 674.562 MB -> 67.711 MB. [2026-01-03T13:46:03.557Z] ====== als (apache-spark) [default], iteration 12 completed (13865.538 ms) ====== [2026-01-03T13:46:03.557Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-03T13:46:03.557Z] GC before operation: completed in 109.771 ms, heap usage 199.044 MB -> 67.323 MB. [2026-01-03T13:46:19.855Z] ====== als (apache-spark) [default], iteration 13 completed (14317.648 ms) ====== [2026-01-03T13:46:19.855Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-03T13:46:19.855Z] GC before operation: completed in 119.191 ms, heap usage 468.602 MB -> 67.985 MB. [2026-01-03T13:46:33.779Z] ====== als (apache-spark) [default], iteration 14 completed (14051.693 ms) ====== [2026-01-03T13:46:33.779Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-03T13:46:33.779Z] GC before operation: completed in 114.212 ms, heap usage 255.353 MB -> 67.904 MB. [2026-01-03T13:46:47.667Z] ====== als (apache-spark) [default], iteration 15 completed (14021.615 ms) ====== [2026-01-03T13:46:47.667Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-03T13:46:47.667Z] GC before operation: completed in 105.425 ms, heap usage 305.582 MB -> 68.289 MB. [2026-01-03T13:47:03.963Z] ====== als (apache-spark) [default], iteration 16 completed (14713.654 ms) ====== [2026-01-03T13:47:03.963Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-03T13:47:03.963Z] GC before operation: completed in 98.537 ms, heap usage 960.429 MB -> 69.284 MB. [2026-01-03T13:47:15.808Z] ====== als (apache-spark) [default], iteration 17 completed (13631.767 ms) ====== [2026-01-03T13:47:15.808Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-03T13:47:15.808Z] GC before operation: completed in 101.985 ms, heap usage 830.476 MB -> 69.482 MB. [2026-01-03T13:47:30.216Z] ====== als (apache-spark) [default], iteration 18 completed (13628.184 ms) ====== [2026-01-03T13:47:30.216Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-03T13:47:30.216Z] GC before operation: completed in 98.067 ms, heap usage 554.694 MB -> 69.218 MB. [2026-01-03T13:47:44.087Z] ====== als (apache-spark) [default], iteration 19 completed (13634.707 ms) ====== [2026-01-03T13:47:44.087Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-03T13:47:44.087Z] GC before operation: completed in 109.870 ms, heap usage 317.408 MB -> 69.226 MB. [2026-01-03T13:48:00.333Z] ====== als (apache-spark) [default], iteration 20 completed (14708.786 ms) ====== [2026-01-03T13:48:00.333Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-03T13:48:00.333Z] GC before operation: completed in 110.465 ms, heap usage 541.128 MB -> 69.796 MB. [2026-01-03T13:48:14.186Z] ====== als (apache-spark) [default], iteration 21 completed (13725.962 ms) ====== [2026-01-03T13:48:14.186Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-03T13:48:14.186Z] GC before operation: completed in 111.705 ms, heap usage 453.150 MB -> 69.983 MB. [2026-01-03T13:48:28.079Z] ====== als (apache-spark) [default], iteration 22 completed (13600.666 ms) ====== [2026-01-03T13:48:28.079Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-03T13:48:28.079Z] GC before operation: completed in 126.473 ms, heap usage 592.703 MB -> 70.389 MB. [2026-01-03T13:48:40.062Z] ====== als (apache-spark) [default], iteration 23 completed (13078.010 ms) ====== [2026-01-03T13:48:40.062Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-03T13:48:40.062Z] GC before operation: completed in 113.762 ms, heap usage 105.674 MB -> 69.948 MB. [2026-01-03T13:48:53.969Z] ====== als (apache-spark) [default], iteration 24 completed (13686.971 ms) ====== [2026-01-03T13:48:53.969Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-03T13:48:53.969Z] GC before operation: completed in 106.324 ms, heap usage 698.830 MB -> 71.015 MB. [2026-01-03T13:49:13.345Z] ====== als (apache-spark) [default], iteration 25 completed (16896.904 ms) ====== [2026-01-03T13:49:13.345Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-03T13:49:13.345Z] GC before operation: completed in 114.522 ms, heap usage 362.672 MB -> 70.808 MB. [2026-01-03T13:49:27.235Z] ====== als (apache-spark) [default], iteration 26 completed (14723.561 ms) ====== [2026-01-03T13:49:27.235Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-03T13:49:27.235Z] GC before operation: completed in 108.748 ms, heap usage 843.956 MB -> 71.765 MB. [2026-01-03T13:49:41.195Z] ====== als (apache-spark) [default], iteration 27 completed (13132.219 ms) ====== [2026-01-03T13:49:41.195Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-03T13:49:41.195Z] GC before operation: completed in 132.292 ms, heap usage 792.438 MB -> 71.918 MB. [2026-01-03T13:49:55.064Z] ====== als (apache-spark) [default], iteration 28 completed (14165.494 ms) ====== [2026-01-03T13:49:55.064Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-03T13:49:55.064Z] GC before operation: completed in 114.397 ms, heap usage 402.763 MB -> 71.720 MB. [2026-01-03T13:50:08.933Z] ====== als (apache-spark) [default], iteration 29 completed (13667.126 ms) ====== [2026-01-03T13:50:08.933Z] ----------------------------------- [2026-01-03T13:50:08.933Z] renaissance-als_0_PASSED [2026-01-03T13:50:08.933Z] ----------------------------------- [2026-01-03T13:50:08.933Z] [2026-01-03T13:50:08.933Z] TEST TEARDOWN: [2026-01-03T13:50:08.933Z] Nothing to be done for teardown. [2026-01-03T13:50:08.933Z] renaissance-als_0 Finish Time: Sat Jan 3 13:50:08 2026 Epoch Time (ms): 1767448208203