[2025-06-19T04:33:27.376Z] Running test renaissance-als_0 ... [2025-06-19T04:33:27.376Z] =============================================== [2025-06-19T04:33:27.376Z] renaissance-als_0 Start Time: Thu Jun 19 04:33:25 2025 Epoch Time (ms): 1750307605927 [2025-06-19T04:33:27.376Z] variation: NoOptions [2025-06-19T04:33:27.376Z] JVM_OPTIONS: [2025-06-19T04:33:27.376Z] { \ [2025-06-19T04:33:27.376Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T04:33:27.376Z] echo "Nothing to be done for setup."; \ [2025-06-19T04:33:27.376Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/renaissance-als_0"; \ [2025-06-19T04:33:27.376Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/renaissance-als_0"; \ [2025-06-19T04:33:27.376Z] echo ""; echo "TESTING:"; \ [2025-06-19T04:33:27.376Z] "/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_17503075588361/renaissance-als_0"/als.json" als; \ [2025-06-19T04:33:27.376Z] 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_17503075588361/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T04:33:27.376Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T04:33:27.376Z] echo "Nothing to be done for teardown."; \ [2025-06-19T04:33:27.376Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17503075588361/TestTargetResult"; [2025-06-19T04:33:27.376Z] [2025-06-19T04:33:27.376Z] TEST SETUP: [2025-06-19T04:33:27.376Z] Nothing to be done for setup. [2025-06-19T04:33:27.376Z] [2025-06-19T04:33:27.376Z] TESTING: [2025-06-19T04:33:33.687Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-19T04:33:39.305Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-19T04:33:39.305Z] GC before operation: completed in 114.918 ms, heap usage 198.320 MB -> 34.980 MB. [2025-06-19T04:34:20.948Z] ====== als (apache-spark) [default], iteration 0 completed (35835.315 ms) ====== [2025-06-19T04:34:20.948Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-19T04:34:20.948Z] GC before operation: completed in 213.276 ms, heap usage 285.953 MB -> 61.698 MB. [2025-06-19T04:34:30.944Z] ====== als (apache-spark) [default], iteration 1 completed (14187.469 ms) ====== [2025-06-19T04:34:30.944Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-19T04:34:31.744Z] GC before operation: completed in 142.490 ms, heap usage 188.856 MB -> 62.376 MB. [2025-06-19T04:34:45.847Z] ====== als (apache-spark) [default], iteration 2 completed (12917.281 ms) ====== [2025-06-19T04:34:45.847Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-19T04:34:45.847Z] GC before operation: completed in 156.135 ms, heap usage 432.424 MB -> 63.475 MB. [2025-06-19T04:34:57.644Z] ====== als (apache-spark) [default], iteration 3 completed (12008.243 ms) ====== [2025-06-19T04:34:57.644Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-19T04:34:57.644Z] GC before operation: completed in 167.917 ms, heap usage 457.221 MB -> 63.947 MB. [2025-06-19T04:35:09.345Z] ====== als (apache-spark) [default], iteration 4 completed (10588.996 ms) ====== [2025-06-19T04:35:09.345Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-19T04:35:09.345Z] GC before operation: completed in 163.231 ms, heap usage 343.085 MB -> 64.350 MB. [2025-06-19T04:35:19.245Z] ====== als (apache-spark) [default], iteration 5 completed (10248.628 ms) ====== [2025-06-19T04:35:19.245Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-19T04:35:19.245Z] GC before operation: completed in 160.289 ms, heap usage 494.042 MB -> 65.151 MB. [2025-06-19T04:35:30.917Z] ====== als (apache-spark) [default], iteration 6 completed (10252.635 ms) ====== [2025-06-19T04:35:30.917Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-19T04:35:30.917Z] GC before operation: completed in 164.278 ms, heap usage 252.345 MB -> 65.439 MB. [2025-06-19T04:35:40.759Z] ====== als (apache-spark) [default], iteration 7 completed (10828.053 ms) ====== [2025-06-19T04:35:40.759Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-19T04:35:41.543Z] GC before operation: completed in 199.720 ms, heap usage 295.519 MB -> 65.989 MB. [2025-06-19T04:35:53.219Z] ====== als (apache-spark) [default], iteration 8 completed (10691.833 ms) ====== [2025-06-19T04:35:53.219Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-19T04:35:53.219Z] GC before operation: completed in 198.762 ms, heap usage 155.405 MB -> 66.367 MB. [2025-06-19T04:36:03.054Z] ====== als (apache-spark) [default], iteration 9 completed (10452.494 ms) ====== [2025-06-19T04:36:03.054Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-19T04:36:03.846Z] GC before operation: completed in 198.513 ms, heap usage 475.967 MB -> 67.040 MB. [2025-06-19T04:36:15.589Z] ====== als (apache-spark) [default], iteration 10 completed (10314.139 ms) ====== [2025-06-19T04:36:15.589Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-19T04:36:15.589Z] GC before operation: completed in 171.752 ms, heap usage 182.168 MB -> 66.845 MB. [2025-06-19T04:36:25.515Z] ====== als (apache-spark) [default], iteration 11 completed (9571.025 ms) ====== [2025-06-19T04:36:25.515Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-19T04:36:25.515Z] GC before operation: completed in 158.361 ms, heap usage 182.579 MB -> 67.032 MB. [2025-06-19T04:36:33.760Z] ====== als (apache-spark) [default], iteration 12 completed (8906.033 ms) ====== [2025-06-19T04:36:33.760Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-19T04:36:33.760Z] GC before operation: completed in 196.672 ms, heap usage 147.304 MB -> 67.360 MB. [2025-06-19T04:36:45.944Z] ====== als (apache-spark) [default], iteration 13 completed (10313.067 ms) ====== [2025-06-19T04:36:45.944Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-19T04:36:45.944Z] GC before operation: completed in 151.126 ms, heap usage 400.198 MB -> 67.957 MB. [2025-06-19T04:36:55.794Z] ====== als (apache-spark) [default], iteration 14 completed (10325.588 ms) ====== [2025-06-19T04:36:55.794Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-19T04:36:55.794Z] GC before operation: completed in 144.612 ms, heap usage 282.610 MB -> 68.054 MB. [2025-06-19T04:37:05.689Z] ====== als (apache-spark) [default], iteration 15 completed (9970.608 ms) ====== [2025-06-19T04:37:05.689Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-19T04:37:05.689Z] GC before operation: completed in 149.130 ms, heap usage 449.532 MB -> 68.532 MB. [2025-06-19T04:37:17.444Z] ====== als (apache-spark) [default], iteration 16 completed (9941.226 ms) ====== [2025-06-19T04:37:17.444Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-19T04:37:17.444Z] GC before operation: completed in 146.456 ms, heap usage 133.708 MB -> 68.441 MB. [2025-06-19T04:37:25.786Z] ====== als (apache-spark) [default], iteration 17 completed (9159.208 ms) ====== [2025-06-19T04:37:25.786Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-19T04:37:25.786Z] GC before operation: completed in 166.098 ms, heap usage 260.820 MB -> 68.797 MB. [2025-06-19T04:37:36.215Z] ====== als (apache-spark) [default], iteration 18 completed (9336.351 ms) ====== [2025-06-19T04:37:36.215Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-19T04:37:36.215Z] GC before operation: completed in 172.199 ms, heap usage 137.866 MB -> 68.937 MB. [2025-06-19T04:37:44.615Z] ====== als (apache-spark) [default], iteration 19 completed (8623.064 ms) ====== [2025-06-19T04:37:44.615Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-19T04:37:44.615Z] GC before operation: completed in 158.569 ms, heap usage 243.453 MB -> 69.295 MB. [2025-06-19T04:37:56.287Z] ====== als (apache-spark) [default], iteration 20 completed (10264.632 ms) ====== [2025-06-19T04:37:56.287Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-19T04:37:56.287Z] GC before operation: completed in 148.923 ms, heap usage 415.190 MB -> 69.700 MB. [2025-06-19T04:38:06.246Z] ====== als (apache-spark) [default], iteration 21 completed (10130.224 ms) ====== [2025-06-19T04:38:06.246Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-19T04:38:06.246Z] GC before operation: completed in 146.769 ms, heap usage 379.516 MB -> 69.869 MB. [2025-06-19T04:38:16.119Z] ====== als (apache-spark) [default], iteration 22 completed (9899.319 ms) ====== [2025-06-19T04:38:16.119Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-19T04:38:16.119Z] GC before operation: completed in 142.938 ms, heap usage 274.354 MB -> 70.050 MB. [2025-06-19T04:38:25.986Z] ====== als (apache-spark) [default], iteration 23 completed (9695.017 ms) ====== [2025-06-19T04:38:25.986Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-19T04:38:25.986Z] GC before operation: completed in 161.392 ms, heap usage 145.557 MB -> 70.318 MB. [2025-06-19T04:38:36.348Z] ====== als (apache-spark) [default], iteration 24 completed (9864.152 ms) ====== [2025-06-19T04:38:36.348Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-19T04:38:36.348Z] GC before operation: completed in 160.969 ms, heap usage 238.871 MB -> 70.673 MB. [2025-06-19T04:38:48.027Z] ====== als (apache-spark) [default], iteration 25 completed (10497.728 ms) ====== [2025-06-19T04:38:48.028Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-19T04:38:48.028Z] GC before operation: completed in 152.728 ms, heap usage 443.442 MB -> 71.135 MB. [2025-06-19T04:38:57.916Z] ====== als (apache-spark) [default], iteration 26 completed (9793.997 ms) ====== [2025-06-19T04:38:57.916Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-19T04:38:57.916Z] GC before operation: completed in 213.498 ms, heap usage 354.215 MB -> 71.218 MB. [2025-06-19T04:39:06.386Z] ====== als (apache-spark) [default], iteration 27 completed (8307.221 ms) ====== [2025-06-19T04:39:06.386Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-19T04:39:06.386Z] GC before operation: completed in 146.554 ms, heap usage 246.536 MB -> 71.425 MB. [2025-06-19T04:39:16.222Z] ====== als (apache-spark) [default], iteration 28 completed (8284.735 ms) ====== [2025-06-19T04:39:16.222Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-19T04:39:16.222Z] GC before operation: completed in 154.978 ms, heap usage 260.691 MB -> 71.205 MB. [2025-06-19T04:39:23.574Z] ====== als (apache-spark) [default], iteration 29 completed (8035.792 ms) ====== [2025-06-19T04:39:24.344Z] ----------------------------------- [2025-06-19T04:39:24.345Z] renaissance-als_0_PASSED [2025-06-19T04:39:24.345Z] ----------------------------------- [2025-06-19T04:39:24.345Z] [2025-06-19T04:39:24.345Z] TEST TEARDOWN: [2025-06-19T04:39:24.345Z] Nothing to be done for teardown. [2025-06-19T04:39:24.345Z] renaissance-als_0 Finish Time: Thu Jun 19 04:39:24 2025 Epoch Time (ms): 1750307964246