[2025-06-30T17:43:02.721Z] Running test renaissance-als_0 ... [2025-06-30T17:43:02.721Z] =============================================== [2025-06-30T17:43:02.721Z] renaissance-als_0 Start Time: Mon Jun 30 17:43:02 2025 Epoch Time (ms): 1751305382010 [2025-06-30T17:43:02.721Z] variation: NoOptions [2025-06-30T17:43:02.721Z] JVM_OPTIONS: [2025-06-30T17:43:02.721Z] { \ [2025-06-30T17:43:02.721Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T17:43:02.721Z] echo "Nothing to be done for setup."; \ [2025-06-30T17:43:02.721Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513052866011/renaissance-als_0"; \ [2025-06-30T17:43:02.721Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513052866011/renaissance-als_0"; \ [2025-06-30T17:43:02.721Z] echo ""; echo "TESTING:"; \ [2025-06-30T17:43:02.722Z] "/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_17513052866011/renaissance-als_0"/als.json" als; \ [2025-06-30T17:43:02.722Z] 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_17513052866011/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T17:43:02.722Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T17:43:02.722Z] echo "Nothing to be done for teardown."; \ [2025-06-30T17:43:02.722Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513052866011/TestTargetResult"; [2025-06-30T17:43:02.722Z] [2025-06-30T17:43:02.722Z] TEST SETUP: [2025-06-30T17:43:02.722Z] Nothing to be done for setup. [2025-06-30T17:43:02.722Z] [2025-06-30T17:43:02.722Z] TESTING: [2025-06-30T17:43:32.875Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-06-30T17:43:37.342Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-30T17:43:37.342Z] GC before operation: completed in 69.257 ms, heap usage 552.411 MB -> 34.526 MB. [2025-06-30T17:44:03.178Z] ====== als (apache-spark) [default], iteration 0 completed (22307.816 ms) ====== [2025-06-30T17:44:03.178Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-30T17:44:03.178Z] GC before operation: completed in 138.355 ms, heap usage 322.396 MB -> 61.311 MB. [2025-06-30T17:44:19.335Z] ====== als (apache-spark) [default], iteration 1 completed (18721.468 ms) ====== [2025-06-30T17:44:19.335Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-30T17:44:19.335Z] GC before operation: completed in 126.690 ms, heap usage 502.263 MB -> 62.534 MB. [2025-06-30T17:44:38.246Z] ====== als (apache-spark) [default], iteration 2 completed (18136.519 ms) ====== [2025-06-30T17:44:38.246Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-30T17:44:38.246Z] GC before operation: completed in 121.550 ms, heap usage 727.835 MB -> 63.334 MB. [2025-06-30T17:44:57.160Z] ====== als (apache-spark) [default], iteration 3 completed (17821.246 ms) ====== [2025-06-30T17:44:57.160Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-30T17:44:57.160Z] GC before operation: completed in 136.245 ms, heap usage 1.725 GB -> 65.083 MB. [2025-06-30T17:45:11.286Z] ====== als (apache-spark) [default], iteration 4 completed (14272.489 ms) ====== [2025-06-30T17:45:11.286Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-30T17:45:11.286Z] GC before operation: completed in 134.065 ms, heap usage 1.297 GB -> 65.195 MB. [2025-06-30T17:45:27.479Z] ====== als (apache-spark) [default], iteration 5 completed (15399.372 ms) ====== [2025-06-30T17:45:27.480Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-30T17:45:27.480Z] GC before operation: completed in 116.775 ms, heap usage 454.155 MB -> 64.952 MB. [2025-06-30T17:45:41.256Z] ====== als (apache-spark) [default], iteration 6 completed (14903.086 ms) ====== [2025-06-30T17:45:41.256Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-30T17:45:41.256Z] GC before operation: completed in 127.308 ms, heap usage 902.880 MB -> 65.773 MB. [2025-06-30T17:46:00.160Z] ====== als (apache-spark) [default], iteration 7 completed (16810.830 ms) ====== [2025-06-30T17:46:00.160Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-30T17:46:00.160Z] GC before operation: completed in 130.836 ms, heap usage 844.898 MB -> 66.243 MB. [2025-06-30T17:46:13.985Z] ====== als (apache-spark) [default], iteration 8 completed (14076.303 ms) ====== [2025-06-30T17:46:13.985Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-30T17:46:13.985Z] GC before operation: completed in 102.329 ms, heap usage 353.870 MB -> 66.118 MB. [2025-06-30T17:46:27.806Z] ====== als (apache-spark) [default], iteration 9 completed (14536.827 ms) ====== [2025-06-30T17:46:27.806Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-30T17:46:27.806Z] GC before operation: completed in 130.945 ms, heap usage 188.886 MB -> 66.192 MB. [2025-06-30T17:46:44.186Z] ====== als (apache-spark) [default], iteration 10 completed (14590.541 ms) ====== [2025-06-30T17:46:44.187Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-30T17:46:44.187Z] GC before operation: completed in 126.673 ms, heap usage 680.929 MB -> 67.168 MB. [2025-06-30T17:46:58.011Z] ====== als (apache-spark) [default], iteration 11 completed (15055.277 ms) ====== [2025-06-30T17:46:58.011Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-30T17:46:58.011Z] GC before operation: completed in 129.083 ms, heap usage 834.065 MB -> 67.679 MB. [2025-06-30T17:47:14.218Z] ====== als (apache-spark) [default], iteration 12 completed (14111.120 ms) ====== [2025-06-30T17:47:14.218Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-30T17:47:14.218Z] GC before operation: completed in 105.845 ms, heap usage 286.862 MB -> 67.114 MB. [2025-06-30T17:47:28.449Z] ====== als (apache-spark) [default], iteration 13 completed (14163.541 ms) ====== [2025-06-30T17:47:28.449Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-30T17:47:28.449Z] GC before operation: completed in 120.302 ms, heap usage 409.593 MB -> 67.555 MB. [2025-06-30T17:47:42.248Z] ====== als (apache-spark) [default], iteration 14 completed (14286.228 ms) ====== [2025-06-30T17:47:42.248Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-30T17:47:42.248Z] GC before operation: completed in 115.391 ms, heap usage 543.154 MB -> 67.910 MB. [2025-06-30T17:47:58.406Z] ====== als (apache-spark) [default], iteration 15 completed (15422.116 ms) ====== [2025-06-30T17:47:58.406Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-30T17:47:58.406Z] GC before operation: completed in 121.527 ms, heap usage 457.881 MB -> 68.190 MB. [2025-06-30T17:48:12.253Z] ====== als (apache-spark) [default], iteration 16 completed (13479.647 ms) ====== [2025-06-30T17:48:12.253Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-30T17:48:12.253Z] GC before operation: completed in 116.074 ms, heap usage 452.548 MB -> 68.306 MB. [2025-06-30T17:48:26.028Z] ====== als (apache-spark) [default], iteration 17 completed (13987.963 ms) ====== [2025-06-30T17:48:26.028Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-30T17:48:26.028Z] GC before operation: completed in 121.538 ms, heap usage 788.752 MB -> 69.109 MB. [2025-06-30T17:48:39.818Z] ====== als (apache-spark) [default], iteration 18 completed (13647.593 ms) ====== [2025-06-30T17:48:39.818Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-30T17:48:39.818Z] GC before operation: completed in 97.863 ms, heap usage 564.051 MB -> 69.034 MB. [2025-06-30T17:48:53.633Z] ====== als (apache-spark) [default], iteration 19 completed (13571.508 ms) ====== [2025-06-30T17:48:53.633Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-30T17:48:53.633Z] GC before operation: completed in 130.286 ms, heap usage 92.703 MB -> 68.703 MB. [2025-06-30T17:49:09.788Z] ====== als (apache-spark) [default], iteration 20 completed (14361.528 ms) ====== [2025-06-30T17:49:09.788Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-30T17:49:09.788Z] GC before operation: completed in 106.213 ms, heap usage 145.526 MB -> 68.889 MB. [2025-06-30T17:49:23.591Z] ====== als (apache-spark) [default], iteration 21 completed (13741.989 ms) ====== [2025-06-30T17:49:23.591Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-30T17:49:23.591Z] GC before operation: completed in 123.963 ms, heap usage 427.019 MB -> 69.676 MB. [2025-06-30T17:49:35.648Z] ====== als (apache-spark) [default], iteration 22 completed (13434.811 ms) ====== [2025-06-30T17:49:35.648Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-30T17:49:35.648Z] GC before operation: completed in 109.754 ms, heap usage 567.843 MB -> 70.093 MB. [2025-06-30T17:49:49.482Z] ====== als (apache-spark) [default], iteration 23 completed (13647.826 ms) ====== [2025-06-30T17:49:49.482Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-30T17:49:50.259Z] GC before operation: completed in 129.582 ms, heap usage 197.564 MB -> 69.792 MB. [2025-06-30T17:50:04.131Z] ====== als (apache-spark) [default], iteration 24 completed (14101.955 ms) ====== [2025-06-30T17:50:04.131Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-30T17:50:04.131Z] GC before operation: completed in 103.666 ms, heap usage 500.775 MB -> 70.453 MB. [2025-06-30T17:50:20.301Z] ====== als (apache-spark) [default], iteration 25 completed (14920.134 ms) ====== [2025-06-30T17:50:20.301Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-30T17:50:20.301Z] GC before operation: completed in 97.768 ms, heap usage 616.937 MB -> 70.915 MB. [2025-06-30T17:50:36.504Z] ====== als (apache-spark) [default], iteration 26 completed (16604.687 ms) ====== [2025-06-30T17:50:36.504Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-30T17:50:36.504Z] GC before operation: completed in 113.329 ms, heap usage 718.914 MB -> 71.222 MB. [2025-06-30T17:50:55.417Z] ====== als (apache-spark) [default], iteration 27 completed (17906.213 ms) ====== [2025-06-30T17:50:55.417Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-30T17:50:55.417Z] GC before operation: completed in 99.358 ms, heap usage 476.510 MB -> 71.234 MB. [2025-06-30T17:51:14.384Z] ====== als (apache-spark) [default], iteration 28 completed (18069.640 ms) ====== [2025-06-30T17:51:14.384Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-30T17:51:14.384Z] GC before operation: completed in 131.518 ms, heap usage 966.219 MB -> 72.053 MB. [2025-06-30T17:51:36.666Z] ====== als (apache-spark) [default], iteration 29 completed (20781.911 ms) ====== [2025-06-30T17:51:36.666Z] ----------------------------------- [2025-06-30T17:51:36.666Z] renaissance-als_0_PASSED [2025-06-30T17:51:36.666Z] ----------------------------------- [2025-06-30T17:51:36.666Z] [2025-06-30T17:51:36.666Z] TEST TEARDOWN: [2025-06-30T17:51:36.666Z] Nothing to be done for teardown. [2025-06-30T17:51:36.666Z] renaissance-als_0 Finish Time: Mon Jun 30 17:51:34 2025 Epoch Time (ms): 1751305894175