[2026-02-06T22:14:36.298Z] Running test renaissance-als_0 ... [2026-02-06T22:14:36.298Z] =============================================== [2026-02-06T22:14:36.298Z] renaissance-als_0 Start Time: Fri Feb 6 22:14:36 2026 Epoch Time (ms): 1770416076193 [2026-02-06T22:14:36.298Z] variation: NoOptions [2026-02-06T22:14:36.298Z] JVM_OPTIONS: [2026-02-06T22:14:36.298Z] { \ [2026-02-06T22:14:36.298Z] echo ""; echo "TEST SETUP:"; \ [2026-02-06T22:14:36.298Z] echo "Nothing to be done for setup."; \ [2026-02-06T22:14:36.298Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17704158798798/renaissance-als_0"; \ [2026-02-06T22:14:36.298Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17704158798798/renaissance-als_0"; \ [2026-02-06T22:14:36.298Z] echo ""; echo "TESTING:"; \ [2026-02-06T22:14:36.298Z] "/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_17704158798798/renaissance-als_0"/als.json" als; \ [2026-02-06T22:14:36.298Z] 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_17704158798798/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-06T22:14:36.298Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-06T22:14:36.298Z] echo "Nothing to be done for teardown."; \ [2026-02-06T22:14:36.298Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17704158798798/TestTargetResult"; [2026-02-06T22:14:36.298Z] [2026-02-06T22:14:36.298Z] TEST SETUP: [2026-02-06T22:14:36.298Z] Nothing to be done for setup. [2026-02-06T22:14:36.298Z] [2026-02-06T22:14:36.298Z] TESTING: [2026-02-06T22:14:42.233Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-02-06T22:14:45.298Z] WARNING: An illegal reflective access operation has occurred [2026-02-06T22:14:45.298Z] 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_17704158798798/renaissance-als_0/harness-221436-4910795300227109831/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-06T22:14:45.298Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-06T22:14:45.298Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-06T22:14:45.298Z] WARNING: All illegal access operations will be denied in a future release [2026-02-06T22:14:46.483Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-06T22:14:46.483Z] GC before operation: completed in 91.672 ms, heap usage 252.025 MB -> 34.233 MB. [2026-02-06T22:15:19.912Z] ====== als (apache-spark) [default], iteration 0 completed (28921.458 ms) ====== [2026-02-06T22:15:19.912Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-06T22:15:19.912Z] GC before operation: completed in 136.042 ms, heap usage 122.354 MB -> 59.951 MB. [2026-02-06T22:15:33.136Z] ====== als (apache-spark) [default], iteration 1 completed (14636.524 ms) ====== [2026-02-06T22:15:33.136Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-06T22:15:33.136Z] GC before operation: completed in 137.388 ms, heap usage 223.924 MB -> 60.888 MB. [2026-02-06T22:15:44.078Z] ====== als (apache-spark) [default], iteration 2 completed (11925.689 ms) ====== [2026-02-06T22:15:44.078Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-06T22:15:44.078Z] GC before operation: completed in 160.376 ms, heap usage 165.436 MB -> 61.325 MB. [2026-02-06T22:15:57.266Z] ====== als (apache-spark) [default], iteration 3 completed (12020.732 ms) ====== [2026-02-06T22:15:57.266Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-06T22:15:57.266Z] GC before operation: completed in 154.494 ms, heap usage 185.657 MB -> 61.866 MB. [2026-02-06T22:16:10.486Z] ====== als (apache-spark) [default], iteration 4 completed (11924.949 ms) ====== [2026-02-06T22:16:10.486Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-06T22:16:10.486Z] GC before operation: completed in 154.952 ms, heap usage 241.834 MB -> 62.494 MB. [2026-02-06T22:16:21.349Z] ====== als (apache-spark) [default], iteration 5 completed (11634.867 ms) ====== [2026-02-06T22:16:21.349Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-06T22:16:21.349Z] GC before operation: completed in 146.568 ms, heap usage 165.897 MB -> 62.904 MB. [2026-02-06T22:16:32.241Z] ====== als (apache-spark) [default], iteration 6 completed (10831.451 ms) ====== [2026-02-06T22:16:32.241Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-06T22:16:32.241Z] GC before operation: completed in 144.037 ms, heap usage 122.390 MB -> 63.453 MB. [2026-02-06T22:16:45.410Z] ====== als (apache-spark) [default], iteration 7 completed (11116.356 ms) ====== [2026-02-06T22:16:45.410Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-06T22:16:45.410Z] GC before operation: completed in 156.342 ms, heap usage 232.084 MB -> 64.101 MB. [2026-02-06T22:16:56.259Z] ====== als (apache-spark) [default], iteration 8 completed (10932.703 ms) ====== [2026-02-06T22:16:56.259Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-06T22:16:56.259Z] GC before operation: completed in 167.088 ms, heap usage 369.967 MB -> 64.745 MB. [2026-02-06T22:17:07.145Z] ====== als (apache-spark) [default], iteration 9 completed (11301.384 ms) ====== [2026-02-06T22:17:07.145Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-06T22:17:07.145Z] GC before operation: completed in 159.972 ms, heap usage 267.815 MB -> 64.909 MB. [2026-02-06T22:17:20.287Z] ====== als (apache-spark) [default], iteration 10 completed (11049.076 ms) ====== [2026-02-06T22:17:20.287Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-06T22:17:20.287Z] GC before operation: completed in 160.688 ms, heap usage 267.151 MB -> 65.154 MB. [2026-02-06T22:17:29.257Z] ====== als (apache-spark) [default], iteration 11 completed (10657.964 ms) ====== [2026-02-06T22:17:29.257Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-06T22:17:29.593Z] GC before operation: completed in 162.741 ms, heap usage 155.666 MB -> 65.295 MB. [2026-02-06T22:17:42.828Z] ====== als (apache-spark) [default], iteration 12 completed (11020.435 ms) ====== [2026-02-06T22:17:42.828Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-06T22:17:42.828Z] GC before operation: completed in 156.448 ms, heap usage 133.118 MB -> 65.543 MB. [2026-02-06T22:17:53.716Z] ====== als (apache-spark) [default], iteration 13 completed (11132.596 ms) ====== [2026-02-06T22:17:53.716Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-06T22:17:53.716Z] GC before operation: completed in 154.905 ms, heap usage 106.575 MB -> 65.787 MB. [2026-02-06T22:18:04.588Z] ====== als (apache-spark) [default], iteration 14 completed (10721.474 ms) ====== [2026-02-06T22:18:04.588Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-06T22:18:04.588Z] GC before operation: completed in 231.668 ms, heap usage 283.195 MB -> 66.179 MB. [2026-02-06T22:18:15.429Z] ====== als (apache-spark) [default], iteration 15 completed (10590.176 ms) ====== [2026-02-06T22:18:15.429Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-06T22:18:15.429Z] GC before operation: completed in 155.069 ms, heap usage 266.639 MB -> 66.467 MB. [2026-02-06T22:18:26.349Z] ====== als (apache-spark) [default], iteration 16 completed (10897.620 ms) ====== [2026-02-06T22:18:26.349Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-06T22:18:26.349Z] GC before operation: completed in 153.445 ms, heap usage 142.313 MB -> 66.531 MB. [2026-02-06T22:18:37.196Z] ====== als (apache-spark) [default], iteration 17 completed (10235.246 ms) ====== [2026-02-06T22:18:37.196Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-06T22:18:37.196Z] GC before operation: completed in 163.068 ms, heap usage 210.769 MB -> 66.893 MB. [2026-02-06T22:18:48.033Z] ====== als (apache-spark) [default], iteration 18 completed (10737.409 ms) ====== [2026-02-06T22:18:48.033Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-06T22:18:48.033Z] GC before operation: completed in 153.541 ms, heap usage 169.775 MB -> 67.048 MB. [2026-02-06T22:19:01.299Z] ====== als (apache-spark) [default], iteration 19 completed (11385.128 ms) ====== [2026-02-06T22:19:01.299Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-06T22:19:01.299Z] GC before operation: completed in 147.266 ms, heap usage 212.034 MB -> 67.382 MB. [2026-02-06T22:19:12.234Z] ====== als (apache-spark) [default], iteration 20 completed (11677.267 ms) ====== [2026-02-06T22:19:12.234Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-06T22:19:12.234Z] GC before operation: completed in 155.633 ms, heap usage 300.579 MB -> 67.626 MB. [2026-02-06T22:19:25.536Z] ====== als (apache-spark) [default], iteration 21 completed (11441.973 ms) ====== [2026-02-06T22:19:25.536Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-06T22:19:25.536Z] GC before operation: completed in 170.905 ms, heap usage 220.343 MB -> 67.864 MB. [2026-02-06T22:19:36.417Z] ====== als (apache-spark) [default], iteration 22 completed (10952.508 ms) ====== [2026-02-06T22:19:36.417Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-06T22:19:36.417Z] GC before operation: completed in 157.896 ms, heap usage 177.347 MB -> 67.994 MB. [2026-02-06T22:19:47.247Z] ====== als (apache-spark) [default], iteration 23 completed (10681.379 ms) ====== [2026-02-06T22:19:47.247Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-06T22:19:47.247Z] GC before operation: completed in 164.221 ms, heap usage 371.960 MB -> 68.487 MB. [2026-02-06T22:19:58.246Z] ====== als (apache-spark) [default], iteration 24 completed (10939.759 ms) ====== [2026-02-06T22:19:58.246Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-06T22:19:58.246Z] GC before operation: completed in 162.283 ms, heap usage 234.539 MB -> 68.627 MB. [2026-02-06T22:20:09.123Z] ====== als (apache-spark) [default], iteration 25 completed (11066.688 ms) ====== [2026-02-06T22:20:09.123Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-06T22:20:09.123Z] GC before operation: completed in 159.544 ms, heap usage 226.127 MB -> 68.876 MB. [2026-02-06T22:20:19.981Z] ====== als (apache-spark) [default], iteration 26 completed (10572.413 ms) ====== [2026-02-06T22:20:19.981Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-06T22:20:19.981Z] GC before operation: completed in 154.325 ms, heap usage 123.615 MB -> 69.002 MB. [2026-02-06T22:20:30.831Z] ====== als (apache-spark) [default], iteration 27 completed (10568.654 ms) ====== [2026-02-06T22:20:30.831Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-06T22:20:30.831Z] GC before operation: completed in 162.213 ms, heap usage 158.352 MB -> 69.245 MB. [2026-02-06T22:20:43.982Z] ====== als (apache-spark) [default], iteration 28 completed (11299.238 ms) ====== [2026-02-06T22:20:43.982Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-06T22:20:43.982Z] GC before operation: completed in 149.096 ms, heap usage 132.903 MB -> 69.026 MB. [2026-02-06T22:20:54.828Z] ====== als (apache-spark) [default], iteration 29 completed (10764.841 ms) ====== [2026-02-06T22:20:54.828Z] ----------------------------------- [2026-02-06T22:20:54.828Z] renaissance-als_0_PASSED [2026-02-06T22:20:54.828Z] ----------------------------------- [2026-02-06T22:20:54.828Z] [2026-02-06T22:20:54.828Z] TEST TEARDOWN: [2026-02-06T22:20:54.828Z] Nothing to be done for teardown. [2026-02-06T22:20:54.828Z] renaissance-als_0 Finish Time: Fri Feb 6 22:20:53 2026 Epoch Time (ms): 1770416453738