[2025-12-06T14:33:26.304Z] Running test renaissance-philosophers_0 ... [2025-12-06T14:33:26.304Z] =============================================== [2025-12-06T14:33:26.304Z] renaissance-philosophers_0 Start Time: Sat Dec 6 06:33:26 2025 Epoch Time (ms): 1765031606032 [2025-12-06T14:33:26.304Z] variation: NoOptions [2025-12-06T14:33:26.304Z] JVM_OPTIONS: [2025-12-06T14:33:26.304Z] { \ [2025-12-06T14:33:26.304Z] echo ""; echo "TEST SETUP:"; \ [2025-12-06T14:33:26.304Z] echo "Nothing to be done for setup."; \ [2025-12-06T14:33:26.304Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/renaissance-philosophers_0"; \ [2025-12-06T14:33:26.304Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/renaissance-philosophers_0"; \ [2025-12-06T14:33:26.304Z] echo ""; echo "TESTING:"; \ [2025-12-06T14:33:26.304Z] "/Users/admin/workspace/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-06T14:33:26.304Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-06T14:33:26.304Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-06T14:33:26.304Z] echo "Nothing to be done for teardown."; \ [2025-12-06T14:33:26.304Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/TestTargetResult"; [2025-12-06T14:33:26.304Z] [2025-12-06T14:33:26.304Z] TEST SETUP: [2025-12-06T14:33:26.304Z] Nothing to be done for setup. [2025-12-06T14:33:26.304Z] [2025-12-06T14:33:26.304Z] TESTING: [2025-12-06T14:33:27.168Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-06T14:33:27.168Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/output_17650287207940/renaissance-philosophers_0/launcher-063326-8281624417643173024/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-06T14:33:27.168Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-06T14:33:27.168Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-06T14:33:27.571Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-06T14:33:27.571Z] GC before operation: completed in 36.461 ms, heap usage 27.398 MB -> 4.630 MB. [2025-12-06T14:33:31.137Z] Camera thread performed 122 scans. [2025-12-06T14:33:31.137Z] ====== philosophers (scala) [default], iteration 0 completed (3067.970 ms) ====== [2025-12-06T14:33:31.137Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-06T14:33:31.137Z] GC before operation: completed in 27.630 ms, heap usage 160.164 MB -> 4.958 MB. [2025-12-06T14:33:33.251Z] Camera thread performed 122 scans. [2025-12-06T14:33:33.251Z] ====== philosophers (scala) [default], iteration 1 completed (2325.415 ms) ====== [2025-12-06T14:33:33.251Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-06T14:33:33.251Z] GC before operation: completed in 14.955 ms, heap usage 43.655 MB -> 4.959 MB. [2025-12-06T14:33:34.161Z] Camera thread performed 122 scans. [2025-12-06T14:33:34.161Z] ====== philosophers (scala) [default], iteration 2 completed (955.247 ms) ====== [2025-12-06T14:33:34.161Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-06T14:33:34.161Z] GC before operation: completed in 19.487 ms, heap usage 179.877 MB -> 4.959 MB. [2025-12-06T14:33:35.564Z] Camera thread performed 122 scans. [2025-12-06T14:33:35.564Z] ====== philosophers (scala) [default], iteration 3 completed (1767.944 ms) ====== [2025-12-06T14:33:35.564Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-06T14:33:35.564Z] GC before operation: completed in 12.810 ms, heap usage 151.431 MB -> 4.960 MB. [2025-12-06T14:33:36.880Z] Camera thread performed 122 scans. [2025-12-06T14:33:36.880Z] ====== philosophers (scala) [default], iteration 4 completed (904.310 ms) ====== [2025-12-06T14:33:36.880Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-06T14:33:36.880Z] GC before operation: completed in 25.502 ms, heap usage 13.996 MB -> 4.961 MB. [2025-12-06T14:33:38.871Z] Camera thread performed 122 scans. [2025-12-06T14:33:38.871Z] ====== philosophers (scala) [default], iteration 5 completed (1950.018 ms) ====== [2025-12-06T14:33:38.871Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-06T14:33:38.871Z] GC before operation: completed in 16.578 ms, heap usage 28.458 MB -> 4.961 MB. [2025-12-06T14:33:39.699Z] Camera thread performed 122 scans. [2025-12-06T14:33:39.699Z] ====== philosophers (scala) [default], iteration 6 completed (994.937 ms) ====== [2025-12-06T14:33:39.699Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-06T14:33:39.699Z] GC before operation: completed in 15.675 ms, heap usage 196.657 MB -> 4.962 MB. [2025-12-06T14:33:41.766Z] Camera thread performed 122 scans. [2025-12-06T14:33:41.766Z] ====== philosophers (scala) [default], iteration 7 completed (1929.540 ms) ====== [2025-12-06T14:33:41.767Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-06T14:33:41.767Z] GC before operation: completed in 10.472 ms, heap usage 189.031 MB -> 4.966 MB. [2025-12-06T14:33:42.642Z] Camera thread performed 122 scans. [2025-12-06T14:33:42.642Z] ====== philosophers (scala) [default], iteration 8 completed (1082.895 ms) ====== [2025-12-06T14:33:42.642Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-06T14:33:42.642Z] GC before operation: completed in 13.347 ms, heap usage 128.029 MB -> 4.966 MB. [2025-12-06T14:33:44.690Z] Camera thread performed 122 scans. [2025-12-06T14:33:44.690Z] ====== philosophers (scala) [default], iteration 9 completed (1889.451 ms) ====== [2025-12-06T14:33:44.690Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-06T14:33:44.691Z] GC before operation: completed in 11.113 ms, heap usage 153.056 MB -> 4.966 MB. [2025-12-06T14:33:45.506Z] Camera thread performed 122 scans. [2025-12-06T14:33:45.506Z] ====== philosophers (scala) [default], iteration 10 completed (935.005 ms) ====== [2025-12-06T14:33:45.506Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-06T14:33:45.506Z] GC before operation: completed in 16.096 ms, heap usage 97.143 MB -> 5.002 MB. [2025-12-06T14:33:47.513Z] Camera thread performed 122 scans. [2025-12-06T14:33:47.513Z] ====== philosophers (scala) [default], iteration 11 completed (1646.205 ms) ====== [2025-12-06T14:33:47.513Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-06T14:33:47.513Z] GC before operation: completed in 14.861 ms, heap usage 28.197 MB -> 4.967 MB. [2025-12-06T14:33:48.370Z] Camera thread performed 122 scans. [2025-12-06T14:33:48.370Z] ====== philosophers (scala) [default], iteration 12 completed (944.676 ms) ====== [2025-12-06T14:33:48.370Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-06T14:33:48.370Z] GC before operation: completed in 22.523 ms, heap usage 178.953 MB -> 4.967 MB. [2025-12-06T14:33:49.210Z] Camera thread performed 122 scans. [2025-12-06T14:33:49.210Z] ====== philosophers (scala) [default], iteration 13 completed (973.713 ms) ====== [2025-12-06T14:33:49.210Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-06T14:33:49.210Z] GC before operation: completed in 13.620 ms, heap usage 10.196 MB -> 4.967 MB. [2025-12-06T14:33:50.576Z] Camera thread performed 122 scans. [2025-12-06T14:33:50.576Z] ====== philosophers (scala) [default], iteration 14 completed (1445.797 ms) ====== [2025-12-06T14:33:50.576Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-06T14:33:50.576Z] GC before operation: completed in 73.813 ms, heap usage 170.226 MB -> 4.967 MB. [2025-12-06T14:33:52.033Z] Camera thread performed 122 scans. [2025-12-06T14:33:52.033Z] ====== philosophers (scala) [default], iteration 15 completed (1412.321 ms) ====== [2025-12-06T14:33:52.033Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-06T14:33:52.033Z] GC before operation: completed in 52.785 ms, heap usage 169.009 MB -> 4.967 MB. [2025-12-06T14:33:54.056Z] Camera thread performed 122 scans. [2025-12-06T14:33:54.056Z] ====== philosophers (scala) [default], iteration 16 completed (1914.684 ms) ====== [2025-12-06T14:33:54.056Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-06T14:33:54.056Z] GC before operation: completed in 20.896 ms, heap usage 295.270 MB -> 4.967 MB. [2025-12-06T14:33:54.892Z] Camera thread performed 122 scans. [2025-12-06T14:33:54.892Z] ====== philosophers (scala) [default], iteration 17 completed (924.465 ms) ====== [2025-12-06T14:33:54.892Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-06T14:33:54.892Z] GC before operation: completed in 25.491 ms, heap usage 26.006 MB -> 4.967 MB. [2025-12-06T14:33:56.215Z] Camera thread performed 122 scans. [2025-12-06T14:33:56.215Z] ====== philosophers (scala) [default], iteration 18 completed (903.881 ms) ====== [2025-12-06T14:33:56.215Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-06T14:33:56.216Z] GC before operation: completed in 17.566 ms, heap usage 120.431 MB -> 4.968 MB. [2025-12-06T14:33:57.633Z] Camera thread performed 122 scans. [2025-12-06T14:33:57.633Z] ====== philosophers (scala) [default], iteration 19 completed (1693.802 ms) ====== [2025-12-06T14:33:57.633Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-06T14:33:57.634Z] GC before operation: completed in 12.167 ms, heap usage 16.975 MB -> 4.968 MB. [2025-12-06T14:33:58.494Z] Camera thread performed 122 scans. [2025-12-06T14:33:58.494Z] ====== philosophers (scala) [default], iteration 20 completed (849.798 ms) ====== [2025-12-06T14:33:58.494Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-06T14:33:58.494Z] GC before operation: completed in 16.405 ms, heap usage 20.803 MB -> 4.968 MB. [2025-12-06T14:34:00.480Z] Camera thread performed 122 scans. [2025-12-06T14:34:00.480Z] ====== philosophers (scala) [default], iteration 21 completed (1610.141 ms) ====== [2025-12-06T14:34:00.480Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-06T14:34:00.480Z] GC before operation: completed in 13.684 ms, heap usage 21.099 MB -> 4.968 MB. [2025-12-06T14:34:01.350Z] Camera thread performed 122 scans. [2025-12-06T14:34:01.351Z] ====== philosophers (scala) [default], iteration 22 completed (937.804 ms) ====== [2025-12-06T14:34:01.351Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-06T14:34:01.351Z] GC before operation: completed in 20.200 ms, heap usage 14.202 MB -> 4.968 MB. [2025-12-06T14:34:02.256Z] Camera thread performed 122 scans. [2025-12-06T14:34:02.256Z] ====== philosophers (scala) [default], iteration 23 completed (902.838 ms) ====== [2025-12-06T14:34:02.256Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-06T14:34:02.256Z] GC before operation: completed in 20.770 ms, heap usage 100.010 MB -> 4.968 MB. [2025-12-06T14:34:03.715Z] Camera thread performed 122 scans. [2025-12-06T14:34:03.715Z] ====== philosophers (scala) [default], iteration 24 completed (1842.889 ms) ====== [2025-12-06T14:34:03.715Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-06T14:34:04.158Z] GC before operation: completed in 17.311 ms, heap usage 113.104 MB -> 4.968 MB. [2025-12-06T14:34:05.078Z] Camera thread performed 122 scans. [2025-12-06T14:34:05.078Z] ====== philosophers (scala) [default], iteration 25 completed (927.359 ms) ====== [2025-12-06T14:34:05.078Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-06T14:34:05.078Z] GC before operation: completed in 12.844 ms, heap usage 99.752 MB -> 4.968 MB. [2025-12-06T14:34:07.226Z] Camera thread performed 122 scans. [2025-12-06T14:34:07.226Z] ====== philosophers (scala) [default], iteration 26 completed (1965.692 ms) ====== [2025-12-06T14:34:07.226Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-06T14:34:07.226Z] GC before operation: completed in 12.449 ms, heap usage 198.161 MB -> 4.968 MB. [2025-12-06T14:34:07.625Z] Camera thread performed 122 scans. [2025-12-06T14:34:07.625Z] ====== philosophers (scala) [default], iteration 27 completed (891.912 ms) ====== [2025-12-06T14:34:07.625Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-06T14:34:07.625Z] GC before operation: completed in 10.463 ms, heap usage 68.981 MB -> 4.968 MB. [2025-12-06T14:34:09.619Z] Camera thread performed 122 scans. [2025-12-06T14:34:09.619Z] ====== philosophers (scala) [default], iteration 28 completed (1837.295 ms) ====== [2025-12-06T14:34:09.619Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-06T14:34:09.619Z] GC before operation: completed in 13.008 ms, heap usage 73.098 MB -> 4.969 MB. [2025-12-06T14:34:10.471Z] Camera thread performed 122 scans. [2025-12-06T14:34:10.471Z] ====== philosophers (scala) [default], iteration 29 completed (929.203 ms) ====== [2025-12-06T14:34:10.850Z] ----------------------------------- [2025-12-06T14:34:10.850Z] renaissance-philosophers_0_PASSED [2025-12-06T14:34:10.850Z] ----------------------------------- [2025-12-06T14:34:10.850Z] [2025-12-06T14:34:10.850Z] TEST TEARDOWN: [2025-12-06T14:34:10.850Z] Nothing to be done for teardown. [2025-12-06T14:34:10.850Z] renaissance-philosophers_0 Finish Time: Sat Dec 6 06:34:10 2025 Epoch Time (ms): 1765031650342