[2025-05-21T21:26:08.750Z] Running test renaissance-philosophers_0 ... [2025-05-21T21:26:08.750Z] =============================================== [2025-05-21T21:26:08.750Z] renaissance-philosophers_0 Start Time: Wed May 21 21:26:07 2025 Epoch Time (ms): 1747862767812 [2025-05-21T21:26:08.750Z] variation: NoOptions [2025-05-21T21:26:08.750Z] JVM_OPTIONS: [2025-05-21T21:26:08.750Z] { \ [2025-05-21T21:26:08.750Z] echo ""; echo "TEST SETUP:"; \ [2025-05-21T21:26:08.750Z] echo "Nothing to be done for setup."; \ [2025-05-21T21:26:08.750Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-philosophers_0"; \ [2025-05-21T21:26:08.750Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-philosophers_0"; \ [2025-05-21T21:26:08.750Z] echo ""; echo "TESTING:"; \ [2025-05-21T21:26:08.750Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-21T21:26:08.750Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-21T21:26:08.750Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-21T21:26:08.750Z] echo "Nothing to be done for teardown."; \ [2025-05-21T21:26:08.750Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/TestTargetResult"; [2025-05-21T21:26:08.750Z] [2025-05-21T21:26:08.750Z] TEST SETUP: [2025-05-21T21:26:08.750Z] Nothing to be done for setup. [2025-05-21T21:26:08.750Z] [2025-05-21T21:26:08.750Z] TESTING: [2025-05-21T21:26:09.551Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-21T21:26:09.551Z] GC before operation: completed in 21.012 ms, heap usage 7.224 MB -> 4.329 MB. [2025-05-21T21:26:20.007Z] Camera thread performed 122 scans. [2025-05-21T21:26:20.007Z] ====== philosophers (scala) [default], iteration 0 completed (9445.248 ms) ====== [2025-05-21T21:26:20.007Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-21T21:26:20.007Z] GC before operation: completed in 16.444 ms, heap usage 181.009 MB -> 4.768 MB. [2025-05-21T21:26:26.976Z] Camera thread performed 122 scans. [2025-05-21T21:26:26.976Z] ====== philosophers (scala) [default], iteration 1 completed (7967.982 ms) ====== [2025-05-21T21:26:26.976Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-21T21:26:26.976Z] GC before operation: completed in 13.891 ms, heap usage 169.796 MB -> 4.771 MB. [2025-05-21T21:26:35.328Z] Camera thread performed 122 scans. [2025-05-21T21:26:35.328Z] ====== philosophers (scala) [default], iteration 2 completed (7590.820 ms) ====== [2025-05-21T21:26:35.328Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-21T21:26:35.328Z] GC before operation: completed in 15.692 ms, heap usage 138.688 MB -> 4.773 MB. [2025-05-21T21:26:42.252Z] Camera thread performed 122 scans. [2025-05-21T21:26:42.252Z] ====== philosophers (scala) [default], iteration 3 completed (7643.028 ms) ====== [2025-05-21T21:26:42.252Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-21T21:26:42.252Z] GC before operation: completed in 14.233 ms, heap usage 173.761 MB -> 4.773 MB. [2025-05-21T21:26:50.597Z] Camera thread performed 122 scans. [2025-05-21T21:26:50.597Z] ====== philosophers (scala) [default], iteration 4 completed (7772.229 ms) ====== [2025-05-21T21:26:50.597Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-21T21:26:50.597Z] GC before operation: completed in 13.748 ms, heap usage 160.601 MB -> 4.773 MB. [2025-05-21T21:26:57.518Z] Camera thread performed 122 scans. [2025-05-21T21:26:57.518Z] ====== philosophers (scala) [default], iteration 5 completed (7736.526 ms) ====== [2025-05-21T21:26:57.518Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-21T21:26:57.518Z] GC before operation: completed in 13.180 ms, heap usage 149.156 MB -> 4.773 MB. [2025-05-21T21:27:06.039Z] Camera thread performed 122 scans. [2025-05-21T21:27:06.039Z] ====== philosophers (scala) [default], iteration 6 completed (7815.424 ms) ====== [2025-05-21T21:27:06.039Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-21T21:27:06.039Z] GC before operation: completed in 13.994 ms, heap usage 35.652 MB -> 4.773 MB. [2025-05-21T21:27:14.381Z] Camera thread performed 122 scans. [2025-05-21T21:27:14.381Z] ====== philosophers (scala) [default], iteration 7 completed (7783.676 ms) ====== [2025-05-21T21:27:14.381Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-21T21:27:14.381Z] GC before operation: completed in 14.907 ms, heap usage 123.754 MB -> 4.773 MB. [2025-05-21T21:27:21.301Z] Camera thread performed 122 scans. [2025-05-21T21:27:21.301Z] ====== philosophers (scala) [default], iteration 8 completed (7791.692 ms) ====== [2025-05-21T21:27:21.301Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-21T21:27:21.301Z] GC before operation: completed in 14.447 ms, heap usage 8.639 MB -> 4.773 MB. [2025-05-21T21:27:29.648Z] Camera thread performed 122 scans. [2025-05-21T21:27:29.648Z] ====== philosophers (scala) [default], iteration 9 completed (7722.165 ms) ====== [2025-05-21T21:27:29.648Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-21T21:27:29.648Z] GC before operation: completed in 14.925 ms, heap usage 33.767 MB -> 4.773 MB. [2025-05-21T21:27:36.632Z] Camera thread performed 122 scans. [2025-05-21T21:27:36.632Z] ====== philosophers (scala) [default], iteration 10 completed (7818.072 ms) ====== [2025-05-21T21:27:36.632Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-21T21:27:36.632Z] GC before operation: completed in 14.542 ms, heap usage 130.873 MB -> 4.773 MB. [2025-05-21T21:27:44.972Z] Camera thread performed 122 scans. [2025-05-21T21:27:44.972Z] ====== philosophers (scala) [default], iteration 11 completed (7736.330 ms) ====== [2025-05-21T21:27:44.972Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-21T21:27:44.972Z] GC before operation: completed in 14.401 ms, heap usage 153.388 MB -> 4.773 MB. [2025-05-21T21:27:53.321Z] Camera thread performed 122 scans. [2025-05-21T21:27:53.321Z] ====== philosophers (scala) [default], iteration 12 completed (7769.757 ms) ====== [2025-05-21T21:27:53.321Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-21T21:27:53.321Z] GC before operation: completed in 14.866 ms, heap usage 140.387 MB -> 4.773 MB. [2025-05-21T21:28:00.245Z] Camera thread performed 122 scans. [2025-05-21T21:28:00.245Z] ====== philosophers (scala) [default], iteration 13 completed (7563.860 ms) ====== [2025-05-21T21:28:00.245Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-21T21:28:00.245Z] GC before operation: completed in 14.497 ms, heap usage 166.009 MB -> 4.773 MB. [2025-05-21T21:28:08.593Z] Camera thread performed 122 scans. [2025-05-21T21:28:08.593Z] ====== philosophers (scala) [default], iteration 14 completed (7662.608 ms) ====== [2025-05-21T21:28:08.593Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-21T21:28:08.593Z] GC before operation: completed in 14.271 ms, heap usage 173.085 MB -> 4.774 MB. [2025-05-21T21:28:15.514Z] Camera thread performed 122 scans. [2025-05-21T21:28:15.514Z] ====== philosophers (scala) [default], iteration 15 completed (7713.988 ms) ====== [2025-05-21T21:28:15.514Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-21T21:28:15.514Z] GC before operation: completed in 13.645 ms, heap usage 143.439 MB -> 4.773 MB. [2025-05-21T21:28:24.210Z] Camera thread performed 122 scans. [2025-05-21T21:28:24.210Z] ====== philosophers (scala) [default], iteration 16 completed (7923.922 ms) ====== [2025-05-21T21:28:24.210Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-21T21:28:24.210Z] GC before operation: completed in 14.556 ms, heap usage 78.154 MB -> 4.774 MB. [2025-05-21T21:28:31.229Z] Camera thread performed 122 scans. [2025-05-21T21:28:31.229Z] ====== philosophers (scala) [default], iteration 17 completed (7508.137 ms) ====== [2025-05-21T21:28:31.229Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-21T21:28:31.229Z] GC before operation: completed in 13.796 ms, heap usage 124.258 MB -> 4.774 MB. [2025-05-21T21:28:38.148Z] Camera thread performed 122 scans. [2025-05-21T21:28:38.148Z] ====== philosophers (scala) [default], iteration 18 completed (7574.612 ms) ====== [2025-05-21T21:28:38.148Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-21T21:28:38.148Z] GC before operation: completed in 15.387 ms, heap usage 19.541 MB -> 4.774 MB. [2025-05-21T21:28:46.492Z] Camera thread performed 122 scans. [2025-05-21T21:28:46.492Z] ====== philosophers (scala) [default], iteration 19 completed (7575.934 ms) ====== [2025-05-21T21:28:46.492Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-21T21:28:46.492Z] GC before operation: completed in 15.798 ms, heap usage 161.196 MB -> 4.774 MB. [2025-05-21T21:28:53.759Z] Camera thread performed 122 scans. [2025-05-21T21:28:53.759Z] ====== philosophers (scala) [default], iteration 20 completed (7797.021 ms) ====== [2025-05-21T21:28:53.759Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-21T21:28:53.759Z] GC before operation: completed in 13.099 ms, heap usage 51.838 MB -> 4.774 MB. [2025-05-21T21:29:02.104Z] Camera thread performed 122 scans. [2025-05-21T21:29:02.104Z] ====== philosophers (scala) [default], iteration 21 completed (7776.896 ms) ====== [2025-05-21T21:29:02.104Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-21T21:29:02.104Z] GC before operation: completed in 13.957 ms, heap usage 172.911 MB -> 4.774 MB. [2025-05-21T21:29:09.026Z] Camera thread performed 122 scans. [2025-05-21T21:29:09.026Z] ====== philosophers (scala) [default], iteration 22 completed (7606.056 ms) ====== [2025-05-21T21:29:09.026Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-21T21:29:09.026Z] GC before operation: completed in 14.736 ms, heap usage 138.021 MB -> 4.774 MB. [2025-05-21T21:29:17.420Z] Camera thread performed 122 scans. [2025-05-21T21:29:17.420Z] ====== philosophers (scala) [default], iteration 23 completed (7716.677 ms) ====== [2025-05-21T21:29:17.420Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-21T21:29:17.420Z] GC before operation: completed in 13.514 ms, heap usage 43.191 MB -> 4.774 MB. [2025-05-21T21:29:25.779Z] Camera thread performed 122 scans. [2025-05-21T21:29:25.779Z] ====== philosophers (scala) [default], iteration 24 completed (7820.962 ms) ====== [2025-05-21T21:29:25.779Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-21T21:29:25.779Z] GC before operation: completed in 14.325 ms, heap usage 82.002 MB -> 4.774 MB. [2025-05-21T21:29:32.772Z] Camera thread performed 122 scans. [2025-05-21T21:29:32.772Z] ====== philosophers (scala) [default], iteration 25 completed (7741.674 ms) ====== [2025-05-21T21:29:32.773Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-21T21:29:32.773Z] GC before operation: completed in 12.840 ms, heap usage 9.035 MB -> 4.775 MB. [2025-05-21T21:29:41.108Z] Camera thread performed 122 scans. [2025-05-21T21:29:41.108Z] ====== philosophers (scala) [default], iteration 26 completed (7817.258 ms) ====== [2025-05-21T21:29:41.108Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-21T21:29:41.108Z] GC before operation: completed in 13.867 ms, heap usage 12.023 MB -> 4.774 MB. [2025-05-21T21:29:48.024Z] Camera thread performed 122 scans. [2025-05-21T21:29:48.024Z] ====== philosophers (scala) [default], iteration 27 completed (7923.986 ms) ====== [2025-05-21T21:29:48.024Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-21T21:29:48.024Z] GC before operation: completed in 13.197 ms, heap usage 56.419 MB -> 4.775 MB. [2025-05-21T21:29:56.575Z] Camera thread performed 122 scans. [2025-05-21T21:29:56.575Z] ====== philosophers (scala) [default], iteration 28 completed (7756.861 ms) ====== [2025-05-21T21:29:56.575Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-21T21:29:56.575Z] GC before operation: completed in 14.223 ms, heap usage 22.027 MB -> 4.774 MB. [2025-05-21T21:30:04.958Z] Camera thread performed 122 scans. [2025-05-21T21:30:04.958Z] ====== philosophers (scala) [default], iteration 29 completed (7849.260 ms) ====== [2025-05-21T21:30:04.958Z] ----------------------------------- [2025-05-21T21:30:04.958Z] renaissance-philosophers_0_PASSED [2025-05-21T21:30:04.958Z] ----------------------------------- [2025-05-21T21:30:04.958Z] [2025-05-21T21:30:04.958Z] TEST TEARDOWN: [2025-05-21T21:30:04.958Z] Nothing to be done for teardown. [2025-05-21T21:30:04.958Z] renaissance-philosophers_0 Finish Time: Wed May 21 21:30:03 2025 Epoch Time (ms): 1747863003661