[2026-01-10T16:23:31.925Z] Running test renaissance-als_0 ... [2026-01-10T16:23:31.925Z] =============================================== [2026-01-10T16:23:31.925Z] renaissance-als_0 Start Time: Sat Jan 10 11:23:31 2026 Epoch Time (ms): 1768062211580 [2026-01-10T16:23:31.925Z] variation: NoOptions [2026-01-10T16:23:32.473Z] JVM_OPTIONS: [2026-01-10T16:23:32.473Z] { \ [2026-01-10T16:23:32.473Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T16:23:32.473Z] echo "Nothing to be done for setup."; \ [2026-01-10T16:23:32.473Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17680622084293/renaissance-als_0"; \ [2026-01-10T16:23:32.473Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17680622084293/renaissance-als_0"; \ [2026-01-10T16:23:32.473Z] echo ""; echo "TESTING:"; \ [2026-01-10T16:23:32.473Z] "/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_17680622084293/renaissance-als_0"/als.json" als; \ [2026-01-10T16:23:32.473Z] 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_17680622084293/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T16:23:32.473Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T16:23:32.473Z] echo "Nothing to be done for teardown."; \ [2026-01-10T16:23:32.473Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17680622084293/TestTargetResult"; [2026-01-10T16:23:32.473Z] [2026-01-10T16:23:32.473Z] TEST SETUP: [2026-01-10T16:23:32.473Z] Nothing to be done for setup. [2026-01-10T16:23:32.473Z] [2026-01-10T16:23:32.473Z] TESTING: [2026-01-10T16:24:03.342Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-10T16:24:14.211Z] WARNING: An illegal reflective access operation has occurred [2026-01-10T16:24:14.211Z] 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_17680622084293/renaissance-als_0/harness-112334-10310759651664779417/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-10T16:24:14.211Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-10T16:24:14.211Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-10T16:24:14.211Z] WARNING: All illegal access operations will be denied in a future release [2026-01-10T16:24:18.987Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T16:24:19.451Z] GC before operation: completed in 371.818 ms, heap usage 160.314 MB -> 34.780 MB. [2026-01-10T16:26:01.855Z] ====== als (apache-spark) [default], iteration 0 completed (89177.665 ms) ====== [2026-01-10T16:26:01.855Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T16:26:01.855Z] GC before operation: completed in 439.197 ms, heap usage 653.889 MB -> 61.109 MB. [2026-01-10T16:26:39.206Z] ====== als (apache-spark) [default], iteration 1 completed (45591.186 ms) ====== [2026-01-10T16:26:39.206Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T16:26:39.711Z] GC before operation: completed in 1369.998 ms, heap usage 542.336 MB -> 61.762 MB. [2026-01-10T16:27:24.844Z] ====== als (apache-spark) [default], iteration 2 completed (40556.215 ms) ====== [2026-01-10T16:27:24.844Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T16:27:24.844Z] GC before operation: completed in 1224.519 ms, heap usage 116.430 MB -> 62.011 MB. [2026-01-10T16:28:08.788Z] ====== als (apache-spark) [default], iteration 3 completed (44193.410 ms) ====== [2026-01-10T16:28:08.788Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T16:28:08.788Z] GC before operation: completed in 605.631 ms, heap usage 493.584 MB -> 62.825 MB. [2026-01-10T16:28:52.904Z] ====== als (apache-spark) [default], iteration 4 completed (39180.345 ms) ====== [2026-01-10T16:28:52.904Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T16:28:52.904Z] GC before operation: completed in 734.950 ms, heap usage 910.463 MB -> 63.736 MB. [2026-01-10T16:29:37.045Z] ====== als (apache-spark) [default], iteration 5 completed (40760.525 ms) ====== [2026-01-10T16:29:37.045Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T16:29:37.045Z] GC before operation: completed in 440.236 ms, heap usage 1.028 GB -> 64.497 MB. [2026-01-10T16:30:21.774Z] ====== als (apache-spark) [default], iteration 6 completed (43506.249 ms) ====== [2026-01-10T16:30:21.774Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T16:30:21.774Z] GC before operation: completed in 872.552 ms, heap usage 812.134 MB -> 64.710 MB. [2026-01-10T16:31:06.929Z] ====== als (apache-spark) [default], iteration 7 completed (43971.403 ms) ====== [2026-01-10T16:31:06.929Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T16:31:06.929Z] GC before operation: completed in 413.341 ms, heap usage 134.663 MB -> 64.501 MB. [2026-01-10T16:31:51.084Z] ====== als (apache-spark) [default], iteration 8 completed (46359.835 ms) ====== [2026-01-10T16:31:51.084Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T16:31:51.085Z] GC before operation: completed in 360.864 ms, heap usage 259.231 MB -> 65.269 MB. [2026-01-10T16:32:27.858Z] ====== als (apache-spark) [default], iteration 9 completed (35115.348 ms) ====== [2026-01-10T16:32:27.858Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T16:32:28.403Z] GC before operation: completed in 1218.610 ms, heap usage 254.580 MB -> 65.333 MB. [2026-01-10T16:33:13.192Z] ====== als (apache-spark) [default], iteration 10 completed (43074.083 ms) ====== [2026-01-10T16:33:13.192Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T16:33:13.192Z] GC before operation: completed in 475.298 ms, heap usage 148.217 MB -> 66.099 MB. [2026-01-10T16:33:57.911Z] ====== als (apache-spark) [default], iteration 11 completed (42654.503 ms) ====== [2026-01-10T16:33:57.911Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T16:33:57.911Z] GC before operation: completed in 376.849 ms, heap usage 930.955 MB -> 66.743 MB. [2026-01-10T16:34:41.791Z] ====== als (apache-spark) [default], iteration 12 completed (39329.623 ms) ====== [2026-01-10T16:34:41.791Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T16:34:41.791Z] GC before operation: completed in 1725.529 ms, heap usage 1.193 GB -> 67.253 MB. [2026-01-10T16:35:19.962Z] ====== als (apache-spark) [default], iteration 13 completed (37866.665 ms) ====== [2026-01-10T16:35:19.962Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T16:35:19.962Z] GC before operation: completed in 1299.860 ms, heap usage 492.806 MB -> 66.777 MB. [2026-01-10T16:36:04.329Z] ====== als (apache-spark) [default], iteration 14 completed (41711.831 ms) ====== [2026-01-10T16:36:04.330Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T16:36:04.330Z] GC before operation: completed in 1511.536 ms, heap usage 126.163 MB -> 67.043 MB. [2026-01-10T16:36:41.259Z] ====== als (apache-spark) [default], iteration 15 completed (36438.236 ms) ====== [2026-01-10T16:36:41.259Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T16:36:41.259Z] GC before operation: completed in 398.680 ms, heap usage 537.985 MB -> 67.309 MB. [2026-01-10T16:37:18.720Z] ====== als (apache-spark) [default], iteration 16 completed (39924.702 ms) ====== [2026-01-10T16:37:18.720Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T16:37:19.738Z] GC before operation: completed in 688.824 ms, heap usage 519.578 MB -> 67.512 MB. [2026-01-10T16:38:04.094Z] ====== als (apache-spark) [default], iteration 17 completed (40838.703 ms) ====== [2026-01-10T16:38:04.094Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T16:38:04.094Z] GC before operation: completed in 640.850 ms, heap usage 960.901 MB -> 68.241 MB. [2026-01-10T16:38:56.964Z] ====== als (apache-spark) [default], iteration 18 completed (45854.862 ms) ====== [2026-01-10T16:38:56.964Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T16:38:56.964Z] GC before operation: completed in 746.552 ms, heap usage 218.050 MB -> 67.812 MB. [2026-01-10T16:39:41.091Z] ====== als (apache-spark) [default], iteration 19 completed (43972.538 ms) ====== [2026-01-10T16:39:41.091Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T16:39:41.091Z] GC before operation: completed in 803.216 ms, heap usage 1.000 GB -> 68.854 MB. [2026-01-10T16:40:19.250Z] ====== als (apache-spark) [default], iteration 20 completed (41416.333 ms) ====== [2026-01-10T16:40:19.250Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T16:40:20.611Z] GC before operation: completed in 1384.437 ms, heap usage 617.173 MB -> 68.637 MB. [2026-01-10T16:41:04.433Z] ====== als (apache-spark) [default], iteration 21 completed (39272.840 ms) ====== [2026-01-10T16:41:04.433Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T16:41:04.433Z] GC before operation: completed in 536.568 ms, heap usage 500.973 MB -> 68.763 MB. [2026-01-10T16:41:41.503Z] ====== als (apache-spark) [default], iteration 22 completed (35618.458 ms) ====== [2026-01-10T16:41:41.503Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T16:41:41.503Z] GC before operation: completed in 1423.187 ms, heap usage 270.579 MB -> 68.724 MB. [2026-01-10T16:42:26.170Z] ====== als (apache-spark) [default], iteration 23 completed (41899.567 ms) ====== [2026-01-10T16:42:26.170Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T16:42:26.170Z] GC before operation: completed in 1329.803 ms, heap usage 287.589 MB -> 68.977 MB. [2026-01-10T16:43:10.462Z] ====== als (apache-spark) [default], iteration 24 completed (42835.952 ms) ====== [2026-01-10T16:43:10.462Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T16:43:10.462Z] GC before operation: completed in 528.198 ms, heap usage 894.773 MB -> 69.922 MB. [2026-01-10T16:43:47.652Z] ====== als (apache-spark) [default], iteration 25 completed (37606.910 ms) ====== [2026-01-10T16:43:47.652Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T16:43:47.652Z] GC before operation: completed in 549.952 ms, heap usage 993.941 MB -> 69.818 MB. [2026-01-10T16:44:24.703Z] ====== als (apache-spark) [default], iteration 26 completed (36320.936 ms) ====== [2026-01-10T16:44:24.703Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T16:44:24.703Z] GC before operation: completed in 1141.185 ms, heap usage 621.184 MB -> 70.230 MB. [2026-01-10T16:45:09.633Z] ====== als (apache-spark) [default], iteration 27 completed (42633.422 ms) ====== [2026-01-10T16:45:09.633Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T16:45:09.633Z] GC before operation: completed in 1322.515 ms, heap usage 535.066 MB -> 70.314 MB. [2026-01-10T16:45:54.037Z] ====== als (apache-spark) [default], iteration 28 completed (39227.612 ms) ====== [2026-01-10T16:45:54.037Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T16:45:54.037Z] GC before operation: completed in 460.300 ms, heap usage 426.476 MB -> 70.337 MB. [2026-01-10T16:46:38.431Z] ====== als (apache-spark) [default], iteration 29 completed (44606.682 ms) ====== [2026-01-10T16:46:38.431Z] ----------------------------------- [2026-01-10T16:46:38.431Z] renaissance-als_0_PASSED [2026-01-10T16:46:38.431Z] ----------------------------------- [2026-01-10T16:46:38.431Z] [2026-01-10T16:46:38.431Z] TEST TEARDOWN: [2026-01-10T16:46:38.431Z] Nothing to be done for teardown. [2026-01-10T16:46:38.431Z] renaissance-als_0 Finish Time: Sat Jan 10 11:46:36 2026 Epoch Time (ms): 1768063596715