[2025-12-17T22:23:27.510Z] Running test renaissance-philosophers_0 ... [2025-12-17T22:23:27.510Z] =============================================== [2025-12-17T22:23:27.510Z] renaissance-philosophers_0 Start Time: Wed Dec 17 22:23:26 2025 Epoch Time (ms): 1766010206911 [2025-12-17T22:23:27.510Z] variation: NoOptions [2025-12-17T22:23:27.510Z] JVM_OPTIONS: [2025-12-17T22:23:27.510Z] { \ [2025-12-17T22:23:27.510Z] echo ""; echo "TEST SETUP:"; \ [2025-12-17T22:23:27.510Z] echo "Nothing to be done for setup."; \ [2025-12-17T22:23:27.510Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/renaissance-philosophers_0"; \ [2025-12-17T22:23:27.510Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/renaissance-philosophers_0"; \ [2025-12-17T22:23:27.510Z] echo ""; echo "TESTING:"; \ [2025-12-17T22:23:27.510Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-17T22:23:27.510Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-17T22:23:27.510Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-17T22:23:27.510Z] echo "Nothing to be done for teardown."; \ [2025-12-17T22:23:27.510Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/TestTargetResult"; [2025-12-17T22:23:27.510Z] [2025-12-17T22:23:27.510Z] TEST SETUP: [2025-12-17T22:23:27.510Z] Nothing to be done for setup. [2025-12-17T22:23:27.510Z] [2025-12-17T22:23:27.510Z] TESTING: [2025-12-17T22:23:28.281Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-17T22:23:28.281Z] GC before operation: completed in 21.226 ms, heap usage 6.584 MB -> 4.315 MB. [2025-12-17T22:23:38.203Z] Camera thread performed 122 scans. [2025-12-17T22:23:38.203Z] ====== philosophers (scala) [default], iteration 0 completed (9156.463 ms) ====== [2025-12-17T22:23:38.203Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-17T22:23:38.203Z] GC before operation: completed in 16.175 ms, heap usage 146.540 MB -> 4.749 MB. [2025-12-17T22:23:45.090Z] Camera thread performed 122 scans. [2025-12-17T22:23:45.090Z] ====== philosophers (scala) [default], iteration 1 completed (7266.592 ms) ====== [2025-12-17T22:23:45.090Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-17T22:23:45.090Z] GC before operation: completed in 12.682 ms, heap usage 9.747 MB -> 4.754 MB. [2025-12-17T22:23:52.062Z] Camera thread performed 122 scans. [2025-12-17T22:23:52.062Z] ====== philosophers (scala) [default], iteration 2 completed (7150.113 ms) ====== [2025-12-17T22:23:52.062Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-17T22:23:52.062Z] GC before operation: completed in 13.733 ms, heap usage 186.954 MB -> 4.755 MB. [2025-12-17T22:24:00.362Z] Camera thread performed 122 scans. [2025-12-17T22:24:00.362Z] ====== philosophers (scala) [default], iteration 3 completed (7231.604 ms) ====== [2025-12-17T22:24:00.362Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-17T22:24:00.362Z] GC before operation: completed in 13.951 ms, heap usage 209.733 MB -> 4.755 MB. [2025-12-17T22:24:07.244Z] Camera thread performed 122 scans. [2025-12-17T22:24:07.244Z] ====== philosophers (scala) [default], iteration 4 completed (7166.980 ms) ====== [2025-12-17T22:24:07.244Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-17T22:24:07.244Z] GC before operation: completed in 12.835 ms, heap usage 181.480 MB -> 4.755 MB. [2025-12-17T22:24:14.127Z] Camera thread performed 122 scans. [2025-12-17T22:24:14.127Z] ====== philosophers (scala) [default], iteration 5 completed (7043.047 ms) ====== [2025-12-17T22:24:14.127Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-17T22:24:14.127Z] GC before operation: completed in 13.940 ms, heap usage 126.429 MB -> 4.755 MB. [2025-12-17T22:24:21.376Z] Camera thread performed 122 scans. [2025-12-17T22:24:21.376Z] ====== philosophers (scala) [default], iteration 6 completed (7044.152 ms) ====== [2025-12-17T22:24:21.376Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-17T22:24:21.376Z] GC before operation: completed in 13.944 ms, heap usage 34.306 MB -> 4.756 MB. [2025-12-17T22:24:28.261Z] Camera thread performed 122 scans. [2025-12-17T22:24:28.261Z] ====== philosophers (scala) [default], iteration 7 completed (6970.443 ms) ====== [2025-12-17T22:24:28.261Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-17T22:24:28.261Z] GC before operation: completed in 13.517 ms, heap usage 171.173 MB -> 4.755 MB. [2025-12-17T22:24:35.149Z] Camera thread performed 122 scans. [2025-12-17T22:24:35.149Z] ====== philosophers (scala) [default], iteration 8 completed (7013.151 ms) ====== [2025-12-17T22:24:35.149Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-17T22:24:35.149Z] GC before operation: completed in 13.463 ms, heap usage 210.509 MB -> 4.756 MB. [2025-12-17T22:24:42.069Z] Camera thread performed 122 scans. [2025-12-17T22:24:42.069Z] ====== philosophers (scala) [default], iteration 9 completed (7157.710 ms) ====== [2025-12-17T22:24:42.069Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-17T22:24:42.069Z] GC before operation: completed in 12.254 ms, heap usage 105.685 MB -> 4.755 MB. [2025-12-17T22:24:48.952Z] Camera thread performed 122 scans. [2025-12-17T22:24:48.952Z] ====== philosophers (scala) [default], iteration 10 completed (7043.061 ms) ====== [2025-12-17T22:24:48.952Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-17T22:24:48.952Z] GC before operation: completed in 13.730 ms, heap usage 8.306 MB -> 4.756 MB. [2025-12-17T22:24:55.839Z] Camera thread performed 122 scans. [2025-12-17T22:24:55.839Z] ====== philosophers (scala) [default], iteration 11 completed (7073.083 ms) ====== [2025-12-17T22:24:55.839Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-17T22:24:55.839Z] GC before operation: completed in 13.514 ms, heap usage 79.287 MB -> 4.756 MB. [2025-12-17T22:25:04.137Z] Camera thread performed 122 scans. [2025-12-17T22:25:04.137Z] ====== philosophers (scala) [default], iteration 12 completed (7004.695 ms) ====== [2025-12-17T22:25:04.137Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-17T22:25:04.137Z] GC before operation: completed in 11.975 ms, heap usage 85.789 MB -> 4.756 MB. [2025-12-17T22:25:09.936Z] Camera thread performed 122 scans. [2025-12-17T22:25:09.936Z] ====== philosophers (scala) [default], iteration 13 completed (6875.404 ms) ====== [2025-12-17T22:25:09.936Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-17T22:25:09.936Z] GC before operation: completed in 14.400 ms, heap usage 122.935 MB -> 4.756 MB. [2025-12-17T22:25:16.868Z] Camera thread performed 122 scans. [2025-12-17T22:25:16.868Z] ====== philosophers (scala) [default], iteration 14 completed (7147.750 ms) ====== [2025-12-17T22:25:16.868Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-17T22:25:16.868Z] GC before operation: completed in 14.748 ms, heap usage 144.937 MB -> 4.756 MB. [2025-12-17T22:25:23.749Z] Camera thread performed 122 scans. [2025-12-17T22:25:23.749Z] ====== philosophers (scala) [default], iteration 15 completed (6915.618 ms) ====== [2025-12-17T22:25:23.749Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-17T22:25:23.749Z] GC before operation: completed in 13.515 ms, heap usage 123.473 MB -> 4.756 MB. [2025-12-17T22:25:32.044Z] Camera thread performed 122 scans. [2025-12-17T22:25:32.045Z] ====== philosophers (scala) [default], iteration 16 completed (7199.676 ms) ====== [2025-12-17T22:25:32.045Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-17T22:25:32.045Z] GC before operation: completed in 12.026 ms, heap usage 28.681 MB -> 4.757 MB. [2025-12-17T22:25:38.931Z] Camera thread performed 122 scans. [2025-12-17T22:25:38.931Z] ====== philosophers (scala) [default], iteration 17 completed (6971.012 ms) ====== [2025-12-17T22:25:38.931Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-17T22:25:38.931Z] GC before operation: completed in 11.883 ms, heap usage 51.683 MB -> 4.756 MB. [2025-12-17T22:25:45.819Z] Camera thread performed 122 scans. [2025-12-17T22:25:45.819Z] ====== philosophers (scala) [default], iteration 18 completed (7200.195 ms) ====== [2025-12-17T22:25:45.819Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-17T22:25:45.819Z] GC before operation: completed in 12.945 ms, heap usage 65.951 MB -> 4.756 MB. [2025-12-17T22:25:52.708Z] Camera thread performed 122 scans. [2025-12-17T22:25:52.708Z] ====== philosophers (scala) [default], iteration 19 completed (7040.957 ms) ====== [2025-12-17T22:25:52.708Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-17T22:25:52.708Z] GC before operation: completed in 13.113 ms, heap usage 109.245 MB -> 4.757 MB. [2025-12-17T22:25:59.592Z] Camera thread performed 122 scans. [2025-12-17T22:25:59.592Z] ====== philosophers (scala) [default], iteration 20 completed (7060.293 ms) ====== [2025-12-17T22:25:59.592Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-17T22:25:59.592Z] GC before operation: completed in 13.901 ms, heap usage 183.604 MB -> 4.757 MB. [2025-12-17T22:26:06.479Z] Camera thread performed 122 scans. [2025-12-17T22:26:06.479Z] ====== philosophers (scala) [default], iteration 21 completed (6868.451 ms) ====== [2025-12-17T22:26:06.479Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-17T22:26:06.479Z] GC before operation: completed in 13.583 ms, heap usage 93.669 MB -> 4.756 MB. [2025-12-17T22:26:13.576Z] Camera thread performed 122 scans. [2025-12-17T22:26:13.577Z] ====== philosophers (scala) [default], iteration 22 completed (6955.217 ms) ====== [2025-12-17T22:26:13.577Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-17T22:26:13.577Z] GC before operation: completed in 13.217 ms, heap usage 125.973 MB -> 4.757 MB. [2025-12-17T22:26:20.464Z] Camera thread performed 122 scans. [2025-12-17T22:26:20.464Z] ====== philosophers (scala) [default], iteration 23 completed (7088.225 ms) ====== [2025-12-17T22:26:20.464Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-17T22:26:20.464Z] GC before operation: completed in 12.127 ms, heap usage 166.700 MB -> 4.757 MB. [2025-12-17T22:26:27.350Z] Camera thread performed 122 scans. [2025-12-17T22:26:27.350Z] ====== philosophers (scala) [default], iteration 24 completed (7025.317 ms) ====== [2025-12-17T22:26:27.350Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-17T22:26:27.350Z] GC before operation: completed in 13.612 ms, heap usage 47.387 MB -> 4.757 MB. [2025-12-17T22:26:34.241Z] Camera thread performed 122 scans. [2025-12-17T22:26:34.241Z] ====== philosophers (scala) [default], iteration 25 completed (6971.096 ms) ====== [2025-12-17T22:26:34.241Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-17T22:26:34.241Z] GC before operation: completed in 13.786 ms, heap usage 70.226 MB -> 4.757 MB. [2025-12-17T22:26:42.541Z] Camera thread performed 122 scans. [2025-12-17T22:26:42.541Z] ====== philosophers (scala) [default], iteration 26 completed (7128.952 ms) ====== [2025-12-17T22:26:42.541Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-17T22:26:42.541Z] GC before operation: completed in 13.616 ms, heap usage 10.054 MB -> 4.757 MB. [2025-12-17T22:26:49.425Z] Camera thread performed 122 scans. [2025-12-17T22:26:49.425Z] ====== philosophers (scala) [default], iteration 27 completed (6981.113 ms) ====== [2025-12-17T22:26:49.425Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-17T22:26:49.425Z] GC before operation: completed in 13.663 ms, heap usage 190.347 MB -> 4.757 MB. [2025-12-17T22:26:56.308Z] Camera thread performed 122 scans. [2025-12-17T22:26:56.308Z] ====== philosophers (scala) [default], iteration 28 completed (6987.265 ms) ====== [2025-12-17T22:26:56.308Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-17T22:26:56.308Z] GC before operation: completed in 14.044 ms, heap usage 172.538 MB -> 4.757 MB. [2025-12-17T22:27:03.189Z] Camera thread performed 122 scans. [2025-12-17T22:27:03.189Z] ====== philosophers (scala) [default], iteration 29 completed (7140.575 ms) ====== [2025-12-17T22:27:03.189Z] ----------------------------------- [2025-12-17T22:27:03.189Z] renaissance-philosophers_0_PASSED [2025-12-17T22:27:03.189Z] ----------------------------------- [2025-12-17T22:27:03.189Z] [2025-12-17T22:27:03.189Z] TEST TEARDOWN: [2025-12-17T22:27:03.189Z] Nothing to be done for teardown. [2025-12-17T22:27:03.189Z] renaissance-philosophers_0 Finish Time: Wed Dec 17 22:27:02 2025 Epoch Time (ms): 1766010422677