[2025-11-06T17:38:34.622Z] Running test renaissance-als_0 ... [2025-11-06T17:38:34.622Z] =============================================== [2025-11-06T17:38:34.622Z] renaissance-als_0 Start Time: Thu Nov 6 17:38:34 2025 Epoch Time (ms): 1762450714153 [2025-11-06T17:38:34.622Z] variation: NoOptions [2025-11-06T17:38:34.622Z] JVM_OPTIONS: [2025-11-06T17:38:34.622Z] { \ [2025-11-06T17:38:34.622Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T17:38:34.622Z] echo "Nothing to be done for setup."; \ [2025-11-06T17:38:34.622Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624507137240/renaissance-als_0"; \ [2025-11-06T17:38:34.622Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624507137240/renaissance-als_0"; \ [2025-11-06T17:38:34.622Z] echo ""; echo "TESTING:"; \ [2025-11-06T17:38:34.622Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624507137240/renaissance-als_0"/als.json" als; \ [2025-11-06T17:38:34.622Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624507137240/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T17:38:34.622Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T17:38:34.622Z] echo "Nothing to be done for teardown."; \ [2025-11-06T17:38:34.622Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17624507137240/TestTargetResult"; [2025-11-06T17:38:34.622Z] [2025-11-06T17:38:34.622Z] TEST SETUP: [2025-11-06T17:38:34.622Z] Nothing to be done for setup. [2025-11-06T17:38:34.622Z] [2025-11-06T17:38:34.622Z] TESTING: [2025-11-06T17:38:40.077Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-06T17:38:43.407Z] WARNING: An illegal reflective access operation has occurred [2025-11-06T17:38:43.407Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/output_17624507137240/renaissance-als_0/harness-173834-1791173330501469316/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-06T17:38:43.407Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-06T17:38:43.407Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-06T17:38:43.407Z] WARNING: All illegal access operations will be denied in a future release [2025-11-06T17:38:44.950Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-06T17:38:44.950Z] GC before operation: completed in 67.926 ms, heap usage 313.002 MB -> 34.463 MB. [2025-11-06T17:39:10.915Z] ====== als (apache-spark) [default], iteration 0 completed (22727.510 ms) ====== [2025-11-06T17:39:10.915Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-06T17:39:10.915Z] GC before operation: completed in 109.353 ms, heap usage 176.594 MB -> 60.337 MB. [2025-11-06T17:39:17.638Z] ====== als (apache-spark) [default], iteration 1 completed (9704.765 ms) ====== [2025-11-06T17:39:17.638Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-06T17:39:18.389Z] GC before operation: completed in 107.101 ms, heap usage 188.339 MB -> 61.144 MB. [2025-11-06T17:39:28.081Z] ====== als (apache-spark) [default], iteration 2 completed (8854.415 ms) ====== [2025-11-06T17:39:28.081Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-06T17:39:28.081Z] GC before operation: completed in 112.513 ms, heap usage 605.202 MB -> 62.285 MB. [2025-11-06T17:39:36.202Z] ====== als (apache-spark) [default], iteration 3 completed (8763.568 ms) ====== [2025-11-06T17:39:36.202Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-06T17:39:36.202Z] GC before operation: completed in 109.870 ms, heap usage 324.708 MB -> 62.352 MB. [2025-11-06T17:39:45.911Z] ====== als (apache-spark) [default], iteration 4 completed (8507.355 ms) ====== [2025-11-06T17:39:45.911Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-06T17:39:45.912Z] GC before operation: completed in 115.065 ms, heap usage 172.003 MB -> 63.018 MB. [2025-11-06T17:39:54.124Z] ====== als (apache-spark) [default], iteration 5 completed (8740.265 ms) ====== [2025-11-06T17:39:54.124Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-06T17:39:54.877Z] GC before operation: completed in 124.296 ms, heap usage 324.834 MB -> 63.517 MB. [2025-11-06T17:40:04.574Z] ====== als (apache-spark) [default], iteration 6 completed (9092.308 ms) ====== [2025-11-06T17:40:04.574Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-06T17:40:04.574Z] GC before operation: completed in 128.232 ms, heap usage 245.523 MB -> 63.929 MB. [2025-11-06T17:40:12.733Z] ====== als (apache-spark) [default], iteration 7 completed (8673.338 ms) ====== [2025-11-06T17:40:12.733Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-06T17:40:12.733Z] GC before operation: completed in 135.134 ms, heap usage 359.669 MB -> 64.563 MB. [2025-11-06T17:40:22.438Z] ====== als (apache-spark) [default], iteration 8 completed (8529.989 ms) ====== [2025-11-06T17:40:22.438Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-06T17:40:22.438Z] GC before operation: completed in 131.281 ms, heap usage 236.581 MB -> 64.977 MB. [2025-11-06T17:40:31.150Z] ====== als (apache-spark) [default], iteration 9 completed (9551.325 ms) ====== [2025-11-06T17:40:31.150Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-06T17:40:31.150Z] GC before operation: completed in 120.231 ms, heap usage 149.283 MB -> 65.173 MB. [2025-11-06T17:40:41.371Z] ====== als (apache-spark) [default], iteration 10 completed (8699.669 ms) ====== [2025-11-06T17:40:41.371Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-06T17:40:41.371Z] GC before operation: completed in 126.866 ms, heap usage 250.782 MB -> 65.511 MB. [2025-11-06T17:40:49.498Z] ====== als (apache-spark) [default], iteration 11 completed (8004.896 ms) ====== [2025-11-06T17:40:49.498Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-06T17:40:49.498Z] GC before operation: completed in 125.755 ms, heap usage 317.032 MB -> 65.874 MB. [2025-11-06T17:40:57.640Z] ====== als (apache-spark) [default], iteration 12 completed (8395.854 ms) ====== [2025-11-06T17:40:57.640Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-06T17:40:57.640Z] GC before operation: completed in 125.068 ms, heap usage 397.696 MB -> 66.129 MB. [2025-11-06T17:41:07.325Z] ====== als (apache-spark) [default], iteration 13 completed (8411.347 ms) ====== [2025-11-06T17:41:07.325Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-06T17:41:07.325Z] GC before operation: completed in 122.895 ms, heap usage 205.949 MB -> 66.233 MB. [2025-11-06T17:41:15.450Z] ====== als (apache-spark) [default], iteration 14 completed (8153.549 ms) ====== [2025-11-06T17:41:15.450Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-06T17:41:15.450Z] GC before operation: completed in 124.853 ms, heap usage 226.371 MB -> 66.527 MB. [2025-11-06T17:41:23.565Z] ====== als (apache-spark) [default], iteration 15 completed (7958.370 ms) ====== [2025-11-06T17:41:23.565Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-06T17:41:23.565Z] GC before operation: completed in 122.647 ms, heap usage 286.142 MB -> 66.843 MB. [2025-11-06T17:41:31.687Z] ====== als (apache-spark) [default], iteration 16 completed (7970.450 ms) ====== [2025-11-06T17:41:31.687Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-06T17:41:31.687Z] GC before operation: completed in 133.895 ms, heap usage 246.364 MB -> 66.991 MB. [2025-11-06T17:41:39.818Z] ====== als (apache-spark) [default], iteration 17 completed (8101.621 ms) ====== [2025-11-06T17:41:39.818Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-06T17:41:39.818Z] GC before operation: completed in 129.978 ms, heap usage 443.372 MB -> 67.589 MB. [2025-11-06T17:41:48.560Z] ====== als (apache-spark) [default], iteration 18 completed (8187.372 ms) ====== [2025-11-06T17:41:48.560Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-06T17:41:48.560Z] GC before operation: completed in 133.565 ms, heap usage 243.674 MB -> 67.524 MB. [2025-11-06T17:41:56.703Z] ====== als (apache-spark) [default], iteration 19 completed (8219.574 ms) ====== [2025-11-06T17:41:56.703Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-06T17:41:56.703Z] GC before operation: completed in 131.682 ms, heap usage 264.402 MB -> 67.772 MB. [2025-11-06T17:42:06.390Z] ====== als (apache-spark) [default], iteration 20 completed (8700.501 ms) ====== [2025-11-06T17:42:06.390Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-06T17:42:06.390Z] GC before operation: completed in 133.047 ms, heap usage 144.289 MB -> 67.930 MB. [2025-11-06T17:42:14.519Z] ====== als (apache-spark) [default], iteration 21 completed (8159.980 ms) ====== [2025-11-06T17:42:14.519Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-06T17:42:14.519Z] GC before operation: completed in 128.483 ms, heap usage 203.576 MB -> 68.173 MB. [2025-11-06T17:42:22.663Z] ====== als (apache-spark) [default], iteration 22 completed (8133.328 ms) ====== [2025-11-06T17:42:22.663Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-06T17:42:22.663Z] GC before operation: completed in 124.197 ms, heap usage 269.800 MB -> 68.678 MB. [2025-11-06T17:42:32.363Z] ====== als (apache-spark) [default], iteration 23 completed (8439.232 ms) ====== [2025-11-06T17:42:32.363Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-06T17:42:32.363Z] GC before operation: completed in 128.901 ms, heap usage 346.707 MB -> 69.041 MB. [2025-11-06T17:42:40.490Z] ====== als (apache-spark) [default], iteration 24 completed (8514.996 ms) ====== [2025-11-06T17:42:40.490Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-06T17:42:40.490Z] GC before operation: completed in 131.532 ms, heap usage 228.244 MB -> 69.062 MB. [2025-11-06T17:42:48.744Z] ====== als (apache-spark) [default], iteration 25 completed (8343.703 ms) ====== [2025-11-06T17:42:48.744Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-06T17:42:48.744Z] GC before operation: completed in 130.642 ms, heap usage 261.859 MB -> 69.316 MB. [2025-11-06T17:42:58.445Z] ====== als (apache-spark) [default], iteration 26 completed (8132.341 ms) ====== [2025-11-06T17:42:58.445Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-06T17:42:58.445Z] GC before operation: completed in 138.857 ms, heap usage 260.417 MB -> 69.555 MB. [2025-11-06T17:43:05.667Z] ====== als (apache-spark) [default], iteration 27 completed (8255.566 ms) ====== [2025-11-06T17:43:05.667Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-06T17:43:05.667Z] GC before operation: completed in 141.685 ms, heap usage 290.100 MB -> 69.800 MB. [2025-11-06T17:43:15.385Z] ====== als (apache-spark) [default], iteration 28 completed (8239.043 ms) ====== [2025-11-06T17:43:15.385Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-06T17:43:15.385Z] GC before operation: completed in 132.466 ms, heap usage 196.766 MB -> 70.060 MB. [2025-11-06T17:43:23.511Z] ====== als (apache-spark) [default], iteration 29 completed (7801.230 ms) ====== [2025-11-06T17:43:23.511Z] ----------------------------------- [2025-11-06T17:43:23.511Z] renaissance-als_0_PASSED [2025-11-06T17:43:23.511Z] ----------------------------------- [2025-11-06T17:43:23.511Z] [2025-11-06T17:43:23.511Z] TEST TEARDOWN: [2025-11-06T17:43:23.511Z] Nothing to be done for teardown. [2025-11-06T17:43:23.511Z] renaissance-als_0 Finish Time: Thu Nov 6 17:43:22 2025 Epoch Time (ms): 1762451002475