[2025-05-28T23:46:32.975Z] Running test renaissance-als_0 ... [2025-05-28T23:46:32.975Z] =============================================== [2025-05-28T23:46:32.975Z] renaissance-als_0 Start Time: Wed May 28 23:46:32 2025 Epoch Time (ms): 1748475992701 [2025-05-28T23:46:32.975Z] variation: NoOptions [2025-05-28T23:46:32.975Z] JVM_OPTIONS: [2025-05-28T23:46:32.975Z] { \ [2025-05-28T23:46:32.975Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T23:46:32.975Z] echo "Nothing to be done for setup."; \ [2025-05-28T23:46:32.975Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-als_0"; \ [2025-05-28T23:46:32.975Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-als_0"; \ [2025-05-28T23:46:32.975Z] echo ""; echo "TESTING:"; \ [2025-05-28T23:46:32.975Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/jdkbinary/j2sdk-image/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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-als_0"/als.json" als; \ [2025-05-28T23:46:32.975Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T23:46:32.975Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T23:46:32.975Z] echo "Nothing to be done for teardown."; \ [2025-05-28T23:46:32.975Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/TestTargetResult"; [2025-05-28T23:46:32.975Z] [2025-05-28T23:46:32.975Z] TEST SETUP: [2025-05-28T23:46:32.975Z] Nothing to be done for setup. [2025-05-28T23:46:32.975Z] [2025-05-28T23:46:32.975Z] TESTING: [2025-05-28T23:46:41.780Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-05-28T23:46:45.886Z] WARNING: An illegal reflective access operation has occurred [2025-05-28T23:46:45.886Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17484759497998/renaissance-als_0/harness-234633-17781779597080886972/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-28T23:46:45.886Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-28T23:46:45.886Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-28T23:46:45.886Z] WARNING: All illegal access operations will be denied in a future release [2025-05-28T23:46:48.902Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-28T23:46:48.902Z] GC before operation: completed in 69.341 ms, heap usage 234.521 MB -> 35.093 MB. [2025-05-28T23:47:07.166Z] ====== als (apache-spark) [default], iteration 0 completed (17466.481 ms) ====== [2025-05-28T23:47:07.166Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-28T23:47:07.166Z] GC before operation: completed in 153.506 ms, heap usage 329.372 MB -> 61.352 MB. [2025-05-28T23:47:13.825Z] ====== als (apache-spark) [default], iteration 1 completed (6368.983 ms) ====== [2025-05-28T23:47:13.825Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-28T23:47:13.825Z] GC before operation: completed in 170.329 ms, heap usage 452.364 MB -> 62.170 MB. [2025-05-28T23:47:19.141Z] ====== als (apache-spark) [default], iteration 2 completed (5370.149 ms) ====== [2025-05-28T23:47:19.141Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-28T23:47:20.083Z] GC before operation: completed in 171.291 ms, heap usage 292.732 MB -> 62.610 MB. [2025-05-28T23:47:25.598Z] ====== als (apache-spark) [default], iteration 3 completed (5985.789 ms) ====== [2025-05-28T23:47:25.598Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-28T23:47:26.616Z] GC before operation: completed in 203.156 ms, heap usage 379.918 MB -> 63.269 MB. [2025-05-28T23:47:32.042Z] ====== als (apache-spark) [default], iteration 4 completed (5259.222 ms) ====== [2025-05-28T23:47:32.042Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-28T23:47:32.042Z] GC before operation: completed in 212.001 ms, heap usage 210.815 MB -> 63.570 MB. [2025-05-28T23:47:37.361Z] ====== als (apache-spark) [default], iteration 5 completed (5273.552 ms) ====== [2025-05-28T23:47:37.361Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-28T23:47:37.361Z] GC before operation: completed in 207.710 ms, heap usage 467.066 MB -> 64.563 MB. [2025-05-28T23:47:42.837Z] ====== als (apache-spark) [default], iteration 6 completed (5233.500 ms) ====== [2025-05-28T23:47:42.837Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-28T23:47:42.837Z] GC before operation: completed in 199.118 ms, heap usage 432.697 MB -> 64.859 MB. [2025-05-28T23:47:48.214Z] ====== als (apache-spark) [default], iteration 7 completed (4997.306 ms) ====== [2025-05-28T23:47:48.214Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-28T23:47:48.214Z] GC before operation: completed in 215.087 ms, heap usage 674.083 MB -> 65.812 MB. [2025-05-28T23:47:54.676Z] ====== als (apache-spark) [default], iteration 8 completed (4948.074 ms) ====== [2025-05-28T23:47:54.676Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-28T23:47:54.676Z] GC before operation: completed in 193.146 ms, heap usage 185.937 MB -> 65.663 MB. [2025-05-28T23:47:58.783Z] ====== als (apache-spark) [default], iteration 9 completed (4837.863 ms) ====== [2025-05-28T23:47:58.783Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-28T23:47:58.783Z] GC before operation: completed in 193.765 ms, heap usage 279.664 MB -> 66.440 MB. [2025-05-28T23:48:04.295Z] ====== als (apache-spark) [default], iteration 10 completed (4484.869 ms) ====== [2025-05-28T23:48:04.295Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-28T23:48:04.295Z] GC before operation: completed in 175.526 ms, heap usage 205.756 MB -> 66.228 MB. [2025-05-28T23:48:09.729Z] ====== als (apache-spark) [default], iteration 11 completed (5133.731 ms) ====== [2025-05-28T23:48:09.729Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-28T23:48:09.729Z] GC before operation: completed in 169.261 ms, heap usage 480.179 MB -> 66.923 MB. [2025-05-28T23:48:13.962Z] ====== als (apache-spark) [default], iteration 12 completed (4633.230 ms) ====== [2025-05-28T23:48:13.962Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-28T23:48:14.979Z] GC before operation: completed in 233.859 ms, heap usage 599.847 MB -> 67.283 MB. [2025-05-28T23:48:19.093Z] ====== als (apache-spark) [default], iteration 13 completed (4587.218 ms) ====== [2025-05-28T23:48:19.093Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-28T23:48:20.038Z] GC before operation: completed in 275.084 ms, heap usage 715.562 MB -> 67.621 MB. [2025-05-28T23:48:24.219Z] ====== als (apache-spark) [default], iteration 14 completed (4076.619 ms) ====== [2025-05-28T23:48:24.219Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-28T23:48:24.219Z] GC before operation: completed in 172.272 ms, heap usage 152.878 MB -> 67.267 MB. [2025-05-28T23:48:28.332Z] ====== als (apache-spark) [default], iteration 15 completed (4358.824 ms) ====== [2025-05-28T23:48:28.332Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-28T23:48:28.332Z] GC before operation: completed in 187.084 ms, heap usage 143.981 MB -> 67.529 MB. [2025-05-28T23:48:34.484Z] ====== als (apache-spark) [default], iteration 16 completed (4297.499 ms) ====== [2025-05-28T23:48:34.484Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-28T23:48:34.484Z] GC before operation: completed in 191.308 ms, heap usage 140.432 MB -> 67.752 MB. [2025-05-28T23:48:37.631Z] ====== als (apache-spark) [default], iteration 17 completed (4249.324 ms) ====== [2025-05-28T23:48:37.631Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-28T23:48:37.631Z] GC before operation: completed in 172.853 ms, heap usage 404.602 MB -> 68.243 MB. [2025-05-28T23:48:42.963Z] ====== als (apache-spark) [default], iteration 18 completed (4124.950 ms) ====== [2025-05-28T23:48:42.963Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-28T23:48:42.963Z] GC before operation: completed in 221.180 ms, heap usage 1.229 GB -> 69.506 MB. [2025-05-28T23:48:47.083Z] ====== als (apache-spark) [default], iteration 19 completed (4087.333 ms) ====== [2025-05-28T23:48:47.083Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-28T23:48:47.083Z] GC before operation: completed in 211.487 ms, heap usage 267.247 MB -> 68.685 MB. [2025-05-28T23:48:51.209Z] ====== als (apache-spark) [default], iteration 20 completed (4139.191 ms) ====== [2025-05-28T23:48:51.209Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-28T23:48:51.209Z] GC before operation: completed in 182.948 ms, heap usage 579.058 MB -> 69.320 MB. [2025-05-28T23:48:55.406Z] ====== als (apache-spark) [default], iteration 21 completed (4127.085 ms) ====== [2025-05-28T23:48:55.406Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-28T23:48:56.419Z] GC before operation: completed in 313.171 ms, heap usage 508.428 MB -> 69.477 MB. [2025-05-28T23:49:00.538Z] ====== als (apache-spark) [default], iteration 22 completed (4244.613 ms) ====== [2025-05-28T23:49:00.538Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-28T23:49:00.538Z] GC before operation: completed in 202.965 ms, heap usage 880.662 MB -> 70.106 MB. [2025-05-28T23:49:04.732Z] ====== als (apache-spark) [default], iteration 23 completed (4061.582 ms) ====== [2025-05-28T23:49:04.733Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-28T23:49:04.733Z] GC before operation: completed in 216.443 ms, heap usage 1.052 GB -> 70.632 MB. [2025-05-28T23:49:10.113Z] ====== als (apache-spark) [default], iteration 24 completed (4273.220 ms) ====== [2025-05-28T23:49:10.113Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-28T23:49:10.113Z] GC before operation: completed in 212.023 ms, heap usage 691.539 MB -> 70.422 MB. [2025-05-28T23:49:14.266Z] ====== als (apache-spark) [default], iteration 25 completed (4023.590 ms) ====== [2025-05-28T23:49:14.266Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-28T23:49:14.266Z] GC before operation: completed in 200.611 ms, heap usage 605.221 MB -> 70.583 MB. [2025-05-28T23:49:18.398Z] ====== als (apache-spark) [default], iteration 26 completed (3930.308 ms) ====== [2025-05-28T23:49:18.398Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-28T23:49:18.398Z] GC before operation: completed in 192.688 ms, heap usage 669.619 MB -> 70.917 MB. [2025-05-28T23:49:22.535Z] ====== als (apache-spark) [default], iteration 27 completed (4108.103 ms) ====== [2025-05-28T23:49:22.535Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-28T23:49:22.535Z] GC before operation: completed in 204.995 ms, heap usage 560.982 MB -> 71.077 MB. [2025-05-28T23:49:26.682Z] ====== als (apache-spark) [default], iteration 28 completed (3830.656 ms) ====== [2025-05-28T23:49:26.682Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-28T23:49:26.682Z] GC before operation: completed in 186.610 ms, heap usage 163.145 MB -> 70.320 MB. [2025-05-28T23:49:30.808Z] ====== als (apache-spark) [default], iteration 29 completed (3889.056 ms) ====== [2025-05-28T23:49:31.753Z] ----------------------------------- [2025-05-28T23:49:31.753Z] renaissance-als_0_PASSED [2025-05-28T23:49:31.753Z] ----------------------------------- [2025-05-28T23:49:31.753Z] [2025-05-28T23:49:31.753Z] TEST TEARDOWN: [2025-05-28T23:49:31.753Z] Nothing to be done for teardown. [2025-05-28T23:49:31.753Z] renaissance-als_0 Finish Time: Wed May 28 23:49:31 2025 Epoch Time (ms): 1748476171130