[2025-05-21T20:32:43.569Z] Running test renaissance-als_0 ... [2025-05-21T20:32:43.569Z] =============================================== [2025-05-21T20:32:43.569Z] renaissance-als_0 Start Time: Wed May 21 20:32:42 2025 Epoch Time (ms): 1747859562681 [2025-05-21T20:32:43.569Z] variation: NoOptions [2025-05-21T20:32:43.569Z] JVM_OPTIONS: [2025-05-21T20:32:43.569Z] { \ [2025-05-21T20:32:43.569Z] echo ""; echo "TEST SETUP:"; \ [2025-05-21T20:32:43.569Z] echo "Nothing to be done for setup."; \ [2025-05-21T20:32:43.569Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17478595149418/renaissance-als_0"; \ [2025-05-21T20:32:43.569Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17478595149418/renaissance-als_0"; \ [2025-05-21T20:32:43.569Z] echo ""; echo "TESTING:"; \ [2025-05-21T20:32:43.569Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17478595149418/renaissance-als_0"/als.json" als; \ [2025-05-21T20:32:43.569Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17478595149418/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-21T20:32:43.569Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-21T20:32:43.569Z] echo "Nothing to be done for teardown."; \ [2025-05-21T20:32:43.569Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17478595149418/TestTargetResult"; [2025-05-21T20:32:43.569Z] [2025-05-21T20:32:43.569Z] TEST SETUP: [2025-05-21T20:32:43.569Z] Nothing to be done for setup. [2025-05-21T20:32:43.569Z] [2025-05-21T20:32:43.569Z] TESTING: [2025-05-21T20:32:54.019Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-05-21T20:33:02.161Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-21T20:33:02.161Z] GC before operation: completed in 61.119 ms, heap usage 180.313 MB -> 36.602 MB. [2025-05-21T20:33:30.650Z] ====== als (apache-spark) [default], iteration 0 completed (25443.072 ms) ====== [2025-05-21T20:33:30.650Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-21T20:33:30.650Z] GC before operation: completed in 274.885 ms, heap usage 1.147 GB -> 64.624 MB. [2025-05-21T20:33:36.028Z] ====== als (apache-spark) [default], iteration 1 completed (6638.355 ms) ====== [2025-05-21T20:33:36.028Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-21T20:33:36.028Z] GC before operation: completed in 197.529 ms, heap usage 867.814 MB -> 65.386 MB. [2025-05-21T20:33:42.721Z] ====== als (apache-spark) [default], iteration 2 completed (5778.498 ms) ====== [2025-05-21T20:33:42.721Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-21T20:33:42.721Z] GC before operation: completed in 177.199 ms, heap usage 620.820 MB -> 66.067 MB. [2025-05-21T20:33:48.096Z] ====== als (apache-spark) [default], iteration 3 completed (5343.359 ms) ====== [2025-05-21T20:33:48.096Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-21T20:33:48.096Z] GC before operation: completed in 167.424 ms, heap usage 958.827 MB -> 66.673 MB. [2025-05-21T20:33:53.462Z] ====== als (apache-spark) [default], iteration 4 completed (5217.728 ms) ====== [2025-05-21T20:33:53.462Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-21T20:33:53.462Z] GC before operation: completed in 177.305 ms, heap usage 608.799 MB -> 67.016 MB. [2025-05-21T20:33:58.844Z] ====== als (apache-spark) [default], iteration 5 completed (5164.007 ms) ====== [2025-05-21T20:33:58.844Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-21T20:33:58.844Z] GC before operation: completed in 140.386 ms, heap usage 1003.314 MB -> 68.102 MB. [2025-05-21T20:34:04.214Z] ====== als (apache-spark) [default], iteration 6 completed (5040.480 ms) ====== [2025-05-21T20:34:04.214Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-21T20:34:05.165Z] GC before operation: completed in 190.406 ms, heap usage 556.804 MB -> 68.017 MB. [2025-05-21T20:34:10.528Z] ====== als (apache-spark) [default], iteration 7 completed (5186.659 ms) ====== [2025-05-21T20:34:10.528Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-21T20:34:10.528Z] GC before operation: completed in 197.357 ms, heap usage 854.826 MB -> 68.984 MB. [2025-05-21T20:34:17.216Z] ====== als (apache-spark) [default], iteration 8 completed (5667.615 ms) ====== [2025-05-21T20:34:17.216Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-21T20:34:17.216Z] GC before operation: completed in 242.608 ms, heap usage 1.337 GB -> 70.019 MB. [2025-05-21T20:34:21.370Z] ====== als (apache-spark) [default], iteration 9 completed (4842.311 ms) ====== [2025-05-21T20:34:21.370Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-21T20:34:22.322Z] GC before operation: completed in 243.495 ms, heap usage 150.765 MB -> 68.798 MB. [2025-05-21T20:34:27.692Z] ====== als (apache-spark) [default], iteration 10 completed (5062.236 ms) ====== [2025-05-21T20:34:27.692Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-21T20:34:27.692Z] GC before operation: completed in 235.217 ms, heap usage 1.087 GB -> 70.420 MB. [2025-05-21T20:34:33.160Z] ====== als (apache-spark) [default], iteration 11 completed (4742.225 ms) ====== [2025-05-21T20:34:33.160Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-21T20:34:33.160Z] GC before operation: completed in 204.929 ms, heap usage 725.440 MB -> 69.958 MB. [2025-05-21T20:34:38.528Z] ====== als (apache-spark) [default], iteration 12 completed (4880.837 ms) ====== [2025-05-21T20:34:38.528Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-21T20:34:38.528Z] GC before operation: completed in 238.966 ms, heap usage 1.265 GB -> 70.944 MB. [2025-05-21T20:34:42.768Z] ====== als (apache-spark) [default], iteration 13 completed (4353.987 ms) ====== [2025-05-21T20:34:42.768Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-21T20:34:42.768Z] GC before operation: completed in 162.873 ms, heap usage 455.085 MB -> 70.159 MB. [2025-05-21T20:34:48.137Z] ====== als (apache-spark) [default], iteration 14 completed (4320.905 ms) ====== [2025-05-21T20:34:48.137Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-21T20:34:48.137Z] GC before operation: completed in 175.945 ms, heap usage 700.537 MB -> 70.871 MB. [2025-05-21T20:34:54.230Z] ====== als (apache-spark) [default], iteration 15 completed (5692.697 ms) ====== [2025-05-21T20:34:54.230Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-21T20:34:54.230Z] GC before operation: completed in 237.605 ms, heap usage 1.736 GB -> 72.251 MB. [2025-05-21T20:34:59.608Z] ====== als (apache-spark) [default], iteration 16 completed (4414.061 ms) ====== [2025-05-21T20:34:59.608Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-21T20:34:59.608Z] GC before operation: completed in 166.520 ms, heap usage 588.364 MB -> 71.331 MB. [2025-05-21T20:35:03.760Z] ====== als (apache-spark) [default], iteration 17 completed (4311.851 ms) ====== [2025-05-21T20:35:03.760Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-21T20:35:03.760Z] GC before operation: completed in 194.373 ms, heap usage 423.271 MB -> 71.398 MB. [2025-05-21T20:35:09.273Z] ====== als (apache-spark) [default], iteration 18 completed (4628.199 ms) ====== [2025-05-21T20:35:09.273Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-21T20:35:09.274Z] GC before operation: completed in 195.851 ms, heap usage 1.190 GB -> 72.654 MB. [2025-05-21T20:35:14.640Z] ====== als (apache-spark) [default], iteration 19 completed (4611.911 ms) ====== [2025-05-21T20:35:14.640Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-21T20:35:14.640Z] GC before operation: completed in 196.606 ms, heap usage 777.158 MB -> 72.378 MB. [2025-05-21T20:35:18.788Z] ====== als (apache-spark) [default], iteration 20 completed (4463.499 ms) ====== [2025-05-21T20:35:18.788Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-21T20:35:18.788Z] GC before operation: completed in 205.888 ms, heap usage 964.119 MB -> 72.606 MB. [2025-05-21T20:35:24.208Z] ====== als (apache-spark) [default], iteration 21 completed (4514.732 ms) ====== [2025-05-21T20:35:24.208Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-21T20:35:24.208Z] GC before operation: completed in 221.947 ms, heap usage 257.676 MB -> 72.184 MB. [2025-05-21T20:35:28.458Z] ====== als (apache-spark) [default], iteration 22 completed (4394.656 ms) ====== [2025-05-21T20:35:28.458Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-21T20:35:28.459Z] GC before operation: completed in 213.516 ms, heap usage 567.604 MB -> 72.946 MB. [2025-05-21T20:35:34.324Z] ====== als (apache-spark) [default], iteration 23 completed (4555.503 ms) ====== [2025-05-21T20:35:34.324Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-21T20:35:34.324Z] GC before operation: completed in 202.229 ms, heap usage 304.739 MB -> 72.732 MB. [2025-05-21T20:35:38.882Z] ====== als (apache-spark) [default], iteration 24 completed (4543.204 ms) ====== [2025-05-21T20:35:38.882Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-21T20:35:38.882Z] GC before operation: completed in 190.099 ms, heap usage 755.717 MB -> 73.647 MB. [2025-05-21T20:35:43.119Z] ====== als (apache-spark) [default], iteration 25 completed (4390.461 ms) ====== [2025-05-21T20:35:43.119Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-21T20:35:44.068Z] GC before operation: completed in 165.106 ms, heap usage 318.438 MB -> 73.302 MB. [2025-05-21T20:35:48.217Z] ====== als (apache-spark) [default], iteration 26 completed (4291.617 ms) ====== [2025-05-21T20:35:48.218Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-21T20:35:48.218Z] GC before operation: completed in 215.653 ms, heap usage 416.210 MB -> 73.711 MB. [2025-05-21T20:35:53.608Z] ====== als (apache-spark) [default], iteration 27 completed (4568.216 ms) ====== [2025-05-21T20:35:53.608Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-21T20:35:53.608Z] GC before operation: completed in 162.307 ms, heap usage 1.040 GB -> 74.310 MB. [2025-05-21T20:35:57.758Z] ====== als (apache-spark) [default], iteration 28 completed (4341.494 ms) ====== [2025-05-21T20:35:57.758Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-21T20:35:57.758Z] GC before operation: completed in 172.628 ms, heap usage 972.667 MB -> 74.529 MB. [2025-05-21T20:36:03.140Z] ====== als (apache-spark) [default], iteration 29 completed (4241.516 ms) ====== [2025-05-21T20:36:03.140Z] ----------------------------------- [2025-05-21T20:36:03.140Z] renaissance-als_0_PASSED [2025-05-21T20:36:03.140Z] ----------------------------------- [2025-05-21T20:36:03.140Z] [2025-05-21T20:36:03.140Z] TEST TEARDOWN: [2025-05-21T20:36:03.140Z] Nothing to be done for teardown. [2025-05-21T20:36:03.140Z] renaissance-als_0 Finish Time: Wed May 21 20:36:02 2025 Epoch Time (ms): 1747859762903