[2025-12-20T14:12:23.665Z] Running test renaissance-philosophers_0 ... [2025-12-20T14:12:23.665Z] =============================================== [2025-12-20T14:12:23.665Z] renaissance-philosophers_0 Start Time: Sat Dec 20 14:12:23 2025 Epoch Time (ms): 1766239943484 [2025-12-20T14:12:23.665Z] variation: NoOptions [2025-12-20T14:12:23.665Z] JVM_OPTIONS: [2025-12-20T14:12:23.665Z] { \ [2025-12-20T14:12:23.665Z] echo ""; echo "TEST SETUP:"; \ [2025-12-20T14:12:23.665Z] echo "Nothing to be done for setup."; \ [2025-12-20T14:12:23.665Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17662373074927/renaissance-philosophers_0"; \ [2025-12-20T14:12:23.665Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17662373074927/renaissance-philosophers_0"; \ [2025-12-20T14:12:23.665Z] echo ""; echo "TESTING:"; \ [2025-12-20T14:12:23.665Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17662373074927/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-20T14:12:23.665Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17662373074927/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-20T14:12:23.665Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-20T14:12:23.665Z] echo "Nothing to be done for teardown."; \ [2025-12-20T14:12:23.665Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17662373074927/TestTargetResult"; [2025-12-20T14:12:23.665Z] [2025-12-20T14:12:23.665Z] TEST SETUP: [2025-12-20T14:12:23.665Z] Nothing to be done for setup. [2025-12-20T14:12:23.665Z] [2025-12-20T14:12:23.665Z] TESTING: [2025-12-20T14:12:24.625Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-20T14:12:24.625Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_17662373074927/renaissance-philosophers_0/launcher-141223-18369168073327086266/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-20T14:12:24.625Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-20T14:12:24.625Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-20T14:12:25.604Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-20T14:12:25.604Z] GC before operation: completed in 38.286 ms, heap usage 27.129 MB -> 4.804 MB. [2025-12-20T14:12:27.587Z] Camera thread performed 122 scans. [2025-12-20T14:12:27.587Z] ====== philosophers (scala) [default], iteration 0 completed (2612.641 ms) ====== [2025-12-20T14:12:27.587Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-20T14:12:28.557Z] GC before operation: completed in 40.127 ms, heap usage 12.834 MB -> 5.138 MB. [2025-12-20T14:12:29.522Z] Camera thread performed 122 scans. [2025-12-20T14:12:29.522Z] ====== philosophers (scala) [default], iteration 1 completed (1304.990 ms) ====== [2025-12-20T14:12:29.522Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-20T14:12:29.522Z] GC before operation: completed in 29.567 ms, heap usage 72.321 MB -> 5.138 MB. [2025-12-20T14:12:30.493Z] Camera thread performed 122 scans. [2025-12-20T14:12:30.493Z] ====== philosophers (scala) [default], iteration 2 completed (1046.701 ms) ====== [2025-12-20T14:12:30.493Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-20T14:12:30.493Z] GC before operation: completed in 31.117 ms, heap usage 11.021 MB -> 5.139 MB. [2025-12-20T14:12:31.454Z] Camera thread performed 122 scans. [2025-12-20T14:12:31.454Z] ====== philosophers (scala) [default], iteration 3 completed (933.116 ms) ====== [2025-12-20T14:12:31.454Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-20T14:12:31.454Z] GC before operation: completed in 23.269 ms, heap usage 40.917 MB -> 5.140 MB. [2025-12-20T14:12:32.875Z] Camera thread performed 122 scans. [2025-12-20T14:12:32.875Z] ====== philosophers (scala) [default], iteration 4 completed (998.486 ms) ====== [2025-12-20T14:12:32.875Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-20T14:12:32.875Z] GC before operation: completed in 23.409 ms, heap usage 68.748 MB -> 5.140 MB. [2025-12-20T14:12:33.850Z] Camera thread performed 122 scans. [2025-12-20T14:12:33.850Z] ====== philosophers (scala) [default], iteration 5 completed (1015.747 ms) ====== [2025-12-20T14:12:33.850Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-20T14:12:33.850Z] GC before operation: completed in 21.389 ms, heap usage 41.079 MB -> 5.140 MB. [2025-12-20T14:12:34.832Z] Camera thread performed 122 scans. [2025-12-20T14:12:34.832Z] ====== philosophers (scala) [default], iteration 6 completed (1046.495 ms) ====== [2025-12-20T14:12:34.832Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-20T14:12:34.832Z] GC before operation: completed in 28.618 ms, heap usage 50.040 MB -> 5.141 MB. [2025-12-20T14:12:35.805Z] Camera thread performed 122 scans. [2025-12-20T14:12:35.805Z] ====== philosophers (scala) [default], iteration 7 completed (1067.377 ms) ====== [2025-12-20T14:12:35.805Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-20T14:12:35.805Z] GC before operation: completed in 24.430 ms, heap usage 36.856 MB -> 5.141 MB. [2025-12-20T14:12:36.792Z] Camera thread performed 122 scans. [2025-12-20T14:12:36.793Z] ====== philosophers (scala) [default], iteration 8 completed (1092.430 ms) ====== [2025-12-20T14:12:36.793Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-20T14:12:36.793Z] GC before operation: completed in 24.781 ms, heap usage 14.319 MB -> 5.141 MB. [2025-12-20T14:12:37.754Z] Camera thread performed 122 scans. [2025-12-20T14:12:37.754Z] ====== philosophers (scala) [default], iteration 9 completed (963.687 ms) ====== [2025-12-20T14:12:37.754Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-20T14:12:37.754Z] GC before operation: completed in 30.425 ms, heap usage 57.267 MB -> 5.142 MB. [2025-12-20T14:12:38.717Z] Camera thread performed 122 scans. [2025-12-20T14:12:38.717Z] ====== philosophers (scala) [default], iteration 10 completed (1036.559 ms) ====== [2025-12-20T14:12:38.717Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-20T14:12:38.717Z] GC before operation: completed in 22.991 ms, heap usage 51.002 MB -> 5.142 MB. [2025-12-20T14:12:39.692Z] Camera thread performed 122 scans. [2025-12-20T14:12:39.692Z] ====== philosophers (scala) [default], iteration 11 completed (1077.945 ms) ====== [2025-12-20T14:12:39.692Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-20T14:12:39.692Z] GC before operation: completed in 22.293 ms, heap usage 35.262 MB -> 5.142 MB. [2025-12-20T14:12:40.662Z] Camera thread performed 122 scans. [2025-12-20T14:12:40.662Z] ====== philosophers (scala) [default], iteration 12 completed (1109.421 ms) ====== [2025-12-20T14:12:40.662Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-20T14:12:40.662Z] GC before operation: completed in 18.619 ms, heap usage 11.146 MB -> 5.143 MB. [2025-12-20T14:12:42.629Z] Camera thread performed 122 scans. [2025-12-20T14:12:42.629Z] ====== philosophers (scala) [default], iteration 13 completed (1073.498 ms) ====== [2025-12-20T14:12:42.629Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-20T14:12:42.629Z] GC before operation: completed in 27.956 ms, heap usage 25.196 MB -> 5.142 MB. [2025-12-20T14:12:43.593Z] Camera thread performed 122 scans. [2025-12-20T14:12:43.593Z] ====== philosophers (scala) [default], iteration 14 completed (997.284 ms) ====== [2025-12-20T14:12:43.593Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-20T14:12:43.593Z] GC before operation: completed in 27.868 ms, heap usage 74.638 MB -> 5.143 MB. [2025-12-20T14:12:44.568Z] Camera thread performed 122 scans. [2025-12-20T14:12:44.568Z] ====== philosophers (scala) [default], iteration 15 completed (1090.560 ms) ====== [2025-12-20T14:12:44.568Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-20T14:12:44.568Z] GC before operation: completed in 23.231 ms, heap usage 77.911 MB -> 5.143 MB. [2025-12-20T14:12:45.530Z] Camera thread performed 122 scans. [2025-12-20T14:12:45.530Z] ====== philosophers (scala) [default], iteration 16 completed (996.988 ms) ====== [2025-12-20T14:12:45.530Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-20T14:12:45.530Z] GC before operation: completed in 26.627 ms, heap usage 39.638 MB -> 5.143 MB. [2025-12-20T14:12:46.491Z] Camera thread performed 122 scans. [2025-12-20T14:12:46.491Z] ====== philosophers (scala) [default], iteration 17 completed (1099.211 ms) ====== [2025-12-20T14:12:46.491Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-20T14:12:46.491Z] GC before operation: completed in 27.736 ms, heap usage 8.344 MB -> 5.143 MB. [2025-12-20T14:12:47.447Z] Camera thread performed 122 scans. [2025-12-20T14:12:47.447Z] ====== philosophers (scala) [default], iteration 18 completed (1107.585 ms) ====== [2025-12-20T14:12:47.448Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-20T14:12:47.448Z] GC before operation: completed in 28.647 ms, heap usage 11.244 MB -> 5.143 MB. [2025-12-20T14:12:48.403Z] Camera thread performed 122 scans. [2025-12-20T14:12:48.403Z] ====== philosophers (scala) [default], iteration 19 completed (1068.930 ms) ====== [2025-12-20T14:12:48.403Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-20T14:12:48.403Z] GC before operation: completed in 29.173 ms, heap usage 27.663 MB -> 5.143 MB. [2025-12-20T14:12:49.365Z] Camera thread performed 122 scans. [2025-12-20T14:12:49.365Z] ====== philosophers (scala) [default], iteration 20 completed (997.560 ms) ====== [2025-12-20T14:12:49.365Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-20T14:12:49.365Z] GC before operation: completed in 21.114 ms, heap usage 51.756 MB -> 5.143 MB. [2025-12-20T14:12:51.339Z] Camera thread performed 122 scans. [2025-12-20T14:12:51.339Z] ====== philosophers (scala) [default], iteration 21 completed (1087.721 ms) ====== [2025-12-20T14:12:51.339Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-20T14:12:51.339Z] GC before operation: completed in 28.336 ms, heap usage 52.281 MB -> 5.143 MB. [2025-12-20T14:12:52.307Z] Camera thread performed 122 scans. [2025-12-20T14:12:52.307Z] ====== philosophers (scala) [default], iteration 22 completed (1045.118 ms) ====== [2025-12-20T14:12:52.307Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-20T14:12:52.307Z] GC before operation: completed in 22.050 ms, heap usage 46.215 MB -> 5.143 MB. [2025-12-20T14:12:53.264Z] Camera thread performed 122 scans. [2025-12-20T14:12:53.264Z] ====== philosophers (scala) [default], iteration 23 completed (1129.704 ms) ====== [2025-12-20T14:12:53.264Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-20T14:12:53.264Z] GC before operation: completed in 20.268 ms, heap usage 50.118 MB -> 5.143 MB. [2025-12-20T14:12:54.229Z] Camera thread performed 122 scans. [2025-12-20T14:12:54.229Z] ====== philosophers (scala) [default], iteration 24 completed (1076.547 ms) ====== [2025-12-20T14:12:54.229Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-20T14:12:54.229Z] GC before operation: completed in 31.476 ms, heap usage 75.126 MB -> 5.144 MB. [2025-12-20T14:12:55.196Z] Camera thread performed 122 scans. [2025-12-20T14:12:55.196Z] ====== philosophers (scala) [default], iteration 25 completed (1102.601 ms) ====== [2025-12-20T14:12:55.196Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-20T14:12:55.196Z] GC before operation: completed in 22.785 ms, heap usage 26.094 MB -> 5.144 MB. [2025-12-20T14:12:56.173Z] Camera thread performed 122 scans. [2025-12-20T14:12:56.173Z] ====== philosophers (scala) [default], iteration 26 completed (978.151 ms) ====== [2025-12-20T14:12:56.173Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-20T14:12:56.173Z] GC before operation: completed in 26.179 ms, heap usage 28.732 MB -> 5.144 MB. [2025-12-20T14:12:57.137Z] Camera thread performed 122 scans. [2025-12-20T14:12:57.137Z] ====== philosophers (scala) [default], iteration 27 completed (1096.231 ms) ====== [2025-12-20T14:12:57.137Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-20T14:12:57.137Z] GC before operation: completed in 29.494 ms, heap usage 54.662 MB -> 5.144 MB. [2025-12-20T14:12:58.106Z] Camera thread performed 122 scans. [2025-12-20T14:12:58.106Z] ====== philosophers (scala) [default], iteration 28 completed (1058.593 ms) ====== [2025-12-20T14:12:58.106Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-20T14:12:58.106Z] GC before operation: completed in 27.960 ms, heap usage 34.307 MB -> 5.144 MB. [2025-12-20T14:13:00.120Z] Camera thread performed 122 scans. [2025-12-20T14:13:00.120Z] ====== philosophers (scala) [default], iteration 29 completed (1063.387 ms) ====== [2025-12-20T14:13:00.120Z] ----------------------------------- [2025-12-20T14:13:00.120Z] renaissance-philosophers_0_PASSED [2025-12-20T14:13:00.120Z] ----------------------------------- [2025-12-20T14:13:00.120Z] [2025-12-20T14:13:00.120Z] TEST TEARDOWN: [2025-12-20T14:13:00.120Z] Nothing to be done for teardown. [2025-12-20T14:13:00.120Z] renaissance-philosophers_0 Finish Time: Sat Dec 20 14:12:59 2025 Epoch Time (ms): 1766239979440