[2025-08-25T20:59:04.503Z] Running test renaissance-als_0 ... [2025-08-25T20:59:04.503Z] =============================================== [2025-08-25T20:59:04.503Z] renaissance-als_0 Start Time: Mon Aug 25 16:59:04 2025 Epoch Time (ms): 1756155544306 [2025-08-25T20:59:04.503Z] variation: NoOptions [2025-08-25T20:59:04.503Z] JVM_OPTIONS: [2025-08-25T20:59:04.503Z] { \ [2025-08-25T20:59:04.503Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T20:59:04.503Z] echo "Nothing to be done for setup."; \ [2025-08-25T20:59:04.503Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17561555447215/renaissance-als_0"; \ [2025-08-25T20:59:04.503Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17561555447215/renaissance-als_0"; \ [2025-08-25T20:59:04.503Z] echo ""; echo "TESTING:"; \ [2025-08-25T20:59:04.503Z] "/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_17561555447215/renaissance-als_0"/als.json" als; \ [2025-08-25T20:59:04.503Z] 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_17561555447215/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T20:59:04.503Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T20:59:04.503Z] echo "Nothing to be done for teardown."; \ [2025-08-25T20:59:04.503Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17561555447215/TestTargetResult"; [2025-08-25T20:59:04.503Z] [2025-08-25T20:59:04.503Z] TEST SETUP: [2025-08-25T20:59:04.503Z] Nothing to be done for setup. [2025-08-25T20:59:04.503Z] [2025-08-25T20:59:04.503Z] TESTING: [2025-08-25T20:59:08.473Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-25T20:59:09.695Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T20:59:09.695Z] 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_17561555447215/renaissance-als_0/harness-165904-12853034983521738848/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T20:59:09.695Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T20:59:09.695Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T20:59:09.695Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T20:59:10.051Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-25T20:59:10.051Z] GC before operation: completed in 24.834 ms, heap usage 247.000 MB -> 34.554 MB. [2025-08-25T20:59:21.248Z] ====== als (apache-spark) [default], iteration 0 completed (9428.793 ms) ====== [2025-08-25T20:59:21.248Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-25T20:59:21.248Z] GC before operation: completed in 51.028 ms, heap usage 148.702 MB -> 60.346 MB. [2025-08-25T20:59:24.384Z] ====== als (apache-spark) [default], iteration 1 completed (4421.980 ms) ====== [2025-08-25T20:59:24.384Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-25T20:59:24.384Z] GC before operation: completed in 53.668 ms, heap usage 316.345 MB -> 61.293 MB. [2025-08-25T20:59:28.458Z] ====== als (apache-spark) [default], iteration 2 completed (3488.920 ms) ====== [2025-08-25T20:59:28.458Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-25T20:59:28.458Z] GC before operation: completed in 54.539 ms, heap usage 184.581 MB -> 61.273 MB. [2025-08-25T20:59:31.755Z] ====== als (apache-spark) [default], iteration 3 completed (3474.284 ms) ====== [2025-08-25T20:59:31.755Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-25T20:59:31.755Z] GC before operation: completed in 49.995 ms, heap usage 115.616 MB -> 61.827 MB. [2025-08-25T20:59:35.824Z] ====== als (apache-spark) [default], iteration 4 completed (3701.018 ms) ====== [2025-08-25T20:59:35.824Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-25T20:59:35.824Z] GC before operation: completed in 48.919 ms, heap usage 122.076 MB -> 62.821 MB. [2025-08-25T20:59:39.029Z] ====== als (apache-spark) [default], iteration 5 completed (3414.675 ms) ====== [2025-08-25T20:59:39.029Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-25T20:59:39.029Z] GC before operation: completed in 53.933 ms, heap usage 207.414 MB -> 63.549 MB. [2025-08-25T20:59:43.064Z] ====== als (apache-spark) [default], iteration 6 completed (3597.605 ms) ====== [2025-08-25T20:59:43.064Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-25T20:59:43.064Z] GC before operation: completed in 54.017 ms, heap usage 366.963 MB -> 64.156 MB. [2025-08-25T20:59:47.091Z] ====== als (apache-spark) [default], iteration 7 completed (3509.229 ms) ====== [2025-08-25T20:59:47.091Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-25T20:59:47.091Z] GC before operation: completed in 53.309 ms, heap usage 235.615 MB -> 64.533 MB. [2025-08-25T20:59:50.290Z] ====== als (apache-spark) [default], iteration 8 completed (3601.966 ms) ====== [2025-08-25T20:59:50.290Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-25T20:59:50.290Z] GC before operation: completed in 52.941 ms, heap usage 262.767 MB -> 65.048 MB. [2025-08-25T20:59:54.487Z] ====== als (apache-spark) [default], iteration 9 completed (3654.854 ms) ====== [2025-08-25T20:59:54.487Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-25T20:59:54.487Z] GC before operation: completed in 53.328 ms, heap usage 319.228 MB -> 65.446 MB. [2025-08-25T20:59:57.785Z] ====== als (apache-spark) [default], iteration 10 completed (3414.725 ms) ====== [2025-08-25T20:59:57.785Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-25T20:59:57.785Z] GC before operation: completed in 54.999 ms, heap usage 347.065 MB -> 65.710 MB. [2025-08-25T21:00:01.957Z] ====== als (apache-spark) [default], iteration 11 completed (3646.380 ms) ====== [2025-08-25T21:00:01.957Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-25T21:00:01.957Z] GC before operation: completed in 59.603 ms, heap usage 257.584 MB -> 65.848 MB. [2025-08-25T21:00:05.287Z] ====== als (apache-spark) [default], iteration 12 completed (3684.769 ms) ====== [2025-08-25T21:00:05.287Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-25T21:00:05.287Z] GC before operation: completed in 57.258 ms, heap usage 235.310 MB -> 66.105 MB. [2025-08-25T21:00:09.267Z] ====== als (apache-spark) [default], iteration 13 completed (3533.685 ms) ====== [2025-08-25T21:00:09.267Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-25T21:00:09.267Z] GC before operation: completed in 60.725 ms, heap usage 141.748 MB -> 66.060 MB. [2025-08-25T21:00:13.340Z] ====== als (apache-spark) [default], iteration 14 completed (3716.106 ms) ====== [2025-08-25T21:00:13.340Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-25T21:00:13.340Z] GC before operation: completed in 55.982 ms, heap usage 201.904 MB -> 66.451 MB. [2025-08-25T21:00:16.612Z] ====== als (apache-spark) [default], iteration 15 completed (3628.154 ms) ====== [2025-08-25T21:00:16.612Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-25T21:00:16.612Z] GC before operation: completed in 58.393 ms, heap usage 209.602 MB -> 66.850 MB. [2025-08-25T21:00:20.681Z] ====== als (apache-spark) [default], iteration 16 completed (3518.200 ms) ====== [2025-08-25T21:00:20.681Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-25T21:00:20.681Z] GC before operation: completed in 70.788 ms, heap usage 184.633 MB -> 66.958 MB. [2025-08-25T21:00:23.862Z] ====== als (apache-spark) [default], iteration 17 completed (3571.718 ms) ====== [2025-08-25T21:00:23.862Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-25T21:00:24.267Z] GC before operation: completed in 61.537 ms, heap usage 267.012 MB -> 67.327 MB. [2025-08-25T21:00:27.609Z] ====== als (apache-spark) [default], iteration 18 completed (3481.743 ms) ====== [2025-08-25T21:00:27.609Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-25T21:00:27.609Z] GC before operation: completed in 57.448 ms, heap usage 244.633 MB -> 67.579 MB. [2025-08-25T21:00:31.844Z] ====== als (apache-spark) [default], iteration 19 completed (3676.095 ms) ====== [2025-08-25T21:00:31.844Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-25T21:00:31.844Z] GC before operation: completed in 58.066 ms, heap usage 239.875 MB -> 67.818 MB. [2025-08-25T21:00:35.272Z] ====== als (apache-spark) [default], iteration 20 completed (3500.439 ms) ====== [2025-08-25T21:00:35.272Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-25T21:00:35.272Z] GC before operation: completed in 63.910 ms, heap usage 221.081 MB -> 67.948 MB. [2025-08-25T21:00:38.645Z] ====== als (apache-spark) [default], iteration 21 completed (3442.790 ms) ====== [2025-08-25T21:00:38.645Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-25T21:00:39.017Z] GC before operation: completed in 62.282 ms, heap usage 248.114 MB -> 68.381 MB. [2025-08-25T21:00:43.198Z] ====== als (apache-spark) [default], iteration 22 completed (3894.953 ms) ====== [2025-08-25T21:00:43.198Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-25T21:00:43.198Z] GC before operation: completed in 59.745 ms, heap usage 278.644 MB -> 68.593 MB. [2025-08-25T21:00:48.258Z] ====== als (apache-spark) [default], iteration 23 completed (4363.753 ms) ====== [2025-08-25T21:00:48.258Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-25T21:00:48.258Z] GC before operation: completed in 87.941 ms, heap usage 278.214 MB -> 68.851 MB. [2025-08-25T21:00:53.434Z] ====== als (apache-spark) [default], iteration 24 completed (5376.160 ms) ====== [2025-08-25T21:00:53.434Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-25T21:00:53.434Z] GC before operation: completed in 65.760 ms, heap usage 449.563 MB -> 69.415 MB. [2025-08-25T21:00:57.678Z] ====== als (apache-spark) [default], iteration 25 completed (3959.572 ms) ====== [2025-08-25T21:00:57.678Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-25T21:00:57.678Z] GC before operation: completed in 63.738 ms, heap usage 459.089 MB -> 69.447 MB. [2025-08-25T21:01:01.841Z] ====== als (apache-spark) [default], iteration 26 completed (4043.532 ms) ====== [2025-08-25T21:01:01.841Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-25T21:01:01.841Z] GC before operation: completed in 72.931 ms, heap usage 403.836 MB -> 69.678 MB. [2025-08-25T21:01:05.030Z] ====== als (apache-spark) [default], iteration 27 completed (3599.628 ms) ====== [2025-08-25T21:01:05.030Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-25T21:01:05.392Z] GC before operation: completed in 59.665 ms, heap usage 192.572 MB -> 69.709 MB. [2025-08-25T21:01:09.407Z] ====== als (apache-spark) [default], iteration 28 completed (3379.296 ms) ====== [2025-08-25T21:01:09.407Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-25T21:01:09.407Z] GC before operation: completed in 78.948 ms, heap usage 247.778 MB -> 69.674 MB. [2025-08-25T21:01:12.796Z] ====== als (apache-spark) [default], iteration 29 completed (3586.378 ms) ====== [2025-08-25T21:01:12.796Z] ----------------------------------- [2025-08-25T21:01:12.796Z] renaissance-als_0_PASSED [2025-08-25T21:01:12.796Z] ----------------------------------- [2025-08-25T21:01:12.796Z] [2025-08-25T21:01:12.796Z] TEST TEARDOWN: [2025-08-25T21:01:12.796Z] Nothing to be done for teardown. [2025-08-25T21:01:12.796Z] renaissance-als_0 Finish Time: Mon Aug 25 17:01:12 2025 Epoch Time (ms): 1756155672464