[2025-07-01T07:59:44.688Z] Running test renaissance-philosophers_0 ... [2025-07-01T07:59:44.688Z] =============================================== [2025-07-01T07:59:44.688Z] renaissance-philosophers_0 Start Time: Tue Jul 1 00:59:44 2025 Epoch Time (ms): 1751356784404 [2025-07-01T07:59:45.121Z] variation: NoOptions [2025-07-01T07:59:45.121Z] JVM_OPTIONS: [2025-07-01T07:59:45.121Z] { \ [2025-07-01T07:59:45.121Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T07:59:45.121Z] echo "Nothing to be done for setup."; \ [2025-07-01T07:59:45.121Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/renaissance-philosophers_0"; \ [2025-07-01T07:59:45.121Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/renaissance-philosophers_0"; \ [2025-07-01T07:59:45.121Z] echo ""; echo "TESTING:"; \ [2025-07-01T07:59:45.121Z] "/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-07-01T07:59:45.121Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T07:59:45.121Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T07:59:45.121Z] echo "Nothing to be done for teardown."; \ [2025-07-01T07:59:45.121Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/TestTargetResult"; [2025-07-01T07:59:45.121Z] [2025-07-01T07:59:45.121Z] TEST SETUP: [2025-07-01T07:59:45.121Z] Nothing to be done for setup. [2025-07-01T07:59:45.121Z] [2025-07-01T07:59:45.121Z] TESTING: [2025-07-01T07:59:48.222Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-01T07:59:48.222Z] GC before operation: completed in 59.768 ms, heap usage 10.501 MB -> 4.556 MB. [2025-07-01T07:59:54.419Z] Camera thread performed 122 scans. [2025-07-01T07:59:54.419Z] ====== philosophers (scala) [default], iteration 0 completed (5854.609 ms) ====== [2025-07-01T07:59:54.419Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-01T07:59:54.419Z] GC before operation: completed in 36.150 ms, heap usage 63.127 MB -> 4.947 MB. [2025-07-01T07:59:55.837Z] Camera thread performed 122 scans. [2025-07-01T07:59:55.837Z] ====== philosophers (scala) [default], iteration 1 completed (1882.527 ms) ====== [2025-07-01T07:59:55.837Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-01T07:59:56.289Z] GC before operation: completed in 195.155 ms, heap usage 65.061 MB -> 4.947 MB. [2025-07-01T07:59:57.737Z] Camera thread performed 122 scans. [2025-07-01T07:59:57.737Z] ====== philosophers (scala) [default], iteration 2 completed (1390.464 ms) ====== [2025-07-01T07:59:57.737Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-01T07:59:57.737Z] GC before operation: completed in 24.974 ms, heap usage 148.976 MB -> 4.922 MB. [2025-07-01T07:59:59.881Z] Camera thread performed 122 scans. [2025-07-01T07:59:59.881Z] ====== philosophers (scala) [default], iteration 3 completed (2338.730 ms) ====== [2025-07-01T07:59:59.881Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-01T07:59:59.881Z] GC before operation: completed in 30.711 ms, heap usage 197.055 MB -> 4.922 MB. [2025-07-01T08:00:02.792Z] Camera thread performed 122 scans. [2025-07-01T08:00:02.792Z] ====== philosophers (scala) [default], iteration 4 completed (2708.070 ms) ====== [2025-07-01T08:00:02.792Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-01T08:00:02.792Z] GC before operation: completed in 44.742 ms, heap usage 181.778 MB -> 4.923 MB. [2025-07-01T08:00:05.553Z] Camera thread performed 122 scans. [2025-07-01T08:00:05.553Z] ====== philosophers (scala) [default], iteration 5 completed (2743.756 ms) ====== [2025-07-01T08:00:05.553Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-01T08:00:05.553Z] GC before operation: completed in 24.933 ms, heap usage 8.090 MB -> 4.923 MB. [2025-07-01T08:00:07.065Z] Camera thread performed 122 scans. [2025-07-01T08:00:07.065Z] ====== philosophers (scala) [default], iteration 6 completed (1333.842 ms) ====== [2025-07-01T08:00:07.065Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-01T08:00:07.065Z] GC before operation: completed in 249.618 ms, heap usage 52.004 MB -> 4.923 MB. [2025-07-01T08:00:10.825Z] Camera thread performed 122 scans. [2025-07-01T08:00:10.825Z] ====== philosophers (scala) [default], iteration 7 completed (3415.447 ms) ====== [2025-07-01T08:00:10.825Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-01T08:00:10.825Z] GC before operation: completed in 44.652 ms, heap usage 28.893 MB -> 4.951 MB. [2025-07-01T08:00:12.354Z] Camera thread performed 122 scans. [2025-07-01T08:00:12.355Z] ====== philosophers (scala) [default], iteration 8 completed (1882.462 ms) ====== [2025-07-01T08:00:12.355Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-01T08:00:12.355Z] GC before operation: completed in 40.139 ms, heap usage 173.032 MB -> 4.924 MB. [2025-07-01T08:00:15.245Z] Camera thread performed 122 scans. [2025-07-01T08:00:15.245Z] ====== philosophers (scala) [default], iteration 9 completed (2367.536 ms) ====== [2025-07-01T08:00:15.245Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-01T08:00:15.245Z] GC before operation: completed in 83.266 ms, heap usage 178.576 MB -> 4.928 MB. [2025-07-01T08:00:16.688Z] Camera thread performed 122 scans. [2025-07-01T08:00:16.688Z] ====== philosophers (scala) [default], iteration 10 completed (1757.999 ms) ====== [2025-07-01T08:00:16.688Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-01T08:00:16.688Z] GC before operation: completed in 40.165 ms, heap usage 41.774 MB -> 4.928 MB. [2025-07-01T08:00:19.526Z] Camera thread performed 122 scans. [2025-07-01T08:00:19.526Z] ====== philosophers (scala) [default], iteration 11 completed (2383.979 ms) ====== [2025-07-01T08:00:19.526Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-01T08:00:19.526Z] GC before operation: completed in 31.400 ms, heap usage 38.614 MB -> 4.928 MB. [2025-07-01T08:00:20.924Z] Camera thread performed 122 scans. [2025-07-01T08:00:20.924Z] ====== philosophers (scala) [default], iteration 12 completed (1685.712 ms) ====== [2025-07-01T08:00:20.924Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-01T08:00:20.924Z] GC before operation: completed in 28.049 ms, heap usage 160.585 MB -> 4.956 MB. [2025-07-01T08:00:23.816Z] Camera thread performed 122 scans. [2025-07-01T08:00:23.816Z] ====== philosophers (scala) [default], iteration 13 completed (3100.879 ms) ====== [2025-07-01T08:00:23.817Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-01T08:00:23.817Z] GC before operation: completed in 29.357 ms, heap usage 20.872 MB -> 4.928 MB. [2025-07-01T08:00:26.663Z] Camera thread performed 122 scans. [2025-07-01T08:00:26.663Z] ====== philosophers (scala) [default], iteration 14 completed (2658.271 ms) ====== [2025-07-01T08:00:26.663Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-01T08:00:26.663Z] GC before operation: completed in 59.041 ms, heap usage 64.036 MB -> 4.929 MB. [2025-07-01T08:00:29.671Z] Camera thread performed 122 scans. [2025-07-01T08:00:29.671Z] ====== philosophers (scala) [default], iteration 15 completed (3068.730 ms) ====== [2025-07-01T08:00:29.671Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-01T08:00:29.671Z] GC before operation: completed in 36.824 ms, heap usage 70.737 MB -> 4.929 MB. [2025-07-01T08:00:32.496Z] Camera thread performed 122 scans. [2025-07-01T08:00:32.496Z] ====== philosophers (scala) [default], iteration 16 completed (2770.001 ms) ====== [2025-07-01T08:00:32.496Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-01T08:00:32.496Z] GC before operation: completed in 60.120 ms, heap usage 8.055 MB -> 4.957 MB. [2025-07-01T08:00:35.394Z] Camera thread performed 122 scans. [2025-07-01T08:00:35.394Z] ====== philosophers (scala) [default], iteration 17 completed (2646.074 ms) ====== [2025-07-01T08:00:35.394Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-01T08:00:35.394Z] GC before operation: completed in 52.700 ms, heap usage 29.077 MB -> 4.956 MB. [2025-07-01T08:00:39.210Z] Camera thread performed 122 scans. [2025-07-01T08:00:39.210Z] ====== philosophers (scala) [default], iteration 18 completed (3344.009 ms) ====== [2025-07-01T08:00:39.210Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-01T08:00:39.210Z] GC before operation: completed in 39.704 ms, heap usage 102.033 MB -> 4.929 MB. [2025-07-01T08:00:42.175Z] Camera thread performed 122 scans. [2025-07-01T08:00:42.175Z] ====== philosophers (scala) [default], iteration 19 completed (2954.177 ms) ====== [2025-07-01T08:00:42.175Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-01T08:00:42.175Z] GC before operation: completed in 53.261 ms, heap usage 158.015 MB -> 4.957 MB. [2025-07-01T08:00:45.138Z] Camera thread performed 122 scans. [2025-07-01T08:00:45.138Z] ====== philosophers (scala) [default], iteration 20 completed (2863.177 ms) ====== [2025-07-01T08:00:45.138Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-01T08:00:45.138Z] GC before operation: completed in 50.077 ms, heap usage 24.830 MB -> 4.957 MB. [2025-07-01T08:00:48.013Z] Camera thread performed 122 scans. [2025-07-01T08:00:48.013Z] ====== philosophers (scala) [default], iteration 21 completed (3321.924 ms) ====== [2025-07-01T08:00:48.013Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-01T08:00:48.013Z] GC before operation: completed in 60.462 ms, heap usage 180.934 MB -> 4.929 MB. [2025-07-01T08:00:50.827Z] Camera thread performed 122 scans. [2025-07-01T08:00:50.827Z] ====== philosophers (scala) [default], iteration 22 completed (2739.910 ms) ====== [2025-07-01T08:00:50.827Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-01T08:00:50.827Z] GC before operation: completed in 45.479 ms, heap usage 156.435 MB -> 4.957 MB. [2025-07-01T08:00:52.875Z] Camera thread performed 122 scans. [2025-07-01T08:00:52.875Z] ====== philosophers (scala) [default], iteration 23 completed (1675.402 ms) ====== [2025-07-01T08:00:52.875Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-01T08:00:52.875Z] GC before operation: completed in 97.492 ms, heap usage 161.535 MB -> 4.930 MB. [2025-07-01T08:00:54.962Z] Camera thread performed 122 scans. [2025-07-01T08:00:54.962Z] ====== philosophers (scala) [default], iteration 24 completed (2290.871 ms) ====== [2025-07-01T08:00:54.962Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-01T08:00:54.962Z] GC before operation: completed in 28.748 ms, heap usage 158.118 MB -> 4.958 MB. [2025-07-01T08:00:57.810Z] Camera thread performed 122 scans. [2025-07-01T08:00:57.810Z] ====== philosophers (scala) [default], iteration 25 completed (2585.040 ms) ====== [2025-07-01T08:00:57.810Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-01T08:00:57.810Z] GC before operation: completed in 111.280 ms, heap usage 24.814 MB -> 4.930 MB. [2025-07-01T08:01:01.615Z] Camera thread performed 122 scans. [2025-07-01T08:01:01.615Z] ====== philosophers (scala) [default], iteration 26 completed (3508.055 ms) ====== [2025-07-01T08:01:01.615Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-01T08:01:01.615Z] GC before operation: completed in 58.267 ms, heap usage 56.978 MB -> 4.930 MB. [2025-07-01T08:01:02.541Z] Camera thread performed 122 scans. [2025-07-01T08:01:02.541Z] ====== philosophers (scala) [default], iteration 27 completed (1283.992 ms) ====== [2025-07-01T08:01:02.541Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-01T08:01:02.541Z] GC before operation: completed in 24.159 ms, heap usage 182.865 MB -> 4.930 MB. [2025-07-01T08:01:04.655Z] Camera thread performed 122 scans. [2025-07-01T08:01:04.655Z] ====== philosophers (scala) [default], iteration 28 completed (1758.752 ms) ====== [2025-07-01T08:01:04.655Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-01T08:01:04.655Z] GC before operation: completed in 53.910 ms, heap usage 150.125 MB -> 4.930 MB. [2025-07-01T08:01:06.804Z] Camera thread performed 122 scans. [2025-07-01T08:01:06.804Z] ====== philosophers (scala) [default], iteration 29 completed (2446.471 ms) ====== [2025-07-01T08:01:07.317Z] ----------------------------------- [2025-07-01T08:01:07.317Z] renaissance-philosophers_0_PASSED [2025-07-01T08:01:07.317Z] ----------------------------------- [2025-07-01T08:01:07.317Z] [2025-07-01T08:01:07.317Z] TEST TEARDOWN: [2025-07-01T08:01:07.317Z] Nothing to be done for teardown. [2025-07-01T08:01:07.317Z] renaissance-philosophers_0 Finish Time: Tue Jul 1 01:01:06 2025 Epoch Time (ms): 1751356866866