[2025-09-04T02:16:30.509Z] Running test renaissance-als_0 ... [2025-09-04T02:16:30.509Z] =============================================== [2025-09-04T02:16:30.509Z] renaissance-als_0 Start Time: Thu Sep 4 02:16:29 2025 Epoch Time (ms): 1756952189743 [2025-09-04T02:16:30.509Z] variation: NoOptions [2025-09-04T02:16:30.510Z] JVM_OPTIONS: [2025-09-04T02:16:30.510Z] { \ [2025-09-04T02:16:30.510Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T02:16:30.510Z] echo "Nothing to be done for setup."; \ [2025-09-04T02:16:30.510Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1_rerun/aqa-tests/TKG/../TKG/output_17569521881397/renaissance-als_0"; \ [2025-09-04T02:16:30.510Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1_rerun/aqa-tests/TKG/../TKG/output_17569521881397/renaissance-als_0"; \ [2025-09-04T02:16:30.510Z] echo ""; echo "TESTING:"; \ [2025-09-04T02:16:30.510Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1_rerun/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_aarch64_linux_testList_1_rerun/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1_rerun/aqa-tests/TKG/../TKG/output_17569521881397/renaissance-als_0"/als.json" als; \ [2025-09-04T02:16:30.510Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1_rerun/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1_rerun/aqa-tests/TKG/../TKG/output_17569521881397/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T02:16:30.510Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T02:16:30.510Z] echo "Nothing to be done for teardown."; \ [2025-09-04T02:16:30.510Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1_rerun/aqa-tests/TKG/../TKG/output_17569521881397/TestTargetResult"; [2025-09-04T02:16:30.510Z] [2025-09-04T02:16:30.510Z] TEST SETUP: [2025-09-04T02:16:30.510Z] Nothing to be done for setup. [2025-09-04T02:16:30.510Z] [2025-09-04T02:16:30.510Z] TESTING: [2025-09-04T02:16:44.108Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-04T02:16:52.306Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-04T02:16:52.306Z] GC before operation: completed in 98.639 ms, heap usage 54.650 MB -> 35.192 MB. [2025-09-04T02:17:31.381Z] ====== als (apache-spark) [default], iteration 0 completed (37733.017 ms) ====== [2025-09-04T02:17:31.381Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-04T02:17:31.381Z] GC before operation: completed in 242.320 ms, heap usage 413.204 MB -> 62.175 MB. [2025-09-04T02:17:45.045Z] ====== als (apache-spark) [default], iteration 1 completed (12275.435 ms) ====== [2025-09-04T02:17:45.045Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-04T02:17:45.045Z] GC before operation: completed in 212.664 ms, heap usage 114.254 MB -> 62.753 MB. [2025-09-04T02:17:54.883Z] ====== als (apache-spark) [default], iteration 2 completed (10403.530 ms) ====== [2025-09-04T02:17:54.883Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-04T02:17:54.883Z] GC before operation: completed in 192.390 ms, heap usage 485.068 MB -> 63.876 MB. [2025-09-04T02:18:06.535Z] ====== als (apache-spark) [default], iteration 3 completed (10122.982 ms) ====== [2025-09-04T02:18:06.535Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-04T02:18:06.535Z] GC before operation: completed in 208.185 ms, heap usage 482.031 MB -> 64.324 MB. [2025-09-04T02:18:16.384Z] ====== als (apache-spark) [default], iteration 4 completed (9195.502 ms) ====== [2025-09-04T02:18:16.384Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-04T02:18:16.384Z] GC before operation: completed in 197.010 ms, heap usage 162.263 MB -> 64.508 MB. [2025-09-04T02:18:27.005Z] ====== als (apache-spark) [default], iteration 5 completed (9400.532 ms) ====== [2025-09-04T02:18:27.005Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-04T02:18:27.005Z] GC before operation: completed in 229.804 ms, heap usage 138.465 MB -> 64.954 MB. [2025-09-04T02:18:35.266Z] ====== als (apache-spark) [default], iteration 6 completed (9047.961 ms) ====== [2025-09-04T02:18:35.266Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-04T02:18:35.266Z] GC before operation: completed in 219.823 ms, heap usage 542.093 MB -> 66.096 MB. [2025-09-04T02:18:46.908Z] ====== als (apache-spark) [default], iteration 7 completed (9945.717 ms) ====== [2025-09-04T02:18:46.908Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-04T02:18:46.908Z] GC before operation: completed in 195.916 ms, heap usage 262.161 MB -> 66.218 MB. [2025-09-04T02:18:53.682Z] ====== als (apache-spark) [default], iteration 8 completed (7729.821 ms) ====== [2025-09-04T02:18:53.682Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-04T02:18:54.642Z] GC before operation: completed in 200.113 ms, heap usage 461.035 MB -> 67.141 MB. [2025-09-04T02:19:02.867Z] ====== als (apache-spark) [default], iteration 9 completed (7517.748 ms) ====== [2025-09-04T02:19:02.867Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-04T02:19:02.867Z] GC before operation: completed in 205.017 ms, heap usage 360.500 MB -> 67.085 MB. [2025-09-04T02:19:11.094Z] ====== als (apache-spark) [default], iteration 10 completed (7325.293 ms) ====== [2025-09-04T02:19:11.094Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-04T02:19:11.094Z] GC before operation: completed in 214.444 ms, heap usage 258.445 MB -> 67.392 MB. [2025-09-04T02:19:17.858Z] ====== als (apache-spark) [default], iteration 11 completed (7608.723 ms) ====== [2025-09-04T02:19:17.858Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-04T02:19:18.821Z] GC before operation: completed in 216.669 ms, heap usage 394.725 MB -> 67.626 MB. [2025-09-04T02:19:27.160Z] ====== als (apache-spark) [default], iteration 12 completed (7673.810 ms) ====== [2025-09-04T02:19:27.160Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-04T02:19:27.160Z] GC before operation: completed in 218.525 ms, heap usage 259.692 MB -> 67.722 MB. [2025-09-04T02:19:34.661Z] ====== als (apache-spark) [default], iteration 13 completed (7444.405 ms) ====== [2025-09-04T02:19:34.661Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-04T02:19:34.661Z] GC before operation: completed in 228.001 ms, heap usage 410.827 MB -> 68.272 MB. [2025-09-04T02:19:42.905Z] ====== als (apache-spark) [default], iteration 14 completed (7329.839 ms) ====== [2025-09-04T02:19:42.905Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-04T02:19:42.906Z] GC before operation: completed in 201.154 ms, heap usage 294.321 MB -> 68.245 MB. [2025-09-04T02:19:49.694Z] ====== als (apache-spark) [default], iteration 15 completed (7338.739 ms) ====== [2025-09-04T02:19:49.694Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-04T02:19:50.662Z] GC before operation: completed in 219.079 ms, heap usage 601.097 MB -> 69.039 MB. [2025-09-04T02:19:59.026Z] ====== als (apache-spark) [default], iteration 16 completed (7351.466 ms) ====== [2025-09-04T02:19:59.026Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-04T02:19:59.026Z] GC before operation: completed in 216.919 ms, heap usage 515.686 MB -> 69.114 MB. [2025-09-04T02:20:05.794Z] ====== als (apache-spark) [default], iteration 17 completed (7514.870 ms) ====== [2025-09-04T02:20:05.794Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-04T02:20:05.794Z] GC before operation: completed in 208.202 ms, heap usage 468.733 MB -> 69.422 MB. [2025-09-04T02:20:14.033Z] ====== als (apache-spark) [default], iteration 18 completed (7310.071 ms) ====== [2025-09-04T02:20:14.033Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-04T02:20:14.033Z] GC before operation: completed in 217.536 ms, heap usage 362.139 MB -> 69.360 MB. [2025-09-04T02:20:22.272Z] ====== als (apache-spark) [default], iteration 19 completed (7411.674 ms) ====== [2025-09-04T02:20:22.272Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-04T02:20:22.272Z] GC before operation: completed in 212.877 ms, heap usage 326.754 MB -> 69.644 MB. [2025-09-04T02:20:30.562Z] ====== als (apache-spark) [default], iteration 20 completed (7570.842 ms) ====== [2025-09-04T02:20:30.562Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-04T02:20:30.562Z] GC before operation: completed in 211.778 ms, heap usage 290.325 MB -> 69.813 MB. [2025-09-04T02:20:41.180Z] ====== als (apache-spark) [default], iteration 21 completed (9594.393 ms) ====== [2025-09-04T02:20:41.180Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-04T02:20:41.180Z] GC before operation: completed in 365.695 ms, heap usage 115.801 MB -> 70.040 MB. [2025-09-04T02:20:49.425Z] ====== als (apache-spark) [default], iteration 22 completed (8365.765 ms) ====== [2025-09-04T02:20:49.425Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-04T02:20:49.425Z] GC before operation: completed in 247.694 ms, heap usage 328.255 MB -> 70.559 MB. [2025-09-04T02:20:57.685Z] ====== als (apache-spark) [default], iteration 23 completed (7243.856 ms) ====== [2025-09-04T02:20:57.685Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-04T02:20:57.685Z] GC before operation: completed in 215.947 ms, heap usage 146.325 MB -> 70.519 MB. [2025-09-04T02:21:05.923Z] ====== als (apache-spark) [default], iteration 24 completed (8398.817 ms) ====== [2025-09-04T02:21:05.923Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-04T02:21:05.923Z] GC before operation: completed in 331.313 ms, heap usage 326.870 MB -> 71.018 MB. [2025-09-04T02:21:15.860Z] ====== als (apache-spark) [default], iteration 25 completed (9487.080 ms) ====== [2025-09-04T02:21:15.860Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-04T02:21:15.860Z] GC before operation: completed in 243.346 ms, heap usage 405.436 MB -> 71.225 MB. [2025-09-04T02:21:25.741Z] ====== als (apache-spark) [default], iteration 26 completed (8721.112 ms) ====== [2025-09-04T02:21:25.742Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-04T02:21:25.742Z] GC before operation: completed in 330.332 ms, heap usage 500.372 MB -> 71.721 MB. [2025-09-04T02:21:34.291Z] ====== als (apache-spark) [default], iteration 27 completed (8457.001 ms) ====== [2025-09-04T02:21:34.291Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-04T02:21:34.291Z] GC before operation: completed in 245.175 ms, heap usage 361.890 MB -> 71.776 MB. [2025-09-04T02:21:42.548Z] ====== als (apache-spark) [default], iteration 28 completed (7410.818 ms) ====== [2025-09-04T02:21:42.548Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-04T02:21:42.548Z] GC before operation: completed in 219.248 ms, heap usage 183.529 MB -> 71.734 MB. [2025-09-04T02:21:50.799Z] ====== als (apache-spark) [default], iteration 29 completed (7120.001 ms) ====== [2025-09-04T02:21:51.758Z] ----------------------------------- [2025-09-04T02:21:51.758Z] renaissance-als_0_PASSED [2025-09-04T02:21:51.758Z] ----------------------------------- [2025-09-04T02:21:51.758Z] [2025-09-04T02:21:51.758Z] TEST TEARDOWN: [2025-09-04T02:21:51.758Z] Nothing to be done for teardown. [2025-09-04T02:21:52.715Z] renaissance-als_0 Finish Time: Thu Sep 4 02:21:51 2025 Epoch Time (ms): 1756952511853