[2025-11-05T22:35:45.109Z] Running test renaissance-als_0 ... [2025-11-05T22:35:45.110Z] =============================================== [2025-11-05T22:35:45.110Z] renaissance-als_0 Start Time: Wed Nov 5 22:35:44 2025 Epoch Time (ms): 1762382144785 [2025-11-05T22:35:45.110Z] variation: NoOptions [2025-11-05T22:35:45.110Z] JVM_OPTIONS: [2025-11-05T22:35:45.110Z] { \ [2025-11-05T22:35:45.110Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T22:35:45.110Z] echo "Nothing to be done for setup."; \ [2025-11-05T22:35:45.110Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623819334793/renaissance-als_0"; \ [2025-11-05T22:35:45.110Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623819334793/renaissance-als_0"; \ [2025-11-05T22:35:45.110Z] echo ""; echo "TESTING:"; \ [2025-11-05T22:35:45.110Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623819334793/renaissance-als_0"/als.json" als; \ [2025-11-05T22:35:45.110Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623819334793/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T22:35:45.110Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T22:35:45.110Z] echo "Nothing to be done for teardown."; \ [2025-11-05T22:35:45.110Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623819334793/TestTargetResult"; [2025-11-05T22:35:45.110Z] [2025-11-05T22:35:45.110Z] TEST SETUP: [2025-11-05T22:35:45.110Z] Nothing to be done for setup. [2025-11-05T22:35:45.110Z] [2025-11-05T22:35:45.110Z] TESTING: [2025-11-05T22:35:50.008Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-05T22:35:54.899Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-05T22:35:54.899Z] GC before operation: completed in 62.694 ms, heap usage 280.926 MB -> 34.711 MB. [2025-11-05T22:36:29.581Z] ====== als (apache-spark) [default], iteration 0 completed (30323.194 ms) ====== [2025-11-05T22:36:29.581Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-05T22:36:29.582Z] GC before operation: completed in 117.917 ms, heap usage 434.123 MB -> 61.564 MB. [2025-11-05T22:36:40.710Z] ====== als (apache-spark) [default], iteration 1 completed (14197.912 ms) ====== [2025-11-05T22:36:40.710Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-05T22:36:40.710Z] GC before operation: completed in 113.069 ms, heap usage 354.582 MB -> 62.698 MB. [2025-11-05T22:36:54.008Z] ====== als (apache-spark) [default], iteration 2 completed (12482.639 ms) ====== [2025-11-05T22:36:54.008Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-05T22:36:54.008Z] GC before operation: completed in 98.698 ms, heap usage 343.302 MB -> 63.275 MB. [2025-11-05T22:37:07.309Z] ====== als (apache-spark) [default], iteration 3 completed (12795.446 ms) ====== [2025-11-05T22:37:07.309Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-05T22:37:07.309Z] GC before operation: completed in 108.997 ms, heap usage 637.260 MB -> 64.208 MB. [2025-11-05T22:37:18.249Z] ====== als (apache-spark) [default], iteration 4 completed (11839.022 ms) ====== [2025-11-05T22:37:18.249Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-05T22:37:18.249Z] GC before operation: completed in 112.094 ms, heap usage 758.043 MB -> 64.736 MB. [2025-11-05T22:37:31.542Z] ====== als (apache-spark) [default], iteration 5 completed (11574.094 ms) ====== [2025-11-05T22:37:31.542Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-05T22:37:31.542Z] GC before operation: completed in 107.674 ms, heap usage 521.822 MB -> 64.996 MB. [2025-11-05T22:37:42.458Z] ====== als (apache-spark) [default], iteration 6 completed (11631.682 ms) ====== [2025-11-05T22:37:42.458Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-05T22:37:42.458Z] GC before operation: completed in 110.763 ms, heap usage 465.219 MB -> 65.542 MB. [2025-11-05T22:37:55.736Z] ====== als (apache-spark) [default], iteration 7 completed (11419.958 ms) ====== [2025-11-05T22:37:55.736Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-05T22:37:55.736Z] GC before operation: completed in 107.170 ms, heap usage 509.498 MB -> 66.129 MB. [2025-11-05T22:38:07.004Z] ====== als (apache-spark) [default], iteration 8 completed (11998.894 ms) ====== [2025-11-05T22:38:07.004Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-05T22:38:07.004Z] GC before operation: completed in 108.246 ms, heap usage 388.702 MB -> 66.443 MB. [2025-11-05T22:38:18.862Z] ====== als (apache-spark) [default], iteration 9 completed (11517.738 ms) ====== [2025-11-05T22:38:18.862Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-05T22:38:18.862Z] GC before operation: completed in 114.078 ms, heap usage 378.725 MB -> 66.784 MB. [2025-11-05T22:38:30.089Z] ====== als (apache-spark) [default], iteration 10 completed (11511.538 ms) ====== [2025-11-05T22:38:30.089Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-05T22:38:30.089Z] GC before operation: completed in 119.532 ms, heap usage 499.124 MB -> 67.216 MB. [2025-11-05T22:38:43.493Z] ====== als (apache-spark) [default], iteration 11 completed (11456.226 ms) ====== [2025-11-05T22:38:43.493Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-05T22:38:43.493Z] GC before operation: completed in 116.789 ms, heap usage 466.595 MB -> 67.406 MB. [2025-11-05T22:38:54.496Z] ====== als (apache-spark) [default], iteration 12 completed (11505.368 ms) ====== [2025-11-05T22:38:54.496Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-05T22:38:54.496Z] GC before operation: completed in 119.207 ms, heap usage 555.906 MB -> 67.785 MB. [2025-11-05T22:39:05.520Z] ====== als (apache-spark) [default], iteration 13 completed (11339.957 ms) ====== [2025-11-05T22:39:05.520Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-05T22:39:05.520Z] GC before operation: completed in 114.232 ms, heap usage 387.611 MB -> 67.746 MB. [2025-11-05T22:39:18.758Z] ====== als (apache-spark) [default], iteration 14 completed (11458.760 ms) ====== [2025-11-05T22:39:18.758Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-05T22:39:18.758Z] GC before operation: completed in 116.209 ms, heap usage 691.544 MB -> 68.480 MB. [2025-11-05T22:39:29.755Z] ====== als (apache-spark) [default], iteration 15 completed (11313.617 ms) ====== [2025-11-05T22:39:29.755Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-05T22:39:29.755Z] GC before operation: completed in 117.146 ms, heap usage 509.111 MB -> 68.527 MB. [2025-11-05T22:39:40.705Z] ====== als (apache-spark) [default], iteration 16 completed (11203.170 ms) ====== [2025-11-05T22:39:40.705Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-05T22:39:40.705Z] GC before operation: completed in 114.320 ms, heap usage 382.427 MB -> 68.491 MB. [2025-11-05T22:39:51.992Z] ====== als (apache-spark) [default], iteration 17 completed (11216.911 ms) ====== [2025-11-05T22:39:51.992Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-05T22:39:51.992Z] GC before operation: completed in 118.417 ms, heap usage 493.403 MB -> 69.078 MB. [2025-11-05T22:40:03.293Z] ====== als (apache-spark) [default], iteration 18 completed (11268.391 ms) ====== [2025-11-05T22:40:03.293Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-05T22:40:03.293Z] GC before operation: completed in 116.761 ms, heap usage 271.062 MB -> 69.003 MB. [2025-11-05T22:40:16.377Z] ====== als (apache-spark) [default], iteration 19 completed (11214.496 ms) ====== [2025-11-05T22:40:16.377Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-05T22:40:16.377Z] GC before operation: completed in 117.229 ms, heap usage 221.636 MB -> 69.148 MB. [2025-11-05T22:40:27.795Z] ====== als (apache-spark) [default], iteration 20 completed (11532.244 ms) ====== [2025-11-05T22:40:27.795Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-05T22:40:27.795Z] GC before operation: completed in 118.468 ms, heap usage 405.518 MB -> 69.467 MB. [2025-11-05T22:40:39.014Z] ====== als (apache-spark) [default], iteration 21 completed (11688.108 ms) ====== [2025-11-05T22:40:39.014Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-05T22:40:39.014Z] GC before operation: completed in 113.256 ms, heap usage 398.052 MB -> 69.811 MB. [2025-11-05T22:40:50.152Z] ====== als (apache-spark) [default], iteration 22 completed (11046.485 ms) ====== [2025-11-05T22:40:50.152Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-05T22:40:50.152Z] GC before operation: completed in 118.591 ms, heap usage 520.293 MB -> 70.260 MB. [2025-11-05T22:41:03.581Z] ====== als (apache-spark) [default], iteration 23 completed (11332.283 ms) ====== [2025-11-05T22:41:03.581Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-05T22:41:03.581Z] GC before operation: completed in 176.400 ms, heap usage 633.388 MB -> 70.700 MB. [2025-11-05T22:41:14.680Z] ====== als (apache-spark) [default], iteration 24 completed (11307.352 ms) ====== [2025-11-05T22:41:14.680Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-05T22:41:14.680Z] GC before operation: completed in 117.505 ms, heap usage 251.195 MB -> 70.489 MB. [2025-11-05T22:41:26.068Z] ====== als (apache-spark) [default], iteration 25 completed (11056.380 ms) ====== [2025-11-05T22:41:26.068Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-05T22:41:26.068Z] GC before operation: completed in 114.730 ms, heap usage 201.323 MB -> 70.654 MB. [2025-11-05T22:41:37.121Z] ====== als (apache-spark) [default], iteration 26 completed (11421.390 ms) ====== [2025-11-05T22:41:37.121Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-05T22:41:37.121Z] GC before operation: completed in 112.462 ms, heap usage 137.054 MB -> 70.951 MB. [2025-11-05T22:41:48.200Z] ====== als (apache-spark) [default], iteration 27 completed (11351.862 ms) ====== [2025-11-05T22:41:48.200Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-05T22:41:48.200Z] GC before operation: completed in 119.246 ms, heap usage 687.186 MB -> 71.776 MB. [2025-11-05T22:42:01.475Z] ====== als (apache-spark) [default], iteration 28 completed (11397.790 ms) ====== [2025-11-05T22:42:01.475Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-05T22:42:01.475Z] GC before operation: completed in 120.810 ms, heap usage 167.102 MB -> 70.840 MB. [2025-11-05T22:42:12.464Z] ====== als (apache-spark) [default], iteration 29 completed (11040.164 ms) ====== [2025-11-05T22:42:12.464Z] ----------------------------------- [2025-11-05T22:42:12.464Z] renaissance-als_0_PASSED [2025-11-05T22:42:12.464Z] ----------------------------------- [2025-11-05T22:42:12.464Z] [2025-11-05T22:42:12.464Z] TEST TEARDOWN: [2025-11-05T22:42:12.464Z] Nothing to be done for teardown. [2025-11-05T22:42:12.464Z] renaissance-als_0 Finish Time: Wed Nov 5 22:42:11 2025 Epoch Time (ms): 1762382531205