[2025-11-06T03:03:12.433Z] Running test renaissance-philosophers_0 ... [2025-11-06T03:03:12.433Z] =============================================== [2025-11-06T03:03:12.433Z] renaissance-philosophers_0 Start Time: Thu Nov 6 03:03:11 2025 Epoch Time (ms): 1762398191757 [2025-11-06T03:03:12.433Z] variation: NoOptions [2025-11-06T03:03:12.433Z] JVM_OPTIONS: [2025-11-06T03:03:12.433Z] { \ [2025-11-06T03:03:12.433Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T03:03:12.433Z] echo "Nothing to be done for setup."; \ [2025-11-06T03:03:12.433Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17623963763701/renaissance-philosophers_0"; \ [2025-11-06T03:03:12.433Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17623963763701/renaissance-philosophers_0"; \ [2025-11-06T03:03:12.433Z] echo ""; echo "TESTING:"; \ [2025-11-06T03:03:12.433Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17623963763701/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-06T03:03:12.433Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17623963763701/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T03:03:12.433Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T03:03:12.433Z] echo "Nothing to be done for teardown."; \ [2025-11-06T03:03:12.433Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17623963763701/TestTargetResult"; [2025-11-06T03:03:12.433Z] [2025-11-06T03:03:12.433Z] TEST SETUP: [2025-11-06T03:03:12.433Z] Nothing to be done for setup. [2025-11-06T03:03:12.433Z] [2025-11-06T03:03:12.433Z] TESTING: [2025-11-06T03:03:13.376Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-06T03:03:13.376Z] GC before operation: completed in 10.584 ms, heap usage 13.765 MB -> 4.745 MB. [2025-11-06T03:03:18.738Z] Camera thread performed 122 scans. [2025-11-06T03:03:18.738Z] ====== philosophers (scala) [default], iteration 0 completed (5526.400 ms) ====== [2025-11-06T03:03:18.738Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-06T03:03:18.738Z] GC before operation: completed in 11.343 ms, heap usage 5.794 MB -> 5.099 MB. [2025-11-06T03:03:24.091Z] Camera thread performed 122 scans. [2025-11-06T03:03:24.091Z] ====== philosophers (scala) [default], iteration 1 completed (4909.229 ms) ====== [2025-11-06T03:03:24.091Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-06T03:03:24.091Z] GC before operation: completed in 10.939 ms, heap usage 33.152 MB -> 5.098 MB. [2025-11-06T03:03:29.411Z] Camera thread performed 122 scans. [2025-11-06T03:03:29.411Z] ====== philosophers (scala) [default], iteration 2 completed (5464.892 ms) ====== [2025-11-06T03:03:29.411Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-06T03:03:29.411Z] GC before operation: completed in 12.095 ms, heap usage 15.780 MB -> 5.100 MB. [2025-11-06T03:03:34.733Z] Camera thread performed 122 scans. [2025-11-06T03:03:34.733Z] ====== philosophers (scala) [default], iteration 3 completed (5566.472 ms) ====== [2025-11-06T03:03:34.733Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-06T03:03:34.733Z] GC before operation: completed in 12.287 ms, heap usage 23.655 MB -> 5.099 MB. [2025-11-06T03:03:40.070Z] Camera thread performed 122 scans. [2025-11-06T03:03:40.070Z] ====== philosophers (scala) [default], iteration 4 completed (5438.746 ms) ====== [2025-11-06T03:03:40.070Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-06T03:03:40.070Z] GC before operation: completed in 10.159 ms, heap usage 10.419 MB -> 5.100 MB. [2025-11-06T03:03:45.444Z] Camera thread performed 122 scans. [2025-11-06T03:03:45.444Z] ====== philosophers (scala) [default], iteration 5 completed (5422.380 ms) ====== [2025-11-06T03:03:45.444Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-06T03:03:45.444Z] GC before operation: completed in 11.615 ms, heap usage 35.146 MB -> 5.100 MB. [2025-11-06T03:03:51.628Z] Camera thread performed 122 scans. [2025-11-06T03:03:51.628Z] ====== philosophers (scala) [default], iteration 6 completed (4896.164 ms) ====== [2025-11-06T03:03:51.628Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-06T03:03:51.628Z] GC before operation: completed in 12.599 ms, heap usage 44.937 MB -> 5.101 MB. [2025-11-06T03:03:55.740Z] Camera thread performed 122 scans. [2025-11-06T03:03:55.740Z] ====== philosophers (scala) [default], iteration 7 completed (5300.368 ms) ====== [2025-11-06T03:03:55.740Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-06T03:03:55.740Z] GC before operation: completed in 10.390 ms, heap usage 28.561 MB -> 5.101 MB. [2025-11-06T03:04:01.073Z] Camera thread performed 122 scans. [2025-11-06T03:04:01.073Z] ====== philosophers (scala) [default], iteration 8 completed (5132.482 ms) ====== [2025-11-06T03:04:01.073Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-06T03:04:01.073Z] GC before operation: completed in 10.651 ms, heap usage 31.226 MB -> 5.102 MB. [2025-11-06T03:04:06.496Z] Camera thread performed 122 scans. [2025-11-06T03:04:06.496Z] ====== philosophers (scala) [default], iteration 9 completed (5286.912 ms) ====== [2025-11-06T03:04:06.496Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-06T03:04:06.496Z] GC before operation: completed in 10.793 ms, heap usage 12.745 MB -> 5.101 MB. [2025-11-06T03:04:10.614Z] Camera thread performed 122 scans. [2025-11-06T03:04:10.614Z] ====== philosophers (scala) [default], iteration 10 completed (4797.980 ms) ====== [2025-11-06T03:04:10.614Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-06T03:04:10.614Z] GC before operation: completed in 9.305 ms, heap usage 31.515 MB -> 5.101 MB. [2025-11-06T03:04:16.053Z] Camera thread performed 122 scans. [2025-11-06T03:04:16.053Z] ====== philosophers (scala) [default], iteration 11 completed (5071.047 ms) ====== [2025-11-06T03:04:16.053Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-06T03:04:16.053Z] GC before operation: completed in 9.551 ms, heap usage 27.361 MB -> 5.102 MB. [2025-11-06T03:04:20.178Z] Camera thread performed 122 scans. [2025-11-06T03:04:20.178Z] ====== philosophers (scala) [default], iteration 12 completed (4449.337 ms) ====== [2025-11-06T03:04:20.178Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-06T03:04:20.178Z] GC before operation: completed in 10.298 ms, heap usage 31.083 MB -> 5.102 MB. [2025-11-06T03:04:25.514Z] Camera thread performed 122 scans. [2025-11-06T03:04:25.514Z] ====== philosophers (scala) [default], iteration 13 completed (5131.976 ms) ====== [2025-11-06T03:04:25.514Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-06T03:04:25.514Z] GC before operation: completed in 11.246 ms, heap usage 36.037 MB -> 5.102 MB. [2025-11-06T03:04:31.032Z] Camera thread performed 122 scans. [2025-11-06T03:04:31.032Z] ====== philosophers (scala) [default], iteration 14 completed (5147.039 ms) ====== [2025-11-06T03:04:31.032Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-06T03:04:31.032Z] GC before operation: completed in 9.528 ms, heap usage 19.368 MB -> 5.102 MB. [2025-11-06T03:04:36.365Z] Camera thread performed 122 scans. [2025-11-06T03:04:36.365Z] ====== philosophers (scala) [default], iteration 15 completed (5018.586 ms) ====== [2025-11-06T03:04:36.365Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-06T03:04:36.365Z] GC before operation: completed in 10.517 ms, heap usage 16.008 MB -> 5.102 MB. [2025-11-06T03:04:41.751Z] Camera thread performed 122 scans. [2025-11-06T03:04:41.751Z] ====== philosophers (scala) [default], iteration 16 completed (5168.182 ms) ====== [2025-11-06T03:04:41.751Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-06T03:04:41.751Z] GC before operation: completed in 9.857 ms, heap usage 17.717 MB -> 5.103 MB. [2025-11-06T03:04:45.855Z] Camera thread performed 122 scans. [2025-11-06T03:04:45.856Z] ====== philosophers (scala) [default], iteration 17 completed (4805.118 ms) ====== [2025-11-06T03:04:45.856Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-06T03:04:45.856Z] GC before operation: completed in 11.079 ms, heap usage 43.040 MB -> 5.102 MB. [2025-11-06T03:04:50.876Z] Camera thread performed 122 scans. [2025-11-06T03:04:50.876Z] ====== philosophers (scala) [default], iteration 18 completed (4439.182 ms) ====== [2025-11-06T03:04:50.876Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-06T03:04:50.876Z] GC before operation: completed in 9.484 ms, heap usage 33.709 MB -> 5.103 MB. [2025-11-06T03:04:54.980Z] Camera thread performed 122 scans. [2025-11-06T03:04:54.980Z] ====== philosophers (scala) [default], iteration 19 completed (4691.238 ms) ====== [2025-11-06T03:04:54.980Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-06T03:04:54.980Z] GC before operation: completed in 10.305 ms, heap usage 12.779 MB -> 5.103 MB. [2025-11-06T03:05:00.300Z] Camera thread performed 122 scans. [2025-11-06T03:05:00.300Z] ====== philosophers (scala) [default], iteration 20 completed (4939.581 ms) ====== [2025-11-06T03:05:00.300Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-06T03:05:00.300Z] GC before operation: completed in 9.662 ms, heap usage 24.147 MB -> 5.103 MB. [2025-11-06T03:05:05.622Z] Camera thread performed 122 scans. [2025-11-06T03:05:05.622Z] ====== philosophers (scala) [default], iteration 21 completed (4903.755 ms) ====== [2025-11-06T03:05:05.622Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-06T03:05:05.622Z] GC before operation: completed in 10.454 ms, heap usage 19.660 MB -> 5.103 MB. [2025-11-06T03:05:09.742Z] Camera thread performed 122 scans. [2025-11-06T03:05:09.742Z] ====== philosophers (scala) [default], iteration 22 completed (4814.476 ms) ====== [2025-11-06T03:05:09.742Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-06T03:05:09.742Z] GC before operation: completed in 10.376 ms, heap usage 31.968 MB -> 5.103 MB. [2025-11-06T03:05:13.849Z] Camera thread performed 122 scans. [2025-11-06T03:05:13.849Z] ====== philosophers (scala) [default], iteration 23 completed (3978.954 ms) ====== [2025-11-06T03:05:13.849Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-06T03:05:13.849Z] GC before operation: completed in 10.928 ms, heap usage 24.338 MB -> 5.103 MB. [2025-11-06T03:05:19.200Z] Camera thread performed 122 scans. [2025-11-06T03:05:19.200Z] ====== philosophers (scala) [default], iteration 24 completed (4961.499 ms) ====== [2025-11-06T03:05:19.201Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-06T03:05:19.201Z] GC before operation: completed in 11.780 ms, heap usage 17.853 MB -> 5.103 MB. [2025-11-06T03:05:24.519Z] Camera thread performed 122 scans. [2025-11-06T03:05:24.519Z] ====== philosophers (scala) [default], iteration 25 completed (5466.673 ms) ====== [2025-11-06T03:05:24.519Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-06T03:05:24.519Z] GC before operation: completed in 10.744 ms, heap usage 11.207 MB -> 5.103 MB. [2025-11-06T03:05:28.626Z] Camera thread performed 122 scans. [2025-11-06T03:05:28.626Z] ====== philosophers (scala) [default], iteration 26 completed (4164.881 ms) ====== [2025-11-06T03:05:28.626Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-06T03:05:28.626Z] GC before operation: completed in 10.280 ms, heap usage 24.973 MB -> 5.104 MB. [2025-11-06T03:05:32.745Z] Camera thread performed 122 scans. [2025-11-06T03:05:32.745Z] ====== philosophers (scala) [default], iteration 27 completed (4681.674 ms) ====== [2025-11-06T03:05:32.745Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-06T03:05:32.745Z] GC before operation: completed in 10.302 ms, heap usage 13.340 MB -> 5.103 MB. [2025-11-06T03:05:38.074Z] Camera thread performed 122 scans. [2025-11-06T03:05:38.074Z] ====== philosophers (scala) [default], iteration 28 completed (5336.527 ms) ====== [2025-11-06T03:05:38.074Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-06T03:05:38.074Z] GC before operation: completed in 10.835 ms, heap usage 39.719 MB -> 5.103 MB. [2025-11-06T03:05:43.397Z] Camera thread performed 122 scans. [2025-11-06T03:05:43.397Z] ====== philosophers (scala) [default], iteration 29 completed (5317.136 ms) ====== [2025-11-06T03:05:44.338Z] ----------------------------------- [2025-11-06T03:05:44.338Z] renaissance-philosophers_0_PASSED [2025-11-06T03:05:44.338Z] ----------------------------------- [2025-11-06T03:05:44.338Z] [2025-11-06T03:05:44.338Z] TEST TEARDOWN: [2025-11-06T03:05:44.338Z] Nothing to be done for teardown. [2025-11-06T03:05:44.338Z] renaissance-philosophers_0 Finish Time: Thu Nov 6 03:05:43 2025 Epoch Time (ms): 1762398343401