[2025-11-29T14:55:00.507Z] Running test renaissance-philosophers_0 ... [2025-11-29T14:55:00.507Z] =============================================== [2025-11-29T14:55:00.507Z] renaissance-philosophers_0 Start Time: Sat Nov 29 14:54:59 2025 Epoch Time (ms): 1764428099735 [2025-11-29T14:55:00.507Z] variation: NoOptions [2025-11-29T14:55:00.507Z] JVM_OPTIONS: [2025-11-29T14:55:00.507Z] { \ [2025-11-29T14:55:00.507Z] echo ""; echo "TEST SETUP:"; \ [2025-11-29T14:55:00.507Z] echo "Nothing to be done for setup."; \ [2025-11-29T14:55:00.507Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17644265017028/renaissance-philosophers_0"; \ [2025-11-29T14:55:00.507Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17644265017028/renaissance-philosophers_0"; \ [2025-11-29T14:55:00.507Z] echo ""; echo "TESTING:"; \ [2025-11-29T14:55:00.507Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/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_ppc64le_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17644265017028/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-29T14:55:00.507Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17644265017028/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-29T14:55:00.507Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-29T14:55:00.507Z] echo "Nothing to be done for teardown."; \ [2025-11-29T14:55:00.507Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17644265017028/TestTargetResult"; [2025-11-29T14:55:00.507Z] [2025-11-29T14:55:00.507Z] TEST SETUP: [2025-11-29T14:55:00.507Z] Nothing to be done for setup. [2025-11-29T14:55:00.507Z] [2025-11-29T14:55:00.507Z] TESTING: [2025-11-29T14:55:00.507Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-11-29T14:55:00.508Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/output_17644265017028/renaissance-philosophers_0/launcher-145459-18238482637841450073/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-11-29T14:55:00.508Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-11-29T14:55:00.508Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-11-29T14:55:01.448Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-29T14:55:01.448Z] GC before operation: completed in 15.264 ms, heap usage 27.128 MB -> 4.801 MB. [2025-11-29T14:55:08.204Z] Camera thread performed 122 scans. [2025-11-29T14:55:08.204Z] ====== philosophers (scala) [default], iteration 0 completed (6231.738 ms) ====== [2025-11-29T14:55:08.204Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-29T14:55:08.204Z] GC before operation: completed in 17.403 ms, heap usage 9.976 MB -> 5.143 MB. [2025-11-29T14:55:13.515Z] Camera thread performed 122 scans. [2025-11-29T14:55:13.515Z] ====== philosophers (scala) [default], iteration 1 completed (6113.024 ms) ====== [2025-11-29T14:55:13.515Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-29T14:55:13.515Z] GC before operation: completed in 12.985 ms, heap usage 29.020 MB -> 5.143 MB. [2025-11-29T14:55:20.126Z] Camera thread performed 122 scans. [2025-11-29T14:55:20.126Z] ====== philosophers (scala) [default], iteration 2 completed (6812.797 ms) ====== [2025-11-29T14:55:20.126Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-29T14:55:20.126Z] GC before operation: completed in 13.087 ms, heap usage 25.027 MB -> 5.144 MB. [2025-11-29T14:55:26.624Z] Camera thread performed 122 scans. [2025-11-29T14:55:26.624Z] ====== philosophers (scala) [default], iteration 3 completed (5370.072 ms) ====== [2025-11-29T14:55:26.624Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-29T14:55:26.624Z] GC before operation: completed in 11.459 ms, heap usage 18.458 MB -> 5.144 MB. [2025-11-29T14:55:31.935Z] Camera thread performed 122 scans. [2025-11-29T14:55:31.935Z] ====== philosophers (scala) [default], iteration 4 completed (5442.936 ms) ====== [2025-11-29T14:55:31.935Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-29T14:55:31.935Z] GC before operation: completed in 20.476 ms, heap usage 31.046 MB -> 5.145 MB. [2025-11-29T14:55:37.238Z] Camera thread performed 122 scans. [2025-11-29T14:55:37.238Z] ====== philosophers (scala) [default], iteration 5 completed (6024.527 ms) ====== [2025-11-29T14:55:37.238Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-29T14:55:37.238Z] GC before operation: completed in 16.784 ms, heap usage 24.815 MB -> 5.146 MB. [2025-11-29T14:55:42.537Z] Camera thread performed 122 scans. [2025-11-29T14:55:42.537Z] ====== philosophers (scala) [default], iteration 6 completed (5448.032 ms) ====== [2025-11-29T14:55:42.537Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-29T14:55:42.538Z] GC before operation: completed in 14.312 ms, heap usage 36.086 MB -> 5.146 MB. [2025-11-29T14:55:47.867Z] Camera thread performed 122 scans. [2025-11-29T14:55:47.867Z] ====== philosophers (scala) [default], iteration 7 completed (4678.230 ms) ====== [2025-11-29T14:55:47.867Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-29T14:55:47.867Z] GC before operation: completed in 13.325 ms, heap usage 7.124 MB -> 5.146 MB. [2025-11-29T14:55:53.164Z] Camera thread performed 122 scans. [2025-11-29T14:55:53.164Z] ====== philosophers (scala) [default], iteration 8 completed (5884.915 ms) ====== [2025-11-29T14:55:53.164Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-29T14:55:53.164Z] GC before operation: completed in 14.728 ms, heap usage 39.640 MB -> 5.146 MB. [2025-11-29T14:55:58.462Z] Camera thread performed 122 scans. [2025-11-29T14:55:58.462Z] ====== philosophers (scala) [default], iteration 9 completed (5575.032 ms) ====== [2025-11-29T14:55:58.462Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-29T14:55:58.462Z] GC before operation: completed in 16.720 ms, heap usage 9.073 MB -> 5.146 MB. [2025-11-29T14:56:03.790Z] Camera thread performed 122 scans. [2025-11-29T14:56:03.790Z] ====== philosophers (scala) [default], iteration 10 completed (5003.017 ms) ====== [2025-11-29T14:56:03.790Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-29T14:56:03.790Z] GC before operation: completed in 13.513 ms, heap usage 8.406 MB -> 5.146 MB. [2025-11-29T14:56:09.207Z] Camera thread performed 122 scans. [2025-11-29T14:56:09.207Z] ====== philosophers (scala) [default], iteration 11 completed (4892.866 ms) ====== [2025-11-29T14:56:09.207Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-29T14:56:09.207Z] GC before operation: completed in 10.811 ms, heap usage 21.639 MB -> 5.147 MB. [2025-11-29T14:56:14.502Z] Camera thread performed 122 scans. [2025-11-29T14:56:14.502Z] ====== philosophers (scala) [default], iteration 12 completed (5583.910 ms) ====== [2025-11-29T14:56:14.502Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-29T14:56:14.502Z] GC before operation: completed in 13.581 ms, heap usage 11.436 MB -> 5.147 MB. [2025-11-29T14:56:19.837Z] Camera thread performed 122 scans. [2025-11-29T14:56:19.837Z] ====== philosophers (scala) [default], iteration 13 completed (5046.122 ms) ====== [2025-11-29T14:56:19.837Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-29T14:56:19.837Z] GC before operation: completed in 10.138 ms, heap usage 7.756 MB -> 5.147 MB. [2025-11-29T14:56:25.290Z] Camera thread performed 122 scans. [2025-11-29T14:56:25.290Z] ====== philosophers (scala) [default], iteration 14 completed (4847.010 ms) ====== [2025-11-29T14:56:25.290Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-29T14:56:25.290Z] GC before operation: completed in 13.972 ms, heap usage 17.658 MB -> 5.148 MB. [2025-11-29T14:56:29.382Z] Camera thread performed 122 scans. [2025-11-29T14:56:29.382Z] ====== philosophers (scala) [default], iteration 15 completed (4866.606 ms) ====== [2025-11-29T14:56:29.382Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-29T14:56:29.382Z] GC before operation: completed in 11.783 ms, heap usage 25.320 MB -> 5.148 MB. [2025-11-29T14:56:34.683Z] Camera thread performed 122 scans. [2025-11-29T14:56:34.683Z] ====== philosophers (scala) [default], iteration 16 completed (5627.782 ms) ====== [2025-11-29T14:56:34.683Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-29T14:56:34.683Z] GC before operation: completed in 11.491 ms, heap usage 31.062 MB -> 5.148 MB. [2025-11-29T14:56:39.984Z] Camera thread performed 122 scans. [2025-11-29T14:56:39.984Z] ====== philosophers (scala) [default], iteration 17 completed (5406.339 ms) ====== [2025-11-29T14:56:39.984Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-29T14:56:39.984Z] GC before operation: completed in 13.854 ms, heap usage 32.621 MB -> 5.148 MB. [2025-11-29T14:56:45.280Z] Camera thread performed 122 scans. [2025-11-29T14:56:45.280Z] ====== philosophers (scala) [default], iteration 18 completed (4899.606 ms) ====== [2025-11-29T14:56:45.280Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-29T14:56:45.280Z] GC before operation: completed in 10.559 ms, heap usage 40.423 MB -> 5.148 MB. [2025-11-29T14:56:50.591Z] Camera thread performed 122 scans. [2025-11-29T14:56:50.591Z] ====== philosophers (scala) [default], iteration 19 completed (5416.950 ms) ====== [2025-11-29T14:56:50.591Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-29T14:56:50.591Z] GC before operation: completed in 9.897 ms, heap usage 12.414 MB -> 5.148 MB. [2025-11-29T14:56:55.981Z] Camera thread performed 122 scans. [2025-11-29T14:56:55.981Z] ====== philosophers (scala) [default], iteration 20 completed (5454.040 ms) ====== [2025-11-29T14:56:55.981Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-29T14:56:55.981Z] GC before operation: completed in 11.445 ms, heap usage 10.887 MB -> 5.148 MB. [2025-11-29T14:57:02.622Z] Camera thread performed 122 scans. [2025-11-29T14:57:02.622Z] ====== philosophers (scala) [default], iteration 21 completed (5677.400 ms) ====== [2025-11-29T14:57:02.622Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-29T14:57:02.622Z] GC before operation: completed in 14.803 ms, heap usage 14.621 MB -> 5.148 MB. [2025-11-29T14:57:08.089Z] Camera thread performed 122 scans. [2025-11-29T14:57:08.089Z] ====== philosophers (scala) [default], iteration 22 completed (6099.923 ms) ====== [2025-11-29T14:57:08.089Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-29T14:57:08.089Z] GC before operation: completed in 17.849 ms, heap usage 27.664 MB -> 5.148 MB. [2025-11-29T14:57:14.704Z] Camera thread performed 122 scans. [2025-11-29T14:57:14.704Z] ====== philosophers (scala) [default], iteration 23 completed (5992.584 ms) ====== [2025-11-29T14:57:14.704Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-29T14:57:14.704Z] GC before operation: completed in 19.514 ms, heap usage 13.267 MB -> 5.148 MB. [2025-11-29T14:57:20.007Z] Camera thread performed 122 scans. [2025-11-29T14:57:20.007Z] ====== philosophers (scala) [default], iteration 24 completed (5507.080 ms) ====== [2025-11-29T14:57:20.007Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-29T14:57:20.007Z] GC before operation: completed in 14.746 ms, heap usage 19.893 MB -> 5.149 MB. [2025-11-29T14:57:25.123Z] Camera thread performed 122 scans. [2025-11-29T14:57:25.123Z] ====== philosophers (scala) [default], iteration 25 completed (5177.014 ms) ====== [2025-11-29T14:57:25.123Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-29T14:57:25.123Z] GC before operation: completed in 17.784 ms, heap usage 30.577 MB -> 5.148 MB. [2025-11-29T14:57:30.426Z] Camera thread performed 122 scans. [2025-11-29T14:57:30.426Z] ====== philosophers (scala) [default], iteration 26 completed (5282.493 ms) ====== [2025-11-29T14:57:30.426Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-29T14:57:30.426Z] GC before operation: completed in 14.009 ms, heap usage 10.348 MB -> 5.148 MB. [2025-11-29T14:57:35.799Z] Camera thread performed 122 scans. [2025-11-29T14:57:35.799Z] ====== philosophers (scala) [default], iteration 27 completed (5050.752 ms) ====== [2025-11-29T14:57:35.799Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-29T14:57:35.799Z] GC before operation: completed in 14.544 ms, heap usage 25.393 MB -> 5.148 MB. [2025-11-29T14:57:39.897Z] Camera thread performed 122 scans. [2025-11-29T14:57:39.897Z] ====== philosophers (scala) [default], iteration 28 completed (4718.148 ms) ====== [2025-11-29T14:57:39.897Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-29T14:57:39.897Z] GC before operation: completed in 12.332 ms, heap usage 6.684 MB -> 5.149 MB. [2025-11-29T14:57:45.208Z] Camera thread performed 122 scans. [2025-11-29T14:57:45.208Z] ====== philosophers (scala) [default], iteration 29 completed (4882.885 ms) ====== [2025-11-29T14:57:45.208Z] ----------------------------------- [2025-11-29T14:57:45.208Z] renaissance-philosophers_0_PASSED [2025-11-29T14:57:45.208Z] ----------------------------------- [2025-11-29T14:57:45.208Z] [2025-11-29T14:57:45.208Z] TEST TEARDOWN: [2025-11-29T14:57:45.208Z] Nothing to be done for teardown. [2025-11-29T14:57:45.208Z] renaissance-philosophers_0 Finish Time: Sat Nov 29 14:57:44 2025 Epoch Time (ms): 1764428264313