[2025-10-02T22:14:48.404Z] Running test renaissance-philosophers_0 ... [2025-10-02T22:14:48.404Z] =============================================== [2025-10-02T22:14:48.404Z] renaissance-philosophers_0 Start Time: Thu Oct 2 15:14:46 2025 Epoch Time (ms): 1759443286876 [2025-10-02T22:14:48.404Z] variation: NoOptions [2025-10-02T22:14:48.404Z] JVM_OPTIONS: [2025-10-02T22:14:48.404Z] { \ [2025-10-02T22:14:48.404Z] echo ""; echo "TEST SETUP:"; \ [2025-10-02T22:14:48.404Z] echo "Nothing to be done for setup."; \ [2025-10-02T22:14:48.404Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17594425709225/renaissance-philosophers_0"; \ [2025-10-02T22:14:48.404Z] cd "/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17594425709225/renaissance-philosophers_0"; \ [2025-10-02T22:14:48.404Z] echo ""; echo "TESTING:"; \ [2025-10-02T22:14:48.404Z] "/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17594425709225/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-10-02T22:14:48.404Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17594425709225/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-02T22:14:48.404Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-02T22:14:48.404Z] echo "Nothing to be done for teardown."; \ [2025-10-02T22:14:48.404Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17594425709225/TestTargetResult"; [2025-10-02T22:14:48.404Z] [2025-10-02T22:14:48.404Z] TEST SETUP: [2025-10-02T22:14:48.404Z] Nothing to be done for setup. [2025-10-02T22:14:48.404Z] [2025-10-02T22:14:48.404Z] TESTING: [2025-10-02T22:14:49.414Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-02T22:14:49.414Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/Users/admin/workspace/workspace/Test_openjdk26_hs_extended.perf_x86-64_mac/aqa-tests/TKG/output_17594425709225/renaissance-philosophers_0/launcher-151447-1330388935139480984/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-02T22:14:49.414Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-02T22:14:49.414Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-02T22:14:50.368Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-10-02T22:14:50.368Z] GC before operation: completed in 71.956 ms, heap usage 6.905 MB -> 4.688 MB. [2025-10-02T22:14:56.503Z] Camera thread performed 122 scans. [2025-10-02T22:14:56.503Z] ====== philosophers (scala) [default], iteration 0 completed (5676.766 ms) ====== [2025-10-02T22:14:56.503Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-10-02T22:14:56.503Z] GC before operation: completed in 72.789 ms, heap usage 13.008 MB -> 5.032 MB. [2025-10-02T22:14:58.139Z] Camera thread performed 122 scans. [2025-10-02T22:14:58.139Z] ====== philosophers (scala) [default], iteration 1 completed (2339.707 ms) ====== [2025-10-02T22:14:58.139Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-10-02T22:14:58.558Z] GC before operation: completed in 90.420 ms, heap usage 11.121 MB -> 5.060 MB. [2025-10-02T22:15:01.534Z] Camera thread performed 122 scans. [2025-10-02T22:15:01.534Z] ====== philosophers (scala) [default], iteration 2 completed (2943.111 ms) ====== [2025-10-02T22:15:01.534Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-10-02T22:15:01.534Z] GC before operation: completed in 29.575 ms, heap usage 136.595 MB -> 5.034 MB. [2025-10-02T22:15:03.622Z] Camera thread performed 122 scans. [2025-10-02T22:15:03.622Z] ====== philosophers (scala) [default], iteration 3 completed (2323.342 ms) ====== [2025-10-02T22:15:03.622Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-10-02T22:15:03.622Z] GC before operation: completed in 95.088 ms, heap usage 30.613 MB -> 5.061 MB. [2025-10-02T22:15:05.190Z] Camera thread performed 122 scans. [2025-10-02T22:15:05.190Z] ====== philosophers (scala) [default], iteration 4 completed (1467.974 ms) ====== [2025-10-02T22:15:05.190Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-10-02T22:15:05.190Z] GC before operation: completed in 47.133 ms, heap usage 24.718 MB -> 5.035 MB. [2025-10-02T22:15:07.206Z] Camera thread performed 122 scans. [2025-10-02T22:15:07.206Z] ====== philosophers (scala) [default], iteration 5 completed (2095.587 ms) ====== [2025-10-02T22:15:07.206Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-10-02T22:15:07.706Z] GC before operation: completed in 41.101 ms, heap usage 88.787 MB -> 5.035 MB. [2025-10-02T22:15:09.886Z] Camera thread performed 122 scans. [2025-10-02T22:15:09.886Z] ====== philosophers (scala) [default], iteration 6 completed (2325.073 ms) ====== [2025-10-02T22:15:09.886Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-10-02T22:15:09.886Z] GC before operation: completed in 31.379 ms, heap usage 77.181 MB -> 5.035 MB. [2025-10-02T22:15:11.998Z] Camera thread performed 122 scans. [2025-10-02T22:15:11.998Z] ====== philosophers (scala) [default], iteration 7 completed (1991.143 ms) ====== [2025-10-02T22:15:11.998Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-10-02T22:15:11.998Z] GC before operation: completed in 33.817 ms, heap usage 175.935 MB -> 5.035 MB. [2025-10-02T22:15:14.063Z] Camera thread performed 122 scans. [2025-10-02T22:15:14.063Z] ====== philosophers (scala) [default], iteration 8 completed (2078.345 ms) ====== [2025-10-02T22:15:14.063Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-10-02T22:15:14.063Z] GC before operation: completed in 47.915 ms, heap usage 68.810 MB -> 5.064 MB. [2025-10-02T22:15:16.118Z] Camera thread performed 122 scans. [2025-10-02T22:15:16.118Z] ====== philosophers (scala) [default], iteration 9 completed (2280.577 ms) ====== [2025-10-02T22:15:16.118Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-10-02T22:15:16.118Z] GC before operation: completed in 72.110 ms, heap usage 180.714 MB -> 5.065 MB. [2025-10-02T22:15:19.024Z] Camera thread performed 122 scans. [2025-10-02T22:15:19.024Z] ====== philosophers (scala) [default], iteration 10 completed (2754.234 ms) ====== [2025-10-02T22:15:19.024Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-10-02T22:15:19.024Z] GC before operation: completed in 107.127 ms, heap usage 10.189 MB -> 5.037 MB. [2025-10-02T22:15:22.941Z] Camera thread performed 122 scans. [2025-10-02T22:15:22.941Z] ====== philosophers (scala) [default], iteration 11 completed (3265.798 ms) ====== [2025-10-02T22:15:22.941Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-10-02T22:15:22.941Z] GC before operation: completed in 54.550 ms, heap usage 29.167 MB -> 5.038 MB. [2025-10-02T22:15:25.197Z] Camera thread performed 122 scans. [2025-10-02T22:15:25.197Z] ====== philosophers (scala) [default], iteration 12 completed (2618.933 ms) ====== [2025-10-02T22:15:25.197Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-10-02T22:15:25.197Z] GC before operation: completed in 44.501 ms, heap usage 20.127 MB -> 5.038 MB. [2025-10-02T22:15:27.265Z] Camera thread performed 122 scans. [2025-10-02T22:15:27.265Z] ====== philosophers (scala) [default], iteration 13 completed (1899.221 ms) ====== [2025-10-02T22:15:27.265Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-10-02T22:15:27.265Z] GC before operation: completed in 103.945 ms, heap usage 91.509 MB -> 5.038 MB. [2025-10-02T22:15:29.402Z] Camera thread performed 122 scans. [2025-10-02T22:15:29.402Z] ====== philosophers (scala) [default], iteration 14 completed (2195.442 ms) ====== [2025-10-02T22:15:29.402Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-10-02T22:15:29.402Z] GC before operation: completed in 45.911 ms, heap usage 10.132 MB -> 5.039 MB. [2025-10-02T22:15:31.624Z] Camera thread performed 122 scans. [2025-10-02T22:15:31.624Z] ====== philosophers (scala) [default], iteration 15 completed (1905.992 ms) ====== [2025-10-02T22:15:31.624Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-10-02T22:15:31.624Z] GC before operation: completed in 52.283 ms, heap usage 30.131 MB -> 5.039 MB. [2025-10-02T22:15:33.763Z] Camera thread performed 122 scans. [2025-10-02T22:15:33.763Z] ====== philosophers (scala) [default], iteration 16 completed (2107.877 ms) ====== [2025-10-02T22:15:33.763Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-10-02T22:15:33.763Z] GC before operation: completed in 44.346 ms, heap usage 20.133 MB -> 5.039 MB. [2025-10-02T22:15:35.192Z] Camera thread performed 122 scans. [2025-10-02T22:15:35.192Z] ====== philosophers (scala) [default], iteration 17 completed (1654.657 ms) ====== [2025-10-02T22:15:35.192Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-10-02T22:15:35.192Z] GC before operation: completed in 52.660 ms, heap usage 29.620 MB -> 5.067 MB. [2025-10-02T22:15:38.093Z] Camera thread performed 122 scans. [2025-10-02T22:15:38.093Z] ====== philosophers (scala) [default], iteration 18 completed (2584.534 ms) ====== [2025-10-02T22:15:38.093Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-10-02T22:15:38.093Z] GC before operation: completed in 21.712 ms, heap usage 9.194 MB -> 5.039 MB. [2025-10-02T22:15:39.518Z] Camera thread performed 122 scans. [2025-10-02T22:15:39.518Z] ====== philosophers (scala) [default], iteration 19 completed (1680.853 ms) ====== [2025-10-02T22:15:39.518Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-10-02T22:15:39.518Z] GC before operation: completed in 30.609 ms, heap usage 145.458 MB -> 5.067 MB. [2025-10-02T22:15:41.779Z] Camera thread performed 122 scans. [2025-10-02T22:15:41.779Z] ====== philosophers (scala) [default], iteration 20 completed (2169.419 ms) ====== [2025-10-02T22:15:41.779Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-10-02T22:15:41.779Z] GC before operation: completed in 77.509 ms, heap usage 123.974 MB -> 5.040 MB. [2025-10-02T22:15:43.229Z] Camera thread performed 122 scans. [2025-10-02T22:15:43.229Z] ====== philosophers (scala) [default], iteration 21 completed (1610.297 ms) ====== [2025-10-02T22:15:43.229Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-10-02T22:15:43.229Z] GC before operation: completed in 24.529 ms, heap usage 13.131 MB -> 5.067 MB. [2025-10-02T22:15:46.029Z] Camera thread performed 122 scans. [2025-10-02T22:15:46.029Z] ====== philosophers (scala) [default], iteration 22 completed (2568.939 ms) ====== [2025-10-02T22:15:46.029Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-10-02T22:15:46.029Z] GC before operation: completed in 69.205 ms, heap usage 30.188 MB -> 5.040 MB. [2025-10-02T22:15:47.607Z] Camera thread performed 122 scans. [2025-10-02T22:15:47.608Z] ====== philosophers (scala) [default], iteration 23 completed (1575.761 ms) ====== [2025-10-02T22:15:47.608Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-10-02T22:15:47.608Z] GC before operation: completed in 37.502 ms, heap usage 89.712 MB -> 5.068 MB. [2025-10-02T22:15:49.621Z] Camera thread performed 122 scans. [2025-10-02T22:15:49.621Z] ====== philosophers (scala) [default], iteration 24 completed (1714.645 ms) ====== [2025-10-02T22:15:49.621Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-10-02T22:15:49.621Z] GC before operation: completed in 47.043 ms, heap usage 70.180 MB -> 5.040 MB. [2025-10-02T22:15:51.728Z] Camera thread performed 122 scans. [2025-10-02T22:15:51.728Z] ====== philosophers (scala) [default], iteration 25 completed (1969.110 ms) ====== [2025-10-02T22:15:51.728Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-10-02T22:15:51.728Z] GC before operation: completed in 46.582 ms, heap usage 12.135 MB -> 5.040 MB. [2025-10-02T22:15:53.193Z] Camera thread performed 122 scans. [2025-10-02T22:15:53.193Z] ====== philosophers (scala) [default], iteration 26 completed (1412.691 ms) ====== [2025-10-02T22:15:53.193Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-10-02T22:15:53.193Z] GC before operation: completed in 46.547 ms, heap usage 34.162 MB -> 5.040 MB. [2025-10-02T22:15:55.293Z] Camera thread performed 122 scans. [2025-10-02T22:15:55.293Z] ====== philosophers (scala) [default], iteration 27 completed (2487.832 ms) ====== [2025-10-02T22:15:55.293Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-10-02T22:15:55.293Z] GC before operation: completed in 23.737 ms, heap usage 28.166 MB -> 5.068 MB. [2025-10-02T22:15:57.311Z] Camera thread performed 122 scans. [2025-10-02T22:15:57.312Z] ====== philosophers (scala) [default], iteration 28 completed (1884.778 ms) ====== [2025-10-02T22:15:57.312Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-10-02T22:15:57.312Z] GC before operation: completed in 73.712 ms, heap usage 24.188 MB -> 5.040 MB. [2025-10-02T22:15:59.380Z] Camera thread performed 122 scans. [2025-10-02T22:15:59.380Z] ====== philosophers (scala) [default], iteration 29 completed (2021.790 ms) ====== [2025-10-02T22:15:59.788Z] ----------------------------------- [2025-10-02T22:15:59.788Z] renaissance-philosophers_0_PASSED [2025-10-02T22:15:59.788Z] ----------------------------------- [2025-10-02T22:15:59.788Z] [2025-10-02T22:15:59.788Z] TEST TEARDOWN: [2025-10-02T22:15:59.788Z] Nothing to be done for teardown. [2025-10-02T22:15:59.788Z] renaissance-philosophers_0 Finish Time: Thu Oct 2 15:15:59 2025 Epoch Time (ms): 1759443359606