[2025-03-30T21:28:29.614Z] Running test renaissance-als_0 ... [2025-03-30T21:28:29.614Z] =============================================== [2025-03-30T21:28:29.614Z] renaissance-als_0 Start Time: Sun Mar 30 17:28:29 2025 Epoch Time (ms): 1743370109354 [2025-03-30T21:28:29.614Z] variation: NoOptions [2025-03-30T21:28:29.614Z] JVM_OPTIONS: [2025-03-30T21:28:29.614Z] { \ [2025-03-30T21:28:29.614Z] echo ""; echo "TEST SETUP:"; \ [2025-03-30T21:28:29.614Z] echo "Nothing to be done for setup."; \ [2025-03-30T21:28:29.614Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17433701098864/renaissance-als_0"; \ [2025-03-30T21:28:29.614Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17433701098864/renaissance-als_0"; \ [2025-03-30T21:28:29.614Z] echo ""; echo "TESTING:"; \ [2025-03-30T21:28:29.614Z] "/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_17433701098864/renaissance-als_0"/als.json" als; \ [2025-03-30T21:28:29.614Z] 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_17433701098864/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-30T21:28:29.614Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-30T21:28:29.614Z] echo "Nothing to be done for teardown."; \ [2025-03-30T21:28:29.614Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17433701098864/TestTargetResult"; [2025-03-30T21:28:29.614Z] [2025-03-30T21:28:29.614Z] TEST SETUP: [2025-03-30T21:28:29.614Z] Nothing to be done for setup. [2025-03-30T21:28:29.614Z] [2025-03-30T21:28:29.614Z] TESTING: [2025-03-30T21:28:33.636Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-03-30T21:28:34.891Z] WARNING: An illegal reflective access operation has occurred [2025-03-30T21:28:34.891Z] 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_17433701098864/renaissance-als_0/harness-172829-7423858168908278386/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-03-30T21:28:34.891Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-30T21:28:34.891Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-30T21:28:34.891Z] WARNING: All illegal access operations will be denied in a future release [2025-03-30T21:28:35.252Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-03-30T21:28:35.252Z] GC before operation: completed in 22.376 ms, heap usage 257.502 MB -> 34.563 MB. [2025-03-30T21:28:44.585Z] ====== als (apache-spark) [default], iteration 0 completed (8917.351 ms) ====== [2025-03-30T21:28:44.585Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-03-30T21:28:44.585Z] GC before operation: completed in 49.141 ms, heap usage 291.385 MB -> 60.417 MB. [2025-03-30T21:28:49.742Z] ====== als (apache-spark) [default], iteration 1 completed (4162.147 ms) ====== [2025-03-30T21:28:49.742Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-03-30T21:28:49.742Z] GC before operation: completed in 46.106 ms, heap usage 276.113 MB -> 61.254 MB. [2025-03-30T21:28:52.965Z] ====== als (apache-spark) [default], iteration 2 completed (3635.453 ms) ====== [2025-03-30T21:28:52.965Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-03-30T21:28:52.965Z] GC before operation: completed in 56.681 ms, heap usage 217.885 MB -> 61.854 MB. [2025-03-30T21:28:56.998Z] ====== als (apache-spark) [default], iteration 3 completed (3580.423 ms) ====== [2025-03-30T21:28:56.998Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-03-30T21:28:56.998Z] GC before operation: completed in 46.767 ms, heap usage 273.882 MB -> 62.405 MB. [2025-03-30T21:29:00.315Z] ====== als (apache-spark) [default], iteration 4 completed (3607.210 ms) ====== [2025-03-30T21:29:00.315Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-03-30T21:29:00.315Z] GC before operation: completed in 48.493 ms, heap usage 232.921 MB -> 62.940 MB. [2025-03-30T21:29:04.570Z] ====== als (apache-spark) [default], iteration 5 completed (3634.280 ms) ====== [2025-03-30T21:29:04.570Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-03-30T21:29:04.570Z] GC before operation: completed in 49.664 ms, heap usage 131.175 MB -> 63.341 MB. [2025-03-30T21:29:07.819Z] ====== als (apache-spark) [default], iteration 6 completed (3339.512 ms) ====== [2025-03-30T21:29:07.819Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-03-30T21:29:07.819Z] GC before operation: completed in 62.336 ms, heap usage 387.782 MB -> 64.221 MB. [2025-03-30T21:29:11.111Z] ====== als (apache-spark) [default], iteration 7 completed (3451.110 ms) ====== [2025-03-30T21:29:11.111Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-03-30T21:29:11.111Z] GC before operation: completed in 53.630 ms, heap usage 272.241 MB -> 64.541 MB. [2025-03-30T21:29:15.175Z] ====== als (apache-spark) [default], iteration 8 completed (3795.897 ms) ====== [2025-03-30T21:29:15.175Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-03-30T21:29:15.175Z] GC before operation: completed in 51.839 ms, heap usage 96.707 MB -> 64.937 MB. [2025-03-30T21:29:19.288Z] ====== als (apache-spark) [default], iteration 9 completed (3468.962 ms) ====== [2025-03-30T21:29:19.288Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-03-30T21:29:19.288Z] GC before operation: completed in 52.858 ms, heap usage 503.960 MB -> 65.675 MB. [2025-03-30T21:29:22.515Z] ====== als (apache-spark) [default], iteration 10 completed (3513.077 ms) ====== [2025-03-30T21:29:22.515Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-03-30T21:29:22.515Z] GC before operation: completed in 52.680 ms, heap usage 252.546 MB -> 65.611 MB. [2025-03-30T21:29:26.666Z] ====== als (apache-spark) [default], iteration 11 completed (3730.171 ms) ====== [2025-03-30T21:29:26.666Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-03-30T21:29:26.666Z] GC before operation: completed in 55.648 ms, heap usage 276.591 MB -> 65.846 MB. [2025-03-30T21:29:29.913Z] ====== als (apache-spark) [default], iteration 12 completed (3586.807 ms) ====== [2025-03-30T21:29:29.913Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-03-30T21:29:29.913Z] GC before operation: completed in 56.076 ms, heap usage 377.730 MB -> 66.210 MB. [2025-03-30T21:29:34.049Z] ====== als (apache-spark) [default], iteration 13 completed (3460.038 ms) ====== [2025-03-30T21:29:34.049Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-03-30T21:29:34.049Z] GC before operation: completed in 56.017 ms, heap usage 170.437 MB -> 66.231 MB. [2025-03-30T21:29:37.318Z] ====== als (apache-spark) [default], iteration 14 completed (3695.763 ms) ====== [2025-03-30T21:29:37.318Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-03-30T21:29:37.318Z] GC before operation: completed in 58.952 ms, heap usage 185.675 MB -> 66.579 MB. [2025-03-30T21:29:41.423Z] ====== als (apache-spark) [default], iteration 15 completed (3727.779 ms) ====== [2025-03-30T21:29:41.423Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-03-30T21:29:41.423Z] GC before operation: completed in 60.842 ms, heap usage 403.966 MB -> 66.954 MB. [2025-03-30T21:29:45.627Z] ====== als (apache-spark) [default], iteration 16 completed (3719.258 ms) ====== [2025-03-30T21:29:45.627Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-03-30T21:29:45.627Z] GC before operation: completed in 54.987 ms, heap usage 99.310 MB -> 66.960 MB. [2025-03-30T21:29:48.844Z] ====== als (apache-spark) [default], iteration 17 completed (3454.979 ms) ====== [2025-03-30T21:29:48.844Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-03-30T21:29:48.844Z] GC before operation: completed in 58.202 ms, heap usage 311.102 MB -> 67.326 MB. [2025-03-30T21:29:52.903Z] ====== als (apache-spark) [default], iteration 18 completed (3658.490 ms) ====== [2025-03-30T21:29:52.903Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-03-30T21:29:52.903Z] GC before operation: completed in 55.667 ms, heap usage 260.170 MB -> 67.581 MB. [2025-03-30T21:29:57.051Z] ====== als (apache-spark) [default], iteration 19 completed (3790.565 ms) ====== [2025-03-30T21:29:57.051Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-03-30T21:29:57.051Z] GC before operation: completed in 58.649 ms, heap usage 130.102 MB -> 67.696 MB. [2025-03-30T21:30:00.335Z] ====== als (apache-spark) [default], iteration 20 completed (3652.526 ms) ====== [2025-03-30T21:30:00.335Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-03-30T21:30:00.335Z] GC before operation: completed in 58.383 ms, heap usage 264.448 MB -> 68.063 MB. [2025-03-30T21:30:04.498Z] ====== als (apache-spark) [default], iteration 21 completed (3730.259 ms) ====== [2025-03-30T21:30:04.498Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-03-30T21:30:04.498Z] GC before operation: completed in 67.447 ms, heap usage 115.867 MB -> 68.177 MB. [2025-03-30T21:30:08.645Z] ====== als (apache-spark) [default], iteration 22 completed (3704.526 ms) ====== [2025-03-30T21:30:08.645Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-03-30T21:30:08.645Z] GC before operation: completed in 60.246 ms, heap usage 147.205 MB -> 68.461 MB. [2025-03-30T21:30:11.848Z] ====== als (apache-spark) [default], iteration 23 completed (3659.759 ms) ====== [2025-03-30T21:30:11.848Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-03-30T21:30:11.848Z] GC before operation: completed in 60.482 ms, heap usage 155.795 MB -> 68.752 MB. [2025-03-30T21:30:15.893Z] ====== als (apache-spark) [default], iteration 24 completed (3618.154 ms) ====== [2025-03-30T21:30:15.893Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-03-30T21:30:15.893Z] GC before operation: completed in 63.229 ms, heap usage 260.024 MB -> 69.064 MB. [2025-03-30T21:30:19.978Z] ====== als (apache-spark) [default], iteration 25 completed (3941.056 ms) ====== [2025-03-30T21:30:19.978Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-03-30T21:30:19.978Z] GC before operation: completed in 62.667 ms, heap usage 125.995 MB -> 68.909 MB. [2025-03-30T21:30:24.037Z] ====== als (apache-spark) [default], iteration 26 completed (3781.866 ms) ====== [2025-03-30T21:30:24.038Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-03-30T21:30:24.038Z] GC before operation: completed in 59.144 ms, heap usage 107.943 MB -> 69.439 MB. [2025-03-30T21:30:28.108Z] ====== als (apache-spark) [default], iteration 27 completed (3592.107 ms) ====== [2025-03-30T21:30:28.108Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-03-30T21:30:28.108Z] GC before operation: completed in 66.644 ms, heap usage 166.467 MB -> 69.691 MB. [2025-03-30T21:30:31.400Z] ====== als (apache-spark) [default], iteration 28 completed (3515.486 ms) ====== [2025-03-30T21:30:31.400Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-03-30T21:30:31.400Z] GC before operation: completed in 59.036 ms, heap usage 219.649 MB -> 69.595 MB. [2025-03-30T21:30:34.634Z] ====== als (apache-spark) [default], iteration 29 completed (3386.147 ms) ====== [2025-03-30T21:30:35.003Z] ----------------------------------- [2025-03-30T21:30:35.003Z] renaissance-als_0_PASSED [2025-03-30T21:30:35.003Z] ----------------------------------- [2025-03-30T21:30:35.003Z] [2025-03-30T21:30:35.003Z] TEST TEARDOWN: [2025-03-30T21:30:35.003Z] Nothing to be done for teardown. [2025-03-30T21:30:35.003Z] renaissance-als_0 Finish Time: Sun Mar 30 17:30:34 2025 Epoch Time (ms): 1743370234578