[2026-02-12T00:12:05.431Z] Running test renaissance-als_0 ... [2026-02-12T00:12:05.431Z] =============================================== [2026-02-12T00:12:05.431Z] renaissance-als_0 Start Time: Thu Feb 12 00:12:04 2026 Epoch Time (ms): 1770855124636 [2026-02-12T00:12:05.431Z] variation: NoOptions [2026-02-12T00:12:05.431Z] JVM_OPTIONS: [2026-02-12T00:12:05.431Z] { \ [2026-02-12T00:12:05.431Z] echo ""; echo "TEST SETUP:"; \ [2026-02-12T00:12:05.431Z] echo "Nothing to be done for setup."; \ [2026-02-12T00:12:05.431Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17708551232235/renaissance-als_0"; \ [2026-02-12T00:12:05.431Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17708551232235/renaissance-als_0"; \ [2026-02-12T00:12:05.431Z] echo ""; echo "TESTING:"; \ [2026-02-12T00:12:05.431Z] "/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_17708551232235/renaissance-als_0"/als.json" als; \ [2026-02-12T00:12:05.431Z] 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_17708551232235/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-12T00:12:05.431Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-12T00:12:05.431Z] echo "Nothing to be done for teardown."; \ [2026-02-12T00:12:05.431Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17708551232235/TestTargetResult"; [2026-02-12T00:12:05.431Z] [2026-02-12T00:12:05.431Z] TEST SETUP: [2026-02-12T00:12:05.431Z] Nothing to be done for setup. [2026-02-12T00:12:05.431Z] [2026-02-12T00:12:05.431Z] TESTING: [2026-02-12T00:12:17.039Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-12T00:12:22.627Z] WARNING: An illegal reflective access operation has occurred [2026-02-12T00:12:22.627Z] 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_17708551232235/renaissance-als_0/harness-001206-8890961322224050045/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-12T00:12:22.627Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-12T00:12:22.627Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-12T00:12:22.627Z] WARNING: All illegal access operations will be denied in a future release [2026-02-12T00:12:25.090Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-12T00:12:25.090Z] GC before operation: completed in 214.177 ms, heap usage 324.502 MB -> 35.032 MB. [2026-02-12T00:13:32.643Z] ====== als (apache-spark) [default], iteration 0 completed (56642.665 ms) ====== [2026-02-12T00:13:32.643Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-12T00:13:32.643Z] GC before operation: completed in 440.411 ms, heap usage 463.828 MB -> 60.635 MB. [2026-02-12T00:13:48.735Z] ====== als (apache-spark) [default], iteration 1 completed (24276.385 ms) ====== [2026-02-12T00:13:48.735Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-12T00:13:48.735Z] GC before operation: completed in 309.677 ms, heap usage 211.781 MB -> 61.061 MB. [2026-02-12T00:14:10.867Z] ====== als (apache-spark) [default], iteration 2 completed (20350.200 ms) ====== [2026-02-12T00:14:10.867Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-12T00:14:10.867Z] GC before operation: completed in 386.579 ms, heap usage 139.027 MB -> 61.630 MB. [2026-02-12T00:14:32.814Z] ====== als (apache-spark) [default], iteration 3 completed (20147.242 ms) ====== [2026-02-12T00:14:32.814Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-12T00:14:32.814Z] GC before operation: completed in 284.592 ms, heap usage 278.302 MB -> 62.301 MB. [2026-02-12T00:14:51.606Z] ====== als (apache-spark) [default], iteration 4 completed (18910.898 ms) ====== [2026-02-12T00:14:51.606Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-12T00:14:51.606Z] GC before operation: completed in 417.035 ms, heap usage 320.659 MB -> 62.964 MB. [2026-02-12T00:15:14.181Z] ====== als (apache-spark) [default], iteration 5 completed (19988.940 ms) ====== [2026-02-12T00:15:14.181Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-12T00:15:14.181Z] GC before operation: completed in 422.036 ms, heap usage 225.647 MB -> 63.509 MB. [2026-02-12T00:15:33.031Z] ====== als (apache-spark) [default], iteration 6 completed (18929.254 ms) ====== [2026-02-12T00:15:33.031Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-12T00:15:33.031Z] GC before operation: completed in 511.123 ms, heap usage 214.878 MB -> 63.845 MB. [2026-02-12T00:15:55.110Z] ====== als (apache-spark) [default], iteration 7 completed (21182.645 ms) ====== [2026-02-12T00:15:55.110Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-12T00:15:55.110Z] GC before operation: completed in 351.310 ms, heap usage 257.508 MB -> 64.389 MB. [2026-02-12T00:16:17.164Z] ====== als (apache-spark) [default], iteration 8 completed (21958.694 ms) ====== [2026-02-12T00:16:17.164Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-12T00:16:17.943Z] GC before operation: completed in 359.613 ms, heap usage 393.816 MB -> 65.008 MB. [2026-02-12T00:16:36.809Z] ====== als (apache-spark) [default], iteration 9 completed (18784.416 ms) ====== [2026-02-12T00:16:36.809Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-12T00:16:37.593Z] GC before operation: completed in 402.256 ms, heap usage 330.540 MB -> 65.345 MB. [2026-02-12T00:17:00.158Z] ====== als (apache-spark) [default], iteration 10 completed (21707.371 ms) ====== [2026-02-12T00:17:00.158Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-12T00:17:00.158Z] GC before operation: completed in 420.377 ms, heap usage 287.846 MB -> 65.438 MB. [2026-02-12T00:17:22.461Z] ====== als (apache-spark) [default], iteration 11 completed (20514.498 ms) ====== [2026-02-12T00:17:22.461Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-12T00:17:22.461Z] GC before operation: completed in 367.069 ms, heap usage 187.006 MB -> 65.576 MB. [2026-02-12T00:17:48.286Z] ====== als (apache-spark) [default], iteration 12 completed (23080.355 ms) ====== [2026-02-12T00:17:48.286Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-12T00:17:48.286Z] GC before operation: completed in 342.195 ms, heap usage 226.195 MB -> 66.416 MB. [2026-02-12T00:18:07.146Z] ====== als (apache-spark) [default], iteration 13 completed (19726.816 ms) ====== [2026-02-12T00:18:07.146Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-12T00:18:07.146Z] GC before operation: completed in 451.031 ms, heap usage 510.117 MB -> 66.582 MB. [2026-02-12T00:18:26.030Z] ====== als (apache-spark) [default], iteration 14 completed (18692.490 ms) ====== [2026-02-12T00:18:26.030Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-12T00:18:26.030Z] GC before operation: completed in 369.363 ms, heap usage 390.249 MB -> 66.620 MB. [2026-02-12T00:18:45.140Z] ====== als (apache-spark) [default], iteration 15 completed (19443.977 ms) ====== [2026-02-12T00:18:45.140Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-12T00:18:45.905Z] GC before operation: completed in 381.910 ms, heap usage 224.635 MB -> 66.700 MB. [2026-02-12T00:19:07.960Z] ====== als (apache-spark) [default], iteration 16 completed (19556.404 ms) ====== [2026-02-12T00:19:07.960Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-12T00:19:07.960Z] GC before operation: completed in 415.708 ms, heap usage 226.694 MB -> 66.949 MB. [2026-02-12T00:19:26.807Z] ====== als (apache-spark) [default], iteration 17 completed (19895.365 ms) ====== [2026-02-12T00:19:26.807Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-12T00:19:27.587Z] GC before operation: completed in 385.862 ms, heap usage 236.790 MB -> 67.196 MB. [2026-02-12T00:19:46.510Z] ====== als (apache-spark) [default], iteration 18 completed (18034.529 ms) ====== [2026-02-12T00:19:46.510Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-12T00:19:46.510Z] GC before operation: completed in 403.822 ms, heap usage 164.223 MB -> 67.325 MB. [2026-02-12T00:20:08.641Z] ====== als (apache-spark) [default], iteration 19 completed (21852.859 ms) ====== [2026-02-12T00:20:08.641Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-12T00:20:09.408Z] GC before operation: completed in 370.785 ms, heap usage 241.411 MB -> 67.687 MB. [2026-02-12T00:20:31.853Z] ====== als (apache-spark) [default], iteration 20 completed (22203.666 ms) ====== [2026-02-12T00:20:31.853Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-12T00:20:32.621Z] GC before operation: completed in 451.492 ms, heap usage 213.238 MB -> 67.934 MB. [2026-02-12T00:20:54.679Z] ====== als (apache-spark) [default], iteration 21 completed (20299.512 ms) ====== [2026-02-12T00:20:54.679Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-12T00:20:54.679Z] GC before operation: completed in 449.037 ms, heap usage 190.949 MB -> 68.067 MB. [2026-02-12T00:21:13.684Z] ====== als (apache-spark) [default], iteration 22 completed (19127.696 ms) ====== [2026-02-12T00:21:13.684Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-12T00:21:13.684Z] GC before operation: completed in 344.988 ms, heap usage 168.008 MB -> 68.354 MB. [2026-02-12T00:21:35.717Z] ====== als (apache-spark) [default], iteration 23 completed (18675.333 ms) ====== [2026-02-12T00:21:35.717Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-12T00:21:35.717Z] GC before operation: completed in 359.971 ms, heap usage 263.378 MB -> 68.711 MB. [2026-02-12T00:21:54.555Z] ====== als (apache-spark) [default], iteration 24 completed (19942.965 ms) ====== [2026-02-12T00:21:54.555Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-12T00:21:54.555Z] GC before operation: completed in 390.493 ms, heap usage 226.859 MB -> 68.990 MB. [2026-02-12T00:22:17.350Z] ====== als (apache-spark) [default], iteration 25 completed (20741.903 ms) ====== [2026-02-12T00:22:17.350Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-12T00:22:17.350Z] GC before operation: completed in 403.548 ms, heap usage 260.213 MB -> 69.198 MB. [2026-02-12T00:22:36.201Z] ====== als (apache-spark) [default], iteration 26 completed (19735.604 ms) ====== [2026-02-12T00:22:36.201Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-12T00:22:36.201Z] GC before operation: completed in 469.711 ms, heap usage 364.055 MB -> 69.546 MB. [2026-02-12T00:22:58.278Z] ====== als (apache-spark) [default], iteration 27 completed (21561.585 ms) ====== [2026-02-12T00:22:58.278Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-12T00:22:59.051Z] GC before operation: completed in 324.048 ms, heap usage 263.077 MB -> 69.237 MB. [2026-02-12T00:23:20.000Z] ====== als (apache-spark) [default], iteration 28 completed (19589.053 ms) ====== [2026-02-12T00:23:20.000Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-12T00:23:20.000Z] GC before operation: completed in 335.619 ms, heap usage 269.170 MB -> 69.481 MB. [2026-02-12T00:23:38.829Z] ====== als (apache-spark) [default], iteration 29 completed (18930.521 ms) ====== [2026-02-12T00:23:40.406Z] ----------------------------------- [2026-02-12T00:23:40.406Z] renaissance-als_0_PASSED [2026-02-12T00:23:40.406Z] ----------------------------------- [2026-02-12T00:23:40.406Z] [2026-02-12T00:23:40.406Z] TEST TEARDOWN: [2026-02-12T00:23:40.406Z] Nothing to be done for teardown. [2026-02-12T00:23:40.406Z] renaissance-als_0 Finish Time: Thu Feb 12 00:23:39 2026 Epoch Time (ms): 1770855819677