[2026-02-12T09:30:32.918Z] Running test renaissance-als_0 ... [2026-02-12T09:30:32.918Z] =============================================== [2026-02-12T09:30:33.359Z] renaissance-als_0 Start Time: Thu Feb 12 04:30:32 2026 Epoch Time (ms): 1770888632671 [2026-02-12T09:30:33.359Z] variation: NoOptions [2026-02-12T09:30:33.359Z] JVM_OPTIONS: [2026-02-12T09:30:33.359Z] { \ [2026-02-12T09:30:33.359Z] echo ""; echo "TEST SETUP:"; \ [2026-02-12T09:30:33.359Z] echo "Nothing to be done for setup."; \ [2026-02-12T09:30:33.359Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17708886307833/renaissance-als_0"; \ [2026-02-12T09:30:33.360Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17708886307833/renaissance-als_0"; \ [2026-02-12T09:30:33.360Z] echo ""; echo "TESTING:"; \ [2026-02-12T09:30:33.360Z] "/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_17708886307833/renaissance-als_0"/als.json" als; \ [2026-02-12T09:30:33.360Z] 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_17708886307833/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-12T09:30:33.360Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-12T09:30:33.360Z] echo "Nothing to be done for teardown."; \ [2026-02-12T09:30:33.360Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17708886307833/TestTargetResult"; [2026-02-12T09:30:33.360Z] [2026-02-12T09:30:33.360Z] TEST SETUP: [2026-02-12T09:30:33.360Z] Nothing to be done for setup. [2026-02-12T09:30:33.360Z] [2026-02-12T09:30:33.360Z] TESTING: [2026-02-12T09:30:58.677Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-12T09:31:07.776Z] WARNING: An illegal reflective access operation has occurred [2026-02-12T09:31:07.776Z] 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_17708886307833/renaissance-als_0/harness-043035-9698070529238082172/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-12T09:31:07.776Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-12T09:31:07.776Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-12T09:31:07.776Z] WARNING: All illegal access operations will be denied in a future release [2026-02-12T09:31:12.886Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-12T09:31:13.344Z] GC before operation: completed in 417.086 ms, heap usage 146.000 MB -> 34.770 MB. [2026-02-12T09:32:40.978Z] ====== als (apache-spark) [default], iteration 0 completed (80059.968 ms) ====== [2026-02-12T09:32:40.978Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-12T09:32:40.978Z] GC before operation: completed in 368.447 ms, heap usage 102.579 MB -> 60.260 MB. [2026-02-12T09:33:18.369Z] ====== als (apache-spark) [default], iteration 1 completed (39515.340 ms) ====== [2026-02-12T09:33:18.369Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-12T09:33:18.369Z] GC before operation: completed in 974.959 ms, heap usage 404.249 MB -> 61.533 MB. [2026-02-12T09:33:55.370Z] ====== als (apache-spark) [default], iteration 2 completed (37579.664 ms) ====== [2026-02-12T09:33:55.370Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-12T09:33:55.829Z] GC before operation: completed in 219.937 ms, heap usage 626.682 MB -> 62.372 MB. [2026-02-12T09:34:27.582Z] ====== als (apache-spark) [default], iteration 3 completed (30450.633 ms) ====== [2026-02-12T09:34:27.582Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-12T09:34:27.582Z] GC before operation: completed in 318.384 ms, heap usage 687.313 MB -> 63.033 MB. [2026-02-12T09:35:04.176Z] ====== als (apache-spark) [default], iteration 4 completed (34551.686 ms) ====== [2026-02-12T09:35:04.177Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-12T09:35:04.177Z] GC before operation: completed in 396.163 ms, heap usage 354.514 MB -> 62.978 MB. [2026-02-12T09:35:35.698Z] ====== als (apache-spark) [default], iteration 5 completed (29214.722 ms) ====== [2026-02-12T09:35:35.698Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-12T09:35:35.698Z] GC before operation: completed in 760.312 ms, heap usage 537.218 MB -> 64.116 MB. [2026-02-12T09:36:12.598Z] ====== als (apache-spark) [default], iteration 6 completed (37613.005 ms) ====== [2026-02-12T09:36:12.598Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-12T09:36:13.047Z] GC before operation: completed in 1225.028 ms, heap usage 468.694 MB -> 64.375 MB. [2026-02-12T09:36:50.165Z] ====== als (apache-spark) [default], iteration 7 completed (32117.777 ms) ====== [2026-02-12T09:36:50.165Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-12T09:36:50.165Z] GC before operation: completed in 380.217 ms, heap usage 662.829 MB -> 65.007 MB. [2026-02-12T09:37:21.609Z] ====== als (apache-spark) [default], iteration 8 completed (33115.860 ms) ====== [2026-02-12T09:37:21.609Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-12T09:37:21.609Z] GC before operation: completed in 520.299 ms, heap usage 871.247 MB -> 65.830 MB. [2026-02-12T09:37:58.975Z] ====== als (apache-spark) [default], iteration 9 completed (35013.755 ms) ====== [2026-02-12T09:37:58.975Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-12T09:37:58.975Z] GC before operation: completed in 473.840 ms, heap usage 1.494 GB -> 66.721 MB. [2026-02-12T09:38:37.096Z] ====== als (apache-spark) [default], iteration 10 completed (34087.419 ms) ====== [2026-02-12T09:38:37.096Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-12T09:38:37.096Z] GC before operation: completed in 435.346 ms, heap usage 395.679 MB -> 65.750 MB. [2026-02-12T09:39:02.899Z] ====== als (apache-spark) [default], iteration 11 completed (28567.465 ms) ====== [2026-02-12T09:39:02.899Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-12T09:39:02.899Z] GC before operation: completed in 385.237 ms, heap usage 112.548 MB -> 65.381 MB. [2026-02-12T09:39:39.664Z] ====== als (apache-spark) [default], iteration 12 completed (34021.210 ms) ====== [2026-02-12T09:39:39.664Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-12T09:39:39.664Z] GC before operation: completed in 385.301 ms, heap usage 1.198 GB -> 67.219 MB. [2026-02-12T09:40:10.781Z] ====== als (apache-spark) [default], iteration 13 completed (32424.142 ms) ====== [2026-02-12T09:40:10.781Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-12T09:40:11.273Z] GC before operation: completed in 887.731 ms, heap usage 866.977 MB -> 67.120 MB. [2026-02-12T09:40:37.497Z] ====== als (apache-spark) [default], iteration 14 completed (25744.105 ms) ====== [2026-02-12T09:40:37.497Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-12T09:40:38.771Z] GC before operation: completed in 972.702 ms, heap usage 716.794 MB -> 67.214 MB. [2026-02-12T09:41:09.553Z] ====== als (apache-spark) [default], iteration 15 completed (25971.278 ms) ====== [2026-02-12T09:41:09.553Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-12T09:41:09.553Z] GC before operation: completed in 213.997 ms, heap usage 275.666 MB -> 66.926 MB. [2026-02-12T09:41:27.706Z] ====== als (apache-spark) [default], iteration 16 completed (21965.508 ms) ====== [2026-02-12T09:41:27.706Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-12T09:41:27.706Z] GC before operation: completed in 283.154 ms, heap usage 155.026 MB -> 67.049 MB. [2026-02-12T09:41:58.891Z] ====== als (apache-spark) [default], iteration 17 completed (25505.877 ms) ====== [2026-02-12T09:41:58.891Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-12T09:41:58.891Z] GC before operation: completed in 255.657 ms, heap usage 207.658 MB -> 67.413 MB. [2026-02-12T09:42:17.032Z] ====== als (apache-spark) [default], iteration 18 completed (21600.714 ms) ====== [2026-02-12T09:42:17.032Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-12T09:42:17.572Z] GC before operation: completed in 298.804 ms, heap usage 121.518 MB -> 67.576 MB. [2026-02-12T09:42:43.635Z] ====== als (apache-spark) [default], iteration 19 completed (24438.569 ms) ====== [2026-02-12T09:42:43.635Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-12T09:42:43.635Z] GC before operation: completed in 291.800 ms, heap usage 957.217 MB -> 68.747 MB. [2026-02-12T09:43:09.624Z] ====== als (apache-spark) [default], iteration 20 completed (24564.098 ms) ====== [2026-02-12T09:43:09.624Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-12T09:43:09.624Z] GC before operation: completed in 248.549 ms, heap usage 149.794 MB -> 68.062 MB. [2026-02-12T09:43:35.207Z] ====== als (apache-spark) [default], iteration 21 completed (23231.889 ms) ====== [2026-02-12T09:43:35.207Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-12T09:43:35.207Z] GC before operation: completed in 355.379 ms, heap usage 153.277 MB -> 68.305 MB. [2026-02-12T09:43:57.213Z] ====== als (apache-spark) [default], iteration 22 completed (24517.044 ms) ====== [2026-02-12T09:43:57.213Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-12T09:43:57.689Z] GC before operation: completed in 213.223 ms, heap usage 372.168 MB -> 68.925 MB. [2026-02-12T09:44:23.444Z] ====== als (apache-spark) [default], iteration 23 completed (23277.946 ms) ====== [2026-02-12T09:44:23.444Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-12T09:44:23.444Z] GC before operation: completed in 267.310 ms, heap usage 855.650 MB -> 69.665 MB. [2026-02-12T09:44:53.983Z] ====== als (apache-spark) [default], iteration 24 completed (27750.760 ms) ====== [2026-02-12T09:44:53.983Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-12T09:44:53.983Z] GC before operation: completed in 426.038 ms, heap usage 140.691 MB -> 69.072 MB. [2026-02-12T09:45:15.848Z] ====== als (apache-spark) [default], iteration 25 completed (23035.350 ms) ====== [2026-02-12T09:45:15.848Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-12T09:45:15.848Z] GC before operation: completed in 229.268 ms, heap usage 549.051 MB -> 69.806 MB. [2026-02-12T09:45:37.257Z] ====== als (apache-spark) [default], iteration 26 completed (20602.708 ms) ====== [2026-02-12T09:45:37.258Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-12T09:45:37.258Z] GC before operation: completed in 301.990 ms, heap usage 291.807 MB -> 69.694 MB. [2026-02-12T09:46:03.349Z] ====== als (apache-spark) [default], iteration 27 completed (24396.438 ms) ====== [2026-02-12T09:46:03.349Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-12T09:46:03.350Z] GC before operation: completed in 414.558 ms, heap usage 361.187 MB -> 69.580 MB. [2026-02-12T09:46:34.199Z] ====== als (apache-spark) [default], iteration 28 completed (29186.364 ms) ====== [2026-02-12T09:46:34.199Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-12T09:46:34.199Z] GC before operation: completed in 423.371 ms, heap usage 152.029 MB -> 69.733 MB. [2026-02-12T09:47:10.544Z] ====== als (apache-spark) [default], iteration 29 completed (32945.680 ms) ====== [2026-02-12T09:47:10.544Z] ----------------------------------- [2026-02-12T09:47:10.544Z] renaissance-als_0_PASSED [2026-02-12T09:47:10.544Z] ----------------------------------- [2026-02-12T09:47:10.544Z] [2026-02-12T09:47:10.544Z] TEST TEARDOWN: [2026-02-12T09:47:10.544Z] Nothing to be done for teardown. [2026-02-12T09:47:10.544Z] renaissance-als_0 Finish Time: Thu Feb 12 04:47:08 2026 Epoch Time (ms): 1770889628963