[2025-05-11T08:18:11.108Z] Running test renaissance-philosophers_0 ... [2025-05-11T08:18:11.108Z] =============================================== [2025-05-11T08:18:11.499Z] renaissance-philosophers_0 Start Time: Sun May 11 08:18:11 2025 Epoch Time (ms): 1746951491147 [2025-05-11T08:18:11.499Z] variation: NoOptions [2025-05-11T08:18:11.499Z] JVM_OPTIONS: [2025-05-11T08:18:11.499Z] { \ [2025-05-11T08:18:11.499Z] echo ""; echo "TEST SETUP:"; \ [2025-05-11T08:18:11.499Z] echo "Nothing to be done for setup."; \ [2025-05-11T08:18:11.499Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-philosophers_0"; \ [2025-05-11T08:18:11.499Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-philosophers_0"; \ [2025-05-11T08:18:11.499Z] echo ""; echo "TESTING:"; \ [2025-05-11T08:18:11.499Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-05-11T08:18:11.499Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-11T08:18:11.499Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-11T08:18:11.499Z] echo "Nothing to be done for teardown."; \ [2025-05-11T08:18:11.499Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\TestTargetResult"; [2025-05-11T08:18:11.499Z] [2025-05-11T08:18:11.499Z] TEST SETUP: [2025-05-11T08:18:11.499Z] Nothing to be done for setup. [2025-05-11T08:18:11.499Z] [2025-05-11T08:18:11.499Z] TESTING: [2025-05-11T08:18:12.579Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-11T08:18:12.579Z] GC before operation: completed in 9.537 ms, heap usage 15.579 MB -> 4.473 MB. [2025-05-11T08:18:15.004Z] Camera thread performed 122 scans. [2025-05-11T08:18:15.004Z] ====== philosophers (scala) [default], iteration 0 completed (2319.629 ms) ====== [2025-05-11T08:18:15.004Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-11T08:18:15.004Z] GC before operation: completed in 18.620 ms, heap usage 21.655 MB -> 4.870 MB. [2025-05-11T08:18:16.118Z] Camera thread performed 122 scans. [2025-05-11T08:18:16.118Z] ====== philosophers (scala) [default], iteration 1 completed (1210.828 ms) ====== [2025-05-11T08:18:16.118Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-11T08:18:16.118Z] GC before operation: completed in 9.520 ms, heap usage 36.041 MB -> 4.872 MB. [2025-05-11T08:18:17.699Z] Camera thread performed 122 scans. [2025-05-11T08:18:17.699Z] ====== philosophers (scala) [default], iteration 2 completed (1443.926 ms) ====== [2025-05-11T08:18:17.699Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-11T08:18:17.700Z] GC before operation: completed in 9.573 ms, heap usage 7.914 MB -> 4.876 MB. [2025-05-11T08:18:19.282Z] Camera thread performed 122 scans. [2025-05-11T08:18:19.282Z] ====== philosophers (scala) [default], iteration 3 completed (1477.390 ms) ====== [2025-05-11T08:18:19.282Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-11T08:18:19.282Z] GC before operation: completed in 9.305 ms, heap usage 45.848 MB -> 4.877 MB. [2025-05-11T08:18:20.373Z] Camera thread performed 122 scans. [2025-05-11T08:18:20.373Z] ====== philosophers (scala) [default], iteration 4 completed (1454.930 ms) ====== [2025-05-11T08:18:20.373Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-11T08:18:20.373Z] GC before operation: completed in 9.866 ms, heap usage 33.744 MB -> 4.878 MB. [2025-05-11T08:18:21.950Z] Camera thread performed 122 scans. [2025-05-11T08:18:21.950Z] ====== philosophers (scala) [default], iteration 5 completed (1349.009 ms) ====== [2025-05-11T08:18:21.950Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-11T08:18:21.950Z] GC before operation: completed in 11.794 ms, heap usage 56.782 MB -> 4.879 MB. [2025-05-11T08:18:23.530Z] Camera thread performed 122 scans. [2025-05-11T08:18:23.530Z] ====== philosophers (scala) [default], iteration 6 completed (1451.910 ms) ====== [2025-05-11T08:18:23.530Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-11T08:18:23.530Z] GC before operation: completed in 9.590 ms, heap usage 33.122 MB -> 4.879 MB. [2025-05-11T08:18:24.623Z] Camera thread performed 122 scans. [2025-05-11T08:18:24.623Z] ====== philosophers (scala) [default], iteration 7 completed (1465.732 ms) ====== [2025-05-11T08:18:24.623Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-11T08:18:24.623Z] GC before operation: completed in 9.703 ms, heap usage 53.090 MB -> 4.880 MB. [2025-05-11T08:18:26.198Z] Camera thread performed 122 scans. [2025-05-11T08:18:26.198Z] ====== philosophers (scala) [default], iteration 8 completed (1421.142 ms) ====== [2025-05-11T08:18:26.198Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-11T08:18:26.198Z] GC before operation: completed in 9.468 ms, heap usage 8.944 MB -> 4.881 MB. [2025-05-11T08:18:27.789Z] Camera thread performed 122 scans. [2025-05-11T08:18:27.789Z] ====== philosophers (scala) [default], iteration 9 completed (1509.255 ms) ====== [2025-05-11T08:18:27.789Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-11T08:18:27.789Z] GC before operation: completed in 9.728 ms, heap usage 8.427 MB -> 4.880 MB. [2025-05-11T08:18:29.368Z] Camera thread performed 122 scans. [2025-05-11T08:18:29.368Z] ====== philosophers (scala) [default], iteration 10 completed (1510.087 ms) ====== [2025-05-11T08:18:29.368Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-11T08:18:29.368Z] GC before operation: completed in 9.834 ms, heap usage 14.425 MB -> 4.882 MB. [2025-05-11T08:18:30.928Z] Camera thread performed 122 scans. [2025-05-11T08:18:30.928Z] ====== philosophers (scala) [default], iteration 11 completed (1494.787 ms) ====== [2025-05-11T08:18:30.928Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-11T08:18:30.928Z] GC before operation: completed in 9.682 ms, heap usage 42.426 MB -> 4.881 MB. [2025-05-11T08:18:32.017Z] Camera thread performed 122 scans. [2025-05-11T08:18:32.017Z] ====== philosophers (scala) [default], iteration 12 completed (1400.605 ms) ====== [2025-05-11T08:18:32.017Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-11T08:18:32.017Z] GC before operation: completed in 9.956 ms, heap usage 49.104 MB -> 4.881 MB. [2025-05-11T08:18:33.594Z] Camera thread performed 122 scans. [2025-05-11T08:18:33.594Z] ====== philosophers (scala) [default], iteration 13 completed (1441.233 ms) ====== [2025-05-11T08:18:33.594Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-11T08:18:33.594Z] GC before operation: completed in 10.227 ms, heap usage 45.094 MB -> 4.883 MB. [2025-05-11T08:18:35.171Z] Camera thread performed 122 scans. [2025-05-11T08:18:35.171Z] ====== philosophers (scala) [default], iteration 14 completed (1484.516 ms) ====== [2025-05-11T08:18:35.171Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-11T08:18:35.171Z] GC before operation: completed in 10.283 ms, heap usage 55.099 MB -> 4.883 MB. [2025-05-11T08:18:36.748Z] Camera thread performed 122 scans. [2025-05-11T08:18:36.748Z] ====== philosophers (scala) [default], iteration 15 completed (1559.620 ms) ====== [2025-05-11T08:18:36.748Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-11T08:18:36.748Z] GC before operation: completed in 10.139 ms, heap usage 19.418 MB -> 4.883 MB. [2025-05-11T08:18:38.321Z] Camera thread performed 122 scans. [2025-05-11T08:18:38.321Z] ====== philosophers (scala) [default], iteration 16 completed (1515.200 ms) ====== [2025-05-11T08:18:38.321Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-11T08:18:38.321Z] GC before operation: completed in 10.506 ms, heap usage 56.127 MB -> 4.884 MB. [2025-05-11T08:18:39.897Z] Camera thread performed 122 scans. [2025-05-11T08:18:39.897Z] ====== philosophers (scala) [default], iteration 17 completed (1609.243 ms) ====== [2025-05-11T08:18:39.897Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-11T08:18:39.897Z] GC before operation: completed in 10.025 ms, heap usage 11.116 MB -> 4.884 MB. [2025-05-11T08:18:41.478Z] Camera thread performed 122 scans. [2025-05-11T08:18:41.478Z] ====== philosophers (scala) [default], iteration 18 completed (1376.884 ms) ====== [2025-05-11T08:18:41.478Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-11T08:18:41.478Z] GC before operation: completed in 9.629 ms, heap usage 23.900 MB -> 4.884 MB. [2025-05-11T08:18:43.055Z] Camera thread performed 122 scans. [2025-05-11T08:18:43.055Z] ====== philosophers (scala) [default], iteration 19 completed (1527.606 ms) ====== [2025-05-11T08:18:43.055Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-11T08:18:43.055Z] GC before operation: completed in 10.013 ms, heap usage 17.406 MB -> 4.884 MB. [2025-05-11T08:18:44.145Z] Camera thread performed 122 scans. [2025-05-11T08:18:44.145Z] ====== philosophers (scala) [default], iteration 20 completed (1512.872 ms) ====== [2025-05-11T08:18:44.145Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-11T08:18:44.145Z] GC before operation: completed in 9.362 ms, heap usage 11.510 MB -> 4.884 MB. [2025-05-11T08:18:45.753Z] Camera thread performed 122 scans. [2025-05-11T08:18:45.753Z] ====== philosophers (scala) [default], iteration 21 completed (1455.781 ms) ====== [2025-05-11T08:18:45.753Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-11T08:18:45.753Z] GC before operation: completed in 10.064 ms, heap usage 44.672 MB -> 4.884 MB. [2025-05-11T08:18:47.327Z] Camera thread performed 122 scans. [2025-05-11T08:18:47.327Z] ====== philosophers (scala) [default], iteration 22 completed (1563.278 ms) ====== [2025-05-11T08:18:47.327Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-11T08:18:47.327Z] GC before operation: completed in 10.624 ms, heap usage 43.398 MB -> 4.885 MB. [2025-05-11T08:18:48.903Z] Camera thread performed 122 scans. [2025-05-11T08:18:48.903Z] ====== philosophers (scala) [default], iteration 23 completed (1420.808 ms) ====== [2025-05-11T08:18:48.903Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-11T08:18:48.903Z] GC before operation: completed in 9.692 ms, heap usage 19.577 MB -> 4.885 MB. [2025-05-11T08:18:50.483Z] Camera thread performed 122 scans. [2025-05-11T08:18:50.483Z] ====== philosophers (scala) [default], iteration 24 completed (1529.876 ms) ====== [2025-05-11T08:18:50.483Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-11T08:18:50.483Z] GC before operation: completed in 10.924 ms, heap usage 35.418 MB -> 4.885 MB. [2025-05-11T08:18:51.573Z] Camera thread performed 122 scans. [2025-05-11T08:18:51.573Z] ====== philosophers (scala) [default], iteration 25 completed (1435.967 ms) ====== [2025-05-11T08:18:51.573Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-11T08:18:51.573Z] GC before operation: completed in 10.254 ms, heap usage 32.814 MB -> 4.885 MB. [2025-05-11T08:18:53.154Z] Camera thread performed 122 scans. [2025-05-11T08:18:53.154Z] ====== philosophers (scala) [default], iteration 26 completed (1495.951 ms) ====== [2025-05-11T08:18:53.154Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-11T08:18:53.154Z] GC before operation: completed in 9.859 ms, heap usage 48.665 MB -> 4.885 MB. [2025-05-11T08:18:54.729Z] Camera thread performed 122 scans. [2025-05-11T08:18:54.729Z] ====== philosophers (scala) [default], iteration 27 completed (1494.701 ms) ====== [2025-05-11T08:18:54.729Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-11T08:18:54.729Z] GC before operation: completed in 10.423 ms, heap usage 13.432 MB -> 4.885 MB. [2025-05-11T08:18:56.303Z] Camera thread performed 122 scans. [2025-05-11T08:18:56.303Z] ====== philosophers (scala) [default], iteration 28 completed (1478.595 ms) ====== [2025-05-11T08:18:56.303Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-11T08:18:56.303Z] GC before operation: completed in 9.860 ms, heap usage 17.052 MB -> 4.885 MB. [2025-05-11T08:18:57.863Z] Camera thread performed 122 scans. [2025-05-11T08:18:57.863Z] ====== philosophers (scala) [default], iteration 29 completed (1492.628 ms) ====== [2025-05-11T08:18:57.863Z] ----------------------------------- [2025-05-11T08:18:57.863Z] renaissance-philosophers_0_PASSED [2025-05-11T08:18:57.863Z] ----------------------------------- [2025-05-11T08:18:57.864Z] [2025-05-11T08:18:57.864Z] TEST TEARDOWN: [2025-05-11T08:18:57.864Z] Nothing to be done for teardown. [2025-05-11T08:18:57.864Z] renaissance-philosophers_0 Finish Time: Sun May 11 08:18:57 2025 Epoch Time (ms): 1746951537808