[2025-12-30T16:26:15.862Z] Running test renaissance-philosophers_0 ... [2025-12-30T16:26:15.862Z] =============================================== [2025-12-30T16:26:15.862Z] renaissance-philosophers_0 Start Time: Tue Dec 30 16:26:15 2025 Epoch Time (ms): 1767111975773 [2025-12-30T16:26:15.862Z] variation: NoOptions [2025-12-30T16:26:15.862Z] JVM_OPTIONS: [2025-12-30T16:26:15.862Z] { \ [2025-12-30T16:26:15.862Z] echo ""; echo "TEST SETUP:"; \ [2025-12-30T16:26:15.862Z] echo "Nothing to be done for setup."; \ [2025-12-30T16:26:15.862Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17671115549698/renaissance-philosophers_0"; \ [2025-12-30T16:26:15.862Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17671115549698/renaissance-philosophers_0"; \ [2025-12-30T16:26:15.862Z] echo ""; echo "TESTING:"; \ [2025-12-30T16:26:15.862Z] "/home/jenkins/workspace/Test_openjdk26_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_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17671115549698/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-30T16:26:15.862Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17671115549698/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-30T16:26:15.862Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-30T16:26:15.862Z] echo "Nothing to be done for teardown."; \ [2025-12-30T16:26:15.862Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17671115549698/TestTargetResult"; [2025-12-30T16:26:15.862Z] [2025-12-30T16:26:15.862Z] TEST SETUP: [2025-12-30T16:26:15.862Z] Nothing to be done for setup. [2025-12-30T16:26:15.862Z] [2025-12-30T16:26:15.862Z] TESTING: [2025-12-30T16:26:16.569Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-30T16:26:16.570Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17671115549698/renaissance-philosophers_0/launcher-162615-1162499548591974473/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-30T16:26:16.570Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-30T16:26:16.570Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-30T16:26:16.570Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-30T16:26:16.570Z] GC before operation: completed in 12.804 ms, heap usage 9.113 MB -> 4.889 MB. [2025-12-30T16:26:18.258Z] Camera thread performed 122 scans. [2025-12-30T16:26:18.591Z] ====== philosophers (scala) [default], iteration 0 completed (1704.300 ms) ====== [2025-12-30T16:26:18.591Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-30T16:26:18.591Z] GC before operation: completed in 15.495 ms, heap usage 56.404 MB -> 5.228 MB. [2025-12-30T16:26:19.305Z] Camera thread performed 122 scans. [2025-12-30T16:26:19.305Z] ====== philosophers (scala) [default], iteration 1 completed (790.970 ms) ====== [2025-12-30T16:26:19.305Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-30T16:26:19.305Z] GC before operation: completed in 12.218 ms, heap usage 7.307 MB -> 5.228 MB. [2025-12-30T16:26:20.016Z] Camera thread performed 122 scans. [2025-12-30T16:26:20.016Z] ====== philosophers (scala) [default], iteration 2 completed (782.127 ms) ====== [2025-12-30T16:26:20.016Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-30T16:26:20.016Z] GC before operation: completed in 21.861 ms, heap usage 54.335 MB -> 5.229 MB. [2025-12-30T16:26:20.722Z] Camera thread performed 122 scans. [2025-12-30T16:26:20.722Z] ====== philosophers (scala) [default], iteration 3 completed (740.589 ms) ====== [2025-12-30T16:26:20.722Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-30T16:26:20.722Z] GC before operation: completed in 13.227 ms, heap usage 34.691 MB -> 5.229 MB. [2025-12-30T16:26:21.429Z] Camera thread performed 122 scans. [2025-12-30T16:26:21.429Z] ====== philosophers (scala) [default], iteration 4 completed (773.616 ms) ====== [2025-12-30T16:26:21.429Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-30T16:26:21.758Z] GC before operation: completed in 13.731 ms, heap usage 110.578 MB -> 5.229 MB. [2025-12-30T16:26:22.464Z] Camera thread performed 122 scans. [2025-12-30T16:26:22.464Z] ====== philosophers (scala) [default], iteration 5 completed (764.040 ms) ====== [2025-12-30T16:26:22.464Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-30T16:26:22.464Z] GC before operation: completed in 11.409 ms, heap usage 18.945 MB -> 5.229 MB. [2025-12-30T16:26:23.184Z] Camera thread performed 122 scans. [2025-12-30T16:26:23.185Z] ====== philosophers (scala) [default], iteration 6 completed (691.701 ms) ====== [2025-12-30T16:26:23.185Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-30T16:26:23.185Z] GC before operation: completed in 11.598 ms, heap usage 47.871 MB -> 5.230 MB. [2025-12-30T16:26:23.900Z] Camera thread performed 122 scans. [2025-12-30T16:26:23.900Z] ====== philosophers (scala) [default], iteration 7 completed (744.945 ms) ====== [2025-12-30T16:26:23.900Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-30T16:26:23.900Z] GC before operation: completed in 11.911 ms, heap usage 58.390 MB -> 5.230 MB. [2025-12-30T16:26:24.610Z] Camera thread performed 122 scans. [2025-12-30T16:26:24.611Z] ====== philosophers (scala) [default], iteration 8 completed (776.218 ms) ====== [2025-12-30T16:26:24.611Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-30T16:26:24.611Z] GC before operation: completed in 11.441 ms, heap usage 29.868 MB -> 5.230 MB. [2025-12-30T16:26:25.321Z] Camera thread performed 122 scans. [2025-12-30T16:26:25.321Z] ====== philosophers (scala) [default], iteration 9 completed (767.060 ms) ====== [2025-12-30T16:26:25.321Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-30T16:26:25.321Z] GC before operation: completed in 12.850 ms, heap usage 30.354 MB -> 5.230 MB. [2025-12-30T16:26:26.031Z] Camera thread performed 122 scans. [2025-12-30T16:26:26.031Z] ====== philosophers (scala) [default], iteration 10 completed (763.308 ms) ====== [2025-12-30T16:26:26.031Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-30T16:26:26.031Z] GC before operation: completed in 13.563 ms, heap usage 109.846 MB -> 5.231 MB. [2025-12-30T16:26:27.180Z] Camera thread performed 122 scans. [2025-12-30T16:26:27.180Z] ====== philosophers (scala) [default], iteration 11 completed (774.668 ms) ====== [2025-12-30T16:26:27.180Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-30T16:26:27.180Z] GC before operation: completed in 10.853 ms, heap usage 33.352 MB -> 5.231 MB. [2025-12-30T16:26:27.888Z] Camera thread performed 122 scans. [2025-12-30T16:26:27.888Z] ====== philosophers (scala) [default], iteration 12 completed (782.974 ms) ====== [2025-12-30T16:26:27.888Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-30T16:26:27.888Z] GC before operation: completed in 11.490 ms, heap usage 41.346 MB -> 5.231 MB. [2025-12-30T16:26:28.599Z] Camera thread performed 122 scans. [2025-12-30T16:26:28.599Z] ====== philosophers (scala) [default], iteration 13 completed (773.463 ms) ====== [2025-12-30T16:26:28.599Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-30T16:26:28.599Z] GC before operation: completed in 11.885 ms, heap usage 19.856 MB -> 5.231 MB. [2025-12-30T16:26:29.310Z] Camera thread performed 122 scans. [2025-12-30T16:26:29.310Z] ====== philosophers (scala) [default], iteration 14 completed (775.069 ms) ====== [2025-12-30T16:26:29.310Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-30T16:26:29.310Z] GC before operation: completed in 13.712 ms, heap usage 90.352 MB -> 5.231 MB. [2025-12-30T16:26:30.036Z] Camera thread performed 122 scans. [2025-12-30T16:26:30.036Z] ====== philosophers (scala) [default], iteration 15 completed (727.347 ms) ====== [2025-12-30T16:26:30.036Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-30T16:26:30.036Z] GC before operation: completed in 12.889 ms, heap usage 73.356 MB -> 5.232 MB. [2025-12-30T16:26:30.748Z] Camera thread performed 122 scans. [2025-12-30T16:26:30.748Z] ====== philosophers (scala) [default], iteration 16 completed (758.148 ms) ====== [2025-12-30T16:26:30.748Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-30T16:26:30.748Z] GC before operation: completed in 11.393 ms, heap usage 24.348 MB -> 5.232 MB. [2025-12-30T16:26:31.455Z] Camera thread performed 122 scans. [2025-12-30T16:26:31.455Z] ====== philosophers (scala) [default], iteration 17 completed (753.114 ms) ====== [2025-12-30T16:26:31.455Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-30T16:26:31.455Z] GC before operation: completed in 10.969 ms, heap usage 36.356 MB -> 5.232 MB. [2025-12-30T16:26:32.607Z] Camera thread performed 122 scans. [2025-12-30T16:26:32.607Z] ====== philosophers (scala) [default], iteration 18 completed (756.053 ms) ====== [2025-12-30T16:26:32.607Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-30T16:26:32.607Z] GC before operation: completed in 12.997 ms, heap usage 87.353 MB -> 5.232 MB. [2025-12-30T16:26:33.330Z] Camera thread performed 122 scans. [2025-12-30T16:26:33.330Z] ====== philosophers (scala) [default], iteration 19 completed (772.379 ms) ====== [2025-12-30T16:26:33.330Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-30T16:26:33.330Z] GC before operation: completed in 12.021 ms, heap usage 101.359 MB -> 5.232 MB. [2025-12-30T16:26:34.042Z] Camera thread performed 122 scans. [2025-12-30T16:26:34.042Z] ====== philosophers (scala) [default], iteration 20 completed (796.376 ms) ====== [2025-12-30T16:26:34.042Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-30T16:26:34.042Z] GC before operation: completed in 11.869 ms, heap usage 38.357 MB -> 5.233 MB. [2025-12-30T16:26:34.753Z] Camera thread performed 122 scans. [2025-12-30T16:26:34.753Z] ====== philosophers (scala) [default], iteration 21 completed (768.941 ms) ====== [2025-12-30T16:26:34.753Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-30T16:26:34.753Z] GC before operation: completed in 11.546 ms, heap usage 28.348 MB -> 5.232 MB. [2025-12-30T16:26:35.542Z] Camera thread performed 122 scans. [2025-12-30T16:26:35.542Z] ====== philosophers (scala) [default], iteration 22 completed (772.075 ms) ====== [2025-12-30T16:26:35.542Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-30T16:26:35.542Z] GC before operation: completed in 11.752 ms, heap usage 27.350 MB -> 5.233 MB. [2025-12-30T16:26:36.247Z] Camera thread performed 122 scans. [2025-12-30T16:26:36.247Z] ====== philosophers (scala) [default], iteration 23 completed (781.237 ms) ====== [2025-12-30T16:26:36.247Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-30T16:26:36.247Z] GC before operation: completed in 11.689 ms, heap usage 61.348 MB -> 5.233 MB. [2025-12-30T16:26:36.959Z] Camera thread performed 122 scans. [2025-12-30T16:26:36.959Z] ====== philosophers (scala) [default], iteration 24 completed (764.332 ms) ====== [2025-12-30T16:26:36.959Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-30T16:26:36.959Z] GC before operation: completed in 11.424 ms, heap usage 40.859 MB -> 5.233 MB. [2025-12-30T16:26:38.103Z] Camera thread performed 122 scans. [2025-12-30T16:26:38.103Z] ====== philosophers (scala) [default], iteration 25 completed (765.796 ms) ====== [2025-12-30T16:26:38.103Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-30T16:26:38.103Z] GC before operation: completed in 11.572 ms, heap usage 23.355 MB -> 5.233 MB. [2025-12-30T16:26:38.812Z] Camera thread performed 122 scans. [2025-12-30T16:26:38.812Z] ====== philosophers (scala) [default], iteration 26 completed (812.885 ms) ====== [2025-12-30T16:26:38.812Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-30T16:26:38.812Z] GC before operation: completed in 11.094 ms, heap usage 22.357 MB -> 5.233 MB. [2025-12-30T16:26:39.519Z] Camera thread performed 122 scans. [2025-12-30T16:26:39.519Z] ====== philosophers (scala) [default], iteration 27 completed (779.218 ms) ====== [2025-12-30T16:26:39.519Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-30T16:26:39.519Z] GC before operation: completed in 10.697 ms, heap usage 40.347 MB -> 5.233 MB. [2025-12-30T16:26:40.227Z] Camera thread performed 122 scans. [2025-12-30T16:26:40.227Z] ====== philosophers (scala) [default], iteration 28 completed (782.045 ms) ====== [2025-12-30T16:26:40.227Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-30T16:26:40.227Z] GC before operation: completed in 12.240 ms, heap usage 24.347 MB -> 5.233 MB. [2025-12-30T16:26:40.937Z] Camera thread performed 122 scans. [2025-12-30T16:26:40.937Z] ====== philosophers (scala) [default], iteration 29 completed (728.756 ms) ====== [2025-12-30T16:26:41.265Z] ----------------------------------- [2025-12-30T16:26:41.265Z] renaissance-philosophers_0_PASSED [2025-12-30T16:26:41.265Z] ----------------------------------- [2025-12-30T16:26:41.265Z] [2025-12-30T16:26:41.265Z] TEST TEARDOWN: [2025-12-30T16:26:41.265Z] Nothing to be done for teardown. [2025-12-30T16:26:41.265Z] renaissance-philosophers_0 Finish Time: Tue Dec 30 16:26:41 2025 Epoch Time (ms): 1767112001118