[2025-07-30T23:20:41.580Z] Running test renaissance-philosophers_0 ... [2025-07-30T23:20:41.878Z] =============================================== [2025-07-30T23:20:41.878Z] renaissance-philosophers_0 Start Time: Wed Jul 30 23:20:41 2025 Epoch Time (ms): 1753917641805 [2025-07-30T23:20:42.170Z] variation: NoOptions [2025-07-30T23:20:42.170Z] JVM_OPTIONS: [2025-07-30T23:20:42.170Z] { \ [2025-07-30T23:20:42.170Z] echo ""; echo "TEST SETUP:"; \ [2025-07-30T23:20:42.170Z] echo "Nothing to be done for setup."; \ [2025-07-30T23:20:42.170Z] mkdir -p "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17539170675670\\renaissance-philosophers_0"; \ [2025-07-30T23:20:42.170Z] cd "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17539170675670\\renaissance-philosophers_0"; \ [2025-07-30T23:20:42.170Z] echo ""; echo "TESTING:"; \ [2025-07-30T23:20:42.170Z] "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/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 "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17539170675670\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-07-30T23:20:42.170Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/; rm -f -r "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17539170675670\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-30T23:20:42.170Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-30T23:20:42.170Z] echo "Nothing to be done for teardown."; \ [2025-07-30T23:20:42.170Z] } 2>&1 | tee -a "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17539170675670\\TestTargetResult"; [2025-07-30T23:20:42.462Z] [2025-07-30T23:20:42.462Z] TEST SETUP: [2025-07-30T23:20:42.462Z] Nothing to be done for setup. [2025-07-30T23:20:42.462Z] [2025-07-30T23:20:42.462Z] TESTING: [2025-07-30T23:20:43.980Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-30T23:20:43.980Z] GC before operation: completed in 11.605 ms, heap usage 15.963 MB -> 4.548 MB. [2025-07-30T23:20:45.547Z] Camera thread performed 122 scans. [2025-07-30T23:20:45.547Z] ====== philosophers (scala) [default], iteration 0 completed (1839.935 ms) ====== [2025-07-30T23:20:45.547Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-30T23:20:45.547Z] GC before operation: completed in 15.370 ms, heap usage 46.676 MB -> 4.923 MB. [2025-07-30T23:20:47.058Z] Camera thread performed 122 scans. [2025-07-30T23:20:47.058Z] ====== philosophers (scala) [default], iteration 1 completed (1431.938 ms) ====== [2025-07-30T23:20:47.058Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-30T23:20:47.058Z] GC before operation: completed in 11.696 ms, heap usage 14.625 MB -> 4.922 MB. [2025-07-30T23:20:48.570Z] Camera thread performed 122 scans. [2025-07-30T23:20:48.570Z] ====== philosophers (scala) [default], iteration 2 completed (1412.761 ms) ====== [2025-07-30T23:20:48.570Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-30T23:20:48.570Z] GC before operation: completed in 10.606 ms, heap usage 6.517 MB -> 4.924 MB. [2025-07-30T23:20:50.082Z] Camera thread performed 122 scans. [2025-07-30T23:20:50.082Z] ====== philosophers (scala) [default], iteration 3 completed (1552.271 ms) ====== [2025-07-30T23:20:50.082Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-30T23:20:50.082Z] GC before operation: completed in 10.223 ms, heap usage 19.999 MB -> 4.924 MB. [2025-07-30T23:20:51.594Z] Camera thread performed 122 scans. [2025-07-30T23:20:51.594Z] ====== philosophers (scala) [default], iteration 4 completed (1544.783 ms) ====== [2025-07-30T23:20:51.594Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-30T23:20:51.594Z] GC before operation: completed in 10.308 ms, heap usage 55.095 MB -> 4.924 MB. [2025-07-30T23:20:53.104Z] Camera thread performed 122 scans. [2025-07-30T23:20:53.104Z] ====== philosophers (scala) [default], iteration 5 completed (1404.582 ms) ====== [2025-07-30T23:20:53.104Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-30T23:20:53.104Z] GC before operation: completed in 10.328 ms, heap usage 78.210 MB -> 4.928 MB. [2025-07-30T23:20:54.152Z] Camera thread performed 122 scans. [2025-07-30T23:20:54.152Z] ====== philosophers (scala) [default], iteration 6 completed (1173.371 ms) ====== [2025-07-30T23:20:54.152Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-30T23:20:54.152Z] GC before operation: completed in 10.246 ms, heap usage 34.144 MB -> 4.929 MB. [2025-07-30T23:20:55.664Z] Camera thread performed 122 scans. [2025-07-30T23:20:55.664Z] ====== philosophers (scala) [default], iteration 7 completed (1241.905 ms) ====== [2025-07-30T23:20:55.664Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-30T23:20:55.664Z] GC before operation: completed in 10.261 ms, heap usage 41.762 MB -> 4.930 MB. [2025-07-30T23:20:56.704Z] Camera thread performed 122 scans. [2025-07-30T23:20:56.704Z] ====== philosophers (scala) [default], iteration 8 completed (1226.154 ms) ====== [2025-07-30T23:20:56.704Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-30T23:20:56.704Z] GC before operation: completed in 9.873 ms, heap usage 5.845 MB -> 4.929 MB. [2025-07-30T23:20:58.217Z] Camera thread performed 122 scans. [2025-07-30T23:20:58.217Z] ====== philosophers (scala) [default], iteration 9 completed (1283.334 ms) ====== [2025-07-30T23:20:58.217Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-30T23:20:58.217Z] GC before operation: completed in 10.372 ms, heap usage 49.124 MB -> 4.930 MB. [2025-07-30T23:20:59.253Z] Camera thread performed 122 scans. [2025-07-30T23:20:59.253Z] ====== philosophers (scala) [default], iteration 10 completed (1264.753 ms) ====== [2025-07-30T23:20:59.253Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-30T23:20:59.253Z] GC before operation: completed in 10.600 ms, heap usage 77.827 MB -> 4.930 MB. [2025-07-30T23:21:00.764Z] Camera thread performed 122 scans. [2025-07-30T23:21:00.764Z] ====== philosophers (scala) [default], iteration 11 completed (1206.846 ms) ====== [2025-07-30T23:21:00.764Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-30T23:21:00.764Z] GC before operation: completed in 11.045 ms, heap usage 56.129 MB -> 4.931 MB. [2025-07-30T23:21:01.803Z] Camera thread performed 122 scans. [2025-07-30T23:21:01.803Z] ====== philosophers (scala) [default], iteration 12 completed (1106.091 ms) ====== [2025-07-30T23:21:01.803Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-30T23:21:01.803Z] GC before operation: completed in 10.479 ms, heap usage 42.444 MB -> 4.931 MB. [2025-07-30T23:21:02.843Z] Camera thread performed 122 scans. [2025-07-30T23:21:02.843Z] ====== philosophers (scala) [default], iteration 13 completed (1195.451 ms) ====== [2025-07-30T23:21:02.843Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-30T23:21:02.843Z] GC before operation: completed in 10.460 ms, heap usage 38.833 MB -> 4.931 MB. [2025-07-30T23:21:04.387Z] Camera thread performed 122 scans. [2025-07-30T23:21:04.387Z] ====== philosophers (scala) [default], iteration 14 completed (1315.310 ms) ====== [2025-07-30T23:21:04.387Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-30T23:21:04.387Z] GC before operation: completed in 10.449 ms, heap usage 56.125 MB -> 4.931 MB. [2025-07-30T23:21:05.495Z] Camera thread performed 122 scans. [2025-07-30T23:21:05.495Z] ====== philosophers (scala) [default], iteration 15 completed (1283.911 ms) ====== [2025-07-30T23:21:05.495Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-30T23:21:05.495Z] GC before operation: completed in 10.204 ms, heap usage 52.138 MB -> 4.931 MB. [2025-07-30T23:21:06.551Z] Camera thread performed 122 scans. [2025-07-30T23:21:06.551Z] ====== philosophers (scala) [default], iteration 16 completed (1262.237 ms) ====== [2025-07-30T23:21:06.551Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-30T23:21:06.845Z] GC before operation: completed in 9.992 ms, heap usage 19.760 MB -> 4.931 MB. [2025-07-30T23:21:07.884Z] Camera thread performed 122 scans. [2025-07-30T23:21:07.884Z] ====== philosophers (scala) [default], iteration 17 completed (1306.822 ms) ====== [2025-07-30T23:21:07.884Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-30T23:21:07.884Z] GC before operation: completed in 10.505 ms, heap usage 41.516 MB -> 4.931 MB. [2025-07-30T23:21:09.412Z] Camera thread performed 122 scans. [2025-07-30T23:21:09.412Z] ====== philosophers (scala) [default], iteration 18 completed (1283.346 ms) ====== [2025-07-30T23:21:09.412Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-30T23:21:09.412Z] GC before operation: completed in 10.290 ms, heap usage 25.127 MB -> 4.932 MB. [2025-07-30T23:21:10.931Z] Camera thread performed 122 scans. [2025-07-30T23:21:10.931Z] ====== philosophers (scala) [default], iteration 19 completed (1370.547 ms) ====== [2025-07-30T23:21:10.931Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-30T23:21:10.931Z] GC before operation: completed in 10.137 ms, heap usage 66.457 MB -> 4.933 MB. [2025-07-30T23:21:11.971Z] Camera thread performed 122 scans. [2025-07-30T23:21:11.971Z] ====== philosophers (scala) [default], iteration 20 completed (1241.935 ms) ====== [2025-07-30T23:21:11.971Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-30T23:21:11.971Z] GC before operation: completed in 10.334 ms, heap usage 44.140 MB -> 4.933 MB. [2025-07-30T23:21:13.011Z] Camera thread performed 122 scans. [2025-07-30T23:21:13.011Z] ====== philosophers (scala) [default], iteration 21 completed (1117.269 ms) ====== [2025-07-30T23:21:13.011Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-30T23:21:13.011Z] GC before operation: completed in 23.307 ms, heap usage 10.849 MB -> 4.933 MB. [2025-07-30T23:21:14.528Z] Camera thread performed 122 scans. [2025-07-30T23:21:14.528Z] ====== philosophers (scala) [default], iteration 22 completed (1298.685 ms) ====== [2025-07-30T23:21:14.528Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-30T23:21:14.528Z] GC before operation: completed in 10.589 ms, heap usage 81.137 MB -> 4.933 MB. [2025-07-30T23:21:15.568Z] Camera thread performed 122 scans. [2025-07-30T23:21:15.568Z] ====== philosophers (scala) [default], iteration 23 completed (1220.647 ms) ====== [2025-07-30T23:21:15.568Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-30T23:21:15.568Z] GC before operation: completed in 10.052 ms, heap usage 7.944 MB -> 4.933 MB. [2025-07-30T23:21:17.081Z] Camera thread performed 122 scans. [2025-07-30T23:21:17.081Z] ====== philosophers (scala) [default], iteration 24 completed (1392.171 ms) ====== [2025-07-30T23:21:17.081Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-30T23:21:17.081Z] GC before operation: completed in 10.438 ms, heap usage 50.138 MB -> 4.933 MB. [2025-07-30T23:21:18.594Z] Camera thread performed 122 scans. [2025-07-30T23:21:18.594Z] ====== philosophers (scala) [default], iteration 25 completed (1375.558 ms) ====== [2025-07-30T23:21:18.594Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-30T23:21:18.594Z] GC before operation: completed in 10.428 ms, heap usage 62.458 MB -> 4.933 MB. [2025-07-30T23:21:20.140Z] Camera thread performed 122 scans. [2025-07-30T23:21:20.140Z] ====== philosophers (scala) [default], iteration 26 completed (1448.471 ms) ====== [2025-07-30T23:21:20.140Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-30T23:21:20.140Z] GC before operation: completed in 10.016 ms, heap usage 41.500 MB -> 4.933 MB. [2025-07-30T23:21:21.177Z] Camera thread performed 122 scans. [2025-07-30T23:21:21.177Z] ====== philosophers (scala) [default], iteration 27 completed (1275.969 ms) ====== [2025-07-30T23:21:21.177Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-30T23:21:21.177Z] GC before operation: completed in 10.427 ms, heap usage 34.133 MB -> 4.933 MB. [2025-07-30T23:21:22.704Z] Camera thread performed 122 scans. [2025-07-30T23:21:22.705Z] ====== philosophers (scala) [default], iteration 28 completed (1302.305 ms) ====== [2025-07-30T23:21:22.705Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-30T23:21:22.705Z] GC before operation: completed in 10.256 ms, heap usage 67.442 MB -> 4.933 MB. [2025-07-30T23:21:23.749Z] Camera thread performed 122 scans. [2025-07-30T23:21:23.749Z] ====== philosophers (scala) [default], iteration 29 completed (1361.544 ms) ====== [2025-07-30T23:21:24.042Z] ----------------------------------- [2025-07-30T23:21:24.042Z] renaissance-philosophers_0_PASSED [2025-07-30T23:21:24.042Z] ----------------------------------- [2025-07-30T23:21:24.042Z] [2025-07-30T23:21:24.042Z] TEST TEARDOWN: [2025-07-30T23:21:24.042Z] Nothing to be done for teardown. [2025-07-30T23:21:24.335Z] renaissance-philosophers_0 Finish Time: Wed Jul 30 23:21:24 2025 Epoch Time (ms): 1753917684102