[2025-11-05T23:04:41.726Z] Running test renaissance-als_0 ... [2025-11-05T23:04:41.726Z] =============================================== [2025-11-05T23:04:42.078Z] renaissance-als_0 Start Time: Wed Nov 5 23:04:41 2025 Epoch Time (ms): 1762383881768 [2025-11-05T23:04:42.078Z] variation: NoOptions [2025-11-05T23:04:42.078Z] JVM_OPTIONS: [2025-11-05T23:04:42.078Z] { \ [2025-11-05T23:04:42.078Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T23:04:42.078Z] echo "Nothing to be done for setup."; \ [2025-11-05T23:04:42.078Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-als_0"; \ [2025-11-05T23:04:42.078Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-als_0"; \ [2025-11-05T23:04:42.078Z] echo ""; echo "TESTING:"; \ [2025-11-05T23:04:42.078Z] "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_17623836678970\\renaissance-als_0"\\als.json" als; \ [2025-11-05T23:04:42.078Z] 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_17623836678970\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T23:04:42.078Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T23:04:42.078Z] echo "Nothing to be done for teardown."; \ [2025-11-05T23:04:42.078Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\TestTargetResult"; [2025-11-05T23:04:42.444Z] [2025-11-05T23:04:42.444Z] TEST SETUP: [2025-11-05T23:04:42.444Z] Nothing to be done for setup. [2025-11-05T23:04:42.444Z] [2025-11-05T23:04:42.444Z] TESTING: [2025-11-05T23:04:58.546Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-05T23:05:01.611Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-05T23:05:01.611Z] GC before operation: completed in 39.214 ms, heap usage 48.997 MB -> 35.538 MB. [2025-11-05T23:05:35.929Z] ====== als (apache-spark) [default], iteration 0 completed (30021.938 ms) ====== [2025-11-05T23:05:35.929Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-05T23:05:35.929Z] GC before operation: completed in 103.403 ms, heap usage 106.901 MB -> 62.347 MB. [2025-11-05T23:05:49.352Z] ====== als (apache-spark) [default], iteration 1 completed (17138.165 ms) ====== [2025-11-05T23:05:49.352Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-05T23:05:49.352Z] GC before operation: completed in 80.573 ms, heap usage 243.163 MB -> 63.223 MB. [2025-11-05T23:06:09.086Z] ====== als (apache-spark) [default], iteration 2 completed (16621.699 ms) ====== [2025-11-05T23:06:09.086Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-05T23:06:09.086Z] GC before operation: completed in 82.635 ms, heap usage 506.124 MB -> 64.148 MB. [2025-11-05T23:06:22.498Z] ====== als (apache-spark) [default], iteration 3 completed (15834.501 ms) ====== [2025-11-05T23:06:22.498Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-05T23:06:22.498Z] GC before operation: completed in 83.389 ms, heap usage 112.397 MB -> 63.877 MB. [2025-11-05T23:06:42.067Z] ====== als (apache-spark) [default], iteration 4 completed (16336.240 ms) ====== [2025-11-05T23:06:42.067Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-05T23:06:42.067Z] GC before operation: completed in 86.178 ms, heap usage 472.670 MB -> 65.299 MB. [2025-11-05T23:06:55.713Z] ====== als (apache-spark) [default], iteration 5 completed (15839.966 ms) ====== [2025-11-05T23:06:55.713Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-05T23:06:55.713Z] GC before operation: completed in 86.805 ms, heap usage 495.769 MB -> 65.882 MB. [2025-11-05T23:07:15.272Z] ====== als (apache-spark) [default], iteration 6 completed (16637.718 ms) ====== [2025-11-05T23:07:15.272Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-05T23:07:15.272Z] GC before operation: completed in 107.430 ms, heap usage 134.696 MB -> 65.955 MB. [2025-11-05T23:07:28.665Z] ====== als (apache-spark) [default], iteration 7 completed (15575.681 ms) ====== [2025-11-05T23:07:28.665Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-05T23:07:28.665Z] GC before operation: completed in 88.631 ms, heap usage 489.229 MB -> 67.007 MB. [2025-11-05T23:07:45.032Z] ====== als (apache-spark) [default], iteration 8 completed (15951.004 ms) ====== [2025-11-05T23:07:45.032Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-05T23:07:45.032Z] GC before operation: completed in 87.100 ms, heap usage 329.125 MB -> 67.312 MB. [2025-11-05T23:08:01.255Z] ====== als (apache-spark) [default], iteration 9 completed (15786.846 ms) ====== [2025-11-05T23:08:01.255Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-05T23:08:01.255Z] GC before operation: completed in 95.585 ms, heap usage 321.100 MB -> 67.559 MB. [2025-11-05T23:08:17.540Z] ====== als (apache-spark) [default], iteration 10 completed (15401.347 ms) ====== [2025-11-05T23:08:17.540Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-05T23:08:17.540Z] GC before operation: completed in 88.399 ms, heap usage 487.935 MB -> 67.980 MB. [2025-11-05T23:08:33.715Z] ====== als (apache-spark) [default], iteration 11 completed (16023.232 ms) ====== [2025-11-05T23:08:33.715Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-05T23:08:33.715Z] GC before operation: completed in 96.830 ms, heap usage 335.742 MB -> 68.115 MB. [2025-11-05T23:08:49.942Z] ====== als (apache-spark) [default], iteration 12 completed (15460.320 ms) ====== [2025-11-05T23:08:49.942Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-05T23:08:49.942Z] GC before operation: completed in 87.430 ms, heap usage 130.331 MB -> 68.066 MB. [2025-11-05T23:09:06.022Z] ====== als (apache-spark) [default], iteration 13 completed (16579.484 ms) ====== [2025-11-05T23:09:06.022Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-05T23:09:06.022Z] GC before operation: completed in 82.835 ms, heap usage 210.376 MB -> 68.376 MB. [2025-11-05T23:09:22.357Z] ====== als (apache-spark) [default], iteration 14 completed (15487.734 ms) ====== [2025-11-05T23:09:22.357Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-05T23:09:22.357Z] GC before operation: completed in 78.369 ms, heap usage 232.682 MB -> 68.712 MB. [2025-11-05T23:09:38.613Z] ====== als (apache-spark) [default], iteration 15 completed (15485.056 ms) ====== [2025-11-05T23:09:38.613Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-05T23:09:38.613Z] GC before operation: completed in 80.733 ms, heap usage 512.576 MB -> 69.344 MB. [2025-11-05T23:09:54.757Z] ====== als (apache-spark) [default], iteration 16 completed (15932.938 ms) ====== [2025-11-05T23:09:54.757Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-05T23:09:54.757Z] GC before operation: completed in 79.027 ms, heap usage 325.071 MB -> 69.425 MB. [2025-11-05T23:10:08.326Z] ====== als (apache-spark) [default], iteration 17 completed (15447.022 ms) ====== [2025-11-05T23:10:08.326Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-05T23:10:08.326Z] GC before operation: completed in 81.975 ms, heap usage 213.945 MB -> 69.431 MB. [2025-11-05T23:10:24.592Z] ====== als (apache-spark) [default], iteration 18 completed (15772.381 ms) ====== [2025-11-05T23:10:24.592Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-05T23:10:24.592Z] GC before operation: completed in 79.419 ms, heap usage 174.370 MB -> 69.694 MB. [2025-11-05T23:10:40.685Z] ====== als (apache-spark) [default], iteration 19 completed (15821.254 ms) ====== [2025-11-05T23:10:40.685Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-05T23:10:40.685Z] GC before operation: completed in 84.573 ms, heap usage 463.101 MB -> 70.416 MB. [2025-11-05T23:11:00.242Z] ====== als (apache-spark) [default], iteration 20 completed (16699.214 ms) ====== [2025-11-05T23:11:00.242Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-05T23:11:00.242Z] GC before operation: completed in 83.960 ms, heap usage 232.955 MB -> 70.387 MB. [2025-11-05T23:11:13.891Z] ====== als (apache-spark) [default], iteration 21 completed (15599.409 ms) ====== [2025-11-05T23:11:13.891Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-05T23:11:13.891Z] GC before operation: completed in 81.132 ms, heap usage 274.428 MB -> 70.617 MB. [2025-11-05T23:11:29.942Z] ====== als (apache-spark) [default], iteration 22 completed (15617.673 ms) ====== [2025-11-05T23:11:29.942Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-05T23:11:29.942Z] GC before operation: completed in 91.516 ms, heap usage 130.501 MB -> 70.855 MB. [2025-11-05T23:11:46.217Z] ====== als (apache-spark) [default], iteration 23 completed (15749.804 ms) ====== [2025-11-05T23:11:46.217Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-05T23:11:46.217Z] GC before operation: completed in 89.651 ms, heap usage 477.195 MB -> 71.669 MB. [2025-11-05T23:12:02.627Z] ====== als (apache-spark) [default], iteration 24 completed (15769.554 ms) ====== [2025-11-05T23:12:02.627Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-05T23:12:02.627Z] GC before operation: completed in 79.247 ms, heap usage 223.749 MB -> 71.486 MB. [2025-11-05T23:12:18.856Z] ====== als (apache-spark) [default], iteration 25 completed (15671.508 ms) ====== [2025-11-05T23:12:18.856Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-05T23:12:18.856Z] GC before operation: completed in 80.249 ms, heap usage 512.396 MB -> 71.957 MB. [2025-11-05T23:12:32.203Z] ====== als (apache-spark) [default], iteration 26 completed (15202.159 ms) ====== [2025-11-05T23:12:32.204Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-05T23:12:32.204Z] GC before operation: completed in 79.120 ms, heap usage 486.473 MB -> 72.230 MB. [2025-11-05T23:12:48.599Z] ====== als (apache-spark) [default], iteration 27 completed (15926.769 ms) ====== [2025-11-05T23:12:48.599Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-05T23:12:48.599Z] GC before operation: completed in 79.294 ms, heap usage 198.711 MB -> 72.045 MB. [2025-11-05T23:13:04.692Z] ====== als (apache-spark) [default], iteration 28 completed (15172.001 ms) ====== [2025-11-05T23:13:04.692Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-05T23:13:04.692Z] GC before operation: completed in 76.827 ms, heap usage 269.596 MB -> 71.998 MB. [2025-11-05T23:13:20.952Z] ====== als (apache-spark) [default], iteration 29 completed (15256.961 ms) ====== [2025-11-05T23:13:20.952Z] ----------------------------------- [2025-11-05T23:13:20.952Z] renaissance-als_0_PASSED [2025-11-05T23:13:20.952Z] ----------------------------------- [2025-11-05T23:13:20.952Z] [2025-11-05T23:13:20.952Z] TEST TEARDOWN: [2025-11-05T23:13:20.952Z] Nothing to be done for teardown. [2025-11-05T23:13:20.952Z] renaissance-als_0 Finish Time: Wed Nov 5 23:13:20 2025 Epoch Time (ms): 1762384400335