[2025-06-13T09:53:05.906Z] Running test renaissance-als_0 ... [2025-06-13T09:53:05.906Z] =============================================== [2025-06-13T09:53:05.906Z] renaissance-als_0 Start Time: Fri Jun 13 09:53:05 2025 Epoch Time (ms): 1749808385459 [2025-06-13T09:53:05.906Z] variation: NoOptions [2025-06-13T09:53:05.906Z] JVM_OPTIONS: [2025-06-13T09:53:05.906Z] { \ [2025-06-13T09:53:05.906Z] echo ""; echo "TEST SETUP:"; \ [2025-06-13T09:53:05.906Z] echo "Nothing to be done for setup."; \ [2025-06-13T09:53:05.906Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17498083843889/renaissance-als_0"; \ [2025-06-13T09:53:05.906Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17498083843889/renaissance-als_0"; \ [2025-06-13T09:53:05.906Z] echo ""; echo "TESTING:"; \ [2025-06-13T09:53:05.906Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17498083843889/renaissance-als_0"/als.json" als; \ [2025-06-13T09:53:05.906Z] 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_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17498083843889/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-13T09:53:05.906Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-13T09:53:05.906Z] echo "Nothing to be done for teardown."; \ [2025-06-13T09:53:05.906Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17498083843889/TestTargetResult"; [2025-06-13T09:53:05.906Z] [2025-06-13T09:53:05.906Z] TEST SETUP: [2025-06-13T09:53:05.906Z] Nothing to be done for setup. [2025-06-13T09:53:05.906Z] [2025-06-13T09:53:05.907Z] TESTING: [2025-06-13T09:53:27.871Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-13T09:53:44.447Z] WARNING: An illegal reflective access operation has occurred [2025-06-13T09:53:44.447Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17498083843889/renaissance-als_0/harness-095307-16980108519683649253/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-06-13T09:53:44.447Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-06-13T09:53:44.447Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-06-13T09:53:44.447Z] WARNING: All illegal access operations will be denied in a future release [2025-06-13T09:53:48.799Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-13T09:53:48.799Z] GC before operation: completed in 315.445 ms, heap usage 252.782 MB -> 34.503 MB. [2025-06-13T09:55:53.145Z] ====== als (apache-spark) [default], iteration 0 completed (117702.375 ms) ====== [2025-06-13T09:55:53.145Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-13T09:55:53.145Z] GC before operation: completed in 813.370 ms, heap usage 178.673 MB -> 60.284 MB. [2025-06-13T09:57:00.231Z] ====== als (apache-spark) [default], iteration 1 completed (59693.005 ms) ====== [2025-06-13T09:57:00.231Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-13T09:57:00.231Z] GC before operation: completed in 677.743 ms, heap usage 362.212 MB -> 61.263 MB. [2025-06-13T09:57:49.233Z] ====== als (apache-spark) [default], iteration 2 completed (51874.379 ms) ====== [2025-06-13T09:57:49.233Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-13T09:57:49.233Z] GC before operation: completed in 665.980 ms, heap usage 92.027 MB -> 61.708 MB. [2025-06-13T09:58:38.064Z] ====== als (apache-spark) [default], iteration 3 completed (43916.497 ms) ====== [2025-06-13T09:58:38.064Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-13T09:58:38.064Z] GC before operation: completed in 574.775 ms, heap usage 210.036 MB -> 62.283 MB. [2025-06-13T09:59:19.779Z] ====== als (apache-spark) [default], iteration 4 completed (45128.071 ms) ====== [2025-06-13T09:59:19.779Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-13T09:59:19.779Z] GC before operation: completed in 592.169 ms, heap usage 321.066 MB -> 62.793 MB. [2025-06-13T10:00:08.539Z] ====== als (apache-spark) [default], iteration 5 completed (46230.194 ms) ====== [2025-06-13T10:00:08.539Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-13T10:00:08.539Z] GC before operation: completed in 584.316 ms, heap usage 172.188 MB -> 63.213 MB. [2025-06-13T10:00:50.707Z] ====== als (apache-spark) [default], iteration 6 completed (42921.553 ms) ====== [2025-06-13T10:00:50.707Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-13T10:00:50.707Z] GC before operation: completed in 582.969 ms, heap usage 359.270 MB -> 63.964 MB. [2025-06-13T10:01:39.640Z] ====== als (apache-spark) [default], iteration 7 completed (40733.885 ms) ====== [2025-06-13T10:01:39.640Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-13T10:01:39.640Z] GC before operation: completed in 595.962 ms, heap usage 243.113 MB -> 64.384 MB. [2025-06-13T10:02:21.368Z] ====== als (apache-spark) [default], iteration 8 completed (43125.542 ms) ====== [2025-06-13T10:02:21.369Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-13T10:02:21.369Z] GC before operation: completed in 563.986 ms, heap usage 114.588 MB -> 64.805 MB. [2025-06-13T10:03:03.567Z] ====== als (apache-spark) [default], iteration 9 completed (38413.839 ms) ====== [2025-06-13T10:03:03.567Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-13T10:03:03.567Z] GC before operation: completed in 478.665 ms, heap usage 187.925 MB -> 65.541 MB. [2025-06-13T10:03:39.682Z] ====== als (apache-spark) [default], iteration 10 completed (36039.081 ms) ====== [2025-06-13T10:03:39.682Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-13T10:03:39.682Z] GC before operation: completed in 506.393 ms, heap usage 377.185 MB -> 65.549 MB. [2025-06-13T10:04:15.546Z] ====== als (apache-spark) [default], iteration 11 completed (38153.151 ms) ====== [2025-06-13T10:04:15.546Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-13T10:04:15.546Z] GC before operation: completed in 470.052 ms, heap usage 225.893 MB -> 66.049 MB. [2025-06-13T10:04:51.492Z] ====== als (apache-spark) [default], iteration 12 completed (34383.194 ms) ====== [2025-06-13T10:04:51.492Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-13T10:04:51.492Z] GC before operation: completed in 543.321 ms, heap usage 522.871 MB -> 66.564 MB. [2025-06-13T10:05:34.098Z] ====== als (apache-spark) [default], iteration 13 completed (39091.499 ms) ====== [2025-06-13T10:05:34.098Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-13T10:05:34.098Z] GC before operation: completed in 510.448 ms, heap usage 99.219 MB -> 65.630 MB. [2025-06-13T10:06:09.715Z] ====== als (apache-spark) [default], iteration 14 completed (34338.725 ms) ====== [2025-06-13T10:06:09.715Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-13T10:06:09.715Z] GC before operation: completed in 486.052 ms, heap usage 162.898 MB -> 66.500 MB. [2025-06-13T10:06:45.360Z] ====== als (apache-spark) [default], iteration 15 completed (36781.131 ms) ====== [2025-06-13T10:06:45.360Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-13T10:06:45.360Z] GC before operation: completed in 484.462 ms, heap usage 209.825 MB -> 66.763 MB. [2025-06-13T10:07:34.226Z] ====== als (apache-spark) [default], iteration 16 completed (46649.083 ms) ====== [2025-06-13T10:07:34.226Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-13T10:07:34.226Z] GC before operation: completed in 465.678 ms, heap usage 225.673 MB -> 66.997 MB. [2025-06-13T10:08:15.520Z] ====== als (apache-spark) [default], iteration 17 completed (36019.782 ms) ====== [2025-06-13T10:08:15.520Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-13T10:08:15.520Z] GC before operation: completed in 442.641 ms, heap usage 263.934 MB -> 67.287 MB. [2025-06-13T10:08:57.089Z] ====== als (apache-spark) [default], iteration 18 completed (40252.956 ms) ====== [2025-06-13T10:08:57.089Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-13T10:08:57.089Z] GC before operation: completed in 426.277 ms, heap usage 307.896 MB -> 67.608 MB. [2025-06-13T10:09:32.313Z] ====== als (apache-spark) [default], iteration 19 completed (38901.953 ms) ====== [2025-06-13T10:09:32.313Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-13T10:09:33.088Z] GC before operation: completed in 566.406 ms, heap usage 210.932 MB -> 67.741 MB. [2025-06-13T10:10:14.295Z] ====== als (apache-spark) [default], iteration 20 completed (38858.339 ms) ====== [2025-06-13T10:10:14.295Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-13T10:10:14.295Z] GC before operation: completed in 516.912 ms, heap usage 218.281 MB -> 67.872 MB. [2025-06-13T10:10:55.832Z] ====== als (apache-spark) [default], iteration 21 completed (38990.409 ms) ====== [2025-06-13T10:10:55.832Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-13T10:10:55.832Z] GC before operation: completed in 544.905 ms, heap usage 246.965 MB -> 68.233 MB. [2025-06-13T10:11:36.991Z] ====== als (apache-spark) [default], iteration 22 completed (40738.143 ms) ====== [2025-06-13T10:11:36.991Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-13T10:11:36.991Z] GC before operation: completed in 577.173 ms, heap usage 111.410 MB -> 68.395 MB. [2025-06-13T10:12:18.008Z] ====== als (apache-spark) [default], iteration 23 completed (41169.598 ms) ====== [2025-06-13T10:12:18.008Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-13T10:12:19.155Z] GC before operation: completed in 612.147 ms, heap usage 243.934 MB -> 68.765 MB. [2025-06-13T10:13:00.330Z] ====== als (apache-spark) [default], iteration 24 completed (37033.868 ms) ====== [2025-06-13T10:13:00.330Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-13T10:13:00.330Z] GC before operation: completed in 517.287 ms, heap usage 323.451 MB -> 69.335 MB. [2025-06-13T10:13:35.736Z] ====== als (apache-spark) [default], iteration 25 completed (36783.454 ms) ====== [2025-06-13T10:13:35.736Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-13T10:13:35.736Z] GC before operation: completed in 560.287 ms, heap usage 337.995 MB -> 69.375 MB. [2025-06-13T10:14:24.421Z] ====== als (apache-spark) [default], iteration 26 completed (43199.817 ms) ====== [2025-06-13T10:14:24.421Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-13T10:14:24.421Z] GC before operation: completed in 565.488 ms, heap usage 284.045 MB -> 69.811 MB. [2025-06-13T10:15:06.012Z] ====== als (apache-spark) [default], iteration 27 completed (40555.593 ms) ====== [2025-06-13T10:15:06.012Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-13T10:15:06.013Z] GC before operation: completed in 747.637 ms, heap usage 246.537 MB -> 68.706 MB. [2025-06-13T10:15:47.955Z] ====== als (apache-spark) [default], iteration 28 completed (40683.163 ms) ====== [2025-06-13T10:15:47.955Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-13T10:15:47.955Z] GC before operation: completed in 604.950 ms, heap usage 269.760 MB -> 68.257 MB. [2025-06-13T10:16:29.466Z] ====== als (apache-spark) [default], iteration 29 completed (39987.299 ms) ====== [2025-06-13T10:16:29.466Z] ----------------------------------- [2025-06-13T10:16:29.466Z] renaissance-als_0_PASSED [2025-06-13T10:16:29.466Z] ----------------------------------- [2025-06-13T10:16:29.466Z] [2025-06-13T10:16:29.466Z] TEST TEARDOWN: [2025-06-13T10:16:29.466Z] Nothing to be done for teardown. [2025-06-13T10:16:29.466Z] renaissance-als_0 Finish Time: Fri Jun 13 10:16:26 2025 Epoch Time (ms): 1749809786529