[2026-01-31T08:57:50.957Z] Running test renaissance-philosophers_0 ... [2026-01-31T08:57:50.957Z] =============================================== [2026-01-31T08:57:50.957Z] renaissance-philosophers_0 Start Time: Sat Jan 31 08:57:50 2026 Epoch Time (ms): 1769849870916 [2026-01-31T08:57:51.309Z] variation: NoOptions [2026-01-31T08:57:51.309Z] JVM_OPTIONS: [2026-01-31T08:57:51.309Z] { \ [2026-01-31T08:57:51.309Z] echo ""; echo "TEST SETUP:"; \ [2026-01-31T08:57:51.309Z] echo "Nothing to be done for setup."; \ [2026-01-31T08:57:51.309Z] mkdir -p "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698478233685\\renaissance-philosophers_0"; \ [2026-01-31T08:57:51.309Z] cd "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698478233685\\renaissance-philosophers_0"; \ [2026-01-31T08:57:51.309Z] echo ""; echo "TESTING:"; \ [2026-01-31T08:57:51.309Z] "c:/workspace/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:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698478233685\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2026-01-31T08:57:51.309Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698478233685\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-31T08:57:51.309Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-31T08:57:51.309Z] echo "Nothing to be done for teardown."; \ [2026-01-31T08:57:51.309Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698478233685\\TestTargetResult"; [2026-01-31T08:57:51.309Z] [2026-01-31T08:57:51.309Z] TEST SETUP: [2026-01-31T08:57:51.309Z] Nothing to be done for setup. [2026-01-31T08:57:51.309Z] [2026-01-31T08:57:51.309Z] TESTING: [2026-01-31T08:57:52.534Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-01-31T08:57:52.534Z] GC before operation: completed in 7.194 ms, heap usage 5.703 MB -> 4.482 MB. [2026-01-31T08:57:53.734Z] Camera thread performed 122 scans. [2026-01-31T08:57:53.734Z] ====== philosophers (scala) [default], iteration 0 completed (1596.080 ms) ====== [2026-01-31T08:57:53.734Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-01-31T08:57:53.734Z] GC before operation: completed in 9.259 ms, heap usage 45.107 MB -> 4.894 MB. [2026-01-31T08:57:54.874Z] Camera thread performed 122 scans. [2026-01-31T08:57:54.874Z] ====== philosophers (scala) [default], iteration 1 completed (953.702 ms) ====== [2026-01-31T08:57:54.874Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-01-31T08:57:54.874Z] GC before operation: completed in 7.350 ms, heap usage 78.678 MB -> 4.894 MB. [2026-01-31T08:57:55.609Z] Camera thread performed 122 scans. [2026-01-31T08:57:55.609Z] ====== philosophers (scala) [default], iteration 2 completed (825.555 ms) ====== [2026-01-31T08:57:55.609Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-01-31T08:57:55.609Z] GC before operation: completed in 7.648 ms, heap usage 41.048 MB -> 4.896 MB. [2026-01-31T08:57:56.768Z] Camera thread performed 122 scans. [2026-01-31T08:57:56.768Z] ====== philosophers (scala) [default], iteration 3 completed (911.978 ms) ====== [2026-01-31T08:57:56.768Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-01-31T08:57:56.768Z] GC before operation: completed in 7.998 ms, heap usage 72.954 MB -> 4.900 MB. [2026-01-31T08:57:57.491Z] Camera thread performed 122 scans. [2026-01-31T08:57:57.491Z] ====== philosophers (scala) [default], iteration 4 completed (800.216 ms) ====== [2026-01-31T08:57:57.491Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-01-31T08:57:57.491Z] GC before operation: completed in 9.198 ms, heap usage 29.663 MB -> 4.900 MB. [2026-01-31T08:57:58.213Z] Camera thread performed 122 scans. [2026-01-31T08:57:58.213Z] ====== philosophers (scala) [default], iteration 5 completed (938.111 ms) ====== [2026-01-31T08:57:58.213Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-01-31T08:57:58.213Z] GC before operation: completed in 7.597 ms, heap usage 85.146 MB -> 4.901 MB. [2026-01-31T08:57:59.369Z] Camera thread performed 122 scans. [2026-01-31T08:57:59.369Z] ====== philosophers (scala) [default], iteration 6 completed (911.605 ms) ====== [2026-01-31T08:57:59.369Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-01-31T08:57:59.369Z] GC before operation: completed in 8.858 ms, heap usage 87.988 MB -> 4.902 MB. [2026-01-31T08:58:00.090Z] Camera thread performed 122 scans. [2026-01-31T08:58:00.090Z] ====== philosophers (scala) [default], iteration 7 completed (971.672 ms) ====== [2026-01-31T08:58:00.090Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-01-31T08:58:00.090Z] GC before operation: completed in 7.693 ms, heap usage 49.954 MB -> 4.903 MB. [2026-01-31T08:58:01.251Z] Camera thread performed 122 scans. [2026-01-31T08:58:01.251Z] ====== philosophers (scala) [default], iteration 8 completed (974.368 ms) ====== [2026-01-31T08:58:01.251Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-01-31T08:58:01.251Z] GC before operation: completed in 7.734 ms, heap usage 21.669 MB -> 4.903 MB. [2026-01-31T08:58:02.406Z] Camera thread performed 122 scans. [2026-01-31T08:58:02.406Z] ====== philosophers (scala) [default], iteration 9 completed (980.267 ms) ====== [2026-01-31T08:58:02.406Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-01-31T08:58:02.406Z] GC before operation: completed in 13.773 ms, heap usage 96.603 MB -> 4.903 MB. [2026-01-31T08:58:03.128Z] Camera thread performed 122 scans. [2026-01-31T08:58:03.128Z] ====== philosophers (scala) [default], iteration 10 completed (929.699 ms) ====== [2026-01-31T08:58:03.128Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-01-31T08:58:03.128Z] GC before operation: completed in 7.098 ms, heap usage 37.637 MB -> 4.904 MB. [2026-01-31T08:58:04.260Z] Camera thread performed 122 scans. [2026-01-31T08:58:04.260Z] ====== philosophers (scala) [default], iteration 11 completed (966.980 ms) ====== [2026-01-31T08:58:04.260Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-01-31T08:58:04.260Z] GC before operation: completed in 7.504 ms, heap usage 88.557 MB -> 4.904 MB. [2026-01-31T08:58:04.980Z] Camera thread performed 122 scans. [2026-01-31T08:58:04.980Z] ====== philosophers (scala) [default], iteration 12 completed (935.383 ms) ====== [2026-01-31T08:58:04.980Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-01-31T08:58:04.980Z] GC before operation: completed in 7.986 ms, heap usage 35.640 MB -> 4.904 MB. [2026-01-31T08:58:06.137Z] Camera thread performed 122 scans. [2026-01-31T08:58:06.137Z] ====== philosophers (scala) [default], iteration 13 completed (947.571 ms) ====== [2026-01-31T08:58:06.137Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-01-31T08:58:06.137Z] GC before operation: completed in 10.658 ms, heap usage 65.579 MB -> 4.906 MB. [2026-01-31T08:58:06.865Z] Camera thread performed 122 scans. [2026-01-31T08:58:06.865Z] ====== philosophers (scala) [default], iteration 14 completed (918.246 ms) ====== [2026-01-31T08:58:06.865Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-01-31T08:58:06.865Z] GC before operation: completed in 8.988 ms, heap usage 72.075 MB -> 4.906 MB. [2026-01-31T08:58:08.025Z] Camera thread performed 122 scans. [2026-01-31T08:58:08.025Z] ====== philosophers (scala) [default], iteration 15 completed (961.478 ms) ====== [2026-01-31T08:58:08.025Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-01-31T08:58:08.025Z] GC before operation: completed in 7.706 ms, heap usage 52.658 MB -> 4.906 MB. [2026-01-31T08:58:08.747Z] Camera thread performed 122 scans. [2026-01-31T08:58:08.747Z] ====== philosophers (scala) [default], iteration 16 completed (951.625 ms) ====== [2026-01-31T08:58:08.747Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-01-31T08:58:08.747Z] GC before operation: completed in 8.405 ms, heap usage 68.971 MB -> 4.907 MB. [2026-01-31T08:58:09.900Z] Camera thread performed 122 scans. [2026-01-31T08:58:09.900Z] ====== philosophers (scala) [default], iteration 17 completed (974.083 ms) ====== [2026-01-31T08:58:09.900Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-01-31T08:58:09.900Z] GC before operation: completed in 6.647 ms, heap usage 9.125 MB -> 4.907 MB. [2026-01-31T08:58:11.057Z] Camera thread performed 122 scans. [2026-01-31T08:58:11.057Z] ====== philosophers (scala) [default], iteration 18 completed (982.654 ms) ====== [2026-01-31T08:58:11.057Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-01-31T08:58:11.057Z] GC before operation: completed in 7.498 ms, heap usage 85.112 MB -> 4.907 MB. [2026-01-31T08:58:11.775Z] Camera thread performed 122 scans. [2026-01-31T08:58:11.775Z] ====== philosophers (scala) [default], iteration 19 completed (962.797 ms) ====== [2026-01-31T08:58:11.776Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-01-31T08:58:11.776Z] GC before operation: completed in 7.215 ms, heap usage 95.627 MB -> 4.907 MB. [2026-01-31T08:58:12.929Z] Camera thread performed 122 scans. [2026-01-31T08:58:12.929Z] ====== philosophers (scala) [default], iteration 20 completed (939.169 ms) ====== [2026-01-31T08:58:12.929Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-01-31T08:58:12.929Z] GC before operation: completed in 8.274 ms, heap usage 25.062 MB -> 4.907 MB. [2026-01-31T08:58:13.654Z] Camera thread performed 122 scans. [2026-01-31T08:58:13.655Z] ====== philosophers (scala) [default], iteration 21 completed (930.616 ms) ====== [2026-01-31T08:58:13.655Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-01-31T08:58:13.655Z] GC before operation: completed in 13.584 ms, heap usage 56.343 MB -> 4.907 MB. [2026-01-31T08:58:14.807Z] Camera thread performed 122 scans. [2026-01-31T08:58:14.807Z] ====== philosophers (scala) [default], iteration 22 completed (949.609 ms) ====== [2026-01-31T08:58:14.807Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-01-31T08:58:14.807Z] GC before operation: completed in 7.075 ms, heap usage 5.638 MB -> 4.907 MB. [2026-01-31T08:58:15.528Z] Camera thread performed 122 scans. [2026-01-31T08:58:15.528Z] ====== philosophers (scala) [default], iteration 23 completed (977.184 ms) ====== [2026-01-31T08:58:15.528Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-01-31T08:58:15.528Z] GC before operation: completed in 8.170 ms, heap usage 28.372 MB -> 4.907 MB. [2026-01-31T08:58:16.703Z] Camera thread performed 122 scans. [2026-01-31T08:58:16.703Z] ====== philosophers (scala) [default], iteration 24 completed (999.319 ms) ====== [2026-01-31T08:58:16.703Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-01-31T08:58:16.703Z] GC before operation: completed in 9.546 ms, heap usage 48.920 MB -> 4.907 MB. [2026-01-31T08:58:17.863Z] Camera thread performed 122 scans. [2026-01-31T08:58:17.863Z] ====== philosophers (scala) [default], iteration 25 completed (942.281 ms) ====== [2026-01-31T08:58:17.863Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-01-31T08:58:17.863Z] GC before operation: completed in 8.225 ms, heap usage 32.799 MB -> 4.907 MB. [2026-01-31T08:58:18.581Z] Camera thread performed 122 scans. [2026-01-31T08:58:18.581Z] ====== philosophers (scala) [default], iteration 26 completed (974.003 ms) ====== [2026-01-31T08:58:18.581Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-01-31T08:58:18.581Z] GC before operation: completed in 8.073 ms, heap usage 103.727 MB -> 4.907 MB. [2026-01-31T08:58:19.735Z] Camera thread performed 122 scans. [2026-01-31T08:58:19.735Z] ====== philosophers (scala) [default], iteration 27 completed (954.513 ms) ====== [2026-01-31T08:58:19.735Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-01-31T08:58:19.735Z] GC before operation: completed in 8.282 ms, heap usage 70.949 MB -> 4.907 MB. [2026-01-31T08:58:20.458Z] Camera thread performed 122 scans. [2026-01-31T08:58:20.458Z] ====== philosophers (scala) [default], iteration 28 completed (948.445 ms) ====== [2026-01-31T08:58:20.458Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-01-31T08:58:20.458Z] GC before operation: completed in 10.914 ms, heap usage 31.634 MB -> 4.907 MB. [2026-01-31T08:58:21.609Z] Camera thread performed 122 scans. [2026-01-31T08:58:21.609Z] ====== philosophers (scala) [default], iteration 29 completed (929.665 ms) ====== [2026-01-31T08:58:21.609Z] ----------------------------------- [2026-01-31T08:58:21.609Z] renaissance-philosophers_0_PASSED [2026-01-31T08:58:21.609Z] ----------------------------------- [2026-01-31T08:58:21.609Z] [2026-01-31T08:58:21.609Z] TEST TEARDOWN: [2026-01-31T08:58:21.609Z] Nothing to be done for teardown. [2026-01-31T08:58:21.609Z] renaissance-philosophers_0 Finish Time: Sat Jan 31 08:58:21 2026 Epoch Time (ms): 1769849901517