[2025-12-04T05:23:00.517Z] Running test renaissance-philosophers_0 ... [2025-12-04T05:23:00.517Z] =============================================== [2025-12-04T05:23:00.829Z] renaissance-philosophers_0 Start Time: Thu Dec 4 05:23:00 2025 Epoch Time (ms): 1764825780675 [2025-12-04T05:23:00.829Z] variation: NoOptions [2025-12-04T05:23:01.140Z] JVM_OPTIONS: [2025-12-04T05:23:01.140Z] { \ [2025-12-04T05:23:01.140Z] echo ""; echo "TEST SETUP:"; \ [2025-12-04T05:23:01.140Z] echo "Nothing to be done for setup."; \ [2025-12-04T05:23:01.140Z] mkdir -p "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17648248843970\\renaissance-philosophers_0"; \ [2025-12-04T05:23:01.140Z] cd "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17648248843970\\renaissance-philosophers_0"; \ [2025-12-04T05:23:01.140Z] echo ""; echo "TESTING:"; \ [2025-12-04T05:23:01.140Z] "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_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:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17648248843970\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-12-04T05:23:01.140Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/; rm -f -r "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17648248843970\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-04T05:23:01.140Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-04T05:23:01.140Z] echo "Nothing to be done for teardown."; \ [2025-12-04T05:23:01.140Z] } 2>&1 | tee -a "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17648248843970\\TestTargetResult"; [2025-12-04T05:23:01.450Z] [2025-12-04T05:23:01.451Z] TEST SETUP: [2025-12-04T05:23:01.451Z] Nothing to be done for setup. [2025-12-04T05:23:01.451Z] [2025-12-04T05:23:01.451Z] TESTING: [2025-12-04T05:23:02.543Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-04T05:23:02.543Z] GC before operation: completed in 10.343 ms, heap usage 5.812 MB -> 4.589 MB. [2025-12-04T05:23:04.708Z] Camera thread performed 122 scans. [2025-12-04T05:23:04.708Z] ====== philosophers (scala) [default], iteration 0 completed (1833.182 ms) ====== [2025-12-04T05:23:04.708Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-04T05:23:04.708Z] GC before operation: completed in 13.793 ms, heap usage 30.836 MB -> 4.969 MB. [2025-12-04T05:23:05.822Z] Camera thread performed 122 scans. [2025-12-04T05:23:05.823Z] ====== philosophers (scala) [default], iteration 1 completed (1436.383 ms) ====== [2025-12-04T05:23:05.823Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-04T05:23:05.823Z] GC before operation: completed in 12.463 ms, heap usage 56.246 MB -> 4.969 MB. [2025-12-04T05:23:07.988Z] Camera thread performed 122 scans. [2025-12-04T05:23:07.988Z] ====== philosophers (scala) [default], iteration 2 completed (1972.531 ms) ====== [2025-12-04T05:23:07.988Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-04T05:23:07.988Z] GC before operation: completed in 10.690 ms, heap usage 60.616 MB -> 4.971 MB. [2025-12-04T05:23:09.569Z] Camera thread performed 122 scans. [2025-12-04T05:23:09.569Z] ====== philosophers (scala) [default], iteration 3 completed (1902.967 ms) ====== [2025-12-04T05:23:09.570Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-04T05:23:09.880Z] GC before operation: completed in 10.789 ms, heap usage 55.064 MB -> 4.971 MB. [2025-12-04T05:23:11.464Z] Camera thread performed 122 scans. [2025-12-04T05:23:11.464Z] ====== philosophers (scala) [default], iteration 4 completed (1824.846 ms) ====== [2025-12-04T05:23:11.464Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-04T05:23:11.464Z] GC before operation: completed in 10.708 ms, heap usage 70.457 MB -> 4.971 MB. [2025-12-04T05:23:13.622Z] Camera thread performed 122 scans. [2025-12-04T05:23:13.622Z] ====== philosophers (scala) [default], iteration 5 completed (1855.497 ms) ====== [2025-12-04T05:23:13.622Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-04T05:23:13.623Z] GC before operation: completed in 10.428 ms, heap usage 75.045 MB -> 4.972 MB. [2025-12-04T05:23:15.208Z] Camera thread performed 122 scans. [2025-12-04T05:23:15.208Z] ====== philosophers (scala) [default], iteration 6 completed (1914.343 ms) ====== [2025-12-04T05:23:15.208Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-04T05:23:15.208Z] GC before operation: completed in 10.180 ms, heap usage 82.098 MB -> 4.973 MB. [2025-12-04T05:23:16.791Z] Camera thread performed 122 scans. [2025-12-04T05:23:16.791Z] ====== philosophers (scala) [default], iteration 7 completed (1302.833 ms) ====== [2025-12-04T05:23:16.791Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-04T05:23:16.791Z] GC before operation: completed in 10.606 ms, heap usage 45.968 MB -> 4.976 MB. [2025-12-04T05:23:17.894Z] Camera thread performed 122 scans. [2025-12-04T05:23:17.894Z] ====== philosophers (scala) [default], iteration 8 completed (1328.851 ms) ====== [2025-12-04T05:23:17.894Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-04T05:23:17.894Z] GC before operation: completed in 10.305 ms, heap usage 58.408 MB -> 4.977 MB. [2025-12-04T05:23:19.552Z] Camera thread performed 122 scans. [2025-12-04T05:23:19.552Z] ====== philosophers (scala) [default], iteration 9 completed (1270.375 ms) ====== [2025-12-04T05:23:19.552Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-04T05:23:19.552Z] GC before operation: completed in 10.884 ms, heap usage 29.921 MB -> 4.977 MB. [2025-12-04T05:23:21.139Z] Camera thread performed 122 scans. [2025-12-04T05:23:21.139Z] ====== philosophers (scala) [default], iteration 10 completed (1837.120 ms) ====== [2025-12-04T05:23:21.139Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-04T05:23:21.139Z] GC before operation: completed in 10.470 ms, heap usage 12.889 MB -> 4.977 MB. [2025-12-04T05:23:23.300Z] Camera thread performed 122 scans. [2025-12-04T05:23:23.301Z] ====== philosophers (scala) [default], iteration 11 completed (1760.359 ms) ====== [2025-12-04T05:23:23.301Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-04T05:23:23.301Z] GC before operation: completed in 10.409 ms, heap usage 76.781 MB -> 4.978 MB. [2025-12-04T05:23:24.884Z] Camera thread performed 122 scans. [2025-12-04T05:23:24.884Z] ====== philosophers (scala) [default], iteration 12 completed (1789.887 ms) ====== [2025-12-04T05:23:24.884Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-04T05:23:24.884Z] GC before operation: completed in 9.916 ms, heap usage 20.872 MB -> 4.978 MB. [2025-12-04T05:23:26.469Z] Camera thread performed 122 scans. [2025-12-04T05:23:26.469Z] ====== philosophers (scala) [default], iteration 13 completed (1864.968 ms) ====== [2025-12-04T05:23:26.469Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-04T05:23:26.469Z] GC before operation: completed in 10.179 ms, heap usage 16.432 MB -> 4.978 MB. [2025-12-04T05:23:28.637Z] Camera thread performed 122 scans. [2025-12-04T05:23:28.637Z] ====== philosophers (scala) [default], iteration 14 completed (1913.567 ms) ====== [2025-12-04T05:23:28.637Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-04T05:23:28.637Z] GC before operation: completed in 10.195 ms, heap usage 66.392 MB -> 4.978 MB. [2025-12-04T05:23:30.816Z] Camera thread performed 122 scans. [2025-12-04T05:23:30.816Z] ====== philosophers (scala) [default], iteration 15 completed (1913.519 ms) ====== [2025-12-04T05:23:30.816Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-04T05:23:30.816Z] GC before operation: completed in 9.998 ms, heap usage 37.063 MB -> 4.978 MB. [2025-12-04T05:23:32.404Z] Camera thread performed 122 scans. [2025-12-04T05:23:32.404Z] ====== philosophers (scala) [default], iteration 16 completed (1793.869 ms) ====== [2025-12-04T05:23:32.404Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-04T05:23:32.404Z] GC before operation: completed in 10.950 ms, heap usage 62.853 MB -> 4.978 MB. [2025-12-04T05:23:34.567Z] Camera thread performed 122 scans. [2025-12-04T05:23:34.567Z] ====== philosophers (scala) [default], iteration 17 completed (1933.565 ms) ====== [2025-12-04T05:23:34.567Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-04T05:23:34.567Z] GC before operation: completed in 10.305 ms, heap usage 54.433 MB -> 4.978 MB. [2025-12-04T05:23:36.152Z] Camera thread performed 122 scans. [2025-12-04T05:23:36.153Z] ====== philosophers (scala) [default], iteration 18 completed (1920.812 ms) ====== [2025-12-04T05:23:36.153Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-04T05:23:36.153Z] GC before operation: completed in 10.269 ms, heap usage 26.626 MB -> 4.978 MB. [2025-12-04T05:23:37.743Z] Camera thread performed 122 scans. [2025-12-04T05:23:37.743Z] ====== philosophers (scala) [default], iteration 19 completed (1581.645 ms) ====== [2025-12-04T05:23:37.743Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-04T05:23:37.743Z] GC before operation: completed in 10.288 ms, heap usage 65.578 MB -> 4.978 MB. [2025-12-04T05:23:39.910Z] Camera thread performed 122 scans. [2025-12-04T05:23:39.910Z] ====== philosophers (scala) [default], iteration 20 completed (1807.556 ms) ====== [2025-12-04T05:23:39.910Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-04T05:23:39.910Z] GC before operation: completed in 10.593 ms, heap usage 28.803 MB -> 4.979 MB. [2025-12-04T05:23:41.500Z] Camera thread performed 122 scans. [2025-12-04T05:23:41.500Z] ====== philosophers (scala) [default], iteration 21 completed (1801.884 ms) ====== [2025-12-04T05:23:41.500Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-04T05:23:41.500Z] GC before operation: completed in 10.019 ms, heap usage 32.063 MB -> 4.979 MB. [2025-12-04T05:23:43.661Z] Camera thread performed 122 scans. [2025-12-04T05:23:43.661Z] ====== philosophers (scala) [default], iteration 22 completed (2003.885 ms) ====== [2025-12-04T05:23:43.661Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-04T05:23:43.661Z] GC before operation: completed in 10.258 ms, heap usage 12.777 MB -> 4.979 MB. [2025-12-04T05:23:45.243Z] Camera thread performed 122 scans. [2025-12-04T05:23:45.243Z] ====== philosophers (scala) [default], iteration 23 completed (1796.132 ms) ====== [2025-12-04T05:23:45.243Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-04T05:23:45.243Z] GC before operation: completed in 10.933 ms, heap usage 15.186 MB -> 4.980 MB. [2025-12-04T05:23:47.404Z] Camera thread performed 122 scans. [2025-12-04T05:23:47.404Z] ====== philosophers (scala) [default], iteration 24 completed (1822.400 ms) ====== [2025-12-04T05:23:47.404Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-04T05:23:47.404Z] GC before operation: completed in 9.977 ms, heap usage 70.361 MB -> 4.980 MB. [2025-12-04T05:23:48.988Z] Camera thread performed 122 scans. [2025-12-04T05:23:48.988Z] ====== philosophers (scala) [default], iteration 25 completed (1844.650 ms) ====== [2025-12-04T05:23:48.988Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-04T05:23:48.988Z] GC before operation: completed in 10.260 ms, heap usage 67.185 MB -> 4.979 MB. [2025-12-04T05:23:50.574Z] Camera thread performed 122 scans. [2025-12-04T05:23:50.574Z] ====== philosophers (scala) [default], iteration 26 completed (1826.990 ms) ====== [2025-12-04T05:23:50.574Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-04T05:23:50.574Z] GC before operation: completed in 10.223 ms, heap usage 69.968 MB -> 4.980 MB. [2025-12-04T05:23:52.740Z] Camera thread performed 122 scans. [2025-12-04T05:23:52.740Z] ====== philosophers (scala) [default], iteration 27 completed (1879.356 ms) ====== [2025-12-04T05:23:52.740Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-04T05:23:52.740Z] GC before operation: completed in 10.062 ms, heap usage 16.106 MB -> 4.980 MB. [2025-12-04T05:23:54.320Z] Camera thread performed 122 scans. [2025-12-04T05:23:54.321Z] ====== philosophers (scala) [default], iteration 28 completed (1765.324 ms) ====== [2025-12-04T05:23:54.321Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-04T05:23:54.321Z] GC before operation: completed in 10.061 ms, heap usage 21.187 MB -> 4.980 MB. [2025-12-04T05:23:56.481Z] Camera thread performed 122 scans. [2025-12-04T05:23:56.481Z] ====== philosophers (scala) [default], iteration 29 completed (1872.276 ms) ====== [2025-12-04T05:23:56.481Z] ----------------------------------- [2025-12-04T05:23:56.481Z] renaissance-philosophers_0_PASSED [2025-12-04T05:23:56.481Z] ----------------------------------- [2025-12-04T05:23:56.481Z] [2025-12-04T05:23:56.481Z] TEST TEARDOWN: [2025-12-04T05:23:56.481Z] Nothing to be done for teardown. [2025-12-04T05:23:56.481Z] renaissance-philosophers_0 Finish Time: Thu Dec 4 05:23:56 2025 Epoch Time (ms): 1764825836477