[2026-02-07T21:17:10.437Z] Running test renaissance-als_0 ... [2026-02-07T21:17:10.437Z] =============================================== [2026-02-07T21:17:10.437Z] renaissance-als_0 Start Time: Sat Feb 7 16:17:09 2026 Epoch Time (ms): 1770499029868 [2026-02-07T21:17:10.437Z] variation: NoOptions [2026-02-07T21:17:10.437Z] JVM_OPTIONS: [2026-02-07T21:17:10.437Z] { \ [2026-02-07T21:17:10.437Z] echo ""; echo "TEST SETUP:"; \ [2026-02-07T21:17:10.437Z] echo "Nothing to be done for setup."; \ [2026-02-07T21:17:10.437Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17704990272864/renaissance-als_0"; \ [2026-02-07T21:17:10.437Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17704990272864/renaissance-als_0"; \ [2026-02-07T21:17:10.437Z] echo ""; echo "TESTING:"; \ [2026-02-07T21:17:10.437Z] "/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_17704990272864/renaissance-als_0"/als.json" als; \ [2026-02-07T21:17:10.437Z] 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_17704990272864/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-07T21:17:10.437Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-07T21:17:10.437Z] echo "Nothing to be done for teardown."; \ [2026-02-07T21:17:10.437Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17704990272864/TestTargetResult"; [2026-02-07T21:17:10.437Z] [2026-02-07T21:17:10.437Z] TEST SETUP: [2026-02-07T21:17:10.437Z] Nothing to be done for setup. [2026-02-07T21:17:10.437Z] [2026-02-07T21:17:10.437Z] TESTING: [2026-02-07T21:18:03.869Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-07T21:18:24.175Z] WARNING: An illegal reflective access operation has occurred [2026-02-07T21:18:24.175Z] 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_17704990272864/renaissance-als_0/harness-161711-11627638751437580894/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-07T21:18:24.175Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-07T21:18:24.175Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-07T21:18:24.175Z] WARNING: All illegal access operations will be denied in a future release [2026-02-07T21:18:28.596Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-07T21:18:30.949Z] GC before operation: completed in 1354.192 ms, heap usage 372.500 MB -> 34.772 MB. [2026-02-07T21:20:31.294Z] ====== als (apache-spark) [default], iteration 0 completed (107749.959 ms) ====== [2026-02-07T21:20:31.294Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-07T21:20:31.294Z] GC before operation: completed in 930.808 ms, heap usage 601.234 MB -> 60.969 MB. [2026-02-07T21:21:15.356Z] ====== als (apache-spark) [default], iteration 1 completed (50692.291 ms) ====== [2026-02-07T21:21:15.356Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-07T21:21:15.356Z] GC before operation: completed in 640.213 ms, heap usage 545.265 MB -> 61.701 MB. [2026-02-07T21:22:08.251Z] ====== als (apache-spark) [default], iteration 2 completed (47018.574 ms) ====== [2026-02-07T21:22:08.251Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-07T21:22:08.251Z] GC before operation: completed in 389.801 ms, heap usage 751.241 MB -> 62.555 MB. [2026-02-07T21:22:52.056Z] ====== als (apache-spark) [default], iteration 3 completed (47053.494 ms) ====== [2026-02-07T21:22:52.056Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-07T21:22:52.057Z] GC before operation: completed in 452.053 ms, heap usage 327.116 MB -> 62.570 MB. [2026-02-07T21:23:35.852Z] ====== als (apache-spark) [default], iteration 4 completed (42845.584 ms) ====== [2026-02-07T21:23:35.852Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-07T21:23:35.852Z] GC before operation: completed in 673.860 ms, heap usage 604.022 MB -> 63.501 MB. [2026-02-07T21:24:20.367Z] ====== als (apache-spark) [default], iteration 5 completed (41062.387 ms) ====== [2026-02-07T21:24:20.367Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-07T21:24:20.367Z] GC before operation: completed in 386.853 ms, heap usage 373.803 MB -> 63.656 MB. [2026-02-07T21:25:05.071Z] ====== als (apache-spark) [default], iteration 6 completed (44699.782 ms) ====== [2026-02-07T21:25:05.071Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-07T21:25:05.071Z] GC before operation: completed in 392.186 ms, heap usage 269.856 MB -> 64.085 MB. [2026-02-07T21:25:48.721Z] ====== als (apache-spark) [default], iteration 7 completed (42424.150 ms) ====== [2026-02-07T21:25:48.721Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-07T21:25:48.721Z] GC before operation: completed in 589.450 ms, heap usage 764.291 MB -> 65.221 MB. [2026-02-07T21:26:33.587Z] ====== als (apache-spark) [default], iteration 8 completed (40382.281 ms) ====== [2026-02-07T21:26:33.587Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-07T21:26:33.587Z] GC before operation: completed in 387.384 ms, heap usage 543.611 MB -> 65.545 MB. [2026-02-07T21:27:18.138Z] ====== als (apache-spark) [default], iteration 9 completed (44073.587 ms) ====== [2026-02-07T21:27:18.138Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-07T21:27:18.138Z] GC before operation: completed in 851.742 ms, heap usage 479.045 MB -> 65.757 MB. [2026-02-07T21:28:02.482Z] ====== als (apache-spark) [default], iteration 10 completed (44516.915 ms) ====== [2026-02-07T21:28:02.482Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-07T21:28:02.482Z] GC before operation: completed in 666.705 ms, heap usage 345.329 MB -> 65.871 MB. [2026-02-07T21:28:39.998Z] ====== als (apache-spark) [default], iteration 11 completed (38176.026 ms) ====== [2026-02-07T21:28:39.998Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-07T21:28:39.998Z] GC before operation: completed in 333.308 ms, heap usage 1014.111 MB -> 66.724 MB. [2026-02-07T21:29:25.057Z] ====== als (apache-spark) [default], iteration 12 completed (40485.421 ms) ====== [2026-02-07T21:29:25.057Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-07T21:29:25.057Z] GC before operation: completed in 519.978 ms, heap usage 512.337 MB -> 66.636 MB. [2026-02-07T21:30:02.092Z] ====== als (apache-spark) [default], iteration 13 completed (38642.972 ms) ====== [2026-02-07T21:30:02.092Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-07T21:30:02.092Z] GC before operation: completed in 678.929 ms, heap usage 163.346 MB -> 66.748 MB. [2026-02-07T21:30:40.109Z] ====== als (apache-spark) [default], iteration 14 completed (37243.711 ms) ====== [2026-02-07T21:30:40.109Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-07T21:30:40.109Z] GC before operation: completed in 250.835 ms, heap usage 613.125 MB -> 67.098 MB. [2026-02-07T21:31:24.100Z] ====== als (apache-spark) [default], iteration 15 completed (39205.260 ms) ====== [2026-02-07T21:31:24.100Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-07T21:31:24.100Z] GC before operation: completed in 503.547 ms, heap usage 467.881 MB -> 67.394 MB. [2026-02-07T21:32:01.016Z] ====== als (apache-spark) [default], iteration 16 completed (39241.553 ms) ====== [2026-02-07T21:32:01.016Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-07T21:32:01.016Z] GC before operation: completed in 492.651 ms, heap usage 497.570 MB -> 67.528 MB. [2026-02-07T21:32:45.478Z] ====== als (apache-spark) [default], iteration 17 completed (40687.750 ms) ====== [2026-02-07T21:32:45.478Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-07T21:32:45.478Z] GC before operation: completed in 1383.572 ms, heap usage 578.661 MB -> 67.869 MB. [2026-02-07T21:33:29.167Z] ====== als (apache-spark) [default], iteration 18 completed (40704.151 ms) ====== [2026-02-07T21:33:29.167Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-07T21:33:29.167Z] GC before operation: completed in 359.282 ms, heap usage 581.350 MB -> 68.191 MB. [2026-02-07T21:34:06.426Z] ====== als (apache-spark) [default], iteration 19 completed (36722.865 ms) ====== [2026-02-07T21:34:06.426Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-07T21:34:06.426Z] GC before operation: completed in 827.674 ms, heap usage 133.854 MB -> 68.693 MB. [2026-02-07T21:35:00.176Z] ====== als (apache-spark) [default], iteration 20 completed (49955.115 ms) ====== [2026-02-07T21:35:00.176Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-07T21:35:00.176Z] GC before operation: completed in 543.417 ms, heap usage 385.488 MB -> 68.307 MB. [2026-02-07T21:35:38.262Z] ====== als (apache-spark) [default], iteration 21 completed (43131.185 ms) ====== [2026-02-07T21:35:38.262Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-07T21:35:38.262Z] GC before operation: completed in 559.716 ms, heap usage 468.155 MB -> 68.671 MB. [2026-02-07T21:36:22.384Z] ====== als (apache-spark) [default], iteration 22 completed (39838.103 ms) ====== [2026-02-07T21:36:22.384Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-07T21:36:22.384Z] GC before operation: completed in 427.034 ms, heap usage 272.670 MB -> 68.694 MB. [2026-02-07T21:37:00.143Z] ====== als (apache-spark) [default], iteration 23 completed (38925.444 ms) ====== [2026-02-07T21:37:00.143Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-07T21:37:00.143Z] GC before operation: completed in 399.228 ms, heap usage 399.276 MB -> 69.057 MB. [2026-02-07T21:37:44.610Z] ====== als (apache-spark) [default], iteration 24 completed (40487.072 ms) ====== [2026-02-07T21:37:44.610Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-07T21:37:44.610Z] GC before operation: completed in 534.952 ms, heap usage 748.806 MB -> 69.787 MB. [2026-02-07T21:38:21.773Z] ====== als (apache-spark) [default], iteration 25 completed (35722.156 ms) ====== [2026-02-07T21:38:21.773Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-07T21:38:21.773Z] GC before operation: completed in 455.948 ms, heap usage 278.545 MB -> 69.431 MB. [2026-02-07T21:39:06.710Z] ====== als (apache-spark) [default], iteration 26 completed (41905.382 ms) ====== [2026-02-07T21:39:06.710Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-07T21:39:06.710Z] GC before operation: completed in 503.905 ms, heap usage 552.659 MB -> 70.007 MB. [2026-02-07T21:39:44.910Z] ====== als (apache-spark) [default], iteration 27 completed (40990.618 ms) ====== [2026-02-07T21:39:44.910Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-07T21:39:44.910Z] GC before operation: completed in 352.044 ms, heap usage 322.520 MB -> 70.040 MB. [2026-02-07T21:40:29.529Z] ====== als (apache-spark) [default], iteration 28 completed (41262.243 ms) ====== [2026-02-07T21:40:29.529Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-07T21:40:29.529Z] GC before operation: completed in 1299.811 ms, heap usage 186.318 MB -> 69.624 MB. [2026-02-07T21:41:00.868Z] ====== als (apache-spark) [default], iteration 29 completed (30562.011 ms) ====== [2026-02-07T21:41:01.807Z] ----------------------------------- [2026-02-07T21:41:01.807Z] renaissance-als_0_PASSED [2026-02-07T21:41:01.807Z] ----------------------------------- [2026-02-07T21:41:01.807Z] [2026-02-07T21:41:01.807Z] TEST TEARDOWN: [2026-02-07T21:41:01.807Z] Nothing to be done for teardown. [2026-02-07T21:41:02.795Z] renaissance-als_0 Finish Time: Sat Feb 7 16:41:02 2026 Epoch Time (ms): 1770500462298