[2026-03-01T22:23:08.003Z] Running test renaissance-als_0 ... [2026-03-01T22:23:08.003Z] =============================================== [2026-03-01T22:23:08.331Z] renaissance-als_0 Start Time: Sun Mar 1 22:23:07 2026 Epoch Time (ms): 1772403787999 [2026-03-01T22:23:08.331Z] variation: NoOptions [2026-03-01T22:23:08.331Z] JVM_OPTIONS: [2026-03-01T22:23:08.331Z] { \ [2026-03-01T22:23:08.331Z] echo ""; echo "TEST SETUP:"; \ [2026-03-01T22:23:08.331Z] echo "Nothing to be done for setup."; \ [2026-03-01T22:23:08.331Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17724036288677/renaissance-als_0"; \ [2026-03-01T22:23:08.331Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17724036288677/renaissance-als_0"; \ [2026-03-01T22:23:08.331Z] echo ""; echo "TESTING:"; \ [2026-03-01T22:23:08.331Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17724036288677/renaissance-als_0"/als.json" als; \ [2026-03-01T22:23:08.331Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17724036288677/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-01T22:23:08.331Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-01T22:23:08.331Z] echo "Nothing to be done for teardown."; \ [2026-03-01T22:23:08.331Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17724036288677/TestTargetResult"; [2026-03-01T22:23:08.331Z] [2026-03-01T22:23:08.331Z] TEST SETUP: [2026-03-01T22:23:08.331Z] Nothing to be done for setup. [2026-03-01T22:23:08.331Z] [2026-03-01T22:23:08.331Z] TESTING: [2026-03-01T22:23:13.100Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-03-01T22:23:16.095Z] WARNING: An illegal reflective access operation has occurred [2026-03-01T22:23:16.095Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17724036288677/renaissance-als_0/harness-222308-1112663994506942895/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-03-01T22:23:16.095Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-03-01T22:23:16.095Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-03-01T22:23:16.095Z] WARNING: All illegal access operations will be denied in a future release [2026-03-01T22:23:17.237Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-01T22:23:17.565Z] GC before operation: completed in 112.938 ms, heap usage 350.011 MB -> 34.492 MB. [2026-03-01T22:23:40.644Z] ====== als (apache-spark) [default], iteration 0 completed (21445.933 ms) ====== [2026-03-01T22:23:40.644Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-01T22:23:40.644Z] GC before operation: completed in 156.194 ms, heap usage 579.690 MB -> 60.828 MB. [2026-03-01T22:23:49.549Z] ====== als (apache-spark) [default], iteration 1 completed (9171.556 ms) ====== [2026-03-01T22:23:49.549Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-01T22:23:49.549Z] GC before operation: completed in 187.041 ms, heap usage 342.556 MB -> 61.279 MB. [2026-03-01T22:23:58.445Z] ====== als (apache-spark) [default], iteration 2 completed (8506.835 ms) ====== [2026-03-01T22:23:58.445Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-01T22:23:58.445Z] GC before operation: completed in 228.322 ms, heap usage 254.431 MB -> 61.714 MB. [2026-03-01T22:24:07.441Z] ====== als (apache-spark) [default], iteration 3 completed (8349.627 ms) ====== [2026-03-01T22:24:07.441Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-01T22:24:07.441Z] GC before operation: completed in 185.924 ms, heap usage 592.014 MB -> 62.825 MB. [2026-03-01T22:24:16.334Z] ====== als (apache-spark) [default], iteration 4 completed (7891.148 ms) ====== [2026-03-01T22:24:16.334Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-01T22:24:16.334Z] GC before operation: completed in 168.078 ms, heap usage 852.737 MB -> 63.515 MB. [2026-03-01T22:24:23.614Z] ====== als (apache-spark) [default], iteration 5 completed (8161.011 ms) ====== [2026-03-01T22:24:23.614Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-01T22:24:23.943Z] GC before operation: completed in 160.498 ms, heap usage 123.428 MB -> 63.296 MB. [2026-03-01T22:24:32.837Z] ====== als (apache-spark) [default], iteration 6 completed (7962.111 ms) ====== [2026-03-01T22:24:32.837Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-01T22:24:32.837Z] GC before operation: completed in 233.336 ms, heap usage 615.584 MB -> 64.376 MB. [2026-03-01T22:24:41.726Z] ====== als (apache-spark) [default], iteration 7 completed (7986.374 ms) ====== [2026-03-01T22:24:41.726Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-01T22:24:41.726Z] GC before operation: completed in 239.450 ms, heap usage 157.370 MB -> 64.350 MB. [2026-03-01T22:24:50.629Z] ====== als (apache-spark) [default], iteration 8 completed (8376.839 ms) ====== [2026-03-01T22:24:50.629Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-01T22:24:50.629Z] GC before operation: completed in 230.581 ms, heap usage 453.335 MB -> 65.317 MB. [2026-03-01T22:24:59.524Z] ====== als (apache-spark) [default], iteration 9 completed (9277.298 ms) ====== [2026-03-01T22:24:59.524Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-01T22:24:59.524Z] GC before operation: completed in 262.997 ms, heap usage 710.652 MB -> 65.794 MB. [2026-03-01T22:25:10.360Z] ====== als (apache-spark) [default], iteration 10 completed (9459.116 ms) ====== [2026-03-01T22:25:10.360Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-01T22:25:10.360Z] GC before operation: completed in 263.606 ms, heap usage 144.345 MB -> 65.396 MB. [2026-03-01T22:25:19.277Z] ====== als (apache-spark) [default], iteration 11 completed (8447.382 ms) ====== [2026-03-01T22:25:19.277Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-01T22:25:19.277Z] GC before operation: completed in 251.611 ms, heap usage 670.437 MB -> 66.307 MB. [2026-03-01T22:25:26.544Z] ====== als (apache-spark) [default], iteration 12 completed (8058.802 ms) ====== [2026-03-01T22:25:26.544Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-01T22:25:26.544Z] GC before operation: completed in 217.513 ms, heap usage 291.085 MB -> 65.990 MB. [2026-03-01T22:25:35.497Z] ====== als (apache-spark) [default], iteration 13 completed (8648.374 ms) ====== [2026-03-01T22:25:35.497Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-01T22:25:35.827Z] GC before operation: completed in 255.872 ms, heap usage 299.760 MB -> 66.354 MB. [2026-03-01T22:25:46.827Z] ====== als (apache-spark) [default], iteration 14 completed (9014.536 ms) ====== [2026-03-01T22:25:46.827Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-01T22:25:46.827Z] GC before operation: completed in 254.853 ms, heap usage 172.734 MB -> 66.371 MB. [2026-03-01T22:25:54.108Z] ====== als (apache-spark) [default], iteration 15 completed (7982.894 ms) ====== [2026-03-01T22:25:54.108Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-01T22:25:54.108Z] GC before operation: completed in 197.965 ms, heap usage 396.527 MB -> 66.865 MB. [2026-03-01T22:26:03.021Z] ====== als (apache-spark) [default], iteration 16 completed (8002.099 ms) ====== [2026-03-01T22:26:03.022Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-01T22:26:03.022Z] GC before operation: completed in 224.266 ms, heap usage 153.114 MB -> 66.890 MB. [2026-03-01T22:26:11.921Z] ====== als (apache-spark) [default], iteration 17 completed (8568.982 ms) ====== [2026-03-01T22:26:11.921Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-01T22:26:11.921Z] GC before operation: completed in 231.061 ms, heap usage 237.593 MB -> 67.245 MB. [2026-03-01T22:26:20.830Z] ====== als (apache-spark) [default], iteration 18 completed (8095.058 ms) ====== [2026-03-01T22:26:20.830Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-01T22:26:20.830Z] GC before operation: completed in 213.368 ms, heap usage 239.528 MB -> 67.493 MB. [2026-03-01T22:26:28.154Z] ====== als (apache-spark) [default], iteration 19 completed (8053.226 ms) ====== [2026-03-01T22:26:28.154Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-01T22:26:28.154Z] GC before operation: completed in 211.854 ms, heap usage 149.278 MB -> 67.614 MB. [2026-03-01T22:26:37.063Z] ====== als (apache-spark) [default], iteration 20 completed (8404.212 ms) ====== [2026-03-01T22:26:37.063Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-01T22:26:37.063Z] GC before operation: completed in 207.259 ms, heap usage 100.750 MB -> 67.627 MB. [2026-03-01T22:26:45.993Z] ====== als (apache-spark) [default], iteration 21 completed (7985.749 ms) ====== [2026-03-01T22:26:45.993Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-01T22:26:45.993Z] GC before operation: completed in 255.553 ms, heap usage 941.863 MB -> 69.037 MB. [2026-03-01T22:26:53.265Z] ====== als (apache-spark) [default], iteration 22 completed (7868.311 ms) ====== [2026-03-01T22:26:53.265Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-01T22:26:53.592Z] GC before operation: completed in 270.164 ms, heap usage 868.190 MB -> 69.264 MB. [2026-03-01T22:27:02.472Z] ====== als (apache-spark) [default], iteration 23 completed (7791.844 ms) ====== [2026-03-01T22:27:02.472Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-01T22:27:02.472Z] GC before operation: completed in 237.978 ms, heap usage 373.733 MB -> 68.897 MB. [2026-03-01T22:27:09.748Z] ====== als (apache-spark) [default], iteration 24 completed (7985.672 ms) ====== [2026-03-01T22:27:09.748Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-01T22:27:10.079Z] GC before operation: completed in 255.340 ms, heap usage 160.463 MB -> 68.923 MB. [2026-03-01T22:27:18.979Z] ====== als (apache-spark) [default], iteration 25 completed (7668.875 ms) ====== [2026-03-01T22:27:18.979Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-01T22:27:18.979Z] GC before operation: completed in 246.327 ms, heap usage 1.074 GB -> 70.290 MB. [2026-03-01T22:27:26.261Z] ====== als (apache-spark) [default], iteration 26 completed (7656.596 ms) ====== [2026-03-01T22:27:26.261Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-01T22:27:26.261Z] GC before operation: completed in 264.228 ms, heap usage 156.630 MB -> 69.420 MB. [2026-03-01T22:27:35.173Z] ====== als (apache-spark) [default], iteration 27 completed (7712.986 ms) ====== [2026-03-01T22:27:35.173Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-01T22:27:35.173Z] GC before operation: completed in 213.840 ms, heap usage 145.636 MB -> 69.675 MB. [2026-03-01T22:27:44.078Z] ====== als (apache-spark) [default], iteration 28 completed (8093.831 ms) ====== [2026-03-01T22:27:44.078Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-01T22:27:44.078Z] GC before operation: completed in 222.179 ms, heap usage 568.083 MB -> 69.996 MB. [2026-03-01T22:27:51.357Z] ====== als (apache-spark) [default], iteration 29 completed (7559.405 ms) ====== [2026-03-01T22:27:51.357Z] ----------------------------------- [2026-03-01T22:27:51.357Z] renaissance-als_0_PASSED [2026-03-01T22:27:51.357Z] ----------------------------------- [2026-03-01T22:27:51.357Z] [2026-03-01T22:27:51.357Z] TEST TEARDOWN: [2026-03-01T22:27:51.357Z] Nothing to be done for teardown. [2026-03-01T22:27:51.357Z] renaissance-als_0 Finish Time: Sun Mar 1 22:27:50 2026 Epoch Time (ms): 1772404070994