[2025-05-07T23:13:52.093Z] Running test renaissance-philosophers_0 ... [2025-05-07T23:13:52.093Z] =============================================== [2025-05-07T23:13:52.093Z] renaissance-philosophers_0 Start Time: Wed May 7 23:13:51 2025 Epoch Time (ms): 1746659631626 [2025-05-07T23:13:52.093Z] variation: NoOptions [2025-05-07T23:13:52.093Z] JVM_OPTIONS: [2025-05-07T23:13:52.093Z] { \ [2025-05-07T23:13:52.094Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T23:13:52.094Z] echo "Nothing to be done for setup."; \ [2025-05-07T23:13:52.094Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17466584511430/renaissance-philosophers_0"; \ [2025-05-07T23:13:52.094Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17466584511430/renaissance-philosophers_0"; \ [2025-05-07T23:13:52.094Z] echo ""; echo "TESTING:"; \ [2025-05-07T23:13:52.094Z] "/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_17466584511430/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-07T23:13:52.094Z] 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_17466584511430/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T23:13:52.094Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T23:13:52.094Z] echo "Nothing to be done for teardown."; \ [2025-05-07T23:13:52.094Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17466584511430/TestTargetResult"; [2025-05-07T23:13:52.094Z] [2025-05-07T23:13:52.094Z] TEST SETUP: [2025-05-07T23:13:52.094Z] Nothing to be done for setup. [2025-05-07T23:13:52.094Z] [2025-05-07T23:13:52.094Z] TESTING: [2025-05-07T23:13:54.561Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-07T23:13:55.332Z] GC before operation: completed in 91.230 ms, heap usage 14.570 MB -> 4.668 MB. [2025-05-07T23:14:04.077Z] Camera thread performed 122 scans. [2025-05-07T23:14:04.078Z] ====== philosophers (scala) [default], iteration 0 completed (8404.018 ms) ====== [2025-05-07T23:14:04.078Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-07T23:14:04.078Z] GC before operation: completed in 67.908 ms, heap usage 75.832 MB -> 5.041 MB. [2025-05-07T23:14:07.594Z] Camera thread performed 122 scans. [2025-05-07T23:14:07.594Z] ====== philosophers (scala) [default], iteration 1 completed (3523.868 ms) ====== [2025-05-07T23:14:07.594Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-07T23:14:07.594Z] GC before operation: completed in 62.370 ms, heap usage 42.165 MB -> 5.040 MB. [2025-05-07T23:14:10.129Z] Camera thread performed 122 scans. [2025-05-07T23:14:10.129Z] ====== philosophers (scala) [default], iteration 2 completed (3163.835 ms) ====== [2025-05-07T23:14:10.129Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-07T23:14:10.129Z] GC before operation: completed in 53.922 ms, heap usage 104.951 MB -> 5.041 MB. [2025-05-07T23:14:13.705Z] Camera thread performed 122 scans. [2025-05-07T23:14:13.705Z] ====== philosophers (scala) [default], iteration 3 completed (3063.137 ms) ====== [2025-05-07T23:14:13.705Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-07T23:14:13.705Z] GC before operation: completed in 40.959 ms, heap usage 120.345 MB -> 5.042 MB. [2025-05-07T23:14:16.247Z] Camera thread performed 122 scans. [2025-05-07T23:14:16.247Z] ====== philosophers (scala) [default], iteration 4 completed (3247.233 ms) ====== [2025-05-07T23:14:16.247Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-07T23:14:17.049Z] GC before operation: completed in 34.912 ms, heap usage 16.759 MB -> 5.042 MB. [2025-05-07T23:14:19.585Z] Camera thread performed 122 scans. [2025-05-07T23:14:19.585Z] ====== philosophers (scala) [default], iteration 5 completed (3069.003 ms) ====== [2025-05-07T23:14:19.585Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-07T23:14:19.585Z] GC before operation: completed in 77.039 ms, heap usage 95.281 MB -> 5.042 MB. [2025-05-07T23:14:23.106Z] Camera thread performed 122 scans. [2025-05-07T23:14:23.106Z] ====== philosophers (scala) [default], iteration 6 completed (3195.164 ms) ====== [2025-05-07T23:14:23.106Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-07T23:14:23.106Z] GC before operation: completed in 26.458 ms, heap usage 100.990 MB -> 5.043 MB. [2025-05-07T23:14:26.650Z] Camera thread performed 122 scans. [2025-05-07T23:14:26.650Z] ====== philosophers (scala) [default], iteration 7 completed (3207.257 ms) ====== [2025-05-07T23:14:26.650Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-07T23:14:26.650Z] GC before operation: completed in 37.992 ms, heap usage 8.162 MB -> 5.044 MB. [2025-05-07T23:14:29.189Z] Camera thread performed 122 scans. [2025-05-07T23:14:29.189Z] ====== philosophers (scala) [default], iteration 8 completed (2911.825 ms) ====== [2025-05-07T23:14:29.189Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-07T23:14:29.189Z] GC before operation: completed in 40.536 ms, heap usage 97.233 MB -> 5.044 MB. [2025-05-07T23:14:31.733Z] Camera thread performed 122 scans. [2025-05-07T23:14:31.733Z] ====== philosophers (scala) [default], iteration 9 completed (2858.366 ms) ====== [2025-05-07T23:14:31.733Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-07T23:14:31.734Z] GC before operation: completed in 26.060 ms, heap usage 90.208 MB -> 5.044 MB. [2025-05-07T23:14:35.170Z] Camera thread performed 122 scans. [2025-05-07T23:14:35.170Z] ====== philosophers (scala) [default], iteration 10 completed (2746.750 ms) ====== [2025-05-07T23:14:35.170Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-07T23:14:35.170Z] GC before operation: completed in 24.862 ms, heap usage 163.232 MB -> 5.044 MB. [2025-05-07T23:14:37.657Z] Camera thread performed 122 scans. [2025-05-07T23:14:37.657Z] ====== philosophers (scala) [default], iteration 11 completed (3030.859 ms) ====== [2025-05-07T23:14:37.657Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-07T23:14:37.657Z] GC before operation: completed in 26.594 ms, heap usage 136.550 MB -> 5.045 MB. [2025-05-07T23:14:41.165Z] Camera thread performed 122 scans. [2025-05-07T23:14:41.165Z] ====== philosophers (scala) [default], iteration 12 completed (3156.840 ms) ====== [2025-05-07T23:14:41.165Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-07T23:14:41.165Z] GC before operation: completed in 20.098 ms, heap usage 60.186 MB -> 5.045 MB. [2025-05-07T23:14:43.779Z] Camera thread performed 122 scans. [2025-05-07T23:14:43.779Z] ====== philosophers (scala) [default], iteration 13 completed (2795.138 ms) ====== [2025-05-07T23:14:43.779Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-07T23:14:43.779Z] GC before operation: completed in 28.333 ms, heap usage 40.633 MB -> 5.045 MB. [2025-05-07T23:14:46.275Z] Camera thread performed 122 scans. [2025-05-07T23:14:46.275Z] ====== philosophers (scala) [default], iteration 14 completed (2727.643 ms) ====== [2025-05-07T23:14:46.275Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-07T23:14:47.093Z] GC before operation: completed in 15.962 ms, heap usage 44.583 MB -> 5.045 MB. [2025-05-07T23:14:49.589Z] Camera thread performed 122 scans. [2025-05-07T23:14:49.590Z] ====== philosophers (scala) [default], iteration 15 completed (2885.057 ms) ====== [2025-05-07T23:14:49.590Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-07T23:14:49.590Z] GC before operation: completed in 35.035 ms, heap usage 63.739 MB -> 5.081 MB. [2025-05-07T23:14:52.085Z] Camera thread performed 122 scans. [2025-05-07T23:14:52.085Z] ====== philosophers (scala) [default], iteration 16 completed (2723.351 ms) ====== [2025-05-07T23:14:52.085Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-07T23:14:52.085Z] GC before operation: completed in 19.291 ms, heap usage 132.690 MB -> 5.046 MB. [2025-05-07T23:14:55.520Z] Camera thread performed 122 scans. [2025-05-07T23:14:55.520Z] ====== philosophers (scala) [default], iteration 17 completed (2635.550 ms) ====== [2025-05-07T23:14:55.520Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-07T23:14:55.520Z] GC before operation: completed in 28.174 ms, heap usage 5.086 MB -> 5.045 MB. [2025-05-07T23:14:57.165Z] Camera thread performed 122 scans. [2025-05-07T23:14:57.165Z] ====== philosophers (scala) [default], iteration 18 completed (2538.705 ms) ====== [2025-05-07T23:14:57.165Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-07T23:14:57.939Z] GC before operation: completed in 32.741 ms, heap usage 134.668 MB -> 5.046 MB. [2025-05-07T23:15:00.461Z] Camera thread performed 122 scans. [2025-05-07T23:15:00.461Z] ====== philosophers (scala) [default], iteration 19 completed (2977.159 ms) ====== [2025-05-07T23:15:00.461Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-07T23:15:00.461Z] GC before operation: completed in 16.623 ms, heap usage 30.710 MB -> 5.046 MB. [2025-05-07T23:15:03.982Z] Camera thread performed 122 scans. [2025-05-07T23:15:03.982Z] ====== philosophers (scala) [default], iteration 20 completed (3070.492 ms) ====== [2025-05-07T23:15:03.982Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-07T23:15:03.982Z] GC before operation: completed in 22.900 ms, heap usage 133.238 MB -> 5.046 MB. [2025-05-07T23:15:06.480Z] Camera thread performed 122 scans. [2025-05-07T23:15:06.480Z] ====== philosophers (scala) [default], iteration 21 completed (2622.699 ms) ====== [2025-05-07T23:15:06.480Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-07T23:15:06.480Z] GC before operation: completed in 41.443 ms, heap usage 70.241 MB -> 5.046 MB. [2025-05-07T23:15:09.050Z] Camera thread performed 122 scans. [2025-05-07T23:15:09.050Z] ====== philosophers (scala) [default], iteration 22 completed (2786.617 ms) ====== [2025-05-07T23:15:09.050Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-07T23:15:09.050Z] GC before operation: completed in 31.188 ms, heap usage 36.681 MB -> 5.046 MB. [2025-05-07T23:15:11.545Z] Camera thread performed 122 scans. [2025-05-07T23:15:11.545Z] ====== philosophers (scala) [default], iteration 23 completed (2767.771 ms) ====== [2025-05-07T23:15:11.545Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-07T23:15:12.336Z] GC before operation: completed in 24.918 ms, heap usage 142.972 MB -> 5.046 MB. [2025-05-07T23:15:14.864Z] Camera thread performed 122 scans. [2025-05-07T23:15:14.864Z] ====== philosophers (scala) [default], iteration 24 completed (2876.984 ms) ====== [2025-05-07T23:15:14.864Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-07T23:15:14.864Z] GC before operation: completed in 24.861 ms, heap usage 61.682 MB -> 5.046 MB. [2025-05-07T23:15:17.442Z] Camera thread performed 122 scans. [2025-05-07T23:15:17.442Z] ====== philosophers (scala) [default], iteration 25 completed (2621.914 ms) ====== [2025-05-07T23:15:17.442Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-07T23:15:17.442Z] GC before operation: completed in 32.159 ms, heap usage 73.713 MB -> 5.046 MB. [2025-05-07T23:15:20.921Z] Camera thread performed 122 scans. [2025-05-07T23:15:20.921Z] ====== philosophers (scala) [default], iteration 26 completed (2927.224 ms) ====== [2025-05-07T23:15:20.921Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-07T23:15:20.921Z] GC before operation: completed in 38.234 ms, heap usage 91.125 MB -> 5.047 MB. [2025-05-07T23:15:23.474Z] Camera thread performed 122 scans. [2025-05-07T23:15:23.474Z] ====== philosophers (scala) [default], iteration 27 completed (3143.171 ms) ====== [2025-05-07T23:15:23.474Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-07T23:15:23.474Z] GC before operation: completed in 40.128 ms, heap usage 22.687 MB -> 5.046 MB. [2025-05-07T23:15:27.026Z] Camera thread performed 122 scans. [2025-05-07T23:15:27.026Z] ====== philosophers (scala) [default], iteration 28 completed (3281.098 ms) ====== [2025-05-07T23:15:27.026Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-07T23:15:27.026Z] GC before operation: completed in 18.324 ms, heap usage 136.090 MB -> 5.047 MB. [2025-05-07T23:15:29.563Z] Camera thread performed 122 scans. [2025-05-07T23:15:29.563Z] ====== philosophers (scala) [default], iteration 29 completed (2583.257 ms) ====== [2025-05-07T23:15:30.332Z] ----------------------------------- [2025-05-07T23:15:30.332Z] renaissance-philosophers_0_PASSED [2025-05-07T23:15:30.332Z] ----------------------------------- [2025-05-07T23:15:30.332Z] [2025-05-07T23:15:30.332Z] TEST TEARDOWN: [2025-05-07T23:15:30.332Z] Nothing to be done for teardown. [2025-05-07T23:15:30.333Z] renaissance-philosophers_0 Finish Time: Wed May 7 23:15:29 2025 Epoch Time (ms): 1746659729721