[2025-10-02T12:54:37.849Z] Running test renaissance-als_0 ... [2025-10-02T12:54:37.849Z] =============================================== [2025-10-02T12:54:37.849Z] renaissance-als_0 Start Time: Thu Oct 2 12:54:37 2025 Epoch Time (ms): 1759409677770 [2025-10-02T12:54:37.849Z] variation: NoOptions [2025-10-02T12:54:37.849Z] JVM_OPTIONS: [2025-10-02T12:54:37.849Z] { \ [2025-10-02T12:54:37.849Z] echo ""; echo "TEST SETUP:"; \ [2025-10-02T12:54:37.849Z] echo "Nothing to be done for setup."; \ [2025-10-02T12:54:37.850Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17594096773416/renaissance-als_0"; \ [2025-10-02T12:54:37.850Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17594096773416/renaissance-als_0"; \ [2025-10-02T12:54:37.850Z] echo ""; echo "TESTING:"; \ [2025-10-02T12:54:37.850Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17594096773416/renaissance-als_0"/als.json" als; \ [2025-10-02T12:54:37.850Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17594096773416/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-02T12:54:37.850Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-02T12:54:37.850Z] echo "Nothing to be done for teardown."; \ [2025-10-02T12:54:37.850Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17594096773416/TestTargetResult"; [2025-10-02T12:54:37.850Z] [2025-10-02T12:54:37.850Z] TEST SETUP: [2025-10-02T12:54:37.850Z] Nothing to be done for setup. [2025-10-02T12:54:37.850Z] [2025-10-02T12:54:37.850Z] TESTING: [2025-10-02T12:54:43.238Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-02T12:54:47.531Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-02T12:54:47.531Z] GC before operation: completed in 44.096 ms, heap usage 53.835 MB -> 35.109 MB. [2025-10-02T12:55:09.173Z] ====== als (apache-spark) [default], iteration 0 completed (19579.716 ms) ====== [2025-10-02T12:55:09.173Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-02T12:55:09.173Z] GC before operation: completed in 104.290 ms, heap usage 258.294 MB -> 61.891 MB. [2025-10-02T12:55:15.831Z] ====== als (apache-spark) [default], iteration 1 completed (7912.913 ms) ====== [2025-10-02T12:55:15.831Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-02T12:55:15.831Z] GC before operation: completed in 76.592 ms, heap usage 192.753 MB -> 62.656 MB. [2025-10-02T12:55:23.831Z] ====== als (apache-spark) [default], iteration 2 completed (7410.204 ms) ====== [2025-10-02T12:55:23.831Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-02T12:55:23.831Z] GC before operation: completed in 91.011 ms, heap usage 263.844 MB -> 63.343 MB. [2025-10-02T12:55:31.929Z] ====== als (apache-spark) [default], iteration 3 completed (7511.722 ms) ====== [2025-10-02T12:55:31.929Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-02T12:55:31.929Z] GC before operation: completed in 82.429 ms, heap usage 306.870 MB -> 64.077 MB. [2025-10-02T12:55:38.597Z] ====== als (apache-spark) [default], iteration 4 completed (7169.683 ms) ====== [2025-10-02T12:55:38.597Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-02T12:55:38.597Z] GC before operation: completed in 86.179 ms, heap usage 323.729 MB -> 64.593 MB. [2025-10-02T12:55:46.640Z] ====== als (apache-spark) [default], iteration 5 completed (6869.447 ms) ====== [2025-10-02T12:55:46.640Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-02T12:55:46.640Z] GC before operation: completed in 92.793 ms, heap usage 213.978 MB -> 65.048 MB. [2025-10-02T12:55:53.301Z] ====== als (apache-spark) [default], iteration 6 completed (6943.715 ms) ====== [2025-10-02T12:55:53.301Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-02T12:55:53.301Z] GC before operation: completed in 86.487 ms, heap usage 312.876 MB -> 65.680 MB. [2025-10-02T12:55:59.981Z] ====== als (apache-spark) [default], iteration 7 completed (6796.828 ms) ====== [2025-10-02T12:55:59.981Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-02T12:55:59.981Z] GC before operation: completed in 108.543 ms, heap usage 188.448 MB -> 65.980 MB. [2025-10-02T12:56:08.036Z] ====== als (apache-spark) [default], iteration 8 completed (6853.539 ms) ====== [2025-10-02T12:56:08.036Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-02T12:56:08.036Z] GC before operation: completed in 110.830 ms, heap usage 350.501 MB -> 66.781 MB. [2025-10-02T12:56:14.697Z] ====== als (apache-spark) [default], iteration 9 completed (6935.722 ms) ====== [2025-10-02T12:56:14.697Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-02T12:56:14.697Z] GC before operation: completed in 84.529 ms, heap usage 435.665 MB -> 67.246 MB. [2025-10-02T12:56:21.346Z] ====== als (apache-spark) [default], iteration 10 completed (7029.146 ms) ====== [2025-10-02T12:56:21.346Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-02T12:56:22.082Z] GC before operation: completed in 90.816 ms, heap usage 476.050 MB -> 67.432 MB. [2025-10-02T12:56:28.742Z] ====== als (apache-spark) [default], iteration 11 completed (6820.474 ms) ====== [2025-10-02T12:56:28.742Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-02T12:56:28.742Z] GC before operation: completed in 95.153 ms, heap usage 508.270 MB -> 67.687 MB. [2025-10-02T12:56:36.787Z] ====== als (apache-spark) [default], iteration 12 completed (6832.530 ms) ====== [2025-10-02T12:56:36.787Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-02T12:56:36.787Z] GC before operation: completed in 88.511 ms, heap usage 438.302 MB -> 67.996 MB. [2025-10-02T12:56:43.455Z] ====== als (apache-spark) [default], iteration 13 completed (6667.992 ms) ====== [2025-10-02T12:56:43.455Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-02T12:56:43.455Z] GC before operation: completed in 89.043 ms, heap usage 558.312 MB -> 68.471 MB. [2025-10-02T12:56:50.186Z] ====== als (apache-spark) [default], iteration 14 completed (6600.121 ms) ====== [2025-10-02T12:56:50.186Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-02T12:56:50.186Z] GC before operation: completed in 96.738 ms, heap usage 151.438 MB -> 68.055 MB. [2025-10-02T12:56:56.843Z] ====== als (apache-spark) [default], iteration 15 completed (6748.231 ms) ====== [2025-10-02T12:56:56.843Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-02T12:56:56.843Z] GC before operation: completed in 91.533 ms, heap usage 518.269 MB -> 68.800 MB. [2025-10-02T12:57:03.494Z] ====== als (apache-spark) [default], iteration 16 completed (6692.584 ms) ====== [2025-10-02T12:57:03.494Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-02T12:57:03.494Z] GC before operation: completed in 94.494 ms, heap usage 273.423 MB -> 68.768 MB. [2025-10-02T12:57:10.162Z] ====== als (apache-spark) [default], iteration 17 completed (6689.847 ms) ====== [2025-10-02T12:57:10.162Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-02T12:57:10.910Z] GC before operation: completed in 90.455 ms, heap usage 472.690 MB -> 69.286 MB. [2025-10-02T12:57:17.568Z] ====== als (apache-spark) [default], iteration 18 completed (6605.370 ms) ====== [2025-10-02T12:57:17.568Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-02T12:57:17.568Z] GC before operation: completed in 88.295 ms, heap usage 498.317 MB -> 69.560 MB. [2025-10-02T12:57:24.223Z] ====== als (apache-spark) [default], iteration 19 completed (6527.510 ms) ====== [2025-10-02T12:57:24.223Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-02T12:57:24.223Z] GC before operation: completed in 85.889 ms, heap usage 171.288 MB -> 69.377 MB. [2025-10-02T12:57:32.267Z] ====== als (apache-spark) [default], iteration 20 completed (6990.040 ms) ====== [2025-10-02T12:57:32.267Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-02T12:57:32.267Z] GC before operation: completed in 84.151 ms, heap usage 288.577 MB -> 69.780 MB. [2025-10-02T12:57:38.919Z] ====== als (apache-spark) [default], iteration 21 completed (6662.754 ms) ====== [2025-10-02T12:57:38.919Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-02T12:57:38.919Z] GC before operation: completed in 92.285 ms, heap usage 205.423 MB -> 69.928 MB. [2025-10-02T12:57:45.588Z] ====== als (apache-spark) [default], iteration 22 completed (6717.266 ms) ====== [2025-10-02T12:57:45.588Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-02T12:57:45.588Z] GC before operation: completed in 87.710 ms, heap usage 204.267 MB -> 70.217 MB. [2025-10-02T12:57:52.235Z] ====== als (apache-spark) [default], iteration 23 completed (6470.790 ms) ====== [2025-10-02T12:57:52.235Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-02T12:57:52.235Z] GC before operation: completed in 89.967 ms, heap usage 304.303 MB -> 70.724 MB. [2025-10-02T12:57:58.893Z] ====== als (apache-spark) [default], iteration 24 completed (6772.510 ms) ====== [2025-10-02T12:57:58.893Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-02T12:57:58.893Z] GC before operation: completed in 90.652 ms, heap usage 349.642 MB -> 70.922 MB. [2025-10-02T12:58:06.016Z] ====== als (apache-spark) [default], iteration 25 completed (6621.210 ms) ====== [2025-10-02T12:58:06.016Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-02T12:58:06.016Z] GC before operation: completed in 93.359 ms, heap usage 440.735 MB -> 71.425 MB. [2025-10-02T12:58:12.675Z] ====== als (apache-spark) [default], iteration 26 completed (6636.978 ms) ====== [2025-10-02T12:58:12.676Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-02T12:58:12.676Z] GC before operation: completed in 91.182 ms, heap usage 303.152 MB -> 71.459 MB. [2025-10-02T12:58:19.323Z] ====== als (apache-spark) [default], iteration 27 completed (6599.410 ms) ====== [2025-10-02T12:58:19.323Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-02T12:58:19.323Z] GC before operation: completed in 85.477 ms, heap usage 502.036 MB -> 71.965 MB. [2025-10-02T12:58:25.982Z] ====== als (apache-spark) [default], iteration 28 completed (6529.255 ms) ====== [2025-10-02T12:58:25.982Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-02T12:58:25.982Z] GC before operation: completed in 83.046 ms, heap usage 248.115 MB -> 71.906 MB. [2025-10-02T12:58:32.639Z] ====== als (apache-spark) [default], iteration 29 completed (6373.851 ms) ====== [2025-10-02T12:58:33.373Z] ----------------------------------- [2025-10-02T12:58:33.373Z] renaissance-als_0_PASSED [2025-10-02T12:58:33.373Z] ----------------------------------- [2025-10-02T12:58:33.373Z] [2025-10-02T12:58:33.373Z] TEST TEARDOWN: [2025-10-02T12:58:33.373Z] Nothing to be done for teardown. [2025-10-02T12:58:33.373Z] renaissance-als_0 Finish Time: Thu Oct 2 12:58:32 2025 Epoch Time (ms): 1759409912712