[2025-07-15T11:51:20.281Z] Running test renaissance-philosophers_0 ... [2025-07-15T11:51:20.281Z] =============================================== [2025-07-15T11:51:20.281Z] renaissance-philosophers_0 Start Time: Tue Jul 15 11:51:19 2025 Epoch Time (ms): 1752580279650 [2025-07-15T11:51:20.281Z] variation: NoOptions [2025-07-15T11:51:20.281Z] JVM_OPTIONS: [2025-07-15T11:51:20.281Z] { \ [2025-07-15T11:51:20.281Z] echo ""; echo "TEST SETUP:"; \ [2025-07-15T11:51:20.281Z] echo "Nothing to be done for setup."; \ [2025-07-15T11:51:20.281Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17525766866101/renaissance-philosophers_0"; \ [2025-07-15T11:51:20.281Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17525766866101/renaissance-philosophers_0"; \ [2025-07-15T11:51:20.281Z] echo ""; echo "TESTING:"; \ [2025-07-15T11:51:20.281Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17525766866101/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-07-15T11:51:20.281Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17525766866101/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-15T11:51:20.281Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-15T11:51:20.281Z] echo "Nothing to be done for teardown."; \ [2025-07-15T11:51:20.281Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17525766866101/TestTargetResult"; [2025-07-15T11:51:20.281Z] [2025-07-15T11:51:20.281Z] TEST SETUP: [2025-07-15T11:51:20.281Z] Nothing to be done for setup. [2025-07-15T11:51:20.281Z] [2025-07-15T11:51:20.281Z] TESTING: [2025-07-15T11:51:23.710Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-15T11:51:23.710Z] GC before operation: completed in 33.339 ms, heap usage 7.284 MB -> 4.294 MB. [2025-07-15T11:51:33.629Z] Camera thread performed 122 scans. [2025-07-15T11:51:33.629Z] ====== philosophers (scala) [default], iteration 0 completed (9149.054 ms) ====== [2025-07-15T11:51:33.629Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-15T11:51:33.629Z] GC before operation: completed in 21.068 ms, heap usage 60.531 MB -> 4.723 MB. [2025-07-15T11:51:39.434Z] Camera thread performed 122 scans. [2025-07-15T11:51:39.434Z] ====== philosophers (scala) [default], iteration 1 completed (6757.705 ms) ====== [2025-07-15T11:51:39.434Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-15T11:51:39.434Z] GC before operation: completed in 19.399 ms, heap usage 103.304 MB -> 4.725 MB. [2025-07-15T11:51:46.720Z] Camera thread performed 122 scans. [2025-07-15T11:51:46.720Z] ====== philosophers (scala) [default], iteration 2 completed (6845.679 ms) ====== [2025-07-15T11:51:46.720Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-15T11:51:46.720Z] GC before operation: completed in 18.573 ms, heap usage 53.986 MB -> 4.726 MB. [2025-07-15T11:51:53.616Z] Camera thread performed 122 scans. [2025-07-15T11:51:53.616Z] ====== philosophers (scala) [default], iteration 3 completed (6928.368 ms) ====== [2025-07-15T11:51:53.616Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-15T11:51:53.616Z] GC before operation: completed in 18.148 ms, heap usage 110.945 MB -> 4.727 MB. [2025-07-15T11:52:00.513Z] Camera thread performed 122 scans. [2025-07-15T11:52:00.513Z] ====== philosophers (scala) [default], iteration 4 completed (6860.141 ms) ====== [2025-07-15T11:52:00.513Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-15T11:52:00.513Z] GC before operation: completed in 18.945 ms, heap usage 41.980 MB -> 4.728 MB. [2025-07-15T11:52:07.415Z] Camera thread performed 122 scans. [2025-07-15T11:52:07.415Z] ====== philosophers (scala) [default], iteration 5 completed (6738.578 ms) ====== [2025-07-15T11:52:07.415Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-15T11:52:07.415Z] GC before operation: completed in 19.839 ms, heap usage 216.413 MB -> 4.728 MB. [2025-07-15T11:52:14.318Z] Camera thread performed 122 scans. [2025-07-15T11:52:14.318Z] ====== philosophers (scala) [default], iteration 6 completed (7277.256 ms) ====== [2025-07-15T11:52:14.318Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-15T11:52:14.318Z] GC before operation: completed in 24.269 ms, heap usage 198.421 MB -> 4.728 MB. [2025-07-15T11:52:22.676Z] Camera thread performed 122 scans. [2025-07-15T11:52:22.676Z] ====== philosophers (scala) [default], iteration 7 completed (7400.260 ms) ====== [2025-07-15T11:52:22.676Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-15T11:52:22.676Z] GC before operation: completed in 21.054 ms, heap usage 94.425 MB -> 4.728 MB. [2025-07-15T11:52:29.579Z] Camera thread performed 122 scans. [2025-07-15T11:52:29.579Z] ====== philosophers (scala) [default], iteration 8 completed (8084.323 ms) ====== [2025-07-15T11:52:29.579Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-15T11:52:29.579Z] GC before operation: completed in 24.680 ms, heap usage 180.480 MB -> 4.729 MB. [2025-07-15T11:52:37.894Z] Camera thread performed 122 scans. [2025-07-15T11:52:37.894Z] ====== philosophers (scala) [default], iteration 9 completed (7071.709 ms) ====== [2025-07-15T11:52:37.894Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-15T11:52:37.894Z] GC before operation: completed in 21.815 ms, heap usage 239.108 MB -> 4.728 MB. [2025-07-15T11:52:43.520Z] Camera thread performed 122 scans. [2025-07-15T11:52:43.520Z] ====== philosophers (scala) [default], iteration 10 completed (6789.611 ms) ====== [2025-07-15T11:52:43.520Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-15T11:52:43.520Z] GC before operation: completed in 20.600 ms, heap usage 153.441 MB -> 4.729 MB. [2025-07-15T11:52:51.855Z] Camera thread performed 122 scans. [2025-07-15T11:52:51.855Z] ====== philosophers (scala) [default], iteration 11 completed (7423.763 ms) ====== [2025-07-15T11:52:51.855Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-15T11:52:51.855Z] GC before operation: completed in 20.549 ms, heap usage 41.223 MB -> 4.729 MB. [2025-07-15T11:52:58.751Z] Camera thread performed 122 scans. [2025-07-15T11:52:58.751Z] ====== philosophers (scala) [default], iteration 12 completed (7371.184 ms) ====== [2025-07-15T11:52:58.751Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-15T11:52:58.751Z] GC before operation: completed in 22.530 ms, heap usage 48.827 MB -> 4.729 MB. [2025-07-15T11:53:05.661Z] Camera thread performed 122 scans. [2025-07-15T11:53:05.661Z] ====== philosophers (scala) [default], iteration 13 completed (7283.538 ms) ====== [2025-07-15T11:53:05.661Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-15T11:53:05.661Z] GC before operation: completed in 23.326 ms, heap usage 246.782 MB -> 4.729 MB. [2025-07-15T11:53:12.752Z] Camera thread performed 122 scans. [2025-07-15T11:53:12.752Z] ====== philosophers (scala) [default], iteration 14 completed (7087.325 ms) ====== [2025-07-15T11:53:12.752Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-15T11:53:12.752Z] GC before operation: completed in 19.987 ms, heap usage 142.297 MB -> 4.729 MB. [2025-07-15T11:53:19.644Z] Camera thread performed 122 scans. [2025-07-15T11:53:19.644Z] ====== philosophers (scala) [default], iteration 15 completed (6919.745 ms) ====== [2025-07-15T11:53:19.644Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-15T11:53:19.644Z] GC before operation: completed in 21.102 ms, heap usage 54.851 MB -> 4.729 MB. [2025-07-15T11:53:27.953Z] Camera thread performed 122 scans. [2025-07-15T11:53:27.953Z] ====== philosophers (scala) [default], iteration 16 completed (7121.958 ms) ====== [2025-07-15T11:53:27.953Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-15T11:53:27.953Z] GC before operation: completed in 20.842 ms, heap usage 68.803 MB -> 4.729 MB. [2025-07-15T11:53:34.850Z] Camera thread performed 122 scans. [2025-07-15T11:53:34.850Z] ====== philosophers (scala) [default], iteration 17 completed (6845.308 ms) ====== [2025-07-15T11:53:34.850Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-15T11:53:34.850Z] GC before operation: completed in 21.369 ms, heap usage 76.245 MB -> 4.729 MB. [2025-07-15T11:53:40.471Z] Camera thread performed 122 scans. [2025-07-15T11:53:40.471Z] ====== philosophers (scala) [default], iteration 18 completed (6673.349 ms) ====== [2025-07-15T11:53:40.471Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-15T11:53:40.472Z] GC before operation: completed in 21.764 ms, heap usage 9.349 MB -> 4.729 MB. [2025-07-15T11:53:48.786Z] Camera thread performed 122 scans. [2025-07-15T11:53:48.786Z] ====== philosophers (scala) [default], iteration 19 completed (7123.457 ms) ====== [2025-07-15T11:53:48.786Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-15T11:53:48.786Z] GC before operation: completed in 22.221 ms, heap usage 51.231 MB -> 4.729 MB. [2025-07-15T11:53:55.685Z] Camera thread performed 122 scans. [2025-07-15T11:53:55.685Z] ====== philosophers (scala) [default], iteration 20 completed (7110.704 ms) ====== [2025-07-15T11:53:55.685Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-15T11:53:55.685Z] GC before operation: completed in 23.407 ms, heap usage 114.838 MB -> 4.729 MB. [2025-07-15T11:54:02.576Z] Camera thread performed 122 scans. [2025-07-15T11:54:02.576Z] ====== philosophers (scala) [default], iteration 21 completed (6782.054 ms) ====== [2025-07-15T11:54:02.576Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-15T11:54:02.576Z] GC before operation: completed in 21.889 ms, heap usage 131.803 MB -> 4.729 MB. [2025-07-15T11:54:09.485Z] Camera thread performed 122 scans. [2025-07-15T11:54:09.485Z] ====== philosophers (scala) [default], iteration 22 completed (6860.303 ms) ====== [2025-07-15T11:54:09.485Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-15T11:54:09.485Z] GC before operation: completed in 22.442 ms, heap usage 67.137 MB -> 4.729 MB. [2025-07-15T11:54:16.390Z] Camera thread performed 122 scans. [2025-07-15T11:54:16.390Z] ====== philosophers (scala) [default], iteration 23 completed (6928.218 ms) ====== [2025-07-15T11:54:16.390Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-15T11:54:16.390Z] GC before operation: completed in 22.355 ms, heap usage 42.426 MB -> 4.729 MB. [2025-07-15T11:54:22.083Z] Camera thread performed 122 scans. [2025-07-15T11:54:22.083Z] ====== philosophers (scala) [default], iteration 24 completed (6716.183 ms) ====== [2025-07-15T11:54:22.083Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-15T11:54:22.083Z] GC before operation: completed in 26.916 ms, heap usage 233.405 MB -> 4.730 MB. [2025-07-15T11:54:29.163Z] Camera thread performed 122 scans. [2025-07-15T11:54:29.163Z] ====== philosophers (scala) [default], iteration 25 completed (7132.336 ms) ====== [2025-07-15T11:54:29.163Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-15T11:54:29.163Z] GC before operation: completed in 21.964 ms, heap usage 46.912 MB -> 4.729 MB. [2025-07-15T11:54:37.481Z] Camera thread performed 122 scans. [2025-07-15T11:54:37.481Z] ====== philosophers (scala) [default], iteration 26 completed (7066.820 ms) ====== [2025-07-15T11:54:37.481Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-15T11:54:37.481Z] GC before operation: completed in 26.114 ms, heap usage 138.161 MB -> 4.730 MB. [2025-07-15T11:54:44.372Z] Camera thread performed 122 scans. [2025-07-15T11:54:44.372Z] ====== philosophers (scala) [default], iteration 27 completed (7168.553 ms) ====== [2025-07-15T11:54:44.372Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-15T11:54:44.372Z] GC before operation: completed in 19.283 ms, heap usage 63.342 MB -> 4.730 MB. [2025-07-15T11:54:49.992Z] Camera thread performed 122 scans. [2025-07-15T11:54:49.992Z] ====== philosophers (scala) [default], iteration 28 completed (6561.920 ms) ====== [2025-07-15T11:54:49.992Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-15T11:54:50.766Z] GC before operation: completed in 20.177 ms, heap usage 95.857 MB -> 4.730 MB. [2025-07-15T11:54:57.655Z] Camera thread performed 122 scans. [2025-07-15T11:54:57.655Z] ====== philosophers (scala) [default], iteration 29 completed (6618.411 ms) ====== [2025-07-15T11:54:57.655Z] ----------------------------------- [2025-07-15T11:54:57.655Z] renaissance-philosophers_0_PASSED [2025-07-15T11:54:57.655Z] ----------------------------------- [2025-07-15T11:54:57.655Z] [2025-07-15T11:54:57.655Z] TEST TEARDOWN: [2025-07-15T11:54:57.655Z] Nothing to be done for teardown. [2025-07-15T11:54:57.655Z] renaissance-philosophers_0 Finish Time: Tue Jul 15 11:54:56 2025 Epoch Time (ms): 1752580496807