[2026-01-17T03:57:23.432Z] Running test renaissance-als_0 ... [2026-01-17T03:57:23.432Z] =============================================== [2026-01-17T03:57:23.922Z] renaissance-als_0 Start Time: Fri Jan 16 22:57:23 2026 Epoch Time (ms): 1768622243275 [2026-01-17T03:57:23.922Z] variation: NoOptions [2026-01-17T03:57:23.922Z] JVM_OPTIONS: [2026-01-17T03:57:24.393Z] { \ [2026-01-17T03:57:24.393Z] echo ""; echo "TEST SETUP:"; \ [2026-01-17T03:57:24.393Z] echo "Nothing to be done for setup."; \ [2026-01-17T03:57:24.393Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17686222416713/renaissance-als_0"; \ [2026-01-17T03:57:24.393Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17686222416713/renaissance-als_0"; \ [2026-01-17T03:57:24.393Z] echo ""; echo "TESTING:"; \ [2026-01-17T03:57:24.394Z] "/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_17686222416713/renaissance-als_0"/als.json" als; \ [2026-01-17T03:57:24.394Z] 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_17686222416713/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-17T03:57:24.394Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-17T03:57:24.394Z] echo "Nothing to be done for teardown."; \ [2026-01-17T03:57:24.394Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17686222416713/TestTargetResult"; [2026-01-17T03:57:24.394Z] [2026-01-17T03:57:24.394Z] TEST SETUP: [2026-01-17T03:57:24.394Z] Nothing to be done for setup. [2026-01-17T03:57:24.394Z] [2026-01-17T03:57:24.394Z] TESTING: [2026-01-17T03:57:42.031Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-17T03:57:51.304Z] WARNING: An illegal reflective access operation has occurred [2026-01-17T03:57:51.304Z] 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_17686222416713/renaissance-als_0/harness-225725-3242718047251397887/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-17T03:57:51.304Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-17T03:57:51.304Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-17T03:57:51.304Z] WARNING: All illegal access operations will be denied in a future release [2026-01-17T03:57:54.839Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-17T03:57:54.839Z] GC before operation: completed in 219.551 ms, heap usage 137.002 MB -> 34.760 MB. [2026-01-17T03:58:55.823Z] ====== als (apache-spark) [default], iteration 0 completed (54420.005 ms) ====== [2026-01-17T03:58:55.823Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-17T03:58:55.823Z] GC before operation: completed in 231.701 ms, heap usage 208.433 MB -> 60.619 MB. [2026-01-17T03:59:21.485Z] ====== als (apache-spark) [default], iteration 1 completed (28814.010 ms) ====== [2026-01-17T03:59:21.485Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-17T03:59:21.485Z] GC before operation: completed in 386.125 ms, heap usage 816.609 MB -> 62.056 MB. [2026-01-17T03:59:46.981Z] ====== als (apache-spark) [default], iteration 2 completed (25156.662 ms) ====== [2026-01-17T03:59:46.981Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-17T03:59:47.526Z] GC before operation: completed in 607.341 ms, heap usage 174.239 MB -> 62.228 MB. [2026-01-17T04:00:13.279Z] ====== als (apache-spark) [default], iteration 3 completed (22318.966 ms) ====== [2026-01-17T04:00:13.279Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-17T04:00:13.279Z] GC before operation: completed in 305.741 ms, heap usage 202.382 MB -> 62.369 MB. [2026-01-17T04:00:38.932Z] ====== als (apache-spark) [default], iteration 4 completed (24363.064 ms) ====== [2026-01-17T04:00:38.932Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-17T04:00:38.932Z] GC before operation: completed in 147.168 ms, heap usage 191.861 MB -> 62.881 MB. [2026-01-17T04:01:00.322Z] ====== als (apache-spark) [default], iteration 5 completed (21885.825 ms) ====== [2026-01-17T04:01:00.322Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-17T04:01:00.322Z] GC before operation: completed in 1072.023 ms, heap usage 402.282 MB -> 63.651 MB. [2026-01-17T04:01:26.235Z] ====== als (apache-spark) [default], iteration 6 completed (24971.153 ms) ====== [2026-01-17T04:01:26.235Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-17T04:01:26.235Z] GC before operation: completed in 445.811 ms, heap usage 644.761 MB -> 64.504 MB. [2026-01-17T04:01:51.906Z] ====== als (apache-spark) [default], iteration 7 completed (24089.383 ms) ====== [2026-01-17T04:01:51.906Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-17T04:01:51.906Z] GC before operation: completed in 378.763 ms, heap usage 953.886 MB -> 65.439 MB. [2026-01-17T04:02:13.334Z] ====== als (apache-spark) [default], iteration 8 completed (21832.388 ms) ====== [2026-01-17T04:02:13.334Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-17T04:02:13.773Z] GC before operation: completed in 803.259 ms, heap usage 367.809 MB -> 65.238 MB. [2026-01-17T04:02:40.241Z] ====== als (apache-spark) [default], iteration 9 completed (24195.148 ms) ====== [2026-01-17T04:02:40.241Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-17T04:02:40.241Z] GC before operation: completed in 388.054 ms, heap usage 231.751 MB -> 65.321 MB. [2026-01-17T04:03:01.864Z] ====== als (apache-spark) [default], iteration 10 completed (21848.065 ms) ====== [2026-01-17T04:03:01.864Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-17T04:03:01.864Z] GC before operation: completed in 385.423 ms, heap usage 1.535 GB -> 67.072 MB. [2026-01-17T04:03:23.425Z] ====== als (apache-spark) [default], iteration 11 completed (18372.300 ms) ====== [2026-01-17T04:03:23.425Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-17T04:03:23.425Z] GC before operation: completed in 211.859 ms, heap usage 540.714 MB -> 66.266 MB. [2026-01-17T04:03:44.438Z] ====== als (apache-spark) [default], iteration 12 completed (21137.295 ms) ====== [2026-01-17T04:03:44.438Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-17T04:03:44.438Z] GC before operation: completed in 254.038 ms, heap usage 632.119 MB -> 66.605 MB. [2026-01-17T04:04:05.701Z] ====== als (apache-spark) [default], iteration 13 completed (20287.123 ms) ====== [2026-01-17T04:04:05.701Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-17T04:04:05.701Z] GC before operation: completed in 848.504 ms, heap usage 953.704 MB -> 67.391 MB. [2026-01-17T04:04:23.741Z] ====== als (apache-spark) [default], iteration 14 completed (18558.275 ms) ====== [2026-01-17T04:04:23.741Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-17T04:04:23.741Z] GC before operation: completed in 218.976 ms, heap usage 501.531 MB -> 67.002 MB. [2026-01-17T04:04:49.061Z] ====== als (apache-spark) [default], iteration 15 completed (22997.054 ms) ====== [2026-01-17T04:04:49.061Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-17T04:04:49.061Z] GC before operation: completed in 445.544 ms, heap usage 592.619 MB -> 67.398 MB. [2026-01-17T04:05:10.618Z] ====== als (apache-spark) [default], iteration 16 completed (20584.975 ms) ====== [2026-01-17T04:05:10.618Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-17T04:05:10.618Z] GC before operation: completed in 308.833 ms, heap usage 663.725 MB -> 67.790 MB. [2026-01-17T04:05:28.456Z] ====== als (apache-spark) [default], iteration 17 completed (20075.551 ms) ====== [2026-01-17T04:05:28.456Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-17T04:05:28.890Z] GC before operation: completed in 284.677 ms, heap usage 425.337 MB -> 67.667 MB. [2026-01-17T04:05:54.619Z] ====== als (apache-spark) [default], iteration 18 completed (21956.412 ms) ====== [2026-01-17T04:05:54.619Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-17T04:05:54.619Z] GC before operation: completed in 1111.996 ms, heap usage 623.072 MB -> 68.124 MB. [2026-01-17T04:06:16.228Z] ====== als (apache-spark) [default], iteration 19 completed (21139.563 ms) ====== [2026-01-17T04:06:16.228Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-17T04:06:16.228Z] GC before operation: completed in 299.092 ms, heap usage 246.253 MB -> 67.943 MB. [2026-01-17T04:06:34.249Z] ====== als (apache-spark) [default], iteration 20 completed (19061.533 ms) ====== [2026-01-17T04:06:34.249Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-17T04:06:34.249Z] GC before operation: completed in 259.289 ms, heap usage 559.208 MB -> 68.622 MB. [2026-01-17T04:06:55.858Z] ====== als (apache-spark) [default], iteration 21 completed (21214.294 ms) ====== [2026-01-17T04:06:55.858Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-17T04:06:55.858Z] GC before operation: completed in 503.381 ms, heap usage 1.213 GB -> 69.604 MB. [2026-01-17T04:07:21.507Z] ====== als (apache-spark) [default], iteration 22 completed (21755.957 ms) ====== [2026-01-17T04:07:21.507Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-17T04:07:21.507Z] GC before operation: completed in 351.628 ms, heap usage 936.775 MB -> 70.665 MB. [2026-01-17T04:07:43.114Z] ====== als (apache-spark) [default], iteration 23 completed (20890.720 ms) ====== [2026-01-17T04:07:43.114Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-17T04:07:43.114Z] GC before operation: completed in 1024.271 ms, heap usage 726.835 MB -> 69.602 MB. [2026-01-17T04:08:09.138Z] ====== als (apache-spark) [default], iteration 24 completed (23989.278 ms) ====== [2026-01-17T04:08:09.138Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-17T04:08:09.138Z] GC before operation: completed in 301.518 ms, heap usage 732.415 MB -> 69.874 MB. [2026-01-17T04:08:27.039Z] ====== als (apache-spark) [default], iteration 25 completed (19168.871 ms) ====== [2026-01-17T04:08:27.039Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-17T04:08:27.039Z] GC before operation: completed in 279.130 ms, heap usage 151.230 MB -> 69.346 MB. [2026-01-17T04:08:48.127Z] ====== als (apache-spark) [default], iteration 26 completed (19336.942 ms) ====== [2026-01-17T04:08:48.127Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-17T04:08:48.127Z] GC before operation: completed in 261.798 ms, heap usage 1.051 GB -> 70.790 MB. [2026-01-17T04:09:10.016Z] ====== als (apache-spark) [default], iteration 27 completed (22982.992 ms) ====== [2026-01-17T04:09:10.016Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-17T04:09:10.016Z] GC before operation: completed in 271.397 ms, heap usage 353.258 MB -> 70.060 MB. [2026-01-17T04:09:31.394Z] ====== als (apache-spark) [default], iteration 28 completed (20175.342 ms) ====== [2026-01-17T04:09:31.394Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-17T04:09:31.394Z] GC before operation: completed in 842.405 ms, heap usage 793.576 MB -> 70.363 MB. [2026-01-17T04:09:52.536Z] ====== als (apache-spark) [default], iteration 29 completed (18507.542 ms) ====== [2026-01-17T04:09:52.536Z] ----------------------------------- [2026-01-17T04:09:52.536Z] renaissance-als_0_PASSED [2026-01-17T04:09:52.536Z] ----------------------------------- [2026-01-17T04:09:52.536Z] [2026-01-17T04:09:52.536Z] TEST TEARDOWN: [2026-01-17T04:09:52.536Z] Nothing to be done for teardown. [2026-01-17T04:09:52.536Z] renaissance-als_0 Finish Time: Fri Jan 16 23:09:51 2026 Epoch Time (ms): 1768622991523