[2025-11-20T05:37:36.193Z] Running test renaissance-philosophers_0 ... [2025-11-20T05:37:36.193Z] =============================================== [2025-11-20T05:37:36.193Z] renaissance-philosophers_0 Start Time: Thu Nov 20 05:37:35 2025 Epoch Time (ms): 1763617055559 [2025-11-20T05:37:36.193Z] variation: NoOptions [2025-11-20T05:37:36.193Z] JVM_OPTIONS: [2025-11-20T05:37:36.193Z] { \ [2025-11-20T05:37:36.193Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T05:37:36.193Z] echo "Nothing to be done for setup."; \ [2025-11-20T05:37:36.194Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636157962141\\renaissance-philosophers_0"; \ [2025-11-20T05:37:36.194Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636157962141\\renaissance-philosophers_0"; \ [2025-11-20T05:37:36.194Z] echo ""; echo "TESTING:"; \ [2025-11-20T05:37:36.194Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_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-32_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636157962141\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-11-20T05:37:36.194Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636157962141\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T05:37:36.194Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T05:37:36.194Z] echo "Nothing to be done for teardown."; \ [2025-11-20T05:37:36.194Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636157962141\\TestTargetResult"; [2025-11-20T05:37:36.194Z] [2025-11-20T05:37:36.194Z] TEST SETUP: [2025-11-20T05:37:36.194Z] Nothing to be done for setup. [2025-11-20T05:37:36.194Z] [2025-11-20T05:37:36.194Z] TESTING: [2025-11-20T05:37:37.258Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-20T05:37:37.258Z] GC before operation: completed in 10.925 ms, heap usage 6.215 MB -> 4.127 MB. [2025-11-20T05:37:41.068Z] Camera thread performed 122 scans. [2025-11-20T05:37:41.068Z] ====== philosophers (scala) [default], iteration 0 completed (3597.904 ms) ====== [2025-11-20T05:37:41.068Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-20T05:37:41.068Z] GC before operation: completed in 11.154 ms, heap usage 5.847 MB -> 4.671 MB. [2025-11-20T05:37:44.042Z] Camera thread performed 122 scans. [2025-11-20T05:37:44.042Z] ====== philosophers (scala) [default], iteration 1 completed (3340.246 ms) ====== [2025-11-20T05:37:44.042Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-20T05:37:44.042Z] GC before operation: completed in 10.579 ms, heap usage 7.537 MB -> 4.849 MB. [2025-11-20T05:37:47.836Z] Camera thread performed 122 scans. [2025-11-20T05:37:47.836Z] ====== philosophers (scala) [default], iteration 2 completed (3147.091 ms) ====== [2025-11-20T05:37:47.836Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-20T05:37:47.836Z] GC before operation: completed in 12.218 ms, heap usage 6.649 MB -> 3.897 MB. [2025-11-20T05:37:50.861Z] Camera thread performed 122 scans. [2025-11-20T05:37:50.861Z] ====== philosophers (scala) [default], iteration 3 completed (3132.447 ms) ====== [2025-11-20T05:37:50.861Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-20T05:37:50.861Z] GC before operation: completed in 9.475 ms, heap usage 5.327 MB -> 4.073 MB. [2025-11-20T05:37:53.864Z] Camera thread performed 122 scans. [2025-11-20T05:37:53.864Z] ====== philosophers (scala) [default], iteration 4 completed (3162.501 ms) ====== [2025-11-20T05:37:53.864Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-20T05:37:53.864Z] GC before operation: completed in 11.046 ms, heap usage 8.626 MB -> 3.901 MB. [2025-11-20T05:37:56.841Z] Camera thread performed 122 scans. [2025-11-20T05:37:56.841Z] ====== philosophers (scala) [default], iteration 5 completed (3206.476 ms) ====== [2025-11-20T05:37:56.841Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-20T05:37:56.841Z] GC before operation: completed in 8.777 ms, heap usage 5.284 MB -> 3.901 MB. [2025-11-20T05:37:59.816Z] Camera thread performed 122 scans. [2025-11-20T05:37:59.816Z] ====== philosophers (scala) [default], iteration 6 completed (2998.646 ms) ====== [2025-11-20T05:37:59.816Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-20T05:37:59.816Z] GC before operation: completed in 10.860 ms, heap usage 6.911 MB -> 3.369 MB. [2025-11-20T05:38:02.793Z] Camera thread performed 122 scans. [2025-11-20T05:38:02.793Z] ====== philosophers (scala) [default], iteration 7 completed (3138.019 ms) ====== [2025-11-20T05:38:02.793Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-20T05:38:02.793Z] GC before operation: completed in 8.663 ms, heap usage 6.157 MB -> 3.545 MB. [2025-11-20T05:38:06.607Z] Camera thread performed 122 scans. [2025-11-20T05:38:06.607Z] ====== philosophers (scala) [default], iteration 8 completed (3127.545 ms) ====== [2025-11-20T05:38:06.607Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-20T05:38:06.607Z] GC before operation: completed in 8.928 ms, heap usage 4.158 MB -> 3.721 MB. [2025-11-20T05:38:09.579Z] Camera thread performed 122 scans. [2025-11-20T05:38:09.579Z] ====== philosophers (scala) [default], iteration 9 completed (3230.744 ms) ====== [2025-11-20T05:38:09.579Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-20T05:38:09.579Z] GC before operation: completed in 8.845 ms, heap usage 7.895 MB -> 3.897 MB. [2025-11-20T05:38:12.564Z] Camera thread performed 122 scans. [2025-11-20T05:38:12.565Z] ====== philosophers (scala) [default], iteration 10 completed (3168.886 ms) ====== [2025-11-20T05:38:12.565Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-20T05:38:12.565Z] GC before operation: completed in 8.497 ms, heap usage 4.616 MB -> 3.369 MB. [2025-11-20T05:38:16.360Z] Camera thread performed 122 scans. [2025-11-20T05:38:16.360Z] ====== philosophers (scala) [default], iteration 11 completed (3283.368 ms) ====== [2025-11-20T05:38:16.360Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-20T05:38:16.360Z] GC before operation: completed in 8.609 ms, heap usage 4.037 MB -> 3.546 MB. [2025-11-20T05:38:19.341Z] Camera thread performed 122 scans. [2025-11-20T05:38:19.341Z] ====== philosophers (scala) [default], iteration 12 completed (3176.508 ms) ====== [2025-11-20T05:38:19.341Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-20T05:38:19.341Z] GC before operation: completed in 8.421 ms, heap usage 5.345 MB -> 3.722 MB. [2025-11-20T05:38:22.318Z] Camera thread performed 122 scans. [2025-11-20T05:38:22.318Z] ====== philosophers (scala) [default], iteration 13 completed (3294.205 ms) ====== [2025-11-20T05:38:22.318Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-20T05:38:22.318Z] GC before operation: completed in 8.681 ms, heap usage 7.200 MB -> 3.899 MB. [2025-11-20T05:38:26.127Z] Camera thread performed 122 scans. [2025-11-20T05:38:26.127Z] ====== philosophers (scala) [default], iteration 14 completed (3231.595 ms) ====== [2025-11-20T05:38:26.127Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-20T05:38:26.127Z] GC before operation: completed in 8.269 ms, heap usage 4.265 MB -> 3.369 MB. [2025-11-20T05:38:29.128Z] Camera thread performed 122 scans. [2025-11-20T05:38:29.128Z] ====== philosophers (scala) [default], iteration 15 completed (3476.338 ms) ====== [2025-11-20T05:38:29.128Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-20T05:38:29.128Z] GC before operation: completed in 8.852 ms, heap usage 6.727 MB -> 3.544 MB. [2025-11-20T05:38:32.109Z] Camera thread performed 122 scans. [2025-11-20T05:38:32.109Z] ====== philosophers (scala) [default], iteration 16 completed (3272.876 ms) ====== [2025-11-20T05:38:32.109Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-20T05:38:32.109Z] GC before operation: completed in 8.373 ms, heap usage 3.776 MB -> 3.721 MB. [2025-11-20T05:38:35.078Z] Camera thread performed 122 scans. [2025-11-20T05:38:35.078Z] ====== philosophers (scala) [default], iteration 17 completed (2897.047 ms) ====== [2025-11-20T05:38:35.078Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-20T05:38:35.078Z] GC before operation: completed in 9.928 ms, heap usage 6.011 MB -> 3.897 MB. [2025-11-20T05:38:38.050Z] Camera thread performed 122 scans. [2025-11-20T05:38:38.050Z] ====== philosophers (scala) [default], iteration 18 completed (2877.398 ms) ====== [2025-11-20T05:38:38.050Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-20T05:38:38.050Z] GC before operation: completed in 9.009 ms, heap usage 7.301 MB -> 3.369 MB. [2025-11-20T05:38:41.031Z] Camera thread performed 122 scans. [2025-11-20T05:38:41.031Z] ====== philosophers (scala) [default], iteration 19 completed (3026.037 ms) ====== [2025-11-20T05:38:41.031Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-20T05:38:41.031Z] GC before operation: completed in 8.839 ms, heap usage 7.781 MB -> 3.544 MB. [2025-11-20T05:38:43.998Z] Camera thread performed 122 scans. [2025-11-20T05:38:43.998Z] ====== philosophers (scala) [default], iteration 20 completed (3000.324 ms) ====== [2025-11-20T05:38:43.998Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-20T05:38:43.998Z] GC before operation: completed in 8.868 ms, heap usage 6.835 MB -> 3.720 MB. [2025-11-20T05:38:47.793Z] Camera thread performed 122 scans. [2025-11-20T05:38:47.793Z] ====== philosophers (scala) [default], iteration 21 completed (3237.994 ms) ====== [2025-11-20T05:38:47.793Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-20T05:38:47.793Z] GC before operation: completed in 8.660 ms, heap usage 6.265 MB -> 3.897 MB. [2025-11-20T05:38:50.758Z] Camera thread performed 122 scans. [2025-11-20T05:38:50.758Z] ====== philosophers (scala) [default], iteration 22 completed (2956.590 ms) ====== [2025-11-20T05:38:50.758Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-20T05:38:50.758Z] GC before operation: completed in 8.620 ms, heap usage 5.397 MB -> 3.369 MB. [2025-11-20T05:38:53.719Z] Camera thread performed 122 scans. [2025-11-20T05:38:53.719Z] ====== philosophers (scala) [default], iteration 23 completed (2985.009 ms) ====== [2025-11-20T05:38:53.719Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-20T05:38:53.719Z] GC before operation: completed in 8.947 ms, heap usage 6.845 MB -> 3.545 MB. [2025-11-20T05:38:56.692Z] Camera thread performed 122 scans. [2025-11-20T05:38:56.692Z] ====== philosophers (scala) [default], iteration 24 completed (3017.073 ms) ====== [2025-11-20T05:38:56.692Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-20T05:38:56.692Z] GC before operation: completed in 8.834 ms, heap usage 7.400 MB -> 3.720 MB. [2025-11-20T05:38:59.664Z] Camera thread performed 122 scans. [2025-11-20T05:38:59.664Z] ====== philosophers (scala) [default], iteration 25 completed (3144.042 ms) ====== [2025-11-20T05:38:59.664Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-20T05:38:59.664Z] GC before operation: completed in 9.038 ms, heap usage 6.948 MB -> 3.895 MB. [2025-11-20T05:39:03.453Z] Camera thread performed 122 scans. [2025-11-20T05:39:03.453Z] ====== philosophers (scala) [default], iteration 26 completed (3364.238 ms) ====== [2025-11-20T05:39:03.453Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-20T05:39:03.453Z] GC before operation: completed in 8.822 ms, heap usage 6.245 MB -> 3.370 MB. [2025-11-20T05:39:06.414Z] Camera thread performed 122 scans. [2025-11-20T05:39:06.414Z] ====== philosophers (scala) [default], iteration 27 completed (3381.129 ms) ====== [2025-11-20T05:39:06.414Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-20T05:39:06.414Z] GC before operation: completed in 8.533 ms, heap usage 5.614 MB -> 3.546 MB. [2025-11-20T05:39:10.196Z] Camera thread performed 122 scans. [2025-11-20T05:39:10.196Z] ====== philosophers (scala) [default], iteration 28 completed (3322.245 ms) ====== [2025-11-20T05:39:10.196Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-20T05:39:10.196Z] GC before operation: completed in 8.870 ms, heap usage 6.442 MB -> 3.722 MB. [2025-11-20T05:39:13.142Z] Camera thread performed 122 scans. [2025-11-20T05:39:13.142Z] ====== philosophers (scala) [default], iteration 29 completed (3216.232 ms) ====== [2025-11-20T05:39:13.142Z] ----------------------------------- [2025-11-20T05:39:13.142Z] renaissance-philosophers_0_PASSED [2025-11-20T05:39:13.142Z] ----------------------------------- [2025-11-20T05:39:13.142Z] [2025-11-20T05:39:13.142Z] TEST TEARDOWN: [2025-11-20T05:39:13.142Z] Nothing to be done for teardown. [2025-11-20T05:39:13.142Z] renaissance-philosophers_0 Finish Time: Thu Nov 20 05:39:12 2025 Epoch Time (ms): 1763617152832