[2025-12-27T14:01:40.389Z] Running test renaissance-philosophers_0 ... [2025-12-27T14:01:40.389Z] =============================================== [2025-12-27T14:01:40.389Z] renaissance-philosophers_0 Start Time: Sat Dec 27 14:01:40 2025 Epoch Time (ms): 1766844100176 [2025-12-27T14:01:40.389Z] variation: NoOptions [2025-12-27T14:01:40.389Z] JVM_OPTIONS: [2025-12-27T14:01:40.389Z] { \ [2025-12-27T14:01:40.389Z] echo ""; echo "TEST SETUP:"; \ [2025-12-27T14:01:40.389Z] echo "Nothing to be done for setup."; \ [2025-12-27T14:01:40.389Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668418055814/renaissance-philosophers_0"; \ [2025-12-27T14:01:40.389Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668418055814/renaissance-philosophers_0"; \ [2025-12-27T14:01:40.389Z] echo ""; echo "TESTING:"; \ [2025-12-27T14:01:40.389Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668418055814/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-27T14:01:40.389Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668418055814/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-27T14:01:40.389Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-27T14:01:40.389Z] echo "Nothing to be done for teardown."; \ [2025-12-27T14:01:40.389Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668418055814/TestTargetResult"; [2025-12-27T14:01:40.389Z] [2025-12-27T14:01:40.389Z] TEST SETUP: [2025-12-27T14:01:40.389Z] Nothing to be done for setup. [2025-12-27T14:01:40.389Z] [2025-12-27T14:01:40.389Z] TESTING: [2025-12-27T14:01:41.168Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-27T14:01:41.168Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17668418055814/renaissance-philosophers_0/launcher-140140-11694555861248076026/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-27T14:01:41.168Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-27T14:01:41.168Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-27T14:01:41.168Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-27T14:01:41.168Z] GC before operation: completed in 23.953 ms, heap usage 27.129 MB -> 4.804 MB. [2025-12-27T14:01:44.602Z] Camera thread performed 122 scans. [2025-12-27T14:01:44.602Z] ====== philosophers (scala) [default], iteration 0 completed (3330.600 ms) ====== [2025-12-27T14:01:44.602Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-27T14:01:44.602Z] GC before operation: completed in 19.668 ms, heap usage 32.286 MB -> 5.137 MB. [2025-12-27T14:01:46.198Z] Camera thread performed 122 scans. [2025-12-27T14:01:46.198Z] ====== philosophers (scala) [default], iteration 1 completed (1355.433 ms) ====== [2025-12-27T14:01:46.198Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-27T14:01:46.198Z] GC before operation: completed in 16.645 ms, heap usage 68.229 MB -> 5.138 MB. [2025-12-27T14:01:47.796Z] Camera thread performed 122 scans. [2025-12-27T14:01:47.796Z] ====== philosophers (scala) [default], iteration 2 completed (1368.963 ms) ====== [2025-12-27T14:01:47.796Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-27T14:01:47.796Z] GC before operation: completed in 14.537 ms, heap usage 73.344 MB -> 5.138 MB. [2025-12-27T14:01:48.565Z] Camera thread performed 122 scans. [2025-12-27T14:01:48.565Z] ====== philosophers (scala) [default], iteration 3 completed (1200.494 ms) ====== [2025-12-27T14:01:48.565Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-27T14:01:48.565Z] GC before operation: completed in 22.606 ms, heap usage 9.784 MB -> 5.139 MB. [2025-12-27T14:01:50.159Z] Camera thread performed 122 scans. [2025-12-27T14:01:50.159Z] ====== philosophers (scala) [default], iteration 4 completed (1467.301 ms) ====== [2025-12-27T14:01:50.159Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-27T14:01:50.159Z] GC before operation: completed in 36.775 ms, heap usage 72.371 MB -> 5.139 MB. [2025-12-27T14:01:51.754Z] Camera thread performed 122 scans. [2025-12-27T14:01:51.754Z] ====== philosophers (scala) [default], iteration 5 completed (1309.876 ms) ====== [2025-12-27T14:01:51.754Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-27T14:01:51.754Z] GC before operation: completed in 29.900 ms, heap usage 59.682 MB -> 5.140 MB. [2025-12-27T14:01:52.540Z] Camera thread performed 122 scans. [2025-12-27T14:01:52.540Z] ====== philosophers (scala) [default], iteration 6 completed (1252.212 ms) ====== [2025-12-27T14:01:52.540Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-27T14:01:53.367Z] GC before operation: completed in 31.720 ms, heap usage 85.045 MB -> 5.140 MB. [2025-12-27T14:01:54.144Z] Camera thread performed 122 scans. [2025-12-27T14:01:54.145Z] ====== philosophers (scala) [default], iteration 7 completed (1359.715 ms) ====== [2025-12-27T14:01:54.145Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-27T14:01:54.145Z] GC before operation: completed in 37.622 ms, heap usage 73.957 MB -> 5.140 MB. [2025-12-27T14:01:55.767Z] Camera thread performed 122 scans. [2025-12-27T14:01:55.768Z] ====== philosophers (scala) [default], iteration 8 completed (1273.653 ms) ====== [2025-12-27T14:01:55.768Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-27T14:01:55.768Z] GC before operation: completed in 44.993 ms, heap usage 15.048 MB -> 5.140 MB. [2025-12-27T14:01:57.375Z] Camera thread performed 122 scans. [2025-12-27T14:01:57.375Z] ====== philosophers (scala) [default], iteration 9 completed (1408.452 ms) ====== [2025-12-27T14:01:57.375Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-27T14:01:57.375Z] GC before operation: completed in 37.029 ms, heap usage 35.723 MB -> 5.140 MB. [2025-12-27T14:01:58.151Z] Camera thread performed 122 scans. [2025-12-27T14:01:58.151Z] ====== philosophers (scala) [default], iteration 10 completed (1322.884 ms) ====== [2025-12-27T14:01:58.151Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-27T14:01:58.151Z] GC before operation: completed in 23.751 ms, heap usage 74.228 MB -> 5.140 MB. [2025-12-27T14:01:59.746Z] Camera thread performed 122 scans. [2025-12-27T14:01:59.747Z] ====== philosophers (scala) [default], iteration 11 completed (1309.791 ms) ====== [2025-12-27T14:01:59.747Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-27T14:01:59.747Z] GC before operation: completed in 24.229 ms, heap usage 21.199 MB -> 5.140 MB. [2025-12-27T14:02:01.380Z] Camera thread performed 122 scans. [2025-12-27T14:02:01.380Z] ====== philosophers (scala) [default], iteration 12 completed (1206.857 ms) ====== [2025-12-27T14:02:01.380Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-27T14:02:01.380Z] GC before operation: completed in 19.140 ms, heap usage 22.330 MB -> 5.141 MB. [2025-12-27T14:02:02.167Z] Camera thread performed 122 scans. [2025-12-27T14:02:02.167Z] ====== philosophers (scala) [default], iteration 13 completed (1366.938 ms) ====== [2025-12-27T14:02:02.167Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-27T14:02:02.167Z] GC before operation: completed in 24.388 ms, heap usage 59.129 MB -> 5.141 MB. [2025-12-27T14:02:03.786Z] Camera thread performed 122 scans. [2025-12-27T14:02:03.786Z] ====== philosophers (scala) [default], iteration 14 completed (1319.586 ms) ====== [2025-12-27T14:02:03.786Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-27T14:02:03.786Z] GC before operation: completed in 19.312 ms, heap usage 36.285 MB -> 5.176 MB. [2025-12-27T14:02:05.399Z] Camera thread performed 122 scans. [2025-12-27T14:02:05.399Z] ====== philosophers (scala) [default], iteration 15 completed (1290.330 ms) ====== [2025-12-27T14:02:05.399Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-27T14:02:05.399Z] GC before operation: completed in 34.871 ms, heap usage 27.747 MB -> 5.142 MB. [2025-12-27T14:02:06.185Z] Camera thread performed 122 scans. [2025-12-27T14:02:06.185Z] ====== philosophers (scala) [default], iteration 16 completed (1116.767 ms) ====== [2025-12-27T14:02:06.185Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-27T14:02:06.185Z] GC before operation: completed in 27.552 ms, heap usage 38.115 MB -> 5.142 MB. [2025-12-27T14:02:07.788Z] Camera thread performed 122 scans. [2025-12-27T14:02:07.788Z] ====== philosophers (scala) [default], iteration 17 completed (1242.999 ms) ====== [2025-12-27T14:02:07.788Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-27T14:02:07.788Z] GC before operation: completed in 35.361 ms, heap usage 77.242 MB -> 5.142 MB. [2025-12-27T14:02:08.562Z] Camera thread performed 122 scans. [2025-12-27T14:02:08.562Z] ====== philosophers (scala) [default], iteration 18 completed (1087.285 ms) ====== [2025-12-27T14:02:08.562Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-27T14:02:08.562Z] GC before operation: completed in 21.905 ms, heap usage 90.338 MB -> 5.142 MB. [2025-12-27T14:02:09.347Z] Camera thread performed 122 scans. [2025-12-27T14:02:09.347Z] ====== philosophers (scala) [default], iteration 19 completed (1090.598 ms) ====== [2025-12-27T14:02:09.347Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-27T14:02:10.126Z] GC before operation: completed in 31.102 ms, heap usage 9.264 MB -> 5.142 MB. [2025-12-27T14:02:10.899Z] Camera thread performed 122 scans. [2025-12-27T14:02:10.899Z] ====== philosophers (scala) [default], iteration 20 completed (1300.227 ms) ====== [2025-12-27T14:02:10.899Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-27T14:02:10.899Z] GC before operation: completed in 29.955 ms, heap usage 57.312 MB -> 5.142 MB. [2025-12-27T14:02:13.043Z] Camera thread performed 122 scans. [2025-12-27T14:02:13.043Z] ====== philosophers (scala) [default], iteration 21 completed (1292.443 ms) ====== [2025-12-27T14:02:13.043Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-27T14:02:13.043Z] GC before operation: completed in 34.233 ms, heap usage 77.619 MB -> 5.142 MB. [2025-12-27T14:02:14.395Z] Camera thread performed 122 scans. [2025-12-27T14:02:14.395Z] ====== philosophers (scala) [default], iteration 22 completed (1309.047 ms) ====== [2025-12-27T14:02:14.395Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-27T14:02:14.395Z] GC before operation: completed in 30.420 ms, heap usage 52.107 MB -> 5.142 MB. [2025-12-27T14:02:15.161Z] Camera thread performed 122 scans. [2025-12-27T14:02:15.161Z] ====== philosophers (scala) [default], iteration 23 completed (1454.125 ms) ====== [2025-12-27T14:02:15.161Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-27T14:02:15.161Z] GC before operation: completed in 35.828 ms, heap usage 29.995 MB -> 5.142 MB. [2025-12-27T14:02:16.751Z] Camera thread performed 122 scans. [2025-12-27T14:02:16.751Z] ====== philosophers (scala) [default], iteration 24 completed (1297.412 ms) ====== [2025-12-27T14:02:16.751Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-27T14:02:16.751Z] GC before operation: completed in 25.540 ms, heap usage 41.690 MB -> 5.142 MB. [2025-12-27T14:02:18.370Z] Camera thread performed 122 scans. [2025-12-27T14:02:18.370Z] ====== philosophers (scala) [default], iteration 25 completed (1356.444 ms) ====== [2025-12-27T14:02:18.370Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-27T14:02:18.370Z] GC before operation: completed in 24.201 ms, heap usage 28.259 MB -> 5.142 MB. [2025-12-27T14:02:19.457Z] Camera thread performed 122 scans. [2025-12-27T14:02:19.457Z] ====== philosophers (scala) [default], iteration 26 completed (1323.413 ms) ====== [2025-12-27T14:02:19.457Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-27T14:02:19.457Z] GC before operation: completed in 36.564 ms, heap usage 66.123 MB -> 5.142 MB. [2025-12-27T14:02:20.227Z] Camera thread performed 122 scans. [2025-12-27T14:02:20.227Z] ====== philosophers (scala) [default], iteration 27 completed (1128.765 ms) ====== [2025-12-27T14:02:20.227Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-27T14:02:20.227Z] GC before operation: completed in 36.406 ms, heap usage 59.730 MB -> 5.143 MB. [2025-12-27T14:02:21.847Z] Camera thread performed 122 scans. [2025-12-27T14:02:21.848Z] ====== philosophers (scala) [default], iteration 28 completed (1206.104 ms) ====== [2025-12-27T14:02:21.848Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-27T14:02:21.848Z] GC before operation: completed in 25.367 ms, heap usage 77.085 MB -> 5.142 MB. [2025-12-27T14:02:22.627Z] Camera thread performed 122 scans. [2025-12-27T14:02:22.627Z] ====== philosophers (scala) [default], iteration 29 completed (1288.718 ms) ====== [2025-12-27T14:02:24.307Z] ----------------------------------- [2025-12-27T14:02:24.307Z] renaissance-philosophers_0_PASSED [2025-12-27T14:02:24.307Z] ----------------------------------- [2025-12-27T14:02:24.307Z] [2025-12-27T14:02:24.307Z] TEST TEARDOWN: [2025-12-27T14:02:24.307Z] Nothing to be done for teardown. [2025-12-27T14:02:24.307Z] renaissance-philosophers_0 Finish Time: Sat Dec 27 14:02:23 2025 Epoch Time (ms): 1766844143445