[2026-01-21T11:08:32.147Z] Running test renaissance-als_0 ... [2026-01-21T11:08:32.147Z] =============================================== [2026-01-21T11:08:32.147Z] renaissance-als_0 Start Time: Wed Jan 21 11:08:32 2026 Epoch Time (ms): 1768993712066 [2026-01-21T11:08:32.461Z] variation: NoOptions [2026-01-21T11:08:32.461Z] JVM_OPTIONS: [2026-01-21T11:08:32.461Z] { \ [2026-01-21T11:08:32.461Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T11:08:32.461Z] echo "Nothing to be done for setup."; \ [2026-01-21T11:08:32.461Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689933831533\\renaissance-als_0"; \ [2026-01-21T11:08:32.461Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689933831533\\renaissance-als_0"; \ [2026-01-21T11:08:32.461Z] echo ""; echo "TESTING:"; \ [2026-01-21T11:08:32.461Z] "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_17689933831533\\renaissance-als_0"\\als.json" als; \ [2026-01-21T11:08:32.461Z] 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_17689933831533\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T11:08:32.461Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T11:08:32.461Z] echo "Nothing to be done for teardown."; \ [2026-01-21T11:08:32.461Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689933831533\\TestTargetResult"; [2026-01-21T11:08:32.811Z] [2026-01-21T11:08:32.811Z] TEST SETUP: [2026-01-21T11:08:32.811Z] Nothing to be done for setup. [2026-01-21T11:08:32.811Z] [2026-01-21T11:08:32.811Z] TESTING: [2026-01-21T11:08:55.827Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-21T11:09:00.658Z] WARNING: An illegal reflective access operation has occurred [2026-01-21T11:09:00.658Z] 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_17689933831533/renaissance-als_0/harness-110833-15601576804778213599/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-21T11:09:00.658Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-21T11:09:00.658Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-21T11:09:00.658Z] WARNING: All illegal access operations will be denied in a future release [2026-01-21T11:09:03.137Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T11:09:03.137Z] GC before operation: completed in 208.044 ms, heap usage 313.000 MB -> 35.169 MB. [2026-01-21T11:10:15.435Z] ====== als (apache-spark) [default], iteration 0 completed (65747.751 ms) ====== [2026-01-21T11:10:15.435Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T11:10:15.435Z] GC before operation: completed in 227.445 ms, heap usage 97.940 MB -> 60.398 MB. [2026-01-21T11:10:49.369Z] ====== als (apache-spark) [default], iteration 1 completed (37260.811 ms) ====== [2026-01-21T11:10:49.369Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T11:10:49.369Z] GC before operation: completed in 266.554 ms, heap usage 373.352 MB -> 60.957 MB. [2026-01-21T11:11:32.371Z] ====== als (apache-spark) [default], iteration 2 completed (38050.681 ms) ====== [2026-01-21T11:11:32.371Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T11:11:32.371Z] GC before operation: completed in 190.582 ms, heap usage 268.601 MB -> 61.869 MB. [2026-01-21T11:12:05.793Z] ====== als (apache-spark) [default], iteration 3 completed (35245.101 ms) ====== [2026-01-21T11:12:05.793Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T11:12:05.793Z] GC before operation: completed in 257.999 ms, heap usage 345.711 MB -> 62.273 MB. [2026-01-21T11:12:46.194Z] ====== als (apache-spark) [default], iteration 4 completed (36804.475 ms) ====== [2026-01-21T11:12:46.194Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T11:12:46.194Z] GC before operation: completed in 242.169 ms, heap usage 244.029 MB -> 62.926 MB. [2026-01-21T11:13:20.172Z] ====== als (apache-spark) [default], iteration 5 completed (36593.709 ms) ====== [2026-01-21T11:13:20.172Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T11:13:20.172Z] GC before operation: completed in 232.638 ms, heap usage 238.549 MB -> 63.005 MB. [2026-01-21T11:14:01.495Z] ====== als (apache-spark) [default], iteration 6 completed (36248.102 ms) ====== [2026-01-21T11:14:01.495Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T11:14:01.496Z] GC before operation: completed in 254.898 ms, heap usage 255.970 MB -> 63.732 MB. [2026-01-21T11:14:34.829Z] ====== als (apache-spark) [default], iteration 7 completed (35383.795 ms) ====== [2026-01-21T11:14:34.829Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T11:14:34.829Z] GC before operation: completed in 187.056 ms, heap usage 327.882 MB -> 64.628 MB. [2026-01-21T11:15:09.870Z] ====== als (apache-spark) [default], iteration 8 completed (35447.778 ms) ====== [2026-01-21T11:15:09.870Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T11:15:09.870Z] GC before operation: completed in 193.445 ms, heap usage 131.558 MB -> 64.914 MB. [2026-01-21T11:15:44.195Z] ====== als (apache-spark) [default], iteration 9 completed (33355.748 ms) ====== [2026-01-21T11:15:44.195Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T11:15:44.195Z] GC before operation: completed in 269.722 ms, heap usage 247.640 MB -> 65.308 MB. [2026-01-21T11:16:19.153Z] ====== als (apache-spark) [default], iteration 10 completed (34479.102 ms) ====== [2026-01-21T11:16:19.153Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T11:16:19.153Z] GC before operation: completed in 246.945 ms, heap usage 257.590 MB -> 65.664 MB. [2026-01-21T11:16:55.016Z] ====== als (apache-spark) [default], iteration 11 completed (36630.976 ms) ====== [2026-01-21T11:16:55.016Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T11:16:55.016Z] GC before operation: completed in 237.502 ms, heap usage 95.245 MB -> 65.783 MB. [2026-01-21T11:17:29.428Z] ====== als (apache-spark) [default], iteration 12 completed (34765.823 ms) ====== [2026-01-21T11:17:29.428Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T11:17:29.914Z] GC before operation: completed in 222.837 ms, heap usage 237.868 MB -> 66.055 MB. [2026-01-21T11:18:12.899Z] ====== als (apache-spark) [default], iteration 13 completed (36764.016 ms) ====== [2026-01-21T11:18:12.899Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T11:18:12.899Z] GC before operation: completed in 216.085 ms, heap usage 274.117 MB -> 66.297 MB. [2026-01-21T11:18:47.443Z] ====== als (apache-spark) [default], iteration 14 completed (36020.644 ms) ====== [2026-01-21T11:18:47.443Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T11:18:47.443Z] GC before operation: completed in 208.462 ms, heap usage 250.435 MB -> 66.536 MB. [2026-01-21T11:19:21.383Z] ====== als (apache-spark) [default], iteration 15 completed (37265.801 ms) ====== [2026-01-21T11:19:21.383Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T11:19:21.383Z] GC before operation: completed in 200.265 ms, heap usage 115.471 MB -> 66.412 MB. [2026-01-21T11:20:02.331Z] ====== als (apache-spark) [default], iteration 16 completed (35146.275 ms) ====== [2026-01-21T11:20:02.331Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T11:20:02.331Z] GC before operation: completed in 200.316 ms, heap usage 121.258 MB -> 66.498 MB. [2026-01-21T11:20:36.095Z] ====== als (apache-spark) [default], iteration 17 completed (35397.625 ms) ====== [2026-01-21T11:20:36.095Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T11:20:36.095Z] GC before operation: completed in 220.012 ms, heap usage 101.120 MB -> 66.736 MB. [2026-01-21T11:21:10.141Z] ====== als (apache-spark) [default], iteration 18 completed (34593.009 ms) ====== [2026-01-21T11:21:10.141Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T11:21:10.141Z] GC before operation: completed in 202.911 ms, heap usage 160.731 MB -> 66.985 MB. [2026-01-21T11:21:51.633Z] ====== als (apache-spark) [default], iteration 19 completed (36274.620 ms) ====== [2026-01-21T11:21:51.633Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T11:21:51.633Z] GC before operation: completed in 232.440 ms, heap usage 230.232 MB -> 67.799 MB. [2026-01-21T11:22:25.439Z] ====== als (apache-spark) [default], iteration 20 completed (36405.255 ms) ====== [2026-01-21T11:22:25.439Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T11:22:25.439Z] GC before operation: completed in 254.144 ms, heap usage 252.744 MB -> 68.043 MB. [2026-01-21T11:22:59.738Z] ====== als (apache-spark) [default], iteration 21 completed (35916.272 ms) ====== [2026-01-21T11:22:59.738Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T11:22:59.738Z] GC before operation: completed in 191.491 ms, heap usage 162.115 MB -> 68.292 MB. [2026-01-21T11:23:34.991Z] ====== als (apache-spark) [default], iteration 22 completed (35326.333 ms) ====== [2026-01-21T11:23:34.991Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T11:23:35.556Z] GC before operation: completed in 238.266 ms, heap usage 227.896 MB -> 68.297 MB. [2026-01-21T11:24:18.334Z] ====== als (apache-spark) [default], iteration 23 completed (37057.971 ms) ====== [2026-01-21T11:24:18.334Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T11:24:18.334Z] GC before operation: completed in 209.865 ms, heap usage 257.079 MB -> 68.823 MB. [2026-01-21T11:24:52.787Z] ====== als (apache-spark) [default], iteration 24 completed (35991.211 ms) ====== [2026-01-21T11:24:52.787Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T11:24:52.787Z] GC before operation: completed in 237.542 ms, heap usage 256.646 MB -> 69.066 MB. [2026-01-21T11:25:26.702Z] ====== als (apache-spark) [default], iteration 25 completed (35444.641 ms) ====== [2026-01-21T11:25:26.702Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T11:25:26.702Z] GC before operation: completed in 264.282 ms, heap usage 263.741 MB -> 69.311 MB. [2026-01-21T11:26:08.168Z] ====== als (apache-spark) [default], iteration 26 completed (38190.944 ms) ====== [2026-01-21T11:26:08.168Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T11:26:08.168Z] GC before operation: completed in 226.338 ms, heap usage 263.237 MB -> 69.553 MB. [2026-01-21T11:26:42.698Z] ====== als (apache-spark) [default], iteration 27 completed (34251.376 ms) ====== [2026-01-21T11:26:42.698Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T11:26:42.698Z] GC before operation: completed in 258.105 ms, heap usage 258.711 MB -> 69.591 MB. [2026-01-21T11:27:17.725Z] ====== als (apache-spark) [default], iteration 28 completed (35379.543 ms) ====== [2026-01-21T11:27:17.725Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T11:27:17.725Z] GC before operation: completed in 193.739 ms, heap usage 195.635 MB -> 69.448 MB. [2026-01-21T11:27:52.354Z] ====== als (apache-spark) [default], iteration 29 completed (35680.378 ms) ====== [2026-01-21T11:27:52.354Z] [2026-01-21T11:27:51.986+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2026-01-21T11:27:52.354Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk11_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17689933831533\renaissance-als_0\harness-110833-15601576804778213599: harness-110833-15601576804778213599\apache-spark\als\hadoop\bin\hadoop.dll [2026-01-21T11:27:52.354Z] ----------------------------------- [2026-01-21T11:27:52.354Z] renaissance-als_0_PASSED [2026-01-21T11:27:52.354Z] ----------------------------------- [2026-01-21T11:27:52.681Z] [2026-01-21T11:27:52.681Z] TEST TEARDOWN: [2026-01-21T11:27:52.681Z] Nothing to be done for teardown. [2026-01-21T11:27:52.681Z] renaissance-als_0 Finish Time: Wed Jan 21 11:27:52 2026 Epoch Time (ms): 1768994872470