[2025-06-29T22:59:44.284Z] Running test renaissance-philosophers_0 ... [2025-06-29T22:59:44.284Z] =============================================== [2025-06-29T22:59:44.593Z] renaissance-philosophers_0 Start Time: Sun Jun 29 22:59:44 2025 Epoch Time (ms): 1751237984338 [2025-06-29T22:59:44.593Z] variation: NoOptions [2025-06-29T22:59:44.918Z] JVM_OPTIONS: [2025-06-29T22:59:44.918Z] { \ [2025-06-29T22:59:44.918Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T22:59:44.918Z] echo "Nothing to be done for setup."; \ [2025-06-29T22:59:44.918Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17512353965472\\renaissance-philosophers_0"; \ [2025-06-29T22:59:44.918Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17512353965472\\renaissance-philosophers_0"; \ [2025-06-29T22:59:44.918Z] echo ""; echo "TESTING:"; \ [2025-06-29T22:59:44.918Z] "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_17512353965472\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-06-29T22:59:44.918Z] 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_17512353965472\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T22:59:44.918Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T22:59:44.918Z] echo "Nothing to be done for teardown."; \ [2025-06-29T22:59:44.918Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17512353965472\\TestTargetResult"; [2025-06-29T22:59:44.918Z] [2025-06-29T22:59:44.918Z] TEST SETUP: [2025-06-29T22:59:44.918Z] Nothing to be done for setup. [2025-06-29T22:59:44.918Z] [2025-06-29T22:59:44.918Z] TESTING: [2025-06-29T22:59:46.041Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-29T22:59:46.041Z] GC before operation: completed in 11.352 ms, heap usage 15.886 MB -> 4.627 MB. [2025-06-29T22:59:47.649Z] Camera thread performed 122 scans. [2025-06-29T22:59:47.649Z] ====== philosophers (scala) [default], iteration 0 completed (1579.992 ms) ====== [2025-06-29T22:59:47.649Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-29T22:59:47.649Z] GC before operation: completed in 12.247 ms, heap usage 10.717 MB -> 4.994 MB. [2025-06-29T22:59:48.757Z] Camera thread performed 122 scans. [2025-06-29T22:59:48.757Z] ====== philosophers (scala) [default], iteration 1 completed (1370.141 ms) ====== [2025-06-29T22:59:48.757Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-29T22:59:48.757Z] GC before operation: completed in 10.358 ms, heap usage 30.097 MB -> 4.993 MB. [2025-06-29T22:59:50.348Z] Camera thread performed 122 scans. [2025-06-29T22:59:50.348Z] ====== philosophers (scala) [default], iteration 2 completed (1397.440 ms) ====== [2025-06-29T22:59:50.348Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-29T22:59:50.348Z] GC before operation: completed in 10.157 ms, heap usage 65.665 MB -> 4.995 MB. [2025-06-29T22:59:51.945Z] Camera thread performed 122 scans. [2025-06-29T22:59:51.945Z] ====== philosophers (scala) [default], iteration 3 completed (1376.805 ms) ====== [2025-06-29T22:59:51.945Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-29T22:59:51.945Z] GC before operation: completed in 9.564 ms, heap usage 32.406 MB -> 4.995 MB. [2025-06-29T22:59:53.045Z] Camera thread performed 122 scans. [2025-06-29T22:59:53.045Z] ====== philosophers (scala) [default], iteration 4 completed (1389.271 ms) ====== [2025-06-29T22:59:53.045Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-29T22:59:53.045Z] GC before operation: completed in 9.540 ms, heap usage 56.106 MB -> 4.995 MB. [2025-06-29T22:59:54.641Z] Camera thread performed 122 scans. [2025-06-29T22:59:54.641Z] ====== philosophers (scala) [default], iteration 5 completed (1463.860 ms) ====== [2025-06-29T22:59:54.641Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-29T22:59:54.641Z] GC before operation: completed in 10.399 ms, heap usage 59.968 MB -> 4.999 MB. [2025-06-29T22:59:56.232Z] Camera thread performed 122 scans. [2025-06-29T22:59:56.232Z] ====== philosophers (scala) [default], iteration 6 completed (1444.637 ms) ====== [2025-06-29T22:59:56.232Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-29T22:59:56.232Z] GC before operation: completed in 9.361 ms, heap usage 25.623 MB -> 5.000 MB. [2025-06-29T22:59:57.334Z] Camera thread performed 122 scans. [2025-06-29T22:59:57.334Z] ====== philosophers (scala) [default], iteration 7 completed (1354.695 ms) ====== [2025-06-29T22:59:57.334Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-29T22:59:57.334Z] GC before operation: completed in 9.794 ms, heap usage 12.157 MB -> 5.000 MB. [2025-06-29T22:59:58.925Z] Camera thread performed 122 scans. [2025-06-29T22:59:58.925Z] ====== philosophers (scala) [default], iteration 8 completed (1451.425 ms) ====== [2025-06-29T22:59:58.925Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-29T22:59:58.925Z] GC before operation: completed in 9.888 ms, heap usage 49.584 MB -> 5.001 MB. [2025-06-29T23:00:00.518Z] Camera thread performed 122 scans. [2025-06-29T23:00:00.518Z] ====== philosophers (scala) [default], iteration 9 completed (1390.021 ms) ====== [2025-06-29T23:00:00.518Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-29T23:00:00.518Z] GC before operation: completed in 10.158 ms, heap usage 44.606 MB -> 5.001 MB. [2025-06-29T23:00:01.621Z] Camera thread performed 122 scans. [2025-06-29T23:00:01.621Z] ====== philosophers (scala) [default], iteration 10 completed (1502.808 ms) ====== [2025-06-29T23:00:01.621Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-29T23:00:01.621Z] GC before operation: completed in 9.770 ms, heap usage 80.208 MB -> 5.001 MB. [2025-06-29T23:00:03.257Z] Camera thread performed 122 scans. [2025-06-29T23:00:03.257Z] ====== philosophers (scala) [default], iteration 11 completed (1443.348 ms) ====== [2025-06-29T23:00:03.257Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-29T23:00:03.257Z] GC before operation: completed in 9.836 ms, heap usage 42.997 MB -> 5.001 MB. [2025-06-29T23:00:04.857Z] Camera thread performed 122 scans. [2025-06-29T23:00:04.857Z] ====== philosophers (scala) [default], iteration 12 completed (1424.436 ms) ====== [2025-06-29T23:00:04.857Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-29T23:00:04.857Z] GC before operation: completed in 9.671 ms, heap usage 79.621 MB -> 5.002 MB. [2025-06-29T23:00:05.958Z] Camera thread performed 122 scans. [2025-06-29T23:00:05.958Z] ====== philosophers (scala) [default], iteration 13 completed (1481.135 ms) ====== [2025-06-29T23:00:05.958Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-29T23:00:05.958Z] GC before operation: completed in 10.566 ms, heap usage 30.671 MB -> 5.002 MB. [2025-06-29T23:00:07.544Z] Camera thread performed 122 scans. [2025-06-29T23:00:07.544Z] ====== philosophers (scala) [default], iteration 14 completed (1469.790 ms) ====== [2025-06-29T23:00:07.544Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-29T23:00:07.544Z] GC before operation: completed in 10.139 ms, heap usage 79.199 MB -> 5.002 MB. [2025-06-29T23:00:09.114Z] Camera thread performed 122 scans. [2025-06-29T23:00:09.114Z] ====== philosophers (scala) [default], iteration 15 completed (1441.048 ms) ====== [2025-06-29T23:00:09.114Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-29T23:00:09.114Z] GC before operation: completed in 9.661 ms, heap usage 30.063 MB -> 5.002 MB. [2025-06-29T23:00:10.703Z] Camera thread performed 122 scans. [2025-06-29T23:00:10.703Z] ====== philosophers (scala) [default], iteration 16 completed (1428.294 ms) ====== [2025-06-29T23:00:10.703Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-29T23:00:10.703Z] GC before operation: completed in 9.950 ms, heap usage 34.593 MB -> 5.003 MB. [2025-06-29T23:00:11.802Z] Camera thread performed 122 scans. [2025-06-29T23:00:11.802Z] ====== philosophers (scala) [default], iteration 17 completed (1402.142 ms) ====== [2025-06-29T23:00:11.802Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-29T23:00:11.802Z] GC before operation: completed in 9.834 ms, heap usage 62.202 MB -> 5.002 MB. [2025-06-29T23:00:13.391Z] Camera thread performed 122 scans. [2025-06-29T23:00:13.391Z] ====== philosophers (scala) [default], iteration 18 completed (1439.561 ms) ====== [2025-06-29T23:00:13.391Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-29T23:00:13.391Z] GC before operation: completed in 10.155 ms, heap usage 80.053 MB -> 5.003 MB. [2025-06-29T23:00:14.984Z] Camera thread performed 122 scans. [2025-06-29T23:00:14.985Z] ====== philosophers (scala) [default], iteration 19 completed (1453.558 ms) ====== [2025-06-29T23:00:14.985Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-29T23:00:14.985Z] GC before operation: completed in 10.225 ms, heap usage 27.919 MB -> 5.003 MB. [2025-06-29T23:00:16.083Z] Camera thread performed 122 scans. [2025-06-29T23:00:16.083Z] ====== philosophers (scala) [default], iteration 20 completed (1380.224 ms) ====== [2025-06-29T23:00:16.083Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-29T23:00:16.083Z] GC before operation: completed in 9.826 ms, heap usage 53.934 MB -> 5.003 MB. [2025-06-29T23:00:17.671Z] Camera thread performed 122 scans. [2025-06-29T23:00:17.671Z] ====== philosophers (scala) [default], iteration 21 completed (1422.571 ms) ====== [2025-06-29T23:00:17.671Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-29T23:00:17.671Z] GC before operation: completed in 9.502 ms, heap usage 19.608 MB -> 5.003 MB. [2025-06-29T23:00:19.262Z] Camera thread performed 122 scans. [2025-06-29T23:00:19.262Z] ====== philosophers (scala) [default], iteration 22 completed (1363.038 ms) ====== [2025-06-29T23:00:19.262Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-29T23:00:19.262Z] GC before operation: completed in 10.138 ms, heap usage 78.618 MB -> 5.004 MB. [2025-06-29T23:00:20.358Z] Camera thread performed 122 scans. [2025-06-29T23:00:20.358Z] ====== philosophers (scala) [default], iteration 23 completed (1440.465 ms) ====== [2025-06-29T23:00:20.358Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-29T23:00:20.358Z] GC before operation: completed in 9.966 ms, heap usage 22.206 MB -> 5.004 MB. [2025-06-29T23:00:21.946Z] Camera thread performed 122 scans. [2025-06-29T23:00:21.946Z] ====== philosophers (scala) [default], iteration 24 completed (1458.756 ms) ====== [2025-06-29T23:00:21.946Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-29T23:00:21.946Z] GC before operation: completed in 10.968 ms, heap usage 9.983 MB -> 5.004 MB. [2025-06-29T23:00:23.534Z] Camera thread performed 122 scans. [2025-06-29T23:00:23.534Z] ====== philosophers (scala) [default], iteration 25 completed (1463.435 ms) ====== [2025-06-29T23:00:23.534Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-29T23:00:23.534Z] GC before operation: completed in 9.678 ms, heap usage 37.215 MB -> 5.004 MB. [2025-06-29T23:00:25.125Z] Camera thread performed 122 scans. [2025-06-29T23:00:25.125Z] ====== philosophers (scala) [default], iteration 26 completed (1425.409 ms) ====== [2025-06-29T23:00:25.125Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-29T23:00:25.125Z] GC before operation: completed in 9.974 ms, heap usage 67.607 MB -> 5.004 MB. [2025-06-29T23:00:26.227Z] Camera thread performed 122 scans. [2025-06-29T23:00:26.227Z] ====== philosophers (scala) [default], iteration 27 completed (1471.014 ms) ====== [2025-06-29T23:00:26.227Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-29T23:00:26.227Z] GC before operation: completed in 9.550 ms, heap usage 10.975 MB -> 5.004 MB. [2025-06-29T23:00:27.816Z] Camera thread performed 122 scans. [2025-06-29T23:00:27.816Z] ====== philosophers (scala) [default], iteration 28 completed (1474.767 ms) ====== [2025-06-29T23:00:27.816Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-29T23:00:27.816Z] GC before operation: completed in 9.742 ms, heap usage 68.994 MB -> 5.004 MB. [2025-06-29T23:00:29.389Z] Camera thread performed 122 scans. [2025-06-29T23:00:29.389Z] ====== philosophers (scala) [default], iteration 29 completed (1470.933 ms) ====== [2025-06-29T23:00:29.389Z] ----------------------------------- [2025-06-29T23:00:29.389Z] renaissance-philosophers_0_PASSED [2025-06-29T23:00:29.389Z] ----------------------------------- [2025-06-29T23:00:29.389Z] [2025-06-29T23:00:29.389Z] TEST TEARDOWN: [2025-06-29T23:00:29.389Z] Nothing to be done for teardown. [2025-06-29T23:00:29.698Z] renaissance-philosophers_0 Finish Time: Sun Jun 29 23:00:29 2025 Epoch Time (ms): 1751238029380