[2026-03-05T19:59:44.488Z] Running test renaissance-als_0 ... [2026-03-05T19:59:44.488Z] =============================================== [2026-03-05T19:59:44.488Z] renaissance-als_0 Start Time: Thu Mar 5 14:59:44 2026 Epoch Time (ms): 1772740784209 [2026-03-05T19:59:44.488Z] variation: NoOptions [2026-03-05T19:59:44.923Z] JVM_OPTIONS: [2026-03-05T19:59:44.923Z] { \ [2026-03-05T19:59:44.923Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T19:59:44.923Z] echo "Nothing to be done for setup."; \ [2026-03-05T19:59:44.923Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17727407812599/renaissance-als_0"; \ [2026-03-05T19:59:44.923Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17727407812599/renaissance-als_0"; \ [2026-03-05T19:59:44.923Z] echo ""; echo "TESTING:"; \ [2026-03-05T19:59:44.923Z] "/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_17727407812599/renaissance-als_0"/als.json" als; \ [2026-03-05T19:59:44.923Z] 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_17727407812599/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T19:59:44.923Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T19:59:44.923Z] echo "Nothing to be done for teardown."; \ [2026-03-05T19:59:44.923Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17727407812599/TestTargetResult"; [2026-03-05T19:59:44.923Z] [2026-03-05T19:59:44.923Z] TEST SETUP: [2026-03-05T19:59:44.923Z] Nothing to be done for setup. [2026-03-05T19:59:44.923Z] [2026-03-05T19:59:44.923Z] TESTING: [2026-03-05T20:00:22.064Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-03-05T20:00:40.887Z] WARNING: An illegal reflective access operation has occurred [2026-03-05T20:00:40.887Z] 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_17727407812599/renaissance-als_0/harness-145947-13451434637454039768/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-03-05T20:00:40.887Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-03-05T20:00:40.887Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-03-05T20:00:40.887Z] WARNING: All illegal access operations will be denied in a future release [2026-03-05T20:00:45.872Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-05T20:00:46.398Z] GC before operation: completed in 246.974 ms, heap usage 290.623 MB -> 34.712 MB. [2026-03-05T20:02:45.618Z] ====== als (apache-spark) [default], iteration 0 completed (113524.667 ms) ====== [2026-03-05T20:02:45.618Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-05T20:02:45.618Z] GC before operation: completed in 1062.564 ms, heap usage 890.897 MB -> 61.258 MB. [2026-03-05T20:03:59.901Z] ====== als (apache-spark) [default], iteration 1 completed (65397.877 ms) ====== [2026-03-05T20:03:59.901Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-05T20:03:59.901Z] GC before operation: completed in 407.077 ms, heap usage 705.421 MB -> 61.913 MB. [2026-03-05T20:04:36.844Z] ====== als (apache-spark) [default], iteration 2 completed (44011.516 ms) ====== [2026-03-05T20:04:36.844Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-05T20:04:36.844Z] GC before operation: completed in 872.581 ms, heap usage 391.731 MB -> 62.049 MB. [2026-03-05T20:05:28.991Z] ====== als (apache-spark) [default], iteration 3 completed (47154.264 ms) ====== [2026-03-05T20:05:28.991Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-05T20:05:28.991Z] GC before operation: completed in 596.015 ms, heap usage 379.573 MB -> 62.617 MB. [2026-03-05T20:06:12.781Z] ====== als (apache-spark) [default], iteration 4 completed (47160.032 ms) ====== [2026-03-05T20:06:12.781Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-05T20:06:12.781Z] GC before operation: completed in 1052.692 ms, heap usage 744.740 MB -> 63.525 MB. [2026-03-05T20:07:05.631Z] ====== als (apache-spark) [default], iteration 5 completed (44600.386 ms) ====== [2026-03-05T20:07:05.631Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-05T20:07:05.631Z] GC before operation: completed in 1212.202 ms, heap usage 681.400 MB -> 63.964 MB. [2026-03-05T20:07:50.809Z] ====== als (apache-spark) [default], iteration 6 completed (50488.192 ms) ====== [2026-03-05T20:07:50.809Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-05T20:07:51.885Z] GC before operation: completed in 994.141 ms, heap usage 230.984 MB -> 64.057 MB. [2026-03-05T20:08:54.015Z] ====== als (apache-spark) [default], iteration 7 completed (53201.257 ms) ====== [2026-03-05T20:08:54.015Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-05T20:08:54.015Z] GC before operation: completed in 596.586 ms, heap usage 713.637 MB -> 65.142 MB. [2026-03-05T20:09:38.433Z] ====== als (apache-spark) [default], iteration 8 completed (48065.845 ms) ====== [2026-03-05T20:09:38.433Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-05T20:09:38.433Z] GC before operation: completed in 1411.493 ms, heap usage 980.520 MB -> 66.059 MB. [2026-03-05T20:10:31.003Z] ====== als (apache-spark) [default], iteration 9 completed (46055.257 ms) ====== [2026-03-05T20:10:31.004Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-05T20:10:31.004Z] GC before operation: completed in 1064.182 ms, heap usage 598.071 MB -> 65.864 MB. [2026-03-05T20:11:09.096Z] ====== als (apache-spark) [default], iteration 10 completed (44289.442 ms) ====== [2026-03-05T20:11:09.096Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-05T20:11:09.595Z] GC before operation: completed in 789.155 ms, heap usage 123.573 MB -> 65.708 MB. [2026-03-05T20:12:03.352Z] ====== als (apache-spark) [default], iteration 11 completed (46588.731 ms) ====== [2026-03-05T20:12:03.352Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-05T20:12:03.352Z] GC before operation: completed in 654.552 ms, heap usage 674.104 MB -> 66.461 MB. [2026-03-05T20:12:57.969Z] ====== als (apache-spark) [default], iteration 12 completed (52302.049 ms) ====== [2026-03-05T20:12:57.969Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-05T20:12:57.969Z] GC before operation: completed in 603.067 ms, heap usage 1.401 GB -> 67.649 MB. [2026-03-05T20:13:42.592Z] ====== als (apache-spark) [default], iteration 13 completed (46229.681 ms) ====== [2026-03-05T20:13:42.592Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-05T20:13:42.592Z] GC before operation: completed in 1511.188 ms, heap usage 680.450 MB -> 66.832 MB. [2026-03-05T20:14:19.490Z] ====== als (apache-spark) [default], iteration 14 completed (37282.316 ms) ====== [2026-03-05T20:14:19.490Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-05T20:14:19.921Z] GC before operation: completed in 540.264 ms, heap usage 237.990 MB -> 66.646 MB. [2026-03-05T20:15:13.145Z] ====== als (apache-spark) [default], iteration 15 completed (47245.454 ms) ====== [2026-03-05T20:15:13.145Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-05T20:15:13.145Z] GC before operation: completed in 313.044 ms, heap usage 216.162 MB -> 66.911 MB. [2026-03-05T20:16:05.952Z] ====== als (apache-spark) [default], iteration 16 completed (50393.258 ms) ====== [2026-03-05T20:16:05.952Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-05T20:16:05.952Z] GC before operation: completed in 746.283 ms, heap usage 432.632 MB -> 67.328 MB. [2026-03-05T20:16:49.830Z] ====== als (apache-spark) [default], iteration 17 completed (42631.045 ms) ====== [2026-03-05T20:16:49.830Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-05T20:16:49.830Z] GC before operation: completed in 680.156 ms, heap usage 730.889 MB -> 67.930 MB. [2026-03-05T20:17:34.035Z] ====== als (apache-spark) [default], iteration 18 completed (43193.529 ms) ====== [2026-03-05T20:17:34.035Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-05T20:17:34.035Z] GC before operation: completed in 1181.796 ms, heap usage 1.157 GB -> 68.657 MB. [2026-03-05T20:18:17.927Z] ====== als (apache-spark) [default], iteration 19 completed (45535.236 ms) ====== [2026-03-05T20:18:17.927Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-05T20:18:17.927Z] GC before operation: completed in 371.870 ms, heap usage 282.779 MB -> 68.052 MB. [2026-03-05T20:19:02.052Z] ====== als (apache-spark) [default], iteration 20 completed (40198.265 ms) ====== [2026-03-05T20:19:02.052Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-05T20:19:02.052Z] GC before operation: completed in 1714.768 ms, heap usage 677.689 MB -> 68.677 MB. [2026-03-05T20:19:54.892Z] ====== als (apache-spark) [default], iteration 21 completed (53294.118 ms) ====== [2026-03-05T20:19:54.892Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-05T20:19:54.892Z] GC before operation: completed in 424.975 ms, heap usage 235.964 MB -> 68.345 MB. [2026-03-05T20:20:48.322Z] ====== als (apache-spark) [default], iteration 22 completed (51239.869 ms) ====== [2026-03-05T20:20:48.322Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-05T20:20:48.322Z] GC before operation: completed in 798.115 ms, heap usage 561.438 MB -> 69.037 MB. [2026-03-05T20:21:32.240Z] ====== als (apache-spark) [default], iteration 23 completed (42454.886 ms) ====== [2026-03-05T20:21:32.240Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-05T20:21:32.240Z] GC before operation: completed in 472.245 ms, heap usage 419.717 MB -> 69.032 MB. [2026-03-05T20:22:24.058Z] ====== als (apache-spark) [default], iteration 24 completed (51175.982 ms) ====== [2026-03-05T20:22:24.058Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-05T20:22:24.484Z] GC before operation: completed in 397.450 ms, heap usage 207.167 MB -> 69.084 MB. [2026-03-05T20:23:16.746Z] ====== als (apache-spark) [default], iteration 25 completed (47121.941 ms) ====== [2026-03-05T20:23:16.747Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-05T20:23:16.747Z] GC before operation: completed in 682.717 ms, heap usage 313.697 MB -> 69.550 MB. [2026-03-05T20:23:53.691Z] ====== als (apache-spark) [default], iteration 26 completed (40306.090 ms) ====== [2026-03-05T20:23:53.691Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-05T20:23:53.691Z] GC before operation: completed in 461.970 ms, heap usage 716.665 MB -> 70.200 MB. [2026-03-05T20:24:38.587Z] ====== als (apache-spark) [default], iteration 27 completed (43390.548 ms) ====== [2026-03-05T20:24:38.587Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-05T20:24:38.587Z] GC before operation: completed in 540.813 ms, heap usage 590.078 MB -> 69.918 MB. [2026-03-05T20:25:30.798Z] ====== als (apache-spark) [default], iteration 28 completed (45154.710 ms) ====== [2026-03-05T20:25:30.798Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-05T20:25:30.798Z] GC before operation: completed in 441.841 ms, heap usage 637.611 MB -> 70.354 MB. [2026-03-05T20:26:23.521Z] ====== als (apache-spark) [default], iteration 29 completed (50647.259 ms) ====== [2026-03-05T20:26:23.521Z] ----------------------------------- [2026-03-05T20:26:23.521Z] renaissance-als_0_PASSED [2026-03-05T20:26:23.521Z] ----------------------------------- [2026-03-05T20:26:23.521Z] [2026-03-05T20:26:23.521Z] TEST TEARDOWN: [2026-03-05T20:26:23.521Z] Nothing to be done for teardown. [2026-03-05T20:26:23.521Z] renaissance-als_0 Finish Time: Thu Mar 5 15:26:19 2026 Epoch Time (ms): 1772742379169