[2025-11-13T05:47:58.828Z] Running test renaissance-als_0 ... [2025-11-13T05:47:58.828Z] =============================================== [2025-11-13T05:47:58.828Z] renaissance-als_0 Start Time: Thu Nov 13 05:47:58 2025 Epoch Time (ms): 1763012878342 [2025-11-13T05:47:58.828Z] variation: NoOptions [2025-11-13T05:47:58.828Z] JVM_OPTIONS: [2025-11-13T05:47:58.828Z] { \ [2025-11-13T05:47:58.828Z] echo ""; echo "TEST SETUP:"; \ [2025-11-13T05:47:58.828Z] echo "Nothing to be done for setup."; \ [2025-11-13T05:47:58.828Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17630128781253/renaissance-als_0"; \ [2025-11-13T05:47:58.828Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17630128781253/renaissance-als_0"; \ [2025-11-13T05:47:58.828Z] echo ""; echo "TESTING:"; \ [2025-11-13T05:47:58.828Z] "/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_17630128781253/renaissance-als_0"/als.json" als; \ [2025-11-13T05:47:58.828Z] 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_17630128781253/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-13T05:47:58.828Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-13T05:47:58.828Z] echo "Nothing to be done for teardown."; \ [2025-11-13T05:47:58.828Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17630128781253/TestTargetResult"; [2025-11-13T05:47:58.828Z] [2025-11-13T05:47:58.828Z] TEST SETUP: [2025-11-13T05:47:58.828Z] Nothing to be done for setup. [2025-11-13T05:47:58.828Z] [2025-11-13T05:47:58.828Z] TESTING: [2025-11-13T05:48:04.096Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-13T05:48:07.295Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-13T05:48:07.295Z] GC before operation: completed in 45.430 ms, heap usage 76.926 MB -> 35.068 MB. [2025-11-13T05:48:32.284Z] ====== als (apache-spark) [default], iteration 0 completed (22295.353 ms) ====== [2025-11-13T05:48:32.284Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-13T05:48:32.284Z] GC before operation: completed in 86.955 ms, heap usage 178.316 MB -> 62.284 MB. [2025-11-13T05:48:40.183Z] ====== als (apache-spark) [default], iteration 1 completed (8884.079 ms) ====== [2025-11-13T05:48:40.183Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-13T05:48:40.183Z] GC before operation: completed in 77.449 ms, heap usage 579.847 MB -> 63.502 MB. [2025-11-13T05:48:48.055Z] ====== als (apache-spark) [default], iteration 2 completed (7741.299 ms) ====== [2025-11-13T05:48:48.055Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-13T05:48:48.055Z] GC before operation: completed in 87.498 ms, heap usage 399.202 MB -> 63.732 MB. [2025-11-13T05:48:55.917Z] ====== als (apache-spark) [default], iteration 3 completed (7388.935 ms) ====== [2025-11-13T05:48:55.917Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-13T05:48:55.917Z] GC before operation: completed in 81.901 ms, heap usage 166.966 MB -> 64.069 MB. [2025-11-13T05:49:03.798Z] ====== als (apache-spark) [default], iteration 4 completed (7568.991 ms) ====== [2025-11-13T05:49:03.798Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-13T05:49:03.798Z] GC before operation: completed in 83.347 ms, heap usage 128.089 MB -> 64.268 MB. [2025-11-13T05:49:10.298Z] ====== als (apache-spark) [default], iteration 5 completed (7171.080 ms) ====== [2025-11-13T05:49:10.298Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-13T05:49:10.298Z] GC before operation: completed in 92.443 ms, heap usage 247.743 MB -> 65.282 MB. [2025-11-13T05:49:18.161Z] ====== als (apache-spark) [default], iteration 6 completed (7219.041 ms) ====== [2025-11-13T05:49:18.162Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-13T05:49:18.162Z] GC before operation: completed in 80.487 ms, heap usage 122.079 MB -> 65.793 MB. [2025-11-13T05:49:26.028Z] ====== als (apache-spark) [default], iteration 7 completed (6923.870 ms) ====== [2025-11-13T05:49:26.028Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-13T05:49:26.028Z] GC before operation: completed in 86.333 ms, heap usage 689.300 MB -> 67.003 MB. [2025-11-13T05:49:33.021Z] ====== als (apache-spark) [default], iteration 8 completed (7089.942 ms) ====== [2025-11-13T05:49:33.021Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-13T05:49:33.021Z] GC before operation: completed in 91.947 ms, heap usage 678.313 MB -> 67.549 MB. [2025-11-13T05:49:39.533Z] ====== als (apache-spark) [default], iteration 9 completed (6796.440 ms) ====== [2025-11-13T05:49:39.533Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-13T05:49:39.533Z] GC before operation: completed in 99.589 ms, heap usage 222.864 MB -> 67.006 MB. [2025-11-13T05:49:47.392Z] ====== als (apache-spark) [default], iteration 10 completed (7100.933 ms) ====== [2025-11-13T05:49:47.392Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-13T05:49:47.392Z] GC before operation: completed in 86.538 ms, heap usage 554.793 MB -> 67.907 MB. [2025-11-13T05:49:53.886Z] ====== als (apache-spark) [default], iteration 11 completed (6829.131 ms) ====== [2025-11-13T05:49:53.886Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-13T05:49:53.886Z] GC before operation: completed in 89.573 ms, heap usage 640.762 MB -> 68.264 MB. [2025-11-13T05:50:01.735Z] ====== als (apache-spark) [default], iteration 12 completed (7212.514 ms) ====== [2025-11-13T05:50:01.735Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-13T05:50:01.735Z] GC before operation: completed in 87.295 ms, heap usage 171.537 MB -> 67.970 MB. [2025-11-13T05:50:09.584Z] ====== als (apache-spark) [default], iteration 13 completed (6919.931 ms) ====== [2025-11-13T05:50:09.584Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-13T05:50:09.584Z] GC before operation: completed in 88.763 ms, heap usage 370.384 MB -> 68.364 MB. [2025-11-13T05:50:16.146Z] ====== als (apache-spark) [default], iteration 14 completed (6745.962 ms) ====== [2025-11-13T05:50:16.146Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-13T05:50:16.146Z] GC before operation: completed in 90.818 ms, heap usage 146.480 MB -> 68.397 MB. [2025-11-13T05:50:24.027Z] ====== als (apache-spark) [default], iteration 15 completed (6700.999 ms) ====== [2025-11-13T05:50:24.027Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-13T05:50:24.027Z] GC before operation: completed in 97.387 ms, heap usage 493.186 MB -> 69.146 MB. [2025-11-13T05:50:35.179Z] ====== als (apache-spark) [default], iteration 16 completed (10916.944 ms) ====== [2025-11-13T05:50:35.179Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-13T05:50:35.179Z] GC before operation: completed in 90.672 ms, heap usage 233.033 MB -> 68.915 MB. [2025-11-13T05:50:41.700Z] ====== als (apache-spark) [default], iteration 17 completed (7277.814 ms) ====== [2025-11-13T05:50:41.700Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-13T05:50:41.700Z] GC before operation: completed in 87.143 ms, heap usage 266.611 MB -> 69.310 MB. [2025-11-13T05:50:52.833Z] ====== als (apache-spark) [default], iteration 18 completed (10188.934 ms) ====== [2025-11-13T05:50:52.833Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-13T05:50:52.833Z] GC before operation: completed in 95.338 ms, heap usage 382.771 MB -> 69.759 MB. [2025-11-13T05:50:59.334Z] ====== als (apache-spark) [default], iteration 19 completed (6853.026 ms) ====== [2025-11-13T05:50:59.334Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-13T05:50:59.334Z] GC before operation: completed in 93.898 ms, heap usage 553.025 MB -> 70.317 MB. [2025-11-13T05:51:07.202Z] ====== als (apache-spark) [default], iteration 20 completed (7781.963 ms) ====== [2025-11-13T05:51:07.202Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-13T05:51:07.924Z] GC before operation: completed in 93.413 ms, heap usage 548.530 MB -> 70.461 MB. [2025-11-13T05:51:17.895Z] ====== als (apache-spark) [default], iteration 21 completed (9427.995 ms) ====== [2025-11-13T05:51:17.895Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-13T05:51:17.895Z] GC before operation: completed in 99.586 ms, heap usage 647.814 MB -> 70.872 MB. [2025-11-13T05:51:25.754Z] ====== als (apache-spark) [default], iteration 22 completed (8345.942 ms) ====== [2025-11-13T05:51:25.754Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-13T05:51:25.754Z] GC before operation: completed in 93.215 ms, heap usage 131.307 MB -> 70.509 MB. [2025-11-13T05:51:36.904Z] ====== als (apache-spark) [default], iteration 23 completed (9876.273 ms) ====== [2025-11-13T05:51:36.904Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-13T05:51:36.904Z] GC before operation: completed in 96.764 ms, heap usage 696.942 MB -> 71.386 MB. [2025-11-13T05:51:43.420Z] ====== als (apache-spark) [default], iteration 24 completed (7094.385 ms) ====== [2025-11-13T05:51:43.420Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-13T05:51:43.420Z] GC before operation: completed in 95.050 ms, heap usage 754.490 MB -> 71.791 MB. [2025-11-13T05:51:52.900Z] ====== als (apache-spark) [default], iteration 25 completed (8463.083 ms) ====== [2025-11-13T05:51:52.900Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-13T05:51:52.900Z] GC before operation: completed in 96.985 ms, heap usage 715.464 MB -> 72.056 MB. [2025-11-13T05:51:59.421Z] ====== als (apache-spark) [default], iteration 26 completed (7083.969 ms) ====== [2025-11-13T05:51:59.421Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-13T05:51:59.421Z] GC before operation: completed in 91.965 ms, heap usage 216.398 MB -> 71.602 MB. [2025-11-13T05:52:05.915Z] ====== als (apache-spark) [default], iteration 27 completed (6860.303 ms) ====== [2025-11-13T05:52:05.915Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-13T05:52:06.638Z] GC before operation: completed in 97.170 ms, heap usage 559.546 MB -> 72.404 MB. [2025-11-13T05:52:13.139Z] ====== als (apache-spark) [default], iteration 28 completed (6686.007 ms) ====== [2025-11-13T05:52:13.139Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-13T05:52:13.139Z] GC before operation: completed in 96.743 ms, heap usage 458.506 MB -> 72.074 MB. [2025-11-13T05:52:20.112Z] ====== als (apache-spark) [default], iteration 29 completed (6480.263 ms) ====== [2025-11-13T05:52:20.112Z] ----------------------------------- [2025-11-13T05:52:20.112Z] renaissance-als_0_PASSED [2025-11-13T05:52:20.112Z] ----------------------------------- [2025-11-13T05:52:20.112Z] [2025-11-13T05:52:20.112Z] TEST TEARDOWN: [2025-11-13T05:52:20.112Z] Nothing to be done for teardown. [2025-11-13T05:52:20.112Z] renaissance-als_0 Finish Time: Thu Nov 13 05:52:19 2025 Epoch Time (ms): 1763013139825