[2026-04-15T16:54:55.068Z] Running test renaissance-als_0 ... [2026-04-15T16:54:55.068Z] =============================================== [2026-04-15T16:54:55.068Z] renaissance-als_0 Start Time: Wed Apr 15 12:54:54 2026 Epoch Time (ms): 1776272094848 [2026-04-15T16:54:55.510Z] variation: NoOptions [2026-04-15T16:54:55.510Z] JVM_OPTIONS: [2026-04-15T16:54:55.510Z] { \ [2026-04-15T16:54:55.510Z] echo ""; echo "TEST SETUP:"; \ [2026-04-15T16:54:55.510Z] echo "Nothing to be done for setup."; \ [2026-04-15T16:54:55.510Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17762720921236/renaissance-als_0"; \ [2026-04-15T16:54:55.510Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17762720921236/renaissance-als_0"; \ [2026-04-15T16:54:55.510Z] echo ""; echo "TESTING:"; \ [2026-04-15T16:54:55.510Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17762720921236/renaissance-als_0"/als.json" als; \ [2026-04-15T16:54:55.510Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17762720921236/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-15T16:54:55.510Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-15T16:54:55.510Z] echo "Nothing to be done for teardown."; \ [2026-04-15T16:54:55.510Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17762720921236/TestTargetResult"; [2026-04-15T16:54:55.510Z] [2026-04-15T16:54:55.510Z] TEST SETUP: [2026-04-15T16:54:55.510Z] Nothing to be done for setup. [2026-04-15T16:54:55.510Z] [2026-04-15T16:54:55.510Z] TESTING: [2026-04-15T16:55:28.584Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-15T16:55:34.333Z] WARNING: An illegal reflective access operation has occurred [2026-04-15T16:55:34.333Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/output_17762720921236/renaissance-als_0/harness-125457-8544063777905090136/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-15T16:55:34.333Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-15T16:55:34.333Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-15T16:55:34.333Z] WARNING: All illegal access operations will be denied in a future release [2026-04-15T16:55:38.474Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-15T16:55:38.474Z] GC before operation: completed in 309.737 ms, heap usage 318.500 MB -> 34.736 MB. [2026-04-15T16:57:22.216Z] ====== als (apache-spark) [default], iteration 0 completed (88703.607 ms) ====== [2026-04-15T16:57:22.216Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-15T16:57:22.216Z] GC before operation: completed in 1116.024 ms, heap usage 608.844 MB -> 60.984 MB. [2026-04-15T16:57:48.211Z] ====== als (apache-spark) [default], iteration 1 completed (37319.937 ms) ====== [2026-04-15T16:57:48.211Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-15T16:57:48.816Z] GC before operation: completed in 855.768 ms, heap usage 255.004 MB -> 61.308 MB. [2026-04-15T16:58:26.201Z] ====== als (apache-spark) [default], iteration 2 completed (35190.540 ms) ====== [2026-04-15T16:58:26.201Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-15T16:58:26.201Z] GC before operation: completed in 518.321 ms, heap usage 468.819 MB -> 62.084 MB. [2026-04-15T16:58:57.286Z] ====== als (apache-spark) [default], iteration 3 completed (29319.597 ms) ====== [2026-04-15T16:58:57.286Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-15T16:58:57.286Z] GC before operation: completed in 1002.980 ms, heap usage 214.408 MB -> 62.362 MB. [2026-04-15T16:59:23.117Z] ====== als (apache-spark) [default], iteration 4 completed (25149.231 ms) ====== [2026-04-15T16:59:23.117Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-15T16:59:23.117Z] GC before operation: completed in 371.096 ms, heap usage 243.039 MB -> 62.919 MB. [2026-04-15T16:59:54.749Z] ====== als (apache-spark) [default], iteration 5 completed (28653.938 ms) ====== [2026-04-15T16:59:54.749Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-15T16:59:54.749Z] GC before operation: completed in 330.693 ms, heap usage 344.949 MB -> 63.645 MB. [2026-04-15T17:00:26.814Z] ====== als (apache-spark) [default], iteration 6 completed (30730.805 ms) ====== [2026-04-15T17:00:26.814Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-15T17:00:26.814Z] GC before operation: completed in 290.162 ms, heap usage 338.569 MB -> 64.163 MB. [2026-04-15T17:00:51.834Z] ====== als (apache-spark) [default], iteration 7 completed (26560.604 ms) ====== [2026-04-15T17:00:51.834Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-15T17:00:51.834Z] GC before operation: completed in 292.623 ms, heap usage 192.432 MB -> 64.523 MB. [2026-04-15T17:01:22.713Z] ====== als (apache-spark) [default], iteration 8 completed (27080.511 ms) ====== [2026-04-15T17:01:22.714Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-15T17:01:22.714Z] GC before operation: completed in 266.510 ms, heap usage 146.109 MB -> 65.021 MB. [2026-04-15T17:01:49.037Z] ====== als (apache-spark) [default], iteration 9 completed (26969.189 ms) ====== [2026-04-15T17:01:49.037Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-15T17:01:49.037Z] GC before operation: completed in 384.761 ms, heap usage 888.603 MB -> 66.265 MB. [2026-04-15T17:02:25.651Z] ====== als (apache-spark) [default], iteration 10 completed (33608.836 ms) ====== [2026-04-15T17:02:25.651Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-15T17:02:25.651Z] GC before operation: completed in 344.019 ms, heap usage 641.837 MB -> 66.088 MB. [2026-04-15T17:03:02.611Z] ====== als (apache-spark) [default], iteration 11 completed (34670.623 ms) ====== [2026-04-15T17:03:02.611Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-15T17:03:02.611Z] GC before operation: completed in 932.671 ms, heap usage 233.752 MB -> 66.252 MB. [2026-04-15T17:03:33.749Z] ====== als (apache-spark) [default], iteration 12 completed (31212.593 ms) ====== [2026-04-15T17:03:33.749Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-15T17:03:33.749Z] GC before operation: completed in 367.650 ms, heap usage 368.971 MB -> 66.325 MB. [2026-04-15T17:04:10.199Z] ====== als (apache-spark) [default], iteration 13 completed (34640.694 ms) ====== [2026-04-15T17:04:10.199Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-15T17:04:10.200Z] GC before operation: completed in 304.658 ms, heap usage 531.607 MB -> 66.761 MB. [2026-04-15T17:04:41.065Z] ====== als (apache-spark) [default], iteration 14 completed (30669.957 ms) ====== [2026-04-15T17:04:41.065Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-15T17:04:41.065Z] GC before operation: completed in 537.303 ms, heap usage 254.220 MB -> 66.670 MB. [2026-04-15T17:05:17.843Z] ====== als (apache-spark) [default], iteration 15 completed (34124.350 ms) ====== [2026-04-15T17:05:17.843Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-15T17:05:17.843Z] GC before operation: completed in 882.884 ms, heap usage 835.768 MB -> 67.708 MB. [2026-04-15T17:05:49.113Z] ====== als (apache-spark) [default], iteration 16 completed (29625.206 ms) ====== [2026-04-15T17:05:49.113Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-15T17:05:49.113Z] GC before operation: completed in 846.475 ms, heap usage 511.588 MB -> 67.562 MB. [2026-04-15T17:06:20.342Z] ====== als (apache-spark) [default], iteration 17 completed (30024.354 ms) ====== [2026-04-15T17:06:20.342Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-15T17:06:20.342Z] GC before operation: completed in 601.771 ms, heap usage 166.383 MB -> 67.204 MB. [2026-04-15T17:06:54.357Z] ====== als (apache-spark) [default], iteration 18 completed (32862.938 ms) ====== [2026-04-15T17:06:54.357Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-15T17:06:54.357Z] GC before operation: completed in 416.243 ms, heap usage 614.347 MB -> 68.159 MB. [2026-04-15T17:07:32.185Z] ====== als (apache-spark) [default], iteration 19 completed (35060.917 ms) ====== [2026-04-15T17:07:32.185Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-15T17:07:32.186Z] GC before operation: completed in 998.180 ms, heap usage 238.679 MB -> 67.945 MB. [2026-04-15T17:07:58.622Z] ====== als (apache-spark) [default], iteration 20 completed (29331.871 ms) ====== [2026-04-15T17:07:58.622Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-15T17:07:58.622Z] GC before operation: completed in 904.053 ms, heap usage 222.273 MB -> 68.187 MB. [2026-04-15T17:08:35.549Z] ====== als (apache-spark) [default], iteration 21 completed (36222.047 ms) ====== [2026-04-15T17:08:35.549Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-15T17:08:36.138Z] GC before operation: completed in 710.376 ms, heap usage 547.198 MB -> 68.770 MB. [2026-04-15T17:09:13.953Z] ====== als (apache-spark) [default], iteration 22 completed (32849.623 ms) ====== [2026-04-15T17:09:13.953Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-15T17:09:13.953Z] GC before operation: completed in 354.256 ms, heap usage 391.981 MB -> 69.019 MB. [2026-04-15T17:09:46.164Z] ====== als (apache-spark) [default], iteration 23 completed (32510.979 ms) ====== [2026-04-15T17:09:46.164Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-15T17:09:46.164Z] GC before operation: completed in 1342.625 ms, heap usage 596.435 MB -> 69.397 MB. [2026-04-15T17:10:17.145Z] ====== als (apache-spark) [default], iteration 24 completed (29363.352 ms) ====== [2026-04-15T17:10:17.145Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-15T17:10:17.145Z] GC before operation: completed in 816.396 ms, heap usage 376.067 MB -> 69.321 MB. [2026-04-15T17:10:43.124Z] ====== als (apache-spark) [default], iteration 25 completed (25794.434 ms) ====== [2026-04-15T17:10:43.124Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-15T17:10:43.124Z] GC before operation: completed in 343.317 ms, heap usage 286.816 MB -> 69.466 MB. [2026-04-15T17:11:14.994Z] ====== als (apache-spark) [default], iteration 26 completed (29307.859 ms) ====== [2026-04-15T17:11:14.994Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-15T17:11:14.994Z] GC before operation: completed in 1130.418 ms, heap usage 595.195 MB -> 70.140 MB. [2026-04-15T17:11:42.339Z] ====== als (apache-spark) [default], iteration 27 completed (28392.475 ms) ====== [2026-04-15T17:11:42.339Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-15T17:11:42.339Z] GC before operation: completed in 899.249 ms, heap usage 482.126 MB -> 70.328 MB. [2026-04-15T17:12:14.251Z] ====== als (apache-spark) [default], iteration 28 completed (31546.031 ms) ====== [2026-04-15T17:12:14.251Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-15T17:12:14.251Z] GC before operation: completed in 477.280 ms, heap usage 280.501 MB -> 69.761 MB. [2026-04-15T17:12:51.293Z] ====== als (apache-spark) [default], iteration 29 completed (32138.060 ms) ====== [2026-04-15T17:12:51.293Z] ----------------------------------- [2026-04-15T17:12:51.293Z] renaissance-als_0_PASSED [2026-04-15T17:12:51.293Z] ----------------------------------- [2026-04-15T17:12:51.293Z] [2026-04-15T17:12:51.293Z] TEST TEARDOWN: [2026-04-15T17:12:51.293Z] Nothing to be done for teardown. [2026-04-15T17:12:51.293Z] renaissance-als_0 Finish Time: Wed Apr 15 13:12:50 2026 Epoch Time (ms): 1776273170378