[2025-05-01T08:07:28.004Z] Running test renaissance-philosophers_0 ... [2025-05-01T08:07:28.004Z] =============================================== [2025-05-01T08:07:28.004Z] renaissance-philosophers_0 Start Time: Thu May 1 08:07:27 2025 Epoch Time (ms): 1746086847495 [2025-05-01T08:07:28.004Z] variation: NoOptions [2025-05-01T08:07:28.004Z] JVM_OPTIONS: [2025-05-01T08:07:28.004Z] { \ [2025-05-01T08:07:28.004Z] echo ""; echo "TEST SETUP:"; \ [2025-05-01T08:07:28.004Z] echo "Nothing to be done for setup."; \ [2025-05-01T08:07:28.004Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17460865365667/renaissance-philosophers_0"; \ [2025-05-01T08:07:28.004Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17460865365667/renaissance-philosophers_0"; \ [2025-05-01T08:07:28.004Z] echo ""; echo "TESTING:"; \ [2025-05-01T08:07:28.004Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17460865365667/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-01T08:07:28.004Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17460865365667/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-01T08:07:28.004Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-01T08:07:28.004Z] echo "Nothing to be done for teardown."; \ [2025-05-01T08:07:28.004Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17460865365667/TestTargetResult"; [2025-05-01T08:07:28.004Z] [2025-05-01T08:07:28.004Z] TEST SETUP: [2025-05-01T08:07:28.004Z] Nothing to be done for setup. [2025-05-01T08:07:28.004Z] [2025-05-01T08:07:28.004Z] TESTING: [2025-05-01T08:07:28.954Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-01T08:07:28.954Z] GC before operation: completed in 17.815 ms, heap usage 14.485 MB -> 4.682 MB. [2025-05-01T08:07:35.721Z] Camera thread performed 122 scans. [2025-05-01T08:07:35.721Z] ====== philosophers (scala) [default], iteration 0 completed (6114.462 ms) ====== [2025-05-01T08:07:35.721Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-01T08:07:35.721Z] GC before operation: completed in 15.984 ms, heap usage 43.852 MB -> 5.047 MB. [2025-05-01T08:07:41.083Z] Camera thread performed 122 scans. [2025-05-01T08:07:41.083Z] ====== philosophers (scala) [default], iteration 1 completed (5818.628 ms) ====== [2025-05-01T08:07:41.083Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-01T08:07:41.083Z] GC before operation: completed in 11.490 ms, heap usage 14.246 MB -> 5.046 MB. [2025-05-01T08:07:46.455Z] Camera thread performed 122 scans. [2025-05-01T08:07:46.455Z] ====== philosophers (scala) [default], iteration 2 completed (4836.221 ms) ====== [2025-05-01T08:07:46.455Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-01T08:07:46.455Z] GC before operation: completed in 10.737 ms, heap usage 25.640 MB -> 5.047 MB. [2025-05-01T08:07:51.816Z] Camera thread performed 122 scans. [2025-05-01T08:07:51.816Z] ====== philosophers (scala) [default], iteration 3 completed (5661.247 ms) ====== [2025-05-01T08:07:51.816Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-01T08:07:51.816Z] GC before operation: completed in 13.860 ms, heap usage 8.919 MB -> 5.048 MB. [2025-05-01T08:07:57.180Z] Camera thread performed 122 scans. [2025-05-01T08:07:57.180Z] ====== philosophers (scala) [default], iteration 4 completed (5401.151 ms) ====== [2025-05-01T08:07:57.180Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-01T08:07:57.180Z] GC before operation: completed in 11.182 ms, heap usage 7.679 MB -> 5.048 MB. [2025-05-01T08:08:02.539Z] Camera thread performed 122 scans. [2025-05-01T08:08:02.539Z] ====== philosophers (scala) [default], iteration 5 completed (5254.372 ms) ====== [2025-05-01T08:08:02.539Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-01T08:08:02.539Z] GC before operation: completed in 12.562 ms, heap usage 7.028 MB -> 5.048 MB. [2025-05-01T08:08:07.900Z] Camera thread performed 122 scans. [2025-05-01T08:08:07.900Z] ====== philosophers (scala) [default], iteration 6 completed (5707.623 ms) ====== [2025-05-01T08:08:07.900Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-01T08:08:07.900Z] GC before operation: completed in 11.949 ms, heap usage 40.948 MB -> 5.049 MB. [2025-05-01T08:08:13.260Z] Camera thread performed 122 scans. [2025-05-01T08:08:13.260Z] ====== philosophers (scala) [default], iteration 7 completed (5230.453 ms) ====== [2025-05-01T08:08:13.260Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-01T08:08:13.260Z] GC before operation: completed in 10.737 ms, heap usage 28.287 MB -> 5.049 MB. [2025-05-01T08:08:18.757Z] Camera thread performed 122 scans. [2025-05-01T08:08:18.757Z] ====== philosophers (scala) [default], iteration 8 completed (5398.534 ms) ====== [2025-05-01T08:08:18.757Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-01T08:08:18.757Z] GC before operation: completed in 11.046 ms, heap usage 29.518 MB -> 5.049 MB. [2025-05-01T08:08:22.901Z] Camera thread performed 122 scans. [2025-05-01T08:08:22.901Z] ====== philosophers (scala) [default], iteration 9 completed (4882.749 ms) ====== [2025-05-01T08:08:22.901Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-01T08:08:22.901Z] GC before operation: completed in 11.958 ms, heap usage 37.491 MB -> 5.049 MB. [2025-05-01T08:08:29.585Z] Camera thread performed 122 scans. [2025-05-01T08:08:29.585Z] ====== philosophers (scala) [default], iteration 10 completed (5592.510 ms) ====== [2025-05-01T08:08:29.585Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-01T08:08:29.585Z] GC before operation: completed in 11.713 ms, heap usage 21.283 MB -> 5.050 MB. [2025-05-01T08:08:33.727Z] Camera thread performed 122 scans. [2025-05-01T08:08:33.727Z] ====== philosophers (scala) [default], iteration 11 completed (4749.265 ms) ====== [2025-05-01T08:08:33.727Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-01T08:08:33.727Z] GC before operation: completed in 12.290 ms, heap usage 27.076 MB -> 5.050 MB. [2025-05-01T08:08:40.410Z] Camera thread performed 122 scans. [2025-05-01T08:08:40.410Z] ====== philosophers (scala) [default], iteration 12 completed (6684.003 ms) ====== [2025-05-01T08:08:40.410Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-01T08:08:40.410Z] GC before operation: completed in 14.837 ms, heap usage 22.527 MB -> 5.050 MB. [2025-05-01T08:08:47.093Z] Camera thread performed 122 scans. [2025-05-01T08:08:47.093Z] ====== philosophers (scala) [default], iteration 13 completed (5986.226 ms) ====== [2025-05-01T08:08:47.093Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-01T08:08:47.093Z] GC before operation: completed in 14.868 ms, heap usage 27.180 MB -> 5.050 MB. [2025-05-01T08:08:53.776Z] Camera thread performed 122 scans. [2025-05-01T08:08:53.776Z] ====== philosophers (scala) [default], iteration 14 completed (6533.955 ms) ====== [2025-05-01T08:08:53.776Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-01T08:08:53.776Z] GC before operation: completed in 15.062 ms, heap usage 12.719 MB -> 5.051 MB. [2025-05-01T08:08:58.740Z] Camera thread performed 122 scans. [2025-05-01T08:08:58.740Z] ====== philosophers (scala) [default], iteration 15 completed (5942.454 ms) ====== [2025-05-01T08:08:58.740Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-01T08:08:58.740Z] GC before operation: completed in 14.900 ms, heap usage 28.113 MB -> 5.050 MB. [2025-05-01T08:09:04.119Z] Camera thread performed 122 scans. [2025-05-01T08:09:04.119Z] ====== philosophers (scala) [default], iteration 16 completed (5528.614 ms) ====== [2025-05-01T08:09:04.119Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-01T08:09:04.119Z] GC before operation: completed in 13.720 ms, heap usage 26.293 MB -> 5.051 MB. [2025-05-01T08:09:10.798Z] Camera thread performed 122 scans. [2025-05-01T08:09:10.798Z] ====== philosophers (scala) [default], iteration 17 completed (6377.466 ms) ====== [2025-05-01T08:09:10.798Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-01T08:09:10.798Z] GC before operation: completed in 13.403 ms, heap usage 7.134 MB -> 5.051 MB. [2025-05-01T08:09:17.553Z] Camera thread performed 122 scans. [2025-05-01T08:09:17.553Z] ====== philosophers (scala) [default], iteration 18 completed (6005.669 ms) ====== [2025-05-01T08:09:17.553Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-01T08:09:17.553Z] GC before operation: completed in 13.163 ms, heap usage 29.082 MB -> 5.051 MB. [2025-05-01T08:09:21.695Z] Camera thread performed 122 scans. [2025-05-01T08:09:21.695Z] ====== philosophers (scala) [default], iteration 19 completed (5020.127 ms) ====== [2025-05-01T08:09:21.695Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-01T08:09:21.695Z] GC before operation: completed in 13.119 ms, heap usage 16.570 MB -> 5.051 MB. [2025-05-01T08:09:28.389Z] Camera thread performed 122 scans. [2025-05-01T08:09:28.390Z] ====== philosophers (scala) [default], iteration 20 completed (6151.830 ms) ====== [2025-05-01T08:09:28.390Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-01T08:09:28.390Z] GC before operation: completed in 16.607 ms, heap usage 23.277 MB -> 5.051 MB. [2025-05-01T08:09:33.751Z] Camera thread performed 122 scans. [2025-05-01T08:09:33.751Z] ====== philosophers (scala) [default], iteration 21 completed (5732.422 ms) ====== [2025-05-01T08:09:33.751Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-01T08:09:33.751Z] GC before operation: completed in 15.260 ms, heap usage 17.918 MB -> 5.051 MB. [2025-05-01T08:09:40.425Z] Camera thread performed 122 scans. [2025-05-01T08:09:40.425Z] ====== philosophers (scala) [default], iteration 22 completed (6616.796 ms) ====== [2025-05-01T08:09:40.425Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-01T08:09:40.425Z] GC before operation: completed in 13.703 ms, heap usage 35.284 MB -> 5.051 MB. [2025-05-01T08:09:47.109Z] Camera thread performed 122 scans. [2025-05-01T08:09:47.110Z] ====== philosophers (scala) [default], iteration 23 completed (6100.467 ms) ====== [2025-05-01T08:09:47.110Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-01T08:09:47.110Z] GC before operation: completed in 15.327 ms, heap usage 29.800 MB -> 5.051 MB. [2025-05-01T08:09:52.471Z] Camera thread performed 122 scans. [2025-05-01T08:09:52.472Z] ====== philosophers (scala) [default], iteration 24 completed (6180.381 ms) ====== [2025-05-01T08:09:52.472Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-01T08:09:52.472Z] GC before operation: completed in 16.765 ms, heap usage 39.658 MB -> 5.052 MB. [2025-05-01T08:09:59.155Z] Camera thread performed 122 scans. [2025-05-01T08:09:59.155Z] ====== philosophers (scala) [default], iteration 25 completed (6146.499 ms) ====== [2025-05-01T08:09:59.155Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-01T08:09:59.155Z] GC before operation: completed in 12.216 ms, heap usage 21.602 MB -> 5.051 MB. [2025-05-01T08:10:05.864Z] Camera thread performed 122 scans. [2025-05-01T08:10:05.864Z] ====== philosophers (scala) [default], iteration 26 completed (6222.517 ms) ====== [2025-05-01T08:10:05.864Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-01T08:10:05.864Z] GC before operation: completed in 14.787 ms, heap usage 6.650 MB -> 5.051 MB. [2025-05-01T08:10:11.229Z] Camera thread performed 122 scans. [2025-05-01T08:10:11.229Z] ====== philosophers (scala) [default], iteration 27 completed (5885.839 ms) ====== [2025-05-01T08:10:11.229Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-01T08:10:11.229Z] GC before operation: completed in 13.655 ms, heap usage 34.913 MB -> 5.051 MB. [2025-05-01T08:10:18.087Z] Camera thread performed 122 scans. [2025-05-01T08:10:18.087Z] ====== philosophers (scala) [default], iteration 28 completed (6457.678 ms) ====== [2025-05-01T08:10:18.087Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-01T08:10:18.087Z] GC before operation: completed in 10.659 ms, heap usage 7.141 MB -> 5.052 MB. [2025-05-01T08:10:23.445Z] Camera thread performed 122 scans. [2025-05-01T08:10:23.445Z] ====== philosophers (scala) [default], iteration 29 completed (5810.966 ms) ====== [2025-05-01T08:10:23.445Z] ----------------------------------- [2025-05-01T08:10:23.445Z] renaissance-philosophers_0_PASSED [2025-05-01T08:10:23.445Z] ----------------------------------- [2025-05-01T08:10:23.445Z] [2025-05-01T08:10:23.445Z] TEST TEARDOWN: [2025-05-01T08:10:23.445Z] Nothing to be done for teardown. [2025-05-01T08:10:23.445Z] renaissance-philosophers_0 Finish Time: Thu May 1 08:10:23 2025 Epoch Time (ms): 1746087023033