[2025-05-29T02:18:11.446Z] Running test renaissance-philosophers_0 ... [2025-05-29T02:18:11.446Z] =============================================== [2025-05-29T02:18:11.446Z] renaissance-philosophers_0 Start Time: Thu May 29 02:18:11 2025 Epoch Time (ms): 1748485091323 [2025-05-29T02:18:11.446Z] variation: NoOptions [2025-05-29T02:18:11.446Z] JVM_OPTIONS: [2025-05-29T02:18:11.446Z] { \ [2025-05-29T02:18:11.446Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T02:18:11.446Z] echo "Nothing to be done for setup."; \ [2025-05-29T02:18:11.446Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/renaissance-philosophers_0"; \ [2025-05-29T02:18:11.446Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/renaissance-philosophers_0"; \ [2025-05-29T02:18:11.446Z] echo ""; echo "TESTING:"; \ [2025-05-29T02:18:11.446Z] "/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_17484833661900/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-29T02:18:11.446Z] 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_17484833661900/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T02:18:11.446Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T02:18:11.446Z] echo "Nothing to be done for teardown."; \ [2025-05-29T02:18:11.446Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/TestTargetResult"; [2025-05-29T02:18:11.446Z] [2025-05-29T02:18:11.446Z] TEST SETUP: [2025-05-29T02:18:11.446Z] Nothing to be done for setup. [2025-05-29T02:18:11.446Z] [2025-05-29T02:18:11.446Z] TESTING: [2025-05-29T02:18:13.043Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-29T02:18:13.043Z] GC before operation: completed in 18.834 ms, heap usage 6.727 MB -> 4.315 MB. [2025-05-29T02:18:22.942Z] Camera thread performed 122 scans. [2025-05-29T02:18:22.942Z] ====== philosophers (scala) [default], iteration 0 completed (9646.906 ms) ====== [2025-05-29T02:18:22.942Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-29T02:18:22.942Z] GC before operation: completed in 15.796 ms, heap usage 30.159 MB -> 4.752 MB. [2025-05-29T02:18:31.235Z] Camera thread performed 122 scans. [2025-05-29T02:18:31.235Z] ====== philosophers (scala) [default], iteration 1 completed (7721.043 ms) ====== [2025-05-29T02:18:31.235Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-29T02:18:31.235Z] GC before operation: completed in 13.216 ms, heap usage 209.766 MB -> 4.754 MB. [2025-05-29T02:18:38.120Z] Camera thread performed 122 scans. [2025-05-29T02:18:38.120Z] ====== philosophers (scala) [default], iteration 2 completed (7750.467 ms) ====== [2025-05-29T02:18:38.120Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-29T02:18:38.120Z] GC before operation: completed in 12.813 ms, heap usage 153.759 MB -> 4.755 MB. [2025-05-29T02:18:46.422Z] Camera thread performed 122 scans. [2025-05-29T02:18:46.422Z] ====== philosophers (scala) [default], iteration 3 completed (7698.349 ms) ====== [2025-05-29T02:18:46.422Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-29T02:18:46.422Z] GC before operation: completed in 12.173 ms, heap usage 115.858 MB -> 4.755 MB. [2025-05-29T02:18:53.301Z] Camera thread performed 122 scans. [2025-05-29T02:18:53.301Z] ====== philosophers (scala) [default], iteration 4 completed (7808.312 ms) ====== [2025-05-29T02:18:53.301Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-29T02:18:54.073Z] GC before operation: completed in 13.431 ms, heap usage 128.441 MB -> 4.755 MB. [2025-05-29T02:19:02.905Z] Camera thread performed 122 scans. [2025-05-29T02:19:02.905Z] ====== philosophers (scala) [default], iteration 5 completed (7830.616 ms) ====== [2025-05-29T02:19:02.905Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-29T02:19:02.905Z] GC before operation: completed in 12.395 ms, heap usage 54.865 MB -> 4.755 MB. [2025-05-29T02:19:09.786Z] Camera thread performed 122 scans. [2025-05-29T02:19:09.786Z] ====== philosophers (scala) [default], iteration 6 completed (7600.107 ms) ====== [2025-05-29T02:19:09.786Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-29T02:19:09.786Z] GC before operation: completed in 13.405 ms, heap usage 86.537 MB -> 4.756 MB. [2025-05-29T02:19:18.087Z] Camera thread performed 122 scans. [2025-05-29T02:19:18.087Z] ====== philosophers (scala) [default], iteration 7 completed (8007.946 ms) ====== [2025-05-29T02:19:18.087Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-29T02:19:18.087Z] GC before operation: completed in 14.747 ms, heap usage 115.938 MB -> 4.755 MB. [2025-05-29T02:19:24.972Z] Camera thread performed 122 scans. [2025-05-29T02:19:24.972Z] ====== philosophers (scala) [default], iteration 8 completed (7863.819 ms) ====== [2025-05-29T02:19:24.972Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-29T02:19:24.972Z] GC before operation: completed in 14.894 ms, heap usage 134.019 MB -> 4.755 MB. [2025-05-29T02:19:33.442Z] Camera thread performed 122 scans. [2025-05-29T02:19:33.442Z] ====== philosophers (scala) [default], iteration 9 completed (8030.072 ms) ====== [2025-05-29T02:19:33.442Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-29T02:19:33.443Z] GC before operation: completed in 13.995 ms, heap usage 24.386 MB -> 4.755 MB. [2025-05-29T02:19:41.746Z] Camera thread performed 122 scans. [2025-05-29T02:19:41.746Z] ====== philosophers (scala) [default], iteration 10 completed (7735.659 ms) ====== [2025-05-29T02:19:41.746Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-29T02:19:41.746Z] GC before operation: completed in 13.398 ms, heap usage 174.528 MB -> 4.756 MB. [2025-05-29T02:19:48.670Z] Camera thread performed 122 scans. [2025-05-29T02:19:48.670Z] ====== philosophers (scala) [default], iteration 11 completed (7905.236 ms) ====== [2025-05-29T02:19:48.670Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-29T02:19:48.670Z] GC before operation: completed in 13.329 ms, heap usage 39.267 MB -> 4.756 MB. [2025-05-29T02:19:56.964Z] Camera thread performed 122 scans. [2025-05-29T02:19:56.964Z] ====== philosophers (scala) [default], iteration 12 completed (7891.334 ms) ====== [2025-05-29T02:19:56.964Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-29T02:19:56.964Z] GC before operation: completed in 14.843 ms, heap usage 164.879 MB -> 4.756 MB. [2025-05-29T02:20:05.266Z] Camera thread performed 122 scans. [2025-05-29T02:20:05.266Z] ====== philosophers (scala) [default], iteration 13 completed (7946.526 ms) ====== [2025-05-29T02:20:05.266Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-29T02:20:05.266Z] GC before operation: completed in 13.568 ms, heap usage 21.850 MB -> 4.756 MB. [2025-05-29T02:20:12.154Z] Camera thread performed 122 scans. [2025-05-29T02:20:12.154Z] ====== philosophers (scala) [default], iteration 14 completed (7670.415 ms) ====== [2025-05-29T02:20:12.154Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-29T02:20:12.154Z] GC before operation: completed in 14.611 ms, heap usage 190.747 MB -> 4.756 MB. [2025-05-29T02:20:20.650Z] Camera thread performed 122 scans. [2025-05-29T02:20:20.650Z] ====== philosophers (scala) [default], iteration 15 completed (7774.750 ms) ====== [2025-05-29T02:20:20.650Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-29T02:20:20.650Z] GC before operation: completed in 15.260 ms, heap usage 71.367 MB -> 4.756 MB. [2025-05-29T02:20:27.705Z] Camera thread performed 122 scans. [2025-05-29T02:20:27.705Z] ====== philosophers (scala) [default], iteration 16 completed (7821.638 ms) ====== [2025-05-29T02:20:27.705Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-29T02:20:27.705Z] GC before operation: completed in 12.877 ms, heap usage 77.293 MB -> 4.756 MB. [2025-05-29T02:20:36.003Z] Camera thread performed 122 scans. [2025-05-29T02:20:36.003Z] ====== philosophers (scala) [default], iteration 17 completed (7677.682 ms) ====== [2025-05-29T02:20:36.003Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-29T02:20:36.003Z] GC before operation: completed in 14.471 ms, heap usage 35.976 MB -> 4.756 MB. [2025-05-29T02:20:44.442Z] Camera thread performed 122 scans. [2025-05-29T02:20:44.442Z] ====== philosophers (scala) [default], iteration 18 completed (7784.917 ms) ====== [2025-05-29T02:20:44.442Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-29T02:20:44.442Z] GC before operation: completed in 14.658 ms, heap usage 108.669 MB -> 4.756 MB. [2025-05-29T02:20:51.327Z] Camera thread performed 122 scans. [2025-05-29T02:20:51.327Z] ====== philosophers (scala) [default], iteration 19 completed (7697.998 ms) ====== [2025-05-29T02:20:51.327Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-29T02:20:51.327Z] GC before operation: completed in 14.277 ms, heap usage 148.649 MB -> 4.756 MB. [2025-05-29T02:21:00.961Z] Camera thread performed 122 scans. [2025-05-29T02:21:00.961Z] ====== philosophers (scala) [default], iteration 20 completed (7748.847 ms) ====== [2025-05-29T02:21:00.961Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-29T02:21:00.961Z] GC before operation: completed in 14.186 ms, heap usage 53.311 MB -> 4.757 MB. [2025-05-29T02:21:06.580Z] Camera thread performed 122 scans. [2025-05-29T02:21:06.580Z] ====== philosophers (scala) [default], iteration 21 completed (7612.388 ms) ====== [2025-05-29T02:21:06.580Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-29T02:21:06.580Z] GC before operation: completed in 14.920 ms, heap usage 66.053 MB -> 4.756 MB. [2025-05-29T02:21:14.879Z] Camera thread performed 122 scans. [2025-05-29T02:21:14.880Z] ====== philosophers (scala) [default], iteration 22 completed (7838.069 ms) ====== [2025-05-29T02:21:14.880Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-29T02:21:14.880Z] GC before operation: completed in 13.202 ms, heap usage 80.678 MB -> 4.757 MB. [2025-05-29T02:21:23.180Z] Camera thread performed 122 scans. [2025-05-29T02:21:23.180Z] ====== philosophers (scala) [default], iteration 23 completed (7825.137 ms) ====== [2025-05-29T02:21:23.180Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-29T02:21:23.180Z] GC before operation: completed in 12.225 ms, heap usage 45.394 MB -> 4.756 MB. [2025-05-29T02:21:30.061Z] Camera thread performed 122 scans. [2025-05-29T02:21:30.061Z] ====== philosophers (scala) [default], iteration 24 completed (7869.246 ms) ====== [2025-05-29T02:21:30.061Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-29T02:21:30.061Z] GC before operation: completed in 14.528 ms, heap usage 30.463 MB -> 4.757 MB. [2025-05-29T02:21:38.358Z] Camera thread performed 122 scans. [2025-05-29T02:21:38.358Z] ====== philosophers (scala) [default], iteration 25 completed (7671.912 ms) ====== [2025-05-29T02:21:38.358Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-29T02:21:38.358Z] GC before operation: completed in 12.373 ms, heap usage 66.254 MB -> 4.756 MB. [2025-05-29T02:21:45.545Z] Camera thread performed 122 scans. [2025-05-29T02:21:45.545Z] ====== philosophers (scala) [default], iteration 26 completed (7783.653 ms) ====== [2025-05-29T02:21:45.545Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-29T02:21:45.545Z] GC before operation: completed in 14.278 ms, heap usage 156.819 MB -> 4.757 MB. [2025-05-29T02:21:53.840Z] Camera thread performed 122 scans. [2025-05-29T02:21:53.840Z] ====== philosophers (scala) [default], iteration 27 completed (7869.626 ms) ====== [2025-05-29T02:21:53.840Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-29T02:21:53.840Z] GC before operation: completed in 13.901 ms, heap usage 209.524 MB -> 4.756 MB. [2025-05-29T02:22:02.138Z] Camera thread performed 122 scans. [2025-05-29T02:22:02.138Z] ====== philosophers (scala) [default], iteration 28 completed (7663.505 ms) ====== [2025-05-29T02:22:02.138Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-29T02:22:02.138Z] GC before operation: completed in 13.336 ms, heap usage 71.327 MB -> 4.757 MB. [2025-05-29T02:22:09.018Z] Camera thread performed 122 scans. [2025-05-29T02:22:09.018Z] ====== philosophers (scala) [default], iteration 29 completed (7897.489 ms) ====== [2025-05-29T02:22:09.018Z] ----------------------------------- [2025-05-29T02:22:09.018Z] renaissance-philosophers_0_PASSED [2025-05-29T02:22:09.018Z] ----------------------------------- [2025-05-29T02:22:09.018Z] [2025-05-29T02:22:09.018Z] TEST TEARDOWN: [2025-05-29T02:22:09.018Z] Nothing to be done for teardown. [2025-05-29T02:22:09.018Z] renaissance-philosophers_0 Finish Time: Thu May 29 02:22:08 2025 Epoch Time (ms): 1748485328814