[2025-07-01T04:08:57.911Z] Running test renaissance-als_0 ... [2025-07-01T04:08:57.911Z] =============================================== [2025-07-01T04:08:57.911Z] renaissance-als_0 Start Time: Tue Jul 1 04:08:57 2025 Epoch Time (ms): 1751342937757 [2025-07-01T04:08:57.911Z] variation: NoOptions [2025-07-01T04:08:57.911Z] JVM_OPTIONS: [2025-07-01T04:08:57.911Z] { \ [2025-07-01T04:08:57.911Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T04:08:57.911Z] echo "Nothing to be done for setup."; \ [2025-07-01T04:08:57.911Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-als_0"; \ [2025-07-01T04:08:57.911Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-als_0"; \ [2025-07-01T04:08:57.911Z] echo ""; echo "TESTING:"; \ [2025-07-01T04:08:57.911Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-als_0"/als.json" als; \ [2025-07-01T04:08:57.911Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T04:08:57.911Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T04:08:57.911Z] echo "Nothing to be done for teardown."; \ [2025-07-01T04:08:57.911Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/TestTargetResult"; [2025-07-01T04:08:57.911Z] [2025-07-01T04:08:57.911Z] TEST SETUP: [2025-07-01T04:08:57.911Z] Nothing to be done for setup. [2025-07-01T04:08:57.911Z] [2025-07-01T04:08:57.911Z] TESTING: [2025-07-01T04:09:05.629Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T04:09:10.725Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T04:09:10.725Z] GC before operation: completed in 59.256 ms, heap usage 50.500 MB -> 35.214 MB. [2025-07-01T04:09:34.836Z] ====== als (apache-spark) [default], iteration 0 completed (23263.550 ms) ====== [2025-07-01T04:09:34.836Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T04:09:34.836Z] GC before operation: completed in 116.527 ms, heap usage 451.574 MB -> 62.121 MB. [2025-07-01T04:09:46.514Z] ====== als (apache-spark) [default], iteration 1 completed (10052.932 ms) ====== [2025-07-01T04:09:46.514Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T04:09:46.514Z] GC before operation: completed in 88.746 ms, heap usage 504.464 MB -> 63.206 MB. [2025-07-01T04:09:56.138Z] ====== als (apache-spark) [default], iteration 2 completed (9236.210 ms) ====== [2025-07-01T04:09:56.138Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T04:09:56.138Z] GC before operation: completed in 90.281 ms, heap usage 360.248 MB -> 63.579 MB. [2025-07-01T04:10:05.691Z] ====== als (apache-spark) [default], iteration 3 completed (9221.269 ms) ====== [2025-07-01T04:10:05.691Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T04:10:05.691Z] GC before operation: completed in 103.037 ms, heap usage 115.063 MB -> 63.886 MB. [2025-07-01T04:10:15.204Z] ====== als (apache-spark) [default], iteration 4 completed (8977.261 ms) ====== [2025-07-01T04:10:15.204Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T04:10:15.204Z] GC before operation: completed in 103.908 ms, heap usage 460.246 MB -> 64.893 MB. [2025-07-01T04:10:23.176Z] ====== als (apache-spark) [default], iteration 5 completed (8649.818 ms) ====== [2025-07-01T04:10:23.176Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T04:10:23.176Z] GC before operation: completed in 96.716 ms, heap usage 251.987 MB -> 65.163 MB. [2025-07-01T04:10:32.812Z] ====== als (apache-spark) [default], iteration 6 completed (8800.681 ms) ====== [2025-07-01T04:10:32.812Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T04:10:32.812Z] GC before operation: completed in 97.683 ms, heap usage 341.694 MB -> 65.778 MB. [2025-07-01T04:10:42.399Z] ====== als (apache-spark) [default], iteration 7 completed (8800.708 ms) ====== [2025-07-01T04:10:42.399Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T04:10:42.399Z] GC before operation: completed in 101.477 ms, heap usage 558.066 MB -> 66.553 MB. [2025-07-01T04:10:52.070Z] ====== als (apache-spark) [default], iteration 8 completed (9060.894 ms) ====== [2025-07-01T04:10:52.070Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T04:10:52.070Z] GC before operation: completed in 96.010 ms, heap usage 355.148 MB -> 66.865 MB. [2025-07-01T04:11:01.600Z] ====== als (apache-spark) [default], iteration 9 completed (8791.013 ms) ====== [2025-07-01T04:11:01.600Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T04:11:01.600Z] GC before operation: completed in 102.061 ms, heap usage 256.913 MB -> 67.024 MB. [2025-07-01T04:11:09.682Z] ====== als (apache-spark) [default], iteration 10 completed (8833.065 ms) ====== [2025-07-01T04:11:09.682Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T04:11:09.682Z] GC before operation: completed in 108.961 ms, heap usage 522.840 MB -> 67.694 MB. [2025-07-01T04:11:19.400Z] ====== als (apache-spark) [default], iteration 11 completed (8720.679 ms) ====== [2025-07-01T04:11:19.400Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T04:11:19.400Z] GC before operation: completed in 123.545 ms, heap usage 324.176 MB -> 67.857 MB. [2025-07-01T04:11:27.340Z] ====== als (apache-spark) [default], iteration 12 completed (8674.503 ms) ====== [2025-07-01T04:11:27.340Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T04:11:27.750Z] GC before operation: completed in 104.789 ms, heap usage 122.959 MB -> 67.720 MB. [2025-07-01T04:11:37.313Z] ====== als (apache-spark) [default], iteration 13 completed (8262.944 ms) ====== [2025-07-01T04:11:37.313Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T04:11:37.313Z] GC before operation: completed in 107.056 ms, heap usage 436.970 MB -> 68.429 MB. [2025-07-01T04:11:45.210Z] ====== als (apache-spark) [default], iteration 14 completed (8422.632 ms) ====== [2025-07-01T04:11:45.210Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T04:11:45.210Z] GC before operation: completed in 107.608 ms, heap usage 356.554 MB -> 68.524 MB. [2025-07-01T04:11:54.979Z] ====== als (apache-spark) [default], iteration 15 completed (8226.961 ms) ====== [2025-07-01T04:11:54.979Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T04:11:54.979Z] GC before operation: completed in 104.450 ms, heap usage 116.754 MB -> 68.526 MB. [2025-07-01T04:12:02.840Z] ====== als (apache-spark) [default], iteration 16 completed (8215.814 ms) ====== [2025-07-01T04:12:02.840Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T04:12:02.840Z] GC before operation: completed in 112.564 ms, heap usage 190.131 MB -> 68.750 MB. [2025-07-01T04:12:10.758Z] ====== als (apache-spark) [default], iteration 17 completed (8301.663 ms) ====== [2025-07-01T04:12:10.758Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T04:12:10.758Z] GC before operation: completed in 112.362 ms, heap usage 148.711 MB -> 68.961 MB. [2025-07-01T04:12:20.480Z] ====== als (apache-spark) [default], iteration 18 completed (8204.019 ms) ====== [2025-07-01T04:12:20.480Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T04:12:20.480Z] GC before operation: completed in 109.406 ms, heap usage 130.509 MB -> 69.336 MB. [2025-07-01T04:12:28.415Z] ====== als (apache-spark) [default], iteration 19 completed (8380.984 ms) ====== [2025-07-01T04:12:28.416Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T04:12:28.416Z] GC before operation: completed in 114.284 ms, heap usage 358.783 MB -> 69.773 MB. [2025-07-01T04:12:38.210Z] ====== als (apache-spark) [default], iteration 20 completed (8502.770 ms) ====== [2025-07-01T04:12:38.210Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T04:12:38.210Z] GC before operation: completed in 119.719 ms, heap usage 235.107 MB -> 69.962 MB. [2025-07-01T04:12:46.230Z] ====== als (apache-spark) [default], iteration 21 completed (8485.730 ms) ====== [2025-07-01T04:12:46.230Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T04:12:46.230Z] GC before operation: completed in 111.302 ms, heap usage 120.216 MB -> 70.031 MB. [2025-07-01T04:12:55.780Z] ====== als (apache-spark) [default], iteration 22 completed (8368.824 ms) ====== [2025-07-01T04:12:55.780Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T04:12:55.780Z] GC before operation: completed in 115.905 ms, heap usage 119.484 MB -> 70.275 MB. [2025-07-01T04:13:03.720Z] ====== als (apache-spark) [default], iteration 23 completed (8351.189 ms) ====== [2025-07-01T04:13:03.720Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T04:13:03.720Z] GC before operation: completed in 121.773 ms, heap usage 449.579 MB -> 71.024 MB. [2025-07-01T04:13:13.283Z] ====== als (apache-spark) [default], iteration 24 completed (8285.944 ms) ====== [2025-07-01T04:13:13.283Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T04:13:13.283Z] GC before operation: completed in 123.514 ms, heap usage 343.475 MB -> 71.043 MB. [2025-07-01T04:13:21.270Z] ====== als (apache-spark) [default], iteration 25 completed (8240.185 ms) ====== [2025-07-01T04:13:21.270Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T04:13:21.270Z] GC before operation: completed in 112.587 ms, heap usage 253.810 MB -> 71.170 MB. [2025-07-01T04:13:29.240Z] ====== als (apache-spark) [default], iteration 26 completed (8095.748 ms) ====== [2025-07-01T04:13:29.240Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T04:13:29.240Z] GC before operation: completed in 112.464 ms, heap usage 207.886 MB -> 71.384 MB. [2025-07-01T04:13:38.719Z] ====== als (apache-spark) [default], iteration 27 completed (8095.717 ms) ====== [2025-07-01T04:13:38.719Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T04:13:38.719Z] GC before operation: completed in 115.853 ms, heap usage 207.419 MB -> 71.705 MB. [2025-07-01T04:13:46.656Z] ====== als (apache-spark) [default], iteration 28 completed (8357.936 ms) ====== [2025-07-01T04:13:46.656Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T04:13:46.656Z] GC before operation: completed in 116.457 ms, heap usage 438.279 MB -> 72.367 MB. [2025-07-01T04:13:54.566Z] ====== als (apache-spark) [default], iteration 29 completed (8000.260 ms) ====== [2025-07-01T04:13:54.938Z] ----------------------------------- [2025-07-01T04:13:54.938Z] renaissance-als_0_PASSED [2025-07-01T04:13:54.938Z] ----------------------------------- [2025-07-01T04:13:54.938Z] [2025-07-01T04:13:54.938Z] TEST TEARDOWN: [2025-07-01T04:13:54.938Z] Nothing to be done for teardown. [2025-07-01T04:13:55.310Z] renaissance-als_0 Finish Time: Tue Jul 1 04:13:54 2025 Epoch Time (ms): 1751343234936