[2025-08-21T00:58:23.677Z] Running test renaissance-als_0 ... [2025-08-21T00:58:23.677Z] =============================================== [2025-08-21T00:58:23.677Z] renaissance-als_0 Start Time: Thu Aug 21 00:58:23 2025 Epoch Time (ms): 1755737903340 [2025-08-21T00:58:23.677Z] variation: NoOptions [2025-08-21T00:58:23.677Z] JVM_OPTIONS: [2025-08-21T00:58:23.677Z] { \ [2025-08-21T00:58:23.677Z] echo ""; echo "TEST SETUP:"; \ [2025-08-21T00:58:23.677Z] echo "Nothing to be done for setup."; \ [2025-08-21T00:58:23.677Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-als_0"; \ [2025-08-21T00:58:23.677Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-als_0"; \ [2025-08-21T00:58:23.677Z] echo ""; echo "TESTING:"; \ [2025-08-21T00:58:23.677Z] "/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_17557377207327/renaissance-als_0"/als.json" als; \ [2025-08-21T00:58:23.678Z] 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_17557377207327/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-21T00:58:23.678Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-21T00:58:23.678Z] echo "Nothing to be done for teardown."; \ [2025-08-21T00:58:23.678Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/TestTargetResult"; [2025-08-21T00:58:23.678Z] [2025-08-21T00:58:23.678Z] TEST SETUP: [2025-08-21T00:58:23.678Z] Nothing to be done for setup. [2025-08-21T00:58:23.678Z] [2025-08-21T00:58:23.678Z] TESTING: [2025-08-21T00:58:31.515Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-21T00:58:36.753Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-21T00:58:36.753Z] GC before operation: completed in 43.456 ms, heap usage 166.025 MB -> 35.122 MB. [2025-08-21T00:59:01.012Z] ====== als (apache-spark) [default], iteration 0 completed (23371.267 ms) ====== [2025-08-21T00:59:01.012Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-21T00:59:01.399Z] GC before operation: completed in 118.017 ms, heap usage 206.900 MB -> 61.885 MB. [2025-08-21T00:59:13.232Z] ====== als (apache-spark) [default], iteration 1 completed (9955.693 ms) ====== [2025-08-21T00:59:13.232Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-21T00:59:13.232Z] GC before operation: completed in 91.352 ms, heap usage 112.141 MB -> 63.003 MB. [2025-08-21T00:59:21.259Z] ====== als (apache-spark) [default], iteration 2 completed (9049.036 ms) ====== [2025-08-21T00:59:21.259Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-21T00:59:21.259Z] GC before operation: completed in 91.832 ms, heap usage 356.997 MB -> 63.622 MB. [2025-08-21T00:59:31.076Z] ====== als (apache-spark) [default], iteration 3 completed (8877.802 ms) ====== [2025-08-21T00:59:31.076Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-21T00:59:31.076Z] GC before operation: completed in 94.598 ms, heap usage 197.795 MB -> 64.161 MB. [2025-08-21T00:59:40.932Z] ====== als (apache-spark) [default], iteration 4 completed (8957.093 ms) ====== [2025-08-21T00:59:40.932Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-21T00:59:40.932Z] GC before operation: completed in 102.126 ms, heap usage 128.622 MB -> 64.430 MB. [2025-08-21T00:59:48.959Z] ====== als (apache-spark) [default], iteration 5 completed (8603.637 ms) ====== [2025-08-21T00:59:48.959Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-21T00:59:48.959Z] GC before operation: completed in 101.794 ms, heap usage 265.260 MB -> 65.125 MB. [2025-08-21T00:59:58.673Z] ====== als (apache-spark) [default], iteration 6 completed (8750.354 ms) ====== [2025-08-21T00:59:58.673Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-21T00:59:58.673Z] GC before operation: completed in 100.374 ms, heap usage 180.378 MB -> 65.651 MB. [2025-08-21T01:00:08.436Z] ====== als (apache-spark) [default], iteration 7 completed (8902.268 ms) ====== [2025-08-21T01:00:08.436Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-21T01:00:08.436Z] GC before operation: completed in 119.094 ms, heap usage 352.745 MB -> 66.376 MB. [2025-08-21T01:00:16.513Z] ====== als (apache-spark) [default], iteration 8 completed (8668.313 ms) ====== [2025-08-21T01:00:16.513Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-21T01:00:16.513Z] GC before operation: completed in 104.013 ms, heap usage 406.241 MB -> 66.903 MB. [2025-08-21T01:00:26.284Z] ====== als (apache-spark) [default], iteration 9 completed (8370.039 ms) ====== [2025-08-21T01:00:26.284Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-21T01:00:26.284Z] GC before operation: completed in 110.132 ms, heap usage 140.131 MB -> 66.965 MB. [2025-08-21T01:00:34.246Z] ====== als (apache-spark) [default], iteration 10 completed (8443.612 ms) ====== [2025-08-21T01:00:34.246Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-21T01:00:34.246Z] GC before operation: completed in 105.946 ms, heap usage 285.423 MB -> 67.357 MB. [2025-08-21T01:00:44.099Z] ====== als (apache-spark) [default], iteration 11 completed (8475.650 ms) ====== [2025-08-21T01:00:44.099Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-21T01:00:44.099Z] GC before operation: completed in 134.849 ms, heap usage 504.756 MB -> 67.958 MB. [2025-08-21T01:00:52.301Z] ====== als (apache-spark) [default], iteration 12 completed (8377.923 ms) ====== [2025-08-21T01:00:52.301Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-21T01:00:52.301Z] GC before operation: completed in 110.208 ms, heap usage 433.166 MB -> 68.194 MB. [2025-08-21T01:01:00.552Z] ====== als (apache-spark) [default], iteration 13 completed (8435.558 ms) ====== [2025-08-21T01:01:00.552Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-21T01:01:00.942Z] GC before operation: completed in 108.587 ms, heap usage 268.673 MB -> 68.161 MB. [2025-08-21T01:01:10.702Z] ====== als (apache-spark) [default], iteration 14 completed (8457.321 ms) ====== [2025-08-21T01:01:10.702Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-21T01:01:10.702Z] GC before operation: completed in 115.398 ms, heap usage 204.155 MB -> 68.361 MB. [2025-08-21T01:01:18.834Z] ====== als (apache-spark) [default], iteration 15 completed (8275.053 ms) ====== [2025-08-21T01:01:18.834Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-21T01:01:18.834Z] GC before operation: completed in 108.163 ms, heap usage 505.088 MB -> 68.897 MB. [2025-08-21T01:01:26.942Z] ====== als (apache-spark) [default], iteration 16 completed (8295.144 ms) ====== [2025-08-21T01:01:26.942Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-21T01:01:26.942Z] GC before operation: completed in 105.586 ms, heap usage 404.439 MB -> 68.960 MB. [2025-08-21T01:01:36.612Z] ====== als (apache-spark) [default], iteration 17 completed (8361.610 ms) ====== [2025-08-21T01:01:36.612Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-21T01:01:36.612Z] GC before operation: completed in 109.087 ms, heap usage 374.403 MB -> 69.329 MB. [2025-08-21T01:01:44.712Z] ====== als (apache-spark) [default], iteration 18 completed (8466.498 ms) ====== [2025-08-21T01:01:44.712Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-21T01:01:44.712Z] GC before operation: completed in 96.687 ms, heap usage 540.066 MB -> 69.850 MB. [2025-08-21T01:01:54.353Z] ====== als (apache-spark) [default], iteration 19 completed (8273.424 ms) ====== [2025-08-21T01:01:54.353Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-21T01:01:54.353Z] GC before operation: completed in 95.772 ms, heap usage 580.330 MB -> 70.029 MB. [2025-08-21T01:02:02.692Z] ====== als (apache-spark) [default], iteration 20 completed (8619.262 ms) ====== [2025-08-21T01:02:02.692Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-21T01:02:02.692Z] GC before operation: completed in 111.086 ms, heap usage 117.389 MB -> 69.782 MB. [2025-08-21T01:02:10.735Z] ====== als (apache-spark) [default], iteration 21 completed (8409.431 ms) ====== [2025-08-21T01:02:10.735Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-21T01:02:11.141Z] GC before operation: completed in 94.549 ms, heap usage 291.384 MB -> 70.187 MB. [2025-08-21T01:02:20.921Z] ====== als (apache-spark) [default], iteration 22 completed (8453.926 ms) ====== [2025-08-21T01:02:20.921Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-21T01:02:20.921Z] GC before operation: completed in 93.182 ms, heap usage 312.509 MB -> 70.637 MB. [2025-08-21T01:02:28.981Z] ====== als (apache-spark) [default], iteration 23 completed (8184.383 ms) ====== [2025-08-21T01:02:28.981Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-21T01:02:28.981Z] GC before operation: completed in 95.147 ms, heap usage 292.622 MB -> 70.776 MB. [2025-08-21T01:02:37.061Z] ====== als (apache-spark) [default], iteration 24 completed (8185.451 ms) ====== [2025-08-21T01:02:37.061Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-21T01:02:37.061Z] GC before operation: completed in 95.221 ms, heap usage 397.045 MB -> 71.127 MB. [2025-08-21T01:02:46.872Z] ====== als (apache-spark) [default], iteration 25 completed (8280.154 ms) ====== [2025-08-21T01:02:46.872Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-21T01:02:46.872Z] GC before operation: completed in 97.247 ms, heap usage 197.547 MB -> 71.097 MB. [2025-08-21T01:02:54.942Z] ====== als (apache-spark) [default], iteration 26 completed (8140.297 ms) ====== [2025-08-21T01:02:54.942Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-21T01:02:54.942Z] GC before operation: completed in 94.786 ms, heap usage 204.849 MB -> 71.378 MB. [2025-08-21T01:03:02.985Z] ====== als (apache-spark) [default], iteration 27 completed (8134.856 ms) ====== [2025-08-21T01:03:02.985Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-21T01:03:02.985Z] GC before operation: completed in 96.239 ms, heap usage 502.089 MB -> 72.105 MB. [2025-08-21T01:03:11.222Z] ====== als (apache-spark) [default], iteration 28 completed (8265.287 ms) ====== [2025-08-21T01:03:11.222Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-21T01:03:11.222Z] GC before operation: completed in 99.825 ms, heap usage 325.842 MB -> 72.169 MB. [2025-08-21T01:03:19.321Z] ====== als (apache-spark) [default], iteration 29 completed (8132.404 ms) ====== [2025-08-21T01:03:20.142Z] ----------------------------------- [2025-08-21T01:03:20.142Z] renaissance-als_0_PASSED [2025-08-21T01:03:20.142Z] ----------------------------------- [2025-08-21T01:03:20.142Z] [2025-08-21T01:03:20.142Z] TEST TEARDOWN: [2025-08-21T01:03:20.142Z] Nothing to be done for teardown. [2025-08-21T01:03:20.142Z] renaissance-als_0 Finish Time: Thu Aug 21 01:03:19 2025 Epoch Time (ms): 1755738199740