[2025-06-30T22:41:48.518Z] Running test renaissance-philosophers_0 ... [2025-06-30T22:41:48.518Z] =============================================== [2025-06-30T22:41:48.518Z] renaissance-philosophers_0 Start Time: Mon Jun 30 22:41:47 2025 Epoch Time (ms): 1751323307307 [2025-06-30T22:41:48.518Z] variation: NoOptions [2025-06-30T22:41:48.518Z] JVM_OPTIONS: [2025-06-30T22:41:48.518Z] { \ [2025-06-30T22:41:48.518Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T22:41:48.518Z] echo "Nothing to be done for setup."; \ [2025-06-30T22:41:48.518Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1751314797482/renaissance-philosophers_0"; \ [2025-06-30T22:41:48.518Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1751314797482/renaissance-philosophers_0"; \ [2025-06-30T22:41:48.518Z] echo ""; echo "TESTING:"; \ [2025-06-30T22:41:48.518Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-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_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1751314797482/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-30T22:41:48.518Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1751314797482/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T22:41:48.518Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T22:41:48.518Z] echo "Nothing to be done for teardown."; \ [2025-06-30T22:41:48.518Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1751314797482/TestTargetResult"; [2025-06-30T22:41:48.518Z] [2025-06-30T22:41:48.518Z] TEST SETUP: [2025-06-30T22:41:48.518Z] Nothing to be done for setup. [2025-06-30T22:41:48.518Z] [2025-06-30T22:41:48.518Z] TESTING: [2025-06-30T22:41:51.129Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-30T22:41:51.129Z] GC before operation: completed in 77.119 ms, heap usage 14.570 MB -> 4.668 MB. [2025-06-30T22:42:00.699Z] Camera thread performed 122 scans. [2025-06-30T22:42:00.699Z] ====== philosophers (scala) [default], iteration 0 completed (9899.515 ms) ====== [2025-06-30T22:42:00.699Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-30T22:42:00.699Z] GC before operation: completed in 85.109 ms, heap usage 122.745 MB -> 5.189 MB. [2025-06-30T22:42:05.375Z] Camera thread performed 122 scans. [2025-06-30T22:42:05.375Z] ====== philosophers (scala) [default], iteration 1 completed (3902.094 ms) ====== [2025-06-30T22:42:05.375Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-30T22:42:05.375Z] GC before operation: completed in 28.139 ms, heap usage 130.089 MB -> 5.042 MB. [2025-06-30T22:42:08.080Z] Camera thread performed 122 scans. [2025-06-30T22:42:08.080Z] ====== philosophers (scala) [default], iteration 2 completed (3432.057 ms) ====== [2025-06-30T22:42:08.080Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-30T22:42:08.080Z] GC before operation: completed in 26.099 ms, heap usage 148.058 MB -> 5.077 MB. [2025-06-30T22:42:11.727Z] Camera thread performed 122 scans. [2025-06-30T22:42:11.727Z] ====== philosophers (scala) [default], iteration 3 completed (3345.503 ms) ====== [2025-06-30T22:42:11.727Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-30T22:42:11.727Z] GC before operation: completed in 30.855 ms, heap usage 117.257 MB -> 5.043 MB. [2025-06-30T22:42:15.350Z] Camera thread performed 122 scans. [2025-06-30T22:42:15.350Z] ====== philosophers (scala) [default], iteration 4 completed (3855.160 ms) ====== [2025-06-30T22:42:15.350Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-30T22:42:15.350Z] GC before operation: completed in 46.075 ms, heap usage 132.957 MB -> 5.044 MB. [2025-06-30T22:42:18.985Z] Camera thread performed 122 scans. [2025-06-30T22:42:18.985Z] ====== philosophers (scala) [default], iteration 5 completed (3572.629 ms) ====== [2025-06-30T22:42:18.985Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-30T22:42:18.985Z] GC before operation: completed in 30.696 ms, heap usage 68.727 MB -> 5.044 MB. [2025-06-30T22:42:22.638Z] Camera thread performed 122 scans. [2025-06-30T22:42:22.638Z] ====== philosophers (scala) [default], iteration 6 completed (3329.681 ms) ====== [2025-06-30T22:42:22.638Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-30T22:42:22.638Z] GC before operation: completed in 23.681 ms, heap usage 20.151 MB -> 5.044 MB. [2025-06-30T22:42:25.274Z] Camera thread performed 122 scans. [2025-06-30T22:42:25.274Z] ====== philosophers (scala) [default], iteration 7 completed (3072.529 ms) ====== [2025-06-30T22:42:25.274Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-30T22:42:25.274Z] GC before operation: completed in 32.389 ms, heap usage 94.643 MB -> 5.044 MB. [2025-06-30T22:42:28.881Z] Camera thread performed 122 scans. [2025-06-30T22:42:28.881Z] ====== philosophers (scala) [default], iteration 8 completed (3158.222 ms) ====== [2025-06-30T22:42:28.881Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-30T22:42:28.881Z] GC before operation: completed in 24.349 ms, heap usage 102.236 MB -> 5.045 MB. [2025-06-30T22:42:31.504Z] Camera thread performed 122 scans. [2025-06-30T22:42:31.504Z] ====== philosophers (scala) [default], iteration 9 completed (3027.079 ms) ====== [2025-06-30T22:42:31.504Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-30T22:42:31.504Z] GC before operation: completed in 30.743 ms, heap usage 182.993 MB -> 5.046 MB. [2025-06-30T22:42:35.221Z] Camera thread performed 122 scans. [2025-06-30T22:42:35.221Z] ====== philosophers (scala) [default], iteration 10 completed (3348.728 ms) ====== [2025-06-30T22:42:35.221Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-30T22:42:35.221Z] GC before operation: completed in 30.669 ms, heap usage 108.587 MB -> 5.046 MB. [2025-06-30T22:42:38.862Z] Camera thread performed 122 scans. [2025-06-30T22:42:38.862Z] ====== philosophers (scala) [default], iteration 11 completed (3628.741 ms) ====== [2025-06-30T22:42:38.862Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-30T22:42:38.862Z] GC before operation: completed in 27.861 ms, heap usage 11.688 MB -> 5.046 MB. [2025-06-30T22:42:41.529Z] Camera thread performed 122 scans. [2025-06-30T22:42:41.529Z] ====== philosophers (scala) [default], iteration 12 completed (3113.331 ms) ====== [2025-06-30T22:42:41.529Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-30T22:42:42.337Z] GC before operation: completed in 25.654 ms, heap usage 69.199 MB -> 5.082 MB. [2025-06-30T22:42:45.437Z] Camera thread performed 122 scans. [2025-06-30T22:42:45.437Z] ====== philosophers (scala) [default], iteration 13 completed (3330.510 ms) ====== [2025-06-30T22:42:45.437Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-30T22:42:45.437Z] GC before operation: completed in 26.090 ms, heap usage 171.147 MB -> 5.046 MB. [2025-06-30T22:42:49.062Z] Camera thread performed 122 scans. [2025-06-30T22:42:49.062Z] ====== philosophers (scala) [default], iteration 14 completed (3528.331 ms) ====== [2025-06-30T22:42:49.062Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-30T22:42:49.062Z] GC before operation: completed in 46.138 ms, heap usage 150.140 MB -> 5.047 MB. [2025-06-30T22:42:52.732Z] Camera thread performed 122 scans. [2025-06-30T22:42:52.732Z] ====== philosophers (scala) [default], iteration 15 completed (3280.598 ms) ====== [2025-06-30T22:42:52.732Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-30T22:42:52.732Z] GC before operation: completed in 35.908 ms, heap usage 131.578 MB -> 5.047 MB. [2025-06-30T22:42:55.333Z] Camera thread performed 122 scans. [2025-06-30T22:42:55.333Z] ====== philosophers (scala) [default], iteration 16 completed (2999.111 ms) ====== [2025-06-30T22:42:55.333Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-30T22:42:55.333Z] GC before operation: completed in 30.091 ms, heap usage 87.240 MB -> 5.047 MB. [2025-06-30T22:42:57.953Z] Camera thread performed 122 scans. [2025-06-30T22:42:57.954Z] ====== philosophers (scala) [default], iteration 17 completed (2989.055 ms) ====== [2025-06-30T22:42:57.954Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-30T22:42:57.954Z] GC before operation: completed in 22.946 ms, heap usage 162.198 MB -> 5.047 MB. [2025-06-30T22:43:01.558Z] Camera thread performed 122 scans. [2025-06-30T22:43:01.558Z] ====== philosophers (scala) [default], iteration 18 completed (2945.926 ms) ====== [2025-06-30T22:43:01.558Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-30T22:43:01.558Z] GC before operation: completed in 42.798 ms, heap usage 37.194 MB -> 5.047 MB. [2025-06-30T22:43:05.208Z] Camera thread performed 122 scans. [2025-06-30T22:43:05.208Z] ====== philosophers (scala) [default], iteration 19 completed (3373.404 ms) ====== [2025-06-30T22:43:05.208Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-30T22:43:05.208Z] GC before operation: completed in 53.749 ms, heap usage 126.639 MB -> 5.047 MB. [2025-06-30T22:43:07.870Z] Camera thread performed 122 scans. [2025-06-30T22:43:07.870Z] ====== philosophers (scala) [default], iteration 20 completed (3187.802 ms) ====== [2025-06-30T22:43:07.870Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-30T22:43:07.870Z] GC before operation: completed in 35.953 ms, heap usage 87.134 MB -> 5.048 MB. [2025-06-30T22:43:11.470Z] Camera thread performed 122 scans. [2025-06-30T22:43:11.470Z] ====== philosophers (scala) [default], iteration 21 completed (3095.141 ms) ====== [2025-06-30T22:43:11.470Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-30T22:43:11.470Z] GC before operation: completed in 31.960 ms, heap usage 148.992 MB -> 5.047 MB. [2025-06-30T22:43:14.143Z] Camera thread performed 122 scans. [2025-06-30T22:43:14.143Z] ====== philosophers (scala) [default], iteration 22 completed (3327.688 ms) ====== [2025-06-30T22:43:14.143Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-30T22:43:14.143Z] GC before operation: completed in 28.522 ms, heap usage 88.017 MB -> 5.083 MB. [2025-06-30T22:43:16.805Z] Camera thread performed 122 scans. [2025-06-30T22:43:16.805Z] ====== philosophers (scala) [default], iteration 23 completed (2836.396 ms) ====== [2025-06-30T22:43:16.805Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-30T22:43:17.617Z] GC before operation: completed in 31.696 ms, heap usage 86.707 MB -> 5.082 MB. [2025-06-30T22:43:21.203Z] Camera thread performed 122 scans. [2025-06-30T22:43:21.203Z] ====== philosophers (scala) [default], iteration 24 completed (3527.712 ms) ====== [2025-06-30T22:43:21.203Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-30T22:43:21.203Z] GC before operation: completed in 61.740 ms, heap usage 74.276 MB -> 5.048 MB. [2025-06-30T22:43:23.796Z] Camera thread performed 122 scans. [2025-06-30T22:43:23.796Z] ====== philosophers (scala) [default], iteration 25 completed (3260.950 ms) ====== [2025-06-30T22:43:23.796Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-30T22:43:23.796Z] GC before operation: completed in 41.170 ms, heap usage 47.210 MB -> 5.048 MB. [2025-06-30T22:43:27.469Z] Camera thread performed 122 scans. [2025-06-30T22:43:27.470Z] ====== philosophers (scala) [default], iteration 26 completed (3395.804 ms) ====== [2025-06-30T22:43:27.470Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-30T22:43:27.470Z] GC before operation: completed in 33.076 ms, heap usage 72.633 MB -> 5.048 MB. [2025-06-30T22:43:31.114Z] Camera thread performed 122 scans. [2025-06-30T22:43:31.114Z] ====== philosophers (scala) [default], iteration 27 completed (3315.824 ms) ====== [2025-06-30T22:43:31.114Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-30T22:43:31.114Z] GC before operation: completed in 37.481 ms, heap usage 9.162 MB -> 5.048 MB. [2025-06-30T22:43:34.396Z] Camera thread performed 122 scans. [2025-06-30T22:43:34.396Z] ====== philosophers (scala) [default], iteration 28 completed (3109.376 ms) ====== [2025-06-30T22:43:34.396Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-30T22:43:34.396Z] GC before operation: completed in 78.362 ms, heap usage 85.671 MB -> 5.048 MB. [2025-06-30T22:43:38.018Z] Camera thread performed 122 scans. [2025-06-30T22:43:38.018Z] ====== philosophers (scala) [default], iteration 29 completed (3335.248 ms) ====== [2025-06-30T22:43:38.018Z] ----------------------------------- [2025-06-30T22:43:38.018Z] renaissance-philosophers_0_PASSED [2025-06-30T22:43:38.018Z] ----------------------------------- [2025-06-30T22:43:38.018Z] [2025-06-30T22:43:38.018Z] TEST TEARDOWN: [2025-06-30T22:43:38.018Z] Nothing to be done for teardown. [2025-06-30T22:43:38.018Z] renaissance-philosophers_0 Finish Time: Mon Jun 30 22:43:37 2025 Epoch Time (ms): 1751323417880