[2025-11-13T02:11:58.572Z] Running test renaissance-philosophers_0 ... [2025-11-13T02:11:58.572Z] =============================================== [2025-11-13T02:11:58.572Z] renaissance-philosophers_0 Start Time: Thu Nov 13 02:11:57 2025 Epoch Time (ms): 1762999917568 [2025-11-13T02:11:58.572Z] variation: NoOptions [2025-11-13T02:11:58.572Z] JVM_OPTIONS: [2025-11-13T02:11:58.572Z] { \ [2025-11-13T02:11:58.572Z] echo ""; echo "TEST SETUP:"; \ [2025-11-13T02:11:58.572Z] echo "Nothing to be done for setup."; \ [2025-11-13T02:11:58.572Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-philosophers_0"; \ [2025-11-13T02:11:58.572Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-philosophers_0"; \ [2025-11-13T02:11:58.572Z] echo ""; echo "TESTING:"; \ [2025-11-13T02:11:58.572Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-13T02:11:58.572Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-13T02:11:58.572Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-13T02:11:58.572Z] echo "Nothing to be done for teardown."; \ [2025-11-13T02:11:58.572Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/TestTargetResult"; [2025-11-13T02:11:58.572Z] [2025-11-13T02:11:58.572Z] TEST SETUP: [2025-11-13T02:11:58.572Z] Nothing to be done for setup. [2025-11-13T02:11:58.572Z] [2025-11-13T02:11:58.572Z] TESTING: [2025-11-13T02:11:58.572Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-13T02:11:58.572Z] GC before operation: completed in 12.115 ms, heap usage 14.115 MB -> 4.712 MB. [2025-11-13T02:12:05.358Z] Camera thread performed 122 scans. [2025-11-13T02:12:05.358Z] ====== philosophers (scala) [default], iteration 0 completed (5956.967 ms) ====== [2025-11-13T02:12:05.358Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-13T02:12:05.358Z] GC before operation: completed in 14.324 ms, heap usage 25.413 MB -> 5.060 MB. [2025-11-13T02:12:09.553Z] Camera thread performed 122 scans. [2025-11-13T02:12:09.553Z] ====== philosophers (scala) [default], iteration 1 completed (5002.829 ms) ====== [2025-11-13T02:12:09.553Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-13T02:12:09.553Z] GC before operation: completed in 9.390 ms, heap usage 35.009 MB -> 5.060 MB. [2025-11-13T02:12:14.977Z] Camera thread performed 122 scans. [2025-11-13T02:12:14.977Z] ====== philosophers (scala) [default], iteration 2 completed (5109.933 ms) ====== [2025-11-13T02:12:14.977Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-13T02:12:14.977Z] GC before operation: completed in 10.887 ms, heap usage 12.815 MB -> 5.061 MB. [2025-11-13T02:12:20.400Z] Camera thread performed 122 scans. [2025-11-13T02:12:20.401Z] ====== philosophers (scala) [default], iteration 3 completed (4911.849 ms) ====== [2025-11-13T02:12:20.401Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-13T02:12:20.401Z] GC before operation: completed in 10.321 ms, heap usage 32.769 MB -> 5.062 MB. [2025-11-13T02:12:25.848Z] Camera thread performed 122 scans. [2025-11-13T02:12:25.848Z] ====== philosophers (scala) [default], iteration 4 completed (6088.433 ms) ====== [2025-11-13T02:12:25.848Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-13T02:12:25.848Z] GC before operation: completed in 9.369 ms, heap usage 14.894 MB -> 5.062 MB. [2025-11-13T02:12:31.273Z] Camera thread performed 122 scans. [2025-11-13T02:12:31.273Z] ====== philosophers (scala) [default], iteration 5 completed (5151.647 ms) ====== [2025-11-13T02:12:31.273Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-13T02:12:31.273Z] GC before operation: completed in 10.603 ms, heap usage 18.789 MB -> 5.062 MB. [2025-11-13T02:12:36.688Z] Camera thread performed 122 scans. [2025-11-13T02:12:36.688Z] ====== philosophers (scala) [default], iteration 6 completed (5607.822 ms) ====== [2025-11-13T02:12:36.688Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-13T02:12:36.688Z] GC before operation: completed in 12.102 ms, heap usage 34.678 MB -> 5.063 MB. [2025-11-13T02:12:42.107Z] Camera thread performed 122 scans. [2025-11-13T02:12:42.107Z] ====== philosophers (scala) [default], iteration 7 completed (5073.509 ms) ====== [2025-11-13T02:12:42.107Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-13T02:12:42.107Z] GC before operation: completed in 10.335 ms, heap usage 29.559 MB -> 5.063 MB. [2025-11-13T02:12:48.247Z] Camera thread performed 122 scans. [2025-11-13T02:12:48.248Z] ====== philosophers (scala) [default], iteration 8 completed (5911.729 ms) ====== [2025-11-13T02:12:48.248Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-13T02:12:48.248Z] GC before operation: completed in 9.965 ms, heap usage 10.832 MB -> 5.064 MB. [2025-11-13T02:12:53.673Z] Camera thread performed 122 scans. [2025-11-13T02:12:53.673Z] ====== philosophers (scala) [default], iteration 9 completed (5320.724 ms) ====== [2025-11-13T02:12:53.673Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-13T02:12:53.673Z] GC before operation: completed in 11.130 ms, heap usage 25.170 MB -> 5.063 MB. [2025-11-13T02:12:57.865Z] Camera thread performed 122 scans. [2025-11-13T02:12:57.865Z] ====== philosophers (scala) [default], iteration 10 completed (5120.521 ms) ====== [2025-11-13T02:12:57.865Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-13T02:12:57.865Z] GC before operation: completed in 10.741 ms, heap usage 29.513 MB -> 5.064 MB. [2025-11-13T02:13:03.285Z] Camera thread performed 122 scans. [2025-11-13T02:13:03.285Z] ====== philosophers (scala) [default], iteration 11 completed (5004.928 ms) ====== [2025-11-13T02:13:03.285Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-13T02:13:03.285Z] GC before operation: completed in 11.850 ms, heap usage 44.555 MB -> 5.064 MB. [2025-11-13T02:13:08.787Z] Camera thread performed 122 scans. [2025-11-13T02:13:08.787Z] ====== philosophers (scala) [default], iteration 12 completed (5648.566 ms) ====== [2025-11-13T02:13:08.787Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-13T02:13:08.787Z] GC before operation: completed in 9.741 ms, heap usage 12.579 MB -> 5.065 MB. [2025-11-13T02:13:14.214Z] Camera thread performed 122 scans. [2025-11-13T02:13:14.214Z] ====== philosophers (scala) [default], iteration 13 completed (5654.866 ms) ====== [2025-11-13T02:13:14.214Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-13T02:13:14.214Z] GC before operation: completed in 9.835 ms, heap usage 37.536 MB -> 5.065 MB. [2025-11-13T02:13:19.643Z] Camera thread performed 122 scans. [2025-11-13T02:13:19.643Z] ====== philosophers (scala) [default], iteration 14 completed (5162.206 ms) ====== [2025-11-13T02:13:19.643Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-13T02:13:19.643Z] GC before operation: completed in 9.086 ms, heap usage 11.840 MB -> 5.065 MB. [2025-11-13T02:13:25.067Z] Camera thread performed 122 scans. [2025-11-13T02:13:25.067Z] ====== philosophers (scala) [default], iteration 15 completed (5253.975 ms) ====== [2025-11-13T02:13:25.067Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-13T02:13:25.067Z] GC before operation: completed in 9.866 ms, heap usage 36.721 MB -> 5.065 MB. [2025-11-13T02:13:30.493Z] Camera thread performed 122 scans. [2025-11-13T02:13:30.493Z] ====== philosophers (scala) [default], iteration 16 completed (5197.581 ms) ====== [2025-11-13T02:13:30.493Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-13T02:13:30.493Z] GC before operation: completed in 11.101 ms, heap usage 18.541 MB -> 5.065 MB. [2025-11-13T02:13:35.917Z] Camera thread performed 122 scans. [2025-11-13T02:13:35.917Z] ====== philosophers (scala) [default], iteration 17 completed (5368.830 ms) ====== [2025-11-13T02:13:35.917Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-13T02:13:35.917Z] GC before operation: completed in 10.896 ms, heap usage 24.869 MB -> 5.065 MB. [2025-11-13T02:13:40.109Z] Camera thread performed 122 scans. [2025-11-13T02:13:40.109Z] ====== philosophers (scala) [default], iteration 18 completed (4691.641 ms) ====== [2025-11-13T02:13:40.109Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-13T02:13:40.109Z] GC before operation: completed in 12.201 ms, heap usage 38.301 MB -> 5.065 MB. [2025-11-13T02:13:46.867Z] Camera thread performed 122 scans. [2025-11-13T02:13:46.867Z] ====== philosophers (scala) [default], iteration 19 completed (5856.499 ms) ====== [2025-11-13T02:13:46.867Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-13T02:13:46.867Z] GC before operation: completed in 13.179 ms, heap usage 25.525 MB -> 5.065 MB. [2025-11-13T02:13:52.285Z] Camera thread performed 122 scans. [2025-11-13T02:13:52.285Z] ====== philosophers (scala) [default], iteration 20 completed (5518.735 ms) ====== [2025-11-13T02:13:52.285Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-13T02:13:52.285Z] GC before operation: completed in 11.242 ms, heap usage 9.921 MB -> 5.066 MB. [2025-11-13T02:13:57.356Z] Camera thread performed 122 scans. [2025-11-13T02:13:57.356Z] ====== philosophers (scala) [default], iteration 21 completed (5609.229 ms) ====== [2025-11-13T02:13:57.356Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-13T02:13:57.356Z] GC before operation: completed in 10.154 ms, heap usage 39.572 MB -> 5.066 MB. [2025-11-13T02:14:02.747Z] Camera thread performed 122 scans. [2025-11-13T02:14:02.747Z] ====== philosophers (scala) [default], iteration 22 completed (5684.231 ms) ====== [2025-11-13T02:14:02.747Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-13T02:14:02.747Z] GC before operation: completed in 10.329 ms, heap usage 17.556 MB -> 5.066 MB. [2025-11-13T02:14:09.456Z] Camera thread performed 122 scans. [2025-11-13T02:14:09.456Z] ====== philosophers (scala) [default], iteration 23 completed (5686.087 ms) ====== [2025-11-13T02:14:09.456Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-13T02:14:09.456Z] GC before operation: completed in 10.601 ms, heap usage 28.239 MB -> 5.066 MB. [2025-11-13T02:14:14.838Z] Camera thread performed 122 scans. [2025-11-13T02:14:14.838Z] ====== philosophers (scala) [default], iteration 24 completed (5951.130 ms) ====== [2025-11-13T02:14:14.838Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-13T02:14:14.838Z] GC before operation: completed in 12.066 ms, heap usage 10.307 MB -> 5.066 MB. [2025-11-13T02:14:20.231Z] Camera thread performed 122 scans. [2025-11-13T02:14:20.231Z] ====== philosophers (scala) [default], iteration 25 completed (5452.572 ms) ====== [2025-11-13T02:14:20.231Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-13T02:14:20.231Z] GC before operation: completed in 12.706 ms, heap usage 23.766 MB -> 5.065 MB. [2025-11-13T02:14:25.611Z] Camera thread performed 122 scans. [2025-11-13T02:14:25.611Z] ====== philosophers (scala) [default], iteration 26 completed (5331.703 ms) ====== [2025-11-13T02:14:25.611Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-13T02:14:25.611Z] GC before operation: completed in 10.392 ms, heap usage 7.329 MB -> 5.066 MB. [2025-11-13T02:14:31.047Z] Camera thread performed 122 scans. [2025-11-13T02:14:31.047Z] ====== philosophers (scala) [default], iteration 27 completed (4964.464 ms) ====== [2025-11-13T02:14:31.047Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-13T02:14:31.047Z] GC before operation: completed in 10.860 ms, heap usage 27.287 MB -> 5.065 MB. [2025-11-13T02:14:35.202Z] Camera thread performed 122 scans. [2025-11-13T02:14:35.202Z] ====== philosophers (scala) [default], iteration 28 completed (4480.273 ms) ====== [2025-11-13T02:14:35.202Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-13T02:14:35.202Z] GC before operation: completed in 15.325 ms, heap usage 6.759 MB -> 5.066 MB. [2025-11-13T02:14:39.359Z] Camera thread performed 122 scans. [2025-11-13T02:14:39.359Z] ====== philosophers (scala) [default], iteration 29 completed (4742.773 ms) ====== [2025-11-13T02:14:40.313Z] ----------------------------------- [2025-11-13T02:14:40.313Z] renaissance-philosophers_0_PASSED [2025-11-13T02:14:40.313Z] ----------------------------------- [2025-11-13T02:14:40.313Z] [2025-11-13T02:14:40.313Z] TEST TEARDOWN: [2025-11-13T02:14:40.313Z] Nothing to be done for teardown. [2025-11-13T02:14:40.313Z] renaissance-philosophers_0 Finish Time: Thu Nov 13 02:14:39 2025 Epoch Time (ms): 1763000079436