[2025-06-19T03:41:52.552Z] Running test renaissance-philosophers_0 ... [2025-06-19T03:41:52.552Z] =============================================== [2025-06-19T03:41:52.552Z] renaissance-philosophers_0 Start Time: Thu Jun 19 03:41:51 2025 Epoch Time (ms): 1750304511980 [2025-06-19T03:41:52.552Z] variation: NoOptions [2025-06-19T03:41:52.552Z] JVM_OPTIONS: [2025-06-19T03:41:52.552Z] { \ [2025-06-19T03:41:52.552Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T03:41:52.552Z] echo "Nothing to be done for setup."; \ [2025-06-19T03:41:52.552Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17503012102880/renaissance-philosophers_0"; \ [2025-06-19T03:41:52.552Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17503012102880/renaissance-philosophers_0"; \ [2025-06-19T03:41:52.552Z] echo ""; echo "TESTING:"; \ [2025-06-19T03:41:52.552Z] "/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_17503012102880/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-19T03:41:52.552Z] 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_17503012102880/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T03:41:52.552Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T03:41:52.552Z] echo "Nothing to be done for teardown."; \ [2025-06-19T03:41:52.552Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17503012102880/TestTargetResult"; [2025-06-19T03:41:52.552Z] [2025-06-19T03:41:52.552Z] TEST SETUP: [2025-06-19T03:41:52.552Z] Nothing to be done for setup. [2025-06-19T03:41:52.552Z] [2025-06-19T03:41:52.552Z] TESTING: [2025-06-19T03:41:54.984Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-19T03:41:54.984Z] GC before operation: completed in 23.162 ms, heap usage 6.348 MB -> 4.289 MB. [2025-06-19T03:42:04.789Z] Camera thread performed 122 scans. [2025-06-19T03:42:04.789Z] ====== philosophers (scala) [default], iteration 0 completed (10000.911 ms) ====== [2025-06-19T03:42:04.789Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-19T03:42:04.789Z] GC before operation: completed in 24.752 ms, heap usage 172.333 MB -> 4.725 MB. [2025-06-19T03:42:11.767Z] Camera thread performed 122 scans. [2025-06-19T03:42:11.767Z] ====== philosophers (scala) [default], iteration 1 completed (7000.970 ms) ====== [2025-06-19T03:42:11.767Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-19T03:42:11.767Z] GC before operation: completed in 20.716 ms, heap usage 29.440 MB -> 4.726 MB. [2025-06-19T03:42:20.146Z] Camera thread performed 122 scans. [2025-06-19T03:42:20.147Z] ====== philosophers (scala) [default], iteration 2 completed (7715.698 ms) ====== [2025-06-19T03:42:20.147Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-19T03:42:20.147Z] GC before operation: completed in 23.302 ms, heap usage 83.446 MB -> 4.728 MB. [2025-06-19T03:42:28.465Z] Camera thread performed 122 scans. [2025-06-19T03:42:28.465Z] ====== philosophers (scala) [default], iteration 3 completed (8466.344 ms) ====== [2025-06-19T03:42:28.465Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-19T03:42:28.465Z] GC before operation: completed in 25.772 ms, heap usage 104.971 MB -> 4.729 MB. [2025-06-19T03:42:36.666Z] Camera thread performed 122 scans. [2025-06-19T03:42:36.666Z] ====== philosophers (scala) [default], iteration 4 completed (8691.071 ms) ====== [2025-06-19T03:42:36.666Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-19T03:42:36.666Z] GC before operation: completed in 29.117 ms, heap usage 163.282 MB -> 4.729 MB. [2025-06-19T03:42:44.866Z] Camera thread performed 122 scans. [2025-06-19T03:42:44.866Z] ====== philosophers (scala) [default], iteration 5 completed (8055.794 ms) ====== [2025-06-19T03:42:44.866Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-19T03:42:44.866Z] GC before operation: completed in 25.709 ms, heap usage 146.659 MB -> 4.729 MB. [2025-06-19T03:42:53.118Z] Camera thread performed 122 scans. [2025-06-19T03:42:53.118Z] ====== philosophers (scala) [default], iteration 6 completed (7928.162 ms) ====== [2025-06-19T03:42:53.118Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-19T03:42:53.118Z] GC before operation: completed in 23.665 ms, heap usage 24.418 MB -> 4.729 MB. [2025-06-19T03:43:01.326Z] Camera thread performed 122 scans. [2025-06-19T03:43:01.326Z] ====== philosophers (scala) [default], iteration 7 completed (7646.078 ms) ====== [2025-06-19T03:43:01.326Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-19T03:43:01.326Z] GC before operation: completed in 23.833 ms, heap usage 141.519 MB -> 4.729 MB. [2025-06-19T03:43:08.141Z] Camera thread performed 122 scans. [2025-06-19T03:43:08.141Z] ====== philosophers (scala) [default], iteration 8 completed (6821.242 ms) ====== [2025-06-19T03:43:08.141Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-19T03:43:08.141Z] GC before operation: completed in 19.665 ms, heap usage 136.194 MB -> 4.729 MB. [2025-06-19T03:43:16.340Z] Camera thread performed 122 scans. [2025-06-19T03:43:16.340Z] ====== philosophers (scala) [default], iteration 9 completed (8314.349 ms) ====== [2025-06-19T03:43:16.340Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-19T03:43:16.340Z] GC before operation: completed in 23.794 ms, heap usage 244.483 MB -> 4.729 MB. [2025-06-19T03:43:24.982Z] Camera thread performed 122 scans. [2025-06-19T03:43:24.982Z] ====== philosophers (scala) [default], iteration 10 completed (8172.148 ms) ====== [2025-06-19T03:43:24.982Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-19T03:43:24.982Z] GC before operation: completed in 22.882 ms, heap usage 51.490 MB -> 4.729 MB. [2025-06-19T03:43:33.182Z] Camera thread performed 122 scans. [2025-06-19T03:43:33.182Z] ====== philosophers (scala) [default], iteration 11 completed (8733.192 ms) ====== [2025-06-19T03:43:33.182Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-19T03:43:33.182Z] GC before operation: completed in 22.576 ms, heap usage 16.473 MB -> 4.729 MB. [2025-06-19T03:43:39.975Z] Camera thread performed 122 scans. [2025-06-19T03:43:39.975Z] ====== philosophers (scala) [default], iteration 12 completed (7369.985 ms) ====== [2025-06-19T03:43:39.975Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-19T03:43:39.975Z] GC before operation: completed in 21.616 ms, heap usage 99.496 MB -> 4.729 MB. [2025-06-19T03:43:48.217Z] Camera thread performed 122 scans. [2025-06-19T03:43:48.217Z] ====== philosophers (scala) [default], iteration 13 completed (7789.712 ms) ====== [2025-06-19T03:43:48.217Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-19T03:43:48.217Z] GC before operation: completed in 23.725 ms, heap usage 150.298 MB -> 4.729 MB. [2025-06-19T03:43:55.010Z] Camera thread performed 122 scans. [2025-06-19T03:43:55.010Z] ====== philosophers (scala) [default], iteration 14 completed (7380.997 ms) ====== [2025-06-19T03:43:55.010Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-19T03:43:55.010Z] GC before operation: completed in 23.241 ms, heap usage 110.894 MB -> 4.730 MB. [2025-06-19T03:44:01.803Z] Camera thread performed 122 scans. [2025-06-19T03:44:01.803Z] ====== philosophers (scala) [default], iteration 15 completed (6644.791 ms) ====== [2025-06-19T03:44:01.803Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-19T03:44:01.803Z] GC before operation: completed in 19.908 ms, heap usage 227.778 MB -> 4.729 MB. [2025-06-19T03:44:08.591Z] Camera thread performed 122 scans. [2025-06-19T03:44:08.591Z] ====== philosophers (scala) [default], iteration 16 completed (6950.518 ms) ====== [2025-06-19T03:44:08.591Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-19T03:44:08.591Z] GC before operation: completed in 20.637 ms, heap usage 128.901 MB -> 4.730 MB. [2025-06-19T03:44:16.790Z] Camera thread performed 122 scans. [2025-06-19T03:44:16.790Z] ====== philosophers (scala) [default], iteration 17 completed (8142.648 ms) ====== [2025-06-19T03:44:16.790Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-19T03:44:16.790Z] GC before operation: completed in 23.746 ms, heap usage 56.502 MB -> 4.730 MB. [2025-06-19T03:44:24.985Z] Camera thread performed 122 scans. [2025-06-19T03:44:24.985Z] ====== philosophers (scala) [default], iteration 18 completed (7968.527 ms) ====== [2025-06-19T03:44:24.985Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-19T03:44:24.985Z] GC before operation: completed in 22.654 ms, heap usage 5.979 MB -> 4.730 MB. [2025-06-19T03:44:33.206Z] Camera thread performed 122 scans. [2025-06-19T03:44:33.206Z] ====== philosophers (scala) [default], iteration 19 completed (8261.989 ms) ====== [2025-06-19T03:44:33.206Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-19T03:44:33.206Z] GC before operation: completed in 23.932 ms, heap usage 187.073 MB -> 4.730 MB. [2025-06-19T03:44:41.404Z] Camera thread performed 122 scans. [2025-06-19T03:44:41.404Z] ====== philosophers (scala) [default], iteration 20 completed (8254.120 ms) ====== [2025-06-19T03:44:41.404Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-19T03:44:41.404Z] GC before operation: completed in 27.988 ms, heap usage 251.121 MB -> 4.730 MB. [2025-06-19T03:44:49.642Z] Camera thread performed 122 scans. [2025-06-19T03:44:49.642Z] ====== philosophers (scala) [default], iteration 21 completed (7261.340 ms) ====== [2025-06-19T03:44:49.642Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-19T03:44:49.642Z] GC before operation: completed in 23.147 ms, heap usage 231.090 MB -> 4.730 MB. [2025-06-19T03:44:55.184Z] Camera thread performed 122 scans. [2025-06-19T03:44:55.184Z] ====== philosophers (scala) [default], iteration 22 completed (6572.818 ms) ====== [2025-06-19T03:44:55.184Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-19T03:44:55.184Z] GC before operation: completed in 19.867 ms, heap usage 169.461 MB -> 4.730 MB. [2025-06-19T03:45:03.378Z] Camera thread performed 122 scans. [2025-06-19T03:45:03.378Z] ====== philosophers (scala) [default], iteration 23 completed (6925.193 ms) ====== [2025-06-19T03:45:03.378Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-19T03:45:03.378Z] GC before operation: completed in 20.536 ms, heap usage 99.487 MB -> 4.730 MB. [2025-06-19T03:45:10.173Z] Camera thread performed 122 scans. [2025-06-19T03:45:10.173Z] ====== philosophers (scala) [default], iteration 24 completed (7682.023 ms) ====== [2025-06-19T03:45:10.173Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-19T03:45:10.173Z] GC before operation: completed in 20.563 ms, heap usage 83.864 MB -> 4.730 MB. [2025-06-19T03:45:16.958Z] Camera thread performed 122 scans. [2025-06-19T03:45:16.958Z] ====== philosophers (scala) [default], iteration 25 completed (7092.503 ms) ====== [2025-06-19T03:45:16.958Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-19T03:45:16.958Z] GC before operation: completed in 24.466 ms, heap usage 232.477 MB -> 4.730 MB. [2025-06-19T03:45:26.734Z] Camera thread performed 122 scans. [2025-06-19T03:45:26.734Z] ====== philosophers (scala) [default], iteration 26 completed (8455.653 ms) ====== [2025-06-19T03:45:26.734Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-19T03:45:26.734Z] GC before operation: completed in 28.576 ms, heap usage 150.866 MB -> 4.731 MB. [2025-06-19T03:45:33.532Z] Camera thread performed 122 scans. [2025-06-19T03:45:33.532Z] ====== philosophers (scala) [default], iteration 27 completed (8203.656 ms) ====== [2025-06-19T03:45:33.532Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-19T03:45:34.469Z] GC before operation: completed in 21.495 ms, heap usage 13.243 MB -> 4.731 MB. [2025-06-19T03:45:41.274Z] Camera thread performed 122 scans. [2025-06-19T03:45:41.274Z] ====== philosophers (scala) [default], iteration 28 completed (7346.069 ms) ====== [2025-06-19T03:45:41.274Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-19T03:45:41.274Z] GC before operation: completed in 26.388 ms, heap usage 230.217 MB -> 4.731 MB. [2025-06-19T03:45:51.049Z] Camera thread performed 122 scans. [2025-06-19T03:45:51.049Z] ====== philosophers (scala) [default], iteration 29 completed (8839.506 ms) ====== [2025-06-19T03:45:51.049Z] ----------------------------------- [2025-06-19T03:45:51.049Z] renaissance-philosophers_0_PASSED [2025-06-19T03:45:51.049Z] ----------------------------------- [2025-06-19T03:45:51.049Z] [2025-06-19T03:45:51.049Z] TEST TEARDOWN: [2025-06-19T03:45:51.049Z] Nothing to be done for teardown. [2025-06-19T03:45:51.049Z] renaissance-philosophers_0 Finish Time: Thu Jun 19 03:45:50 2025 Epoch Time (ms): 1750304750189