[2025-10-03T20:42:21.651Z] Running test renaissance-als_0 ... [2025-10-03T20:42:21.651Z] =============================================== [2025-10-03T20:42:21.651Z] renaissance-als_0 Start Time: Fri Oct 3 20:42:21 2025 Epoch Time (ms): 1759524141184 [2025-10-03T20:42:21.651Z] variation: NoOptions [2025-10-03T20:42:21.651Z] JVM_OPTIONS: [2025-10-03T20:42:21.651Z] { \ [2025-10-03T20:42:21.651Z] echo ""; echo "TEST SETUP:"; \ [2025-10-03T20:42:21.651Z] echo "Nothing to be done for setup."; \ [2025-10-03T20:42:21.651Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17595241404662/renaissance-als_0"; \ [2025-10-03T20:42:21.651Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17595241404662/renaissance-als_0"; \ [2025-10-03T20:42:21.651Z] echo ""; echo "TESTING:"; \ [2025-10-03T20:42:21.652Z] "/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_17595241404662/renaissance-als_0"/als.json" als; \ [2025-10-03T20:42:21.652Z] 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_17595241404662/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-03T20:42:21.652Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-03T20:42:21.652Z] echo "Nothing to be done for teardown."; \ [2025-10-03T20:42:21.652Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17595241404662/TestTargetResult"; [2025-10-03T20:42:21.652Z] [2025-10-03T20:42:21.652Z] TEST SETUP: [2025-10-03T20:42:21.652Z] Nothing to be done for setup. [2025-10-03T20:42:21.652Z] [2025-10-03T20:42:21.652Z] TESTING: [2025-10-03T20:42:37.406Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-03T20:42:45.582Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-03T20:42:45.582Z] GC before operation: completed in 101.282 ms, heap usage 49.403 MB -> 35.151 MB. [2025-10-03T20:43:18.691Z] ====== als (apache-spark) [default], iteration 0 completed (29124.950 ms) ====== [2025-10-03T20:43:18.691Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-03T20:43:18.691Z] GC before operation: completed in 167.397 ms, heap usage 220.268 MB -> 61.765 MB. [2025-10-03T20:43:25.583Z] ====== als (apache-spark) [default], iteration 1 completed (9737.284 ms) ====== [2025-10-03T20:43:25.583Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-03T20:43:25.583Z] GC before operation: completed in 154.858 ms, heap usage 338.908 MB -> 62.907 MB. [2025-10-03T20:43:36.074Z] ====== als (apache-spark) [default], iteration 2 completed (8331.416 ms) ====== [2025-10-03T20:43:36.075Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-03T20:43:36.075Z] GC before operation: completed in 161.988 ms, heap usage 409.252 MB -> 63.511 MB. [2025-10-03T20:43:42.806Z] ====== als (apache-spark) [default], iteration 3 completed (7982.362 ms) ====== [2025-10-03T20:43:42.806Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-03T20:43:42.806Z] GC before operation: completed in 167.872 ms, heap usage 319.704 MB -> 64.053 MB. [2025-10-03T20:43:50.954Z] ====== als (apache-spark) [default], iteration 4 completed (7693.711 ms) ====== [2025-10-03T20:43:50.954Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-03T20:43:50.954Z] GC before operation: completed in 174.629 ms, heap usage 267.180 MB -> 64.484 MB. [2025-10-03T20:43:59.132Z] ====== als (apache-spark) [default], iteration 5 completed (7301.448 ms) ====== [2025-10-03T20:43:59.132Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-03T20:43:59.132Z] GC before operation: completed in 181.235 ms, heap usage 511.830 MB -> 65.377 MB. [2025-10-03T20:44:07.317Z] ====== als (apache-spark) [default], iteration 6 completed (7351.743 ms) ====== [2025-10-03T20:44:07.317Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-03T20:44:07.317Z] GC before operation: completed in 176.359 ms, heap usage 295.400 MB -> 65.674 MB. [2025-10-03T20:44:14.012Z] ====== als (apache-spark) [default], iteration 7 completed (7069.632 ms) ====== [2025-10-03T20:44:14.012Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-03T20:44:14.012Z] GC before operation: completed in 188.584 ms, heap usage 304.618 MB -> 66.227 MB. [2025-10-03T20:44:22.167Z] ====== als (apache-spark) [default], iteration 8 completed (7312.737 ms) ====== [2025-10-03T20:44:22.168Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-03T20:44:22.168Z] GC before operation: completed in 192.581 ms, heap usage 281.556 MB -> 66.716 MB. [2025-10-03T20:44:30.324Z] ====== als (apache-spark) [default], iteration 9 completed (7262.087 ms) ====== [2025-10-03T20:44:30.324Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-03T20:44:30.324Z] GC before operation: completed in 189.294 ms, heap usage 603.962 MB -> 67.404 MB. [2025-10-03T20:44:38.486Z] ====== als (apache-spark) [default], iteration 10 completed (7314.146 ms) ====== [2025-10-03T20:44:38.486Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-03T20:44:38.486Z] GC before operation: completed in 200.460 ms, heap usage 354.933 MB -> 67.372 MB. [2025-10-03T20:44:45.222Z] ====== als (apache-spark) [default], iteration 11 completed (6945.771 ms) ====== [2025-10-03T20:44:45.222Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-03T20:44:45.222Z] GC before operation: completed in 193.535 ms, heap usage 276.465 MB -> 67.558 MB. [2025-10-03T20:44:53.377Z] ====== als (apache-spark) [default], iteration 12 completed (7221.006 ms) ====== [2025-10-03T20:44:53.377Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-03T20:44:53.377Z] GC before operation: completed in 196.463 ms, heap usage 243.404 MB -> 67.691 MB. [2025-10-03T20:45:00.105Z] ====== als (apache-spark) [default], iteration 13 completed (6807.675 ms) ====== [2025-10-03T20:45:00.105Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-03T20:45:00.105Z] GC before operation: completed in 192.471 ms, heap usage 281.817 MB -> 68.019 MB. [2025-10-03T20:45:08.301Z] ====== als (apache-spark) [default], iteration 14 completed (6938.317 ms) ====== [2025-10-03T20:45:08.301Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-03T20:45:08.301Z] GC before operation: completed in 188.112 ms, heap usage 252.800 MB -> 68.266 MB. [2025-10-03T20:45:15.083Z] ====== als (apache-spark) [default], iteration 15 completed (6730.629 ms) ====== [2025-10-03T20:45:15.083Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-03T20:45:15.083Z] GC before operation: completed in 200.355 ms, heap usage 569.204 MB -> 69.486 MB. [2025-10-03T20:45:22.573Z] ====== als (apache-spark) [default], iteration 16 completed (6828.082 ms) ====== [2025-10-03T20:45:22.573Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-03T20:45:22.573Z] GC before operation: completed in 199.962 ms, heap usage 158.277 MB -> 68.715 MB. [2025-10-03T20:45:29.289Z] ====== als (apache-spark) [default], iteration 17 completed (6825.141 ms) ====== [2025-10-03T20:45:29.289Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-03T20:45:29.289Z] GC before operation: completed in 194.808 ms, heap usage 245.124 MB -> 69.074 MB. [2025-10-03T20:45:37.438Z] ====== als (apache-spark) [default], iteration 18 completed (6719.400 ms) ====== [2025-10-03T20:45:37.438Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-03T20:45:37.438Z] GC before operation: completed in 208.703 ms, heap usage 543.761 MB -> 69.771 MB. [2025-10-03T20:45:44.193Z] ====== als (apache-spark) [default], iteration 19 completed (6817.816 ms) ====== [2025-10-03T20:45:44.193Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-03T20:45:44.193Z] GC before operation: completed in 207.257 ms, heap usage 507.982 MB -> 70.043 MB. [2025-10-03T20:45:50.929Z] ====== als (apache-spark) [default], iteration 20 completed (6875.567 ms) ====== [2025-10-03T20:45:50.929Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-03T20:45:50.929Z] GC before operation: completed in 197.963 ms, heap usage 216.771 MB -> 69.740 MB. [2025-10-03T20:45:59.199Z] ====== als (apache-spark) [default], iteration 21 completed (6730.540 ms) ====== [2025-10-03T20:45:59.199Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-03T20:45:59.199Z] GC before operation: completed in 210.899 ms, heap usage 231.444 MB -> 70.106 MB. [2025-10-03T20:46:05.949Z] ====== als (apache-spark) [default], iteration 22 completed (6929.471 ms) ====== [2025-10-03T20:46:05.949Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-03T20:46:05.949Z] GC before operation: completed in 208.400 ms, heap usage 236.460 MB -> 70.351 MB. [2025-10-03T20:46:14.183Z] ====== als (apache-spark) [default], iteration 23 completed (8142.466 ms) ====== [2025-10-03T20:46:14.183Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-03T20:46:14.183Z] GC before operation: completed in 210.091 ms, heap usage 462.957 MB -> 70.927 MB. [2025-10-03T20:46:21.609Z] ====== als (apache-spark) [default], iteration 24 completed (7000.253 ms) ====== [2025-10-03T20:46:21.609Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-03T20:46:22.568Z] GC before operation: completed in 229.583 ms, heap usage 269.722 MB -> 70.896 MB. [2025-10-03T20:46:30.769Z] ====== als (apache-spark) [default], iteration 25 completed (7803.599 ms) ====== [2025-10-03T20:46:30.769Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-03T20:46:30.769Z] GC before operation: completed in 277.716 ms, heap usage 198.975 MB -> 71.090 MB. [2025-10-03T20:46:38.997Z] ====== als (apache-spark) [default], iteration 26 completed (8347.777 ms) ====== [2025-10-03T20:46:38.997Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-03T20:46:39.992Z] GC before operation: completed in 258.094 ms, heap usage 385.245 MB -> 71.590 MB. [2025-10-03T20:46:48.246Z] ====== als (apache-spark) [default], iteration 27 completed (7817.028 ms) ====== [2025-10-03T20:46:48.246Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-03T20:46:48.246Z] GC before operation: completed in 245.992 ms, heap usage 237.144 MB -> 71.743 MB. [2025-10-03T20:46:56.512Z] ====== als (apache-spark) [default], iteration 28 completed (8480.018 ms) ====== [2025-10-03T20:46:56.512Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-03T20:46:57.466Z] GC before operation: completed in 232.429 ms, heap usage 179.362 MB -> 71.701 MB. [2025-10-03T20:47:05.719Z] ====== als (apache-spark) [default], iteration 29 completed (7929.541 ms) ====== [2025-10-03T20:47:05.719Z] ----------------------------------- [2025-10-03T20:47:05.719Z] renaissance-als_0_PASSED [2025-10-03T20:47:05.719Z] ----------------------------------- [2025-10-03T20:47:05.719Z] [2025-10-03T20:47:05.719Z] TEST TEARDOWN: [2025-10-03T20:47:05.719Z] Nothing to be done for teardown. [2025-10-03T20:47:05.719Z] renaissance-als_0 Finish Time: Fri Oct 3 20:47:05 2025 Epoch Time (ms): 1759524425626