[2025-12-24T22:47:50.637Z] Running test renaissance-philosophers_0 ... [2025-12-24T22:47:50.637Z] =============================================== [2025-12-24T22:47:50.637Z] renaissance-philosophers_0 Start Time: Wed Dec 24 22:47:50 2025 Epoch Time (ms): 1766616470418 [2025-12-24T22:47:50.637Z] variation: NoOptions [2025-12-24T22:47:50.637Z] JVM_OPTIONS: [2025-12-24T22:47:50.637Z] { \ [2025-12-24T22:47:50.637Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T22:47:50.637Z] echo "Nothing to be done for setup."; \ [2025-12-24T22:47:50.637Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17666144333162/renaissance-philosophers_0"; \ [2025-12-24T22:47:50.637Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17666144333162/renaissance-philosophers_0"; \ [2025-12-24T22:47:50.637Z] echo ""; echo "TESTING:"; \ [2025-12-24T22:47:50.637Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17666144333162/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-24T22:47:50.637Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17666144333162/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T22:47:50.637Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T22:47:50.637Z] echo "Nothing to be done for teardown."; \ [2025-12-24T22:47:50.637Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17666144333162/TestTargetResult"; [2025-12-24T22:47:50.637Z] [2025-12-24T22:47:50.637Z] TEST SETUP: [2025-12-24T22:47:50.637Z] Nothing to be done for setup. [2025-12-24T22:47:50.637Z] [2025-12-24T22:47:50.637Z] TESTING: [2025-12-24T22:47:51.447Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-24T22:47:51.447Z] GC before operation: completed in 19.382 ms, heap usage 15.841 MB -> 4.665 MB. [2025-12-24T22:47:53.743Z] Camera thread performed 122 scans. [2025-12-24T22:47:53.744Z] ====== philosophers (scala) [default], iteration 0 completed (2153.489 ms) ====== [2025-12-24T22:47:53.744Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-24T22:47:53.744Z] GC before operation: completed in 17.081 ms, heap usage 46.092 MB -> 5.032 MB. [2025-12-24T22:47:54.452Z] Camera thread performed 122 scans. [2025-12-24T22:47:54.452Z] ====== philosophers (scala) [default], iteration 1 completed (752.920 ms) ====== [2025-12-24T22:47:54.452Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-24T22:47:54.452Z] GC before operation: completed in 12.407 ms, heap usage 54.531 MB -> 5.034 MB. [2025-12-24T22:47:55.159Z] Camera thread performed 122 scans. [2025-12-24T22:47:55.159Z] ====== philosophers (scala) [default], iteration 2 completed (715.393 ms) ====== [2025-12-24T22:47:55.159Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-24T22:47:55.159Z] GC before operation: completed in 10.750 ms, heap usage 48.346 MB -> 5.034 MB. [2025-12-24T22:47:55.871Z] Camera thread performed 122 scans. [2025-12-24T22:47:55.871Z] ====== philosophers (scala) [default], iteration 3 completed (695.606 ms) ====== [2025-12-24T22:47:55.871Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-24T22:47:55.871Z] GC before operation: completed in 11.691 ms, heap usage 6.205 MB -> 5.034 MB. [2025-12-24T22:47:56.580Z] Camera thread performed 122 scans. [2025-12-24T22:47:56.580Z] ====== philosophers (scala) [default], iteration 4 completed (705.198 ms) ====== [2025-12-24T22:47:56.580Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-24T22:47:56.580Z] GC before operation: completed in 11.460 ms, heap usage 33.708 MB -> 5.034 MB. [2025-12-24T22:47:57.289Z] Camera thread performed 122 scans. [2025-12-24T22:47:57.289Z] ====== philosophers (scala) [default], iteration 5 completed (681.866 ms) ====== [2025-12-24T22:47:57.289Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-24T22:47:57.289Z] GC before operation: completed in 11.466 ms, heap usage 44.687 MB -> 5.034 MB. [2025-12-24T22:47:57.998Z] Camera thread performed 122 scans. [2025-12-24T22:47:57.998Z] ====== philosophers (scala) [default], iteration 6 completed (713.660 ms) ====== [2025-12-24T22:47:57.998Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-24T22:47:57.998Z] GC before operation: completed in 11.003 ms, heap usage 53.670 MB -> 5.035 MB. [2025-12-24T22:47:58.709Z] Camera thread performed 122 scans. [2025-12-24T22:47:58.709Z] ====== philosophers (scala) [default], iteration 7 completed (681.361 ms) ====== [2025-12-24T22:47:58.709Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-24T22:47:58.709Z] GC before operation: completed in 10.310 ms, heap usage 16.631 MB -> 5.035 MB. [2025-12-24T22:47:59.416Z] Camera thread performed 122 scans. [2025-12-24T22:47:59.416Z] ====== philosophers (scala) [default], iteration 8 completed (719.970 ms) ====== [2025-12-24T22:47:59.416Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-24T22:47:59.416Z] GC before operation: completed in 11.696 ms, heap usage 57.657 MB -> 5.036 MB. [2025-12-24T22:48:00.138Z] Camera thread performed 122 scans. [2025-12-24T22:48:00.138Z] ====== philosophers (scala) [default], iteration 9 completed (699.092 ms) ====== [2025-12-24T22:48:00.138Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-24T22:48:00.138Z] GC before operation: completed in 13.765 ms, heap usage 32.627 MB -> 5.035 MB. [2025-12-24T22:48:00.845Z] Camera thread performed 122 scans. [2025-12-24T22:48:00.845Z] ====== philosophers (scala) [default], iteration 10 completed (713.818 ms) ====== [2025-12-24T22:48:00.845Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-24T22:48:00.845Z] GC before operation: completed in 11.665 ms, heap usage 54.658 MB -> 5.036 MB. [2025-12-24T22:48:01.557Z] Camera thread performed 122 scans. [2025-12-24T22:48:01.557Z] ====== philosophers (scala) [default], iteration 11 completed (717.355 ms) ====== [2025-12-24T22:48:01.557Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-24T22:48:01.557Z] GC before operation: completed in 10.868 ms, heap usage 15.661 MB -> 5.036 MB. [2025-12-24T22:48:02.268Z] Camera thread performed 122 scans. [2025-12-24T22:48:02.268Z] ====== philosophers (scala) [default], iteration 12 completed (737.815 ms) ====== [2025-12-24T22:48:02.268Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-24T22:48:02.268Z] GC before operation: completed in 12.081 ms, heap usage 50.155 MB -> 5.036 MB. [2025-12-24T22:48:02.985Z] Camera thread performed 122 scans. [2025-12-24T22:48:02.985Z] ====== philosophers (scala) [default], iteration 13 completed (712.846 ms) ====== [2025-12-24T22:48:02.985Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-24T22:48:02.985Z] GC before operation: completed in 12.611 ms, heap usage 60.156 MB -> 5.036 MB. [2025-12-24T22:48:03.709Z] Camera thread performed 122 scans. [2025-12-24T22:48:03.709Z] ====== philosophers (scala) [default], iteration 14 completed (696.497 ms) ====== [2025-12-24T22:48:03.709Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-24T22:48:03.709Z] GC before operation: completed in 12.090 ms, heap usage 51.156 MB -> 5.037 MB. [2025-12-24T22:48:04.428Z] Camera thread performed 122 scans. [2025-12-24T22:48:04.428Z] ====== philosophers (scala) [default], iteration 15 completed (687.839 ms) ====== [2025-12-24T22:48:04.428Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-24T22:48:04.428Z] GC before operation: completed in 10.819 ms, heap usage 39.661 MB -> 5.037 MB. [2025-12-24T22:48:05.145Z] Camera thread performed 122 scans. [2025-12-24T22:48:05.145Z] ====== philosophers (scala) [default], iteration 16 completed (675.858 ms) ====== [2025-12-24T22:48:05.145Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-24T22:48:05.145Z] GC before operation: completed in 11.042 ms, heap usage 6.631 MB -> 5.038 MB. [2025-12-24T22:48:05.858Z] Camera thread performed 122 scans. [2025-12-24T22:48:05.858Z] ====== philosophers (scala) [default], iteration 17 completed (688.981 ms) ====== [2025-12-24T22:48:05.858Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-24T22:48:05.858Z] GC before operation: completed in 11.945 ms, heap usage 8.662 MB -> 5.037 MB. [2025-12-24T22:48:06.570Z] Camera thread performed 122 scans. [2025-12-24T22:48:06.570Z] ====== philosophers (scala) [default], iteration 18 completed (688.424 ms) ====== [2025-12-24T22:48:06.570Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-24T22:48:06.570Z] GC before operation: completed in 12.063 ms, heap usage 61.660 MB -> 5.038 MB. [2025-12-24T22:48:07.281Z] Camera thread performed 122 scans. [2025-12-24T22:48:07.281Z] ====== philosophers (scala) [default], iteration 19 completed (713.699 ms) ====== [2025-12-24T22:48:07.281Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-24T22:48:07.281Z] GC before operation: completed in 10.888 ms, heap usage 34.161 MB -> 5.037 MB. [2025-12-24T22:48:07.992Z] Camera thread performed 122 scans. [2025-12-24T22:48:07.992Z] ====== philosophers (scala) [default], iteration 20 completed (692.340 ms) ====== [2025-12-24T22:48:07.992Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-24T22:48:07.992Z] GC before operation: completed in 11.276 ms, heap usage 57.658 MB -> 5.038 MB. [2025-12-24T22:48:08.708Z] Camera thread performed 122 scans. [2025-12-24T22:48:08.709Z] ====== philosophers (scala) [default], iteration 21 completed (693.816 ms) ====== [2025-12-24T22:48:08.709Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-24T22:48:08.709Z] GC before operation: completed in 10.783 ms, heap usage 31.163 MB -> 5.038 MB. [2025-12-24T22:48:09.488Z] Camera thread performed 122 scans. [2025-12-24T22:48:09.489Z] ====== philosophers (scala) [default], iteration 22 completed (702.080 ms) ====== [2025-12-24T22:48:09.489Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-24T22:48:09.489Z] GC before operation: completed in 10.916 ms, heap usage 62.160 MB -> 5.038 MB. [2025-12-24T22:48:09.819Z] Camera thread performed 122 scans. [2025-12-24T22:48:09.819Z] ====== philosophers (scala) [default], iteration 23 completed (657.514 ms) ====== [2025-12-24T22:48:09.819Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-24T22:48:09.819Z] GC before operation: completed in 11.260 ms, heap usage 50.657 MB -> 5.038 MB. [2025-12-24T22:48:10.539Z] Camera thread performed 122 scans. [2025-12-24T22:48:10.539Z] ====== philosophers (scala) [default], iteration 24 completed (706.823 ms) ====== [2025-12-24T22:48:10.539Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-24T22:48:10.539Z] GC before operation: completed in 13.641 ms, heap usage 39.660 MB -> 5.038 MB. [2025-12-24T22:48:11.250Z] Camera thread performed 122 scans. [2025-12-24T22:48:11.250Z] ====== philosophers (scala) [default], iteration 25 completed (674.555 ms) ====== [2025-12-24T22:48:11.250Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-24T22:48:11.250Z] GC before operation: completed in 11.505 ms, heap usage 45.160 MB -> 5.038 MB. [2025-12-24T22:48:11.971Z] Camera thread performed 122 scans. [2025-12-24T22:48:11.971Z] ====== philosophers (scala) [default], iteration 26 completed (695.005 ms) ====== [2025-12-24T22:48:11.971Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-24T22:48:11.971Z] GC before operation: completed in 10.983 ms, heap usage 12.658 MB -> 5.059 MB. [2025-12-24T22:48:12.693Z] Camera thread performed 122 scans. [2025-12-24T22:48:12.693Z] ====== philosophers (scala) [default], iteration 27 completed (686.377 ms) ====== [2025-12-24T22:48:12.693Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-24T22:48:12.693Z] GC before operation: completed in 10.873 ms, heap usage 32.173 MB -> 5.038 MB. [2025-12-24T22:48:13.400Z] Camera thread performed 122 scans. [2025-12-24T22:48:13.400Z] ====== philosophers (scala) [default], iteration 28 completed (670.054 ms) ====== [2025-12-24T22:48:13.400Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-24T22:48:13.400Z] GC before operation: completed in 11.523 ms, heap usage 44.150 MB -> 5.039 MB. [2025-12-24T22:48:14.108Z] Camera thread performed 122 scans. [2025-12-24T22:48:14.108Z] ====== philosophers (scala) [default], iteration 29 completed (689.128 ms) ====== [2025-12-24T22:48:14.440Z] ----------------------------------- [2025-12-24T22:48:14.440Z] renaissance-philosophers_0_PASSED [2025-12-24T22:48:14.440Z] ----------------------------------- [2025-12-24T22:48:14.440Z] [2025-12-24T22:48:14.440Z] TEST TEARDOWN: [2025-12-24T22:48:14.440Z] Nothing to be done for teardown. [2025-12-24T22:48:14.440Z] renaissance-philosophers_0 Finish Time: Wed Dec 24 22:48:14 2025 Epoch Time (ms): 1766616494178