[2025-03-26T22:03:07.653Z] Running test renaissance-philosophers_0 ... [2025-03-26T22:03:07.653Z] =============================================== [2025-03-26T22:03:07.653Z] renaissance-philosophers_0 Start Time: Wed Mar 26 22:03:07 2025 Epoch Time (ms): 1743026587255 [2025-03-26T22:03:07.653Z] variation: NoOptions [2025-03-26T22:03:07.653Z] JVM_OPTIONS: [2025-03-26T22:03:07.653Z] { \ [2025-03-26T22:03:07.653Z] echo ""; echo "TEST SETUP:"; \ [2025-03-26T22:03:07.653Z] echo "Nothing to be done for setup."; \ [2025-03-26T22:03:07.653Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17430247615623/renaissance-philosophers_0"; \ [2025-03-26T22:03:07.653Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17430247615623/renaissance-philosophers_0"; \ [2025-03-26T22:03:07.653Z] echo ""; echo "TESTING:"; \ [2025-03-26T22:03:07.653Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17430247615623/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-03-26T22:03:07.653Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17430247615623/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-26T22:03:07.653Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-26T22:03:07.653Z] echo "Nothing to be done for teardown."; \ [2025-03-26T22:03:07.653Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17430247615623/TestTargetResult"; [2025-03-26T22:03:07.653Z] [2025-03-26T22:03:07.653Z] TEST SETUP: [2025-03-26T22:03:07.653Z] Nothing to be done for setup. [2025-03-26T22:03:07.653Z] [2025-03-26T22:03:07.653Z] TESTING: [2025-03-26T22:03:08.609Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-03-26T22:03:08.609Z] GC before operation: completed in 12.631 ms, heap usage 7.427 MB -> 5.837 MB. [2025-03-26T22:03:18.378Z] Camera thread performed 122 scans. [2025-03-26T22:03:18.378Z] ====== philosophers (scala) [default], iteration 0 completed (9497.371 ms) ====== [2025-03-26T22:03:18.378Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-03-26T22:03:18.378Z] GC before operation: completed in 22.376 ms, heap usage 26.677 MB -> 6.214 MB. [2025-03-26T22:03:21.418Z] Camera thread performed 122 scans. [2025-03-26T22:03:22.377Z] ====== philosophers (scala) [default], iteration 1 completed (3419.358 ms) ====== [2025-03-26T22:03:22.377Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-03-26T22:03:22.377Z] GC before operation: completed in 19.353 ms, heap usage 84.506 MB -> 6.215 MB. [2025-03-26T22:03:26.588Z] Camera thread performed 122 scans. [2025-03-26T22:03:26.588Z] ====== philosophers (scala) [default], iteration 2 completed (4892.282 ms) ====== [2025-03-26T22:03:26.588Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-03-26T22:03:26.588Z] GC before operation: completed in 18.606 ms, heap usage 10.110 MB -> 6.216 MB. [2025-03-26T22:03:30.755Z] Camera thread performed 122 scans. [2025-03-26T22:03:30.755Z] ====== philosophers (scala) [default], iteration 3 completed (3607.853 ms) ====== [2025-03-26T22:03:30.755Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-03-26T22:03:30.755Z] GC before operation: completed in 18.577 ms, heap usage 93.932 MB -> 6.216 MB. [2025-03-26T22:03:33.781Z] Camera thread performed 122 scans. [2025-03-26T22:03:33.781Z] ====== philosophers (scala) [default], iteration 4 completed (3751.350 ms) ====== [2025-03-26T22:03:33.781Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-03-26T22:03:33.781Z] GC before operation: completed in 18.455 ms, heap usage 65.798 MB -> 6.217 MB. [2025-03-26T22:03:37.951Z] Camera thread performed 122 scans. [2025-03-26T22:03:37.951Z] ====== philosophers (scala) [default], iteration 5 completed (3706.757 ms) ====== [2025-03-26T22:03:37.951Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-03-26T22:03:37.951Z] GC before operation: completed in 19.044 ms, heap usage 66.923 MB -> 6.217 MB. [2025-03-26T22:03:43.353Z] Camera thread performed 122 scans. [2025-03-26T22:03:43.353Z] ====== philosophers (scala) [default], iteration 6 completed (5108.306 ms) ====== [2025-03-26T22:03:43.353Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-03-26T22:03:43.353Z] GC before operation: completed in 19.480 ms, heap usage 42.646 MB -> 6.218 MB. [2025-03-26T22:03:48.752Z] Camera thread performed 122 scans. [2025-03-26T22:03:48.752Z] ====== philosophers (scala) [default], iteration 7 completed (5318.610 ms) ====== [2025-03-26T22:03:48.752Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-03-26T22:03:48.752Z] GC before operation: completed in 20.134 ms, heap usage 29.274 MB -> 6.218 MB. [2025-03-26T22:03:53.299Z] Camera thread performed 122 scans. [2025-03-26T22:03:53.300Z] ====== philosophers (scala) [default], iteration 8 completed (5065.004 ms) ====== [2025-03-26T22:03:53.300Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-03-26T22:03:53.300Z] GC before operation: completed in 20.557 ms, heap usage 45.705 MB -> 6.218 MB. [2025-03-26T22:04:00.015Z] Camera thread performed 122 scans. [2025-03-26T22:04:00.015Z] ====== philosophers (scala) [default], iteration 9 completed (6110.359 ms) ====== [2025-03-26T22:04:00.015Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-03-26T22:04:00.015Z] GC before operation: completed in 19.678 ms, heap usage 88.507 MB -> 6.218 MB. [2025-03-26T22:04:05.479Z] Camera thread performed 122 scans. [2025-03-26T22:04:05.479Z] ====== philosophers (scala) [default], iteration 10 completed (5662.104 ms) ====== [2025-03-26T22:04:05.479Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-03-26T22:04:05.479Z] GC before operation: completed in 19.978 ms, heap usage 51.872 MB -> 6.218 MB. [2025-03-26T22:04:10.871Z] Camera thread performed 122 scans. [2025-03-26T22:04:10.871Z] ====== philosophers (scala) [default], iteration 11 completed (5670.331 ms) ====== [2025-03-26T22:04:10.871Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-03-26T22:04:10.871Z] GC before operation: completed in 18.231 ms, heap usage 62.136 MB -> 6.218 MB. [2025-03-26T22:04:16.257Z] Camera thread performed 122 scans. [2025-03-26T22:04:16.257Z] ====== philosophers (scala) [default], iteration 12 completed (4558.712 ms) ====== [2025-03-26T22:04:16.257Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-03-26T22:04:16.257Z] GC before operation: completed in 19.221 ms, heap usage 52.595 MB -> 6.218 MB. [2025-03-26T22:04:19.294Z] Camera thread performed 122 scans. [2025-03-26T22:04:19.294Z] ====== philosophers (scala) [default], iteration 13 completed (3707.888 ms) ====== [2025-03-26T22:04:19.294Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-03-26T22:04:19.294Z] GC before operation: completed in 18.702 ms, heap usage 11.751 MB -> 6.218 MB. [2025-03-26T22:04:22.332Z] Camera thread performed 122 scans. [2025-03-26T22:04:22.332Z] ====== philosophers (scala) [default], iteration 14 completed (3425.999 ms) ====== [2025-03-26T22:04:22.332Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-03-26T22:04:22.332Z] GC before operation: completed in 18.378 ms, heap usage 35.226 MB -> 6.218 MB. [2025-03-26T22:04:27.729Z] Camera thread performed 122 scans. [2025-03-26T22:04:27.729Z] ====== philosophers (scala) [default], iteration 15 completed (4222.593 ms) ====== [2025-03-26T22:04:27.729Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-03-26T22:04:27.729Z] GC before operation: completed in 19.792 ms, heap usage 41.229 MB -> 6.218 MB. [2025-03-26T22:04:30.755Z] Camera thread performed 122 scans. [2025-03-26T22:04:30.755Z] ====== philosophers (scala) [default], iteration 16 completed (3715.190 ms) ====== [2025-03-26T22:04:30.755Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-03-26T22:04:30.755Z] GC before operation: completed in 25.011 ms, heap usage 72.162 MB -> 6.219 MB. [2025-03-26T22:04:34.917Z] Camera thread performed 122 scans. [2025-03-26T22:04:34.917Z] ====== philosophers (scala) [default], iteration 17 completed (4327.418 ms) ====== [2025-03-26T22:04:34.917Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-03-26T22:04:34.917Z] GC before operation: completed in 18.849 ms, heap usage 84.491 MB -> 6.218 MB. [2025-03-26T22:04:39.084Z] Camera thread performed 122 scans. [2025-03-26T22:04:39.084Z] ====== philosophers (scala) [default], iteration 18 completed (4175.967 ms) ====== [2025-03-26T22:04:39.084Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-03-26T22:04:39.084Z] GC before operation: completed in 19.197 ms, heap usage 32.564 MB -> 6.219 MB. [2025-03-26T22:04:43.994Z] Camera thread performed 122 scans. [2025-03-26T22:04:43.994Z] ====== philosophers (scala) [default], iteration 19 completed (4118.882 ms) ====== [2025-03-26T22:04:43.994Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-03-26T22:04:43.994Z] GC before operation: completed in 19.425 ms, heap usage 48.548 MB -> 6.218 MB. [2025-03-26T22:04:47.024Z] Camera thread performed 122 scans. [2025-03-26T22:04:47.024Z] ====== philosophers (scala) [default], iteration 20 completed (3810.670 ms) ====== [2025-03-26T22:04:47.024Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-03-26T22:04:47.024Z] GC before operation: completed in 19.038 ms, heap usage 46.169 MB -> 6.219 MB. [2025-03-26T22:04:51.200Z] Camera thread performed 122 scans. [2025-03-26T22:04:51.200Z] ====== philosophers (scala) [default], iteration 21 completed (3590.635 ms) ====== [2025-03-26T22:04:51.200Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-03-26T22:04:51.200Z] GC before operation: completed in 20.300 ms, heap usage 40.626 MB -> 6.219 MB. [2025-03-26T22:04:54.234Z] Camera thread performed 122 scans. [2025-03-26T22:04:54.234Z] ====== philosophers (scala) [default], iteration 22 completed (2995.742 ms) ====== [2025-03-26T22:04:54.234Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-03-26T22:04:54.234Z] GC before operation: completed in 18.773 ms, heap usage 87.861 MB -> 6.219 MB. [2025-03-26T22:04:56.201Z] Camera thread performed 122 scans. [2025-03-26T22:04:56.201Z] ====== philosophers (scala) [default], iteration 23 completed (2293.086 ms) ====== [2025-03-26T22:04:56.201Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-03-26T22:04:56.201Z] GC before operation: completed in 18.926 ms, heap usage 99.333 MB -> 6.219 MB. [2025-03-26T22:05:00.374Z] Camera thread performed 122 scans. [2025-03-26T22:05:00.374Z] ====== philosophers (scala) [default], iteration 24 completed (4325.033 ms) ====== [2025-03-26T22:05:00.374Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-03-26T22:05:00.374Z] GC before operation: completed in 19.440 ms, heap usage 20.246 MB -> 6.219 MB. [2025-03-26T22:05:05.758Z] Camera thread performed 122 scans. [2025-03-26T22:05:05.758Z] ====== philosophers (scala) [default], iteration 25 completed (5076.926 ms) ====== [2025-03-26T22:05:05.758Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-03-26T22:05:05.758Z] GC before operation: completed in 20.694 ms, heap usage 84.380 MB -> 6.219 MB. [2025-03-26T22:05:09.921Z] Camera thread performed 122 scans. [2025-03-26T22:05:09.921Z] ====== philosophers (scala) [default], iteration 26 completed (4117.488 ms) ====== [2025-03-26T22:05:09.921Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-03-26T22:05:09.921Z] GC before operation: completed in 18.582 ms, heap usage 15.277 MB -> 6.220 MB. [2025-03-26T22:05:12.949Z] Camera thread performed 122 scans. [2025-03-26T22:05:12.949Z] ====== philosophers (scala) [default], iteration 27 completed (3431.000 ms) ====== [2025-03-26T22:05:12.949Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-03-26T22:05:12.949Z] GC before operation: completed in 19.033 ms, heap usage 127.227 MB -> 6.219 MB. [2025-03-26T22:05:17.116Z] Camera thread performed 122 scans. [2025-03-26T22:05:17.116Z] ====== philosophers (scala) [default], iteration 28 completed (3379.713 ms) ====== [2025-03-26T22:05:17.116Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-03-26T22:05:17.116Z] GC before operation: completed in 19.288 ms, heap usage 48.497 MB -> 6.220 MB. [2025-03-26T22:05:21.279Z] Camera thread performed 122 scans. [2025-03-26T22:05:21.279Z] ====== philosophers (scala) [default], iteration 29 completed (4480.427 ms) ====== [2025-03-26T22:05:21.279Z] ----------------------------------- [2025-03-26T22:05:21.279Z] renaissance-philosophers_0_PASSED [2025-03-26T22:05:21.279Z] ----------------------------------- [2025-03-26T22:05:21.279Z] [2025-03-26T22:05:21.279Z] TEST TEARDOWN: [2025-03-26T22:05:21.279Z] Nothing to be done for teardown. [2025-03-26T22:05:21.279Z] renaissance-philosophers_0 Finish Time: Wed Mar 26 22:05:20 2025 Epoch Time (ms): 1743026720833