[2026-01-19T04:46:41.186Z] Running test renaissance-als_0 ... [2026-01-19T04:46:41.186Z] =============================================== [2026-01-19T04:46:41.186Z] renaissance-als_0 Start Time: Mon Jan 19 04:46:40 2026 Epoch Time (ms): 1768798000413 [2026-01-19T04:46:41.186Z] variation: NoOptions [2026-01-19T04:46:41.186Z] JVM_OPTIONS: [2026-01-19T04:46:41.186Z] { \ [2026-01-19T04:46:41.186Z] echo ""; echo "TEST SETUP:"; \ [2026-01-19T04:46:41.186Z] echo "Nothing to be done for setup."; \ [2026-01-19T04:46:41.186Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17687978761872/renaissance-als_0"; \ [2026-01-19T04:46:41.186Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17687978761872/renaissance-als_0"; \ [2026-01-19T04:46:41.186Z] echo ""; echo "TESTING:"; \ [2026-01-19T04:46:41.186Z] "/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_17687978761872/renaissance-als_0"/als.json" als; \ [2026-01-19T04:46:41.187Z] 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_17687978761872/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-19T04:46:41.187Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-19T04:46:41.187Z] echo "Nothing to be done for teardown."; \ [2026-01-19T04:46:41.187Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17687978761872/TestTargetResult"; [2026-01-19T04:46:41.187Z] [2026-01-19T04:46:41.187Z] TEST SETUP: [2026-01-19T04:46:41.187Z] Nothing to be done for setup. [2026-01-19T04:46:41.187Z] [2026-01-19T04:46:41.187Z] TESTING: [2026-01-19T04:46:41.968Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-19T04:46:41.968Z] 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_17687978761872/renaissance-als_0/launcher-044640-18234783426412499846/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-19T04:46:41.968Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-19T04:46:41.968Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-19T04:47:12.444Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2026-01-19T04:47:14.944Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-19T04:47:14.944Z] GC before operation: completed in 65.491 ms, heap usage 211.207 MB -> 34.384 MB. [2026-01-19T04:47:40.893Z] ====== als (apache-spark) [default], iteration 0 completed (23162.789 ms) ====== [2026-01-19T04:47:40.893Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-19T04:47:40.893Z] GC before operation: completed in 130.765 ms, heap usage 236.422 MB -> 61.682 MB. [2026-01-19T04:47:52.678Z] ====== als (apache-spark) [default], iteration 1 completed (13752.395 ms) ====== [2026-01-19T04:47:52.678Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-19T04:47:52.678Z] GC before operation: completed in 105.778 ms, heap usage 327.996 MB -> 61.973 MB. [2026-01-19T04:48:09.016Z] ====== als (apache-spark) [default], iteration 2 completed (14333.503 ms) ====== [2026-01-19T04:48:09.016Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-19T04:48:09.016Z] GC before operation: completed in 97.335 ms, heap usage 271.165 MB -> 62.687 MB. [2026-01-19T04:48:22.917Z] ====== als (apache-spark) [default], iteration 3 completed (13713.743 ms) ====== [2026-01-19T04:48:22.917Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-19T04:48:22.917Z] GC before operation: completed in 93.175 ms, heap usage 325.764 MB -> 63.178 MB. [2026-01-19T04:48:36.832Z] ====== als (apache-spark) [default], iteration 4 completed (14096.913 ms) ====== [2026-01-19T04:48:36.832Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-19T04:48:36.832Z] GC before operation: completed in 96.216 ms, heap usage 298.978 MB -> 63.931 MB. [2026-01-19T04:48:50.759Z] ====== als (apache-spark) [default], iteration 5 completed (14075.480 ms) ====== [2026-01-19T04:48:50.759Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-19T04:48:50.759Z] GC before operation: completed in 87.112 ms, heap usage 172.366 MB -> 64.353 MB. [2026-01-19T04:49:04.674Z] ====== als (apache-spark) [default], iteration 6 completed (13894.546 ms) ====== [2026-01-19T04:49:04.674Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-19T04:49:04.674Z] GC before operation: completed in 91.757 ms, heap usage 1.192 GB -> 66.269 MB. [2026-01-19T04:49:18.627Z] ====== als (apache-spark) [default], iteration 7 completed (13654.235 ms) ====== [2026-01-19T04:49:18.627Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-19T04:49:18.627Z] GC before operation: completed in 105.048 ms, heap usage 308.634 MB -> 65.671 MB. [2026-01-19T04:49:32.558Z] ====== als (apache-spark) [default], iteration 8 completed (13966.327 ms) ====== [2026-01-19T04:49:32.558Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-19T04:49:32.558Z] GC before operation: completed in 92.301 ms, heap usage 273.173 MB -> 66.093 MB. [2026-01-19T04:49:46.633Z] ====== als (apache-spark) [default], iteration 9 completed (13962.053 ms) ====== [2026-01-19T04:49:46.633Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-19T04:49:47.415Z] GC before operation: completed in 104.838 ms, heap usage 261.337 MB -> 66.812 MB. [2026-01-19T04:50:01.307Z] ====== als (apache-spark) [default], iteration 10 completed (13930.325 ms) ====== [2026-01-19T04:50:01.307Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-19T04:50:01.307Z] GC before operation: completed in 104.006 ms, heap usage 440.573 MB -> 66.900 MB. [2026-01-19T04:50:17.583Z] ====== als (apache-spark) [default], iteration 11 completed (14294.163 ms) ====== [2026-01-19T04:50:17.583Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-19T04:50:17.583Z] GC before operation: completed in 93.303 ms, heap usage 365.477 MB -> 67.204 MB. [2026-01-19T04:50:31.456Z] ====== als (apache-spark) [default], iteration 12 completed (13736.823 ms) ====== [2026-01-19T04:50:31.456Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-19T04:50:31.456Z] GC before operation: completed in 97.708 ms, heap usage 299.384 MB -> 67.674 MB. [2026-01-19T04:50:45.378Z] ====== als (apache-spark) [default], iteration 13 completed (13914.196 ms) ====== [2026-01-19T04:50:45.378Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-19T04:50:45.378Z] GC before operation: completed in 99.516 ms, heap usage 474.643 MB -> 67.994 MB. [2026-01-19T04:50:59.305Z] ====== als (apache-spark) [default], iteration 14 completed (14076.225 ms) ====== [2026-01-19T04:50:59.305Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-19T04:50:59.305Z] GC before operation: completed in 111.931 ms, heap usage 372.754 MB -> 67.984 MB. [2026-01-19T04:51:13.197Z] ====== als (apache-spark) [default], iteration 15 completed (13869.126 ms) ====== [2026-01-19T04:51:13.197Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-19T04:51:13.197Z] GC before operation: completed in 103.764 ms, heap usage 163.255 MB -> 67.932 MB. [2026-01-19T04:51:27.107Z] ====== als (apache-spark) [default], iteration 16 completed (14247.100 ms) ====== [2026-01-19T04:51:27.107Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-19T04:51:27.107Z] GC before operation: completed in 88.163 ms, heap usage 174.489 MB -> 67.982 MB. [2026-01-19T04:51:40.992Z] ====== als (apache-spark) [default], iteration 17 completed (13585.460 ms) ====== [2026-01-19T04:51:40.992Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-19T04:51:40.992Z] GC before operation: completed in 130.288 ms, heap usage 150.611 MB -> 68.259 MB. [2026-01-19T04:51:57.262Z] ====== als (apache-spark) [default], iteration 18 completed (14274.680 ms) ====== [2026-01-19T04:51:57.262Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-19T04:51:57.262Z] GC before operation: completed in 110.365 ms, heap usage 124.723 MB -> 68.511 MB. [2026-01-19T04:52:11.151Z] ====== als (apache-spark) [default], iteration 19 completed (13831.099 ms) ====== [2026-01-19T04:52:11.151Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-19T04:52:11.151Z] GC before operation: completed in 87.307 ms, heap usage 325.909 MB -> 68.994 MB. [2026-01-19T04:52:25.011Z] ====== als (apache-spark) [default], iteration 20 completed (13848.690 ms) ====== [2026-01-19T04:52:25.011Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-19T04:52:25.011Z] GC before operation: completed in 94.646 ms, heap usage 331.972 MB -> 69.457 MB. [2026-01-19T04:52:38.966Z] ====== als (apache-spark) [default], iteration 21 completed (14610.740 ms) ====== [2026-01-19T04:52:38.966Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-19T04:52:38.966Z] GC before operation: completed in 94.886 ms, heap usage 313.234 MB -> 69.668 MB. [2026-01-19T04:52:52.858Z] ====== als (apache-spark) [default], iteration 22 completed (14059.171 ms) ====== [2026-01-19T04:52:52.858Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-19T04:52:53.640Z] GC before operation: completed in 95.182 ms, heap usage 144.611 MB -> 69.704 MB. [2026-01-19T04:53:07.509Z] ====== als (apache-spark) [default], iteration 23 completed (13669.970 ms) ====== [2026-01-19T04:53:07.509Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-19T04:53:07.509Z] GC before operation: completed in 86.064 ms, heap usage 147.810 MB -> 69.836 MB. [2026-01-19T04:53:21.428Z] ====== als (apache-spark) [default], iteration 24 completed (13216.894 ms) ====== [2026-01-19T04:53:21.428Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-19T04:53:21.428Z] GC before operation: completed in 117.230 ms, heap usage 469.378 MB -> 70.632 MB. [2026-01-19T04:53:35.297Z] ====== als (apache-spark) [default], iteration 25 completed (13991.946 ms) ====== [2026-01-19T04:53:35.297Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-19T04:53:35.297Z] GC before operation: completed in 88.966 ms, heap usage 314.784 MB -> 70.571 MB. [2026-01-19T04:53:51.218Z] ====== als (apache-spark) [default], iteration 26 completed (13466.236 ms) ====== [2026-01-19T04:53:51.218Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-19T04:53:51.218Z] GC before operation: completed in 111.639 ms, heap usage 384.346 MB -> 71.053 MB. [2026-01-19T04:54:03.285Z] ====== als (apache-spark) [default], iteration 27 completed (14589.670 ms) ====== [2026-01-19T04:54:03.285Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-19T04:54:03.285Z] GC before operation: completed in 114.126 ms, heap usage 144.332 MB -> 70.972 MB. [2026-01-19T04:54:19.538Z] ====== als (apache-spark) [default], iteration 28 completed (13865.949 ms) ====== [2026-01-19T04:54:19.538Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-19T04:54:19.538Z] GC before operation: completed in 115.017 ms, heap usage 377.404 MB -> 71.437 MB. [2026-01-19T04:54:33.437Z] ====== als (apache-spark) [default], iteration 29 completed (13840.367 ms) ====== [2026-01-19T04:54:33.437Z] ----------------------------------- [2026-01-19T04:54:33.437Z] renaissance-als_0_PASSED [2026-01-19T04:54:33.437Z] ----------------------------------- [2026-01-19T04:54:33.437Z] [2026-01-19T04:54:33.437Z] TEST TEARDOWN: [2026-01-19T04:54:33.437Z] Nothing to be done for teardown. [2026-01-19T04:54:33.437Z] renaissance-als_0 Finish Time: Mon Jan 19 04:54:32 2026 Epoch Time (ms): 1768798472233