[2026-01-10T10:13:11.891Z] Running test renaissance-als_0 ... [2026-01-10T10:13:11.891Z] =============================================== [2026-01-10T10:13:11.891Z] renaissance-als_0 Start Time: Sat Jan 10 10:13:11 2026 Epoch Time (ms): 1768039991834 [2026-01-10T10:13:12.249Z] variation: NoOptions [2026-01-10T10:13:12.249Z] JVM_OPTIONS: [2026-01-10T10:13:12.249Z] { \ [2026-01-10T10:13:12.249Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T10:13:12.249Z] echo "Nothing to be done for setup."; \ [2026-01-10T10:13:12.249Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-als_0"; \ [2026-01-10T10:13:12.249Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-als_0"; \ [2026-01-10T10:13:12.249Z] echo ""; echo "TESTING:"; \ [2026-01-10T10:13:12.249Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-als_0"\\als.json" als; \ [2026-01-10T10:13:12.249Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T10:13:12.249Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T10:13:12.249Z] echo "Nothing to be done for teardown."; \ [2026-01-10T10:13:12.249Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\TestTargetResult"; [2026-01-10T10:13:12.249Z] [2026-01-10T10:13:12.249Z] TEST SETUP: [2026-01-10T10:13:12.249Z] Nothing to be done for setup. [2026-01-10T10:13:12.249Z] [2026-01-10T10:13:12.249Z] TESTING: [2026-01-10T10:13:28.285Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-10T10:13:30.615Z] WARNING: An illegal reflective access operation has occurred [2026-01-10T10:13:30.615Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17680397902078/renaissance-als_0/harness-101313-3240106222980064772/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-10T10:13:30.615Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-10T10:13:30.615Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-10T10:13:30.615Z] WARNING: All illegal access operations will be denied in a future release [2026-01-10T10:13:31.880Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T10:13:31.880Z] GC before operation: completed in 100.282 ms, heap usage 378.000 MB -> 35.521 MB. [2026-01-10T10:14:05.933Z] ====== als (apache-spark) [default], iteration 0 completed (33303.600 ms) ====== [2026-01-10T10:14:05.933Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T10:14:05.933Z] GC before operation: completed in 133.152 ms, heap usage 235.826 MB -> 60.997 MB. [2026-01-10T10:14:25.625Z] ====== als (apache-spark) [default], iteration 1 completed (18013.212 ms) ====== [2026-01-10T10:14:25.626Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T10:14:25.626Z] GC before operation: completed in 136.530 ms, heap usage 366.490 MB -> 61.897 MB. [2026-01-10T10:14:41.792Z] ====== als (apache-spark) [default], iteration 2 completed (17206.967 ms) ====== [2026-01-10T10:14:41.792Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T10:14:42.272Z] GC before operation: completed in 146.162 ms, heap usage 287.175 MB -> 62.310 MB. [2026-01-10T10:15:02.065Z] ====== als (apache-spark) [default], iteration 3 completed (17060.947 ms) ====== [2026-01-10T10:15:02.065Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T10:15:02.065Z] GC before operation: completed in 132.671 ms, heap usage 181.079 MB -> 62.724 MB. [2026-01-10T10:15:18.243Z] ====== als (apache-spark) [default], iteration 4 completed (17017.275 ms) ====== [2026-01-10T10:15:18.243Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T10:15:18.243Z] GC before operation: completed in 134.739 ms, heap usage 272.198 MB -> 63.355 MB. [2026-01-10T10:15:34.568Z] ====== als (apache-spark) [default], iteration 5 completed (16728.041 ms) ====== [2026-01-10T10:15:34.568Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T10:15:34.568Z] GC before operation: completed in 143.837 ms, heap usage 494.406 MB -> 64.241 MB. [2026-01-10T10:15:54.043Z] ====== als (apache-spark) [default], iteration 6 completed (16994.857 ms) ====== [2026-01-10T10:15:54.044Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T10:15:54.044Z] GC before operation: completed in 137.080 ms, heap usage 257.603 MB -> 64.410 MB. [2026-01-10T10:16:10.376Z] ====== als (apache-spark) [default], iteration 7 completed (16828.851 ms) ====== [2026-01-10T10:16:10.376Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T10:16:10.376Z] GC before operation: completed in 139.618 ms, heap usage 188.832 MB -> 64.941 MB. [2026-01-10T10:16:26.776Z] ====== als (apache-spark) [default], iteration 8 completed (17310.229 ms) ====== [2026-01-10T10:16:26.776Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T10:16:26.776Z] GC before operation: completed in 170.823 ms, heap usage 330.461 MB -> 65.571 MB. [2026-01-10T10:16:43.063Z] ====== als (apache-spark) [default], iteration 9 completed (16623.605 ms) ====== [2026-01-10T10:16:43.063Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T10:16:43.488Z] GC before operation: completed in 155.457 ms, heap usage 368.545 MB -> 65.884 MB. [2026-01-10T10:17:02.948Z] ====== als (apache-spark) [default], iteration 10 completed (16904.120 ms) ====== [2026-01-10T10:17:02.948Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T10:17:02.948Z] GC before operation: completed in 151.435 ms, heap usage 183.228 MB -> 65.870 MB. [2026-01-10T10:17:18.968Z] ====== als (apache-spark) [default], iteration 11 completed (16668.500 ms) ====== [2026-01-10T10:17:18.968Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T10:17:18.968Z] GC before operation: completed in 137.664 ms, heap usage 280.082 MB -> 66.373 MB. [2026-01-10T10:17:35.157Z] ====== als (apache-spark) [default], iteration 12 completed (17043.855 ms) ====== [2026-01-10T10:17:35.157Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T10:17:35.157Z] GC before operation: completed in 139.648 ms, heap usage 258.836 MB -> 66.521 MB. [2026-01-10T10:17:54.795Z] ====== als (apache-spark) [default], iteration 13 completed (17292.308 ms) ====== [2026-01-10T10:17:54.795Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T10:17:54.795Z] GC before operation: completed in 132.017 ms, heap usage 276.637 MB -> 66.737 MB. [2026-01-10T10:18:10.960Z] ====== als (apache-spark) [default], iteration 14 completed (16758.693 ms) ====== [2026-01-10T10:18:10.960Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T10:18:10.960Z] GC before operation: completed in 146.341 ms, heap usage 99.407 MB -> 66.870 MB. [2026-01-10T10:18:30.457Z] ====== als (apache-spark) [default], iteration 15 completed (17892.347 ms) ====== [2026-01-10T10:18:30.457Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T10:18:30.457Z] GC before operation: completed in 146.657 ms, heap usage 265.057 MB -> 67.368 MB. [2026-01-10T10:18:46.657Z] ====== als (apache-spark) [default], iteration 16 completed (16548.609 ms) ====== [2026-01-10T10:18:46.657Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T10:18:46.657Z] GC before operation: completed in 158.819 ms, heap usage 362.440 MB -> 67.604 MB. [2026-01-10T10:19:02.852Z] ====== als (apache-spark) [default], iteration 17 completed (17618.859 ms) ====== [2026-01-10T10:19:02.852Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T10:19:02.852Z] GC before operation: completed in 144.047 ms, heap usage 324.396 MB -> 67.869 MB. [2026-01-10T10:19:22.445Z] ====== als (apache-spark) [default], iteration 18 completed (16789.784 ms) ====== [2026-01-10T10:19:22.445Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T10:19:22.445Z] GC before operation: completed in 148.284 ms, heap usage 115.911 MB -> 67.880 MB. [2026-01-10T10:19:38.662Z] ====== als (apache-spark) [default], iteration 19 completed (17120.032 ms) ====== [2026-01-10T10:19:38.662Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T10:19:38.662Z] GC before operation: completed in 146.310 ms, heap usage 228.377 MB -> 68.157 MB. [2026-01-10T10:19:54.960Z] ====== als (apache-spark) [default], iteration 20 completed (16765.860 ms) ====== [2026-01-10T10:19:54.960Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T10:19:54.960Z] GC before operation: completed in 151.275 ms, heap usage 279.271 MB -> 68.641 MB. [2026-01-10T10:20:14.536Z] ====== als (apache-spark) [default], iteration 21 completed (18712.302 ms) ====== [2026-01-10T10:20:14.536Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T10:20:14.536Z] GC before operation: completed in 145.075 ms, heap usage 267.993 MB -> 68.840 MB. [2026-01-10T10:20:30.921Z] ====== als (apache-spark) [default], iteration 22 completed (17094.584 ms) ====== [2026-01-10T10:20:30.922Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T10:20:30.922Z] GC before operation: completed in 157.184 ms, heap usage 101.144 MB -> 68.921 MB. [2026-01-10T10:20:50.569Z] ====== als (apache-spark) [default], iteration 23 completed (16528.609 ms) ====== [2026-01-10T10:20:50.569Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T10:20:50.569Z] GC before operation: completed in 162.951 ms, heap usage 263.830 MB -> 69.286 MB. [2026-01-10T10:21:06.898Z] ====== als (apache-spark) [default], iteration 24 completed (16969.565 ms) ====== [2026-01-10T10:21:06.898Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T10:21:06.898Z] GC before operation: completed in 154.175 ms, heap usage 286.836 MB -> 69.535 MB. [2026-01-10T10:21:23.157Z] ====== als (apache-spark) [default], iteration 25 completed (16980.153 ms) ====== [2026-01-10T10:21:23.157Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T10:21:23.157Z] GC before operation: completed in 149.844 ms, heap usage 283.158 MB -> 69.773 MB. [2026-01-10T10:21:39.577Z] ====== als (apache-spark) [default], iteration 26 completed (16768.874 ms) ====== [2026-01-10T10:21:39.577Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T10:21:39.577Z] GC before operation: completed in 166.858 ms, heap usage 234.574 MB -> 69.900 MB. [2026-01-10T10:21:58.945Z] ====== als (apache-spark) [default], iteration 27 completed (16558.835 ms) ====== [2026-01-10T10:21:58.945Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T10:21:58.945Z] GC before operation: completed in 156.533 ms, heap usage 294.246 MB -> 69.801 MB. [2026-01-10T10:22:15.352Z] ====== als (apache-spark) [default], iteration 28 completed (16973.663 ms) ====== [2026-01-10T10:22:15.352Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T10:22:15.352Z] GC before operation: completed in 154.498 ms, heap usage 324.097 MB -> 70.047 MB. [2026-01-10T10:22:31.471Z] ====== als (apache-spark) [default], iteration 29 completed (16576.171 ms) ====== [2026-01-10T10:22:31.471Z] ----------------------------------- [2026-01-10T10:22:31.471Z] renaissance-als_0_PASSED [2026-01-10T10:22:31.471Z] ----------------------------------- [2026-01-10T10:22:31.471Z] [2026-01-10T10:22:31.471Z] TEST TEARDOWN: [2026-01-10T10:22:31.471Z] Nothing to be done for teardown. [2026-01-10T10:22:31.471Z] renaissance-als_0 Finish Time: Sat Jan 10 10:22:30 2026 Epoch Time (ms): 1768040550981