[2025-11-06T13:58:36.149Z] Running test renaissance-als_0 ... [2025-11-06T13:58:36.149Z] =============================================== [2025-11-06T13:58:36.149Z] renaissance-als_0 Start Time: Thu Nov 6 13:58:35 2025 Epoch Time (ms): 1762437515574 [2025-11-06T13:58:36.149Z] variation: NoOptions [2025-11-06T13:58:36.149Z] JVM_OPTIONS: [2025-11-06T13:58:36.149Z] { \ [2025-11-06T13:58:36.149Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T13:58:36.149Z] echo "Nothing to be done for setup."; \ [2025-11-06T13:58:36.149Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624375152549/renaissance-als_0"; \ [2025-11-06T13:58:36.149Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624375152549/renaissance-als_0"; \ [2025-11-06T13:58:36.149Z] echo ""; echo "TESTING:"; \ [2025-11-06T13:58:36.149Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/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_alpine-linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624375152549/renaissance-als_0"/als.json" als; \ [2025-11-06T13:58:36.149Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624375152549/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T13:58:36.149Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T13:58:36.149Z] echo "Nothing to be done for teardown."; \ [2025-11-06T13:58:36.149Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624375152549/TestTargetResult"; [2025-11-06T13:58:36.149Z] [2025-11-06T13:58:36.149Z] TEST SETUP: [2025-11-06T13:58:36.149Z] Nothing to be done for setup. [2025-11-06T13:58:36.149Z] [2025-11-06T13:58:36.149Z] TESTING: [2025-11-06T13:58:41.431Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-06T13:58:44.644Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-06T13:58:44.644Z] GC before operation: completed in 37.093 ms, heap usage 88.877 MB -> 34.937 MB. [2025-11-06T13:59:06.561Z] ====== als (apache-spark) [default], iteration 0 completed (20570.917 ms) ====== [2025-11-06T13:59:06.561Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-06T13:59:06.561Z] GC before operation: completed in 95.122 ms, heap usage 497.598 MB -> 62.318 MB. [2025-11-06T13:59:14.498Z] ====== als (apache-spark) [default], iteration 1 completed (7947.380 ms) ====== [2025-11-06T13:59:14.498Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-06T13:59:14.498Z] GC before operation: completed in 84.081 ms, heap usage 706.980 MB -> 63.499 MB. [2025-11-06T13:59:21.121Z] ====== als (apache-spark) [default], iteration 2 completed (7491.798 ms) ====== [2025-11-06T13:59:21.121Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-06T13:59:21.121Z] GC before operation: completed in 72.656 ms, heap usage 132.540 MB -> 63.559 MB. [2025-11-06T13:59:29.013Z] ====== als (apache-spark) [default], iteration 3 completed (7242.148 ms) ====== [2025-11-06T13:59:29.013Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-06T13:59:29.013Z] GC before operation: completed in 95.843 ms, heap usage 232.489 MB -> 64.114 MB. [2025-11-06T13:59:37.376Z] ====== als (apache-spark) [default], iteration 4 completed (7271.491 ms) ====== [2025-11-06T13:59:37.376Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-06T13:59:37.376Z] GC before operation: completed in 83.215 ms, heap usage 461.529 MB -> 65.063 MB. [2025-11-06T13:59:43.910Z] ====== als (apache-spark) [default], iteration 5 completed (6918.745 ms) ====== [2025-11-06T13:59:43.910Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-06T13:59:43.910Z] GC before operation: completed in 83.662 ms, heap usage 121.726 MB -> 65.162 MB. [2025-11-06T13:59:51.814Z] ====== als (apache-spark) [default], iteration 6 completed (6990.316 ms) ====== [2025-11-06T13:59:51.815Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-06T13:59:51.815Z] GC before operation: completed in 84.388 ms, heap usage 294.938 MB -> 65.848 MB. [2025-11-06T13:59:58.351Z] ====== als (apache-spark) [default], iteration 7 completed (7001.533 ms) ====== [2025-11-06T13:59:58.351Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-06T13:59:58.351Z] GC before operation: completed in 82.885 ms, heap usage 271.970 MB -> 66.364 MB. [2025-11-06T14:00:04.999Z] ====== als (apache-spark) [default], iteration 8 completed (6900.552 ms) ====== [2025-11-06T14:00:04.999Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-06T14:00:05.719Z] GC before operation: completed in 81.312 ms, heap usage 251.972 MB -> 66.975 MB. [2025-11-06T14:00:12.290Z] ====== als (apache-spark) [default], iteration 9 completed (7007.914 ms) ====== [2025-11-06T14:00:12.290Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-06T14:00:13.009Z] GC before operation: completed in 92.920 ms, heap usage 500.458 MB -> 67.405 MB. [2025-11-06T14:00:19.536Z] ====== als (apache-spark) [default], iteration 10 completed (6923.370 ms) ====== [2025-11-06T14:00:19.536Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-06T14:00:19.536Z] GC before operation: completed in 92.278 ms, heap usage 146.790 MB -> 67.376 MB. [2025-11-06T14:00:27.443Z] ====== als (apache-spark) [default], iteration 11 completed (6960.265 ms) ====== [2025-11-06T14:00:27.443Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-06T14:00:27.443Z] GC before operation: completed in 91.323 ms, heap usage 751.296 MB -> 68.365 MB. [2025-11-06T14:00:34.137Z] ====== als (apache-spark) [default], iteration 12 completed (6657.376 ms) ====== [2025-11-06T14:00:34.137Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-06T14:00:34.137Z] GC before operation: completed in 90.015 ms, heap usage 391.885 MB -> 68.040 MB. [2025-11-06T14:00:40.675Z] ====== als (apache-spark) [default], iteration 13 completed (6761.617 ms) ====== [2025-11-06T14:00:40.675Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-06T14:00:40.675Z] GC before operation: completed in 87.269 ms, heap usage 482.677 MB -> 68.618 MB. [2025-11-06T14:00:48.599Z] ====== als (apache-spark) [default], iteration 14 completed (6837.164 ms) ====== [2025-11-06T14:00:48.599Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-06T14:00:48.599Z] GC before operation: completed in 88.260 ms, heap usage 503.163 MB -> 68.790 MB. [2025-11-06T14:00:55.131Z] ====== als (apache-spark) [default], iteration 15 completed (6588.038 ms) ====== [2025-11-06T14:00:55.131Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-06T14:00:55.131Z] GC before operation: completed in 91.984 ms, heap usage 176.580 MB -> 68.624 MB. [2025-11-06T14:01:02.907Z] ====== als (apache-spark) [default], iteration 16 completed (7884.699 ms) ====== [2025-11-06T14:01:02.907Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-06T14:01:02.907Z] GC before operation: completed in 113.882 ms, heap usage 292.171 MB -> 69.067 MB. [2025-11-06T14:01:10.831Z] ====== als (apache-spark) [default], iteration 17 completed (6901.456 ms) ====== [2025-11-06T14:01:10.831Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-06T14:01:10.831Z] GC before operation: completed in 98.852 ms, heap usage 115.779 MB -> 69.193 MB. [2025-11-06T14:01:17.361Z] ====== als (apache-spark) [default], iteration 18 completed (6857.043 ms) ====== [2025-11-06T14:01:17.361Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-06T14:01:17.361Z] GC before operation: completed in 97.236 ms, heap usage 731.724 MB -> 70.120 MB. [2025-11-06T14:01:25.305Z] ====== als (apache-spark) [default], iteration 19 completed (6964.372 ms) ====== [2025-11-06T14:01:25.305Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-06T14:01:25.305Z] GC before operation: completed in 92.867 ms, heap usage 184.683 MB -> 69.700 MB. [2025-11-06T14:01:34.768Z] ====== als (apache-spark) [default], iteration 20 completed (9066.466 ms) ====== [2025-11-06T14:01:34.768Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-06T14:01:34.768Z] GC before operation: completed in 97.121 ms, heap usage 729.777 MB -> 70.711 MB. [2025-11-06T14:01:41.521Z] ====== als (apache-spark) [default], iteration 21 completed (6848.581 ms) ====== [2025-11-06T14:01:41.521Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-06T14:01:41.521Z] GC before operation: completed in 93.406 ms, heap usage 683.738 MB -> 70.942 MB. [2025-11-06T14:01:48.054Z] ====== als (apache-spark) [default], iteration 22 completed (6931.288 ms) ====== [2025-11-06T14:01:48.054Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-06T14:01:48.054Z] GC before operation: completed in 90.208 ms, heap usage 199.730 MB -> 70.592 MB. [2025-11-06T14:01:54.594Z] ====== als (apache-spark) [default], iteration 23 completed (6636.002 ms) ====== [2025-11-06T14:01:54.594Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-06T14:01:54.594Z] GC before operation: completed in 92.024 ms, heap usage 344.128 MB -> 71.062 MB. [2025-11-06T14:02:02.505Z] ====== als (apache-spark) [default], iteration 24 completed (6651.150 ms) ====== [2025-11-06T14:02:02.505Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-06T14:02:02.505Z] GC before operation: completed in 95.199 ms, heap usage 546.010 MB -> 71.621 MB. [2025-11-06T14:02:09.090Z] ====== als (apache-spark) [default], iteration 25 completed (6719.052 ms) ====== [2025-11-06T14:02:09.090Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-06T14:02:09.090Z] GC before operation: completed in 93.419 ms, heap usage 487.460 MB -> 71.759 MB. [2025-11-06T14:02:15.629Z] ====== als (apache-spark) [default], iteration 26 completed (6618.694 ms) ====== [2025-11-06T14:02:15.629Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-06T14:02:15.629Z] GC before operation: completed in 89.960 ms, heap usage 161.430 MB -> 71.551 MB. [2025-11-06T14:02:22.159Z] ====== als (apache-spark) [default], iteration 27 completed (6702.474 ms) ====== [2025-11-06T14:02:22.159Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-06T14:02:22.888Z] GC before operation: completed in 98.565 ms, heap usage 134.771 MB -> 71.854 MB. [2025-11-06T14:02:29.421Z] ====== als (apache-spark) [default], iteration 28 completed (6900.991 ms) ====== [2025-11-06T14:02:29.421Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-06T14:02:29.421Z] GC before operation: completed in 92.355 ms, heap usage 706.460 MB -> 72.228 MB. [2025-11-06T14:02:37.920Z] ====== als (apache-spark) [default], iteration 29 completed (6632.234 ms) ====== [2025-11-06T14:02:37.920Z] ----------------------------------- [2025-11-06T14:02:37.920Z] renaissance-als_0_PASSED [2025-11-06T14:02:37.920Z] ----------------------------------- [2025-11-06T14:02:37.920Z] [2025-11-06T14:02:37.920Z] TEST TEARDOWN: [2025-11-06T14:02:37.920Z] Nothing to be done for teardown. [2025-11-06T14:02:37.920Z] renaissance-als_0 Finish Time: Thu Nov 6 14:02:36 2025 Epoch Time (ms): 1762437756433