[2025-05-29T02:54:56.402Z] Running test renaissance-philosophers_0 ... [2025-05-29T02:54:56.402Z] =============================================== [2025-05-29T02:54:56.402Z] renaissance-philosophers_0 Start Time: Thu May 29 02:54:56 2025 Epoch Time (ms): 1748487296054 [2025-05-29T02:54:56.402Z] variation: NoOptions [2025-05-29T02:54:56.402Z] JVM_OPTIONS: [2025-05-29T02:54:56.402Z] { \ [2025-05-29T02:54:56.402Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T02:54:56.402Z] echo "Nothing to be done for setup."; \ [2025-05-29T02:54:56.402Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-philosophers_0"; \ [2025-05-29T02:54:56.402Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-philosophers_0"; \ [2025-05-29T02:54:56.402Z] echo ""; echo "TESTING:"; \ [2025-05-29T02:54:56.402Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-29T02:54:56.402Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T02:54:56.402Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T02:54:56.402Z] echo "Nothing to be done for teardown."; \ [2025-05-29T02:54:56.402Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/TestTargetResult"; [2025-05-29T02:54:56.402Z] [2025-05-29T02:54:56.402Z] TEST SETUP: [2025-05-29T02:54:56.402Z] Nothing to be done for setup. [2025-05-29T02:54:56.402Z] [2025-05-29T02:54:56.402Z] TESTING: [2025-05-29T02:54:57.982Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-29T02:54:57.982Z] GC before operation: completed in 27.296 ms, heap usage 8.360 MB -> 4.341 MB. [2025-05-29T02:55:09.631Z] Camera thread performed 122 scans. [2025-05-29T02:55:09.631Z] ====== philosophers (scala) [default], iteration 0 completed (11196.299 ms) ====== [2025-05-29T02:55:09.631Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-29T02:55:09.631Z] GC before operation: completed in 18.362 ms, heap usage 144.671 MB -> 4.783 MB. [2025-05-29T02:55:16.458Z] Camera thread performed 122 scans. [2025-05-29T02:55:16.458Z] ====== philosophers (scala) [default], iteration 1 completed (6755.755 ms) ====== [2025-05-29T02:55:16.458Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-29T02:55:16.458Z] GC before operation: completed in 19.066 ms, heap usage 61.170 MB -> 4.787 MB. [2025-05-29T02:55:23.578Z] Camera thread performed 122 scans. [2025-05-29T02:55:23.578Z] ====== philosophers (scala) [default], iteration 2 completed (7091.445 ms) ====== [2025-05-29T02:55:23.578Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-29T02:55:23.578Z] GC before operation: completed in 21.016 ms, heap usage 126.809 MB -> 4.789 MB. [2025-05-29T02:55:30.407Z] Camera thread performed 122 scans. [2025-05-29T02:55:30.407Z] ====== philosophers (scala) [default], iteration 3 completed (6774.049 ms) ====== [2025-05-29T02:55:30.407Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-29T02:55:30.407Z] GC before operation: completed in 19.780 ms, heap usage 193.789 MB -> 4.789 MB. [2025-05-29T02:55:37.237Z] Camera thread performed 122 scans. [2025-05-29T02:55:37.237Z] ====== philosophers (scala) [default], iteration 4 completed (6680.159 ms) ====== [2025-05-29T02:55:37.237Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-29T02:55:37.237Z] GC before operation: completed in 18.291 ms, heap usage 110.383 MB -> 4.790 MB. [2025-05-29T02:55:44.088Z] Camera thread performed 122 scans. [2025-05-29T02:55:44.088Z] ====== philosophers (scala) [default], iteration 5 completed (6998.629 ms) ====== [2025-05-29T02:55:44.088Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-29T02:55:44.088Z] GC before operation: completed in 20.197 ms, heap usage 194.643 MB -> 4.789 MB. [2025-05-29T02:55:50.997Z] Camera thread performed 122 scans. [2025-05-29T02:55:50.997Z] ====== philosophers (scala) [default], iteration 6 completed (6839.132 ms) ====== [2025-05-29T02:55:50.997Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-29T02:55:50.997Z] GC before operation: completed in 19.487 ms, heap usage 153.184 MB -> 4.790 MB. [2025-05-29T02:55:57.827Z] Camera thread performed 122 scans. [2025-05-29T02:55:57.827Z] ====== philosophers (scala) [default], iteration 7 completed (7408.573 ms) ====== [2025-05-29T02:55:57.827Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-29T02:55:57.827Z] GC before operation: completed in 23.718 ms, heap usage 117.909 MB -> 4.789 MB. [2025-05-29T02:56:04.657Z] Camera thread performed 122 scans. [2025-05-29T02:56:04.657Z] ====== philosophers (scala) [default], iteration 8 completed (6827.537 ms) ====== [2025-05-29T02:56:04.657Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-29T02:56:04.657Z] GC before operation: completed in 18.574 ms, heap usage 61.202 MB -> 4.790 MB. [2025-05-29T02:56:11.484Z] Camera thread performed 122 scans. [2025-05-29T02:56:11.484Z] ====== philosophers (scala) [default], iteration 9 completed (6973.151 ms) ====== [2025-05-29T02:56:11.484Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-29T02:56:11.484Z] GC before operation: completed in 21.776 ms, heap usage 155.494 MB -> 4.790 MB. [2025-05-29T02:56:18.314Z] Camera thread performed 122 scans. [2025-05-29T02:56:18.314Z] ====== philosophers (scala) [default], iteration 10 completed (6772.242 ms) ====== [2025-05-29T02:56:18.314Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-29T02:56:18.314Z] GC before operation: completed in 19.938 ms, heap usage 105.775 MB -> 4.790 MB. [2025-05-29T02:56:25.146Z] Camera thread performed 122 scans. [2025-05-29T02:56:25.146Z] ====== philosophers (scala) [default], iteration 11 completed (6906.271 ms) ====== [2025-05-29T02:56:25.147Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-29T02:56:25.147Z] GC before operation: completed in 19.867 ms, heap usage 225.745 MB -> 4.790 MB. [2025-05-29T02:56:31.976Z] Camera thread performed 122 scans. [2025-05-29T02:56:31.976Z] ====== philosophers (scala) [default], iteration 12 completed (6872.153 ms) ====== [2025-05-29T02:56:31.976Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-29T02:56:32.925Z] GC before operation: completed in 19.059 ms, heap usage 96.556 MB -> 4.790 MB. [2025-05-29T02:56:39.916Z] Camera thread performed 122 scans. [2025-05-29T02:56:39.916Z] ====== philosophers (scala) [default], iteration 13 completed (6898.404 ms) ====== [2025-05-29T02:56:39.916Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-29T02:56:39.916Z] GC before operation: completed in 19.387 ms, heap usage 238.730 MB -> 4.790 MB. [2025-05-29T02:56:46.897Z] Camera thread performed 122 scans. [2025-05-29T02:56:46.897Z] ====== philosophers (scala) [default], iteration 14 completed (6863.035 ms) ====== [2025-05-29T02:56:46.897Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-29T02:56:46.897Z] GC before operation: completed in 21.842 ms, heap usage 236.817 MB -> 4.790 MB. [2025-05-29T02:56:53.726Z] Camera thread performed 122 scans. [2025-05-29T02:56:53.726Z] ====== philosophers (scala) [default], iteration 15 completed (7086.184 ms) ====== [2025-05-29T02:56:53.726Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-29T02:56:53.726Z] GC before operation: completed in 22.160 ms, heap usage 70.811 MB -> 4.790 MB. [2025-05-29T02:57:00.553Z] Camera thread performed 122 scans. [2025-05-29T02:57:00.553Z] ====== philosophers (scala) [default], iteration 16 completed (7153.597 ms) ====== [2025-05-29T02:57:00.553Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-29T02:57:00.553Z] GC before operation: completed in 21.570 ms, heap usage 137.456 MB -> 4.790 MB. [2025-05-29T02:57:07.388Z] Camera thread performed 122 scans. [2025-05-29T02:57:07.388Z] ====== philosophers (scala) [default], iteration 17 completed (6935.755 ms) ====== [2025-05-29T02:57:07.388Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-29T02:57:07.388Z] GC before operation: completed in 19.071 ms, heap usage 54.355 MB -> 4.790 MB. [2025-05-29T02:57:15.622Z] Camera thread performed 122 scans. [2025-05-29T02:57:15.622Z] ====== philosophers (scala) [default], iteration 18 completed (7209.138 ms) ====== [2025-05-29T02:57:15.622Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-29T02:57:15.622Z] GC before operation: completed in 21.877 ms, heap usage 142.726 MB -> 4.791 MB. [2025-05-29T02:57:21.206Z] Camera thread performed 122 scans. [2025-05-29T02:57:21.206Z] ====== philosophers (scala) [default], iteration 19 completed (6939.090 ms) ====== [2025-05-29T02:57:21.206Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-29T02:57:21.970Z] GC before operation: completed in 22.118 ms, heap usage 270.596 MB -> 4.790 MB. [2025-05-29T02:57:28.798Z] Camera thread performed 122 scans. [2025-05-29T02:57:28.798Z] ====== philosophers (scala) [default], iteration 20 completed (7016.270 ms) ====== [2025-05-29T02:57:28.798Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-29T02:57:28.798Z] GC before operation: completed in 20.794 ms, heap usage 142.287 MB -> 4.791 MB. [2025-05-29T02:57:35.621Z] Camera thread performed 122 scans. [2025-05-29T02:57:35.621Z] ====== philosophers (scala) [default], iteration 21 completed (6845.460 ms) ====== [2025-05-29T02:57:35.621Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-29T02:57:35.621Z] GC before operation: completed in 22.509 ms, heap usage 249.887 MB -> 4.791 MB. [2025-05-29T02:57:42.452Z] Camera thread performed 122 scans. [2025-05-29T02:57:42.452Z] ====== philosophers (scala) [default], iteration 22 completed (6912.597 ms) ====== [2025-05-29T02:57:42.452Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-29T02:57:42.452Z] GC before operation: completed in 18.374 ms, heap usage 34.114 MB -> 4.791 MB. [2025-05-29T02:57:49.628Z] Camera thread performed 122 scans. [2025-05-29T02:57:49.628Z] ====== philosophers (scala) [default], iteration 23 completed (7066.880 ms) ====== [2025-05-29T02:57:49.628Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-29T02:57:49.628Z] GC before operation: completed in 19.868 ms, heap usage 153.115 MB -> 4.791 MB. [2025-05-29T02:57:56.486Z] Camera thread performed 122 scans. [2025-05-29T02:57:56.487Z] ====== philosophers (scala) [default], iteration 24 completed (6868.199 ms) ====== [2025-05-29T02:57:56.487Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-29T02:57:56.487Z] GC before operation: completed in 18.618 ms, heap usage 13.242 MB -> 4.791 MB. [2025-05-29T02:58:03.329Z] Camera thread performed 122 scans. [2025-05-29T02:58:03.329Z] ====== philosophers (scala) [default], iteration 25 completed (6864.725 ms) ====== [2025-05-29T02:58:03.329Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-29T02:58:03.329Z] GC before operation: completed in 19.397 ms, heap usage 134.074 MB -> 4.791 MB. [2025-05-29T02:58:10.328Z] Camera thread performed 122 scans. [2025-05-29T02:58:10.328Z] ====== philosophers (scala) [default], iteration 26 completed (7035.499 ms) ====== [2025-05-29T02:58:10.328Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-29T02:58:10.328Z] GC before operation: completed in 18.435 ms, heap usage 12.497 MB -> 4.791 MB. [2025-05-29T02:58:17.157Z] Camera thread performed 122 scans. [2025-05-29T02:58:17.157Z] ====== philosophers (scala) [default], iteration 27 completed (7089.721 ms) ====== [2025-05-29T02:58:17.157Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-29T02:58:17.157Z] GC before operation: completed in 19.250 ms, heap usage 134.565 MB -> 4.791 MB. [2025-05-29T02:58:25.394Z] Camera thread performed 122 scans. [2025-05-29T02:58:25.394Z] ====== philosophers (scala) [default], iteration 28 completed (6915.804 ms) ====== [2025-05-29T02:58:25.394Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-29T02:58:25.394Z] GC before operation: completed in 18.096 ms, heap usage 124.512 MB -> 4.791 MB. [2025-05-29T02:58:31.008Z] Camera thread performed 122 scans. [2025-05-29T02:58:31.008Z] ====== philosophers (scala) [default], iteration 29 completed (6928.455 ms) ====== [2025-05-29T02:58:31.770Z] ----------------------------------- [2025-05-29T02:58:31.770Z] renaissance-philosophers_0_PASSED [2025-05-29T02:58:31.770Z] ----------------------------------- [2025-05-29T02:58:31.770Z] [2025-05-29T02:58:31.770Z] TEST TEARDOWN: [2025-05-29T02:58:31.770Z] Nothing to be done for teardown. [2025-05-29T02:58:31.770Z] renaissance-philosophers_0 Finish Time: Thu May 29 02:58:31 2025 Epoch Time (ms): 1748487511107