[2025-06-12T19:34:15.472Z] Running test renaissance-als_0 ... [2025-06-12T19:34:15.472Z] =============================================== [2025-06-12T19:34:15.472Z] renaissance-als_0 Start Time: Thu Jun 12 19:34:14 2025 Epoch Time (ms): 1749756854921 [2025-06-12T19:34:15.472Z] variation: NoOptions [2025-06-12T19:34:15.472Z] JVM_OPTIONS: [2025-06-12T19:34:15.472Z] { \ [2025-06-12T19:34:15.472Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T19:34:15.472Z] echo "Nothing to be done for setup."; \ [2025-06-12T19:34:15.472Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-als_0"; \ [2025-06-12T19:34:15.472Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-als_0"; \ [2025-06-12T19:34:15.472Z] echo ""; echo "TESTING:"; \ [2025-06-12T19:34:15.472Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-als_0"/als.json" als; \ [2025-06-12T19:34:15.472Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T19:34:15.472Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T19:34:15.472Z] echo "Nothing to be done for teardown."; \ [2025-06-12T19:34:15.472Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/TestTargetResult"; [2025-06-12T19:34:15.472Z] [2025-06-12T19:34:15.472Z] TEST SETUP: [2025-06-12T19:34:15.472Z] Nothing to be done for setup. [2025-06-12T19:34:15.472Z] [2025-06-12T19:34:15.472Z] TESTING: [2025-06-12T19:34:21.469Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-12T19:34:27.402Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-12T19:34:27.402Z] GC before operation: completed in 60.457 ms, heap usage 65.462 MB -> 35.005 MB. [2025-06-12T19:35:05.610Z] ====== als (apache-spark) [default], iteration 0 completed (37637.252 ms) ====== [2025-06-12T19:35:05.610Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-12T19:35:05.610Z] GC before operation: completed in 124.148 ms, heap usage 184.950 MB -> 62.094 MB. [2025-06-12T19:35:22.489Z] ====== als (apache-spark) [default], iteration 1 completed (15484.859 ms) ====== [2025-06-12T19:35:22.489Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-12T19:35:22.489Z] GC before operation: completed in 139.019 ms, heap usage 194.536 MB -> 63.137 MB. [2025-06-12T19:35:36.935Z] ====== als (apache-spark) [default], iteration 2 completed (15292.903 ms) ====== [2025-06-12T19:35:36.935Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-12T19:35:37.761Z] GC before operation: completed in 110.952 ms, heap usage 363.058 MB -> 63.493 MB. [2025-06-12T19:35:52.176Z] ====== als (apache-spark) [default], iteration 3 completed (14598.085 ms) ====== [2025-06-12T19:35:52.176Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-12T19:35:53.007Z] GC before operation: completed in 134.989 ms, heap usage 180.052 MB -> 63.832 MB. [2025-06-12T19:36:07.464Z] ====== als (apache-spark) [default], iteration 4 completed (14923.795 ms) ====== [2025-06-12T19:36:07.464Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-12T19:36:08.288Z] GC before operation: completed in 122.126 ms, heap usage 326.533 MB -> 64.588 MB. [2025-06-12T19:36:22.892Z] ====== als (apache-spark) [default], iteration 5 completed (13674.348 ms) ====== [2025-06-12T19:36:22.892Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-12T19:36:22.892Z] GC before operation: completed in 138.887 ms, heap usage 359.852 MB -> 65.291 MB. [2025-06-12T19:36:45.027Z] ====== als (apache-spark) [default], iteration 6 completed (17631.850 ms) ====== [2025-06-12T19:36:45.027Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-12T19:36:45.027Z] GC before operation: completed in 147.566 ms, heap usage 250.604 MB -> 65.625 MB. [2025-06-12T19:37:03.977Z] ====== als (apache-spark) [default], iteration 7 completed (21724.226 ms) ====== [2025-06-12T19:37:03.978Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-12T19:37:03.978Z] GC before operation: completed in 288.760 ms, heap usage 330.758 MB -> 66.264 MB. [2025-06-12T19:37:26.326Z] ====== als (apache-spark) [default], iteration 8 completed (21673.163 ms) ====== [2025-06-12T19:37:26.326Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-12T19:37:26.326Z] GC before operation: completed in 197.874 ms, heap usage 148.081 MB -> 66.600 MB. [2025-06-12T19:37:45.574Z] ====== als (apache-spark) [default], iteration 9 completed (18493.089 ms) ====== [2025-06-12T19:37:45.574Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-12T19:37:45.574Z] GC before operation: completed in 221.063 ms, heap usage 209.207 MB -> 66.940 MB. [2025-06-12T19:38:05.109Z] ====== als (apache-spark) [default], iteration 10 completed (19257.504 ms) ====== [2025-06-12T19:38:05.109Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-12T19:38:05.109Z] GC before operation: completed in 129.066 ms, heap usage 194.810 MB -> 67.457 MB. [2025-06-12T19:38:31.615Z] ====== als (apache-spark) [default], iteration 11 completed (25997.969 ms) ====== [2025-06-12T19:38:31.615Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-12T19:38:31.615Z] GC before operation: completed in 212.180 ms, heap usage 151.465 MB -> 67.738 MB. [2025-06-12T19:38:45.649Z] ====== als (apache-spark) [default], iteration 12 completed (14290.751 ms) ====== [2025-06-12T19:38:45.649Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-12T19:38:45.649Z] GC before operation: completed in 255.809 ms, heap usage 459.998 MB -> 68.238 MB. [2025-06-12T19:39:11.894Z] ====== als (apache-spark) [default], iteration 13 completed (23406.369 ms) ====== [2025-06-12T19:39:11.894Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-12T19:39:11.894Z] GC before operation: completed in 387.049 ms, heap usage 362.627 MB -> 68.237 MB. [2025-06-12T19:39:34.540Z] ====== als (apache-spark) [default], iteration 14 completed (23442.259 ms) ====== [2025-06-12T19:39:34.540Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-12T19:39:34.540Z] GC before operation: completed in 215.893 ms, heap usage 265.567 MB -> 68.347 MB. [2025-06-12T19:39:56.937Z] ====== als (apache-spark) [default], iteration 15 completed (20892.996 ms) ====== [2025-06-12T19:39:56.937Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-12T19:39:56.937Z] GC before operation: completed in 241.806 ms, heap usage 274.825 MB -> 68.639 MB. [2025-06-12T19:40:16.144Z] ====== als (apache-spark) [default], iteration 16 completed (18721.923 ms) ====== [2025-06-12T19:40:16.144Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-12T19:40:16.144Z] GC before operation: completed in 198.848 ms, heap usage 157.446 MB -> 68.995 MB. [2025-06-12T19:40:35.337Z] ====== als (apache-spark) [default], iteration 17 completed (18088.202 ms) ====== [2025-06-12T19:40:35.337Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-12T19:40:35.337Z] GC before operation: completed in 265.365 ms, heap usage 266.341 MB -> 69.139 MB. [2025-06-12T19:40:54.564Z] ====== als (apache-spark) [default], iteration 18 completed (19144.905 ms) ====== [2025-06-12T19:40:54.564Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-12T19:40:54.564Z] GC before operation: completed in 255.289 ms, heap usage 248.371 MB -> 69.433 MB. [2025-06-12T19:41:14.308Z] ====== als (apache-spark) [default], iteration 19 completed (19172.325 ms) ====== [2025-06-12T19:41:14.308Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-12T19:41:14.308Z] GC before operation: completed in 255.062 ms, heap usage 351.928 MB -> 69.877 MB. [2025-06-12T19:41:30.722Z] ====== als (apache-spark) [default], iteration 20 completed (17368.490 ms) ====== [2025-06-12T19:41:30.722Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-12T19:41:30.722Z] GC before operation: completed in 260.497 ms, heap usage 495.194 MB -> 70.266 MB. [2025-06-12T19:41:47.117Z] ====== als (apache-spark) [default], iteration 21 completed (14554.246 ms) ====== [2025-06-12T19:41:47.117Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-12T19:41:47.117Z] GC before operation: completed in 235.859 ms, heap usage 370.174 MB -> 70.430 MB. [2025-06-12T19:42:01.160Z] ====== als (apache-spark) [default], iteration 22 completed (15028.602 ms) ====== [2025-06-12T19:42:01.160Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-12T19:42:01.160Z] GC before operation: completed in 177.575 ms, heap usage 172.088 MB -> 70.425 MB. [2025-06-12T19:42:17.549Z] ====== als (apache-spark) [default], iteration 23 completed (15203.813 ms) ====== [2025-06-12T19:42:17.549Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-12T19:42:17.549Z] GC before operation: completed in 209.067 ms, heap usage 285.335 MB -> 70.918 MB. [2025-06-12T19:42:37.295Z] ====== als (apache-spark) [default], iteration 24 completed (19990.358 ms) ====== [2025-06-12T19:42:37.295Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-12T19:42:37.295Z] GC before operation: completed in 186.260 ms, heap usage 317.373 MB -> 70.973 MB. [2025-06-12T19:42:53.523Z] ====== als (apache-spark) [default], iteration 25 completed (14944.323 ms) ====== [2025-06-12T19:42:53.523Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-12T19:42:53.523Z] GC before operation: completed in 218.742 ms, heap usage 468.955 MB -> 71.485 MB. [2025-06-12T19:43:09.689Z] ====== als (apache-spark) [default], iteration 26 completed (14621.663 ms) ====== [2025-06-12T19:43:09.689Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-12T19:43:09.689Z] GC before operation: completed in 217.842 ms, heap usage 261.105 MB -> 71.406 MB. [2025-06-12T19:43:25.856Z] ====== als (apache-spark) [default], iteration 27 completed (16379.146 ms) ====== [2025-06-12T19:43:25.856Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-12T19:43:25.856Z] GC before operation: completed in 236.824 ms, heap usage 551.263 MB -> 72.127 MB. [2025-06-12T19:43:42.087Z] ====== als (apache-spark) [default], iteration 28 completed (16988.065 ms) ====== [2025-06-12T19:43:42.087Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-12T19:43:42.087Z] GC before operation: completed in 256.478 ms, heap usage 246.021 MB -> 71.975 MB. [2025-06-12T19:43:58.846Z] ====== als (apache-spark) [default], iteration 29 completed (15782.598 ms) ====== [2025-06-12T19:43:59.613Z] ----------------------------------- [2025-06-12T19:43:59.613Z] renaissance-als_0_PASSED [2025-06-12T19:43:59.613Z] ----------------------------------- [2025-06-12T19:43:59.613Z] [2025-06-12T19:43:59.613Z] TEST TEARDOWN: [2025-06-12T19:43:59.613Z] Nothing to be done for teardown. [2025-06-12T19:43:59.613Z] renaissance-als_0 Finish Time: Thu Jun 12 19:43:58 2025 Epoch Time (ms): 1749757438796