[2025-11-07T00:04:00.068Z] Running test renaissance-philosophers_0 ... [2025-11-07T00:04:00.068Z] =============================================== [2025-11-07T00:04:00.068Z] renaissance-philosophers_0 Start Time: Fri Nov 7 00:03:59 2025 Epoch Time (ms): 1762473839425 [2025-11-07T00:04:00.068Z] variation: NoOptions [2025-11-07T00:04:00.068Z] JVM_OPTIONS: [2025-11-07T00:04:00.068Z] { \ [2025-11-07T00:04:00.068Z] echo ""; echo "TEST SETUP:"; \ [2025-11-07T00:04:00.068Z] echo "Nothing to be done for setup."; \ [2025-11-07T00:04:00.068Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17624729476273/renaissance-philosophers_0"; \ [2025-11-07T00:04:00.068Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17624729476273/renaissance-philosophers_0"; \ [2025-11-07T00:04:00.068Z] echo ""; echo "TESTING:"; \ [2025-11-07T00:04:00.068Z] "/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_17624729476273/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-07T00:04:00.068Z] 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_17624729476273/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-07T00:04:00.068Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-07T00:04:00.068Z] echo "Nothing to be done for teardown."; \ [2025-11-07T00:04:00.068Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17624729476273/TestTargetResult"; [2025-11-07T00:04:00.068Z] [2025-11-07T00:04:00.068Z] TEST SETUP: [2025-11-07T00:04:00.068Z] Nothing to be done for setup. [2025-11-07T00:04:00.068Z] [2025-11-07T00:04:00.068Z] TESTING: [2025-11-07T00:04:01.029Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-11-07T00:04:01.029Z] 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_17624729476273/renaissance-philosophers_0/launcher-000359-7848785539562190476/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-11-07T00:04:01.029Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-11-07T00:04:01.029Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-11-07T00:04:02.006Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-07T00:04:02.006Z] GC before operation: completed in 45.575 ms, heap usage 12.965 MB -> 4.871 MB. [2025-11-07T00:04:07.514Z] Camera thread performed 122 scans. [2025-11-07T00:04:07.515Z] ====== philosophers (scala) [default], iteration 0 completed (5130.863 ms) ====== [2025-11-07T00:04:07.515Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-07T00:04:07.515Z] GC before operation: completed in 56.945 ms, heap usage 73.897 MB -> 5.215 MB. [2025-11-07T00:04:08.485Z] Camera thread performed 122 scans. [2025-11-07T00:04:08.485Z] ====== philosophers (scala) [default], iteration 1 completed (1851.330 ms) ====== [2025-11-07T00:04:08.485Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-07T00:04:08.485Z] GC before operation: completed in 48.703 ms, heap usage 46.340 MB -> 5.215 MB. [2025-11-07T00:04:10.661Z] Camera thread performed 122 scans. [2025-11-07T00:04:10.661Z] ====== philosophers (scala) [default], iteration 2 completed (1894.329 ms) ====== [2025-11-07T00:04:10.661Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-07T00:04:10.661Z] GC before operation: completed in 36.088 ms, heap usage 134.363 MB -> 5.215 MB. [2025-11-07T00:04:12.644Z] Camera thread performed 122 scans. [2025-11-07T00:04:12.644Z] ====== philosophers (scala) [default], iteration 3 completed (1720.374 ms) ====== [2025-11-07T00:04:12.644Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-07T00:04:12.644Z] GC before operation: completed in 31.584 ms, heap usage 19.158 MB -> 5.216 MB. [2025-11-07T00:04:14.638Z] Camera thread performed 122 scans. [2025-11-07T00:04:14.638Z] ====== philosophers (scala) [default], iteration 4 completed (1685.398 ms) ====== [2025-11-07T00:04:14.638Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-07T00:04:14.638Z] GC before operation: completed in 36.073 ms, heap usage 114.597 MB -> 5.252 MB. [2025-11-07T00:04:15.605Z] Camera thread performed 122 scans. [2025-11-07T00:04:15.605Z] ====== philosophers (scala) [default], iteration 5 completed (1665.873 ms) ====== [2025-11-07T00:04:15.605Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-07T00:04:15.605Z] GC before operation: completed in 43.233 ms, heap usage 128.522 MB -> 5.217 MB. [2025-11-07T00:04:17.591Z] Camera thread performed 122 scans. [2025-11-07T00:04:17.591Z] ====== philosophers (scala) [default], iteration 6 completed (1734.405 ms) ====== [2025-11-07T00:04:17.591Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-07T00:04:17.591Z] GC before operation: completed in 35.943 ms, heap usage 130.434 MB -> 5.218 MB. [2025-11-07T00:04:19.574Z] Camera thread performed 122 scans. [2025-11-07T00:04:19.574Z] ====== philosophers (scala) [default], iteration 7 completed (1713.425 ms) ====== [2025-11-07T00:04:19.574Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-07T00:04:19.574Z] GC before operation: completed in 35.157 ms, heap usage 115.923 MB -> 5.252 MB. [2025-11-07T00:04:21.558Z] Camera thread performed 122 scans. [2025-11-07T00:04:21.558Z] ====== philosophers (scala) [default], iteration 8 completed (1759.821 ms) ====== [2025-11-07T00:04:21.558Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-07T00:04:21.558Z] GC before operation: completed in 35.572 ms, heap usage 131.909 MB -> 5.218 MB. [2025-11-07T00:04:23.538Z] Camera thread performed 122 scans. [2025-11-07T00:04:23.538Z] ====== philosophers (scala) [default], iteration 9 completed (1774.362 ms) ====== [2025-11-07T00:04:23.538Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-07T00:04:23.538Z] GC before operation: completed in 36.170 ms, heap usage 111.343 MB -> 5.218 MB. [2025-11-07T00:04:24.502Z] Camera thread performed 122 scans. [2025-11-07T00:04:24.503Z] ====== philosophers (scala) [default], iteration 10 completed (1737.367 ms) ====== [2025-11-07T00:04:24.503Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-07T00:04:24.503Z] GC before operation: completed in 44.880 ms, heap usage 67.856 MB -> 5.219 MB. [2025-11-07T00:04:26.483Z] Camera thread performed 122 scans. [2025-11-07T00:04:26.483Z] ====== philosophers (scala) [default], iteration 11 completed (1767.414 ms) ====== [2025-11-07T00:04:26.484Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-07T00:04:26.484Z] GC before operation: completed in 35.769 ms, heap usage 113.919 MB -> 5.219 MB. [2025-11-07T00:04:28.464Z] Camera thread performed 122 scans. [2025-11-07T00:04:28.464Z] ====== philosophers (scala) [default], iteration 12 completed (1842.035 ms) ====== [2025-11-07T00:04:28.464Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-07T00:04:28.464Z] GC before operation: completed in 33.827 ms, heap usage 83.919 MB -> 5.219 MB. [2025-11-07T00:04:30.159Z] Camera thread performed 122 scans. [2025-11-07T00:04:30.159Z] ====== philosophers (scala) [default], iteration 13 completed (1717.332 ms) ====== [2025-11-07T00:04:30.159Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-07T00:04:30.159Z] GC before operation: completed in 35.158 ms, heap usage 116.417 MB -> 5.220 MB. [2025-11-07T00:04:32.143Z] Camera thread performed 122 scans. [2025-11-07T00:04:32.143Z] ====== philosophers (scala) [default], iteration 14 completed (1806.062 ms) ====== [2025-11-07T00:04:32.143Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-07T00:04:32.143Z] GC before operation: completed in 33.411 ms, heap usage 126.402 MB -> 5.220 MB. [2025-11-07T00:04:34.124Z] Camera thread performed 122 scans. [2025-11-07T00:04:34.124Z] ====== philosophers (scala) [default], iteration 15 completed (1638.557 ms) ====== [2025-11-07T00:04:34.124Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-07T00:04:34.124Z] GC before operation: completed in 33.008 ms, heap usage 110.915 MB -> 5.219 MB. [2025-11-07T00:04:35.092Z] Camera thread performed 122 scans. [2025-11-07T00:04:35.092Z] ====== philosophers (scala) [default], iteration 16 completed (1524.505 ms) ====== [2025-11-07T00:04:35.092Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-07T00:04:35.092Z] GC before operation: completed in 33.618 ms, heap usage 126.904 MB -> 5.220 MB. [2025-11-07T00:04:37.072Z] Camera thread performed 122 scans. [2025-11-07T00:04:37.072Z] ====== philosophers (scala) [default], iteration 17 completed (1583.974 ms) ====== [2025-11-07T00:04:37.072Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-07T00:04:37.072Z] GC before operation: completed in 32.921 ms, heap usage 128.419 MB -> 5.220 MB. [2025-11-07T00:04:39.056Z] Camera thread performed 122 scans. [2025-11-07T00:04:39.056Z] ====== philosophers (scala) [default], iteration 18 completed (1705.893 ms) ====== [2025-11-07T00:04:39.056Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-07T00:04:39.056Z] GC before operation: completed in 32.995 ms, heap usage 112.415 MB -> 5.220 MB. [2025-11-07T00:04:40.021Z] Camera thread performed 122 scans. [2025-11-07T00:04:40.021Z] ====== philosophers (scala) [default], iteration 19 completed (1757.775 ms) ====== [2025-11-07T00:04:40.021Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-07T00:04:40.987Z] GC before operation: completed in 34.030 ms, heap usage 114.913 MB -> 5.220 MB. [2025-11-07T00:04:41.952Z] Camera thread performed 122 scans. [2025-11-07T00:04:41.952Z] ====== philosophers (scala) [default], iteration 20 completed (1565.142 ms) ====== [2025-11-07T00:04:41.952Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-07T00:04:41.952Z] GC before operation: completed in 36.517 ms, heap usage 125.919 MB -> 5.220 MB. [2025-11-07T00:04:43.934Z] Camera thread performed 122 scans. [2025-11-07T00:04:43.934Z] ====== philosophers (scala) [default], iteration 21 completed (1827.761 ms) ====== [2025-11-07T00:04:43.934Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-07T00:04:43.934Z] GC before operation: completed in 46.926 ms, heap usage 124.403 MB -> 5.220 MB. [2025-11-07T00:04:45.911Z] Camera thread performed 122 scans. [2025-11-07T00:04:45.911Z] ====== philosophers (scala) [default], iteration 22 completed (1651.291 ms) ====== [2025-11-07T00:04:45.911Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-07T00:04:45.912Z] GC before operation: completed in 44.772 ms, heap usage 112.299 MB -> 5.220 MB. [2025-11-07T00:04:46.878Z] Camera thread performed 122 scans. [2025-11-07T00:04:46.878Z] ====== philosophers (scala) [default], iteration 23 completed (1568.271 ms) ====== [2025-11-07T00:04:46.878Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-07T00:04:46.878Z] GC before operation: completed in 40.244 ms, heap usage 127.299 MB -> 5.220 MB. [2025-11-07T00:04:48.861Z] Camera thread performed 122 scans. [2025-11-07T00:04:48.861Z] ====== philosophers (scala) [default], iteration 24 completed (1629.592 ms) ====== [2025-11-07T00:04:48.861Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-07T00:04:48.861Z] GC before operation: completed in 28.915 ms, heap usage 115.885 MB -> 5.221 MB. [2025-11-07T00:04:50.848Z] Camera thread performed 122 scans. [2025-11-07T00:04:50.848Z] ====== philosophers (scala) [default], iteration 25 completed (1664.656 ms) ====== [2025-11-07T00:04:50.848Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-07T00:04:50.848Z] GC before operation: completed in 29.316 ms, heap usage 106.874 MB -> 5.220 MB. [2025-11-07T00:04:52.834Z] Camera thread performed 122 scans. [2025-11-07T00:04:52.834Z] ====== philosophers (scala) [default], iteration 26 completed (1681.129 ms) ====== [2025-11-07T00:04:52.834Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-07T00:04:52.834Z] GC before operation: completed in 41.925 ms, heap usage 112.886 MB -> 5.221 MB. [2025-11-07T00:04:53.800Z] Camera thread performed 122 scans. [2025-11-07T00:04:53.800Z] ====== philosophers (scala) [default], iteration 27 completed (1801.692 ms) ====== [2025-11-07T00:04:53.800Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-07T00:04:53.800Z] GC before operation: completed in 38.638 ms, heap usage 128.405 MB -> 5.221 MB. [2025-11-07T00:04:55.808Z] Camera thread performed 122 scans. [2025-11-07T00:04:55.808Z] ====== philosophers (scala) [default], iteration 28 completed (1729.949 ms) ====== [2025-11-07T00:04:55.808Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-07T00:04:55.808Z] GC before operation: completed in 26.561 ms, heap usage 144.924 MB -> 5.221 MB. [2025-11-07T00:04:57.783Z] Camera thread performed 122 scans. [2025-11-07T00:04:57.783Z] ====== philosophers (scala) [default], iteration 29 completed (1680.389 ms) ====== [2025-11-07T00:04:57.783Z] ----------------------------------- [2025-11-07T00:04:57.783Z] renaissance-philosophers_0_PASSED [2025-11-07T00:04:57.783Z] ----------------------------------- [2025-11-07T00:04:57.783Z] [2025-11-07T00:04:57.783Z] TEST TEARDOWN: [2025-11-07T00:04:57.783Z] Nothing to be done for teardown. [2025-11-07T00:04:57.783Z] renaissance-philosophers_0 Finish Time: Fri Nov 7 00:04:57 2025 Epoch Time (ms): 1762473897564