[2025-05-21T19:53:16.285Z] Running test renaissance-philosophers_0 ... [2025-05-21T19:53:16.285Z] =============================================== [2025-05-21T19:53:16.285Z] renaissance-philosophers_0 Start Time: Wed May 21 19:53:15 2025 Epoch Time (ms): 1747857195875 [2025-05-21T19:53:16.285Z] variation: NoOptions [2025-05-21T19:53:16.285Z] JVM_OPTIONS: [2025-05-21T19:53:16.285Z] { \ [2025-05-21T19:53:16.285Z] echo ""; echo "TEST SETUP:"; \ [2025-05-21T19:53:16.285Z] echo "Nothing to be done for setup."; \ [2025-05-21T19:53:16.285Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478567742722/renaissance-philosophers_0"; \ [2025-05-21T19:53:16.285Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478567742722/renaissance-philosophers_0"; \ [2025-05-21T19:53:16.285Z] echo ""; echo "TESTING:"; \ [2025-05-21T19:53:16.285Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478567742722/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-21T19:53:16.285Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478567742722/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-21T19:53:16.285Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-21T19:53:16.285Z] echo "Nothing to be done for teardown."; \ [2025-05-21T19:53:16.285Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17478567742722/TestTargetResult"; [2025-05-21T19:53:16.285Z] [2025-05-21T19:53:16.285Z] TEST SETUP: [2025-05-21T19:53:16.285Z] Nothing to be done for setup. [2025-05-21T19:53:16.285Z] [2025-05-21T19:53:16.285Z] TESTING: [2025-05-21T19:53:17.869Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-21T19:53:17.869Z] GC before operation: completed in 34.052 ms, heap usage 14.729 MB -> 4.899 MB. [2025-05-21T19:53:22.369Z] Camera thread performed 122 scans. [2025-05-21T19:53:22.369Z] ====== philosophers (scala) [default], iteration 0 completed (4238.836 ms) ====== [2025-05-21T19:53:22.369Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-21T19:53:22.369Z] GC before operation: completed in 30.420 ms, heap usage 6.043 MB -> 5.271 MB. [2025-05-21T19:53:24.864Z] Camera thread performed 122 scans. [2025-05-21T19:53:24.864Z] ====== philosophers (scala) [default], iteration 1 completed (2854.867 ms) ====== [2025-05-21T19:53:24.864Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-21T19:53:24.864Z] GC before operation: completed in 32.298 ms, heap usage 58.233 MB -> 5.271 MB. [2025-05-21T19:53:27.320Z] Camera thread performed 122 scans. [2025-05-21T19:53:27.320Z] ====== philosophers (scala) [default], iteration 2 completed (1861.642 ms) ====== [2025-05-21T19:53:27.320Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-21T19:53:27.320Z] GC before operation: completed in 28.097 ms, heap usage 18.611 MB -> 5.272 MB. [2025-05-21T19:53:28.894Z] Camera thread performed 122 scans. [2025-05-21T19:53:28.894Z] ====== philosophers (scala) [default], iteration 3 completed (1627.241 ms) ====== [2025-05-21T19:53:28.894Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-21T19:53:28.894Z] GC before operation: completed in 29.761 ms, heap usage 27.852 MB -> 5.272 MB. [2025-05-21T19:53:30.528Z] Camera thread performed 122 scans. [2025-05-21T19:53:30.528Z] ====== philosophers (scala) [default], iteration 4 completed (2015.822 ms) ====== [2025-05-21T19:53:30.528Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-21T19:53:30.528Z] GC before operation: completed in 32.664 ms, heap usage 59.064 MB -> 5.273 MB. [2025-05-21T19:53:32.998Z] Camera thread performed 122 scans. [2025-05-21T19:53:32.998Z] ====== philosophers (scala) [default], iteration 5 completed (1803.209 ms) ====== [2025-05-21T19:53:32.998Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-21T19:53:32.998Z] GC before operation: completed in 25.530 ms, heap usage 33.783 MB -> 5.273 MB. [2025-05-21T19:53:34.583Z] Camera thread performed 122 scans. [2025-05-21T19:53:34.583Z] ====== philosophers (scala) [default], iteration 6 completed (1600.487 ms) ====== [2025-05-21T19:53:34.583Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-21T19:53:34.583Z] GC before operation: completed in 34.069 ms, heap usage 69.452 MB -> 5.273 MB. [2025-05-21T19:53:36.174Z] Camera thread performed 122 scans. [2025-05-21T19:53:36.174Z] ====== philosophers (scala) [default], iteration 7 completed (1590.574 ms) ====== [2025-05-21T19:53:36.174Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-21T19:53:36.174Z] GC before operation: completed in 34.964 ms, heap usage 74.804 MB -> 5.274 MB. [2025-05-21T19:53:36.937Z] Camera thread performed 122 scans. [2025-05-21T19:53:36.937Z] ====== philosophers (scala) [default], iteration 8 completed (1484.723 ms) ====== [2025-05-21T19:53:36.937Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-21T19:53:37.708Z] GC before operation: completed in 28.631 ms, heap usage 10.162 MB -> 5.274 MB. [2025-05-21T19:53:39.300Z] Camera thread performed 122 scans. [2025-05-21T19:53:39.300Z] ====== philosophers (scala) [default], iteration 9 completed (1571.673 ms) ====== [2025-05-21T19:53:39.300Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-21T19:53:39.300Z] GC before operation: completed in 31.948 ms, heap usage 61.777 MB -> 5.309 MB. [2025-05-21T19:53:40.891Z] Camera thread performed 122 scans. [2025-05-21T19:53:40.891Z] ====== philosophers (scala) [default], iteration 10 completed (1933.151 ms) ====== [2025-05-21T19:53:40.891Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-21T19:53:40.891Z] GC before operation: completed in 33.744 ms, heap usage 20.839 MB -> 5.274 MB. [2025-05-21T19:53:42.465Z] Camera thread performed 122 scans. [2025-05-21T19:53:42.465Z] ====== philosophers (scala) [default], iteration 11 completed (1769.630 ms) ====== [2025-05-21T19:53:42.465Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-21T19:53:42.465Z] GC before operation: completed in 40.605 ms, heap usage 15.176 MB -> 5.274 MB. [2025-05-21T19:53:44.043Z] Camera thread performed 122 scans. [2025-05-21T19:53:44.817Z] ====== philosophers (scala) [default], iteration 12 completed (1701.819 ms) ====== [2025-05-21T19:53:44.817Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-21T19:53:44.817Z] GC before operation: completed in 36.761 ms, heap usage 56.275 MB -> 5.274 MB. [2025-05-21T19:53:46.398Z] Camera thread performed 122 scans. [2025-05-21T19:53:46.398Z] ====== philosophers (scala) [default], iteration 13 completed (1908.503 ms) ====== [2025-05-21T19:53:46.398Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-21T19:53:46.398Z] GC before operation: completed in 32.412 ms, heap usage 36.473 MB -> 5.274 MB. [2025-05-21T19:53:48.000Z] Camera thread performed 122 scans. [2025-05-21T19:53:48.000Z] ====== philosophers (scala) [default], iteration 14 completed (1735.284 ms) ====== [2025-05-21T19:53:48.000Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-21T19:53:48.000Z] GC before operation: completed in 30.882 ms, heap usage 12.469 MB -> 5.275 MB. [2025-05-21T19:53:49.570Z] Camera thread performed 122 scans. [2025-05-21T19:53:49.570Z] ====== philosophers (scala) [default], iteration 15 completed (1504.213 ms) ====== [2025-05-21T19:53:49.570Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-21T19:53:49.570Z] GC before operation: completed in 28.475 ms, heap usage 68.172 MB -> 5.309 MB. [2025-05-21T19:53:51.147Z] Camera thread performed 122 scans. [2025-05-21T19:53:51.147Z] ====== philosophers (scala) [default], iteration 16 completed (1569.569 ms) ====== [2025-05-21T19:53:51.147Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-21T19:53:51.147Z] GC before operation: completed in 29.417 ms, heap usage 48.060 MB -> 5.310 MB. [2025-05-21T19:53:52.723Z] Camera thread performed 122 scans. [2025-05-21T19:53:52.723Z] ====== philosophers (scala) [default], iteration 17 completed (1673.293 ms) ====== [2025-05-21T19:53:52.723Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-21T19:53:52.723Z] GC before operation: completed in 41.762 ms, heap usage 6.965 MB -> 5.275 MB. [2025-05-21T19:53:54.303Z] Camera thread performed 122 scans. [2025-05-21T19:53:54.303Z] ====== philosophers (scala) [default], iteration 18 completed (1526.243 ms) ====== [2025-05-21T19:53:54.303Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-21T19:53:54.303Z] GC before operation: completed in 29.483 ms, heap usage 16.766 MB -> 5.275 MB. [2025-05-21T19:53:55.880Z] Camera thread performed 122 scans. [2025-05-21T19:53:55.880Z] ====== philosophers (scala) [default], iteration 19 completed (1532.071 ms) ====== [2025-05-21T19:53:55.880Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-21T19:53:55.880Z] GC before operation: completed in 24.596 ms, heap usage 84.459 MB -> 5.275 MB. [2025-05-21T19:53:58.889Z] Camera thread performed 122 scans. [2025-05-21T19:53:58.889Z] ====== philosophers (scala) [default], iteration 20 completed (1709.094 ms) ====== [2025-05-21T19:53:58.889Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-21T19:53:58.889Z] GC before operation: completed in 35.913 ms, heap usage 60.291 MB -> 5.276 MB. [2025-05-21T19:53:59.659Z] Camera thread performed 122 scans. [2025-05-21T19:53:59.659Z] ====== philosophers (scala) [default], iteration 21 completed (1579.793 ms) ====== [2025-05-21T19:53:59.659Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-21T19:53:59.659Z] GC before operation: completed in 34.799 ms, heap usage 39.021 MB -> 5.275 MB. [2025-05-21T19:54:01.257Z] Camera thread performed 122 scans. [2025-05-21T19:54:01.257Z] ====== philosophers (scala) [default], iteration 22 completed (1551.394 ms) ====== [2025-05-21T19:54:01.257Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-21T19:54:01.257Z] GC before operation: completed in 42.375 ms, heap usage 71.083 MB -> 5.276 MB. [2025-05-21T19:54:02.852Z] Camera thread performed 122 scans. [2025-05-21T19:54:02.853Z] ====== philosophers (scala) [default], iteration 23 completed (1653.636 ms) ====== [2025-05-21T19:54:02.853Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-21T19:54:02.853Z] GC before operation: completed in 28.445 ms, heap usage 36.442 MB -> 5.276 MB. [2025-05-21T19:54:04.432Z] Camera thread performed 122 scans. [2025-05-21T19:54:04.432Z] ====== philosophers (scala) [default], iteration 24 completed (1441.851 ms) ====== [2025-05-21T19:54:04.432Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-21T19:54:04.432Z] GC before operation: completed in 25.533 ms, heap usage 56.191 MB -> 5.276 MB. [2025-05-21T19:54:06.016Z] Camera thread performed 122 scans. [2025-05-21T19:54:06.016Z] ====== philosophers (scala) [default], iteration 25 completed (1617.457 ms) ====== [2025-05-21T19:54:06.016Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-21T19:54:06.016Z] GC before operation: completed in 33.117 ms, heap usage 43.439 MB -> 5.276 MB. [2025-05-21T19:54:07.595Z] Camera thread performed 122 scans. [2025-05-21T19:54:07.595Z] ====== philosophers (scala) [default], iteration 26 completed (1743.335 ms) ====== [2025-05-21T19:54:07.595Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-21T19:54:07.595Z] GC before operation: completed in 26.008 ms, heap usage 12.220 MB -> 5.276 MB. [2025-05-21T19:54:09.174Z] Camera thread performed 122 scans. [2025-05-21T19:54:09.174Z] ====== philosophers (scala) [default], iteration 27 completed (1562.229 ms) ====== [2025-05-21T19:54:09.175Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-21T19:54:09.175Z] GC before operation: completed in 28.384 ms, heap usage 49.470 MB -> 5.276 MB. [2025-05-21T19:54:10.756Z] Camera thread performed 122 scans. [2025-05-21T19:54:10.756Z] ====== philosophers (scala) [default], iteration 28 completed (1479.944 ms) ====== [2025-05-21T19:54:10.756Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-21T19:54:10.756Z] GC before operation: completed in 26.294 ms, heap usage 74.163 MB -> 5.276 MB. [2025-05-21T19:54:12.342Z] Camera thread performed 122 scans. [2025-05-21T19:54:12.342Z] ====== philosophers (scala) [default], iteration 29 completed (1591.678 ms) ====== [2025-05-21T19:54:13.101Z] ----------------------------------- [2025-05-21T19:54:13.101Z] renaissance-philosophers_0_PASSED [2025-05-21T19:54:13.101Z] ----------------------------------- [2025-05-21T19:54:13.101Z] [2025-05-21T19:54:13.101Z] TEST TEARDOWN: [2025-05-21T19:54:13.101Z] Nothing to be done for teardown. [2025-05-21T19:54:13.101Z] renaissance-philosophers_0 Finish Time: Wed May 21 19:54:12 2025 Epoch Time (ms): 1747857252312