[2025-12-04T00:23:46.848Z] Running test renaissance-philosophers_0 ... [2025-12-04T00:23:46.848Z] =============================================== [2025-12-04T00:23:46.848Z] renaissance-philosophers_0 Start Time: Thu Dec 4 00:23:46 2025 Epoch Time (ms): 1764807826599 [2025-12-04T00:23:46.848Z] variation: NoOptions [2025-12-04T00:23:46.848Z] JVM_OPTIONS: [2025-12-04T00:23:46.848Z] { \ [2025-12-04T00:23:46.848Z] echo ""; echo "TEST SETUP:"; \ [2025-12-04T00:23:46.848Z] echo "Nothing to be done for setup."; \ [2025-12-04T00:23:46.848Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-philosophers_0"; \ [2025-12-04T00:23:46.848Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-philosophers_0"; \ [2025-12-04T00:23:46.849Z] echo ""; echo "TESTING:"; \ [2025-12-04T00:23:46.849Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/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 "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-04T00:23:46.849Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-04T00:23:46.849Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-04T00:23:46.849Z] echo "Nothing to be done for teardown."; \ [2025-12-04T00:23:46.849Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/TestTargetResult"; [2025-12-04T00:23:46.849Z] [2025-12-04T00:23:46.849Z] TEST SETUP: [2025-12-04T00:23:46.849Z] Nothing to be done for setup. [2025-12-04T00:23:46.849Z] [2025-12-04T00:23:46.849Z] TESTING: [2025-12-04T00:23:47.523Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-04T00:23:47.523Z] GC before operation: completed in 21.153 ms, heap usage 15.944 MB -> 4.647 MB. [2025-12-04T00:23:49.692Z] Camera thread performed 122 scans. [2025-12-04T00:23:49.692Z] ====== philosophers (scala) [default], iteration 0 completed (1855.212 ms) ====== [2025-12-04T00:23:49.692Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-04T00:23:49.692Z] GC before operation: completed in 11.000 ms, heap usage 11.702 MB -> 5.013 MB. [2025-12-04T00:23:50.365Z] Camera thread performed 122 scans. [2025-12-04T00:23:50.365Z] ====== philosophers (scala) [default], iteration 1 completed (913.350 ms) ====== [2025-12-04T00:23:50.365Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-04T00:23:50.365Z] GC before operation: completed in 16.013 ms, heap usage 9.094 MB -> 5.014 MB. [2025-12-04T00:23:51.077Z] Camera thread performed 122 scans. [2025-12-04T00:23:51.077Z] ====== philosophers (scala) [default], iteration 2 completed (710.152 ms) ====== [2025-12-04T00:23:51.077Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-04T00:23:51.077Z] GC before operation: completed in 11.011 ms, heap usage 51.987 MB -> 5.016 MB. [2025-12-04T00:23:51.753Z] Camera thread performed 122 scans. [2025-12-04T00:23:51.753Z] ====== philosophers (scala) [default], iteration 3 completed (639.506 ms) ====== [2025-12-04T00:23:51.753Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-04T00:23:51.753Z] GC before operation: completed in 12.951 ms, heap usage 22.728 MB -> 5.016 MB. [2025-12-04T00:23:52.426Z] Camera thread performed 122 scans. [2025-12-04T00:23:52.426Z] ====== philosophers (scala) [default], iteration 4 completed (748.485 ms) ====== [2025-12-04T00:23:52.426Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-04T00:23:52.426Z] GC before operation: completed in 12.530 ms, heap usage 16.701 MB -> 5.016 MB. [2025-12-04T00:23:53.100Z] Camera thread performed 122 scans. [2025-12-04T00:23:53.100Z] ====== philosophers (scala) [default], iteration 5 completed (776.767 ms) ====== [2025-12-04T00:23:53.100Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-04T00:23:53.100Z] GC before operation: completed in 11.218 ms, heap usage 14.211 MB -> 5.016 MB. [2025-12-04T00:23:54.197Z] Camera thread performed 122 scans. [2025-12-04T00:23:54.197Z] ====== philosophers (scala) [default], iteration 6 completed (785.411 ms) ====== [2025-12-04T00:23:54.197Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-04T00:23:54.197Z] GC before operation: completed in 14.801 ms, heap usage 15.692 MB -> 5.016 MB. [2025-12-04T00:23:54.868Z] Camera thread performed 122 scans. [2025-12-04T00:23:54.868Z] ====== philosophers (scala) [default], iteration 7 completed (740.049 ms) ====== [2025-12-04T00:23:54.868Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-04T00:23:54.868Z] GC before operation: completed in 11.355 ms, heap usage 50.643 MB -> 5.016 MB. [2025-12-04T00:23:55.546Z] Camera thread performed 122 scans. [2025-12-04T00:23:55.546Z] ====== philosophers (scala) [default], iteration 8 completed (766.937 ms) ====== [2025-12-04T00:23:55.546Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-04T00:23:55.546Z] GC before operation: completed in 11.833 ms, heap usage 37.645 MB -> 5.017 MB. [2025-12-04T00:23:56.218Z] Camera thread performed 122 scans. [2025-12-04T00:23:56.218Z] ====== philosophers (scala) [default], iteration 9 completed (773.179 ms) ====== [2025-12-04T00:23:56.218Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-04T00:23:56.218Z] GC before operation: completed in 21.540 ms, heap usage 48.640 MB -> 5.017 MB. [2025-12-04T00:23:57.378Z] Camera thread performed 122 scans. [2025-12-04T00:23:57.378Z] ====== philosophers (scala) [default], iteration 10 completed (783.058 ms) ====== [2025-12-04T00:23:57.378Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-04T00:23:57.378Z] GC before operation: completed in 23.464 ms, heap usage 13.606 MB -> 5.017 MB. [2025-12-04T00:23:58.073Z] Camera thread performed 122 scans. [2025-12-04T00:23:58.073Z] ====== philosophers (scala) [default], iteration 11 completed (757.674 ms) ====== [2025-12-04T00:23:58.073Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-04T00:23:58.073Z] GC before operation: completed in 12.400 ms, heap usage 22.638 MB -> 5.017 MB. [2025-12-04T00:23:58.753Z] Camera thread performed 122 scans. [2025-12-04T00:23:58.753Z] ====== philosophers (scala) [default], iteration 12 completed (751.807 ms) ====== [2025-12-04T00:23:58.753Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-04T00:23:58.753Z] GC before operation: completed in 10.901 ms, heap usage 9.608 MB -> 5.017 MB. [2025-12-04T00:23:59.425Z] Camera thread performed 122 scans. [2025-12-04T00:23:59.425Z] ====== philosophers (scala) [default], iteration 13 completed (761.341 ms) ====== [2025-12-04T00:23:59.425Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-04T00:23:59.425Z] GC before operation: completed in 15.592 ms, heap usage 57.640 MB -> 5.017 MB. [2025-12-04T00:24:00.105Z] Camera thread performed 122 scans. [2025-12-04T00:24:00.105Z] ====== philosophers (scala) [default], iteration 14 completed (748.938 ms) ====== [2025-12-04T00:24:00.105Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-04T00:24:00.105Z] GC before operation: completed in 12.018 ms, heap usage 57.130 MB -> 5.018 MB. [2025-12-04T00:24:01.198Z] Camera thread performed 122 scans. [2025-12-04T00:24:01.198Z] ====== philosophers (scala) [default], iteration 15 completed (747.377 ms) ====== [2025-12-04T00:24:01.198Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-04T00:24:01.198Z] GC before operation: completed in 12.801 ms, heap usage 63.123 MB -> 5.018 MB. [2025-12-04T00:24:01.891Z] Camera thread performed 122 scans. [2025-12-04T00:24:01.891Z] ====== philosophers (scala) [default], iteration 16 completed (763.360 ms) ====== [2025-12-04T00:24:01.891Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-04T00:24:01.891Z] GC before operation: completed in 10.845 ms, heap usage 36.130 MB -> 5.018 MB. [2025-12-04T00:24:02.583Z] Camera thread performed 122 scans. [2025-12-04T00:24:02.583Z] ====== philosophers (scala) [default], iteration 17 completed (751.980 ms) ====== [2025-12-04T00:24:02.583Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-04T00:24:02.583Z] GC before operation: completed in 11.265 ms, heap usage 41.636 MB -> 5.018 MB. [2025-12-04T00:24:03.270Z] Camera thread performed 122 scans. [2025-12-04T00:24:03.270Z] ====== philosophers (scala) [default], iteration 18 completed (751.676 ms) ====== [2025-12-04T00:24:03.270Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-04T00:24:03.270Z] GC before operation: completed in 12.695 ms, heap usage 65.610 MB -> 5.019 MB. [2025-12-04T00:24:03.949Z] Camera thread performed 122 scans. [2025-12-04T00:24:03.949Z] ====== philosophers (scala) [default], iteration 19 completed (765.657 ms) ====== [2025-12-04T00:24:03.949Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-04T00:24:03.949Z] GC before operation: completed in 12.748 ms, heap usage 47.641 MB -> 5.019 MB. [2025-12-04T00:24:05.044Z] Camera thread performed 122 scans. [2025-12-04T00:24:05.044Z] ====== philosophers (scala) [default], iteration 20 completed (753.436 ms) ====== [2025-12-04T00:24:05.044Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-04T00:24:05.044Z] GC before operation: completed in 11.950 ms, heap usage 57.636 MB -> 5.019 MB. [2025-12-04T00:24:05.735Z] Camera thread performed 122 scans. [2025-12-04T00:24:05.735Z] ====== philosophers (scala) [default], iteration 21 completed (753.537 ms) ====== [2025-12-04T00:24:05.735Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-04T00:24:05.735Z] GC before operation: completed in 11.548 ms, heap usage 65.096 MB -> 5.019 MB. [2025-12-04T00:24:06.414Z] Camera thread performed 122 scans. [2025-12-04T00:24:06.414Z] ====== philosophers (scala) [default], iteration 22 completed (743.218 ms) ====== [2025-12-04T00:24:06.414Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-04T00:24:06.414Z] GC before operation: completed in 10.906 ms, heap usage 47.644 MB -> 5.019 MB. [2025-12-04T00:24:07.088Z] Camera thread performed 122 scans. [2025-12-04T00:24:07.088Z] ====== philosophers (scala) [default], iteration 23 completed (756.892 ms) ====== [2025-12-04T00:24:07.088Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-04T00:24:07.088Z] GC before operation: completed in 12.740 ms, heap usage 22.144 MB -> 5.019 MB. [2025-12-04T00:24:07.764Z] Camera thread performed 122 scans. [2025-12-04T00:24:07.764Z] ====== philosophers (scala) [default], iteration 24 completed (773.179 ms) ====== [2025-12-04T00:24:07.764Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-04T00:24:07.764Z] GC before operation: completed in 12.548 ms, heap usage 50.641 MB -> 5.020 MB. [2025-12-04T00:24:08.862Z] Camera thread performed 122 scans. [2025-12-04T00:24:08.862Z] ====== philosophers (scala) [default], iteration 25 completed (793.611 ms) ====== [2025-12-04T00:24:08.862Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-04T00:24:08.862Z] GC before operation: completed in 11.550 ms, heap usage 51.132 MB -> 5.019 MB. [2025-12-04T00:24:09.580Z] Camera thread performed 122 scans. [2025-12-04T00:24:09.580Z] ====== philosophers (scala) [default], iteration 26 completed (791.979 ms) ====== [2025-12-04T00:24:09.580Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-04T00:24:09.580Z] GC before operation: completed in 10.529 ms, heap usage 7.615 MB -> 5.040 MB. [2025-12-04T00:24:10.253Z] Camera thread performed 122 scans. [2025-12-04T00:24:10.253Z] ====== philosophers (scala) [default], iteration 27 completed (787.553 ms) ====== [2025-12-04T00:24:10.253Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-04T00:24:10.253Z] GC before operation: completed in 11.326 ms, heap usage 9.635 MB -> 5.020 MB. [2025-12-04T00:24:11.016Z] Camera thread performed 122 scans. [2025-12-04T00:24:11.016Z] ====== philosophers (scala) [default], iteration 28 completed (771.108 ms) ====== [2025-12-04T00:24:11.016Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-04T00:24:11.016Z] GC before operation: completed in 11.662 ms, heap usage 61.135 MB -> 5.020 MB. [2025-12-04T00:24:11.688Z] Camera thread performed 122 scans. [2025-12-04T00:24:11.688Z] ====== philosophers (scala) [default], iteration 29 completed (750.179 ms) ====== [2025-12-04T00:24:11.999Z] ----------------------------------- [2025-12-04T00:24:11.999Z] renaissance-philosophers_0_PASSED [2025-12-04T00:24:11.999Z] ----------------------------------- [2025-12-04T00:24:11.999Z] [2025-12-04T00:24:11.999Z] TEST TEARDOWN: [2025-12-04T00:24:11.999Z] Nothing to be done for teardown. [2025-12-04T00:24:11.999Z] renaissance-philosophers_0 Finish Time: Thu Dec 4 00:24:11 2025 Epoch Time (ms): 1764807851876