[2025-07-01T13:45:42.661Z] Running test renaissance-philosophers_0 ... [2025-07-01T13:45:42.959Z] =============================================== [2025-07-01T13:45:42.959Z] renaissance-philosophers_0 Start Time: Tue Jul 1 13:45:42 2025 Epoch Time (ms): 1751377542871 [2025-07-01T13:45:43.256Z] variation: NoOptions [2025-07-01T13:45:43.256Z] JVM_OPTIONS: [2025-07-01T13:45:43.256Z] { \ [2025-07-01T13:45:43.256Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T13:45:43.256Z] echo "Nothing to be done for setup."; \ [2025-07-01T13:45:43.256Z] mkdir -p "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17513769676307\\renaissance-philosophers_0"; \ [2025-07-01T13:45:43.256Z] cd "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17513769676307\\renaissance-philosophers_0"; \ [2025-07-01T13:45:43.256Z] echo ""; echo "TESTING:"; \ [2025-07-01T13:45:43.256Z] "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_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:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17513769676307\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-07-01T13:45:43.256Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/; rm -f -r "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17513769676307\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T13:45:43.256Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T13:45:43.256Z] echo "Nothing to be done for teardown."; \ [2025-07-01T13:45:43.256Z] } 2>&1 | tee -a "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17513769676307\\TestTargetResult"; [2025-07-01T13:45:43.553Z] [2025-07-01T13:45:43.553Z] TEST SETUP: [2025-07-01T13:45:43.553Z] Nothing to be done for setup. [2025-07-01T13:45:43.553Z] [2025-07-01T13:45:43.553Z] TESTING: [2025-07-01T13:45:45.102Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-01T13:45:45.102Z] GC before operation: completed in 12.478 ms, heap usage 16.083 MB -> 4.585 MB. [2025-07-01T13:45:47.263Z] Camera thread performed 122 scans. [2025-07-01T13:45:47.263Z] ====== philosophers (scala) [default], iteration 0 completed (2436.582 ms) ====== [2025-07-01T13:45:47.263Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-01T13:45:47.263Z] GC before operation: completed in 15.320 ms, heap usage 20.230 MB -> 4.953 MB. [2025-07-01T13:45:48.828Z] Camera thread performed 122 scans. [2025-07-01T13:45:48.828Z] ====== philosophers (scala) [default], iteration 1 completed (1441.949 ms) ====== [2025-07-01T13:45:48.828Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-01T13:45:48.828Z] GC before operation: completed in 14.773 ms, heap usage 53.655 MB -> 4.952 MB. [2025-07-01T13:45:50.366Z] Camera thread performed 122 scans. [2025-07-01T13:45:50.366Z] ====== philosophers (scala) [default], iteration 2 completed (1636.672 ms) ====== [2025-07-01T13:45:50.366Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-01T13:45:50.366Z] GC before operation: completed in 11.663 ms, heap usage 10.862 MB -> 4.954 MB. [2025-07-01T13:45:51.905Z] Camera thread performed 122 scans. [2025-07-01T13:45:51.905Z] ====== philosophers (scala) [default], iteration 3 completed (1527.813 ms) ====== [2025-07-01T13:45:51.905Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-01T13:45:51.905Z] GC before operation: completed in 11.695 ms, heap usage 58.713 MB -> 4.954 MB. [2025-07-01T13:45:54.007Z] Camera thread performed 122 scans. [2025-07-01T13:45:54.007Z] ====== philosophers (scala) [default], iteration 4 completed (1757.401 ms) ====== [2025-07-01T13:45:54.007Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-01T13:45:54.007Z] GC before operation: completed in 11.096 ms, heap usage 50.559 MB -> 4.954 MB. [2025-07-01T13:45:55.540Z] Camera thread performed 122 scans. [2025-07-01T13:45:55.540Z] ====== philosophers (scala) [default], iteration 5 completed (1701.113 ms) ====== [2025-07-01T13:45:55.540Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-01T13:45:55.540Z] GC before operation: completed in 11.324 ms, heap usage 13.659 MB -> 4.958 MB. [2025-07-01T13:45:57.639Z] Camera thread performed 122 scans. [2025-07-01T13:45:57.639Z] ====== philosophers (scala) [default], iteration 6 completed (1827.099 ms) ====== [2025-07-01T13:45:57.639Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-01T13:45:57.639Z] GC before operation: completed in 11.398 ms, heap usage 56.186 MB -> 4.958 MB. [2025-07-01T13:45:59.269Z] Camera thread performed 122 scans. [2025-07-01T13:45:59.269Z] ====== philosophers (scala) [default], iteration 7 completed (1710.591 ms) ====== [2025-07-01T13:45:59.269Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-01T13:45:59.269Z] GC before operation: completed in 12.642 ms, heap usage 74.175 MB -> 4.958 MB. [2025-07-01T13:46:00.804Z] Camera thread performed 122 scans. [2025-07-01T13:46:00.804Z] ====== philosophers (scala) [default], iteration 8 completed (1614.146 ms) ====== [2025-07-01T13:46:00.804Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-01T13:46:00.804Z] GC before operation: completed in 11.860 ms, heap usage 59.498 MB -> 4.959 MB. [2025-07-01T13:46:02.338Z] Camera thread performed 122 scans. [2025-07-01T13:46:02.338Z] ====== philosophers (scala) [default], iteration 9 completed (1821.964 ms) ====== [2025-07-01T13:46:02.338Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-01T13:46:02.639Z] GC before operation: completed in 10.910 ms, heap usage 61.522 MB -> 4.959 MB. [2025-07-01T13:46:04.740Z] Camera thread performed 122 scans. [2025-07-01T13:46:04.740Z] ====== philosophers (scala) [default], iteration 10 completed (1880.833 ms) ====== [2025-07-01T13:46:04.740Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-01T13:46:04.740Z] GC before operation: completed in 11.961 ms, heap usage 55.880 MB -> 4.959 MB. [2025-07-01T13:46:06.272Z] Camera thread performed 122 scans. [2025-07-01T13:46:06.272Z] ====== philosophers (scala) [default], iteration 11 completed (1709.990 ms) ====== [2025-07-01T13:46:06.272Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-01T13:46:06.272Z] GC before operation: completed in 11.522 ms, heap usage 80.542 MB -> 4.959 MB. [2025-07-01T13:46:07.823Z] Camera thread performed 122 scans. [2025-07-01T13:46:07.824Z] ====== philosophers (scala) [default], iteration 12 completed (1633.415 ms) ====== [2025-07-01T13:46:07.824Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-01T13:46:07.824Z] GC before operation: completed in 10.989 ms, heap usage 25.922 MB -> 4.960 MB. [2025-07-01T13:46:09.928Z] Camera thread performed 122 scans. [2025-07-01T13:46:09.928Z] ====== philosophers (scala) [default], iteration 13 completed (1777.679 ms) ====== [2025-07-01T13:46:09.928Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-01T13:46:09.928Z] GC before operation: completed in 12.475 ms, heap usage 33.785 MB -> 4.960 MB. [2025-07-01T13:46:10.986Z] Camera thread performed 122 scans. [2025-07-01T13:46:10.986Z] ====== philosophers (scala) [default], iteration 14 completed (1543.092 ms) ====== [2025-07-01T13:46:10.986Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-01T13:46:10.986Z] GC before operation: completed in 10.993 ms, heap usage 50.555 MB -> 4.960 MB. [2025-07-01T13:46:13.092Z] Camera thread performed 122 scans. [2025-07-01T13:46:13.092Z] ====== philosophers (scala) [default], iteration 15 completed (1702.773 ms) ====== [2025-07-01T13:46:13.092Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-01T13:46:13.092Z] GC before operation: completed in 11.975 ms, heap usage 20.170 MB -> 4.960 MB. [2025-07-01T13:46:14.630Z] Camera thread performed 122 scans. [2025-07-01T13:46:14.630Z] ====== philosophers (scala) [default], iteration 16 completed (1708.458 ms) ====== [2025-07-01T13:46:14.630Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-01T13:46:14.630Z] GC before operation: completed in 12.249 ms, heap usage 8.633 MB -> 4.960 MB. [2025-07-01T13:46:15.689Z] Camera thread performed 122 scans. [2025-07-01T13:46:15.689Z] ====== philosophers (scala) [default], iteration 17 completed (1276.757 ms) ====== [2025-07-01T13:46:15.689Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-01T13:46:15.689Z] GC before operation: completed in 10.984 ms, heap usage 52.482 MB -> 4.960 MB. [2025-07-01T13:46:17.789Z] Camera thread performed 122 scans. [2025-07-01T13:46:17.789Z] ====== philosophers (scala) [default], iteration 18 completed (1751.600 ms) ====== [2025-07-01T13:46:17.789Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-01T13:46:17.789Z] GC before operation: completed in 10.851 ms, heap usage 46.528 MB -> 4.960 MB. [2025-07-01T13:46:19.330Z] Camera thread performed 122 scans. [2025-07-01T13:46:19.330Z] ====== philosophers (scala) [default], iteration 19 completed (1618.476 ms) ====== [2025-07-01T13:46:19.330Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-01T13:46:19.330Z] GC before operation: completed in 11.581 ms, heap usage 75.857 MB -> 4.960 MB. [2025-07-01T13:46:20.864Z] Camera thread performed 122 scans. [2025-07-01T13:46:20.864Z] ====== philosophers (scala) [default], iteration 20 completed (1718.096 ms) ====== [2025-07-01T13:46:20.864Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-01T13:46:20.864Z] GC before operation: completed in 11.500 ms, heap usage 78.529 MB -> 4.961 MB. [2025-07-01T13:46:22.965Z] Camera thread performed 122 scans. [2025-07-01T13:46:22.965Z] ====== philosophers (scala) [default], iteration 21 completed (1650.645 ms) ====== [2025-07-01T13:46:22.965Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-01T13:46:22.965Z] GC before operation: completed in 11.624 ms, heap usage 16.170 MB -> 4.961 MB. [2025-07-01T13:46:24.498Z] Camera thread performed 122 scans. [2025-07-01T13:46:24.498Z] ====== philosophers (scala) [default], iteration 22 completed (1674.437 ms) ====== [2025-07-01T13:46:24.498Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-01T13:46:24.498Z] GC before operation: completed in 12.759 ms, heap usage 39.543 MB -> 4.961 MB. [2025-07-01T13:46:26.035Z] Camera thread performed 122 scans. [2025-07-01T13:46:26.035Z] ====== philosophers (scala) [default], iteration 23 completed (1607.422 ms) ====== [2025-07-01T13:46:26.035Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-01T13:46:26.035Z] GC before operation: completed in 11.008 ms, heap usage 62.154 MB -> 4.961 MB. [2025-07-01T13:46:28.133Z] Camera thread performed 122 scans. [2025-07-01T13:46:28.133Z] ====== philosophers (scala) [default], iteration 24 completed (1810.554 ms) ====== [2025-07-01T13:46:28.133Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-01T13:46:28.133Z] GC before operation: completed in 12.405 ms, heap usage 41.609 MB -> 4.961 MB. [2025-07-01T13:46:29.665Z] Camera thread performed 122 scans. [2025-07-01T13:46:29.665Z] ====== philosophers (scala) [default], iteration 25 completed (1664.629 ms) ====== [2025-07-01T13:46:29.665Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-01T13:46:29.665Z] GC before operation: completed in 11.294 ms, heap usage 35.557 MB -> 4.961 MB. [2025-07-01T13:46:31.197Z] Camera thread performed 122 scans. [2025-07-01T13:46:31.197Z] ====== philosophers (scala) [default], iteration 26 completed (1782.307 ms) ====== [2025-07-01T13:46:31.197Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-01T13:46:31.197Z] GC before operation: completed in 10.941 ms, heap usage 74.959 MB -> 4.961 MB. [2025-07-01T13:46:32.730Z] Camera thread performed 122 scans. [2025-07-01T13:46:32.730Z] ====== philosophers (scala) [default], iteration 27 completed (1618.630 ms) ====== [2025-07-01T13:46:32.730Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-01T13:46:32.730Z] GC before operation: completed in 10.764 ms, heap usage 64.171 MB -> 4.961 MB. [2025-07-01T13:46:34.831Z] Camera thread performed 122 scans. [2025-07-01T13:46:34.831Z] ====== philosophers (scala) [default], iteration 28 completed (1691.477 ms) ====== [2025-07-01T13:46:34.831Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-01T13:46:34.831Z] GC before operation: completed in 11.218 ms, heap usage 21.864 MB -> 4.962 MB. [2025-07-01T13:46:36.365Z] Camera thread performed 122 scans. [2025-07-01T13:46:36.365Z] ====== philosophers (scala) [default], iteration 29 completed (1611.738 ms) ====== [2025-07-01T13:46:36.365Z] ----------------------------------- [2025-07-01T13:46:36.365Z] renaissance-philosophers_0_PASSED [2025-07-01T13:46:36.365Z] ----------------------------------- [2025-07-01T13:46:36.365Z] [2025-07-01T13:46:36.365Z] TEST TEARDOWN: [2025-07-01T13:46:36.365Z] Nothing to be done for teardown. [2025-07-01T13:46:36.663Z] renaissance-philosophers_0 Finish Time: Tue Jul 1 13:46:36 2025 Epoch Time (ms): 1751377596379