[2025-12-30T18:01:50.903Z] Running test renaissance-philosophers_0 ... [2025-12-30T18:01:50.903Z] =============================================== [2025-12-30T18:01:51.289Z] renaissance-philosophers_0 Start Time: Tue Dec 30 18:01:50 2025 Epoch Time (ms): 1767117710912 [2025-12-30T18:01:51.289Z] variation: NoOptions [2025-12-30T18:01:51.289Z] JVM_OPTIONS: [2025-12-30T18:01:51.289Z] { \ [2025-12-30T18:01:51.289Z] echo ""; echo "TEST SETUP:"; \ [2025-12-30T18:01:51.289Z] echo "Nothing to be done for setup."; \ [2025-12-30T18:01:51.289Z] mkdir -p "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1767117162803\\renaissance-philosophers_0"; \ [2025-12-30T18:01:51.289Z] cd "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1767117162803\\renaissance-philosophers_0"; \ [2025-12-30T18:01:51.289Z] echo ""; echo "TESTING:"; \ [2025-12-30T18:01:51.289Z] "c:/jenkins/workspace/Test_openjdk26_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_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1767117162803\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-12-30T18:01:51.289Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1767117162803\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-30T18:01:51.289Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-30T18:01:51.289Z] echo "Nothing to be done for teardown."; \ [2025-12-30T18:01:51.289Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1767117162803\\TestTargetResult"; [2025-12-30T18:01:51.289Z] [2025-12-30T18:01:51.289Z] TEST SETUP: [2025-12-30T18:01:51.289Z] Nothing to be done for setup. [2025-12-30T18:01:51.289Z] [2025-12-30T18:01:51.289Z] TESTING: [2025-12-30T18:01:52.053Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-30T18:01:52.053Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_1767117162803/renaissance-philosophers_0/launcher-180151-7322400557707493461/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-30T18:01:52.053Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-30T18:01:52.053Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-30T18:01:52.423Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-30T18:01:52.423Z] GC before operation: completed in 14.100 ms, heap usage 6.428 MB -> 5.080 MB. [2025-12-30T18:01:53.959Z] Camera thread performed 122 scans. [2025-12-30T18:01:53.959Z] ====== philosophers (scala) [default], iteration 0 completed (1290.778 ms) ====== [2025-12-30T18:01:53.959Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-30T18:01:53.959Z] GC before operation: completed in 12.124 ms, heap usage 39.544 MB -> 5.420 MB. [2025-12-30T18:01:54.398Z] Camera thread performed 122 scans. [2025-12-30T18:01:54.398Z] ====== philosophers (scala) [default], iteration 1 completed (574.532 ms) ====== [2025-12-30T18:01:54.398Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-30T18:01:54.398Z] GC before operation: completed in 11.301 ms, heap usage 19.268 MB -> 5.422 MB. [2025-12-30T18:01:54.758Z] Camera thread performed 122 scans. [2025-12-30T18:01:54.758Z] ====== philosophers (scala) [default], iteration 2 completed (521.214 ms) ====== [2025-12-30T18:01:54.758Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-30T18:01:54.758Z] GC before operation: completed in 12.150 ms, heap usage 24.687 MB -> 5.422 MB. [2025-12-30T18:01:55.514Z] Camera thread performed 122 scans. [2025-12-30T18:01:55.514Z] ====== philosophers (scala) [default], iteration 3 completed (645.966 ms) ====== [2025-12-30T18:01:55.514Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-30T18:01:55.514Z] GC before operation: completed in 10.752 ms, heap usage 19.655 MB -> 5.423 MB. [2025-12-30T18:01:56.262Z] Camera thread performed 122 scans. [2025-12-30T18:01:56.262Z] ====== philosophers (scala) [default], iteration 4 completed (581.387 ms) ====== [2025-12-30T18:01:56.262Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-30T18:01:56.262Z] GC before operation: completed in 11.034 ms, heap usage 35.477 MB -> 5.423 MB. [2025-12-30T18:01:56.615Z] Camera thread performed 122 scans. [2025-12-30T18:01:56.615Z] ====== philosophers (scala) [default], iteration 5 completed (616.029 ms) ====== [2025-12-30T18:01:56.615Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-30T18:01:56.615Z] GC before operation: completed in 10.787 ms, heap usage 20.956 MB -> 5.424 MB. [2025-12-30T18:01:57.358Z] Camera thread performed 122 scans. [2025-12-30T18:01:57.358Z] ====== philosophers (scala) [default], iteration 6 completed (619.797 ms) ====== [2025-12-30T18:01:57.358Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-30T18:01:57.358Z] GC before operation: completed in 10.266 ms, heap usage 38.387 MB -> 5.424 MB. [2025-12-30T18:01:58.106Z] Camera thread performed 122 scans. [2025-12-30T18:01:58.106Z] ====== philosophers (scala) [default], iteration 7 completed (643.578 ms) ====== [2025-12-30T18:01:58.106Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-30T18:01:58.106Z] GC before operation: completed in 11.026 ms, heap usage 22.431 MB -> 5.424 MB. [2025-12-30T18:01:58.461Z] Camera thread performed 122 scans. [2025-12-30T18:01:58.461Z] ====== philosophers (scala) [default], iteration 8 completed (640.663 ms) ====== [2025-12-30T18:01:58.461Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-30T18:01:58.461Z] GC before operation: completed in 10.937 ms, heap usage 33.550 MB -> 5.424 MB. [2025-12-30T18:01:59.202Z] Camera thread performed 122 scans. [2025-12-30T18:01:59.202Z] ====== philosophers (scala) [default], iteration 9 completed (654.026 ms) ====== [2025-12-30T18:01:59.202Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-30T18:01:59.202Z] GC before operation: completed in 10.830 ms, heap usage 66.960 MB -> 5.424 MB. [2025-12-30T18:01:59.954Z] Camera thread performed 122 scans. [2025-12-30T18:01:59.954Z] ====== philosophers (scala) [default], iteration 10 completed (534.840 ms) ====== [2025-12-30T18:01:59.954Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-30T18:01:59.954Z] GC before operation: completed in 11.373 ms, heap usage 41.517 MB -> 5.425 MB. [2025-12-30T18:02:00.308Z] Camera thread performed 122 scans. [2025-12-30T18:02:00.308Z] ====== philosophers (scala) [default], iteration 11 completed (540.464 ms) ====== [2025-12-30T18:02:00.308Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-30T18:02:00.308Z] GC before operation: completed in 10.391 ms, heap usage 29.516 MB -> 5.425 MB. [2025-12-30T18:02:01.056Z] Camera thread performed 122 scans. [2025-12-30T18:02:01.056Z] ====== philosophers (scala) [default], iteration 12 completed (594.108 ms) ====== [2025-12-30T18:02:01.056Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-30T18:02:01.056Z] GC before operation: completed in 10.756 ms, heap usage 26.952 MB -> 5.425 MB. [2025-12-30T18:02:01.797Z] Camera thread performed 122 scans. [2025-12-30T18:02:01.797Z] ====== philosophers (scala) [default], iteration 13 completed (642.286 ms) ====== [2025-12-30T18:02:01.797Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-30T18:02:01.797Z] GC before operation: completed in 11.396 ms, heap usage 60.575 MB -> 5.425 MB. [2025-12-30T18:02:02.151Z] Camera thread performed 122 scans. [2025-12-30T18:02:02.151Z] ====== philosophers (scala) [default], iteration 14 completed (651.075 ms) ====== [2025-12-30T18:02:02.151Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-30T18:02:02.151Z] GC before operation: completed in 11.733 ms, heap usage 9.397 MB -> 5.426 MB. [2025-12-30T18:02:02.892Z] Camera thread performed 122 scans. [2025-12-30T18:02:02.892Z] ====== philosophers (scala) [default], iteration 15 completed (646.486 ms) ====== [2025-12-30T18:02:02.892Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-30T18:02:02.892Z] GC before operation: completed in 11.057 ms, heap usage 40.958 MB -> 5.426 MB. [2025-12-30T18:02:03.632Z] Camera thread performed 122 scans. [2025-12-30T18:02:03.632Z] ====== philosophers (scala) [default], iteration 16 completed (617.838 ms) ====== [2025-12-30T18:02:03.632Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-30T18:02:03.632Z] GC before operation: completed in 11.054 ms, heap usage 35.540 MB -> 5.426 MB. [2025-12-30T18:02:04.374Z] Camera thread performed 122 scans. [2025-12-30T18:02:04.374Z] ====== philosophers (scala) [default], iteration 17 completed (660.159 ms) ====== [2025-12-30T18:02:04.374Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-30T18:02:04.374Z] GC before operation: completed in 11.621 ms, heap usage 45.377 MB -> 5.426 MB. [2025-12-30T18:02:05.116Z] Camera thread performed 122 scans. [2025-12-30T18:02:05.116Z] ====== philosophers (scala) [default], iteration 18 completed (666.424 ms) ====== [2025-12-30T18:02:05.116Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-30T18:02:05.116Z] GC before operation: completed in 11.754 ms, heap usage 133.035 MB -> 5.426 MB. [2025-12-30T18:02:05.470Z] Camera thread performed 122 scans. [2025-12-30T18:02:05.470Z] ====== philosophers (scala) [default], iteration 19 completed (647.452 ms) ====== [2025-12-30T18:02:05.470Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-30T18:02:05.470Z] GC before operation: completed in 12.413 ms, heap usage 97.331 MB -> 5.426 MB. [2025-12-30T18:02:06.204Z] Camera thread performed 122 scans. [2025-12-30T18:02:06.204Z] ====== philosophers (scala) [default], iteration 20 completed (645.688 ms) ====== [2025-12-30T18:02:06.204Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-30T18:02:06.204Z] GC before operation: completed in 12.322 ms, heap usage 92.537 MB -> 5.426 MB. [2025-12-30T18:02:06.948Z] Camera thread performed 122 scans. [2025-12-30T18:02:06.948Z] ====== philosophers (scala) [default], iteration 21 completed (616.299 ms) ====== [2025-12-30T18:02:06.948Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-30T18:02:06.948Z] GC before operation: completed in 11.135 ms, heap usage 16.376 MB -> 5.426 MB. [2025-12-30T18:02:07.710Z] Camera thread performed 122 scans. [2025-12-30T18:02:07.710Z] ====== philosophers (scala) [default], iteration 22 completed (588.309 ms) ====== [2025-12-30T18:02:07.710Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-30T18:02:07.710Z] GC before operation: completed in 11.457 ms, heap usage 9.907 MB -> 5.427 MB. [2025-12-30T18:02:08.100Z] Camera thread performed 122 scans. [2025-12-30T18:02:08.100Z] ====== philosophers (scala) [default], iteration 23 completed (593.495 ms) ====== [2025-12-30T18:02:08.100Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-30T18:02:08.100Z] GC before operation: completed in 11.893 ms, heap usage 41.548 MB -> 5.427 MB. [2025-12-30T18:02:08.842Z] Camera thread performed 122 scans. [2025-12-30T18:02:08.842Z] ====== philosophers (scala) [default], iteration 24 completed (649.830 ms) ====== [2025-12-30T18:02:08.842Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-30T18:02:08.842Z] GC before operation: completed in 11.195 ms, heap usage 68.539 MB -> 5.427 MB. [2025-12-30T18:02:09.583Z] Camera thread performed 122 scans. [2025-12-30T18:02:09.583Z] ====== philosophers (scala) [default], iteration 25 completed (626.006 ms) ====== [2025-12-30T18:02:09.583Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-30T18:02:09.583Z] GC before operation: completed in 11.451 ms, heap usage 20.903 MB -> 5.427 MB. [2025-12-30T18:02:09.940Z] Camera thread performed 122 scans. [2025-12-30T18:02:09.940Z] ====== philosophers (scala) [default], iteration 26 completed (637.489 ms) ====== [2025-12-30T18:02:09.940Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-30T18:02:09.940Z] GC before operation: completed in 12.330 ms, heap usage 129.950 MB -> 5.427 MB. [2025-12-30T18:02:10.683Z] Camera thread performed 122 scans. [2025-12-30T18:02:10.683Z] ====== philosophers (scala) [default], iteration 27 completed (652.092 ms) ====== [2025-12-30T18:02:10.683Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-30T18:02:10.683Z] GC before operation: completed in 11.607 ms, heap usage 49.040 MB -> 5.427 MB. [2025-12-30T18:02:11.429Z] Camera thread performed 122 scans. [2025-12-30T18:02:11.429Z] ====== philosophers (scala) [default], iteration 28 completed (659.884 ms) ====== [2025-12-30T18:02:11.429Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-30T18:02:11.429Z] GC before operation: completed in 11.274 ms, heap usage 75.958 MB -> 5.427 MB. [2025-12-30T18:02:12.038Z] Camera thread performed 122 scans. [2025-12-30T18:02:12.038Z] ====== philosophers (scala) [default], iteration 29 completed (595.826 ms) ====== [2025-12-30T18:02:12.038Z] [2025-12-30T18:02:11.997+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2025-12-30T18:02:12.038Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk26_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_1767117162803\renaissance-philosophers_0\launcher-180151-7322400557707493461: launcher-180151-7322400557707493461\renaissance-harness_3\lib\renaissance-harness_3-0.16.0.jar: The process cannot access the file because it is being used by another process [2025-12-30T18:02:12.038Z] [2025-12-30T18:02:11.997+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2025-12-30T18:02:12.038Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk26_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_1767117162803\renaissance-philosophers_0\launcher-180151-7322400557707493461: launcher-180151-7322400557707493461\renaissance-harness_3\lib\renaissance-harness_3-0.16.0.jar: The process cannot access the file because it is being used by another process [2025-12-30T18:02:12.379Z] ----------------------------------- [2025-12-30T18:02:12.379Z] renaissance-philosophers_0_PASSED [2025-12-30T18:02:12.379Z] ----------------------------------- [2025-12-30T18:02:12.379Z] [2025-12-30T18:02:12.379Z] TEST TEARDOWN: [2025-12-30T18:02:12.379Z] Nothing to be done for teardown. [2025-12-30T18:02:12.379Z] renaissance-philosophers_0 Finish Time: Tue Dec 30 18:02:12 2025 Epoch Time (ms): 1767117732173