[2025-03-30T21:51:43.922Z] Running test renaissance-philosophers_0 ... [2025-03-30T21:51:43.922Z] =============================================== [2025-03-30T21:51:43.922Z] renaissance-philosophers_0 Start Time: Sun Mar 30 21:51:43 2025 Epoch Time (ms): 1743371503301 [2025-03-30T21:51:43.922Z] variation: NoOptions [2025-03-30T21:51:43.922Z] JVM_OPTIONS: [2025-03-30T21:51:43.922Z] { \ [2025-03-30T21:51:43.922Z] echo ""; echo "TEST SETUP:"; \ [2025-03-30T21:51:43.922Z] echo "Nothing to be done for setup."; \ [2025-03-30T21:51:43.922Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/renaissance-philosophers_0"; \ [2025-03-30T21:51:43.922Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/renaissance-philosophers_0"; \ [2025-03-30T21:51:43.922Z] echo ""; echo "TESTING:"; \ [2025-03-30T21:51:43.922Z] "/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_17433693453819/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-03-30T21:51:43.922Z] 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_17433693453819/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-30T21:51:43.922Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-30T21:51:43.922Z] echo "Nothing to be done for teardown."; \ [2025-03-30T21:51:43.922Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17433693453819/TestTargetResult"; [2025-03-30T21:51:43.922Z] [2025-03-30T21:51:43.922Z] TEST SETUP: [2025-03-30T21:51:43.922Z] Nothing to be done for setup. [2025-03-30T21:51:43.922Z] [2025-03-30T21:51:43.922Z] TESTING: [2025-03-30T21:51:45.508Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-03-30T21:51:45.508Z] GC before operation: completed in 29.040 ms, heap usage 9.269 MB -> 4.331 MB. [2025-03-30T21:51:53.786Z] Camera thread performed 122 scans. [2025-03-30T21:51:53.786Z] ====== philosophers (scala) [default], iteration 0 completed (8790.494 ms) ====== [2025-03-30T21:51:53.786Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-03-30T21:51:53.786Z] GC before operation: completed in 19.678 ms, heap usage 27.125 MB -> 4.773 MB. [2025-03-30T21:52:02.062Z] Camera thread performed 122 scans. [2025-03-30T21:52:02.062Z] ====== philosophers (scala) [default], iteration 1 completed (7192.103 ms) ====== [2025-03-30T21:52:02.062Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-03-30T21:52:02.062Z] GC before operation: completed in 17.558 ms, heap usage 15.799 MB -> 4.774 MB. [2025-03-30T21:52:08.919Z] Camera thread performed 122 scans. [2025-03-30T21:52:08.919Z] ====== philosophers (scala) [default], iteration 2 completed (7220.569 ms) ====== [2025-03-30T21:52:08.919Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-03-30T21:52:08.919Z] GC before operation: completed in 18.797 ms, heap usage 245.750 MB -> 4.775 MB. [2025-03-30T21:52:15.779Z] Camera thread performed 122 scans. [2025-03-30T21:52:15.779Z] ====== philosophers (scala) [default], iteration 3 completed (7341.107 ms) ====== [2025-03-30T21:52:15.779Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-03-30T21:52:15.779Z] GC before operation: completed in 19.719 ms, heap usage 53.773 MB -> 4.775 MB. [2025-03-30T21:52:24.047Z] Camera thread performed 122 scans. [2025-03-30T21:52:24.047Z] ====== philosophers (scala) [default], iteration 4 completed (7217.984 ms) ====== [2025-03-30T21:52:24.047Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-03-30T21:52:24.047Z] GC before operation: completed in 18.543 ms, heap usage 24.432 MB -> 4.776 MB. [2025-03-30T21:52:30.900Z] Camera thread performed 122 scans. [2025-03-30T21:52:30.900Z] ====== philosophers (scala) [default], iteration 5 completed (7281.631 ms) ====== [2025-03-30T21:52:30.900Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-03-30T21:52:30.900Z] GC before operation: completed in 18.916 ms, heap usage 104.842 MB -> 4.776 MB. [2025-03-30T21:52:37.757Z] Camera thread performed 122 scans. [2025-03-30T21:52:37.757Z] ====== philosophers (scala) [default], iteration 6 completed (7191.515 ms) ====== [2025-03-30T21:52:37.757Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-03-30T21:52:37.757Z] GC before operation: completed in 19.408 ms, heap usage 178.294 MB -> 4.776 MB. [2025-03-30T21:52:44.618Z] Camera thread performed 122 scans. [2025-03-30T21:52:44.618Z] ====== philosophers (scala) [default], iteration 7 completed (7024.466 ms) ====== [2025-03-30T21:52:44.618Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-03-30T21:52:44.618Z] GC before operation: completed in 18.929 ms, heap usage 194.568 MB -> 4.776 MB. [2025-03-30T21:52:52.886Z] Camera thread performed 122 scans. [2025-03-30T21:52:52.886Z] ====== philosophers (scala) [default], iteration 8 completed (7267.312 ms) ====== [2025-03-30T21:52:52.886Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-03-30T21:52:52.886Z] GC before operation: completed in 19.637 ms, heap usage 66.660 MB -> 4.776 MB. [2025-03-30T21:52:59.744Z] Camera thread performed 122 scans. [2025-03-30T21:52:59.744Z] ====== philosophers (scala) [default], iteration 9 completed (7192.443 ms) ====== [2025-03-30T21:52:59.744Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-03-30T21:52:59.744Z] GC before operation: completed in 19.988 ms, heap usage 252.309 MB -> 4.776 MB. [2025-03-30T21:53:06.601Z] Camera thread performed 122 scans. [2025-03-30T21:53:06.601Z] ====== philosophers (scala) [default], iteration 10 completed (7379.502 ms) ====== [2025-03-30T21:53:06.601Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-03-30T21:53:06.601Z] GC before operation: completed in 18.497 ms, heap usage 102.349 MB -> 4.776 MB. [2025-03-30T21:53:13.463Z] Camera thread performed 122 scans. [2025-03-30T21:53:13.463Z] ====== philosophers (scala) [default], iteration 11 completed (7308.431 ms) ====== [2025-03-30T21:53:13.463Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-03-30T21:53:14.231Z] GC before operation: completed in 19.962 ms, heap usage 103.519 MB -> 4.776 MB. [2025-03-30T21:53:21.093Z] Camera thread performed 122 scans. [2025-03-30T21:53:21.093Z] ====== philosophers (scala) [default], iteration 12 completed (7372.660 ms) ====== [2025-03-30T21:53:21.093Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-03-30T21:53:21.093Z] GC before operation: completed in 20.199 ms, heap usage 104.676 MB -> 4.776 MB. [2025-03-30T21:53:27.953Z] Camera thread performed 122 scans. [2025-03-30T21:53:27.953Z] ====== philosophers (scala) [default], iteration 13 completed (7023.112 ms) ====== [2025-03-30T21:53:27.953Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-03-30T21:53:27.953Z] GC before operation: completed in 19.293 ms, heap usage 168.778 MB -> 4.776 MB. [2025-03-30T21:53:36.225Z] Camera thread performed 122 scans. [2025-03-30T21:53:36.225Z] ====== philosophers (scala) [default], iteration 14 completed (7298.770 ms) ====== [2025-03-30T21:53:36.225Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-03-30T21:53:36.225Z] GC before operation: completed in 20.199 ms, heap usage 115.781 MB -> 4.777 MB. [2025-03-30T21:53:43.086Z] Camera thread performed 122 scans. [2025-03-30T21:53:43.086Z] ====== philosophers (scala) [default], iteration 15 completed (7279.201 ms) ====== [2025-03-30T21:53:43.086Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-03-30T21:53:43.086Z] GC before operation: completed in 18.950 ms, heap usage 138.642 MB -> 4.777 MB. [2025-03-30T21:53:49.951Z] Camera thread performed 122 scans. [2025-03-30T21:53:49.951Z] ====== philosophers (scala) [default], iteration 16 completed (7196.390 ms) ====== [2025-03-30T21:53:49.951Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-03-30T21:53:49.951Z] GC before operation: completed in 18.447 ms, heap usage 58.093 MB -> 4.777 MB. [2025-03-30T21:53:58.227Z] Camera thread performed 122 scans. [2025-03-30T21:53:58.227Z] ====== philosophers (scala) [default], iteration 17 completed (7166.230 ms) ====== [2025-03-30T21:53:58.227Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-03-30T21:53:58.227Z] GC before operation: completed in 18.918 ms, heap usage 70.104 MB -> 4.777 MB. [2025-03-30T21:54:05.091Z] Camera thread performed 122 scans. [2025-03-30T21:54:05.091Z] ====== philosophers (scala) [default], iteration 18 completed (6978.248 ms) ====== [2025-03-30T21:54:05.091Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-03-30T21:54:05.091Z] GC before operation: completed in 19.455 ms, heap usage 250.286 MB -> 4.777 MB. [2025-03-30T21:54:11.949Z] Camera thread performed 122 scans. [2025-03-30T21:54:11.950Z] ====== philosophers (scala) [default], iteration 19 completed (7225.573 ms) ====== [2025-03-30T21:54:11.950Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-03-30T21:54:11.950Z] GC before operation: completed in 21.852 ms, heap usage 188.921 MB -> 4.777 MB. [2025-03-30T21:54:18.809Z] Camera thread performed 122 scans. [2025-03-30T21:54:18.809Z] ====== philosophers (scala) [default], iteration 20 completed (7238.447 ms) ====== [2025-03-30T21:54:18.809Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-03-30T21:54:18.809Z] GC before operation: completed in 20.763 ms, heap usage 141.499 MB -> 4.777 MB. [2025-03-30T21:54:25.671Z] Camera thread performed 122 scans. [2025-03-30T21:54:25.671Z] ====== philosophers (scala) [default], iteration 21 completed (7151.526 ms) ====== [2025-03-30T21:54:25.671Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-03-30T21:54:25.671Z] GC before operation: completed in 21.150 ms, heap usage 170.334 MB -> 4.777 MB. [2025-03-30T21:54:33.943Z] Camera thread performed 122 scans. [2025-03-30T21:54:33.943Z] ====== philosophers (scala) [default], iteration 22 completed (7085.311 ms) ====== [2025-03-30T21:54:33.943Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-03-30T21:54:33.943Z] GC before operation: completed in 20.117 ms, heap usage 174.102 MB -> 4.777 MB. [2025-03-30T21:54:40.805Z] Camera thread performed 122 scans. [2025-03-30T21:54:40.805Z] ====== philosophers (scala) [default], iteration 23 completed (7262.808 ms) ====== [2025-03-30T21:54:40.805Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-03-30T21:54:40.805Z] GC before operation: completed in 18.508 ms, heap usage 77.823 MB -> 4.777 MB. [2025-03-30T21:54:47.664Z] Camera thread performed 122 scans. [2025-03-30T21:54:47.664Z] ====== philosophers (scala) [default], iteration 24 completed (7156.023 ms) ====== [2025-03-30T21:54:47.664Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-03-30T21:54:47.664Z] GC before operation: completed in 20.007 ms, heap usage 226.260 MB -> 4.777 MB. [2025-03-30T21:54:54.477Z] Camera thread performed 122 scans. [2025-03-30T21:54:54.477Z] ====== philosophers (scala) [default], iteration 25 completed (7221.839 ms) ====== [2025-03-30T21:54:54.477Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-03-30T21:54:54.477Z] GC before operation: completed in 18.586 ms, heap usage 129.157 MB -> 4.777 MB. [2025-03-30T21:55:02.905Z] Camera thread performed 122 scans. [2025-03-30T21:55:02.906Z] ====== philosophers (scala) [default], iteration 26 completed (7172.271 ms) ====== [2025-03-30T21:55:02.906Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-03-30T21:55:02.906Z] GC before operation: completed in 21.242 ms, heap usage 61.826 MB -> 4.777 MB. [2025-03-30T21:55:09.765Z] Camera thread performed 122 scans. [2025-03-30T21:55:09.765Z] ====== philosophers (scala) [default], iteration 27 completed (7320.472 ms) ====== [2025-03-30T21:55:09.765Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-03-30T21:55:09.766Z] GC before operation: completed in 20.162 ms, heap usage 120.093 MB -> 4.777 MB. [2025-03-30T21:55:16.627Z] Camera thread performed 122 scans. [2025-03-30T21:55:16.627Z] ====== philosophers (scala) [default], iteration 28 completed (7111.904 ms) ====== [2025-03-30T21:55:16.627Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-03-30T21:55:16.627Z] GC before operation: completed in 19.192 ms, heap usage 108.767 MB -> 4.777 MB. [2025-03-30T21:55:24.896Z] Camera thread performed 122 scans. [2025-03-30T21:55:24.896Z] ====== philosophers (scala) [default], iteration 29 completed (7474.958 ms) ====== [2025-03-30T21:55:24.896Z] ----------------------------------- [2025-03-30T21:55:24.896Z] renaissance-philosophers_0_PASSED [2025-03-30T21:55:24.896Z] ----------------------------------- [2025-03-30T21:55:24.896Z] [2025-03-30T21:55:24.896Z] TEST TEARDOWN: [2025-03-30T21:55:24.896Z] Nothing to be done for teardown. [2025-03-30T21:55:24.896Z] renaissance-philosophers_0 Finish Time: Sun Mar 30 21:55:23 2025 Epoch Time (ms): 1743371723716