[2026-02-07T03:28:43.879Z] Running test renaissance-als_0 ... [2026-02-07T03:28:43.879Z] =============================================== [2026-02-07T03:28:43.879Z] renaissance-als_0 Start Time: Sat Feb 7 03:28:43 2026 Epoch Time (ms): 1770434923227 [2026-02-07T03:28:43.879Z] variation: NoOptions [2026-02-07T03:28:43.879Z] JVM_OPTIONS: [2026-02-07T03:28:43.879Z] { \ [2026-02-07T03:28:43.879Z] echo ""; echo "TEST SETUP:"; \ [2026-02-07T03:28:43.879Z] echo "Nothing to be done for setup."; \ [2026-02-07T03:28:43.879Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17704349226741/renaissance-als_0"; \ [2026-02-07T03:28:43.879Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17704349226741/renaissance-als_0"; \ [2026-02-07T03:28:43.879Z] echo ""; echo "TESTING:"; \ [2026-02-07T03:28:43.879Z] "/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_17704349226741/renaissance-als_0"/als.json" als; \ [2026-02-07T03:28:43.880Z] 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_17704349226741/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-07T03:28:43.880Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-07T03:28:43.880Z] echo "Nothing to be done for teardown."; \ [2026-02-07T03:28:43.880Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17704349226741/TestTargetResult"; [2026-02-07T03:28:43.880Z] [2026-02-07T03:28:43.880Z] TEST SETUP: [2026-02-07T03:28:43.880Z] Nothing to be done for setup. [2026-02-07T03:28:43.880Z] [2026-02-07T03:28:43.880Z] TESTING: [2026-02-07T03:28:50.741Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-07T03:28:54.164Z] WARNING: An illegal reflective access operation has occurred [2026-02-07T03:28:54.164Z] 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_17704349226741/renaissance-als_0/harness-032843-16009938759539961812/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-07T03:28:54.164Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-07T03:28:54.164Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-07T03:28:54.164Z] WARNING: All illegal access operations will be denied in a future release [2026-02-07T03:28:54.935Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-07T03:28:54.935Z] GC before operation: completed in 82.545 ms, heap usage 463.002 MB -> 34.531 MB. [2026-02-07T03:29:17.570Z] ====== als (apache-spark) [default], iteration 0 completed (22487.797 ms) ====== [2026-02-07T03:29:17.570Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-07T03:29:17.570Z] GC before operation: completed in 119.385 ms, heap usage 349.197 MB -> 60.467 MB. [2026-02-07T03:29:27.582Z] ====== als (apache-spark) [default], iteration 1 completed (9302.871 ms) ====== [2026-02-07T03:29:27.582Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-07T03:29:27.582Z] GC before operation: completed in 125.411 ms, heap usage 255.069 MB -> 61.259 MB. [2026-02-07T03:29:37.514Z] ====== als (apache-spark) [default], iteration 2 completed (8939.764 ms) ====== [2026-02-07T03:29:37.515Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-07T03:29:37.515Z] GC before operation: completed in 129.149 ms, heap usage 380.124 MB -> 61.914 MB. [2026-02-07T03:29:45.845Z] ====== als (apache-spark) [default], iteration 3 completed (8756.802 ms) ====== [2026-02-07T03:29:45.845Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-07T03:29:45.845Z] GC before operation: completed in 124.569 ms, heap usage 262.595 MB -> 62.358 MB. [2026-02-07T03:29:55.760Z] ====== als (apache-spark) [default], iteration 4 completed (8555.673 ms) ====== [2026-02-07T03:29:55.760Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-07T03:29:55.760Z] GC before operation: completed in 146.206 ms, heap usage 601.105 MB -> 63.325 MB. [2026-02-07T03:30:04.087Z] ====== als (apache-spark) [default], iteration 5 completed (8811.546 ms) ====== [2026-02-07T03:30:04.087Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-07T03:30:04.087Z] GC before operation: completed in 134.917 ms, heap usage 263.976 MB -> 63.393 MB. [2026-02-07T03:30:12.391Z] ====== als (apache-spark) [default], iteration 6 completed (8240.466 ms) ====== [2026-02-07T03:30:12.391Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-07T03:30:12.391Z] GC before operation: completed in 136.569 ms, heap usage 196.642 MB -> 63.835 MB. [2026-02-07T03:30:21.266Z] ====== als (apache-spark) [default], iteration 7 completed (8123.879 ms) ====== [2026-02-07T03:30:21.266Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-07T03:30:22.082Z] GC before operation: completed in 175.132 ms, heap usage 249.249 MB -> 64.448 MB. [2026-02-07T03:30:30.417Z] ====== als (apache-spark) [default], iteration 8 completed (8054.606 ms) ====== [2026-02-07T03:30:30.417Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-07T03:30:30.417Z] GC before operation: completed in 141.705 ms, heap usage 265.451 MB -> 65.107 MB. [2026-02-07T03:30:40.321Z] ====== als (apache-spark) [default], iteration 9 completed (10650.846 ms) ====== [2026-02-07T03:30:40.321Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-07T03:30:40.321Z] GC before operation: completed in 133.593 ms, heap usage 363.764 MB -> 65.409 MB. [2026-02-07T03:30:50.254Z] ====== als (apache-spark) [default], iteration 10 completed (8998.814 ms) ====== [2026-02-07T03:30:50.254Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-07T03:30:50.254Z] GC before operation: completed in 146.437 ms, heap usage 252.123 MB -> 65.624 MB. [2026-02-07T03:31:04.058Z] ====== als (apache-spark) [default], iteration 11 completed (12893.686 ms) ====== [2026-02-07T03:31:04.058Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-07T03:31:04.058Z] GC before operation: completed in 144.567 ms, heap usage 216.870 MB -> 65.853 MB. [2026-02-07T03:31:12.400Z] ====== als (apache-spark) [default], iteration 12 completed (8261.033 ms) ====== [2026-02-07T03:31:12.400Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-07T03:31:12.400Z] GC before operation: completed in 144.693 ms, heap usage 191.473 MB -> 66.003 MB. [2026-02-07T03:31:20.822Z] ====== als (apache-spark) [default], iteration 13 completed (8357.579 ms) ====== [2026-02-07T03:31:20.822Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-07T03:31:20.822Z] GC before operation: completed in 158.016 ms, heap usage 213.927 MB -> 66.251 MB. [2026-02-07T03:31:29.147Z] ====== als (apache-spark) [default], iteration 14 completed (8061.113 ms) ====== [2026-02-07T03:31:29.147Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-07T03:31:29.147Z] GC before operation: completed in 153.532 ms, heap usage 336.285 MB -> 66.662 MB. [2026-02-07T03:31:38.184Z] ====== als (apache-spark) [default], iteration 15 completed (7954.563 ms) ====== [2026-02-07T03:31:38.184Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-07T03:31:38.184Z] GC before operation: completed in 149.115 ms, heap usage 469.193 MB -> 67.092 MB. [2026-02-07T03:31:49.911Z] ====== als (apache-spark) [default], iteration 16 completed (11854.540 ms) ====== [2026-02-07T03:31:49.911Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-07T03:31:49.911Z] GC before operation: completed in 147.378 ms, heap usage 229.300 MB -> 67.087 MB. [2026-02-07T03:31:58.242Z] ====== als (apache-spark) [default], iteration 17 completed (8014.923 ms) ====== [2026-02-07T03:31:58.242Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-07T03:31:58.242Z] GC before operation: completed in 150.604 ms, heap usage 452.075 MB -> 67.581 MB. [2026-02-07T03:32:06.544Z] ====== als (apache-spark) [default], iteration 18 completed (8896.153 ms) ====== [2026-02-07T03:32:06.544Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-07T03:32:07.325Z] GC before operation: completed in 141.680 ms, heap usage 245.737 MB -> 67.506 MB. [2026-02-07T03:32:26.541Z] ====== als (apache-spark) [default], iteration 19 completed (17955.883 ms) ====== [2026-02-07T03:32:26.541Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-07T03:32:26.541Z] GC before operation: completed in 149.662 ms, heap usage 187.963 MB -> 67.623 MB. [2026-02-07T03:32:34.929Z] ====== als (apache-spark) [default], iteration 20 completed (8486.119 ms) ====== [2026-02-07T03:32:34.929Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-07T03:32:34.929Z] GC before operation: completed in 151.011 ms, heap usage 190.262 MB -> 67.997 MB. [2026-02-07T03:32:44.852Z] ====== als (apache-spark) [default], iteration 21 completed (9938.467 ms) ====== [2026-02-07T03:32:44.852Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-07T03:32:44.852Z] GC before operation: completed in 154.072 ms, heap usage 402.215 MB -> 68.339 MB. [2026-02-07T03:32:54.851Z] ====== als (apache-spark) [default], iteration 22 completed (9204.240 ms) ====== [2026-02-07T03:32:54.851Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-07T03:32:54.851Z] GC before operation: completed in 144.698 ms, heap usage 357.166 MB -> 68.617 MB. [2026-02-07T03:33:03.156Z] ====== als (apache-spark) [default], iteration 23 completed (8297.218 ms) ====== [2026-02-07T03:33:03.156Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-07T03:33:03.156Z] GC before operation: completed in 148.492 ms, heap usage 321.995 MB -> 68.998 MB. [2026-02-07T03:33:10.568Z] ====== als (apache-spark) [default], iteration 24 completed (8083.282 ms) ====== [2026-02-07T03:33:10.568Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-07T03:33:10.568Z] GC before operation: completed in 150.801 ms, heap usage 269.571 MB -> 69.006 MB. [2026-02-07T03:33:22.271Z] ====== als (apache-spark) [default], iteration 25 completed (10620.471 ms) ====== [2026-02-07T03:33:22.271Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-07T03:33:22.271Z] GC before operation: completed in 141.016 ms, heap usage 237.046 MB -> 69.143 MB. [2026-02-07T03:33:33.275Z] ====== als (apache-spark) [default], iteration 26 completed (9714.039 ms) ====== [2026-02-07T03:33:33.275Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-07T03:33:33.275Z] GC before operation: completed in 143.996 ms, heap usage 259.449 MB -> 69.511 MB. [2026-02-07T03:33:49.930Z] ====== als (apache-spark) [default], iteration 27 completed (15612.548 ms) ====== [2026-02-07T03:33:49.930Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-07T03:33:49.930Z] GC before operation: completed in 154.318 ms, heap usage 140.498 MB -> 69.636 MB. [2026-02-07T03:33:59.829Z] ====== als (apache-spark) [default], iteration 28 completed (11287.964 ms) ====== [2026-02-07T03:33:59.829Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-07T03:33:59.829Z] GC before operation: completed in 145.398 ms, heap usage 282.011 MB -> 69.999 MB. [2026-02-07T03:34:08.152Z] ====== als (apache-spark) [default], iteration 29 completed (8134.106 ms) ====== [2026-02-07T03:34:08.152Z] ----------------------------------- [2026-02-07T03:34:08.152Z] renaissance-als_0_PASSED [2026-02-07T03:34:08.152Z] ----------------------------------- [2026-02-07T03:34:08.152Z] [2026-02-07T03:34:08.153Z] TEST TEARDOWN: [2026-02-07T03:34:08.153Z] Nothing to be done for teardown. [2026-02-07T03:34:08.153Z] renaissance-als_0 Finish Time: Sat Feb 7 03:34:07 2026 Epoch Time (ms): 1770435247709