[2025-11-26T22:16:53.943Z] Running test renaissance-als_0 ... [2025-11-26T22:16:53.943Z] =============================================== [2025-11-26T22:16:53.943Z] renaissance-als_0 Start Time: Wed Nov 26 22:16:53 2025 Epoch Time (ms): 1764195413738 [2025-11-26T22:16:53.943Z] variation: NoOptions [2025-11-26T22:16:53.943Z] JVM_OPTIONS: [2025-11-26T22:16:53.943Z] { \ [2025-11-26T22:16:53.943Z] echo ""; echo "TEST SETUP:"; \ [2025-11-26T22:16:53.943Z] echo "Nothing to be done for setup."; \ [2025-11-26T22:16:53.943Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-als_0"; \ [2025-11-26T22:16:53.943Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-als_0"; \ [2025-11-26T22:16:53.943Z] echo ""; echo "TESTING:"; \ [2025-11-26T22:16:53.943Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-als_0"/als.json" als; \ [2025-11-26T22:16:53.943Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-26T22:16:53.943Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-26T22:16:53.943Z] echo "Nothing to be done for teardown."; \ [2025-11-26T22:16:53.943Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17641953159956/TestTargetResult"; [2025-11-26T22:16:53.943Z] [2025-11-26T22:16:53.943Z] TEST SETUP: [2025-11-26T22:16:53.943Z] Nothing to be done for setup. [2025-11-26T22:16:53.943Z] [2025-11-26T22:16:53.943Z] TESTING: [2025-11-26T22:17:02.556Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-11-26T22:17:07.200Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-26T22:17:07.200Z] GC before operation: completed in 79.161 ms, heap usage 239.852 MB -> 34.663 MB. [2025-11-26T22:17:21.264Z] ====== als (apache-spark) [default], iteration 0 completed (13075.938 ms) ====== [2025-11-26T22:17:21.264Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-26T22:17:21.264Z] GC before operation: completed in 129.710 ms, heap usage 139.798 MB -> 61.781 MB. [2025-11-26T22:17:25.869Z] ====== als (apache-spark) [default], iteration 1 completed (4855.902 ms) ====== [2025-11-26T22:17:25.869Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-26T22:17:25.869Z] GC before operation: completed in 123.585 ms, heap usage 241.150 MB -> 62.876 MB. [2025-11-26T22:17:31.691Z] ====== als (apache-spark) [default], iteration 2 completed (4721.944 ms) ====== [2025-11-26T22:17:31.691Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-26T22:17:31.691Z] GC before operation: completed in 118.295 ms, heap usage 1.136 GB -> 64.615 MB. [2025-11-26T22:17:36.358Z] ====== als (apache-spark) [default], iteration 3 completed (4682.752 ms) ====== [2025-11-26T22:17:36.358Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-26T22:17:36.358Z] GC before operation: completed in 125.085 ms, heap usage 839.465 MB -> 65.130 MB. [2025-11-26T22:17:40.972Z] ====== als (apache-spark) [default], iteration 4 completed (4489.789 ms) ====== [2025-11-26T22:17:40.972Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-26T22:17:40.972Z] GC before operation: completed in 117.510 ms, heap usage 528.243 MB -> 65.141 MB. [2025-11-26T22:17:45.526Z] ====== als (apache-spark) [default], iteration 5 completed (4482.940 ms) ====== [2025-11-26T22:17:45.526Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-26T22:17:45.526Z] GC before operation: completed in 114.958 ms, heap usage 491.352 MB -> 65.757 MB. [2025-11-26T22:17:50.132Z] ====== als (apache-spark) [default], iteration 6 completed (4384.106 ms) ====== [2025-11-26T22:17:50.132Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-26T22:17:50.132Z] GC before operation: completed in 126.685 ms, heap usage 484.360 MB -> 66.390 MB. [2025-11-26T22:17:55.680Z] ====== als (apache-spark) [default], iteration 7 completed (4516.023 ms) ====== [2025-11-26T22:17:55.680Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-26T22:17:55.680Z] GC before operation: completed in 121.001 ms, heap usage 543.523 MB -> 66.870 MB. [2025-11-26T22:18:00.262Z] ====== als (apache-spark) [default], iteration 8 completed (4512.976 ms) ====== [2025-11-26T22:18:00.262Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-26T22:18:00.262Z] GC before operation: completed in 125.062 ms, heap usage 470.811 MB -> 67.344 MB. [2025-11-26T22:18:04.840Z] ====== als (apache-spark) [default], iteration 9 completed (4458.131 ms) ====== [2025-11-26T22:18:04.840Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-26T22:18:04.840Z] GC before operation: completed in 126.646 ms, heap usage 563.152 MB -> 67.739 MB. [2025-11-26T22:18:09.403Z] ====== als (apache-spark) [default], iteration 10 completed (4491.918 ms) ====== [2025-11-26T22:18:09.403Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-26T22:18:09.403Z] GC before operation: completed in 131.150 ms, heap usage 489.915 MB -> 67.955 MB. [2025-11-26T22:18:13.998Z] ====== als (apache-spark) [default], iteration 11 completed (4448.952 ms) ====== [2025-11-26T22:18:13.998Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-26T22:18:14.777Z] GC before operation: completed in 135.114 ms, heap usage 136.520 MB -> 67.495 MB. [2025-11-26T22:18:19.335Z] ====== als (apache-spark) [default], iteration 12 completed (4396.049 ms) ====== [2025-11-26T22:18:19.335Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-26T22:18:19.335Z] GC before operation: completed in 129.369 ms, heap usage 775.076 MB -> 68.426 MB. [2025-11-26T22:18:23.915Z] ====== als (apache-spark) [default], iteration 13 completed (4311.561 ms) ====== [2025-11-26T22:18:23.915Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-26T22:18:23.915Z] GC before operation: completed in 123.624 ms, heap usage 597.928 MB -> 68.885 MB. [2025-11-26T22:18:28.595Z] ====== als (apache-spark) [default], iteration 14 completed (4471.960 ms) ====== [2025-11-26T22:18:28.595Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-26T22:18:28.595Z] GC before operation: completed in 125.685 ms, heap usage 345.370 MB -> 68.374 MB. [2025-11-26T22:18:33.263Z] ====== als (apache-spark) [default], iteration 15 completed (4488.901 ms) ====== [2025-11-26T22:18:33.263Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-26T22:18:33.263Z] GC before operation: completed in 140.602 ms, heap usage 333.243 MB -> 68.629 MB. [2025-11-26T22:18:37.991Z] ====== als (apache-spark) [default], iteration 16 completed (4397.095 ms) ====== [2025-11-26T22:18:37.991Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-26T22:18:37.991Z] GC before operation: completed in 123.302 ms, heap usage 295.890 MB -> 69.060 MB. [2025-11-26T22:18:46.160Z] ====== als (apache-spark) [default], iteration 17 completed (4364.140 ms) ====== [2025-11-26T22:18:46.160Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-26T22:18:46.160Z] GC before operation: completed in 132.373 ms, heap usage 418.233 MB -> 69.604 MB. [2025-11-26T22:18:47.800Z] ====== als (apache-spark) [default], iteration 18 completed (4576.622 ms) ====== [2025-11-26T22:18:47.800Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-26T22:18:47.800Z] GC before operation: completed in 126.544 ms, heap usage 433.812 MB -> 69.805 MB. [2025-11-26T22:18:52.341Z] ====== als (apache-spark) [default], iteration 19 completed (4431.261 ms) ====== [2025-11-26T22:18:52.341Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-26T22:18:52.341Z] GC before operation: completed in 144.317 ms, heap usage 415.421 MB -> 70.140 MB. [2025-11-26T22:18:56.871Z] ====== als (apache-spark) [default], iteration 20 completed (4411.939 ms) ====== [2025-11-26T22:18:56.871Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-26T22:18:56.871Z] GC before operation: completed in 138.230 ms, heap usage 402.300 MB -> 70.406 MB. [2025-11-26T22:19:01.440Z] ====== als (apache-spark) [default], iteration 21 completed (4365.319 ms) ====== [2025-11-26T22:19:01.440Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-26T22:19:01.440Z] GC before operation: completed in 134.749 ms, heap usage 404.695 MB -> 70.668 MB. [2025-11-26T22:19:05.997Z] ====== als (apache-spark) [default], iteration 22 completed (4323.117 ms) ====== [2025-11-26T22:19:05.997Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-26T22:19:05.997Z] GC before operation: completed in 137.972 ms, heap usage 476.026 MB -> 70.944 MB. [2025-11-26T22:19:10.515Z] ====== als (apache-spark) [default], iteration 23 completed (4429.860 ms) ====== [2025-11-26T22:19:10.515Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-26T22:19:11.295Z] GC before operation: completed in 151.302 ms, heap usage 321.779 MB -> 70.858 MB. [2025-11-26T22:19:15.378Z] ====== als (apache-spark) [default], iteration 24 completed (4523.678 ms) ====== [2025-11-26T22:19:15.378Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-26T22:19:15.378Z] GC before operation: completed in 142.670 ms, heap usage 600.893 MB -> 71.532 MB. [2025-11-26T22:19:19.881Z] ====== als (apache-spark) [default], iteration 25 completed (4281.894 ms) ====== [2025-11-26T22:19:19.881Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-26T22:19:20.665Z] GC before operation: completed in 139.582 ms, heap usage 325.299 MB -> 71.352 MB. [2025-11-26T22:19:25.223Z] ====== als (apache-spark) [default], iteration 26 completed (4305.064 ms) ====== [2025-11-26T22:19:25.223Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-26T22:19:25.223Z] GC before operation: completed in 135.787 ms, heap usage 397.735 MB -> 71.691 MB. [2025-11-26T22:19:29.736Z] ====== als (apache-spark) [default], iteration 27 completed (4549.256 ms) ====== [2025-11-26T22:19:29.736Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-26T22:19:29.736Z] GC before operation: completed in 131.074 ms, heap usage 486.781 MB -> 71.793 MB. [2025-11-26T22:19:34.293Z] ====== als (apache-spark) [default], iteration 28 completed (4454.623 ms) ====== [2025-11-26T22:19:34.293Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-26T22:19:34.293Z] GC before operation: completed in 136.371 ms, heap usage 271.941 MB -> 71.599 MB. [2025-11-26T22:19:38.826Z] ====== als (apache-spark) [default], iteration 29 completed (4500.088 ms) ====== [2025-11-26T22:19:39.602Z] ----------------------------------- [2025-11-26T22:19:39.602Z] renaissance-als_0_PASSED [2025-11-26T22:19:39.602Z] ----------------------------------- [2025-11-26T22:19:39.602Z] [2025-11-26T22:19:39.602Z] TEST TEARDOWN: [2025-11-26T22:19:39.602Z] Nothing to be done for teardown. [2025-11-26T22:19:39.602Z] renaissance-als_0 Finish Time: Wed Nov 26 22:19:39 2025 Epoch Time (ms): 1764195579213