[2025-12-30T18:16:39.451Z] Running test renaissance-philosophers_0 ... [2025-12-30T18:16:39.451Z] =============================================== [2025-12-30T18:16:39.451Z] renaissance-philosophers_0 Start Time: Tue Dec 30 18:16:39 2025 Epoch Time (ms): 1767118599007 [2025-12-30T18:16:39.451Z] variation: NoOptions [2025-12-30T18:16:39.451Z] JVM_OPTIONS: [2025-12-30T18:16:39.451Z] { \ [2025-12-30T18:16:39.451Z] echo ""; echo "TEST SETUP:"; \ [2025-12-30T18:16:39.451Z] echo "Nothing to be done for setup."; \ [2025-12-30T18:16:39.451Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1767117986328/renaissance-philosophers_0"; \ [2025-12-30T18:16:39.451Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1767117986328/renaissance-philosophers_0"; \ [2025-12-30T18:16:39.451Z] echo ""; echo "TESTING:"; \ [2025-12-30T18:16:39.451Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-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_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1767117986328/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-30T18:16:39.451Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1767117986328/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-30T18:16:39.451Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-30T18:16:39.451Z] echo "Nothing to be done for teardown."; \ [2025-12-30T18:16:39.451Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_1767117986328/TestTargetResult"; [2025-12-30T18:16:39.451Z] [2025-12-30T18:16:39.451Z] TEST SETUP: [2025-12-30T18:16:39.451Z] Nothing to be done for setup. [2025-12-30T18:16:39.451Z] [2025-12-30T18:16:39.451Z] TESTING: [2025-12-30T18:16:40.407Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-30T18:16:40.407Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_1767117986328/renaissance-philosophers_0/launcher-181639-13539623324236842789/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-30T18:16:40.407Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-30T18:16:40.407Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-30T18:16:41.369Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-30T18:16:41.369Z] GC before operation: completed in 24.530 ms, heap usage 9.039 MB -> 4.846 MB. [2025-12-30T18:16:44.423Z] Camera thread performed 122 scans. [2025-12-30T18:16:44.423Z] ====== philosophers (scala) [default], iteration 0 completed (3539.997 ms) ====== [2025-12-30T18:16:44.423Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-30T18:16:44.423Z] GC before operation: completed in 26.567 ms, heap usage 27.918 MB -> 5.190 MB. [2025-12-30T18:16:47.475Z] Camera thread performed 122 scans. [2025-12-30T18:16:47.475Z] ====== philosophers (scala) [default], iteration 1 completed (3027.854 ms) ====== [2025-12-30T18:16:47.475Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-30T18:16:47.475Z] GC before operation: completed in 32.355 ms, heap usage 142.366 MB -> 5.190 MB. [2025-12-30T18:16:49.457Z] Camera thread performed 122 scans. [2025-12-30T18:16:49.457Z] ====== philosophers (scala) [default], iteration 2 completed (2037.349 ms) ====== [2025-12-30T18:16:49.457Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-30T18:16:49.457Z] GC before operation: completed in 21.882 ms, heap usage 11.291 MB -> 5.191 MB. [2025-12-30T18:16:52.509Z] Camera thread performed 122 scans. [2025-12-30T18:16:52.509Z] ====== philosophers (scala) [default], iteration 3 completed (2576.637 ms) ====== [2025-12-30T18:16:52.509Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-30T18:16:52.509Z] GC before operation: completed in 23.299 ms, heap usage 43.238 MB -> 5.191 MB. [2025-12-30T18:16:55.558Z] Camera thread performed 122 scans. [2025-12-30T18:16:55.558Z] ====== philosophers (scala) [default], iteration 4 completed (2592.960 ms) ====== [2025-12-30T18:16:55.558Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-30T18:16:55.558Z] GC before operation: completed in 22.236 ms, heap usage 37.036 MB -> 5.192 MB. [2025-12-30T18:16:57.537Z] Camera thread performed 122 scans. [2025-12-30T18:16:57.537Z] ====== philosophers (scala) [default], iteration 5 completed (2633.056 ms) ====== [2025-12-30T18:16:57.537Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-30T18:16:57.537Z] GC before operation: completed in 22.302 ms, heap usage 15.987 MB -> 5.192 MB. [2025-12-30T18:17:00.591Z] Camera thread performed 122 scans. [2025-12-30T18:17:00.591Z] ====== philosophers (scala) [default], iteration 6 completed (2723.766 ms) ====== [2025-12-30T18:17:00.591Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-30T18:17:00.591Z] GC before operation: completed in 26.860 ms, heap usage 82.806 MB -> 5.193 MB. [2025-12-30T18:17:06.019Z] Camera thread performed 122 scans. [2025-12-30T18:17:06.019Z] ====== philosophers (scala) [default], iteration 7 completed (4891.733 ms) ====== [2025-12-30T18:17:06.019Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-30T18:17:06.019Z] GC before operation: completed in 28.738 ms, heap usage 22.011 MB -> 5.193 MB. [2025-12-30T18:17:10.919Z] Camera thread performed 122 scans. [2025-12-30T18:17:10.919Z] ====== philosophers (scala) [default], iteration 8 completed (5330.863 ms) ====== [2025-12-30T18:17:10.919Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-30T18:17:10.919Z] GC before operation: completed in 26.581 ms, heap usage 40.884 MB -> 5.193 MB. [2025-12-30T18:17:12.893Z] Camera thread performed 122 scans. [2025-12-30T18:17:12.893Z] ====== philosophers (scala) [default], iteration 9 completed (2247.364 ms) ====== [2025-12-30T18:17:12.893Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-30T18:17:12.893Z] GC before operation: completed in 28.979 ms, heap usage 80.897 MB -> 5.193 MB. [2025-12-30T18:17:18.317Z] Camera thread performed 122 scans. [2025-12-30T18:17:18.317Z] ====== philosophers (scala) [default], iteration 10 completed (4573.953 ms) ====== [2025-12-30T18:17:18.317Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-30T18:17:18.317Z] GC before operation: completed in 29.187 ms, heap usage 40.360 MB -> 5.194 MB. [2025-12-30T18:17:21.380Z] Camera thread performed 122 scans. [2025-12-30T18:17:21.380Z] ====== philosophers (scala) [default], iteration 11 completed (4182.600 ms) ====== [2025-12-30T18:17:21.380Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-30T18:17:22.346Z] GC before operation: completed in 31.810 ms, heap usage 24.199 MB -> 5.194 MB. [2025-12-30T18:17:26.543Z] Camera thread performed 122 scans. [2025-12-30T18:17:26.543Z] ====== philosophers (scala) [default], iteration 12 completed (4316.167 ms) ====== [2025-12-30T18:17:26.543Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-30T18:17:26.543Z] GC before operation: completed in 29.243 ms, heap usage 45.280 MB -> 5.194 MB. [2025-12-30T18:17:30.744Z] Camera thread performed 122 scans. [2025-12-30T18:17:30.744Z] ====== philosophers (scala) [default], iteration 13 completed (4986.022 ms) ====== [2025-12-30T18:17:30.744Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-30T18:17:31.711Z] GC before operation: completed in 28.477 ms, heap usage 54.409 MB -> 5.194 MB. [2025-12-30T18:17:35.933Z] Camera thread performed 122 scans. [2025-12-30T18:17:35.933Z] ====== philosophers (scala) [default], iteration 14 completed (4630.077 ms) ====== [2025-12-30T18:17:35.933Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-30T18:17:35.933Z] GC before operation: completed in 83.219 ms, heap usage 75.053 MB -> 5.195 MB. [2025-12-30T18:17:41.411Z] Camera thread performed 122 scans. [2025-12-30T18:17:41.411Z] ====== philosophers (scala) [default], iteration 15 completed (4932.927 ms) ====== [2025-12-30T18:17:41.411Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-30T18:17:41.411Z] GC before operation: completed in 28.787 ms, heap usage 47.151 MB -> 5.195 MB. [2025-12-30T18:17:45.626Z] Camera thread performed 122 scans. [2025-12-30T18:17:45.626Z] ====== philosophers (scala) [default], iteration 16 completed (4773.878 ms) ====== [2025-12-30T18:17:45.626Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-30T18:17:45.626Z] GC before operation: completed in 28.766 ms, heap usage 28.769 MB -> 5.195 MB. [2025-12-30T18:17:49.879Z] Camera thread performed 122 scans. [2025-12-30T18:17:49.879Z] ====== philosophers (scala) [default], iteration 17 completed (4329.065 ms) ====== [2025-12-30T18:17:49.879Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-30T18:17:49.879Z] GC before operation: completed in 28.070 ms, heap usage 27.351 MB -> 5.195 MB. [2025-12-30T18:17:54.110Z] Camera thread performed 122 scans. [2025-12-30T18:17:54.110Z] ====== philosophers (scala) [default], iteration 18 completed (4216.377 ms) ====== [2025-12-30T18:17:54.110Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-30T18:17:54.110Z] GC before operation: completed in 33.604 ms, heap usage 71.817 MB -> 5.195 MB. [2025-12-30T18:17:59.553Z] Camera thread performed 122 scans. [2025-12-30T18:17:59.553Z] ====== philosophers (scala) [default], iteration 19 completed (4591.830 ms) ====== [2025-12-30T18:17:59.553Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-30T18:17:59.553Z] GC before operation: completed in 31.288 ms, heap usage 118.893 MB -> 5.195 MB. [2025-12-30T18:18:03.321Z] Camera thread performed 122 scans. [2025-12-30T18:18:03.321Z] ====== philosophers (scala) [default], iteration 20 completed (4324.225 ms) ====== [2025-12-30T18:18:03.321Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-30T18:18:03.321Z] GC before operation: completed in 24.970 ms, heap usage 6.287 MB -> 5.195 MB. [2025-12-30T18:18:08.779Z] Camera thread performed 122 scans. [2025-12-30T18:18:08.779Z] ====== philosophers (scala) [default], iteration 21 completed (4918.148 ms) ====== [2025-12-30T18:18:08.779Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-30T18:18:08.779Z] GC before operation: completed in 25.227 ms, heap usage 8.866 MB -> 5.195 MB. [2025-12-30T18:18:11.829Z] Camera thread performed 122 scans. [2025-12-30T18:18:11.829Z] ====== philosophers (scala) [default], iteration 22 completed (4040.172 ms) ====== [2025-12-30T18:18:11.829Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-30T18:18:12.796Z] GC before operation: completed in 29.307 ms, heap usage 118.888 MB -> 5.195 MB. [2025-12-30T18:18:16.997Z] Camera thread performed 122 scans. [2025-12-30T18:18:16.997Z] ====== philosophers (scala) [default], iteration 23 completed (4943.336 ms) ====== [2025-12-30T18:18:16.997Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-30T18:18:16.997Z] GC before operation: completed in 25.727 ms, heap usage 15.399 MB -> 5.195 MB. [2025-12-30T18:18:22.443Z] Camera thread performed 122 scans. [2025-12-30T18:18:22.443Z] ====== philosophers (scala) [default], iteration 24 completed (4671.655 ms) ====== [2025-12-30T18:18:22.443Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-30T18:18:22.443Z] GC before operation: completed in 25.045 ms, heap usage 41.580 MB -> 5.195 MB. [2025-12-30T18:18:26.667Z] Camera thread performed 122 scans. [2025-12-30T18:18:26.667Z] ====== philosophers (scala) [default], iteration 25 completed (4869.198 ms) ====== [2025-12-30T18:18:26.667Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-30T18:18:26.667Z] GC before operation: completed in 28.844 ms, heap usage 20.052 MB -> 5.195 MB. [2025-12-30T18:18:32.159Z] Camera thread performed 122 scans. [2025-12-30T18:18:32.159Z] ====== philosophers (scala) [default], iteration 26 completed (5137.020 ms) ====== [2025-12-30T18:18:32.159Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-30T18:18:32.159Z] GC before operation: completed in 25.174 ms, heap usage 20.666 MB -> 5.196 MB. [2025-12-30T18:18:37.609Z] Camera thread performed 122 scans. [2025-12-30T18:18:37.609Z] ====== philosophers (scala) [default], iteration 27 completed (4806.704 ms) ====== [2025-12-30T18:18:37.609Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-30T18:18:37.609Z] GC before operation: completed in 27.016 ms, heap usage 20.396 MB -> 5.196 MB. [2025-12-30T18:18:40.652Z] Camera thread performed 122 scans. [2025-12-30T18:18:40.652Z] ====== philosophers (scala) [default], iteration 28 completed (3187.662 ms) ====== [2025-12-30T18:18:40.652Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-30T18:18:40.652Z] GC before operation: completed in 29.974 ms, heap usage 91.331 MB -> 5.196 MB. [2025-12-30T18:18:44.838Z] Camera thread performed 122 scans. [2025-12-30T18:18:44.838Z] ====== philosophers (scala) [default], iteration 29 completed (4091.126 ms) ====== [2025-12-30T18:18:44.838Z] ----------------------------------- [2025-12-30T18:18:44.838Z] renaissance-philosophers_0_PASSED [2025-12-30T18:18:44.838Z] ----------------------------------- [2025-12-30T18:18:44.838Z] [2025-12-30T18:18:44.838Z] TEST TEARDOWN: [2025-12-30T18:18:44.838Z] Nothing to be done for teardown. [2025-12-30T18:18:44.838Z] renaissance-philosophers_0 Finish Time: Tue Dec 30 18:18:44 2025 Epoch Time (ms): 1767118724088