[2025-06-11T21:09:29.035Z] Running test renaissance-als_0 ... [2025-06-11T21:09:29.035Z] =============================================== [2025-06-11T21:09:29.035Z] renaissance-als_0 Start Time: Wed Jun 11 21:09:28 2025 Epoch Time (ms): 1749676168312 [2025-06-11T21:09:29.035Z] variation: NoOptions [2025-06-11T21:09:29.035Z] JVM_OPTIONS: [2025-06-11T21:09:29.035Z] { \ [2025-06-11T21:09:29.035Z] echo ""; echo "TEST SETUP:"; \ [2025-06-11T21:09:29.035Z] echo "Nothing to be done for setup."; \ [2025-06-11T21:09:29.035Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17496761176898/renaissance-als_0"; \ [2025-06-11T21:09:29.035Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17496761176898/renaissance-als_0"; \ [2025-06-11T21:09:29.035Z] echo ""; echo "TESTING:"; \ [2025-06-11T21:09:29.035Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17496761176898/renaissance-als_0"/als.json" als; \ [2025-06-11T21:09:29.035Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17496761176898/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-11T21:09:29.035Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-11T21:09:29.035Z] echo "Nothing to be done for teardown."; \ [2025-06-11T21:09:29.035Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17496761176898/TestTargetResult"; [2025-06-11T21:09:29.035Z] [2025-06-11T21:09:29.035Z] TEST SETUP: [2025-06-11T21:09:29.035Z] Nothing to be done for setup. [2025-06-11T21:09:29.035Z] [2025-06-11T21:09:29.035Z] TESTING: [2025-06-11T21:09:37.647Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-11T21:09:43.548Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-11T21:09:43.548Z] GC before operation: completed in 61.295 ms, heap usage 286.821 MB -> 34.915 MB. [2025-06-11T21:10:19.933Z] ====== als (apache-spark) [default], iteration 0 completed (30994.495 ms) ====== [2025-06-11T21:10:19.933Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-11T21:10:19.933Z] GC before operation: completed in 170.292 ms, heap usage 217.162 MB -> 61.663 MB. [2025-06-11T21:10:30.294Z] ====== als (apache-spark) [default], iteration 1 completed (13211.806 ms) ====== [2025-06-11T21:10:30.294Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-11T21:10:30.294Z] GC before operation: completed in 150.195 ms, heap usage 140.442 MB -> 62.473 MB. [2025-06-11T21:10:42.440Z] ====== als (apache-spark) [default], iteration 2 completed (10787.989 ms) ====== [2025-06-11T21:10:42.440Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-11T21:10:42.440Z] GC before operation: completed in 189.491 ms, heap usage 167.662 MB -> 63.199 MB. [2025-06-11T21:10:53.330Z] ====== als (apache-spark) [default], iteration 3 completed (10847.073 ms) ====== [2025-06-11T21:10:53.330Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-11T21:10:53.330Z] GC before operation: completed in 164.112 ms, heap usage 363.678 MB -> 63.916 MB. [2025-06-11T21:11:03.717Z] ====== als (apache-spark) [default], iteration 4 completed (10157.127 ms) ====== [2025-06-11T21:11:03.717Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-11T21:11:03.717Z] GC before operation: completed in 172.897 ms, heap usage 436.511 MB -> 64.635 MB. [2025-06-11T21:11:14.086Z] ====== als (apache-spark) [default], iteration 5 completed (9940.625 ms) ====== [2025-06-11T21:11:14.086Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-11T21:11:14.086Z] GC before operation: completed in 156.467 ms, heap usage 206.134 MB -> 64.814 MB. [2025-06-11T21:11:24.344Z] ====== als (apache-spark) [default], iteration 6 completed (9824.171 ms) ====== [2025-06-11T21:11:24.345Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-11T21:11:24.345Z] GC before operation: completed in 176.503 ms, heap usage 215.165 MB -> 65.423 MB. [2025-06-11T21:11:36.504Z] ====== als (apache-spark) [default], iteration 7 completed (10963.280 ms) ====== [2025-06-11T21:11:36.504Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-11T21:11:36.504Z] GC before operation: completed in 204.887 ms, heap usage 156.228 MB -> 65.960 MB. [2025-06-11T21:11:45.132Z] ====== als (apache-spark) [default], iteration 8 completed (9555.728 ms) ====== [2025-06-11T21:11:45.132Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-11T21:11:45.972Z] GC before operation: completed in 192.825 ms, heap usage 432.272 MB -> 66.861 MB. [2025-06-11T21:11:56.807Z] ====== als (apache-spark) [default], iteration 9 completed (9703.660 ms) ====== [2025-06-11T21:11:56.807Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-11T21:11:56.807Z] GC before operation: completed in 142.193 ms, heap usage 88.687 MB -> 66.704 MB. [2025-06-11T21:12:05.473Z] ====== als (apache-spark) [default], iteration 10 completed (9514.259 ms) ====== [2025-06-11T21:12:05.473Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-11T21:12:05.473Z] GC before operation: completed in 190.937 ms, heap usage 268.209 MB -> 67.027 MB. [2025-06-11T21:12:15.794Z] ====== als (apache-spark) [default], iteration 11 completed (9339.522 ms) ====== [2025-06-11T21:12:15.794Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-11T21:12:15.794Z] GC before operation: completed in 220.911 ms, heap usage 309.837 MB -> 67.512 MB. [2025-06-11T21:12:26.088Z] ====== als (apache-spark) [default], iteration 12 completed (9459.722 ms) ====== [2025-06-11T21:12:26.088Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-11T21:12:26.088Z] GC before operation: completed in 218.564 ms, heap usage 378.879 MB -> 67.754 MB. [2025-06-11T21:12:34.700Z] ====== als (apache-spark) [default], iteration 13 completed (9311.267 ms) ====== [2025-06-11T21:12:34.700Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-11T21:12:35.574Z] GC before operation: completed in 208.338 ms, heap usage 274.070 MB -> 67.875 MB. [2025-06-11T21:12:45.937Z] ====== als (apache-spark) [default], iteration 14 completed (9019.048 ms) ====== [2025-06-11T21:12:45.937Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-11T21:12:45.937Z] GC before operation: completed in 157.082 ms, heap usage 135.778 MB -> 68.007 MB. [2025-06-11T21:12:54.565Z] ====== als (apache-spark) [default], iteration 15 completed (9035.758 ms) ====== [2025-06-11T21:12:54.565Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-11T21:12:54.565Z] GC before operation: completed in 154.914 ms, heap usage 233.260 MB -> 68.456 MB. [2025-06-11T21:13:03.286Z] ====== als (apache-spark) [default], iteration 16 completed (9159.430 ms) ====== [2025-06-11T21:13:03.286Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-11T21:13:04.095Z] GC before operation: completed in 214.018 ms, heap usage 267.831 MB -> 68.618 MB. [2025-06-11T21:13:12.726Z] ====== als (apache-spark) [default], iteration 17 completed (9182.542 ms) ====== [2025-06-11T21:13:12.726Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-11T21:13:13.593Z] GC before operation: completed in 172.915 ms, heap usage 489.060 MB -> 69.124 MB. [2025-06-11T21:13:22.221Z] ====== als (apache-spark) [default], iteration 18 completed (9004.042 ms) ====== [2025-06-11T21:13:22.221Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-11T21:13:23.074Z] GC before operation: completed in 179.899 ms, heap usage 243.595 MB -> 69.087 MB. [2025-06-11T21:13:31.793Z] ====== als (apache-spark) [default], iteration 19 completed (8766.775 ms) ====== [2025-06-11T21:13:31.793Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-11T21:13:31.793Z] GC before operation: completed in 154.343 ms, heap usage 371.942 MB -> 69.435 MB. [2025-06-11T21:13:42.232Z] ====== als (apache-spark) [default], iteration 20 completed (9426.114 ms) ====== [2025-06-11T21:13:42.232Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-11T21:13:42.232Z] GC before operation: completed in 170.902 ms, heap usage 122.818 MB -> 69.505 MB. [2025-06-11T21:13:50.856Z] ====== als (apache-spark) [default], iteration 21 completed (9193.877 ms) ====== [2025-06-11T21:13:50.856Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-11T21:13:51.673Z] GC before operation: completed in 221.372 ms, heap usage 231.800 MB -> 69.929 MB. [2025-06-11T21:14:00.305Z] ====== als (apache-spark) [default], iteration 22 completed (9004.141 ms) ====== [2025-06-11T21:14:00.305Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-11T21:14:01.120Z] GC before operation: completed in 195.127 ms, heap usage 142.548 MB -> 70.048 MB. [2025-06-11T21:14:09.808Z] ====== als (apache-spark) [default], iteration 23 completed (8930.481 ms) ====== [2025-06-11T21:14:09.808Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-11T21:14:09.808Z] GC before operation: completed in 188.339 ms, heap usage 575.994 MB -> 70.830 MB. [2025-06-11T21:14:20.104Z] ====== als (apache-spark) [default], iteration 24 completed (8823.212 ms) ====== [2025-06-11T21:14:20.104Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-11T21:14:20.104Z] GC before operation: completed in 171.403 ms, heap usage 114.051 MB -> 70.745 MB. [2025-06-11T21:14:28.774Z] ====== als (apache-spark) [default], iteration 25 completed (8864.588 ms) ====== [2025-06-11T21:14:28.774Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-11T21:14:28.774Z] GC before operation: completed in 170.655 ms, heap usage 405.859 MB -> 71.035 MB. [2025-06-11T21:14:37.393Z] ====== als (apache-spark) [default], iteration 26 completed (8811.759 ms) ====== [2025-06-11T21:14:37.393Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-11T21:14:37.393Z] GC before operation: completed in 148.186 ms, heap usage 131.073 MB -> 71.113 MB. [2025-06-11T21:14:46.021Z] ====== als (apache-spark) [default], iteration 27 completed (8514.054 ms) ====== [2025-06-11T21:14:46.021Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-11T21:14:46.834Z] GC before operation: completed in 147.670 ms, heap usage 246.242 MB -> 71.487 MB. [2025-06-11T21:14:55.444Z] ====== als (apache-spark) [default], iteration 28 completed (8558.887 ms) ====== [2025-06-11T21:14:55.444Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-11T21:14:55.444Z] GC before operation: completed in 197.317 ms, heap usage 244.663 MB -> 71.276 MB. [2025-06-11T21:15:04.632Z] ====== als (apache-spark) [default], iteration 29 completed (8783.037 ms) ====== [2025-06-11T21:15:05.443Z] ----------------------------------- [2025-06-11T21:15:05.443Z] renaissance-als_0_PASSED [2025-06-11T21:15:05.443Z] ----------------------------------- [2025-06-11T21:15:05.443Z] [2025-06-11T21:15:05.443Z] TEST TEARDOWN: [2025-06-11T21:15:05.443Z] Nothing to be done for teardown. [2025-06-11T21:15:05.443Z] renaissance-als_0 Finish Time: Wed Jun 11 21:15:05 2025 Epoch Time (ms): 1749676505350