[2025-09-04T07:45:21.029Z] Running test renaissance-philosophers_0 ... [2025-09-04T07:45:21.029Z] =============================================== [2025-09-04T07:45:21.029Z] renaissance-philosophers_0 Start Time: Thu Sep 4 07:45:20 2025 Epoch Time (ms): 1756971920119 [2025-09-04T07:45:21.029Z] variation: NoOptions [2025-09-04T07:45:21.029Z] JVM_OPTIONS: [2025-09-04T07:45:21.029Z] { \ [2025-09-04T07:45:21.029Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T07:45:21.029Z] echo "Nothing to be done for setup."; \ [2025-09-04T07:45:21.029Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17569705985719/renaissance-philosophers_0"; \ [2025-09-04T07:45:21.029Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17569705985719/renaissance-philosophers_0"; \ [2025-09-04T07:45:21.029Z] echo ""; echo "TESTING:"; \ [2025-09-04T07:45:21.029Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_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_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17569705985719/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-04T07:45:21.029Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17569705985719/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T07:45:21.029Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T07:45:21.029Z] echo "Nothing to be done for teardown."; \ [2025-09-04T07:45:21.029Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17569705985719/TestTargetResult"; [2025-09-04T07:45:21.029Z] [2025-09-04T07:45:21.029Z] TEST SETUP: [2025-09-04T07:45:21.029Z] Nothing to be done for setup. [2025-09-04T07:45:21.029Z] [2025-09-04T07:45:21.029Z] TESTING: [2025-09-04T07:45:21.856Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-04T07:45:21.856Z] GC before operation: completed in 23.818 ms, heap usage 7.241 MB -> 4.316 MB. [2025-09-04T07:45:31.957Z] Camera thread performed 122 scans. [2025-09-04T07:45:31.957Z] ====== philosophers (scala) [default], iteration 0 completed (9408.576 ms) ====== [2025-09-04T07:45:31.957Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-04T07:45:31.957Z] GC before operation: completed in 14.893 ms, heap usage 226.650 MB -> 4.751 MB. [2025-09-04T07:45:38.858Z] Camera thread performed 122 scans. [2025-09-04T07:45:38.858Z] ====== philosophers (scala) [default], iteration 1 completed (7229.111 ms) ====== [2025-09-04T07:45:38.858Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-04T07:45:38.858Z] GC before operation: completed in 13.214 ms, heap usage 33.754 MB -> 4.754 MB. [2025-09-04T07:45:45.764Z] Camera thread performed 122 scans. [2025-09-04T07:45:45.764Z] ====== philosophers (scala) [default], iteration 2 completed (7490.972 ms) ====== [2025-09-04T07:45:45.764Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-04T07:45:45.764Z] GC before operation: completed in 13.417 ms, heap usage 48.683 MB -> 4.756 MB. [2025-09-04T07:45:54.089Z] Camera thread performed 122 scans. [2025-09-04T07:45:54.089Z] ====== philosophers (scala) [default], iteration 3 completed (7479.606 ms) ====== [2025-09-04T07:45:54.089Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-04T07:45:54.089Z] GC before operation: completed in 13.572 ms, heap usage 69.501 MB -> 4.756 MB. [2025-09-04T07:46:01.186Z] Camera thread performed 122 scans. [2025-09-04T07:46:01.186Z] ====== philosophers (scala) [default], iteration 4 completed (7330.774 ms) ====== [2025-09-04T07:46:01.186Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-04T07:46:01.186Z] GC before operation: completed in 12.282 ms, heap usage 61.356 MB -> 4.756 MB. [2025-09-04T07:46:08.098Z] Camera thread performed 122 scans. [2025-09-04T07:46:08.098Z] ====== philosophers (scala) [default], iteration 5 completed (7615.490 ms) ====== [2025-09-04T07:46:08.098Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-04T07:46:08.098Z] GC before operation: completed in 12.280 ms, heap usage 41.456 MB -> 4.756 MB. [2025-09-04T07:46:16.421Z] Camera thread performed 122 scans. [2025-09-04T07:46:16.421Z] ====== philosophers (scala) [default], iteration 6 completed (7462.939 ms) ====== [2025-09-04T07:46:16.421Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-04T07:46:16.421Z] GC before operation: completed in 12.032 ms, heap usage 204.095 MB -> 4.756 MB. [2025-09-04T07:46:23.329Z] Camera thread performed 122 scans. [2025-09-04T07:46:23.329Z] ====== philosophers (scala) [default], iteration 7 completed (7304.680 ms) ====== [2025-09-04T07:46:23.329Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-04T07:46:23.329Z] GC before operation: completed in 13.842 ms, heap usage 77.882 MB -> 4.756 MB. [2025-09-04T07:46:30.315Z] Camera thread performed 122 scans. [2025-09-04T07:46:30.315Z] ====== philosophers (scala) [default], iteration 8 completed (7307.128 ms) ====== [2025-09-04T07:46:30.315Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-04T07:46:30.315Z] GC before operation: completed in 14.024 ms, heap usage 198.073 MB -> 4.756 MB. [2025-09-04T07:46:38.638Z] Camera thread performed 122 scans. [2025-09-04T07:46:38.638Z] ====== philosophers (scala) [default], iteration 9 completed (7521.682 ms) ====== [2025-09-04T07:46:38.638Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-04T07:46:38.638Z] GC before operation: completed in 12.448 ms, heap usage 28.678 MB -> 4.756 MB. [2025-09-04T07:46:45.545Z] Camera thread performed 122 scans. [2025-09-04T07:46:45.545Z] ====== philosophers (scala) [default], iteration 10 completed (7399.010 ms) ====== [2025-09-04T07:46:45.545Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-04T07:46:45.545Z] GC before operation: completed in 12.882 ms, heap usage 87.937 MB -> 4.756 MB. [2025-09-04T07:46:53.871Z] Camera thread performed 122 scans. [2025-09-04T07:46:53.871Z] ====== philosophers (scala) [default], iteration 11 completed (7504.662 ms) ====== [2025-09-04T07:46:53.871Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-04T07:46:53.871Z] GC before operation: completed in 12.476 ms, heap usage 153.917 MB -> 4.757 MB. [2025-09-04T07:47:00.862Z] Camera thread performed 122 scans. [2025-09-04T07:47:00.862Z] ====== philosophers (scala) [default], iteration 12 completed (7427.325 ms) ====== [2025-09-04T07:47:00.862Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-04T07:47:00.862Z] GC before operation: completed in 13.372 ms, heap usage 81.527 MB -> 4.756 MB. [2025-09-04T07:47:07.764Z] Camera thread performed 122 scans. [2025-09-04T07:47:07.764Z] ====== philosophers (scala) [default], iteration 13 completed (7384.045 ms) ====== [2025-09-04T07:47:07.764Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-04T07:47:07.764Z] GC before operation: completed in 13.966 ms, heap usage 56.601 MB -> 4.756 MB. [2025-09-04T07:47:16.087Z] Camera thread performed 122 scans. [2025-09-04T07:47:16.087Z] ====== philosophers (scala) [default], iteration 14 completed (7456.990 ms) ====== [2025-09-04T07:47:16.087Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-04T07:47:16.087Z] GC before operation: completed in 13.568 ms, heap usage 78.927 MB -> 4.757 MB. [2025-09-04T07:47:23.036Z] Camera thread performed 122 scans. [2025-09-04T07:47:23.036Z] ====== philosophers (scala) [default], iteration 15 completed (7435.941 ms) ====== [2025-09-04T07:47:23.036Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-04T07:47:23.036Z] GC before operation: completed in 12.426 ms, heap usage 63.122 MB -> 4.757 MB. [2025-09-04T07:47:30.040Z] Camera thread performed 122 scans. [2025-09-04T07:47:30.040Z] ====== philosophers (scala) [default], iteration 16 completed (7472.724 ms) ====== [2025-09-04T07:47:30.040Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-04T07:47:30.040Z] GC before operation: completed in 13.043 ms, heap usage 23.511 MB -> 4.757 MB. [2025-09-04T07:47:38.368Z] Camera thread performed 122 scans. [2025-09-04T07:47:38.368Z] ====== philosophers (scala) [default], iteration 17 completed (7432.777 ms) ====== [2025-09-04T07:47:38.368Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-04T07:47:38.368Z] GC before operation: completed in 13.334 ms, heap usage 185.482 MB -> 4.757 MB. [2025-09-04T07:47:45.269Z] Camera thread performed 122 scans. [2025-09-04T07:47:45.269Z] ====== philosophers (scala) [default], iteration 18 completed (7501.586 ms) ====== [2025-09-04T07:47:45.269Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-04T07:47:45.269Z] GC before operation: completed in 14.535 ms, heap usage 162.031 MB -> 4.757 MB. [2025-09-04T07:47:53.591Z] Camera thread performed 122 scans. [2025-09-04T07:47:53.591Z] ====== philosophers (scala) [default], iteration 19 completed (7388.501 ms) ====== [2025-09-04T07:47:53.591Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-04T07:47:53.591Z] GC before operation: completed in 12.599 ms, heap usage 71.753 MB -> 4.757 MB. [2025-09-04T07:48:00.520Z] Camera thread performed 122 scans. [2025-09-04T07:48:00.520Z] ====== philosophers (scala) [default], iteration 20 completed (7473.603 ms) ====== [2025-09-04T07:48:00.520Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-04T07:48:00.520Z] GC before operation: completed in 14.442 ms, heap usage 121.756 MB -> 4.757 MB. [2025-09-04T07:48:07.422Z] Camera thread performed 122 scans. [2025-09-04T07:48:07.422Z] ====== philosophers (scala) [default], iteration 21 completed (7468.549 ms) ====== [2025-09-04T07:48:07.422Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-04T07:48:07.422Z] GC before operation: completed in 15.162 ms, heap usage 210.026 MB -> 4.757 MB. [2025-09-04T07:48:15.935Z] Camera thread performed 122 scans. [2025-09-04T07:48:15.935Z] ====== philosophers (scala) [default], iteration 22 completed (7481.138 ms) ====== [2025-09-04T07:48:15.935Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-04T07:48:15.935Z] GC before operation: completed in 12.794 ms, heap usage 155.266 MB -> 4.757 MB. [2025-09-04T07:48:23.014Z] Camera thread performed 122 scans. [2025-09-04T07:48:23.014Z] ====== philosophers (scala) [default], iteration 23 completed (7270.136 ms) ====== [2025-09-04T07:48:23.014Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-04T07:48:23.014Z] GC before operation: completed in 14.812 ms, heap usage 156.905 MB -> 4.757 MB. [2025-09-04T07:48:29.956Z] Camera thread performed 122 scans. [2025-09-04T07:48:29.956Z] ====== philosophers (scala) [default], iteration 24 completed (7478.240 ms) ====== [2025-09-04T07:48:29.956Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-04T07:48:29.956Z] GC before operation: completed in 12.982 ms, heap usage 161.098 MB -> 4.757 MB. [2025-09-04T07:48:36.856Z] Camera thread performed 122 scans. [2025-09-04T07:48:36.856Z] ====== philosophers (scala) [default], iteration 25 completed (7263.444 ms) ====== [2025-09-04T07:48:36.856Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-04T07:48:36.856Z] GC before operation: completed in 13.281 ms, heap usage 121.060 MB -> 4.757 MB. [2025-09-04T07:48:45.179Z] Camera thread performed 122 scans. [2025-09-04T07:48:45.179Z] ====== philosophers (scala) [default], iteration 26 completed (7404.139 ms) ====== [2025-09-04T07:48:45.179Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-04T07:48:45.179Z] GC before operation: completed in 14.783 ms, heap usage 86.622 MB -> 4.757 MB. [2025-09-04T07:48:52.090Z] Camera thread performed 122 scans. [2025-09-04T07:48:52.090Z] ====== philosophers (scala) [default], iteration 27 completed (7332.299 ms) ====== [2025-09-04T07:48:52.090Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-04T07:48:52.090Z] GC before operation: completed in 13.780 ms, heap usage 98.193 MB -> 4.757 MB. [2025-09-04T07:48:59.024Z] Camera thread performed 122 scans. [2025-09-04T07:48:59.024Z] ====== philosophers (scala) [default], iteration 28 completed (7445.917 ms) ====== [2025-09-04T07:48:59.024Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-04T07:48:59.024Z] GC before operation: completed in 13.149 ms, heap usage 97.738 MB -> 4.757 MB. [2025-09-04T07:49:07.349Z] Camera thread performed 122 scans. [2025-09-04T07:49:07.349Z] ====== philosophers (scala) [default], iteration 29 completed (7474.468 ms) ====== [2025-09-04T07:49:07.349Z] ----------------------------------- [2025-09-04T07:49:07.349Z] renaissance-philosophers_0_PASSED [2025-09-04T07:49:07.349Z] ----------------------------------- [2025-09-04T07:49:07.349Z] [2025-09-04T07:49:07.349Z] TEST TEARDOWN: [2025-09-04T07:49:07.349Z] Nothing to be done for teardown. [2025-09-04T07:49:07.349Z] renaissance-philosophers_0 Finish Time: Thu Sep 4 07:49:06 2025 Epoch Time (ms): 1756972146592