[2025-05-08T08:08:59.222Z] Running test renaissance-philosophers_0 ... [2025-05-08T08:08:59.222Z] =============================================== [2025-05-08T08:08:59.222Z] renaissance-philosophers_0 Start Time: Thu May 8 08:08:58 2025 Epoch Time (ms): 1746691738147 [2025-05-08T08:08:59.222Z] variation: NoOptions [2025-05-08T08:08:59.222Z] JVM_OPTIONS: [2025-05-08T08:08:59.222Z] { \ [2025-05-08T08:08:59.222Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T08:08:59.222Z] echo "Nothing to be done for setup."; \ [2025-05-08T08:08:59.222Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-philosophers_0"; \ [2025-05-08T08:08:59.222Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-philosophers_0"; \ [2025-05-08T08:08:59.222Z] echo ""; echo "TESTING:"; \ [2025-05-08T08:08:59.222Z] "/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_1746689949764/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-08T08:08:59.222Z] 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_1746689949764/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T08:08:59.222Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T08:08:59.222Z] echo "Nothing to be done for teardown."; \ [2025-05-08T08:08:59.222Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/TestTargetResult"; [2025-05-08T08:08:59.222Z] [2025-05-08T08:08:59.222Z] TEST SETUP: [2025-05-08T08:08:59.222Z] Nothing to be done for setup. [2025-05-08T08:08:59.222Z] [2025-05-08T08:08:59.222Z] TESTING: [2025-05-08T08:08:59.222Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-08T08:08:59.222Z] GC before operation: completed in 12.691 ms, heap usage 14.104 MB -> 4.713 MB. [2025-05-08T08:09:05.920Z] Camera thread performed 122 scans. [2025-05-08T08:09:05.920Z] ====== philosophers (scala) [default], iteration 0 completed (6539.501 ms) ====== [2025-05-08T08:09:05.920Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-08T08:09:05.920Z] GC before operation: completed in 13.143 ms, heap usage 32.407 MB -> 5.073 MB. [2025-05-08T08:09:11.322Z] Camera thread performed 122 scans. [2025-05-08T08:09:11.322Z] ====== philosophers (scala) [default], iteration 1 completed (5657.902 ms) ====== [2025-05-08T08:09:11.322Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-08T08:09:11.322Z] GC before operation: completed in 10.284 ms, heap usage 13.162 MB -> 5.073 MB. [2025-05-08T08:09:18.193Z] Camera thread performed 122 scans. [2025-05-08T08:09:18.193Z] ====== philosophers (scala) [default], iteration 2 completed (5602.326 ms) ====== [2025-05-08T08:09:18.193Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-08T08:09:18.193Z] GC before operation: completed in 9.716 ms, heap usage 11.247 MB -> 5.073 MB. [2025-05-08T08:09:23.571Z] Camera thread performed 122 scans. [2025-05-08T08:09:23.571Z] ====== philosophers (scala) [default], iteration 3 completed (5843.229 ms) ====== [2025-05-08T08:09:23.571Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-08T08:09:23.571Z] GC before operation: completed in 14.829 ms, heap usage 23.864 MB -> 5.074 MB. [2025-05-08T08:09:28.944Z] Camera thread performed 122 scans. [2025-05-08T08:09:28.944Z] ====== philosophers (scala) [default], iteration 4 completed (5390.572 ms) ====== [2025-05-08T08:09:28.944Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-08T08:09:28.944Z] GC before operation: completed in 11.221 ms, heap usage 9.968 MB -> 5.074 MB. [2025-05-08T08:09:34.347Z] Camera thread performed 122 scans. [2025-05-08T08:09:34.347Z] ====== philosophers (scala) [default], iteration 5 completed (5473.173 ms) ====== [2025-05-08T08:09:34.347Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-08T08:09:34.347Z] GC before operation: completed in 9.833 ms, heap usage 16.291 MB -> 5.074 MB. [2025-05-08T08:09:39.718Z] Camera thread performed 122 scans. [2025-05-08T08:09:39.718Z] ====== philosophers (scala) [default], iteration 6 completed (5394.911 ms) ====== [2025-05-08T08:09:39.718Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-08T08:09:39.718Z] GC before operation: completed in 13.737 ms, heap usage 17.948 MB -> 5.075 MB. [2025-05-08T08:09:45.090Z] Camera thread performed 122 scans. [2025-05-08T08:09:45.090Z] ====== philosophers (scala) [default], iteration 7 completed (5774.281 ms) ====== [2025-05-08T08:09:45.090Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-08T08:09:45.090Z] GC before operation: completed in 11.263 ms, heap usage 27.028 MB -> 5.075 MB. [2025-05-08T08:09:51.502Z] Camera thread performed 122 scans. [2025-05-08T08:09:51.503Z] ====== philosophers (scala) [default], iteration 8 completed (5811.270 ms) ====== [2025-05-08T08:09:51.503Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-08T08:09:51.503Z] GC before operation: completed in 9.911 ms, heap usage 31.744 MB -> 5.075 MB. [2025-05-08T08:09:56.885Z] Camera thread performed 122 scans. [2025-05-08T08:09:56.885Z] ====== philosophers (scala) [default], iteration 9 completed (5481.737 ms) ====== [2025-05-08T08:09:56.885Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-08T08:09:56.885Z] GC before operation: completed in 9.610 ms, heap usage 8.848 MB -> 5.075 MB. [2025-05-08T08:10:02.262Z] Camera thread performed 122 scans. [2025-05-08T08:10:02.262Z] ====== philosophers (scala) [default], iteration 10 completed (5566.553 ms) ====== [2025-05-08T08:10:02.262Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-08T08:10:02.262Z] GC before operation: completed in 10.682 ms, heap usage 10.338 MB -> 5.075 MB. [2025-05-08T08:10:07.644Z] Camera thread performed 122 scans. [2025-05-08T08:10:07.644Z] ====== philosophers (scala) [default], iteration 11 completed (5834.799 ms) ====== [2025-05-08T08:10:07.644Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-08T08:10:07.644Z] GC before operation: completed in 10.811 ms, heap usage 29.810 MB -> 5.075 MB. [2025-05-08T08:10:14.339Z] Camera thread performed 122 scans. [2025-05-08T08:10:14.339Z] ====== philosophers (scala) [default], iteration 12 completed (5622.289 ms) ====== [2025-05-08T08:10:14.339Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-08T08:10:14.339Z] GC before operation: completed in 9.792 ms, heap usage 26.570 MB -> 5.076 MB. [2025-05-08T08:10:19.714Z] Camera thread performed 122 scans. [2025-05-08T08:10:19.714Z] ====== philosophers (scala) [default], iteration 13 completed (5858.427 ms) ====== [2025-05-08T08:10:19.714Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-08T08:10:19.715Z] GC before operation: completed in 10.310 ms, heap usage 10.853 MB -> 5.076 MB. [2025-05-08T08:10:23.864Z] Camera thread performed 122 scans. [2025-05-08T08:10:23.864Z] ====== philosophers (scala) [default], iteration 14 completed (4746.313 ms) ====== [2025-05-08T08:10:23.864Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-08T08:10:24.816Z] GC before operation: completed in 10.050 ms, heap usage 28.655 MB -> 5.076 MB. [2025-05-08T08:10:28.965Z] Camera thread performed 122 scans. [2025-05-08T08:10:28.965Z] ====== philosophers (scala) [default], iteration 15 completed (4551.255 ms) ====== [2025-05-08T08:10:28.965Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-08T08:10:28.965Z] GC before operation: completed in 9.861 ms, heap usage 34.901 MB -> 5.076 MB. [2025-05-08T08:10:33.115Z] Camera thread performed 122 scans. [2025-05-08T08:10:33.115Z] ====== philosophers (scala) [default], iteration 16 completed (4550.836 ms) ====== [2025-05-08T08:10:33.115Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-08T08:10:33.115Z] GC before operation: completed in 9.748 ms, heap usage 26.236 MB -> 5.076 MB. [2025-05-08T08:10:38.480Z] Camera thread performed 122 scans. [2025-05-08T08:10:38.480Z] ====== philosophers (scala) [default], iteration 17 completed (5346.416 ms) ====== [2025-05-08T08:10:38.480Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-08T08:10:38.480Z] GC before operation: completed in 9.293 ms, heap usage 33.921 MB -> 5.076 MB. [2025-05-08T08:10:43.848Z] Camera thread performed 122 scans. [2025-05-08T08:10:43.848Z] ====== philosophers (scala) [default], iteration 18 completed (5219.097 ms) ====== [2025-05-08T08:10:43.848Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-08T08:10:43.848Z] GC before operation: completed in 8.846 ms, heap usage 6.631 MB -> 5.077 MB. [2025-05-08T08:10:49.216Z] Camera thread performed 122 scans. [2025-05-08T08:10:49.216Z] ====== philosophers (scala) [default], iteration 19 completed (4968.421 ms) ====== [2025-05-08T08:10:49.216Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-08T08:10:49.216Z] GC before operation: completed in 10.278 ms, heap usage 38.972 MB -> 5.076 MB. [2025-05-08T08:10:53.368Z] Camera thread performed 122 scans. [2025-05-08T08:10:53.368Z] ====== philosophers (scala) [default], iteration 20 completed (4622.730 ms) ====== [2025-05-08T08:10:53.368Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-08T08:10:53.368Z] GC before operation: completed in 10.670 ms, heap usage 22.528 MB -> 5.077 MB. [2025-05-08T08:10:58.734Z] Camera thread performed 122 scans. [2025-05-08T08:10:58.734Z] ====== philosophers (scala) [default], iteration 21 completed (4928.842 ms) ====== [2025-05-08T08:10:58.734Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-08T08:10:58.734Z] GC before operation: completed in 9.991 ms, heap usage 37.148 MB -> 5.076 MB. [2025-05-08T08:11:02.880Z] Camera thread performed 122 scans. [2025-05-08T08:11:02.880Z] ====== philosophers (scala) [default], iteration 22 completed (4533.207 ms) ====== [2025-05-08T08:11:02.880Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-08T08:11:02.880Z] GC before operation: completed in 10.200 ms, heap usage 16.682 MB -> 5.077 MB. [2025-05-08T08:11:08.247Z] Camera thread performed 122 scans. [2025-05-08T08:11:08.247Z] ====== philosophers (scala) [default], iteration 23 completed (4516.498 ms) ====== [2025-05-08T08:11:08.247Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-08T08:11:08.247Z] GC before operation: completed in 9.285 ms, heap usage 11.365 MB -> 5.077 MB. [2025-05-08T08:11:12.398Z] Camera thread performed 122 scans. [2025-05-08T08:11:12.398Z] ====== philosophers (scala) [default], iteration 24 completed (4584.492 ms) ====== [2025-05-08T08:11:12.398Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-08T08:11:12.398Z] GC before operation: completed in 11.140 ms, heap usage 14.725 MB -> 5.077 MB. [2025-05-08T08:11:16.549Z] Camera thread performed 122 scans. [2025-05-08T08:11:16.549Z] ====== philosophers (scala) [default], iteration 25 completed (4641.214 ms) ====== [2025-05-08T08:11:16.549Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-08T08:11:16.549Z] GC before operation: completed in 9.946 ms, heap usage 34.622 MB -> 5.077 MB. [2025-05-08T08:11:21.916Z] Camera thread performed 122 scans. [2025-05-08T08:11:21.916Z] ====== philosophers (scala) [default], iteration 26 completed (4820.668 ms) ====== [2025-05-08T08:11:21.916Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-08T08:11:21.916Z] GC before operation: completed in 9.933 ms, heap usage 13.359 MB -> 5.077 MB. [2025-05-08T08:11:27.285Z] Camera thread performed 122 scans. [2025-05-08T08:11:27.285Z] ====== philosophers (scala) [default], iteration 27 completed (4819.646 ms) ====== [2025-05-08T08:11:27.285Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-08T08:11:27.285Z] GC before operation: completed in 9.637 ms, heap usage 21.117 MB -> 5.077 MB. [2025-05-08T08:11:30.298Z] Camera thread performed 122 scans. [2025-05-08T08:11:30.298Z] ====== philosophers (scala) [default], iteration 28 completed (4017.187 ms) ====== [2025-05-08T08:11:30.298Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-08T08:11:30.298Z] GC before operation: completed in 9.995 ms, heap usage 8.078 MB -> 5.077 MB. [2025-05-08T08:11:35.667Z] Camera thread performed 122 scans. [2025-05-08T08:11:35.667Z] ====== philosophers (scala) [default], iteration 29 completed (4678.998 ms) ====== [2025-05-08T08:11:35.667Z] ----------------------------------- [2025-05-08T08:11:35.667Z] renaissance-philosophers_0_PASSED [2025-05-08T08:11:35.667Z] ----------------------------------- [2025-05-08T08:11:35.667Z] [2025-05-08T08:11:35.667Z] TEST TEARDOWN: [2025-05-08T08:11:35.667Z] Nothing to be done for teardown. [2025-05-08T08:11:35.667Z] renaissance-philosophers_0 Finish Time: Thu May 8 08:11:34 2025 Epoch Time (ms): 1746691894924