[2026-04-14T04:07:53.544Z] Running test renaissance-als_0 ... [2026-04-14T04:07:53.544Z] =============================================== [2026-04-14T04:07:53.544Z] renaissance-als_0 Start Time: Tue Apr 14 04:07:53 2026 Epoch Time (ms): 1776139673034 [2026-04-14T04:07:53.544Z] variation: NoOptions [2026-04-14T04:07:53.544Z] JVM_OPTIONS: [2026-04-14T04:07:53.544Z] { \ [2026-04-14T04:07:53.544Z] echo ""; echo "TEST SETUP:"; \ [2026-04-14T04:07:53.544Z] echo "Nothing to be done for setup."; \ [2026-04-14T04:07:53.544Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17761396722192/renaissance-als_0"; \ [2026-04-14T04:07:53.544Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17761396722192/renaissance-als_0"; \ [2026-04-14T04:07:53.544Z] echo ""; echo "TESTING:"; \ [2026-04-14T04:07:53.545Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17761396722192/renaissance-als_0"/als.json" als; \ [2026-04-14T04:07:53.545Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17761396722192/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-14T04:07:53.545Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-14T04:07:53.545Z] echo "Nothing to be done for teardown."; \ [2026-04-14T04:07:53.545Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17761396722192/TestTargetResult"; [2026-04-14T04:07:53.545Z] [2026-04-14T04:07:53.545Z] TEST SETUP: [2026-04-14T04:07:53.545Z] Nothing to be done for setup. [2026-04-14T04:07:53.545Z] [2026-04-14T04:07:53.545Z] TESTING: [2026-04-14T04:08:03.583Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-14T04:08:09.039Z] WARNING: An illegal reflective access operation has occurred [2026-04-14T04:08:09.039Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17761396722192/renaissance-als_0/harness-040754-6079410114889109864/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-14T04:08:09.039Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-14T04:08:09.039Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-14T04:08:09.039Z] WARNING: All illegal access operations will be denied in a future release [2026-04-14T04:08:10.578Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-14T04:08:10.578Z] GC before operation: completed in 219.490 ms, heap usage 328.002 MB -> 34.505 MB. [2026-04-14T04:08:51.721Z] ====== als (apache-spark) [default], iteration 0 completed (36236.068 ms) ====== [2026-04-14T04:08:51.721Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-14T04:08:51.721Z] GC before operation: completed in 188.793 ms, heap usage 158.841 MB -> 60.332 MB. [2026-04-14T04:09:03.199Z] ====== als (apache-spark) [default], iteration 1 completed (14013.924 ms) ====== [2026-04-14T04:09:03.199Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-14T04:09:03.199Z] GC before operation: completed in 209.688 ms, heap usage 164.584 MB -> 60.777 MB. [2026-04-14T04:09:16.726Z] ====== als (apache-spark) [default], iteration 2 completed (12182.467 ms) ====== [2026-04-14T04:09:16.726Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-14T04:09:16.726Z] GC before operation: completed in 226.633 ms, heap usage 645.974 MB -> 62.301 MB. [2026-04-14T04:09:28.796Z] ====== als (apache-spark) [default], iteration 3 completed (12703.305 ms) ====== [2026-04-14T04:09:28.796Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-14T04:09:28.796Z] GC before operation: completed in 252.571 ms, heap usage 314.979 MB -> 62.472 MB. [2026-04-14T04:09:42.400Z] ====== als (apache-spark) [default], iteration 4 completed (12122.576 ms) ====== [2026-04-14T04:09:42.400Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-14T04:09:42.400Z] GC before operation: completed in 198.505 ms, heap usage 629.533 MB -> 63.326 MB. [2026-04-14T04:09:56.020Z] ====== als (apache-spark) [default], iteration 5 completed (12609.904 ms) ====== [2026-04-14T04:09:56.020Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-14T04:09:56.020Z] GC before operation: completed in 269.697 ms, heap usage 148.271 MB -> 63.334 MB. [2026-04-14T04:10:07.561Z] ====== als (apache-spark) [default], iteration 6 completed (11314.904 ms) ====== [2026-04-14T04:10:07.561Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-14T04:10:07.561Z] GC before operation: completed in 249.438 ms, heap usage 141.456 MB -> 63.828 MB. [2026-04-14T04:10:19.486Z] ====== als (apache-spark) [default], iteration 7 completed (11341.806 ms) ====== [2026-04-14T04:10:19.486Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-14T04:10:19.486Z] GC before operation: completed in 244.847 ms, heap usage 239.005 MB -> 64.466 MB. [2026-04-14T04:10:33.074Z] ====== als (apache-spark) [default], iteration 8 completed (12543.246 ms) ====== [2026-04-14T04:10:33.074Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-14T04:10:33.074Z] GC before operation: completed in 255.933 ms, heap usage 374.800 MB -> 65.103 MB. [2026-04-14T04:10:44.639Z] ====== als (apache-spark) [default], iteration 9 completed (12059.642 ms) ====== [2026-04-14T04:10:44.639Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-14T04:10:44.639Z] GC before operation: completed in 251.781 ms, heap usage 328.131 MB -> 65.380 MB. [2026-04-14T04:10:58.225Z] ====== als (apache-spark) [default], iteration 10 completed (12130.561 ms) ====== [2026-04-14T04:10:58.225Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-14T04:10:58.225Z] GC before operation: completed in 219.720 ms, heap usage 215.243 MB -> 65.515 MB. [2026-04-14T04:11:11.958Z] ====== als (apache-spark) [default], iteration 11 completed (12755.755 ms) ====== [2026-04-14T04:11:11.958Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-14T04:11:11.958Z] GC before operation: completed in 231.907 ms, heap usage 216.478 MB -> 65.786 MB. [2026-04-14T04:11:23.462Z] ====== als (apache-spark) [default], iteration 12 completed (11380.268 ms) ====== [2026-04-14T04:11:23.462Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-14T04:11:23.462Z] GC before operation: completed in 235.825 ms, heap usage 313.159 MB -> 66.197 MB. [2026-04-14T04:11:34.979Z] ====== als (apache-spark) [default], iteration 13 completed (12084.638 ms) ====== [2026-04-14T04:11:34.979Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-14T04:11:35.735Z] GC before operation: completed in 208.883 ms, heap usage 141.856 MB -> 66.159 MB. [2026-04-14T04:11:47.292Z] ====== als (apache-spark) [default], iteration 14 completed (11612.014 ms) ====== [2026-04-14T04:11:47.292Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-14T04:11:48.045Z] GC before operation: completed in 263.364 ms, heap usage 258.095 MB -> 66.529 MB. [2026-04-14T04:12:01.588Z] ====== als (apache-spark) [default], iteration 15 completed (12344.249 ms) ====== [2026-04-14T04:12:01.588Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-14T04:12:01.588Z] GC before operation: completed in 321.333 ms, heap usage 402.539 MB -> 66.930 MB. [2026-04-14T04:12:13.603Z] ====== als (apache-spark) [default], iteration 16 completed (12761.597 ms) ====== [2026-04-14T04:12:13.603Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-14T04:12:14.356Z] GC before operation: completed in 207.013 ms, heap usage 197.779 MB -> 66.948 MB. [2026-04-14T04:12:27.997Z] ====== als (apache-spark) [default], iteration 17 completed (12683.881 ms) ====== [2026-04-14T04:12:27.997Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-14T04:12:27.997Z] GC before operation: completed in 229.851 ms, heap usage 156.347 MB -> 67.191 MB. [2026-04-14T04:12:38.821Z] ====== als (apache-spark) [default], iteration 18 completed (11315.942 ms) ====== [2026-04-14T04:12:38.821Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-14T04:12:38.821Z] GC before operation: completed in 201.269 ms, heap usage 194.218 MB -> 67.442 MB. [2026-04-14T04:12:52.391Z] ====== als (apache-spark) [default], iteration 19 completed (11561.599 ms) ====== [2026-04-14T04:12:52.391Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-14T04:12:52.391Z] GC before operation: completed in 295.972 ms, heap usage 322.022 MB -> 67.917 MB. [2026-04-14T04:13:04.557Z] ====== als (apache-spark) [default], iteration 20 completed (12505.522 ms) ====== [2026-04-14T04:13:04.557Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-14T04:13:04.557Z] GC before operation: completed in 237.978 ms, heap usage 175.956 MB -> 67.929 MB. [2026-04-14T04:13:17.893Z] ====== als (apache-spark) [default], iteration 21 completed (12101.864 ms) ====== [2026-04-14T04:13:17.893Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-14T04:13:17.893Z] GC before operation: completed in 286.325 ms, heap usage 306.127 MB -> 68.386 MB. [2026-04-14T04:13:31.889Z] ====== als (apache-spark) [default], iteration 22 completed (12833.098 ms) ====== [2026-04-14T04:13:31.889Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-14T04:13:31.889Z] GC before operation: completed in 261.877 ms, heap usage 245.296 MB -> 68.558 MB. [2026-04-14T04:13:43.419Z] ====== als (apache-spark) [default], iteration 23 completed (12167.923 ms) ====== [2026-04-14T04:13:43.419Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-14T04:13:43.419Z] GC before operation: completed in 263.427 ms, heap usage 219.314 MB -> 68.703 MB. [2026-04-14T04:13:58.621Z] ====== als (apache-spark) [default], iteration 24 completed (12105.330 ms) ====== [2026-04-14T04:13:58.621Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-14T04:13:58.621Z] GC before operation: completed in 262.446 ms, heap usage 261.938 MB -> 69.057 MB. [2026-04-14T04:14:12.276Z] ====== als (apache-spark) [default], iteration 25 completed (12479.577 ms) ====== [2026-04-14T04:14:12.276Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-14T04:14:12.277Z] GC before operation: completed in 198.572 ms, heap usage 280.948 MB -> 69.319 MB. [2026-04-14T04:14:34.393Z] ====== als (apache-spark) [default], iteration 26 completed (11742.857 ms) ====== [2026-04-14T04:14:34.393Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-14T04:14:34.393Z] GC before operation: completed in 244.559 ms, heap usage 170.227 MB -> 69.449 MB. [2026-04-14T04:14:46.811Z] ====== als (apache-spark) [default], iteration 27 completed (12251.563 ms) ====== [2026-04-14T04:14:46.811Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-14T04:14:46.811Z] GC before operation: completed in 325.144 ms, heap usage 287.562 MB -> 69.343 MB. [2026-04-14T04:14:48.989Z] ====== als (apache-spark) [default], iteration 28 completed (11333.815 ms) ====== [2026-04-14T04:14:48.989Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-14T04:14:48.989Z] GC before operation: completed in 241.605 ms, heap usage 148.171 MB -> 69.474 MB. [2026-04-14T04:14:59.244Z] ====== als (apache-spark) [default], iteration 29 completed (11648.496 ms) ====== [2026-04-14T04:14:59.244Z] ----------------------------------- [2026-04-14T04:14:59.244Z] renaissance-als_0_PASSED [2026-04-14T04:14:59.244Z] ----------------------------------- [2026-04-14T04:14:59.244Z] [2026-04-14T04:14:59.244Z] TEST TEARDOWN: [2026-04-14T04:14:59.244Z] Nothing to be done for teardown. [2026-04-14T04:14:59.244Z] renaissance-als_0 Finish Time: Tue Apr 14 04:14:58 2026 Epoch Time (ms): 1776140098683