[2025-07-01T00:22:30.469Z] Running test renaissance-als_0 ... [2025-07-01T00:22:30.791Z] =============================================== [2025-07-01T00:22:30.791Z] renaissance-als_0 Start Time: Tue Jul 1 00:22:30 2025 Epoch Time (ms): 1751329350683 [2025-07-01T00:22:30.791Z] variation: NoOptions [2025-07-01T00:22:31.117Z] JVM_OPTIONS: [2025-07-01T00:22:31.117Z] { \ [2025-07-01T00:22:31.117Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T00:22:31.117Z] echo "Nothing to be done for setup."; \ [2025-07-01T00:22:31.117Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\renaissance-als_0"; \ [2025-07-01T00:22:31.117Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\renaissance-als_0"; \ [2025-07-01T00:22:31.117Z] echo ""; echo "TESTING:"; \ [2025-07-01T00:22:31.117Z] "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_17513292438879\\renaissance-als_0"\\als.json" als; \ [2025-07-01T00:22:31.117Z] 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_17513292438879\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T00:22:31.117Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T00:22:31.117Z] echo "Nothing to be done for teardown."; \ [2025-07-01T00:22:31.117Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\TestTargetResult"; [2025-07-01T00:22:31.117Z] [2025-07-01T00:22:31.117Z] TEST SETUP: [2025-07-01T00:22:31.117Z] Nothing to be done for setup. [2025-07-01T00:22:31.117Z] [2025-07-01T00:22:31.117Z] TESTING: [2025-07-01T00:22:44.036Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T00:22:47.762Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T00:22:47.762Z] GC before operation: completed in 35.778 ms, heap usage 50.000 MB -> 35.401 MB. [2025-07-01T00:23:15.949Z] ====== als (apache-spark) [default], iteration 0 completed (26232.135 ms) ====== [2025-07-01T00:23:15.950Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T00:23:15.950Z] GC before operation: completed in 89.261 ms, heap usage 182.062 MB -> 62.004 MB. [2025-07-01T00:23:31.797Z] ====== als (apache-spark) [default], iteration 1 completed (14887.745 ms) ====== [2025-07-01T00:23:31.797Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T00:23:31.797Z] GC before operation: completed in 78.607 ms, heap usage 355.809 MB -> 63.483 MB. [2025-07-01T00:23:44.799Z] ====== als (apache-spark) [default], iteration 2 completed (14363.417 ms) ====== [2025-07-01T00:23:44.799Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T00:23:44.799Z] GC before operation: completed in 77.143 ms, heap usage 217.748 MB -> 63.901 MB. [2025-07-01T00:24:00.731Z] ====== als (apache-spark) [default], iteration 3 completed (14085.521 ms) ====== [2025-07-01T00:24:00.731Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T00:24:00.731Z] GC before operation: completed in 71.431 ms, heap usage 296.704 MB -> 64.585 MB. [2025-07-01T00:24:13.753Z] ====== als (apache-spark) [default], iteration 4 completed (14064.170 ms) ====== [2025-07-01T00:24:13.753Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T00:24:13.753Z] GC before operation: completed in 74.915 ms, heap usage 178.397 MB -> 65.010 MB. [2025-07-01T00:24:29.485Z] ====== als (apache-spark) [default], iteration 5 completed (14040.562 ms) ====== [2025-07-01T00:24:29.485Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T00:24:29.485Z] GC before operation: completed in 78.372 ms, heap usage 438.827 MB -> 65.884 MB. [2025-07-01T00:24:42.470Z] ====== als (apache-spark) [default], iteration 6 completed (13978.068 ms) ====== [2025-07-01T00:24:42.470Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T00:24:42.470Z] GC before operation: completed in 110.852 ms, heap usage 231.461 MB -> 66.161 MB. [2025-07-01T00:24:58.259Z] ====== als (apache-spark) [default], iteration 7 completed (13820.993 ms) ====== [2025-07-01T00:24:58.259Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T00:24:58.259Z] GC before operation: completed in 82.954 ms, heap usage 227.307 MB -> 66.682 MB. [2025-07-01T00:25:11.187Z] ====== als (apache-spark) [default], iteration 8 completed (13800.362 ms) ====== [2025-07-01T00:25:11.188Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T00:25:11.188Z] GC before operation: completed in 82.116 ms, heap usage 281.870 MB -> 67.269 MB. [2025-07-01T00:25:24.311Z] ====== als (apache-spark) [default], iteration 9 completed (13781.289 ms) ====== [2025-07-01T00:25:24.311Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T00:25:24.311Z] GC before operation: completed in 72.540 ms, heap usage 264.825 MB -> 67.741 MB. [2025-07-01T00:25:40.037Z] ====== als (apache-spark) [default], iteration 10 completed (13844.796 ms) ====== [2025-07-01T00:25:40.037Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T00:25:40.037Z] GC before operation: completed in 72.977 ms, heap usage 166.031 MB -> 67.577 MB. [2025-07-01T00:25:53.151Z] ====== als (apache-spark) [default], iteration 11 completed (13677.170 ms) ====== [2025-07-01T00:25:53.151Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T00:25:53.151Z] GC before operation: completed in 74.480 ms, heap usage 241.463 MB -> 67.928 MB. [2025-07-01T00:26:06.189Z] ====== als (apache-spark) [default], iteration 12 completed (13474.961 ms) ====== [2025-07-01T00:26:06.189Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T00:26:06.189Z] GC before operation: completed in 81.452 ms, heap usage 296.400 MB -> 68.296 MB. [2025-07-01T00:26:21.910Z] ====== als (apache-spark) [default], iteration 13 completed (13663.487 ms) ====== [2025-07-01T00:26:21.911Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T00:26:21.911Z] GC before operation: completed in 71.664 ms, heap usage 460.821 MB -> 68.815 MB. [2025-07-01T00:26:35.074Z] ====== als (apache-spark) [default], iteration 14 completed (13669.030 ms) ====== [2025-07-01T00:26:35.074Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T00:26:35.074Z] GC before operation: completed in 73.622 ms, heap usage 199.482 MB -> 68.646 MB. [2025-07-01T00:26:48.130Z] ====== als (apache-spark) [default], iteration 15 completed (13623.037 ms) ====== [2025-07-01T00:26:48.130Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T00:26:48.131Z] GC before operation: completed in 74.218 ms, heap usage 226.921 MB -> 69.014 MB. [2025-07-01T00:27:03.812Z] ====== als (apache-spark) [default], iteration 16 completed (13755.841 ms) ====== [2025-07-01T00:27:03.812Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T00:27:03.812Z] GC before operation: completed in 80.083 ms, heap usage 389.925 MB -> 69.394 MB. [2025-07-01T00:27:16.906Z] ====== als (apache-spark) [default], iteration 17 completed (13689.557 ms) ====== [2025-07-01T00:27:16.906Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T00:27:16.906Z] GC before operation: completed in 74.904 ms, heap usage 363.814 MB -> 69.614 MB. [2025-07-01T00:27:29.908Z] ====== als (apache-spark) [default], iteration 18 completed (13741.065 ms) ====== [2025-07-01T00:27:29.908Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T00:27:29.908Z] GC before operation: completed in 73.929 ms, heap usage 236.350 MB -> 69.735 MB. [2025-07-01T00:27:45.627Z] ====== als (apache-spark) [default], iteration 19 completed (13722.257 ms) ====== [2025-07-01T00:27:45.627Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T00:27:45.627Z] GC before operation: completed in 76.164 ms, heap usage 463.603 MB -> 70.340 MB. [2025-07-01T00:27:58.827Z] ====== als (apache-spark) [default], iteration 20 completed (13816.759 ms) ====== [2025-07-01T00:27:58.827Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T00:27:58.827Z] GC before operation: completed in 72.923 ms, heap usage 138.176 MB -> 70.164 MB. [2025-07-01T00:28:11.831Z] ====== als (apache-spark) [default], iteration 21 completed (13800.267 ms) ====== [2025-07-01T00:28:11.831Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T00:28:11.831Z] GC before operation: completed in 76.316 ms, heap usage 361.615 MB -> 70.677 MB. [2025-07-01T00:28:27.559Z] ====== als (apache-spark) [default], iteration 22 completed (13685.259 ms) ====== [2025-07-01T00:28:27.559Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T00:28:27.559Z] GC before operation: completed in 74.974 ms, heap usage 299.949 MB -> 70.955 MB. [2025-07-01T00:28:40.638Z] ====== als (apache-spark) [default], iteration 23 completed (13500.718 ms) ====== [2025-07-01T00:28:40.638Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T00:28:40.638Z] GC before operation: completed in 75.429 ms, heap usage 387.089 MB -> 71.227 MB. [2025-07-01T00:28:53.545Z] ====== als (apache-spark) [default], iteration 24 completed (13934.655 ms) ====== [2025-07-01T00:28:53.545Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T00:28:53.545Z] GC before operation: completed in 75.732 ms, heap usage 472.960 MB -> 71.710 MB. [2025-07-01T00:29:06.657Z] ====== als (apache-spark) [default], iteration 25 completed (13615.654 ms) ====== [2025-07-01T00:29:06.657Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T00:29:06.985Z] GC before operation: completed in 77.756 ms, heap usage 360.156 MB -> 71.726 MB. [2025-07-01T00:29:22.725Z] ====== als (apache-spark) [default], iteration 26 completed (13594.915 ms) ====== [2025-07-01T00:29:22.725Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T00:29:22.725Z] GC before operation: completed in 77.658 ms, heap usage 468.228 MB -> 72.279 MB. [2025-07-01T00:29:35.673Z] ====== als (apache-spark) [default], iteration 27 completed (13506.422 ms) ====== [2025-07-01T00:29:35.673Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T00:29:35.673Z] GC before operation: completed in 76.090 ms, heap usage 439.227 MB -> 72.521 MB. [2025-07-01T00:29:48.723Z] ====== als (apache-spark) [default], iteration 28 completed (13517.590 ms) ====== [2025-07-01T00:29:48.723Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T00:29:48.723Z] GC before operation: completed in 76.013 ms, heap usage 241.098 MB -> 71.960 MB. [2025-07-01T00:30:01.827Z] ====== als (apache-spark) [default], iteration 29 completed (13640.866 ms) ====== [2025-07-01T00:30:02.540Z] ----------------------------------- [2025-07-01T00:30:02.540Z] renaissance-als_0_PASSED [2025-07-01T00:30:02.540Z] ----------------------------------- [2025-07-01T00:30:02.540Z] [2025-07-01T00:30:02.540Z] TEST TEARDOWN: [2025-07-01T00:30:02.540Z] Nothing to be done for teardown. [2025-07-01T00:30:02.878Z] renaissance-als_0 Finish Time: Tue Jul 1 00:30:02 2025 Epoch Time (ms): 1751329802494