[2026-01-21T04:41:35.920Z] Running test renaissance-als_0 ... [2026-01-21T04:41:35.920Z] =============================================== [2026-01-21T04:41:35.920Z] renaissance-als_0 Start Time: Wed Jan 21 04:41:35 2026 Epoch Time (ms): 1768970495846 [2026-01-21T04:41:36.287Z] variation: NoOptions [2026-01-21T04:41:36.287Z] JVM_OPTIONS: [2026-01-21T04:41:36.287Z] { \ [2026-01-21T04:41:36.287Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T04:41:36.287Z] echo "Nothing to be done for setup."; \ [2026-01-21T04:41:36.287Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689702115571\\renaissance-als_0"; \ [2026-01-21T04:41:36.287Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689702115571\\renaissance-als_0"; \ [2026-01-21T04:41:36.287Z] echo ""; echo "TESTING:"; \ [2026-01-21T04:41:36.287Z] "c:/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689702115571\\renaissance-als_0"\\als.json" als; \ [2026-01-21T04:41:36.287Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689702115571\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T04:41:36.287Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T04:41:36.287Z] echo "Nothing to be done for teardown."; \ [2026-01-21T04:41:36.287Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689702115571\\TestTargetResult"; [2026-01-21T04:41:36.287Z] [2026-01-21T04:41:36.287Z] TEST SETUP: [2026-01-21T04:41:36.287Z] Nothing to be done for setup. [2026-01-21T04:41:36.287Z] [2026-01-21T04:41:36.287Z] TESTING: [2026-01-21T04:41:52.241Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-21T04:41:55.342Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T04:41:55.342Z] GC before operation: completed in 40.688 ms, heap usage 218.500 MB -> 35.547 MB. [2026-01-21T04:42:29.473Z] ====== als (apache-spark) [default], iteration 0 completed (28676.035 ms) ====== [2026-01-21T04:42:29.473Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T04:42:29.473Z] GC before operation: completed in 81.542 ms, heap usage 117.226 MB -> 61.999 MB. [2026-01-21T04:42:42.791Z] ====== als (apache-spark) [default], iteration 1 completed (16965.112 ms) ====== [2026-01-21T04:42:42.791Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T04:42:42.791Z] GC before operation: completed in 76.764 ms, heap usage 464.661 MB -> 63.566 MB. [2026-01-21T04:42:58.876Z] ====== als (apache-spark) [default], iteration 2 completed (16530.441 ms) ====== [2026-01-21T04:42:58.876Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T04:42:58.876Z] GC before operation: completed in 74.535 ms, heap usage 331.264 MB -> 63.983 MB. [2026-01-21T04:43:15.182Z] ====== als (apache-spark) [default], iteration 3 completed (15969.577 ms) ====== [2026-01-21T04:43:15.182Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T04:43:15.182Z] GC before operation: completed in 75.434 ms, heap usage 362.079 MB -> 64.518 MB. [2026-01-21T04:43:31.373Z] ====== als (apache-spark) [default], iteration 4 completed (15976.693 ms) ====== [2026-01-21T04:43:31.374Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T04:43:31.374Z] GC before operation: completed in 85.447 ms, heap usage 158.834 MB -> 64.883 MB. [2026-01-21T04:43:47.525Z] ====== als (apache-spark) [default], iteration 5 completed (15889.739 ms) ====== [2026-01-21T04:43:47.525Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T04:43:47.525Z] GC before operation: completed in 94.477 ms, heap usage 130.573 MB -> 64.977 MB. [2026-01-21T04:44:03.706Z] ====== als (apache-spark) [default], iteration 6 completed (16037.547 ms) ====== [2026-01-21T04:44:03.706Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T04:44:03.706Z] GC before operation: completed in 80.508 ms, heap usage 354.098 MB -> 66.226 MB. [2026-01-21T04:44:19.788Z] ====== als (apache-spark) [default], iteration 7 completed (15826.629 ms) ====== [2026-01-21T04:44:19.788Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T04:44:19.788Z] GC before operation: completed in 102.300 ms, heap usage 205.007 MB -> 66.551 MB. [2026-01-21T04:44:35.837Z] ====== als (apache-spark) [default], iteration 8 completed (16163.309 ms) ====== [2026-01-21T04:44:35.837Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T04:44:35.837Z] GC before operation: completed in 90.036 ms, heap usage 455.590 MB -> 67.497 MB. [2026-01-21T04:44:53.438Z] ====== als (apache-spark) [default], iteration 9 completed (16321.726 ms) ====== [2026-01-21T04:44:53.438Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T04:44:53.438Z] GC before operation: completed in 77.889 ms, heap usage 117.852 MB -> 66.833 MB. [2026-01-21T04:45:09.645Z] ====== als (apache-spark) [default], iteration 10 completed (16079.679 ms) ====== [2026-01-21T04:45:09.645Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T04:45:09.645Z] GC before operation: completed in 77.318 ms, heap usage 463.042 MB -> 67.941 MB. [2026-01-21T04:45:25.652Z] ====== als (apache-spark) [default], iteration 11 completed (15833.965 ms) ====== [2026-01-21T04:45:25.652Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T04:45:25.652Z] GC before operation: completed in 97.496 ms, heap usage 205.198 MB -> 67.761 MB. [2026-01-21T04:45:41.817Z] ====== als (apache-spark) [default], iteration 12 completed (15975.028 ms) ====== [2026-01-21T04:45:41.817Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T04:45:41.817Z] GC before operation: completed in 77.790 ms, heap usage 302.940 MB -> 68.295 MB. [2026-01-21T04:45:58.055Z] ====== als (apache-spark) [default], iteration 13 completed (16017.461 ms) ====== [2026-01-21T04:45:58.055Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T04:45:58.055Z] GC before operation: completed in 78.801 ms, heap usage 460.128 MB -> 68.804 MB. [2026-01-21T04:46:14.220Z] ====== als (apache-spark) [default], iteration 14 completed (15736.724 ms) ====== [2026-01-21T04:46:14.220Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T04:46:14.220Z] GC before operation: completed in 81.336 ms, heap usage 337.329 MB -> 68.871 MB. [2026-01-21T04:46:30.493Z] ====== als (apache-spark) [default], iteration 15 completed (16050.775 ms) ====== [2026-01-21T04:46:30.493Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T04:46:30.493Z] GC before operation: completed in 78.611 ms, heap usage 460.487 MB -> 69.414 MB. [2026-01-21T04:46:46.670Z] ====== als (apache-spark) [default], iteration 16 completed (15877.103 ms) ====== [2026-01-21T04:46:46.670Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T04:46:46.670Z] GC before operation: completed in 81.175 ms, heap usage 439.493 MB -> 69.582 MB. [2026-01-21T04:47:02.916Z] ====== als (apache-spark) [default], iteration 17 completed (15394.642 ms) ====== [2026-01-21T04:47:02.916Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T04:47:02.916Z] GC before operation: completed in 78.175 ms, heap usage 371.696 MB -> 69.580 MB. [2026-01-21T04:47:19.116Z] ====== als (apache-spark) [default], iteration 18 completed (15758.189 ms) ====== [2026-01-21T04:47:19.116Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T04:47:19.116Z] GC before operation: completed in 76.451 ms, heap usage 130.800 MB -> 69.746 MB. [2026-01-21T04:47:35.145Z] ====== als (apache-spark) [default], iteration 19 completed (15586.900 ms) ====== [2026-01-21T04:47:35.145Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T04:47:35.145Z] GC before operation: completed in 78.406 ms, heap usage 465.755 MB -> 70.338 MB. [2026-01-21T04:47:51.242Z] ====== als (apache-spark) [default], iteration 20 completed (16546.052 ms) ====== [2026-01-21T04:47:51.242Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T04:47:51.242Z] GC before operation: completed in 85.370 ms, heap usage 501.932 MB -> 70.622 MB. [2026-01-21T04:48:07.338Z] ====== als (apache-spark) [default], iteration 21 completed (15566.980 ms) ====== [2026-01-21T04:48:07.338Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T04:48:07.338Z] GC before operation: completed in 76.951 ms, heap usage 331.151 MB -> 70.694 MB. [2026-01-21T04:48:23.406Z] ====== als (apache-spark) [default], iteration 22 completed (15659.280 ms) ====== [2026-01-21T04:48:23.406Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T04:48:23.406Z] GC before operation: completed in 80.742 ms, heap usage 509.916 MB -> 71.100 MB. [2026-01-21T04:48:39.442Z] ====== als (apache-spark) [default], iteration 23 completed (15829.299 ms) ====== [2026-01-21T04:48:39.442Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T04:48:39.442Z] GC before operation: completed in 79.700 ms, heap usage 511.181 MB -> 71.371 MB. [2026-01-21T04:48:55.443Z] ====== als (apache-spark) [default], iteration 24 completed (16044.793 ms) ====== [2026-01-21T04:48:55.443Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T04:48:55.443Z] GC before operation: completed in 79.478 ms, heap usage 508.652 MB -> 71.677 MB. [2026-01-21T04:49:11.497Z] ====== als (apache-spark) [default], iteration 25 completed (15817.148 ms) ====== [2026-01-21T04:49:11.497Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T04:49:11.497Z] GC before operation: completed in 79.390 ms, heap usage 161.162 MB -> 71.467 MB. [2026-01-21T04:49:27.555Z] ====== als (apache-spark) [default], iteration 26 completed (15366.089 ms) ====== [2026-01-21T04:49:27.555Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T04:49:27.555Z] GC before operation: completed in 75.846 ms, heap usage 256.463 MB -> 71.850 MB. [2026-01-21T04:49:43.570Z] ====== als (apache-spark) [default], iteration 27 completed (16013.771 ms) ====== [2026-01-21T04:49:43.570Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T04:49:43.570Z] GC before operation: completed in 76.520 ms, heap usage 249.352 MB -> 72.105 MB. [2026-01-21T04:49:59.743Z] ====== als (apache-spark) [default], iteration 28 completed (15313.628 ms) ====== [2026-01-21T04:49:59.743Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T04:49:59.743Z] GC before operation: completed in 77.479 ms, heap usage 364.225 MB -> 72.034 MB. [2026-01-21T04:50:13.112Z] ====== als (apache-spark) [default], iteration 29 completed (15625.533 ms) ====== [2026-01-21T04:50:13.864Z] ----------------------------------- [2026-01-21T04:50:13.864Z] renaissance-als_0_PASSED [2026-01-21T04:50:13.864Z] ----------------------------------- [2026-01-21T04:50:14.222Z] [2026-01-21T04:50:14.222Z] TEST TEARDOWN: [2026-01-21T04:50:14.222Z] Nothing to be done for teardown. [2026-01-21T04:50:14.222Z] renaissance-als_0 Finish Time: Wed Jan 21 04:50:13 2026 Epoch Time (ms): 1768971013974