[2025-12-30T16:47:16.502Z] Running test renaissance-philosophers_0 ... [2025-12-30T16:47:16.502Z] =============================================== [2025-12-30T16:47:16.502Z] renaissance-philosophers_0 Start Time: Tue Dec 30 16:47:16 2025 Epoch Time (ms): 1767113236297 [2025-12-30T16:47:16.502Z] variation: NoOptions [2025-12-30T16:47:16.502Z] JVM_OPTIONS: [2025-12-30T16:47:16.502Z] { \ [2025-12-30T16:47:16.502Z] echo ""; echo "TEST SETUP:"; \ [2025-12-30T16:47:16.502Z] echo "Nothing to be done for setup."; \ [2025-12-30T16:47:16.502Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17671124689054/renaissance-philosophers_0"; \ [2025-12-30T16:47:16.502Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17671124689054/renaissance-philosophers_0"; \ [2025-12-30T16:47:16.502Z] echo ""; echo "TESTING:"; \ [2025-12-30T16:47:16.502Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17671124689054/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-30T16:47:16.502Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17671124689054/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-30T16:47:16.502Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-30T16:47:16.502Z] echo "Nothing to be done for teardown."; \ [2025-12-30T16:47:16.502Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17671124689054/TestTargetResult"; [2025-12-30T16:47:16.502Z] [2025-12-30T16:47:16.502Z] TEST SETUP: [2025-12-30T16:47:16.502Z] Nothing to be done for setup. [2025-12-30T16:47:16.502Z] [2025-12-30T16:47:16.502Z] TESTING: [2025-12-30T16:47:17.459Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-30T16:47:17.459Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17671124689054/renaissance-philosophers_0/launcher-164716-14059300929572728212/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-30T16:47:17.459Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-30T16:47:17.459Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-30T16:47:18.425Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-30T16:47:18.425Z] GC before operation: completed in 23.558 ms, heap usage 6.946 MB -> 5.052 MB. [2025-12-30T16:47:22.630Z] Camera thread performed 122 scans. [2025-12-30T16:47:22.630Z] ====== philosophers (scala) [default], iteration 0 completed (4323.484 ms) ====== [2025-12-30T16:47:22.630Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-30T16:47:22.630Z] GC before operation: completed in 24.965 ms, heap usage 20.636 MB -> 5.393 MB. [2025-12-30T16:47:26.391Z] Camera thread performed 122 scans. [2025-12-30T16:47:26.391Z] ====== philosophers (scala) [default], iteration 1 completed (3045.253 ms) ====== [2025-12-30T16:47:26.391Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-30T16:47:26.391Z] GC before operation: completed in 18.690 ms, heap usage 31.555 MB -> 5.394 MB. [2025-12-30T16:47:28.367Z] Camera thread performed 122 scans. [2025-12-30T16:47:28.367Z] ====== philosophers (scala) [default], iteration 2 completed (2556.989 ms) ====== [2025-12-30T16:47:28.367Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-30T16:47:28.367Z] GC before operation: completed in 15.970 ms, heap usage 145.087 MB -> 5.395 MB. [2025-12-30T16:47:31.414Z] Camera thread performed 122 scans. [2025-12-30T16:47:31.414Z] ====== philosophers (scala) [default], iteration 3 completed (2531.667 ms) ====== [2025-12-30T16:47:31.414Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-30T16:47:31.414Z] GC before operation: completed in 17.354 ms, heap usage 174.576 MB -> 5.396 MB. [2025-12-30T16:47:33.495Z] Camera thread performed 122 scans. [2025-12-30T16:47:33.495Z] ====== philosophers (scala) [default], iteration 4 completed (2637.301 ms) ====== [2025-12-30T16:47:33.495Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-30T16:47:33.495Z] GC before operation: completed in 22.467 ms, heap usage 87.795 MB -> 5.396 MB. [2025-12-30T16:47:36.544Z] Camera thread performed 122 scans. [2025-12-30T16:47:36.544Z] ====== philosophers (scala) [default], iteration 5 completed (2630.872 ms) ====== [2025-12-30T16:47:36.544Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-30T16:47:36.544Z] GC before operation: completed in 18.778 ms, heap usage 108.737 MB -> 5.397 MB. [2025-12-30T16:47:39.593Z] Camera thread performed 122 scans. [2025-12-30T16:47:39.593Z] ====== philosophers (scala) [default], iteration 6 completed (2663.847 ms) ====== [2025-12-30T16:47:39.593Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-30T16:47:39.593Z] GC before operation: completed in 16.397 ms, heap usage 66.159 MB -> 5.397 MB. [2025-12-30T16:47:41.568Z] Camera thread performed 122 scans. [2025-12-30T16:47:41.568Z] ====== philosophers (scala) [default], iteration 7 completed (2560.542 ms) ====== [2025-12-30T16:47:41.568Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-30T16:47:41.568Z] GC before operation: completed in 15.381 ms, heap usage 137.620 MB -> 5.397 MB. [2025-12-30T16:47:44.619Z] Camera thread performed 122 scans. [2025-12-30T16:47:44.619Z] ====== philosophers (scala) [default], iteration 8 completed (2598.693 ms) ====== [2025-12-30T16:47:44.619Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-30T16:47:44.619Z] GC before operation: completed in 16.273 ms, heap usage 100.618 MB -> 5.398 MB. [2025-12-30T16:47:46.591Z] Camera thread performed 122 scans. [2025-12-30T16:47:46.591Z] ====== philosophers (scala) [default], iteration 9 completed (2577.634 ms) ====== [2025-12-30T16:47:46.591Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-30T16:47:46.591Z] GC before operation: completed in 14.340 ms, heap usage 17.107 MB -> 5.398 MB. [2025-12-30T16:47:49.634Z] Camera thread performed 122 scans. [2025-12-30T16:47:49.634Z] ====== philosophers (scala) [default], iteration 10 completed (2592.817 ms) ====== [2025-12-30T16:47:49.634Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-30T16:47:49.634Z] GC before operation: completed in 18.266 ms, heap usage 110.103 MB -> 5.398 MB. [2025-12-30T16:47:51.612Z] Camera thread performed 122 scans. [2025-12-30T16:47:51.612Z] ====== philosophers (scala) [default], iteration 11 completed (2554.283 ms) ====== [2025-12-30T16:47:51.612Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-30T16:47:51.612Z] GC before operation: completed in 21.760 ms, heap usage 182.594 MB -> 5.399 MB. [2025-12-30T16:47:54.664Z] Camera thread performed 122 scans. [2025-12-30T16:47:54.664Z] ====== philosophers (scala) [default], iteration 12 completed (2636.397 ms) ====== [2025-12-30T16:47:54.664Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-30T16:47:54.664Z] GC before operation: completed in 14.905 ms, heap usage 98.605 MB -> 5.399 MB. [2025-12-30T16:47:57.728Z] Camera thread performed 122 scans. [2025-12-30T16:47:57.728Z] ====== philosophers (scala) [default], iteration 13 completed (2591.541 ms) ====== [2025-12-30T16:47:57.728Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-30T16:47:57.728Z] GC before operation: completed in 14.624 ms, heap usage 12.543 MB -> 5.399 MB. [2025-12-30T16:47:59.704Z] Camera thread performed 122 scans. [2025-12-30T16:47:59.704Z] ====== philosophers (scala) [default], iteration 14 completed (2534.392 ms) ====== [2025-12-30T16:47:59.704Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-30T16:47:59.704Z] GC before operation: completed in 15.416 ms, heap usage 58.604 MB -> 5.399 MB. [2025-12-30T16:48:02.750Z] Camera thread performed 122 scans. [2025-12-30T16:48:02.750Z] ====== philosophers (scala) [default], iteration 15 completed (2619.574 ms) ====== [2025-12-30T16:48:02.750Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-30T16:48:02.750Z] GC before operation: completed in 17.489 ms, heap usage 50.108 MB -> 5.399 MB. [2025-12-30T16:48:04.723Z] Camera thread performed 122 scans. [2025-12-30T16:48:04.723Z] ====== philosophers (scala) [default], iteration 16 completed (2510.561 ms) ====== [2025-12-30T16:48:04.723Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-30T16:48:04.723Z] GC before operation: completed in 14.697 ms, heap usage 11.040 MB -> 5.399 MB. [2025-12-30T16:48:07.773Z] Camera thread performed 122 scans. [2025-12-30T16:48:07.773Z] ====== philosophers (scala) [default], iteration 17 completed (2492.167 ms) ====== [2025-12-30T16:48:07.773Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-30T16:48:07.773Z] GC before operation: completed in 17.139 ms, heap usage 75.602 MB -> 5.399 MB. [2025-12-30T16:48:09.745Z] Camera thread performed 122 scans. [2025-12-30T16:48:09.745Z] ====== philosophers (scala) [default], iteration 18 completed (2544.628 ms) ====== [2025-12-30T16:48:09.745Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-30T16:48:09.745Z] GC before operation: completed in 15.244 ms, heap usage 11.015 MB -> 5.399 MB. [2025-12-30T16:48:12.790Z] Camera thread performed 122 scans. [2025-12-30T16:48:12.790Z] ====== philosophers (scala) [default], iteration 19 completed (2527.451 ms) ====== [2025-12-30T16:48:12.790Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-30T16:48:12.790Z] GC before operation: completed in 16.241 ms, heap usage 48.097 MB -> 5.399 MB. [2025-12-30T16:48:15.842Z] Camera thread performed 122 scans. [2025-12-30T16:48:15.842Z] ====== philosophers (scala) [default], iteration 20 completed (2673.879 ms) ====== [2025-12-30T16:48:15.842Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-30T16:48:15.842Z] GC before operation: completed in 15.641 ms, heap usage 146.592 MB -> 5.399 MB. [2025-12-30T16:48:17.818Z] Camera thread performed 122 scans. [2025-12-30T16:48:17.818Z] ====== philosophers (scala) [default], iteration 21 completed (2620.966 ms) ====== [2025-12-30T16:48:17.818Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-30T16:48:17.818Z] GC before operation: completed in 17.422 ms, heap usage 32.109 MB -> 5.399 MB. [2025-12-30T16:48:20.869Z] Camera thread performed 122 scans. [2025-12-30T16:48:20.869Z] ====== philosophers (scala) [default], iteration 22 completed (2573.175 ms) ====== [2025-12-30T16:48:20.869Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-30T16:48:20.869Z] GC before operation: completed in 15.089 ms, heap usage 66.100 MB -> 5.400 MB. [2025-12-30T16:48:22.844Z] Camera thread performed 122 scans. [2025-12-30T16:48:22.844Z] ====== philosophers (scala) [default], iteration 23 completed (2640.837 ms) ====== [2025-12-30T16:48:22.844Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-30T16:48:22.844Z] GC before operation: completed in 18.880 ms, heap usage 144.104 MB -> 5.400 MB. [2025-12-30T16:48:25.890Z] Camera thread performed 122 scans. [2025-12-30T16:48:25.890Z] ====== philosophers (scala) [default], iteration 24 completed (2533.851 ms) ====== [2025-12-30T16:48:25.890Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-30T16:48:25.890Z] GC before operation: completed in 16.069 ms, heap usage 138.103 MB -> 5.400 MB. [2025-12-30T16:48:27.896Z] Camera thread performed 122 scans. [2025-12-30T16:48:27.896Z] ====== philosophers (scala) [default], iteration 25 completed (2560.877 ms) ====== [2025-12-30T16:48:27.896Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-30T16:48:27.896Z] GC before operation: completed in 18.969 ms, heap usage 6.101 MB -> 5.400 MB. [2025-12-30T16:48:30.950Z] Camera thread performed 122 scans. [2025-12-30T16:48:30.950Z] ====== philosophers (scala) [default], iteration 26 completed (2657.455 ms) ====== [2025-12-30T16:48:30.950Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-30T16:48:30.950Z] GC before operation: completed in 16.919 ms, heap usage 129.099 MB -> 5.400 MB. [2025-12-30T16:48:33.666Z] Camera thread performed 122 scans. [2025-12-30T16:48:33.666Z] ====== philosophers (scala) [default], iteration 27 completed (2676.775 ms) ====== [2025-12-30T16:48:33.666Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-30T16:48:33.666Z] GC before operation: completed in 17.524 ms, heap usage 122.098 MB -> 5.400 MB. [2025-12-30T16:48:36.715Z] Camera thread performed 122 scans. [2025-12-30T16:48:36.715Z] ====== philosophers (scala) [default], iteration 28 completed (2594.888 ms) ====== [2025-12-30T16:48:36.715Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-30T16:48:36.715Z] GC before operation: completed in 16.221 ms, heap usage 103.603 MB -> 5.400 MB. [2025-12-30T16:48:38.690Z] Camera thread performed 122 scans. [2025-12-30T16:48:38.690Z] ====== philosophers (scala) [default], iteration 29 completed (2604.943 ms) ====== [2025-12-30T16:48:39.653Z] ----------------------------------- [2025-12-30T16:48:39.653Z] renaissance-philosophers_0_PASSED [2025-12-30T16:48:39.653Z] ----------------------------------- [2025-12-30T16:48:39.653Z] [2025-12-30T16:48:39.653Z] TEST TEARDOWN: [2025-12-30T16:48:39.653Z] Nothing to be done for teardown. [2025-12-30T16:48:39.653Z] renaissance-philosophers_0 Finish Time: Tue Dec 30 16:48:38 2025 Epoch Time (ms): 1767113318824