[2025-11-05T23:17:39.959Z] Running test renaissance-als_0 ... [2025-11-05T23:17:39.959Z] =============================================== [2025-11-05T23:17:39.959Z] renaissance-als_0 Start Time: Wed Nov 5 18:17:39 2025 Epoch Time (ms): 1762384659720 [2025-11-05T23:17:39.959Z] variation: NoOptions [2025-11-05T23:17:39.959Z] JVM_OPTIONS: [2025-11-05T23:17:39.959Z] { \ [2025-11-05T23:17:39.959Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T23:17:39.959Z] echo "Nothing to be done for setup."; \ [2025-11-05T23:17:39.959Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17623846595439/renaissance-als_0"; \ [2025-11-05T23:17:39.959Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17623846595439/renaissance-als_0"; \ [2025-11-05T23:17:39.959Z] echo ""; echo "TESTING:"; \ [2025-11-05T23:17:39.959Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/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_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17623846595439/renaissance-als_0"/als.json" als; \ [2025-11-05T23:17:39.959Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17623846595439/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T23:17:39.959Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T23:17:39.959Z] echo "Nothing to be done for teardown."; \ [2025-11-05T23:17:39.959Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17623846595439/TestTargetResult"; [2025-11-05T23:17:39.959Z] [2025-11-05T23:17:39.959Z] TEST SETUP: [2025-11-05T23:17:39.959Z] Nothing to be done for setup. [2025-11-05T23:17:39.959Z] [2025-11-05T23:17:39.959Z] TESTING: [2025-11-05T23:17:43.910Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-05T23:17:44.673Z] WARNING: An illegal reflective access operation has occurred [2025-11-05T23:17:44.673Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/output_17623846595439/renaissance-als_0/harness-181740-3835728766565806322/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-05T23:17:44.673Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-05T23:17:44.673Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-05T23:17:44.673Z] WARNING: All illegal access operations will be denied in a future release [2025-11-05T23:17:45.439Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-05T23:17:45.439Z] GC before operation: completed in 23.239 ms, heap usage 389.502 MB -> 34.736 MB. [2025-11-05T23:17:54.759Z] ====== als (apache-spark) [default], iteration 0 completed (7790.629 ms) ====== [2025-11-05T23:17:54.759Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-05T23:17:54.759Z] GC before operation: completed in 49.486 ms, heap usage 172.201 MB -> 60.740 MB. [2025-11-05T23:17:57.253Z] ====== als (apache-spark) [default], iteration 1 completed (3374.927 ms) ====== [2025-11-05T23:17:57.253Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-05T23:17:57.253Z] GC before operation: completed in 47.923 ms, heap usage 88.268 MB -> 61.369 MB. [2025-11-05T23:18:00.036Z] ====== als (apache-spark) [default], iteration 2 completed (2802.922 ms) ====== [2025-11-05T23:18:00.036Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-05T23:18:00.036Z] GC before operation: completed in 44.462 ms, heap usage 229.878 MB -> 62.055 MB. [2025-11-05T23:18:03.323Z] ====== als (apache-spark) [default], iteration 3 completed (2769.242 ms) ====== [2025-11-05T23:18:03.323Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-05T23:18:03.323Z] GC before operation: completed in 48.922 ms, heap usage 246.041 MB -> 62.608 MB. [2025-11-05T23:18:05.960Z] ====== als (apache-spark) [default], iteration 4 completed (2733.473 ms) ====== [2025-11-05T23:18:05.960Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-05T23:18:05.960Z] GC before operation: completed in 58.954 ms, heap usage 407.097 MB -> 63.217 MB. [2025-11-05T23:18:08.594Z] ====== als (apache-spark) [default], iteration 5 completed (2730.255 ms) ====== [2025-11-05T23:18:08.594Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-05T23:18:08.594Z] GC before operation: completed in 47.843 ms, heap usage 162.447 MB -> 63.530 MB. [2025-11-05T23:18:12.007Z] ====== als (apache-spark) [default], iteration 6 completed (2740.097 ms) ====== [2025-11-05T23:18:12.007Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-05T23:18:12.007Z] GC before operation: completed in 48.027 ms, heap usage 237.439 MB -> 64.186 MB. [2025-11-05T23:18:14.551Z] ====== als (apache-spark) [default], iteration 7 completed (2828.926 ms) ====== [2025-11-05T23:18:14.551Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-05T23:18:14.551Z] GC before operation: completed in 50.284 ms, heap usage 160.485 MB -> 64.626 MB. [2025-11-05T23:18:17.776Z] ====== als (apache-spark) [default], iteration 8 completed (2778.154 ms) ====== [2025-11-05T23:18:17.776Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-05T23:18:17.776Z] GC before operation: completed in 51.675 ms, heap usage 596.723 MB -> 65.689 MB. [2025-11-05T23:18:20.339Z] ====== als (apache-spark) [default], iteration 9 completed (2779.513 ms) ====== [2025-11-05T23:18:20.339Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-05T23:18:20.339Z] GC before operation: completed in 49.554 ms, heap usage 232.689 MB -> 65.542 MB. [2025-11-05T23:18:22.882Z] ====== als (apache-spark) [default], iteration 10 completed (2561.939 ms) ====== [2025-11-05T23:18:22.882Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-05T23:18:22.882Z] GC before operation: completed in 49.623 ms, heap usage 281.283 MB -> 65.923 MB. [2025-11-05T23:18:26.196Z] ====== als (apache-spark) [default], iteration 11 completed (2671.283 ms) ====== [2025-11-05T23:18:26.196Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-05T23:18:26.196Z] GC before operation: completed in 50.031 ms, heap usage 371.945 MB -> 66.152 MB. [2025-11-05T23:18:28.744Z] ====== als (apache-spark) [default], iteration 12 completed (2681.257 ms) ====== [2025-11-05T23:18:28.744Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-05T23:18:28.744Z] GC before operation: completed in 53.647 ms, heap usage 152.410 MB -> 66.180 MB. [2025-11-05T23:18:31.921Z] ====== als (apache-spark) [default], iteration 13 completed (2735.852 ms) ====== [2025-11-05T23:18:31.921Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-05T23:18:31.921Z] GC before operation: completed in 50.571 ms, heap usage 376.518 MB -> 66.735 MB. [2025-11-05T23:18:34.384Z] ====== als (apache-spark) [default], iteration 14 completed (2649.333 ms) ====== [2025-11-05T23:18:34.384Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-05T23:18:34.384Z] GC before operation: completed in 55.195 ms, heap usage 220.735 MB -> 66.813 MB. [2025-11-05T23:18:36.849Z] ====== als (apache-spark) [default], iteration 15 completed (2659.583 ms) ====== [2025-11-05T23:18:36.849Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-05T23:18:37.215Z] GC before operation: completed in 54.149 ms, heap usage 166.995 MB -> 66.936 MB. [2025-11-05T23:18:40.365Z] ====== als (apache-spark) [default], iteration 16 completed (2665.991 ms) ====== [2025-11-05T23:18:40.365Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-05T23:18:40.365Z] GC before operation: completed in 55.295 ms, heap usage 130.477 MB -> 67.187 MB. [2025-11-05T23:18:42.909Z] ====== als (apache-spark) [default], iteration 17 completed (2900.659 ms) ====== [2025-11-05T23:18:42.909Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-05T23:18:42.909Z] GC before operation: completed in 52.564 ms, heap usage 130.753 MB -> 67.468 MB. [2025-11-05T23:18:46.048Z] ====== als (apache-spark) [default], iteration 18 completed (2807.564 ms) ====== [2025-11-05T23:18:46.048Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-05T23:18:46.048Z] GC before operation: completed in 66.748 ms, heap usage 215.966 MB -> 67.698 MB. [2025-11-05T23:18:49.231Z] ====== als (apache-spark) [default], iteration 19 completed (2737.391 ms) ====== [2025-11-05T23:18:49.231Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-05T23:18:49.231Z] GC before operation: completed in 52.118 ms, heap usage 131.464 MB -> 67.921 MB. [2025-11-05T23:18:51.703Z] ====== als (apache-spark) [default], iteration 20 completed (2745.055 ms) ====== [2025-11-05T23:18:51.703Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-05T23:18:51.703Z] GC before operation: completed in 51.553 ms, heap usage 287.347 MB -> 68.295 MB. [2025-11-05T23:18:54.878Z] ====== als (apache-spark) [default], iteration 21 completed (2731.416 ms) ====== [2025-11-05T23:18:54.878Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-05T23:18:54.878Z] GC before operation: completed in 54.401 ms, heap usage 128.898 MB -> 68.458 MB. [2025-11-05T23:18:57.283Z] ====== als (apache-spark) [default], iteration 22 completed (2848.281 ms) ====== [2025-11-05T23:18:57.283Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-05T23:18:57.639Z] GC before operation: completed in 51.186 ms, heap usage 373.669 MB -> 68.943 MB. [2025-11-05T23:19:00.785Z] ====== als (apache-spark) [default], iteration 23 completed (2678.158 ms) ====== [2025-11-05T23:19:00.785Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-05T23:19:00.785Z] GC before operation: completed in 52.694 ms, heap usage 238.213 MB -> 69.097 MB. [2025-11-05T23:19:03.252Z] ====== als (apache-spark) [default], iteration 24 completed (2718.064 ms) ====== [2025-11-05T23:19:03.252Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-05T23:19:03.252Z] GC before operation: completed in 51.888 ms, heap usage 253.228 MB -> 69.349 MB. [2025-11-05T23:19:05.706Z] ====== als (apache-spark) [default], iteration 25 completed (2628.919 ms) ====== [2025-11-05T23:19:05.706Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-05T23:19:06.083Z] GC before operation: completed in 52.013 ms, heap usage 176.005 MB -> 69.458 MB. [2025-11-05T23:19:08.540Z] ====== als (apache-spark) [default], iteration 26 completed (2644.282 ms) ====== [2025-11-05T23:19:08.540Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-05T23:19:08.540Z] GC before operation: completed in 54.140 ms, heap usage 374.817 MB -> 69.934 MB. [2025-11-05T23:19:11.736Z] ====== als (apache-spark) [default], iteration 27 completed (2580.226 ms) ====== [2025-11-05T23:19:11.737Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-05T23:19:11.737Z] GC before operation: completed in 52.405 ms, heap usage 434.425 MB -> 70.192 MB. [2025-11-05T23:19:14.196Z] ====== als (apache-spark) [default], iteration 28 completed (2547.675 ms) ====== [2025-11-05T23:19:14.196Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-05T23:19:14.196Z] GC before operation: completed in 52.018 ms, heap usage 117.575 MB -> 70.222 MB. [2025-11-05T23:19:16.665Z] ====== als (apache-spark) [default], iteration 29 completed (2687.092 ms) ====== [2025-11-05T23:19:17.017Z] ----------------------------------- [2025-11-05T23:19:17.017Z] renaissance-als_0_PASSED [2025-11-05T23:19:17.017Z] ----------------------------------- [2025-11-05T23:19:17.017Z] [2025-11-05T23:19:17.017Z] TEST TEARDOWN: [2025-11-05T23:19:17.017Z] Nothing to be done for teardown. [2025-11-05T23:19:17.017Z] renaissance-als_0 Finish Time: Wed Nov 5 18:19:16 2025 Epoch Time (ms): 1762384756687