[2025-04-30T21:56:11.124Z] Running test renaissance-philosophers_0 ... [2025-04-30T21:56:11.124Z] =============================================== [2025-04-30T21:56:11.124Z] renaissance-philosophers_0 Start Time: Wed Apr 30 21:56:10 2025 Epoch Time (ms): 1746050170582 [2025-04-30T21:56:11.124Z] variation: NoOptions [2025-04-30T21:56:11.124Z] JVM_OPTIONS: [2025-04-30T21:56:11.124Z] { \ [2025-04-30T21:56:11.124Z] echo ""; echo "TEST SETUP:"; \ [2025-04-30T21:56:11.124Z] echo "Nothing to be done for setup."; \ [2025-04-30T21:56:11.124Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17460498426678/renaissance-philosophers_0"; \ [2025-04-30T21:56:11.124Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17460498426678/renaissance-philosophers_0"; \ [2025-04-30T21:56:11.124Z] echo ""; echo "TESTING:"; \ [2025-04-30T21:56:11.124Z] "/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_17460498426678/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-04-30T21:56:11.124Z] 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_17460498426678/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-30T21:56:11.124Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-30T21:56:11.124Z] echo "Nothing to be done for teardown."; \ [2025-04-30T21:56:11.124Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17460498426678/TestTargetResult"; [2025-04-30T21:56:11.124Z] [2025-04-30T21:56:11.124Z] TEST SETUP: [2025-04-30T21:56:11.124Z] Nothing to be done for setup. [2025-04-30T21:56:11.124Z] [2025-04-30T21:56:11.124Z] TESTING: [2025-04-30T21:56:11.903Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-04-30T21:56:11.903Z] GC before operation: completed in 17.838 ms, heap usage 14.570 MB -> 4.665 MB. [2025-04-30T21:56:15.359Z] Camera thread performed 122 scans. [2025-04-30T21:56:15.359Z] ====== philosophers (scala) [default], iteration 0 completed (3338.223 ms) ====== [2025-04-30T21:56:15.359Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-04-30T21:56:15.359Z] GC before operation: completed in 21.722 ms, heap usage 9.320 MB -> 5.042 MB. [2025-04-30T21:56:16.964Z] Camera thread performed 122 scans. [2025-04-30T21:56:16.964Z] ====== philosophers (scala) [default], iteration 1 completed (1955.638 ms) ====== [2025-04-30T21:56:16.964Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-04-30T21:56:16.964Z] GC before operation: completed in 17.118 ms, heap usage 60.247 MB -> 5.042 MB. [2025-04-30T21:56:19.450Z] Camera thread performed 122 scans. [2025-04-30T21:56:19.450Z] ====== philosophers (scala) [default], iteration 2 completed (1853.667 ms) ====== [2025-04-30T21:56:19.450Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-04-30T21:56:19.450Z] GC before operation: completed in 16.967 ms, heap usage 38.917 MB -> 5.042 MB. [2025-04-30T21:56:21.055Z] Camera thread performed 122 scans. [2025-04-30T21:56:21.055Z] ====== philosophers (scala) [default], iteration 3 completed (1696.338 ms) ====== [2025-04-30T21:56:21.055Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-04-30T21:56:21.055Z] GC before operation: completed in 14.736 ms, heap usage 44.839 MB -> 5.043 MB. [2025-04-30T21:56:22.666Z] Camera thread performed 122 scans. [2025-04-30T21:56:22.666Z] ====== philosophers (scala) [default], iteration 4 completed (1744.532 ms) ====== [2025-04-30T21:56:22.666Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-04-30T21:56:22.666Z] GC before operation: completed in 13.768 ms, heap usage 18.848 MB -> 5.044 MB. [2025-04-30T21:56:24.271Z] Camera thread performed 122 scans. [2025-04-30T21:56:24.271Z] ====== philosophers (scala) [default], iteration 5 completed (1735.805 ms) ====== [2025-04-30T21:56:24.271Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-04-30T21:56:24.271Z] GC before operation: completed in 15.078 ms, heap usage 28.225 MB -> 5.044 MB. [2025-04-30T21:56:25.875Z] Camera thread performed 122 scans. [2025-04-30T21:56:25.875Z] ====== philosophers (scala) [default], iteration 6 completed (1657.690 ms) ====== [2025-04-30T21:56:25.875Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-04-30T21:56:25.875Z] GC before operation: completed in 13.786 ms, heap usage 44.182 MB -> 5.044 MB. [2025-04-30T21:56:27.478Z] Camera thread performed 122 scans. [2025-04-30T21:56:27.478Z] ====== philosophers (scala) [default], iteration 7 completed (1645.459 ms) ====== [2025-04-30T21:56:27.478Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-04-30T21:56:27.478Z] GC before operation: completed in 15.018 ms, heap usage 33.673 MB -> 5.044 MB. [2025-04-30T21:56:29.080Z] Camera thread performed 122 scans. [2025-04-30T21:56:29.080Z] ====== philosophers (scala) [default], iteration 8 completed (1661.655 ms) ====== [2025-04-30T21:56:29.080Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-04-30T21:56:29.080Z] GC before operation: completed in 15.719 ms, heap usage 18.210 MB -> 5.045 MB. [2025-04-30T21:56:31.570Z] Camera thread performed 122 scans. [2025-04-30T21:56:31.570Z] ====== philosophers (scala) [default], iteration 9 completed (1866.851 ms) ====== [2025-04-30T21:56:31.570Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-04-30T21:56:31.570Z] GC before operation: completed in 14.144 ms, heap usage 67.686 MB -> 5.045 MB. [2025-04-30T21:56:33.287Z] Camera thread performed 122 scans. [2025-04-30T21:56:33.287Z] ====== philosophers (scala) [default], iteration 10 completed (1673.442 ms) ====== [2025-04-30T21:56:33.287Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-04-30T21:56:33.287Z] GC before operation: completed in 15.479 ms, heap usage 79.649 MB -> 5.045 MB. [2025-04-30T21:56:34.888Z] Camera thread performed 122 scans. [2025-04-30T21:56:34.888Z] ====== philosophers (scala) [default], iteration 11 completed (1602.952 ms) ====== [2025-04-30T21:56:34.888Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-04-30T21:56:34.888Z] GC before operation: completed in 15.138 ms, heap usage 15.083 MB -> 5.045 MB. [2025-04-30T21:56:36.492Z] Camera thread performed 122 scans. [2025-04-30T21:56:36.492Z] ====== philosophers (scala) [default], iteration 12 completed (1763.036 ms) ====== [2025-04-30T21:56:36.492Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-04-30T21:56:36.492Z] GC before operation: completed in 15.678 ms, heap usage 42.067 MB -> 5.045 MB. [2025-04-30T21:56:38.097Z] Camera thread performed 122 scans. [2025-04-30T21:56:38.097Z] ====== philosophers (scala) [default], iteration 13 completed (1685.916 ms) ====== [2025-04-30T21:56:38.097Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-04-30T21:56:38.097Z] GC before operation: completed in 16.557 ms, heap usage 87.217 MB -> 5.046 MB. [2025-04-30T21:56:39.696Z] Camera thread performed 122 scans. [2025-04-30T21:56:39.697Z] ====== philosophers (scala) [default], iteration 14 completed (1629.723 ms) ====== [2025-04-30T21:56:39.697Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-04-30T21:56:39.697Z] GC before operation: completed in 13.959 ms, heap usage 61.189 MB -> 5.046 MB. [2025-04-30T21:56:41.297Z] Camera thread performed 122 scans. [2025-04-30T21:56:41.297Z] ====== philosophers (scala) [default], iteration 15 completed (1722.509 ms) ====== [2025-04-30T21:56:41.297Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-04-30T21:56:41.297Z] GC before operation: completed in 16.418 ms, heap usage 58.261 MB -> 5.046 MB. [2025-04-30T21:56:42.901Z] Camera thread performed 122 scans. [2025-04-30T21:56:42.901Z] ====== philosophers (scala) [default], iteration 16 completed (1663.882 ms) ====== [2025-04-30T21:56:42.901Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-04-30T21:56:42.901Z] GC before operation: completed in 13.810 ms, heap usage 45.264 MB -> 5.046 MB. [2025-04-30T21:56:44.500Z] Camera thread performed 122 scans. [2025-04-30T21:56:44.500Z] ====== philosophers (scala) [default], iteration 17 completed (1736.586 ms) ====== [2025-04-30T21:56:44.500Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-04-30T21:56:44.500Z] GC before operation: completed in 13.579 ms, heap usage 27.270 MB -> 5.046 MB. [2025-04-30T21:56:46.989Z] Camera thread performed 122 scans. [2025-04-30T21:56:46.989Z] ====== philosophers (scala) [default], iteration 18 completed (1727.452 ms) ====== [2025-04-30T21:56:46.989Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-04-30T21:56:46.989Z] GC before operation: completed in 14.052 ms, heap usage 24.684 MB -> 5.047 MB. [2025-04-30T21:56:48.622Z] Camera thread performed 122 scans. [2025-04-30T21:56:48.622Z] ====== philosophers (scala) [default], iteration 19 completed (1738.619 ms) ====== [2025-04-30T21:56:48.622Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-04-30T21:56:48.622Z] GC before operation: completed in 14.191 ms, heap usage 69.123 MB -> 5.047 MB. [2025-04-30T21:56:50.222Z] Camera thread performed 122 scans. [2025-04-30T21:56:50.222Z] ====== philosophers (scala) [default], iteration 20 completed (1662.688 ms) ====== [2025-04-30T21:56:50.222Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-04-30T21:56:50.222Z] GC before operation: completed in 16.675 ms, heap usage 52.672 MB -> 5.047 MB. [2025-04-30T21:56:52.056Z] Camera thread performed 122 scans. [2025-04-30T21:56:52.056Z] ====== philosophers (scala) [default], iteration 21 completed (1778.830 ms) ====== [2025-04-30T21:56:52.056Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-04-30T21:56:52.056Z] GC before operation: completed in 15.505 ms, heap usage 40.673 MB -> 5.047 MB. [2025-04-30T21:56:53.667Z] Camera thread performed 122 scans. [2025-04-30T21:56:53.667Z] ====== philosophers (scala) [default], iteration 22 completed (1861.806 ms) ====== [2025-04-30T21:56:53.667Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-04-30T21:56:53.667Z] GC before operation: completed in 14.156 ms, heap usage 49.643 MB -> 5.047 MB. [2025-04-30T21:56:55.267Z] Camera thread performed 122 scans. [2025-04-30T21:56:55.267Z] ====== philosophers (scala) [default], iteration 23 completed (1690.267 ms) ====== [2025-04-30T21:56:55.267Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-04-30T21:56:55.267Z] GC before operation: completed in 15.368 ms, heap usage 32.038 MB -> 5.047 MB. [2025-04-30T21:56:56.917Z] Camera thread performed 122 scans. [2025-04-30T21:56:56.917Z] ====== philosophers (scala) [default], iteration 24 completed (1687.999 ms) ====== [2025-04-30T21:56:56.917Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-04-30T21:56:56.917Z] GC before operation: completed in 14.607 ms, heap usage 29.160 MB -> 5.047 MB. [2025-04-30T21:56:59.288Z] Camera thread performed 122 scans. [2025-04-30T21:56:59.288Z] ====== philosophers (scala) [default], iteration 25 completed (1659.705 ms) ====== [2025-04-30T21:56:59.288Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-04-30T21:56:59.288Z] GC before operation: completed in 13.458 ms, heap usage 28.026 MB -> 5.047 MB. [2025-04-30T21:57:00.949Z] Camera thread performed 122 scans. [2025-04-30T21:57:00.949Z] ====== philosophers (scala) [default], iteration 26 completed (1779.122 ms) ====== [2025-04-30T21:57:00.949Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-04-30T21:57:00.949Z] GC before operation: completed in 13.109 ms, heap usage 6.763 MB -> 5.048 MB. [2025-04-30T21:57:02.681Z] Camera thread performed 122 scans. [2025-04-30T21:57:02.681Z] ====== philosophers (scala) [default], iteration 27 completed (1678.587 ms) ====== [2025-04-30T21:57:02.681Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-04-30T21:57:02.681Z] GC before operation: completed in 14.806 ms, heap usage 74.653 MB -> 5.047 MB. [2025-04-30T21:57:08.682Z] Camera thread performed 122 scans. [2025-04-30T21:57:08.682Z] ====== philosophers (scala) [default], iteration 28 completed (1937.290 ms) ====== [2025-04-30T21:57:08.682Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-04-30T21:57:08.682Z] GC before operation: completed in 15.245 ms, heap usage 29.699 MB -> 5.048 MB. [2025-04-30T21:57:08.682Z] Camera thread performed 122 scans. [2025-04-30T21:57:08.682Z] ====== philosophers (scala) [default], iteration 29 completed (1688.784 ms) ====== [2025-04-30T21:57:08.682Z] ----------------------------------- [2025-04-30T21:57:08.682Z] renaissance-philosophers_0_PASSED [2025-04-30T21:57:08.682Z] ----------------------------------- [2025-04-30T21:57:08.682Z] [2025-04-30T21:57:08.682Z] TEST TEARDOWN: [2025-04-30T21:57:08.682Z] Nothing to be done for teardown. [2025-04-30T21:57:08.682Z] renaissance-philosophers_0 Finish Time: Wed Apr 30 21:57:05 2025 Epoch Time (ms): 1746050225650