[2025-07-01T01:01:01.882Z] Running test renaissance-philosophers_0 ... [2025-07-01T01:01:01.882Z] =============================================== [2025-07-01T01:01:02.209Z] renaissance-philosophers_0 Start Time: Tue Jul 1 01:01:01 2025 Epoch Time (ms): 1751331661930 [2025-07-01T01:01:02.209Z] variation: NoOptions [2025-07-01T01:01:02.209Z] JVM_OPTIONS: [2025-07-01T01:01:02.209Z] { \ [2025-07-01T01:01:02.209Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T01:01:02.209Z] echo "Nothing to be done for setup."; \ [2025-07-01T01:01:02.209Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\renaissance-philosophers_0"; \ [2025-07-01T01:01:02.209Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\renaissance-philosophers_0"; \ [2025-07-01T01:01:02.209Z] echo ""; echo "TESTING:"; \ [2025-07-01T01:01:02.209Z] "c:/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-07-01T01:01:02.209Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T01:01:02.209Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T01:01:02.209Z] echo "Nothing to be done for teardown."; \ [2025-07-01T01:01:02.209Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513292438879\\TestTargetResult"; [2025-07-01T01:01:02.209Z] [2025-07-01T01:01:02.209Z] TEST SETUP: [2025-07-01T01:01:02.209Z] Nothing to be done for setup. [2025-07-01T01:01:02.209Z] [2025-07-01T01:01:02.209Z] TESTING: [2025-07-01T01:01:03.331Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-01T01:01:03.332Z] GC before operation: completed in 10.638 ms, heap usage 15.926 MB -> 4.634 MB. [2025-07-01T01:01:05.097Z] Camera thread performed 122 scans. [2025-07-01T01:01:05.097Z] ====== philosophers (scala) [default], iteration 0 completed (1770.681 ms) ====== [2025-07-01T01:01:05.097Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-01T01:01:05.097Z] GC before operation: completed in 12.784 ms, heap usage 23.825 MB -> 4.944 MB. [2025-07-01T01:01:06.745Z] Camera thread performed 122 scans. [2025-07-01T01:01:06.745Z] ====== philosophers (scala) [default], iteration 1 completed (1362.730 ms) ====== [2025-07-01T01:01:06.745Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-01T01:01:06.745Z] GC before operation: completed in 11.145 ms, heap usage 74.959 MB -> 4.943 MB. [2025-07-01T01:01:07.852Z] Camera thread performed 122 scans. [2025-07-01T01:01:07.852Z] ====== philosophers (scala) [default], iteration 2 completed (1501.150 ms) ====== [2025-07-01T01:01:07.852Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-01T01:01:07.852Z] GC before operation: completed in 9.713 ms, heap usage 10.144 MB -> 4.945 MB. [2025-07-01T01:01:09.455Z] Camera thread performed 122 scans. [2025-07-01T01:01:09.455Z] ====== philosophers (scala) [default], iteration 3 completed (1410.557 ms) ====== [2025-07-01T01:01:09.455Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-01T01:01:09.455Z] GC before operation: completed in 9.478 ms, heap usage 20.992 MB -> 4.945 MB. [2025-07-01T01:01:11.056Z] Camera thread performed 122 scans. [2025-07-01T01:01:11.056Z] ====== philosophers (scala) [default], iteration 4 completed (1461.463 ms) ====== [2025-07-01T01:01:11.056Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-01T01:01:11.056Z] GC before operation: completed in 9.589 ms, heap usage 47.277 MB -> 4.945 MB. [2025-07-01T01:01:12.655Z] Camera thread performed 122 scans. [2025-07-01T01:01:12.655Z] ====== philosophers (scala) [default], iteration 5 completed (1521.050 ms) ====== [2025-07-01T01:01:12.655Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-01T01:01:12.655Z] GC before operation: completed in 10.182 ms, heap usage 79.591 MB -> 4.949 MB. [2025-07-01T01:01:13.764Z] Camera thread performed 122 scans. [2025-07-01T01:01:13.764Z] ====== philosophers (scala) [default], iteration 6 completed (1436.051 ms) ====== [2025-07-01T01:01:13.764Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-01T01:01:13.764Z] GC before operation: completed in 9.295 ms, heap usage 69.109 MB -> 4.951 MB. [2025-07-01T01:01:15.363Z] Camera thread performed 122 scans. [2025-07-01T01:01:15.363Z] ====== philosophers (scala) [default], iteration 7 completed (1334.820 ms) ====== [2025-07-01T01:01:15.363Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-01T01:01:15.363Z] GC before operation: completed in 9.378 ms, heap usage 43.168 MB -> 4.950 MB. [2025-07-01T01:01:16.962Z] Camera thread performed 122 scans. [2025-07-01T01:01:16.962Z] ====== philosophers (scala) [default], iteration 8 completed (1536.752 ms) ====== [2025-07-01T01:01:16.963Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-01T01:01:16.963Z] GC before operation: completed in 9.669 ms, heap usage 30.143 MB -> 4.951 MB. [2025-07-01T01:01:18.070Z] Camera thread performed 122 scans. [2025-07-01T01:01:18.070Z] ====== philosophers (scala) [default], iteration 9 completed (1272.072 ms) ====== [2025-07-01T01:01:18.070Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-01T01:01:18.070Z] GC before operation: completed in 11.240 ms, heap usage 51.105 MB -> 4.952 MB. [2025-07-01T01:01:19.670Z] Camera thread performed 122 scans. [2025-07-01T01:01:19.670Z] ====== philosophers (scala) [default], iteration 10 completed (1517.506 ms) ====== [2025-07-01T01:01:19.670Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-01T01:01:19.670Z] GC before operation: completed in 9.443 ms, heap usage 16.157 MB -> 4.952 MB. [2025-07-01T01:01:20.778Z] Camera thread performed 122 scans. [2025-07-01T01:01:20.778Z] ====== philosophers (scala) [default], iteration 11 completed (1297.486 ms) ====== [2025-07-01T01:01:20.778Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-01T01:01:20.778Z] GC before operation: completed in 8.849 ms, heap usage 25.902 MB -> 4.953 MB. [2025-07-01T01:01:22.382Z] Camera thread performed 122 scans. [2025-07-01T01:01:22.382Z] ====== philosophers (scala) [default], iteration 12 completed (1267.871 ms) ====== [2025-07-01T01:01:22.382Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-01T01:01:22.382Z] GC before operation: completed in 9.085 ms, heap usage 42.117 MB -> 4.953 MB. [2025-07-01T01:01:23.492Z] Camera thread performed 122 scans. [2025-07-01T01:01:23.492Z] ====== philosophers (scala) [default], iteration 13 completed (1470.756 ms) ====== [2025-07-01T01:01:23.492Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-01T01:01:23.492Z] GC before operation: completed in 9.883 ms, heap usage 76.168 MB -> 4.953 MB. [2025-07-01T01:01:25.098Z] Camera thread performed 122 scans. [2025-07-01T01:01:25.098Z] ====== philosophers (scala) [default], iteration 14 completed (1482.749 ms) ====== [2025-07-01T01:01:25.098Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-01T01:01:25.098Z] GC before operation: completed in 9.664 ms, heap usage 11.956 MB -> 4.953 MB. [2025-07-01T01:01:26.702Z] Camera thread performed 122 scans. [2025-07-01T01:01:26.702Z] ====== philosophers (scala) [default], iteration 15 completed (1409.638 ms) ====== [2025-07-01T01:01:26.702Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-01T01:01:26.702Z] GC before operation: completed in 9.903 ms, heap usage 73.010 MB -> 4.953 MB. [2025-07-01T01:01:27.811Z] Camera thread performed 122 scans. [2025-07-01T01:01:27.811Z] ====== philosophers (scala) [default], iteration 16 completed (1399.257 ms) ====== [2025-07-01T01:01:27.811Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-01T01:01:27.811Z] GC before operation: completed in 9.499 ms, heap usage 40.657 MB -> 4.953 MB. [2025-07-01T01:01:29.418Z] Camera thread performed 122 scans. [2025-07-01T01:01:29.418Z] ====== philosophers (scala) [default], iteration 17 completed (1291.129 ms) ====== [2025-07-01T01:01:29.418Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-01T01:01:29.418Z] GC before operation: completed in 9.564 ms, heap usage 32.476 MB -> 4.953 MB. [2025-07-01T01:01:31.019Z] Camera thread performed 122 scans. [2025-07-01T01:01:31.019Z] ====== philosophers (scala) [default], iteration 18 completed (1500.149 ms) ====== [2025-07-01T01:01:31.019Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-01T01:01:31.019Z] GC before operation: completed in 9.550 ms, heap usage 72.554 MB -> 4.953 MB. [2025-07-01T01:01:32.668Z] Camera thread performed 122 scans. [2025-07-01T01:01:32.668Z] ====== philosophers (scala) [default], iteration 19 completed (1615.456 ms) ====== [2025-07-01T01:01:32.668Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-01T01:01:32.668Z] GC before operation: completed in 10.062 ms, heap usage 54.952 MB -> 4.953 MB. [2025-07-01T01:01:33.778Z] Camera thread performed 122 scans. [2025-07-01T01:01:33.778Z] ====== philosophers (scala) [default], iteration 20 completed (1441.054 ms) ====== [2025-07-01T01:01:33.778Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-01T01:01:33.778Z] GC before operation: completed in 9.561 ms, heap usage 45.167 MB -> 4.953 MB. [2025-07-01T01:01:35.379Z] Camera thread performed 122 scans. [2025-07-01T01:01:35.379Z] ====== philosophers (scala) [default], iteration 21 completed (1510.815 ms) ====== [2025-07-01T01:01:35.379Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-01T01:01:35.379Z] GC before operation: completed in 9.532 ms, heap usage 24.087 MB -> 4.953 MB. [2025-07-01T01:01:36.983Z] Camera thread performed 122 scans. [2025-07-01T01:01:36.983Z] ====== philosophers (scala) [default], iteration 22 completed (1391.341 ms) ====== [2025-07-01T01:01:36.983Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-01T01:01:36.983Z] GC before operation: completed in 9.437 ms, heap usage 54.571 MB -> 4.954 MB. [2025-07-01T01:01:38.587Z] Camera thread performed 122 scans. [2025-07-01T01:01:38.587Z] ====== philosophers (scala) [default], iteration 23 completed (1540.222 ms) ====== [2025-07-01T01:01:38.587Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-01T01:01:38.587Z] GC before operation: completed in 9.938 ms, heap usage 53.161 MB -> 4.953 MB. [2025-07-01T01:01:39.696Z] Camera thread performed 122 scans. [2025-07-01T01:01:39.696Z] ====== philosophers (scala) [default], iteration 24 completed (1466.961 ms) ====== [2025-07-01T01:01:39.696Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-01T01:01:39.696Z] GC before operation: completed in 9.753 ms, heap usage 32.039 MB -> 4.954 MB. [2025-07-01T01:01:41.301Z] Camera thread performed 122 scans. [2025-07-01T01:01:41.301Z] ====== philosophers (scala) [default], iteration 25 completed (1337.120 ms) ====== [2025-07-01T01:01:41.301Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-01T01:01:41.301Z] GC before operation: completed in 10.034 ms, heap usage 53.815 MB -> 4.954 MB. [2025-07-01T01:01:42.413Z] Camera thread performed 122 scans. [2025-07-01T01:01:42.413Z] ====== philosophers (scala) [default], iteration 26 completed (1443.783 ms) ====== [2025-07-01T01:01:42.413Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-01T01:01:42.413Z] GC before operation: completed in 10.450 ms, heap usage 80.608 MB -> 4.954 MB. [2025-07-01T01:01:44.017Z] Camera thread performed 122 scans. [2025-07-01T01:01:44.017Z] ====== philosophers (scala) [default], iteration 27 completed (1487.927 ms) ====== [2025-07-01T01:01:44.017Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-01T01:01:44.017Z] GC before operation: completed in 9.981 ms, heap usage 31.625 MB -> 4.954 MB. [2025-07-01T01:01:45.623Z] Camera thread performed 122 scans. [2025-07-01T01:01:45.623Z] ====== philosophers (scala) [default], iteration 28 completed (1533.638 ms) ====== [2025-07-01T01:01:45.623Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-01T01:01:45.623Z] GC before operation: completed in 11.617 ms, heap usage 36.171 MB -> 4.954 MB. [2025-07-01T01:01:47.211Z] Camera thread performed 122 scans. [2025-07-01T01:01:47.211Z] ====== philosophers (scala) [default], iteration 29 completed (1524.710 ms) ====== [2025-07-01T01:01:47.211Z] ----------------------------------- [2025-07-01T01:01:47.211Z] renaissance-philosophers_0_PASSED [2025-07-01T01:01:47.211Z] ----------------------------------- [2025-07-01T01:01:47.211Z] [2025-07-01T01:01:47.211Z] TEST TEARDOWN: [2025-07-01T01:01:47.211Z] Nothing to be done for teardown. [2025-07-01T01:01:47.522Z] renaissance-philosophers_0 Finish Time: Tue Jul 1 01:01:47 2025 Epoch Time (ms): 1751331707226