[2025-12-25T00:08:27.325Z] Running test renaissance-philosophers_0 ... [2025-12-25T00:08:27.325Z] =============================================== [2025-12-25T00:08:27.325Z] renaissance-philosophers_0 Start Time: Thu Dec 25 00:08:27 2025 Epoch Time (ms): 1766621307236 [2025-12-25T00:08:27.681Z] variation: NoOptions [2025-12-25T00:08:27.681Z] JVM_OPTIONS: [2025-12-25T00:08:27.681Z] { \ [2025-12-25T00:08:27.681Z] echo ""; echo "TEST SETUP:"; \ [2025-12-25T00:08:27.681Z] echo "Nothing to be done for setup."; \ [2025-12-25T00:08:27.681Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17666184974796\\renaissance-philosophers_0"; \ [2025-12-25T00:08:27.681Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17666184974796\\renaissance-philosophers_0"; \ [2025-12-25T00:08:27.681Z] echo ""; echo "TESTING:"; \ [2025-12-25T00:08:27.681Z] "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_17666184974796\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-12-25T00:08:27.681Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_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_17666184974796\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-25T00:08:27.681Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-25T00:08:27.681Z] echo "Nothing to be done for teardown."; \ [2025-12-25T00:08:27.681Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17666184974796\\TestTargetResult"; [2025-12-25T00:08:27.681Z] [2025-12-25T00:08:27.681Z] TEST SETUP: [2025-12-25T00:08:27.681Z] Nothing to be done for setup. [2025-12-25T00:08:27.681Z] [2025-12-25T00:08:27.681Z] TESTING: [2025-12-25T00:08:28.898Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-25T00:08:28.899Z] GC before operation: completed in 22.373 ms, heap usage 8.741 MB -> 4.557 MB. [2025-12-25T00:08:35.885Z] Camera thread performed 122 scans. [2025-12-25T00:08:35.885Z] ====== philosophers (scala) [default], iteration 0 completed (2039.968 ms) ====== [2025-12-25T00:08:35.885Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-25T00:08:35.885Z] GC before operation: completed in 11.744 ms, heap usage 41.995 MB -> 4.936 MB. [2025-12-25T00:08:36.689Z] Camera thread performed 122 scans. [2025-12-25T00:08:36.689Z] ====== philosophers (scala) [default], iteration 1 completed (5655.799 ms) ====== [2025-12-25T00:08:36.689Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-25T00:08:36.689Z] GC before operation: completed in 10.493 ms, heap usage 25.270 MB -> 4.936 MB. [2025-12-25T00:08:37.459Z] Camera thread performed 122 scans. [2025-12-25T00:08:37.459Z] ====== philosophers (scala) [default], iteration 2 completed (744.405 ms) ====== [2025-12-25T00:08:37.459Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-25T00:08:37.459Z] GC before operation: completed in 10.472 ms, heap usage 22.690 MB -> 4.937 MB. [2025-12-25T00:08:37.964Z] Camera thread performed 122 scans. [2025-12-25T00:08:37.964Z] ====== philosophers (scala) [default], iteration 3 completed (803.637 ms) ====== [2025-12-25T00:08:37.964Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-25T00:08:37.964Z] GC before operation: completed in 10.685 ms, heap usage 37.163 MB -> 4.937 MB. [2025-12-25T00:08:38.709Z] Camera thread performed 122 scans. [2025-12-25T00:08:38.709Z] ====== philosophers (scala) [default], iteration 4 completed (651.624 ms) ====== [2025-12-25T00:08:38.709Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-25T00:08:38.709Z] GC before operation: completed in 10.949 ms, heap usage 24.084 MB -> 4.938 MB. [2025-12-25T00:08:39.629Z] Camera thread performed 122 scans. [2025-12-25T00:08:39.629Z] ====== philosophers (scala) [default], iteration 5 completed (672.381 ms) ====== [2025-12-25T00:08:39.629Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-25T00:08:39.629Z] GC before operation: completed in 16.526 ms, heap usage 12.590 MB -> 4.938 MB. [2025-12-25T00:08:40.371Z] Camera thread performed 122 scans. [2025-12-25T00:08:40.371Z] ====== philosophers (scala) [default], iteration 6 completed (1038.236 ms) ====== [2025-12-25T00:08:40.371Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-25T00:08:40.371Z] GC before operation: completed in 10.458 ms, heap usage 43.077 MB -> 4.938 MB. [2025-12-25T00:08:41.114Z] Camera thread performed 122 scans. [2025-12-25T00:08:41.114Z] ====== philosophers (scala) [default], iteration 7 completed (670.227 ms) ====== [2025-12-25T00:08:41.114Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-25T00:08:41.114Z] GC before operation: completed in 9.806 ms, heap usage 17.537 MB -> 4.938 MB. [2025-12-25T00:08:41.858Z] Camera thread performed 122 scans. [2025-12-25T00:08:41.859Z] ====== philosophers (scala) [default], iteration 8 completed (646.130 ms) ====== [2025-12-25T00:08:41.859Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-25T00:08:41.859Z] GC before operation: completed in 10.413 ms, heap usage 35.060 MB -> 4.939 MB. [2025-12-25T00:08:42.613Z] Camera thread performed 122 scans. [2025-12-25T00:08:42.613Z] ====== philosophers (scala) [default], iteration 9 completed (698.933 ms) ====== [2025-12-25T00:08:42.613Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-25T00:08:42.613Z] GC before operation: completed in 10.000 ms, heap usage 8.034 MB -> 4.938 MB. [2025-12-25T00:08:42.972Z] Camera thread performed 122 scans. [2025-12-25T00:08:42.972Z] ====== philosophers (scala) [default], iteration 10 completed (579.034 ms) ====== [2025-12-25T00:08:42.972Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-25T00:08:42.972Z] GC before operation: completed in 10.992 ms, heap usage 10.038 MB -> 4.943 MB. [2025-12-25T00:08:43.717Z] Camera thread performed 122 scans. [2025-12-25T00:08:43.717Z] ====== philosophers (scala) [default], iteration 11 completed (682.860 ms) ====== [2025-12-25T00:08:43.717Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-25T00:08:43.717Z] GC before operation: completed in 10.553 ms, heap usage 50.065 MB -> 4.944 MB. [2025-12-25T00:08:44.494Z] Camera thread performed 122 scans. [2025-12-25T00:08:44.494Z] ====== philosophers (scala) [default], iteration 12 completed (646.810 ms) ====== [2025-12-25T00:08:44.494Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-25T00:08:44.494Z] GC before operation: completed in 10.349 ms, heap usage 15.040 MB -> 4.944 MB. [2025-12-25T00:08:45.243Z] Camera thread performed 122 scans. [2025-12-25T00:08:45.243Z] ====== philosophers (scala) [default], iteration 13 completed (702.396 ms) ====== [2025-12-25T00:08:45.243Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-25T00:08:45.243Z] GC before operation: completed in 10.848 ms, heap usage 51.067 MB -> 4.944 MB. [2025-12-25T00:08:45.989Z] Camera thread performed 122 scans. [2025-12-25T00:08:45.989Z] ====== philosophers (scala) [default], iteration 14 completed (629.938 ms) ====== [2025-12-25T00:08:45.989Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-25T00:08:45.989Z] GC before operation: completed in 9.893 ms, heap usage 7.031 MB -> 4.944 MB. [2025-12-25T00:08:46.344Z] Camera thread performed 122 scans. [2025-12-25T00:08:46.344Z] ====== philosophers (scala) [default], iteration 15 completed (677.713 ms) ====== [2025-12-25T00:08:46.344Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-25T00:08:46.344Z] GC before operation: completed in 10.163 ms, heap usage 23.953 MB -> 4.944 MB. [2025-12-25T00:08:47.088Z] Camera thread performed 122 scans. [2025-12-25T00:08:47.088Z] ====== philosophers (scala) [default], iteration 16 completed (673.130 ms) ====== [2025-12-25T00:08:47.088Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-25T00:08:47.088Z] GC before operation: completed in 10.177 ms, heap usage 53.069 MB -> 4.944 MB. [2025-12-25T00:08:47.832Z] Camera thread performed 122 scans. [2025-12-25T00:08:47.832Z] ====== philosophers (scala) [default], iteration 17 completed (705.595 ms) ====== [2025-12-25T00:08:47.832Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-25T00:08:47.832Z] GC before operation: completed in 9.972 ms, heap usage 33.067 MB -> 4.944 MB. [2025-12-25T00:08:48.559Z] Camera thread performed 122 scans. [2025-12-25T00:08:48.559Z] ====== philosophers (scala) [default], iteration 18 completed (689.512 ms) ====== [2025-12-25T00:08:48.559Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-25T00:08:48.559Z] GC before operation: completed in 10.202 ms, heap usage 48.560 MB -> 4.945 MB. [2025-12-25T00:08:49.306Z] Camera thread performed 122 scans. [2025-12-25T00:08:49.306Z] ====== philosophers (scala) [default], iteration 19 completed (662.611 ms) ====== [2025-12-25T00:08:49.306Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-25T00:08:49.306Z] GC before operation: completed in 10.169 ms, heap usage 14.041 MB -> 4.945 MB. [2025-12-25T00:08:50.058Z] Camera thread performed 122 scans. [2025-12-25T00:08:50.058Z] ====== philosophers (scala) [default], iteration 20 completed (690.459 ms) ====== [2025-12-25T00:08:50.058Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-25T00:08:50.058Z] GC before operation: completed in 11.241 ms, heap usage 48.076 MB -> 4.945 MB. [2025-12-25T00:08:50.800Z] Camera thread performed 122 scans. [2025-12-25T00:08:50.800Z] ====== philosophers (scala) [default], iteration 21 completed (690.763 ms) ====== [2025-12-25T00:08:50.800Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-25T00:08:50.800Z] GC before operation: completed in 10.349 ms, heap usage 15.567 MB -> 4.945 MB. [2025-12-25T00:08:51.568Z] Camera thread performed 122 scans. [2025-12-25T00:08:51.568Z] ====== philosophers (scala) [default], iteration 22 completed (751.845 ms) ====== [2025-12-25T00:08:51.568Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-25T00:08:51.568Z] GC before operation: completed in 10.196 ms, heap usage 41.923 MB -> 4.946 MB. [2025-12-25T00:08:52.308Z] Camera thread performed 122 scans. [2025-12-25T00:08:52.308Z] ====== philosophers (scala) [default], iteration 23 completed (645.742 ms) ====== [2025-12-25T00:08:52.308Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-25T00:08:52.308Z] GC before operation: completed in 10.016 ms, heap usage 22.540 MB -> 4.946 MB. [2025-12-25T00:08:52.685Z] Camera thread performed 122 scans. [2025-12-25T00:08:52.685Z] ====== philosophers (scala) [default], iteration 24 completed (682.234 ms) ====== [2025-12-25T00:08:52.686Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-25T00:08:52.686Z] GC before operation: completed in 10.093 ms, heap usage 20.536 MB -> 4.946 MB. [2025-12-25T00:08:53.426Z] Camera thread performed 122 scans. [2025-12-25T00:08:53.426Z] ====== philosophers (scala) [default], iteration 25 completed (700.584 ms) ====== [2025-12-25T00:08:53.426Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-25T00:08:53.426Z] GC before operation: completed in 10.123 ms, heap usage 41.565 MB -> 4.946 MB. [2025-12-25T00:08:54.169Z] Camera thread performed 122 scans. [2025-12-25T00:08:54.169Z] ====== philosophers (scala) [default], iteration 26 completed (690.632 ms) ====== [2025-12-25T00:08:54.169Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-25T00:08:54.169Z] GC before operation: completed in 10.154 ms, heap usage 45.567 MB -> 4.946 MB. [2025-12-25T00:08:54.912Z] Camera thread performed 122 scans. [2025-12-25T00:08:54.912Z] ====== philosophers (scala) [default], iteration 27 completed (681.598 ms) ====== [2025-12-25T00:08:54.912Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-25T00:08:54.912Z] GC before operation: completed in 9.455 ms, heap usage 21.061 MB -> 4.946 MB. [2025-12-25T00:08:55.659Z] Camera thread performed 122 scans. [2025-12-25T00:08:55.659Z] ====== philosophers (scala) [default], iteration 28 completed (694.046 ms) ====== [2025-12-25T00:08:55.659Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-25T00:08:55.659Z] GC before operation: completed in 10.502 ms, heap usage 48.068 MB -> 4.947 MB. [2025-12-25T00:08:56.399Z] Camera thread performed 122 scans. [2025-12-25T00:08:56.399Z] ====== philosophers (scala) [default], iteration 29 completed (703.798 ms) ====== [2025-12-25T00:08:56.399Z] ----------------------------------- [2025-12-25T00:08:56.399Z] renaissance-philosophers_0_PASSED [2025-12-25T00:08:56.399Z] ----------------------------------- [2025-12-25T00:08:56.399Z] [2025-12-25T00:08:56.399Z] TEST TEARDOWN: [2025-12-25T00:08:56.399Z] Nothing to be done for teardown. [2025-12-25T00:08:56.738Z] renaissance-philosophers_0 Finish Time: Thu Dec 25 00:08:56 2025 Epoch Time (ms): 1766621336442