[2025-11-26T23:13:48.408Z] Running test renaissance-philosophers_0 ... [2025-11-26T23:13:48.408Z] =============================================== [2025-11-26T23:13:48.408Z] renaissance-philosophers_0 Start Time: Wed Nov 26 23:13:48 2025 Epoch Time (ms): 1764198828291 [2025-11-26T23:13:48.408Z] variation: NoOptions [2025-11-26T23:13:48.408Z] JVM_OPTIONS: [2025-11-26T23:13:48.408Z] { \ [2025-11-26T23:13:48.408Z] echo ""; echo "TEST SETUP:"; \ [2025-11-26T23:13:48.408Z] echo "Nothing to be done for setup."; \ [2025-11-26T23:13:48.408Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17641969269689/renaissance-philosophers_0"; \ [2025-11-26T23:13:48.408Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17641969269689/renaissance-philosophers_0"; \ [2025-11-26T23:13:48.408Z] echo ""; echo "TESTING:"; \ [2025-11-26T23:13:48.408Z] "/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_17641969269689/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-26T23:13:48.408Z] 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_17641969269689/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-26T23:13:48.408Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-26T23:13:48.408Z] echo "Nothing to be done for teardown."; \ [2025-11-26T23:13:48.408Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17641969269689/TestTargetResult"; [2025-11-26T23:13:48.408Z] [2025-11-26T23:13:48.408Z] TEST SETUP: [2025-11-26T23:13:48.408Z] Nothing to be done for setup. [2025-11-26T23:13:48.408Z] [2025-11-26T23:13:48.408Z] TESTING: [2025-11-26T23:13:49.369Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-26T23:13:49.369Z] GC before operation: completed in 16.942 ms, heap usage 14.318 MB -> 4.696 MB. [2025-11-26T23:13:56.425Z] Camera thread performed 122 scans. [2025-11-26T23:13:56.425Z] ====== philosophers (scala) [default], iteration 0 completed (6638.621 ms) ====== [2025-11-26T23:13:56.425Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-26T23:13:56.425Z] GC before operation: completed in 15.631 ms, heap usage 37.402 MB -> 5.065 MB. [2025-11-26T23:14:03.167Z] Camera thread performed 122 scans. [2025-11-26T23:14:03.167Z] ====== philosophers (scala) [default], iteration 1 completed (6087.565 ms) ====== [2025-11-26T23:14:03.167Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-26T23:14:03.167Z] GC before operation: completed in 12.197 ms, heap usage 20.101 MB -> 5.066 MB. [2025-11-26T23:14:08.131Z] Camera thread performed 122 scans. [2025-11-26T23:14:08.131Z] ====== philosophers (scala) [default], iteration 2 completed (5936.954 ms) ====== [2025-11-26T23:14:08.131Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-26T23:14:08.131Z] GC before operation: completed in 11.821 ms, heap usage 12.317 MB -> 5.067 MB. [2025-11-26T23:14:15.061Z] Camera thread performed 122 scans. [2025-11-26T23:14:15.061Z] ====== philosophers (scala) [default], iteration 3 completed (5830.279 ms) ====== [2025-11-26T23:14:15.061Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-26T23:14:15.061Z] GC before operation: completed in 10.611 ms, heap usage 20.084 MB -> 5.067 MB. [2025-11-26T23:14:19.370Z] Camera thread performed 122 scans. [2025-11-26T23:14:19.370Z] ====== philosophers (scala) [default], iteration 4 completed (5483.346 ms) ====== [2025-11-26T23:14:19.370Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-26T23:14:19.370Z] GC before operation: completed in 11.819 ms, heap usage 23.060 MB -> 5.067 MB. [2025-11-26T23:14:24.992Z] Camera thread performed 122 scans. [2025-11-26T23:14:24.992Z] ====== philosophers (scala) [default], iteration 5 completed (5685.089 ms) ====== [2025-11-26T23:14:24.992Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-26T23:14:24.992Z] GC before operation: completed in 13.924 ms, heap usage 30.551 MB -> 5.068 MB. [2025-11-26T23:14:31.742Z] Camera thread performed 122 scans. [2025-11-26T23:14:31.742Z] ====== philosophers (scala) [default], iteration 6 completed (5746.604 ms) ====== [2025-11-26T23:14:31.742Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-26T23:14:31.742Z] GC before operation: completed in 16.619 ms, heap usage 25.188 MB -> 5.068 MB. [2025-11-26T23:14:35.927Z] Camera thread performed 122 scans. [2025-11-26T23:14:35.927Z] ====== philosophers (scala) [default], iteration 7 completed (4813.752 ms) ====== [2025-11-26T23:14:35.927Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-26T23:14:35.927Z] GC before operation: completed in 11.199 ms, heap usage 24.660 MB -> 5.068 MB. [2025-11-26T23:14:42.072Z] Camera thread performed 122 scans. [2025-11-26T23:14:42.072Z] ====== philosophers (scala) [default], iteration 8 completed (5013.250 ms) ====== [2025-11-26T23:14:42.072Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-26T23:14:42.072Z] GC before operation: completed in 11.048 ms, heap usage 14.576 MB -> 5.069 MB. [2025-11-26T23:14:47.843Z] Camera thread performed 122 scans. [2025-11-26T23:14:47.843Z] ====== philosophers (scala) [default], iteration 9 completed (6132.947 ms) ====== [2025-11-26T23:14:47.843Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-26T23:14:47.843Z] GC before operation: completed in 11.155 ms, heap usage 25.052 MB -> 5.069 MB. [2025-11-26T23:14:56.204Z] Camera thread performed 122 scans. [2025-11-26T23:14:56.204Z] ====== philosophers (scala) [default], iteration 10 completed (6010.250 ms) ====== [2025-11-26T23:14:56.204Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-26T23:14:56.204Z] GC before operation: completed in 11.123 ms, heap usage 14.527 MB -> 5.069 MB. [2025-11-26T23:14:59.247Z] Camera thread performed 122 scans. [2025-11-26T23:14:59.247Z] ====== philosophers (scala) [default], iteration 11 completed (6141.249 ms) ====== [2025-11-26T23:14:59.247Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-26T23:14:59.247Z] GC before operation: completed in 13.733 ms, heap usage 11.721 MB -> 5.069 MB. [2025-11-26T23:15:06.085Z] Camera thread performed 122 scans. [2025-11-26T23:15:06.085Z] ====== philosophers (scala) [default], iteration 12 completed (5886.047 ms) ====== [2025-11-26T23:15:06.085Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-26T23:15:06.085Z] GC before operation: completed in 12.712 ms, heap usage 15.601 MB -> 5.070 MB. [2025-11-26T23:15:12.450Z] Camera thread performed 122 scans. [2025-11-26T23:15:12.450Z] ====== philosophers (scala) [default], iteration 13 completed (5722.993 ms) ====== [2025-11-26T23:15:12.450Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-26T23:15:12.450Z] GC before operation: completed in 12.565 ms, heap usage 30.731 MB -> 5.070 MB. [2025-11-26T23:15:19.133Z] Camera thread performed 122 scans. [2025-11-26T23:15:19.133Z] ====== philosophers (scala) [default], iteration 14 completed (6080.375 ms) ====== [2025-11-26T23:15:19.133Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-26T23:15:19.133Z] GC before operation: completed in 16.537 ms, heap usage 14.083 MB -> 5.070 MB. [2025-11-26T23:15:26.238Z] Camera thread performed 122 scans. [2025-11-26T23:15:26.238Z] ====== philosophers (scala) [default], iteration 15 completed (6159.312 ms) ====== [2025-11-26T23:15:26.238Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-26T23:15:26.238Z] GC before operation: completed in 15.125 ms, heap usage 29.133 MB -> 5.070 MB. [2025-11-26T23:15:29.432Z] Camera thread performed 122 scans. [2025-11-26T23:15:29.432Z] ====== philosophers (scala) [default], iteration 16 completed (6095.557 ms) ====== [2025-11-26T23:15:29.432Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-26T23:15:29.432Z] GC before operation: completed in 19.646 ms, heap usage 34.619 MB -> 5.070 MB. [2025-11-26T23:15:36.011Z] Camera thread performed 122 scans. [2025-11-26T23:15:36.011Z] ====== philosophers (scala) [default], iteration 17 completed (5994.267 ms) ====== [2025-11-26T23:15:36.011Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-26T23:15:36.011Z] GC before operation: completed in 17.063 ms, heap usage 12.357 MB -> 5.070 MB. [2025-11-26T23:15:41.498Z] Camera thread performed 122 scans. [2025-11-26T23:15:41.498Z] ====== philosophers (scala) [default], iteration 18 completed (6150.360 ms) ====== [2025-11-26T23:15:41.498Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-26T23:15:41.498Z] GC before operation: completed in 13.354 ms, heap usage 26.628 MB -> 5.070 MB. [2025-11-26T23:15:48.216Z] Camera thread performed 122 scans. [2025-11-26T23:15:48.216Z] ====== philosophers (scala) [default], iteration 19 completed (6374.076 ms) ====== [2025-11-26T23:15:48.216Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-26T23:15:48.216Z] GC before operation: completed in 11.640 ms, heap usage 27.530 MB -> 5.070 MB. [2025-11-26T23:15:53.969Z] Camera thread performed 122 scans. [2025-11-26T23:15:53.969Z] ====== philosophers (scala) [default], iteration 20 completed (5820.125 ms) ====== [2025-11-26T23:15:53.969Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-26T23:15:53.969Z] GC before operation: completed in 11.011 ms, heap usage 11.175 MB -> 5.070 MB. [2025-11-26T23:16:01.073Z] Camera thread performed 122 scans. [2025-11-26T23:16:01.073Z] ====== philosophers (scala) [default], iteration 21 completed (6947.898 ms) ====== [2025-11-26T23:16:01.073Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-26T23:16:01.074Z] GC before operation: completed in 10.938 ms, heap usage 17.929 MB -> 5.070 MB. [2025-11-26T23:16:08.035Z] Camera thread performed 122 scans. [2025-11-26T23:16:08.035Z] ====== philosophers (scala) [default], iteration 22 completed (6465.648 ms) ====== [2025-11-26T23:16:08.035Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-26T23:16:08.035Z] GC before operation: completed in 14.050 ms, heap usage 10.269 MB -> 5.070 MB. [2025-11-26T23:16:14.356Z] Camera thread performed 122 scans. [2025-11-26T23:16:14.356Z] ====== philosophers (scala) [default], iteration 23 completed (6115.129 ms) ====== [2025-11-26T23:16:14.356Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-26T23:16:14.356Z] GC before operation: completed in 15.624 ms, heap usage 19.167 MB -> 5.070 MB. [2025-11-26T23:16:20.276Z] Camera thread performed 122 scans. [2025-11-26T23:16:20.276Z] ====== philosophers (scala) [default], iteration 24 completed (6469.985 ms) ====== [2025-11-26T23:16:20.276Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-26T23:16:20.276Z] GC before operation: completed in 10.803 ms, heap usage 5.808 MB -> 5.070 MB. [2025-11-26T23:16:25.897Z] Camera thread performed 122 scans. [2025-11-26T23:16:25.897Z] ====== philosophers (scala) [default], iteration 25 completed (5890.215 ms) ====== [2025-11-26T23:16:25.897Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-26T23:16:25.897Z] GC before operation: completed in 12.601 ms, heap usage 27.265 MB -> 5.070 MB. [2025-11-26T23:16:31.571Z] Camera thread performed 122 scans. [2025-11-26T23:16:31.571Z] ====== philosophers (scala) [default], iteration 26 completed (5039.391 ms) ====== [2025-11-26T23:16:31.571Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-26T23:16:31.571Z] GC before operation: completed in 10.913 ms, heap usage 6.544 MB -> 5.071 MB. [2025-11-26T23:16:37.214Z] Camera thread performed 122 scans. [2025-11-26T23:16:37.214Z] ====== philosophers (scala) [default], iteration 27 completed (5920.191 ms) ====== [2025-11-26T23:16:37.214Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-26T23:16:37.214Z] GC before operation: completed in 10.702 ms, heap usage 34.941 MB -> 5.071 MB. [2025-11-26T23:16:42.845Z] Camera thread performed 122 scans. [2025-11-26T23:16:42.845Z] ====== philosophers (scala) [default], iteration 28 completed (5857.954 ms) ====== [2025-11-26T23:16:42.845Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-26T23:16:42.845Z] GC before operation: completed in 11.347 ms, heap usage 37.756 MB -> 5.071 MB. [2025-11-26T23:16:48.389Z] Camera thread performed 122 scans. [2025-11-26T23:16:48.389Z] ====== philosophers (scala) [default], iteration 29 completed (6028.779 ms) ====== [2025-11-26T23:16:48.389Z] ----------------------------------- [2025-11-26T23:16:48.389Z] renaissance-philosophers_0_PASSED [2025-11-26T23:16:48.389Z] ----------------------------------- [2025-11-26T23:16:48.389Z] [2025-11-26T23:16:48.389Z] TEST TEARDOWN: [2025-11-26T23:16:48.389Z] Nothing to be done for teardown. [2025-11-26T23:16:48.389Z] renaissance-philosophers_0 Finish Time: Wed Nov 26 23:16:48 2025 Epoch Time (ms): 1764199008291