[2025-06-18T20:10:44.444Z] Running test renaissance-philosophers_0 ... [2025-06-18T20:10:44.444Z] =============================================== [2025-06-18T20:10:44.444Z] renaissance-philosophers_0 Start Time: Wed Jun 18 16:10:44 2025 Epoch Time (ms): 1750277444090 [2025-06-18T20:10:44.444Z] variation: NoOptions [2025-06-18T20:10:44.444Z] JVM_OPTIONS: [2025-06-18T20:10:44.444Z] { \ [2025-06-18T20:10:44.444Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T20:10:44.444Z] echo "Nothing to be done for setup."; \ [2025-06-18T20:10:44.444Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-philosophers_0"; \ [2025-06-18T20:10:44.444Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-philosophers_0"; \ [2025-06-18T20:10:44.444Z] echo ""; echo "TESTING:"; \ [2025-06-18T20:10:44.444Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_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_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-18T20:10:44.444Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T20:10:44.444Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T20:10:44.444Z] echo "Nothing to be done for teardown."; \ [2025-06-18T20:10:44.444Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/TestTargetResult"; [2025-06-18T20:10:44.444Z] [2025-06-18T20:10:44.444Z] TEST SETUP: [2025-06-18T20:10:44.444Z] Nothing to be done for setup. [2025-06-18T20:10:44.444Z] [2025-06-18T20:10:44.444Z] TESTING: [2025-06-18T20:10:44.798Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-18T20:10:44.798Z] GC before operation: completed in 7.044 ms, heap usage 18.521 MB -> 4.863 MB. [2025-06-18T20:10:46.018Z] Camera thread performed 122 scans. [2025-06-18T20:10:46.018Z] ====== philosophers (scala) [default], iteration 0 completed (1339.202 ms) ====== [2025-06-18T20:10:46.018Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-18T20:10:46.018Z] GC before operation: completed in 6.733 ms, heap usage 12.510 MB -> 5.230 MB. [2025-06-18T20:10:46.775Z] Camera thread performed 122 scans. [2025-06-18T20:10:46.775Z] ====== philosophers (scala) [default], iteration 1 completed (690.692 ms) ====== [2025-06-18T20:10:46.775Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-18T20:10:46.775Z] GC before operation: completed in 5.089 ms, heap usage 25.901 MB -> 5.230 MB. [2025-06-18T20:10:47.529Z] Camera thread performed 122 scans. [2025-06-18T20:10:47.529Z] ====== philosophers (scala) [default], iteration 2 completed (761.510 ms) ====== [2025-06-18T20:10:47.529Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-18T20:10:47.529Z] GC before operation: completed in 5.003 ms, heap usage 48.406 MB -> 5.231 MB. [2025-06-18T20:10:48.288Z] Camera thread performed 122 scans. [2025-06-18T20:10:48.288Z] ====== philosophers (scala) [default], iteration 3 completed (761.129 ms) ====== [2025-06-18T20:10:48.288Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-18T20:10:48.288Z] GC before operation: completed in 5.275 ms, heap usage 5.603 MB -> 5.231 MB. [2025-06-18T20:10:49.074Z] Camera thread performed 122 scans. [2025-06-18T20:10:49.074Z] ====== philosophers (scala) [default], iteration 4 completed (755.582 ms) ====== [2025-06-18T20:10:49.074Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-18T20:10:49.074Z] GC before operation: completed in 4.930 ms, heap usage 10.535 MB -> 5.231 MB. [2025-06-18T20:10:49.830Z] Camera thread performed 122 scans. [2025-06-18T20:10:49.830Z] ====== philosophers (scala) [default], iteration 5 completed (735.975 ms) ====== [2025-06-18T20:10:49.830Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-18T20:10:49.830Z] GC before operation: completed in 4.862 ms, heap usage 13.985 MB -> 5.232 MB. [2025-06-18T20:10:50.591Z] Camera thread performed 122 scans. [2025-06-18T20:10:50.591Z] ====== philosophers (scala) [default], iteration 6 completed (737.163 ms) ====== [2025-06-18T20:10:50.591Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-18T20:10:50.591Z] GC before operation: completed in 4.976 ms, heap usage 40.795 MB -> 5.232 MB. [2025-06-18T20:10:51.352Z] Camera thread performed 122 scans. [2025-06-18T20:10:51.352Z] ====== philosophers (scala) [default], iteration 7 completed (772.149 ms) ====== [2025-06-18T20:10:51.352Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-18T20:10:51.352Z] GC before operation: completed in 4.995 ms, heap usage 39.157 MB -> 5.232 MB. [2025-06-18T20:10:52.109Z] Camera thread performed 122 scans. [2025-06-18T20:10:52.109Z] ====== philosophers (scala) [default], iteration 8 completed (786.530 ms) ====== [2025-06-18T20:10:52.109Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-18T20:10:52.109Z] GC before operation: completed in 4.869 ms, heap usage 33.667 MB -> 5.232 MB. [2025-06-18T20:10:52.866Z] Camera thread performed 122 scans. [2025-06-18T20:10:52.866Z] ====== philosophers (scala) [default], iteration 9 completed (725.707 ms) ====== [2025-06-18T20:10:52.866Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-18T20:10:52.866Z] GC before operation: completed in 5.678 ms, heap usage 54.159 MB -> 5.233 MB. [2025-06-18T20:10:53.693Z] Camera thread performed 122 scans. [2025-06-18T20:10:53.693Z] ====== philosophers (scala) [default], iteration 10 completed (704.072 ms) ====== [2025-06-18T20:10:53.693Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-18T20:10:53.693Z] GC before operation: completed in 5.572 ms, heap usage 27.407 MB -> 5.233 MB. [2025-06-18T20:10:54.050Z] Camera thread performed 122 scans. [2025-06-18T20:10:54.050Z] ====== philosophers (scala) [default], iteration 11 completed (701.891 ms) ====== [2025-06-18T20:10:54.050Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-18T20:10:54.050Z] GC before operation: completed in 4.945 ms, heap usage 53.358 MB -> 5.233 MB. [2025-06-18T20:10:54.813Z] Camera thread performed 122 scans. [2025-06-18T20:10:54.813Z] ====== philosophers (scala) [default], iteration 12 completed (657.336 ms) ====== [2025-06-18T20:10:54.813Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-18T20:10:54.813Z] GC before operation: completed in 8.164 ms, heap usage 37.340 MB -> 5.233 MB. [2025-06-18T20:10:55.640Z] Camera thread performed 122 scans. [2025-06-18T20:10:55.640Z] ====== philosophers (scala) [default], iteration 13 completed (642.754 ms) ====== [2025-06-18T20:10:55.640Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-18T20:10:55.640Z] GC before operation: completed in 5.772 ms, heap usage 52.340 MB -> 5.233 MB. [2025-06-18T20:10:56.399Z] Camera thread performed 122 scans. [2025-06-18T20:10:56.399Z] ====== philosophers (scala) [default], iteration 14 completed (658.063 ms) ====== [2025-06-18T20:10:56.399Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-18T20:10:56.399Z] GC before operation: completed in 11.181 ms, heap usage 32.981 MB -> 5.233 MB. [2025-06-18T20:10:56.754Z] Camera thread performed 122 scans. [2025-06-18T20:10:56.754Z] ====== philosophers (scala) [default], iteration 15 completed (649.863 ms) ====== [2025-06-18T20:10:56.754Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-18T20:10:56.754Z] GC before operation: completed in 7.211 ms, heap usage 41.061 MB -> 5.233 MB. [2025-06-18T20:10:57.512Z] Camera thread performed 122 scans. [2025-06-18T20:10:57.512Z] ====== philosophers (scala) [default], iteration 16 completed (688.683 ms) ====== [2025-06-18T20:10:57.512Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-18T20:10:57.512Z] GC before operation: completed in 5.055 ms, heap usage 35.416 MB -> 5.233 MB. [2025-06-18T20:10:58.271Z] Camera thread performed 122 scans. [2025-06-18T20:10:58.271Z] ====== philosophers (scala) [default], iteration 17 completed (728.946 ms) ====== [2025-06-18T20:10:58.271Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-18T20:10:58.271Z] GC before operation: completed in 4.927 ms, heap usage 23.711 MB -> 5.233 MB. [2025-06-18T20:10:59.034Z] Camera thread performed 122 scans. [2025-06-18T20:10:59.034Z] ====== philosophers (scala) [default], iteration 18 completed (696.745 ms) ====== [2025-06-18T20:10:59.034Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-18T20:10:59.034Z] GC before operation: completed in 4.843 ms, heap usage 6.812 MB -> 5.234 MB. [2025-06-18T20:10:59.795Z] Camera thread performed 122 scans. [2025-06-18T20:10:59.795Z] ====== philosophers (scala) [default], iteration 19 completed (718.185 ms) ====== [2025-06-18T20:10:59.795Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-18T20:10:59.795Z] GC before operation: completed in 5.685 ms, heap usage 45.402 MB -> 5.234 MB. [2025-06-18T20:11:00.557Z] Camera thread performed 122 scans. [2025-06-18T20:11:00.557Z] ====== philosophers (scala) [default], iteration 20 completed (665.056 ms) ====== [2025-06-18T20:11:00.557Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-18T20:11:00.557Z] GC before operation: completed in 5.393 ms, heap usage 31.029 MB -> 5.234 MB. [2025-06-18T20:11:00.927Z] Camera thread performed 122 scans. [2025-06-18T20:11:00.927Z] ====== philosophers (scala) [default], iteration 21 completed (663.131 ms) ====== [2025-06-18T20:11:00.927Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-18T20:11:00.927Z] GC before operation: completed in 5.018 ms, heap usage 19.287 MB -> 5.234 MB. [2025-06-18T20:11:01.708Z] Camera thread performed 122 scans. [2025-06-18T20:11:01.708Z] ====== philosophers (scala) [default], iteration 22 completed (649.519 ms) ====== [2025-06-18T20:11:01.708Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-18T20:11:01.708Z] GC before operation: completed in 4.908 ms, heap usage 23.407 MB -> 5.234 MB. [2025-06-18T20:11:02.479Z] Camera thread performed 122 scans. [2025-06-18T20:11:02.479Z] ====== philosophers (scala) [default], iteration 23 completed (666.786 ms) ====== [2025-06-18T20:11:02.479Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-18T20:11:02.479Z] GC before operation: completed in 4.993 ms, heap usage 47.839 MB -> 5.234 MB. [2025-06-18T20:11:03.257Z] Camera thread performed 122 scans. [2025-06-18T20:11:03.257Z] ====== philosophers (scala) [default], iteration 24 completed (661.225 ms) ====== [2025-06-18T20:11:03.257Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-18T20:11:03.257Z] GC before operation: completed in 4.843 ms, heap usage 11.822 MB -> 5.235 MB. [2025-06-18T20:11:03.621Z] Camera thread performed 122 scans. [2025-06-18T20:11:03.621Z] ====== philosophers (scala) [default], iteration 25 completed (681.345 ms) ====== [2025-06-18T20:11:03.621Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-18T20:11:03.621Z] GC before operation: completed in 5.559 ms, heap usage 22.431 MB -> 5.234 MB. [2025-06-18T20:11:04.391Z] Camera thread performed 122 scans. [2025-06-18T20:11:04.391Z] ====== philosophers (scala) [default], iteration 26 completed (611.142 ms) ====== [2025-06-18T20:11:04.391Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-18T20:11:04.391Z] GC before operation: completed in 7.338 ms, heap usage 33.196 MB -> 5.235 MB. [2025-06-18T20:11:05.155Z] Camera thread performed 122 scans. [2025-06-18T20:11:05.155Z] ====== philosophers (scala) [default], iteration 27 completed (721.975 ms) ====== [2025-06-18T20:11:05.155Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-18T20:11:05.155Z] GC before operation: completed in 8.932 ms, heap usage 43.016 MB -> 5.234 MB. [2025-06-18T20:11:05.535Z] Camera thread performed 122 scans. [2025-06-18T20:11:05.535Z] ====== philosophers (scala) [default], iteration 28 completed (559.920 ms) ====== [2025-06-18T20:11:05.535Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-18T20:11:05.535Z] GC before operation: completed in 11.087 ms, heap usage 22.308 MB -> 5.235 MB. [2025-06-18T20:11:06.298Z] Camera thread performed 122 scans. [2025-06-18T20:11:06.298Z] ====== philosophers (scala) [default], iteration 29 completed (568.443 ms) ====== [2025-06-18T20:11:06.298Z] ----------------------------------- [2025-06-18T20:11:06.298Z] renaissance-philosophers_0_PASSED [2025-06-18T20:11:06.298Z] ----------------------------------- [2025-06-18T20:11:06.298Z] [2025-06-18T20:11:06.298Z] TEST TEARDOWN: [2025-06-18T20:11:06.298Z] Nothing to be done for teardown. [2025-06-18T20:11:06.298Z] renaissance-philosophers_0 Finish Time: Wed Jun 18 16:11:06 2025 Epoch Time (ms): 1750277466123