[2025-07-15T11:02:33.269Z] Running test renaissance-als_0 ... [2025-07-15T11:02:33.269Z] =============================================== [2025-07-15T11:02:33.269Z] renaissance-als_0 Start Time: Tue Jul 15 11:02:32 2025 Epoch Time (ms): 1752577352343 [2025-07-15T11:02:33.269Z] variation: NoOptions [2025-07-15T11:02:33.269Z] JVM_OPTIONS: [2025-07-15T11:02:33.269Z] { \ [2025-07-15T11:02:33.269Z] echo ""; echo "TEST SETUP:"; \ [2025-07-15T11:02:33.269Z] echo "Nothing to be done for setup."; \ [2025-07-15T11:02:33.269Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17525773038705/renaissance-als_0"; \ [2025-07-15T11:02:33.269Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17525773038705/renaissance-als_0"; \ [2025-07-15T11:02:33.269Z] echo ""; echo "TESTING:"; \ [2025-07-15T11:02:33.269Z] "/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_17525773038705/renaissance-als_0"/als.json" als; \ [2025-07-15T11:02:33.269Z] 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_17525773038705/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-15T11:02:33.269Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-15T11:02:33.269Z] echo "Nothing to be done for teardown."; \ [2025-07-15T11:02:33.269Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17525773038705/TestTargetResult"; [2025-07-15T11:02:33.269Z] [2025-07-15T11:02:33.269Z] TEST SETUP: [2025-07-15T11:02:33.269Z] Nothing to be done for setup. [2025-07-15T11:02:33.269Z] [2025-07-15T11:02:33.269Z] TESTING: [2025-07-15T11:02:42.913Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-07-15T11:02:50.887Z] WARNING: An illegal reflective access operation has occurred [2025-07-15T11:02:50.887Z] 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_17525773038705/renaissance-als_0/harness-110233-2802970818435011079/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-07-15T11:02:50.887Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-07-15T11:02:50.887Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-07-15T11:02:50.887Z] WARNING: All illegal access operations will be denied in a future release [2025-07-15T11:02:53.828Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-15T11:02:54.755Z] GC before operation: completed in 101.805 ms, heap usage 432.500 MB -> 35.133 MB. [2025-07-15T11:03:22.917Z] ====== als (apache-spark) [default], iteration 0 completed (24686.190 ms) ====== [2025-07-15T11:03:22.917Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-15T11:03:22.917Z] GC before operation: completed in 220.325 ms, heap usage 680.461 MB -> 61.761 MB. [2025-07-15T11:03:28.168Z] ====== als (apache-spark) [default], iteration 1 completed (7510.205 ms) ====== [2025-07-15T11:03:28.168Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-15T11:03:29.097Z] GC before operation: completed in 263.122 ms, heap usage 1.199 GB -> 63.163 MB. [2025-07-15T11:03:35.634Z] ====== als (apache-spark) [default], iteration 2 completed (6040.943 ms) ====== [2025-07-15T11:03:35.634Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-15T11:03:35.634Z] GC before operation: completed in 251.843 ms, heap usage 1.210 GB -> 63.713 MB. [2025-07-15T11:03:42.205Z] ====== als (apache-spark) [default], iteration 3 completed (6109.321 ms) ====== [2025-07-15T11:03:42.205Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-15T11:03:42.205Z] GC before operation: completed in 230.795 ms, heap usage 166.125 MB -> 63.066 MB. [2025-07-15T11:03:48.794Z] ====== als (apache-spark) [default], iteration 4 completed (5873.315 ms) ====== [2025-07-15T11:03:48.794Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-15T11:03:48.794Z] GC before operation: completed in 260.775 ms, heap usage 577.442 MB -> 64.155 MB. [2025-07-15T11:03:55.382Z] ====== als (apache-spark) [default], iteration 5 completed (5802.484 ms) ====== [2025-07-15T11:03:55.382Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-15T11:03:55.382Z] GC before operation: completed in 236.202 ms, heap usage 168.845 MB -> 64.149 MB. [2025-07-15T11:04:02.016Z] ====== als (apache-spark) [default], iteration 6 completed (5898.154 ms) ====== [2025-07-15T11:04:02.016Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-15T11:04:02.016Z] GC before operation: completed in 268.613 ms, heap usage 694.706 MB -> 65.296 MB. [2025-07-15T11:04:07.287Z] ====== als (apache-spark) [default], iteration 7 completed (5823.737 ms) ====== [2025-07-15T11:04:07.287Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-15T11:04:08.217Z] GC before operation: completed in 247.148 ms, heap usage 235.089 MB -> 65.298 MB. [2025-07-15T11:04:13.530Z] ====== als (apache-spark) [default], iteration 8 completed (5625.561 ms) ====== [2025-07-15T11:04:13.530Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-15T11:04:14.459Z] GC before operation: completed in 255.658 ms, heap usage 205.960 MB -> 65.822 MB. [2025-07-15T11:04:20.286Z] ====== als (apache-spark) [default], iteration 9 completed (5874.454 ms) ====== [2025-07-15T11:04:20.286Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-15T11:04:20.286Z] GC before operation: completed in 290.084 ms, heap usage 1.111 GB -> 67.113 MB. [2025-07-15T11:04:26.895Z] ====== als (apache-spark) [default], iteration 10 completed (5523.835 ms) ====== [2025-07-15T11:04:26.895Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-15T11:04:26.895Z] GC before operation: completed in 269.892 ms, heap usage 358.344 MB -> 66.528 MB. [2025-07-15T11:04:32.271Z] ====== als (apache-spark) [default], iteration 11 completed (5515.312 ms) ====== [2025-07-15T11:04:32.272Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-15T11:04:32.272Z] GC before operation: completed in 239.264 ms, heap usage 531.638 MB -> 66.954 MB. [2025-07-15T11:04:38.872Z] ====== als (apache-spark) [default], iteration 12 completed (5716.709 ms) ====== [2025-07-15T11:04:38.872Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-15T11:04:38.872Z] GC before operation: completed in 250.279 ms, heap usage 481.338 MB -> 67.216 MB. [2025-07-15T11:04:45.428Z] ====== als (apache-spark) [default], iteration 13 completed (5653.599 ms) ====== [2025-07-15T11:04:45.428Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-15T11:04:45.428Z] GC before operation: completed in 253.877 ms, heap usage 1.062 GB -> 68.113 MB. [2025-07-15T11:04:50.673Z] ====== als (apache-spark) [default], iteration 14 completed (5319.992 ms) ====== [2025-07-15T11:04:50.673Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-15T11:04:50.673Z] GC before operation: completed in 258.497 ms, heap usage 429.110 MB -> 67.668 MB. [2025-07-15T11:04:57.273Z] ====== als (apache-spark) [default], iteration 15 completed (5490.468 ms) ====== [2025-07-15T11:04:57.273Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-15T11:04:57.273Z] GC before operation: completed in 249.439 ms, heap usage 473.869 MB -> 67.977 MB. [2025-07-15T11:05:02.563Z] ====== als (apache-spark) [default], iteration 16 completed (5408.696 ms) ====== [2025-07-15T11:05:02.563Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-15T11:05:02.563Z] GC before operation: completed in 267.452 ms, heap usage 1.074 GB -> 68.847 MB. [2025-07-15T11:05:09.147Z] ====== als (apache-spark) [default], iteration 17 completed (5203.258 ms) ====== [2025-07-15T11:05:09.147Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-15T11:05:09.147Z] GC before operation: completed in 309.322 ms, heap usage 262.588 MB -> 68.178 MB. [2025-07-15T11:05:14.410Z] ====== als (apache-spark) [default], iteration 18 completed (5490.281 ms) ====== [2025-07-15T11:05:14.410Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-15T11:05:14.410Z] GC before operation: completed in 287.611 ms, heap usage 1.041 GB -> 69.364 MB. [2025-07-15T11:05:20.966Z] ====== als (apache-spark) [default], iteration 19 completed (5403.582 ms) ====== [2025-07-15T11:05:20.966Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-15T11:05:20.966Z] GC before operation: completed in 288.716 ms, heap usage 708.172 MB -> 69.170 MB. [2025-07-15T11:05:26.255Z] ====== als (apache-spark) [default], iteration 20 completed (5544.177 ms) ====== [2025-07-15T11:05:26.255Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-15T11:05:26.255Z] GC before operation: completed in 286.922 ms, heap usage 342.866 MB -> 68.989 MB. [2025-07-15T11:05:32.802Z] ====== als (apache-spark) [default], iteration 21 completed (5446.141 ms) ====== [2025-07-15T11:05:32.802Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-15T11:05:32.802Z] GC before operation: completed in 275.083 ms, heap usage 470.105 MB -> 69.489 MB. [2025-07-15T11:05:38.070Z] ====== als (apache-spark) [default], iteration 22 completed (5429.744 ms) ====== [2025-07-15T11:05:38.070Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-15T11:05:39.000Z] GC before operation: completed in 288.827 ms, heap usage 262.038 MB -> 69.416 MB. [2025-07-15T11:05:44.257Z] ====== als (apache-spark) [default], iteration 23 completed (5263.908 ms) ====== [2025-07-15T11:05:44.257Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-15T11:05:44.257Z] GC before operation: completed in 295.015 ms, heap usage 705.185 MB -> 70.205 MB. [2025-07-15T11:05:50.825Z] ====== als (apache-spark) [default], iteration 24 completed (5647.793 ms) ====== [2025-07-15T11:05:50.825Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-15T11:05:50.825Z] GC before operation: completed in 300.466 ms, heap usage 844.871 MB -> 70.654 MB. [2025-07-15T11:05:56.070Z] ====== als (apache-spark) [default], iteration 25 completed (5108.567 ms) ====== [2025-07-15T11:05:56.070Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-15T11:05:56.070Z] GC before operation: completed in 275.192 ms, heap usage 587.049 MB -> 70.516 MB. [2025-07-15T11:06:02.630Z] ====== als (apache-spark) [default], iteration 26 completed (5199.802 ms) ====== [2025-07-15T11:06:02.630Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-15T11:06:02.630Z] GC before operation: completed in 356.540 ms, heap usage 145.496 MB -> 70.276 MB. [2025-07-15T11:06:07.871Z] ====== als (apache-spark) [default], iteration 27 completed (5102.380 ms) ====== [2025-07-15T11:06:07.871Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-15T11:06:07.871Z] GC before operation: completed in 290.956 ms, heap usage 654.270 MB -> 70.650 MB. [2025-07-15T11:06:14.436Z] ====== als (apache-spark) [default], iteration 28 completed (5501.989 ms) ====== [2025-07-15T11:06:14.436Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-15T11:06:14.436Z] GC before operation: completed in 300.685 ms, heap usage 833.590 MB -> 71.206 MB. [2025-07-15T11:06:19.680Z] ====== als (apache-spark) [default], iteration 29 completed (5204.279 ms) ====== [2025-07-15T11:06:20.605Z] ----------------------------------- [2025-07-15T11:06:20.605Z] renaissance-als_0_PASSED [2025-07-15T11:06:20.605Z] ----------------------------------- [2025-07-15T11:06:20.605Z] [2025-07-15T11:06:20.605Z] TEST TEARDOWN: [2025-07-15T11:06:20.605Z] Nothing to be done for teardown. [2025-07-15T11:06:20.605Z] renaissance-als_0 Finish Time: Tue Jul 15 11:06:19 2025 Epoch Time (ms): 1752577579831