[2025-08-28T13:21:27.627Z] Running test renaissance-philosophers_0 ... [2025-08-28T13:21:27.627Z] =============================================== [2025-08-28T13:21:27.627Z] renaissance-philosophers_0 Start Time: Thu Aug 28 13:21:27 2025 Epoch Time (ms): 1756387287505 [2025-08-28T13:21:27.627Z] variation: NoOptions [2025-08-28T13:21:27.627Z] JVM_OPTIONS: [2025-08-28T13:21:27.627Z] { \ [2025-08-28T13:21:27.627Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T13:21:27.627Z] echo "Nothing to be done for setup."; \ [2025-08-28T13:21:27.627Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-philosophers_0"; \ [2025-08-28T13:21:27.627Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-philosophers_0"; \ [2025-08-28T13:21:27.627Z] echo ""; echo "TESTING:"; \ [2025-08-28T13:21:27.627Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-28T13:21:27.627Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T13:21:27.627Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T13:21:27.627Z] echo "Nothing to be done for teardown."; \ [2025-08-28T13:21:27.627Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/TestTargetResult"; [2025-08-28T13:21:27.627Z] [2025-08-28T13:21:27.627Z] TEST SETUP: [2025-08-28T13:21:27.627Z] Nothing to be done for setup. [2025-08-28T13:21:27.627Z] [2025-08-28T13:21:27.627Z] TESTING: [2025-08-28T13:21:29.751Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-28T13:21:29.751Z] GC before operation: completed in 21.732 ms, heap usage 10.699 MB -> 4.403 MB. [2025-08-28T13:21:31.903Z] Camera thread performed 122 scans. [2025-08-28T13:21:31.903Z] ====== philosophers (scala) [default], iteration 0 completed (2344.209 ms) ====== [2025-08-28T13:21:31.903Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-28T13:21:31.903Z] GC before operation: completed in 54.440 ms, heap usage 16.144 MB -> 4.814 MB. [2025-08-28T13:21:33.335Z] Camera thread performed 122 scans. [2025-08-28T13:21:33.335Z] ====== philosophers (scala) [default], iteration 1 completed (1163.148 ms) ====== [2025-08-28T13:21:33.335Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-28T13:21:33.335Z] GC before operation: completed in 64.850 ms, heap usage 11.337 MB -> 4.815 MB. [2025-08-28T13:21:34.006Z] Camera thread performed 122 scans. [2025-08-28T13:21:34.006Z] ====== philosophers (scala) [default], iteration 2 completed (967.745 ms) ====== [2025-08-28T13:21:34.006Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-28T13:21:34.006Z] GC before operation: completed in 48.198 ms, heap usage 33.896 MB -> 4.815 MB. [2025-08-28T13:21:35.474Z] Camera thread performed 122 scans. [2025-08-28T13:21:35.474Z] ====== philosophers (scala) [default], iteration 3 completed (1197.596 ms) ====== [2025-08-28T13:21:35.474Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-28T13:21:35.474Z] GC before operation: completed in 151.023 ms, heap usage 32.924 MB -> 4.816 MB. [2025-08-28T13:21:36.870Z] Camera thread performed 122 scans. [2025-08-28T13:21:36.870Z] ====== philosophers (scala) [default], iteration 4 completed (1021.832 ms) ====== [2025-08-28T13:21:36.870Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-28T13:21:36.870Z] GC before operation: completed in 73.658 ms, heap usage 56.995 MB -> 4.819 MB. [2025-08-28T13:21:38.254Z] Camera thread performed 122 scans. [2025-08-28T13:21:38.254Z] ====== philosophers (scala) [default], iteration 5 completed (1489.162 ms) ====== [2025-08-28T13:21:38.254Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-28T13:21:38.254Z] GC before operation: completed in 55.162 ms, heap usage 27.972 MB -> 4.819 MB. [2025-08-28T13:21:38.904Z] Camera thread performed 122 scans. [2025-08-28T13:21:38.904Z] ====== philosophers (scala) [default], iteration 6 completed (879.308 ms) ====== [2025-08-28T13:21:38.904Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-28T13:21:38.904Z] GC before operation: completed in 60.642 ms, heap usage 21.410 MB -> 4.820 MB. [2025-08-28T13:21:40.465Z] Camera thread performed 122 scans. [2025-08-28T13:21:40.465Z] ====== philosophers (scala) [default], iteration 7 completed (967.122 ms) ====== [2025-08-28T13:21:40.465Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-28T13:21:40.465Z] GC before operation: completed in 71.977 ms, heap usage 97.947 MB -> 4.820 MB. [2025-08-28T13:21:41.813Z] Camera thread performed 122 scans. [2025-08-28T13:21:41.813Z] ====== philosophers (scala) [default], iteration 8 completed (1343.857 ms) ====== [2025-08-28T13:21:41.813Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-28T13:21:41.813Z] GC before operation: completed in 121.659 ms, heap usage 4.852 MB -> 4.821 MB. [2025-08-28T13:21:42.478Z] Camera thread performed 122 scans. [2025-08-28T13:21:42.479Z] ====== philosophers (scala) [default], iteration 9 completed (897.961 ms) ====== [2025-08-28T13:21:42.479Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-28T13:21:42.479Z] GC before operation: completed in 99.858 ms, heap usage 53.390 MB -> 4.843 MB. [2025-08-28T13:21:43.898Z] Camera thread performed 122 scans. [2025-08-28T13:21:43.899Z] ====== philosophers (scala) [default], iteration 10 completed (986.258 ms) ====== [2025-08-28T13:21:43.899Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-28T13:21:43.899Z] GC before operation: completed in 51.385 ms, heap usage 14.465 MB -> 4.822 MB. [2025-08-28T13:21:44.549Z] Camera thread performed 122 scans. [2025-08-28T13:21:44.549Z] ====== philosophers (scala) [default], iteration 11 completed (891.015 ms) ====== [2025-08-28T13:21:44.549Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-28T13:21:44.549Z] GC before operation: completed in 59.618 ms, heap usage 58.934 MB -> 4.821 MB. [2025-08-28T13:21:45.876Z] Camera thread performed 122 scans. [2025-08-28T13:21:45.876Z] ====== philosophers (scala) [default], iteration 12 completed (855.585 ms) ====== [2025-08-28T13:21:45.876Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-28T13:21:45.876Z] GC before operation: completed in 47.781 ms, heap usage 42.396 MB -> 4.843 MB. [2025-08-28T13:21:46.489Z] Camera thread performed 122 scans. [2025-08-28T13:21:46.489Z] ====== philosophers (scala) [default], iteration 13 completed (966.626 ms) ====== [2025-08-28T13:21:46.489Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-28T13:21:46.489Z] GC before operation: completed in 41.074 ms, heap usage 37.887 MB -> 4.823 MB. [2025-08-28T13:21:47.191Z] Camera thread performed 122 scans. [2025-08-28T13:21:47.191Z] ====== philosophers (scala) [default], iteration 14 completed (794.873 ms) ====== [2025-08-28T13:21:47.191Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-28T13:21:47.191Z] GC before operation: completed in 71.746 ms, heap usage 7.455 MB -> 4.822 MB. [2025-08-28T13:21:48.531Z] Camera thread performed 122 scans. [2025-08-28T13:21:48.531Z] ====== philosophers (scala) [default], iteration 15 completed (846.013 ms) ====== [2025-08-28T13:21:48.531Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-28T13:21:48.531Z] GC before operation: completed in 27.647 ms, heap usage 19.947 MB -> 4.822 MB. [2025-08-28T13:21:49.175Z] Camera thread performed 122 scans. [2025-08-28T13:21:49.175Z] ====== philosophers (scala) [default], iteration 16 completed (835.887 ms) ====== [2025-08-28T13:21:49.175Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-28T13:21:49.175Z] GC before operation: completed in 93.202 ms, heap usage 106.953 MB -> 4.822 MB. [2025-08-28T13:21:49.787Z] Camera thread performed 122 scans. [2025-08-28T13:21:49.787Z] ====== philosophers (scala) [default], iteration 17 completed (823.933 ms) ====== [2025-08-28T13:21:49.787Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-28T13:21:50.448Z] GC before operation: completed in 102.572 ms, heap usage 57.945 MB -> 4.844 MB. [2025-08-28T13:21:51.082Z] Camera thread performed 122 scans. [2025-08-28T13:21:51.082Z] ====== philosophers (scala) [default], iteration 18 completed (784.786 ms) ====== [2025-08-28T13:21:51.082Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-28T13:21:51.082Z] GC before operation: completed in 82.249 ms, heap usage 20.970 MB -> 4.823 MB. [2025-08-28T13:21:51.714Z] Camera thread performed 122 scans. [2025-08-28T13:21:51.714Z] ====== philosophers (scala) [default], iteration 19 completed (883.883 ms) ====== [2025-08-28T13:21:51.714Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-28T13:21:51.714Z] GC before operation: completed in 46.123 ms, heap usage 55.444 MB -> 4.823 MB. [2025-08-28T13:21:53.054Z] Camera thread performed 122 scans. [2025-08-28T13:21:53.054Z] ====== philosophers (scala) [default], iteration 20 completed (826.246 ms) ====== [2025-08-28T13:21:53.054Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-28T13:21:53.054Z] GC before operation: completed in 65.406 ms, heap usage 30.920 MB -> 4.823 MB. [2025-08-28T13:21:53.802Z] Camera thread performed 122 scans. [2025-08-28T13:21:53.802Z] ====== philosophers (scala) [default], iteration 21 completed (753.700 ms) ====== [2025-08-28T13:21:53.802Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-28T13:21:53.802Z] GC before operation: completed in 62.319 ms, heap usage 5.391 MB -> 4.823 MB. [2025-08-28T13:21:54.459Z] Camera thread performed 122 scans. [2025-08-28T13:21:54.459Z] ====== philosophers (scala) [default], iteration 22 completed (849.619 ms) ====== [2025-08-28T13:21:54.459Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-28T13:21:54.459Z] GC before operation: completed in 81.413 ms, heap usage 31.940 MB -> 4.824 MB. [2025-08-28T13:21:55.878Z] Camera thread performed 122 scans. [2025-08-28T13:21:55.878Z] ====== philosophers (scala) [default], iteration 23 completed (923.870 ms) ====== [2025-08-28T13:21:55.878Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-28T13:21:55.878Z] GC before operation: completed in 57.316 ms, heap usage 6.420 MB -> 4.824 MB. [2025-08-28T13:21:56.529Z] Camera thread performed 122 scans. [2025-08-28T13:21:56.529Z] ====== philosophers (scala) [default], iteration 24 completed (727.720 ms) ====== [2025-08-28T13:21:56.529Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-28T13:21:56.529Z] GC before operation: completed in 53.043 ms, heap usage 23.952 MB -> 4.824 MB. [2025-08-28T13:21:57.193Z] Camera thread performed 122 scans. [2025-08-28T13:21:57.193Z] ====== philosophers (scala) [default], iteration 25 completed (696.908 ms) ====== [2025-08-28T13:21:57.193Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-28T13:21:57.193Z] GC before operation: completed in 97.486 ms, heap usage 9.440 MB -> 4.824 MB. [2025-08-28T13:21:58.600Z] Camera thread performed 122 scans. [2025-08-28T13:21:58.600Z] ====== philosophers (scala) [default], iteration 26 completed (1041.259 ms) ====== [2025-08-28T13:21:58.600Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-28T13:21:58.600Z] GC before operation: completed in 55.889 ms, heap usage 52.375 MB -> 4.825 MB. [2025-08-28T13:21:59.241Z] Camera thread performed 122 scans. [2025-08-28T13:21:59.241Z] ====== philosophers (scala) [default], iteration 27 completed (874.374 ms) ====== [2025-08-28T13:21:59.241Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-28T13:21:59.241Z] GC before operation: completed in 107.824 ms, heap usage 59.921 MB -> 4.825 MB. [2025-08-28T13:21:59.882Z] Camera thread performed 122 scans. [2025-08-28T13:21:59.883Z] ====== philosophers (scala) [default], iteration 28 completed (858.275 ms) ====== [2025-08-28T13:21:59.883Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-28T13:21:59.883Z] GC before operation: completed in 66.428 ms, heap usage 53.450 MB -> 4.825 MB. [2025-08-28T13:22:01.203Z] Camera thread performed 122 scans. [2025-08-28T13:22:01.203Z] ====== philosophers (scala) [default], iteration 29 completed (745.304 ms) ====== [2025-08-28T13:22:01.203Z] ----------------------------------- [2025-08-28T13:22:01.203Z] renaissance-philosophers_0_PASSED [2025-08-28T13:22:01.203Z] ----------------------------------- [2025-08-28T13:22:01.203Z] [2025-08-28T13:22:01.203Z] TEST TEARDOWN: [2025-08-28T13:22:01.203Z] Nothing to be done for teardown. [2025-08-28T13:22:01.203Z] renaissance-philosophers_0 Finish Time: Thu Aug 28 13:22:00 2025 Epoch Time (ms): 1756387320808