[2025-06-12T00:21:53.389Z] Running test renaissance-philosophers_0 ... [2025-06-12T00:21:53.389Z] =============================================== [2025-06-12T00:21:53.389Z] renaissance-philosophers_0 Start Time: Thu Jun 12 00:21:52 2025 Epoch Time (ms): 1749687712614 [2025-06-12T00:21:53.389Z] variation: NoOptions [2025-06-12T00:21:53.389Z] JVM_OPTIONS: [2025-06-12T00:21:53.389Z] { \ [2025-06-12T00:21:53.389Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T00:21:53.389Z] echo "Nothing to be done for setup."; \ [2025-06-12T00:21:53.389Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496857263863/renaissance-philosophers_0"; \ [2025-06-12T00:21:53.389Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496857263863/renaissance-philosophers_0"; \ [2025-06-12T00:21:53.389Z] echo ""; echo "TESTING:"; \ [2025-06-12T00:21:53.389Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496857263863/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-12T00:21:53.389Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496857263863/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T00:21:53.389Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T00:21:53.389Z] echo "Nothing to be done for teardown."; \ [2025-06-12T00:21:53.389Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496857263863/TestTargetResult"; [2025-06-12T00:21:53.389Z] [2025-06-12T00:21:53.389Z] TEST SETUP: [2025-06-12T00:21:53.389Z] Nothing to be done for setup. [2025-06-12T00:21:53.389Z] [2025-06-12T00:21:53.389Z] TESTING: [2025-06-12T00:21:54.342Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-12T00:21:54.342Z] GC before operation: completed in 14.688 ms, heap usage 15.073 MB -> 4.203 MB. [2025-06-12T00:22:01.032Z] Camera thread performed 122 scans. [2025-06-12T00:22:01.032Z] ====== philosophers (scala) [default], iteration 0 completed (5991.945 ms) ====== [2025-06-12T00:22:01.032Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-12T00:22:01.032Z] GC before operation: completed in 16.344 ms, heap usage 5.398 MB -> 4.612 MB. [2025-06-12T00:22:07.118Z] Camera thread performed 122 scans. [2025-06-12T00:22:07.118Z] ====== philosophers (scala) [default], iteration 1 completed (5716.867 ms) ====== [2025-06-12T00:22:07.118Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-12T00:22:07.118Z] GC before operation: completed in 17.253 ms, heap usage 5.961 MB -> 4.615 MB. [2025-06-12T00:22:11.264Z] Camera thread performed 122 scans. [2025-06-12T00:22:11.264Z] ====== philosophers (scala) [default], iteration 2 completed (5178.980 ms) ====== [2025-06-12T00:22:11.264Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-12T00:22:11.264Z] GC before operation: completed in 17.365 ms, heap usage 35.609 MB -> 4.617 MB. [2025-06-12T00:22:16.653Z] Camera thread performed 122 scans. [2025-06-12T00:22:16.653Z] ====== philosophers (scala) [default], iteration 3 completed (5490.618 ms) ====== [2025-06-12T00:22:16.653Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-12T00:22:16.653Z] GC before operation: completed in 13.985 ms, heap usage 29.690 MB -> 4.617 MB. [2025-06-12T00:22:23.349Z] Camera thread performed 122 scans. [2025-06-12T00:22:23.349Z] ====== philosophers (scala) [default], iteration 4 completed (6247.622 ms) ====== [2025-06-12T00:22:23.349Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-12T00:22:23.349Z] GC before operation: completed in 16.069 ms, heap usage 19.335 MB -> 4.618 MB. [2025-06-12T00:22:28.714Z] Camera thread performed 122 scans. [2025-06-12T00:22:28.714Z] ====== philosophers (scala) [default], iteration 5 completed (5936.270 ms) ====== [2025-06-12T00:22:28.714Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-12T00:22:28.714Z] GC before operation: completed in 15.611 ms, heap usage 7.182 MB -> 4.618 MB. [2025-06-12T00:22:35.399Z] Camera thread performed 122 scans. [2025-06-12T00:22:35.399Z] ====== philosophers (scala) [default], iteration 6 completed (6159.645 ms) ====== [2025-06-12T00:22:35.399Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-12T00:22:35.400Z] GC before operation: completed in 17.694 ms, heap usage 22.719 MB -> 4.619 MB. [2025-06-12T00:22:40.766Z] Camera thread performed 122 scans. [2025-06-12T00:22:40.766Z] ====== philosophers (scala) [default], iteration 7 completed (5512.062 ms) ====== [2025-06-12T00:22:40.766Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-12T00:22:40.766Z] GC before operation: completed in 16.552 ms, heap usage 31.166 MB -> 4.619 MB. [2025-06-12T00:22:46.135Z] Camera thread performed 122 scans. [2025-06-12T00:22:46.135Z] ====== philosophers (scala) [default], iteration 8 completed (5706.417 ms) ====== [2025-06-12T00:22:46.135Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-12T00:22:46.135Z] GC before operation: completed in 16.372 ms, heap usage 6.421 MB -> 4.619 MB. [2025-06-12T00:22:51.498Z] Camera thread performed 122 scans. [2025-06-12T00:22:51.498Z] ====== philosophers (scala) [default], iteration 9 completed (4537.519 ms) ====== [2025-06-12T00:22:51.498Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-12T00:22:51.498Z] GC before operation: completed in 14.506 ms, heap usage 27.875 MB -> 4.619 MB. [2025-06-12T00:22:56.862Z] Camera thread performed 122 scans. [2025-06-12T00:22:56.862Z] ====== philosophers (scala) [default], iteration 10 completed (5535.495 ms) ====== [2025-06-12T00:22:56.862Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-12T00:22:56.862Z] GC before operation: completed in 17.652 ms, heap usage 13.982 MB -> 4.620 MB. [2025-06-12T00:23:02.238Z] Camera thread performed 122 scans. [2025-06-12T00:23:02.238Z] ====== philosophers (scala) [default], iteration 11 completed (5816.947 ms) ====== [2025-06-12T00:23:02.238Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-12T00:23:02.238Z] GC before operation: completed in 14.106 ms, heap usage 20.471 MB -> 4.621 MB. [2025-06-12T00:23:08.352Z] Camera thread performed 122 scans. [2025-06-12T00:23:08.352Z] ====== philosophers (scala) [default], iteration 12 completed (5863.931 ms) ====== [2025-06-12T00:23:08.352Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-12T00:23:08.352Z] GC before operation: completed in 12.539 ms, heap usage 23.506 MB -> 4.621 MB. [2025-06-12T00:23:12.497Z] Camera thread performed 122 scans. [2025-06-12T00:23:12.497Z] ====== philosophers (scala) [default], iteration 13 completed (4495.899 ms) ====== [2025-06-12T00:23:12.497Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-12T00:23:12.498Z] GC before operation: completed in 16.672 ms, heap usage 12.439 MB -> 4.621 MB. [2025-06-12T00:23:19.190Z] Camera thread performed 122 scans. [2025-06-12T00:23:19.190Z] ====== philosophers (scala) [default], iteration 14 completed (6272.254 ms) ====== [2025-06-12T00:23:19.190Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-12T00:23:19.190Z] GC before operation: completed in 17.513 ms, heap usage 11.853 MB -> 4.621 MB. [2025-06-12T00:23:23.341Z] Camera thread performed 122 scans. [2025-06-12T00:23:23.341Z] ====== philosophers (scala) [default], iteration 15 completed (4302.848 ms) ====== [2025-06-12T00:23:23.341Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-12T00:23:23.342Z] GC before operation: completed in 14.492 ms, heap usage 28.488 MB -> 4.621 MB. [2025-06-12T00:23:28.712Z] Camera thread performed 122 scans. [2025-06-12T00:23:28.712Z] ====== philosophers (scala) [default], iteration 16 completed (5439.320 ms) ====== [2025-06-12T00:23:28.712Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-12T00:23:28.712Z] GC before operation: completed in 15.613 ms, heap usage 21.202 MB -> 4.621 MB. [2025-06-12T00:23:34.078Z] Camera thread performed 122 scans. [2025-06-12T00:23:34.078Z] ====== philosophers (scala) [default], iteration 17 completed (5315.309 ms) ====== [2025-06-12T00:23:34.078Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-12T00:23:34.078Z] GC before operation: completed in 13.145 ms, heap usage 21.826 MB -> 4.621 MB. [2025-06-12T00:23:39.442Z] Camera thread performed 122 scans. [2025-06-12T00:23:39.442Z] ====== philosophers (scala) [default], iteration 18 completed (5782.083 ms) ====== [2025-06-12T00:23:39.442Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-12T00:23:39.442Z] GC before operation: completed in 19.633 ms, heap usage 20.257 MB -> 4.622 MB. [2025-06-12T00:23:46.126Z] Camera thread performed 122 scans. [2025-06-12T00:23:46.126Z] ====== philosophers (scala) [default], iteration 19 completed (6028.191 ms) ====== [2025-06-12T00:23:46.126Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-12T00:23:46.126Z] GC before operation: completed in 18.312 ms, heap usage 11.932 MB -> 4.621 MB. [2025-06-12T00:23:51.669Z] Camera thread performed 122 scans. [2025-06-12T00:23:51.669Z] ====== philosophers (scala) [default], iteration 20 completed (6209.835 ms) ====== [2025-06-12T00:23:51.669Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-12T00:23:51.669Z] GC before operation: completed in 17.353 ms, heap usage 8.160 MB -> 4.622 MB. [2025-06-12T00:23:58.394Z] Camera thread performed 122 scans. [2025-06-12T00:23:58.394Z] ====== philosophers (scala) [default], iteration 21 completed (5691.088 ms) ====== [2025-06-12T00:23:58.394Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-12T00:23:58.394Z] GC before operation: completed in 14.568 ms, heap usage 25.759 MB -> 4.622 MB. [2025-06-12T00:24:03.813Z] Camera thread performed 122 scans. [2025-06-12T00:24:03.813Z] ====== philosophers (scala) [default], iteration 22 completed (5989.258 ms) ====== [2025-06-12T00:24:03.813Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-12T00:24:03.813Z] GC before operation: completed in 16.215 ms, heap usage 15.708 MB -> 4.658 MB. [2025-06-12T00:24:09.882Z] Camera thread performed 122 scans. [2025-06-12T00:24:09.882Z] ====== philosophers (scala) [default], iteration 23 completed (5682.801 ms) ====== [2025-06-12T00:24:09.882Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-12T00:24:09.882Z] GC before operation: completed in 16.032 ms, heap usage 15.678 MB -> 4.622 MB. [2025-06-12T00:24:15.245Z] Camera thread performed 122 scans. [2025-06-12T00:24:15.245Z] ====== philosophers (scala) [default], iteration 24 completed (6159.394 ms) ====== [2025-06-12T00:24:15.245Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-12T00:24:15.245Z] GC before operation: completed in 16.732 ms, heap usage 14.415 MB -> 4.622 MB. [2025-06-12T00:24:20.608Z] Camera thread performed 122 scans. [2025-06-12T00:24:20.608Z] ====== philosophers (scala) [default], iteration 25 completed (5050.750 ms) ====== [2025-06-12T00:24:20.608Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-12T00:24:20.608Z] GC before operation: completed in 15.375 ms, heap usage 17.314 MB -> 4.622 MB. [2025-06-12T00:24:27.294Z] Camera thread performed 122 scans. [2025-06-12T00:24:27.294Z] ====== philosophers (scala) [default], iteration 26 completed (6155.780 ms) ====== [2025-06-12T00:24:27.294Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-12T00:24:27.294Z] GC before operation: completed in 16.271 ms, heap usage 9.390 MB -> 4.622 MB. [2025-06-12T00:24:32.662Z] Camera thread performed 122 scans. [2025-06-12T00:24:32.662Z] ====== philosophers (scala) [default], iteration 27 completed (5262.209 ms) ====== [2025-06-12T00:24:32.662Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-12T00:24:32.662Z] GC before operation: completed in 15.279 ms, heap usage 12.970 MB -> 4.622 MB. [2025-06-12T00:24:38.033Z] Camera thread performed 122 scans. [2025-06-12T00:24:38.033Z] ====== philosophers (scala) [default], iteration 28 completed (5087.689 ms) ====== [2025-06-12T00:24:38.033Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-12T00:24:38.033Z] GC before operation: completed in 12.942 ms, heap usage 5.838 MB -> 4.623 MB. [2025-06-12T00:24:43.891Z] Camera thread performed 122 scans. [2025-06-12T00:24:43.891Z] ====== philosophers (scala) [default], iteration 29 completed (6021.198 ms) ====== [2025-06-12T00:24:43.891Z] ----------------------------------- [2025-06-12T00:24:43.891Z] renaissance-philosophers_0_PASSED [2025-06-12T00:24:43.891Z] ----------------------------------- [2025-06-12T00:24:43.891Z] [2025-06-12T00:24:43.891Z] TEST TEARDOWN: [2025-06-12T00:24:43.891Z] Nothing to be done for teardown. [2025-06-12T00:24:43.891Z] renaissance-philosophers_0 Finish Time: Thu Jun 12 00:24:43 2025 Epoch Time (ms): 1749687883022