[2025-12-27T14:30:30.941Z] Running test renaissance-philosophers_0 ... [2025-12-27T14:30:30.941Z] =============================================== [2025-12-27T14:30:30.941Z] renaissance-philosophers_0 Start Time: Sat Dec 27 14:30:30 2025 Epoch Time (ms): 1766845830484 [2025-12-27T14:30:30.941Z] variation: NoOptions [2025-12-27T14:30:30.941Z] JVM_OPTIONS: [2025-12-27T14:30:30.941Z] { \ [2025-12-27T14:30:30.941Z] echo ""; echo "TEST SETUP:"; \ [2025-12-27T14:30:30.941Z] echo "Nothing to be done for setup."; \ [2025-12-27T14:30:30.941Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-philosophers_0"; \ [2025-12-27T14:30:30.941Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-philosophers_0"; \ [2025-12-27T14:30:30.941Z] echo ""; echo "TESTING:"; \ [2025-12-27T14:30:30.941Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/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_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-27T14:30:30.941Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-27T14:30:30.941Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-27T14:30:30.941Z] echo "Nothing to be done for teardown."; \ [2025-12-27T14:30:30.941Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/TestTargetResult"; [2025-12-27T14:30:30.941Z] [2025-12-27T14:30:30.941Z] TEST SETUP: [2025-12-27T14:30:30.941Z] Nothing to be done for setup. [2025-12-27T14:30:30.941Z] [2025-12-27T14:30:30.941Z] TESTING: [2025-12-27T14:30:31.924Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-27T14:30:31.924Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/output_17668443397974/renaissance-philosophers_0/launcher-143030-12983638934366460461/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-27T14:30:31.924Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-27T14:30:31.924Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-27T14:30:31.924Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-27T14:30:31.924Z] GC before operation: completed in 15.261 ms, heap usage 27.127 MB -> 4.800 MB. [2025-12-27T14:30:37.351Z] Camera thread performed 122 scans. [2025-12-27T14:30:37.351Z] ====== philosophers (scala) [default], iteration 0 completed (5474.407 ms) ====== [2025-12-27T14:30:37.351Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-27T14:30:37.351Z] GC before operation: completed in 17.295 ms, heap usage 11.945 MB -> 5.143 MB. [2025-12-27T14:30:42.954Z] Camera thread performed 122 scans. [2025-12-27T14:30:42.954Z] ====== philosophers (scala) [default], iteration 1 completed (5731.460 ms) ====== [2025-12-27T14:30:42.954Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-27T14:30:42.954Z] GC before operation: completed in 12.007 ms, heap usage 26.761 MB -> 5.143 MB. [2025-12-27T14:30:48.968Z] Camera thread performed 122 scans. [2025-12-27T14:30:48.968Z] ====== philosophers (scala) [default], iteration 2 completed (5489.023 ms) ====== [2025-12-27T14:30:48.968Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-27T14:30:48.968Z] GC before operation: completed in 13.218 ms, heap usage 17.268 MB -> 5.144 MB. [2025-12-27T14:30:53.156Z] Camera thread performed 122 scans. [2025-12-27T14:30:53.156Z] ====== philosophers (scala) [default], iteration 3 completed (4592.373 ms) ====== [2025-12-27T14:30:53.156Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-27T14:30:53.156Z] GC before operation: completed in 11.080 ms, heap usage 14.019 MB -> 5.145 MB. [2025-12-27T14:30:59.918Z] Camera thread performed 122 scans. [2025-12-27T14:30:59.918Z] ====== philosophers (scala) [default], iteration 4 completed (6002.583 ms) ====== [2025-12-27T14:30:59.918Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-27T14:30:59.918Z] GC before operation: completed in 12.371 ms, heap usage 11.731 MB -> 5.145 MB. [2025-12-27T14:31:05.339Z] Camera thread performed 122 scans. [2025-12-27T14:31:05.339Z] ====== philosophers (scala) [default], iteration 5 completed (5380.357 ms) ====== [2025-12-27T14:31:05.339Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-27T14:31:05.339Z] GC before operation: completed in 13.140 ms, heap usage 35.077 MB -> 5.145 MB. [2025-12-27T14:31:10.759Z] Camera thread performed 122 scans. [2025-12-27T14:31:10.759Z] ====== philosophers (scala) [default], iteration 6 completed (5430.488 ms) ====== [2025-12-27T14:31:10.759Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-27T14:31:10.759Z] GC before operation: completed in 12.561 ms, heap usage 29.660 MB -> 5.146 MB. [2025-12-27T14:31:16.185Z] Camera thread performed 122 scans. [2025-12-27T14:31:16.185Z] ====== philosophers (scala) [default], iteration 7 completed (5850.172 ms) ====== [2025-12-27T14:31:16.185Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-27T14:31:16.185Z] GC before operation: completed in 13.924 ms, heap usage 30.670 MB -> 5.146 MB. [2025-12-27T14:31:21.608Z] Camera thread performed 122 scans. [2025-12-27T14:31:21.608Z] ====== philosophers (scala) [default], iteration 8 completed (5626.661 ms) ====== [2025-12-27T14:31:21.608Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-27T14:31:21.608Z] GC before operation: completed in 10.818 ms, heap usage 12.204 MB -> 5.146 MB. [2025-12-27T14:31:27.034Z] Camera thread performed 122 scans. [2025-12-27T14:31:27.034Z] ====== philosophers (scala) [default], iteration 9 completed (5538.636 ms) ====== [2025-12-27T14:31:27.034Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-27T14:31:27.034Z] GC before operation: completed in 12.327 ms, heap usage 36.276 MB -> 5.146 MB. [2025-12-27T14:31:32.460Z] Camera thread performed 122 scans. [2025-12-27T14:31:32.460Z] ====== philosophers (scala) [default], iteration 10 completed (5240.622 ms) ====== [2025-12-27T14:31:32.460Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-27T14:31:32.460Z] GC before operation: completed in 11.761 ms, heap usage 24.717 MB -> 5.146 MB. [2025-12-27T14:31:39.212Z] Camera thread performed 122 scans. [2025-12-27T14:31:39.212Z] ====== philosophers (scala) [default], iteration 11 completed (6089.119 ms) ====== [2025-12-27T14:31:39.212Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-27T14:31:39.212Z] GC before operation: completed in 12.730 ms, heap usage 8.999 MB -> 5.146 MB. [2025-12-27T14:31:44.639Z] Camera thread performed 122 scans. [2025-12-27T14:31:44.639Z] ====== philosophers (scala) [default], iteration 12 completed (6354.950 ms) ====== [2025-12-27T14:31:44.639Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-27T14:31:44.639Z] GC before operation: completed in 11.607 ms, heap usage 39.062 MB -> 5.147 MB. [2025-12-27T14:31:51.141Z] Camera thread performed 122 scans. [2025-12-27T14:31:51.141Z] ====== philosophers (scala) [default], iteration 13 completed (5706.365 ms) ====== [2025-12-27T14:31:51.141Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-27T14:31:51.141Z] GC before operation: completed in 10.635 ms, heap usage 21.816 MB -> 5.147 MB. [2025-12-27T14:31:56.570Z] Camera thread performed 122 scans. [2025-12-27T14:31:56.570Z] ====== philosophers (scala) [default], iteration 14 completed (6016.558 ms) ====== [2025-12-27T14:31:56.570Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-27T14:31:56.570Z] GC before operation: completed in 11.557 ms, heap usage 16.723 MB -> 5.147 MB. [2025-12-27T14:32:02.041Z] Camera thread performed 122 scans. [2025-12-27T14:32:02.041Z] ====== philosophers (scala) [default], iteration 15 completed (5776.227 ms) ====== [2025-12-27T14:32:02.041Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-27T14:32:02.041Z] GC before operation: completed in 12.378 ms, heap usage 14.133 MB -> 5.147 MB. [2025-12-27T14:32:08.804Z] Camera thread performed 122 scans. [2025-12-27T14:32:08.804Z] ====== philosophers (scala) [default], iteration 16 completed (6580.488 ms) ====== [2025-12-27T14:32:08.804Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-27T14:32:08.804Z] GC before operation: completed in 11.265 ms, heap usage 9.402 MB -> 5.147 MB. [2025-12-27T14:32:15.561Z] Camera thread performed 122 scans. [2025-12-27T14:32:15.561Z] ====== philosophers (scala) [default], iteration 17 completed (6052.365 ms) ====== [2025-12-27T14:32:15.561Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-27T14:32:15.561Z] GC before operation: completed in 11.692 ms, heap usage 30.638 MB -> 5.147 MB. [2025-12-27T14:32:20.983Z] Camera thread performed 122 scans. [2025-12-27T14:32:20.983Z] ====== philosophers (scala) [default], iteration 18 completed (5981.976 ms) ====== [2025-12-27T14:32:20.983Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-27T14:32:20.983Z] GC before operation: completed in 12.036 ms, heap usage 17.718 MB -> 5.147 MB. [2025-12-27T14:32:26.409Z] Camera thread performed 122 scans. [2025-12-27T14:32:26.409Z] ====== philosophers (scala) [default], iteration 19 completed (5780.858 ms) ====== [2025-12-27T14:32:26.409Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-27T14:32:26.409Z] GC before operation: completed in 11.120 ms, heap usage 5.968 MB -> 5.147 MB. [2025-12-27T14:32:33.169Z] Camera thread performed 122 scans. [2025-12-27T14:32:33.169Z] ====== philosophers (scala) [default], iteration 20 completed (5750.588 ms) ====== [2025-12-27T14:32:33.169Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-27T14:32:33.169Z] GC before operation: completed in 13.840 ms, heap usage 39.248 MB -> 5.148 MB. [2025-12-27T14:32:38.593Z] Camera thread performed 122 scans. [2025-12-27T14:32:38.593Z] ====== philosophers (scala) [default], iteration 21 completed (6012.752 ms) ====== [2025-12-27T14:32:38.593Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-27T14:32:38.593Z] GC before operation: completed in 12.818 ms, heap usage 6.703 MB -> 5.148 MB. [2025-12-27T14:32:45.355Z] Camera thread performed 122 scans. [2025-12-27T14:32:45.355Z] ====== philosophers (scala) [default], iteration 22 completed (6032.606 ms) ====== [2025-12-27T14:32:45.355Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-27T14:32:45.355Z] GC before operation: completed in 12.146 ms, heap usage 37.876 MB -> 5.148 MB. [2025-12-27T14:32:50.606Z] Camera thread performed 122 scans. [2025-12-27T14:32:50.606Z] ====== philosophers (scala) [default], iteration 23 completed (5772.226 ms) ====== [2025-12-27T14:32:50.606Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-27T14:32:50.606Z] GC before operation: completed in 12.373 ms, heap usage 35.734 MB -> 5.148 MB. [2025-12-27T14:32:57.361Z] Camera thread performed 122 scans. [2025-12-27T14:32:57.361Z] ====== philosophers (scala) [default], iteration 24 completed (6609.031 ms) ====== [2025-12-27T14:32:57.361Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-27T14:32:57.361Z] GC before operation: completed in 11.697 ms, heap usage 35.414 MB -> 5.148 MB. [2025-12-27T14:33:01.592Z] Camera thread performed 122 scans. [2025-12-27T14:33:01.592Z] ====== philosophers (scala) [default], iteration 25 completed (4474.743 ms) ====== [2025-12-27T14:33:01.592Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-27T14:33:01.592Z] GC before operation: completed in 12.350 ms, heap usage 10.380 MB -> 5.148 MB. [2025-12-27T14:33:07.017Z] Camera thread performed 122 scans. [2025-12-27T14:33:07.017Z] ====== philosophers (scala) [default], iteration 26 completed (5801.301 ms) ====== [2025-12-27T14:33:07.017Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-27T14:33:07.017Z] GC before operation: completed in 11.381 ms, heap usage 35.132 MB -> 5.148 MB. [2025-12-27T14:33:13.787Z] Camera thread performed 122 scans. [2025-12-27T14:33:13.788Z] ====== philosophers (scala) [default], iteration 27 completed (6009.606 ms) ====== [2025-12-27T14:33:13.788Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-27T14:33:13.788Z] GC before operation: completed in 11.647 ms, heap usage 6.960 MB -> 5.148 MB. [2025-12-27T14:33:19.217Z] Camera thread performed 122 scans. [2025-12-27T14:33:19.217Z] ====== philosophers (scala) [default], iteration 28 completed (5914.510 ms) ====== [2025-12-27T14:33:19.217Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-27T14:33:19.217Z] GC before operation: completed in 18.176 ms, heap usage 21.619 MB -> 5.148 MB. [2025-12-27T14:33:26.073Z] Camera thread performed 122 scans. [2025-12-27T14:33:26.073Z] ====== philosophers (scala) [default], iteration 29 completed (6303.750 ms) ====== [2025-12-27T14:33:26.073Z] ----------------------------------- [2025-12-27T14:33:26.073Z] renaissance-philosophers_0_PASSED [2025-12-27T14:33:26.073Z] ----------------------------------- [2025-12-27T14:33:26.073Z] [2025-12-27T14:33:26.073Z] TEST TEARDOWN: [2025-12-27T14:33:26.073Z] Nothing to be done for teardown. [2025-12-27T14:33:26.073Z] renaissance-philosophers_0 Finish Time: Sat Dec 27 14:33:25 2025 Epoch Time (ms): 1766846005362