[2025-05-16T10:25:33.001Z] Running test renaissance-philosophers_0 ... [2025-05-16T10:25:33.001Z] =============================================== [2025-05-16T10:25:33.001Z] renaissance-philosophers_0 Start Time: Fri May 16 10:25:32 2025 Epoch Time (ms): 1747391132169 [2025-05-16T10:25:33.001Z] variation: NoOptions [2025-05-16T10:25:33.001Z] JVM_OPTIONS: [2025-05-16T10:25:33.001Z] { \ [2025-05-16T10:25:33.001Z] echo ""; echo "TEST SETUP:"; \ [2025-05-16T10:25:33.001Z] echo "Nothing to be done for setup."; \ [2025-05-16T10:25:33.001Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17473908236653/renaissance-philosophers_0"; \ [2025-05-16T10:25:33.001Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17473908236653/renaissance-philosophers_0"; \ [2025-05-16T10:25:33.001Z] echo ""; echo "TESTING:"; \ [2025-05-16T10:25:33.001Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17473908236653/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-16T10:25:33.001Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17473908236653/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-16T10:25:33.001Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-16T10:25:33.001Z] echo "Nothing to be done for teardown."; \ [2025-05-16T10:25:33.001Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17473908236653/TestTargetResult"; [2025-05-16T10:25:33.001Z] [2025-05-16T10:25:33.001Z] TEST SETUP: [2025-05-16T10:25:33.001Z] Nothing to be done for setup. [2025-05-16T10:25:33.001Z] [2025-05-16T10:25:33.001Z] TESTING: [2025-05-16T10:25:33.977Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-16T10:25:33.977Z] GC before operation: completed in 13.456 ms, heap usage 14.277 MB -> 4.681 MB. [2025-05-16T10:25:39.034Z] Camera thread performed 122 scans. [2025-05-16T10:25:39.034Z] ====== philosophers (scala) [default], iteration 0 completed (5619.283 ms) ====== [2025-05-16T10:25:39.034Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-16T10:25:39.034Z] GC before operation: completed in 15.303 ms, heap usage 28.081 MB -> 5.045 MB. [2025-05-16T10:25:44.674Z] Camera thread performed 122 scans. [2025-05-16T10:25:44.674Z] ====== philosophers (scala) [default], iteration 1 completed (5110.896 ms) ====== [2025-05-16T10:25:44.674Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-16T10:25:44.674Z] GC before operation: completed in 14.492 ms, heap usage 12.592 MB -> 5.044 MB. [2025-05-16T10:25:48.864Z] Camera thread performed 122 scans. [2025-05-16T10:25:48.864Z] ====== philosophers (scala) [default], iteration 2 completed (4260.015 ms) ====== [2025-05-16T10:25:48.864Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-16T10:25:48.864Z] GC before operation: completed in 11.837 ms, heap usage 23.891 MB -> 5.045 MB. [2025-05-16T10:25:54.311Z] Camera thread performed 122 scans. [2025-05-16T10:25:54.311Z] ====== philosophers (scala) [default], iteration 3 completed (5391.386 ms) ====== [2025-05-16T10:25:54.311Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-16T10:25:54.311Z] GC before operation: completed in 11.653 ms, heap usage 8.692 MB -> 5.045 MB. [2025-05-16T10:25:59.672Z] Camera thread performed 122 scans. [2025-05-16T10:25:59.672Z] ====== philosophers (scala) [default], iteration 4 completed (5187.074 ms) ====== [2025-05-16T10:25:59.672Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-16T10:25:59.672Z] GC before operation: completed in 11.701 ms, heap usage 25.945 MB -> 5.046 MB. [2025-05-16T10:26:03.918Z] Camera thread performed 122 scans. [2025-05-16T10:26:03.918Z] ====== philosophers (scala) [default], iteration 5 completed (5065.934 ms) ====== [2025-05-16T10:26:03.918Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-16T10:26:03.918Z] GC before operation: completed in 11.262 ms, heap usage 18.314 MB -> 5.046 MB. [2025-05-16T10:26:09.417Z] Camera thread performed 122 scans. [2025-05-16T10:26:09.417Z] ====== philosophers (scala) [default], iteration 6 completed (4926.628 ms) ====== [2025-05-16T10:26:09.417Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-16T10:26:09.417Z] GC before operation: completed in 10.850 ms, heap usage 38.599 MB -> 5.047 MB. [2025-05-16T10:26:14.769Z] Camera thread performed 122 scans. [2025-05-16T10:26:14.770Z] ====== philosophers (scala) [default], iteration 7 completed (5067.752 ms) ====== [2025-05-16T10:26:14.770Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-16T10:26:14.770Z] GC before operation: completed in 12.254 ms, heap usage 53.164 MB -> 5.047 MB. [2025-05-16T10:26:18.974Z] Camera thread performed 122 scans. [2025-05-16T10:26:18.974Z] ====== philosophers (scala) [default], iteration 8 completed (4785.500 ms) ====== [2025-05-16T10:26:18.974Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-16T10:26:18.974Z] GC before operation: completed in 10.175 ms, heap usage 18.296 MB -> 5.047 MB. [2025-05-16T10:26:23.904Z] Camera thread performed 122 scans. [2025-05-16T10:26:23.904Z] ====== philosophers (scala) [default], iteration 9 completed (4871.357 ms) ====== [2025-05-16T10:26:23.904Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-16T10:26:23.904Z] GC before operation: completed in 16.089 ms, heap usage 7.231 MB -> 5.047 MB. [2025-05-16T10:26:29.260Z] Camera thread performed 122 scans. [2025-05-16T10:26:29.260Z] ====== philosophers (scala) [default], iteration 10 completed (5052.302 ms) ====== [2025-05-16T10:26:29.260Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-16T10:26:29.260Z] GC before operation: completed in 11.007 ms, heap usage 6.080 MB -> 5.047 MB. [2025-05-16T10:26:33.564Z] Camera thread performed 122 scans. [2025-05-16T10:26:33.564Z] ====== philosophers (scala) [default], iteration 11 completed (4914.392 ms) ====== [2025-05-16T10:26:33.564Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-16T10:26:33.564Z] GC before operation: completed in 11.883 ms, heap usage 16.493 MB -> 5.048 MB. [2025-05-16T10:26:38.914Z] Camera thread performed 122 scans. [2025-05-16T10:26:38.914Z] ====== philosophers (scala) [default], iteration 12 completed (4596.240 ms) ====== [2025-05-16T10:26:38.914Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-16T10:26:38.914Z] GC before operation: completed in 10.703 ms, heap usage 6.279 MB -> 5.048 MB. [2025-05-16T10:26:43.148Z] Camera thread performed 122 scans. [2025-05-16T10:26:43.148Z] ====== philosophers (scala) [default], iteration 13 completed (4917.191 ms) ====== [2025-05-16T10:26:43.148Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-16T10:26:43.148Z] GC before operation: completed in 11.429 ms, heap usage 40.167 MB -> 5.048 MB. [2025-05-16T10:26:47.275Z] Camera thread performed 122 scans. [2025-05-16T10:26:47.275Z] ====== philosophers (scala) [default], iteration 14 completed (3948.493 ms) ====== [2025-05-16T10:26:47.275Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-16T10:26:47.275Z] GC before operation: completed in 11.347 ms, heap usage 21.128 MB -> 5.049 MB. [2025-05-16T10:26:51.414Z] Camera thread performed 122 scans. [2025-05-16T10:26:51.414Z] ====== philosophers (scala) [default], iteration 15 completed (4286.268 ms) ====== [2025-05-16T10:26:51.414Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-16T10:26:51.414Z] GC before operation: completed in 13.466 ms, heap usage 36.280 MB -> 5.049 MB. [2025-05-16T10:26:56.766Z] Camera thread performed 122 scans. [2025-05-16T10:26:56.766Z] ====== philosophers (scala) [default], iteration 16 completed (4939.108 ms) ====== [2025-05-16T10:26:56.766Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-16T10:26:56.766Z] GC before operation: completed in 14.257 ms, heap usage 43.470 MB -> 5.049 MB. [2025-05-16T10:27:02.110Z] Camera thread performed 122 scans. [2025-05-16T10:27:02.110Z] ====== philosophers (scala) [default], iteration 17 completed (4964.677 ms) ====== [2025-05-16T10:27:02.110Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-16T10:27:02.110Z] GC before operation: completed in 10.661 ms, heap usage 18.869 MB -> 5.049 MB. [2025-05-16T10:27:06.150Z] Camera thread performed 122 scans. [2025-05-16T10:27:06.150Z] ====== philosophers (scala) [default], iteration 18 completed (4549.721 ms) ====== [2025-05-16T10:27:06.150Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-16T10:27:06.150Z] GC before operation: completed in 11.747 ms, heap usage 33.525 MB -> 5.049 MB. [2025-05-16T10:27:11.499Z] Camera thread performed 122 scans. [2025-05-16T10:27:11.499Z] ====== philosophers (scala) [default], iteration 19 completed (5049.444 ms) ====== [2025-05-16T10:27:11.499Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-16T10:27:11.499Z] GC before operation: completed in 12.570 ms, heap usage 27.965 MB -> 5.049 MB. [2025-05-16T10:27:17.039Z] Camera thread performed 122 scans. [2025-05-16T10:27:17.040Z] ====== philosophers (scala) [default], iteration 20 completed (5039.977 ms) ====== [2025-05-16T10:27:17.040Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-16T10:27:17.040Z] GC before operation: completed in 15.787 ms, heap usage 45.870 MB -> 5.049 MB. [2025-05-16T10:27:21.173Z] Camera thread performed 122 scans. [2025-05-16T10:27:21.173Z] ====== philosophers (scala) [default], iteration 21 completed (4991.711 ms) ====== [2025-05-16T10:27:21.173Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-16T10:27:21.173Z] GC before operation: completed in 11.654 ms, heap usage 36.274 MB -> 5.049 MB. [2025-05-16T10:27:26.606Z] Camera thread performed 122 scans. [2025-05-16T10:27:26.606Z] ====== philosophers (scala) [default], iteration 22 completed (5310.295 ms) ====== [2025-05-16T10:27:26.606Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-16T10:27:26.606Z] GC before operation: completed in 13.367 ms, heap usage 18.280 MB -> 5.049 MB. [2025-05-16T10:27:31.952Z] Camera thread performed 122 scans. [2025-05-16T10:27:31.952Z] ====== philosophers (scala) [default], iteration 23 completed (4997.708 ms) ====== [2025-05-16T10:27:31.952Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-16T10:27:31.952Z] GC before operation: completed in 11.677 ms, heap usage 7.620 MB -> 5.049 MB. [2025-05-16T10:27:37.482Z] Camera thread performed 122 scans. [2025-05-16T10:27:37.482Z] ====== philosophers (scala) [default], iteration 24 completed (5169.235 ms) ====== [2025-05-16T10:27:37.482Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-16T10:27:37.482Z] GC before operation: completed in 11.340 ms, heap usage 35.519 MB -> 5.049 MB. [2025-05-16T10:27:42.833Z] Camera thread performed 122 scans. [2025-05-16T10:27:42.833Z] ====== philosophers (scala) [default], iteration 25 completed (5291.207 ms) ====== [2025-05-16T10:27:42.833Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-16T10:27:42.833Z] GC before operation: completed in 12.986 ms, heap usage 36.525 MB -> 5.049 MB. [2025-05-16T10:27:47.667Z] Camera thread performed 122 scans. [2025-05-16T10:27:47.667Z] ====== philosophers (scala) [default], iteration 26 completed (5232.172 ms) ====== [2025-05-16T10:27:47.667Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-16T10:27:47.667Z] GC before operation: completed in 10.831 ms, heap usage 17.521 MB -> 5.049 MB. [2025-05-16T10:27:53.028Z] Camera thread performed 122 scans. [2025-05-16T10:27:53.028Z] ====== philosophers (scala) [default], iteration 27 completed (5192.813 ms) ====== [2025-05-16T10:27:53.028Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-16T10:27:53.028Z] GC before operation: completed in 13.003 ms, heap usage 14.253 MB -> 5.049 MB. [2025-05-16T10:27:57.158Z] Camera thread performed 122 scans. [2025-05-16T10:27:57.158Z] ====== philosophers (scala) [default], iteration 28 completed (4773.835 ms) ====== [2025-05-16T10:27:57.158Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-16T10:27:57.158Z] GC before operation: completed in 11.594 ms, heap usage 15.046 MB -> 5.049 MB. [2025-05-16T10:28:02.534Z] Camera thread performed 122 scans. [2025-05-16T10:28:02.534Z] ====== philosophers (scala) [default], iteration 29 completed (4412.494 ms) ====== [2025-05-16T10:28:02.534Z] ----------------------------------- [2025-05-16T10:28:02.534Z] renaissance-philosophers_0_PASSED [2025-05-16T10:28:02.534Z] ----------------------------------- [2025-05-16T10:28:02.534Z] [2025-05-16T10:28:02.534Z] TEST TEARDOWN: [2025-05-16T10:28:02.534Z] Nothing to be done for teardown. [2025-05-16T10:28:02.534Z] renaissance-philosophers_0 Finish Time: Fri May 16 10:28:01 2025 Epoch Time (ms): 1747391281483