[2025-06-18T22:24:36.961Z] Running test renaissance-philosophers_0 ... [2025-06-18T22:24:36.961Z] =============================================== [2025-06-18T22:24:37.297Z] renaissance-philosophers_0 Start Time: Wed Jun 18 22:24:36 2025 Epoch Time (ms): 1750285476961 [2025-06-18T22:24:37.297Z] variation: NoOptions [2025-06-18T22:24:37.297Z] JVM_OPTIONS: [2025-06-18T22:24:37.297Z] { \ [2025-06-18T22:24:37.297Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T22:24:37.297Z] echo "Nothing to be done for setup."; \ [2025-06-18T22:24:37.297Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17502851224747\\renaissance-philosophers_0"; \ [2025-06-18T22:24:37.297Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17502851224747\\renaissance-philosophers_0"; \ [2025-06-18T22:24:37.297Z] echo ""; echo "TESTING:"; \ [2025-06-18T22:24:37.297Z] "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_17502851224747\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-06-18T22:24:37.297Z] 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_17502851224747\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T22:24:37.297Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T22:24:37.297Z] echo "Nothing to be done for teardown."; \ [2025-06-18T22:24:37.297Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17502851224747\\TestTargetResult"; [2025-06-18T22:24:37.686Z] [2025-06-18T22:24:37.686Z] TEST SETUP: [2025-06-18T22:24:37.686Z] Nothing to be done for setup. [2025-06-18T22:24:37.686Z] [2025-06-18T22:24:37.686Z] TESTING: [2025-06-18T22:24:38.794Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-18T22:24:38.794Z] GC before operation: completed in 17.117 ms, heap usage 6.897 MB -> 5.105 MB. [2025-06-18T22:24:41.027Z] Camera thread performed 122 scans. [2025-06-18T22:24:41.027Z] ====== philosophers (scala) [default], iteration 0 completed (1878.122 ms) ====== [2025-06-18T22:24:41.027Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-18T22:24:41.027Z] GC before operation: completed in 13.865 ms, heap usage 11.036 MB -> 4.994 MB. [2025-06-18T22:24:42.194Z] Camera thread performed 122 scans. [2025-06-18T22:24:42.194Z] ====== philosophers (scala) [default], iteration 1 completed (1419.847 ms) ====== [2025-06-18T22:24:42.194Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-18T22:24:42.194Z] GC before operation: completed in 12.964 ms, heap usage 45.891 MB -> 4.994 MB. [2025-06-18T22:24:43.292Z] Camera thread performed 122 scans. [2025-06-18T22:24:43.292Z] ====== philosophers (scala) [default], iteration 2 completed (882.616 ms) ====== [2025-06-18T22:24:43.292Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-18T22:24:43.292Z] GC before operation: completed in 14.340 ms, heap usage 11.786 MB -> 4.995 MB. [2025-06-18T22:24:43.981Z] Camera thread performed 122 scans. [2025-06-18T22:24:43.981Z] ====== philosophers (scala) [default], iteration 3 completed (873.700 ms) ====== [2025-06-18T22:24:43.981Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-18T22:24:43.981Z] GC before operation: completed in 12.656 ms, heap usage 11.713 MB -> 4.995 MB. [2025-06-18T22:24:45.105Z] Camera thread performed 122 scans. [2025-06-18T22:24:45.106Z] ====== philosophers (scala) [default], iteration 4 completed (962.728 ms) ====== [2025-06-18T22:24:45.106Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-18T22:24:45.106Z] GC before operation: completed in 13.034 ms, heap usage 19.668 MB -> 4.996 MB. [2025-06-18T22:24:46.325Z] Camera thread performed 122 scans. [2025-06-18T22:24:46.325Z] ====== philosophers (scala) [default], iteration 5 completed (1048.575 ms) ====== [2025-06-18T22:24:46.325Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-18T22:24:46.325Z] GC before operation: completed in 12.772 ms, heap usage 21.140 MB -> 4.997 MB. [2025-06-18T22:24:47.003Z] Camera thread performed 122 scans. [2025-06-18T22:24:47.003Z] ====== philosophers (scala) [default], iteration 6 completed (1063.002 ms) ====== [2025-06-18T22:24:47.003Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-18T22:24:47.003Z] GC before operation: completed in 12.414 ms, heap usage 8.120 MB -> 4.997 MB. [2025-06-18T22:24:48.101Z] Camera thread performed 122 scans. [2025-06-18T22:24:48.101Z] ====== philosophers (scala) [default], iteration 7 completed (937.680 ms) ====== [2025-06-18T22:24:48.101Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-18T22:24:48.101Z] GC before operation: completed in 12.992 ms, heap usage 34.137 MB -> 4.997 MB. [2025-06-18T22:24:49.205Z] Camera thread performed 122 scans. [2025-06-18T22:24:49.205Z] ====== philosophers (scala) [default], iteration 8 completed (922.492 ms) ====== [2025-06-18T22:24:49.205Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-18T22:24:49.205Z] GC before operation: completed in 12.574 ms, heap usage 50.629 MB -> 4.997 MB. [2025-06-18T22:24:49.893Z] Camera thread performed 122 scans. [2025-06-18T22:24:49.893Z] ====== philosophers (scala) [default], iteration 9 completed (890.633 ms) ====== [2025-06-18T22:24:49.893Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-18T22:24:49.893Z] GC before operation: completed in 13.878 ms, heap usage 48.602 MB -> 4.997 MB. [2025-06-18T22:24:51.002Z] Camera thread performed 122 scans. [2025-06-18T22:24:51.002Z] ====== philosophers (scala) [default], iteration 10 completed (930.569 ms) ====== [2025-06-18T22:24:51.002Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-18T22:24:51.002Z] GC before operation: completed in 13.597 ms, heap usage 15.098 MB -> 5.001 MB. [2025-06-18T22:24:51.683Z] Camera thread performed 122 scans. [2025-06-18T22:24:51.683Z] ====== philosophers (scala) [default], iteration 11 completed (880.655 ms) ====== [2025-06-18T22:24:51.683Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-18T22:24:51.683Z] GC before operation: completed in 13.414 ms, heap usage 23.103 MB -> 5.002 MB. [2025-06-18T22:24:52.785Z] Camera thread performed 122 scans. [2025-06-18T22:24:52.785Z] ====== philosophers (scala) [default], iteration 12 completed (935.727 ms) ====== [2025-06-18T22:24:52.785Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-18T22:24:52.785Z] GC before operation: completed in 12.508 ms, heap usage 23.623 MB -> 5.002 MB. [2025-06-18T22:24:53.886Z] Camera thread performed 122 scans. [2025-06-18T22:24:53.886Z] ====== philosophers (scala) [default], iteration 13 completed (934.528 ms) ====== [2025-06-18T22:24:53.886Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-18T22:24:53.886Z] GC before operation: completed in 13.091 ms, heap usage 12.628 MB -> 5.002 MB. [2025-06-18T22:24:54.564Z] Camera thread performed 122 scans. [2025-06-18T22:24:54.564Z] ====== philosophers (scala) [default], iteration 14 completed (894.087 ms) ====== [2025-06-18T22:24:54.564Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-18T22:24:54.564Z] GC before operation: completed in 13.547 ms, heap usage 31.123 MB -> 5.003 MB. [2025-06-18T22:24:55.662Z] Camera thread performed 122 scans. [2025-06-18T22:24:55.662Z] ====== philosophers (scala) [default], iteration 15 completed (923.634 ms) ====== [2025-06-18T22:24:55.662Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-18T22:24:55.662Z] GC before operation: completed in 12.512 ms, heap usage 37.116 MB -> 5.002 MB. [2025-06-18T22:24:56.440Z] Camera thread performed 122 scans. [2025-06-18T22:24:56.440Z] ====== philosophers (scala) [default], iteration 16 completed (834.884 ms) ====== [2025-06-18T22:24:56.440Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-18T22:24:56.440Z] GC before operation: completed in 13.010 ms, heap usage 50.557 MB -> 5.003 MB. [2025-06-18T22:24:57.547Z] Camera thread performed 122 scans. [2025-06-18T22:24:57.547Z] ====== philosophers (scala) [default], iteration 17 completed (992.762 ms) ====== [2025-06-18T22:24:57.547Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-18T22:24:57.547Z] GC before operation: completed in 12.824 ms, heap usage 18.099 MB -> 5.003 MB. [2025-06-18T22:24:58.231Z] Camera thread performed 122 scans. [2025-06-18T22:24:58.231Z] ====== philosophers (scala) [default], iteration 18 completed (882.209 ms) ====== [2025-06-18T22:24:58.231Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-18T22:24:58.231Z] GC before operation: completed in 12.972 ms, heap usage 23.099 MB -> 5.004 MB. [2025-06-18T22:24:59.431Z] Camera thread performed 122 scans. [2025-06-18T22:24:59.431Z] ====== philosophers (scala) [default], iteration 19 completed (934.558 ms) ====== [2025-06-18T22:24:59.431Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-18T22:24:59.431Z] GC before operation: completed in 15.337 ms, heap usage 13.605 MB -> 5.004 MB. [2025-06-18T22:25:00.140Z] Camera thread performed 122 scans. [2025-06-18T22:25:00.140Z] ====== philosophers (scala) [default], iteration 20 completed (876.287 ms) ====== [2025-06-18T22:25:00.140Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-18T22:25:00.140Z] GC before operation: completed in 12.938 ms, heap usage 33.623 MB -> 5.003 MB. [2025-06-18T22:25:00.825Z] Camera thread performed 122 scans. [2025-06-18T22:25:00.825Z] ====== philosophers (scala) [default], iteration 21 completed (873.972 ms) ====== [2025-06-18T22:25:00.825Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-18T22:25:01.385Z] GC before operation: completed in 13.547 ms, heap usage 49.002 MB -> 5.004 MB. [2025-06-18T22:25:02.066Z] Camera thread performed 122 scans. [2025-06-18T22:25:02.066Z] ====== philosophers (scala) [default], iteration 22 completed (1159.223 ms) ====== [2025-06-18T22:25:02.066Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-18T22:25:02.066Z] GC before operation: completed in 13.147 ms, heap usage 40.624 MB -> 5.004 MB. [2025-06-18T22:25:03.168Z] Camera thread performed 122 scans. [2025-06-18T22:25:03.168Z] ====== philosophers (scala) [default], iteration 23 completed (886.768 ms) ====== [2025-06-18T22:25:03.168Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-18T22:25:03.168Z] GC before operation: completed in 13.035 ms, heap usage 31.623 MB -> 5.004 MB. [2025-06-18T22:25:03.858Z] Camera thread performed 122 scans. [2025-06-18T22:25:03.858Z] ====== philosophers (scala) [default], iteration 24 completed (943.049 ms) ====== [2025-06-18T22:25:03.858Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-18T22:25:04.180Z] GC before operation: completed in 13.436 ms, heap usage 14.128 MB -> 5.004 MB. [2025-06-18T22:25:04.859Z] Camera thread performed 122 scans. [2025-06-18T22:25:04.859Z] ====== philosophers (scala) [default], iteration 25 completed (954.533 ms) ====== [2025-06-18T22:25:04.859Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-18T22:25:04.859Z] GC before operation: completed in 12.637 ms, heap usage 10.099 MB -> 5.004 MB. [2025-06-18T22:25:05.958Z] Camera thread performed 122 scans. [2025-06-18T22:25:05.958Z] ====== philosophers (scala) [default], iteration 26 completed (872.924 ms) ====== [2025-06-18T22:25:05.958Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-18T22:25:05.958Z] GC before operation: completed in 13.295 ms, heap usage 26.623 MB -> 5.005 MB. [2025-06-18T22:25:07.055Z] Camera thread performed 122 scans. [2025-06-18T22:25:07.055Z] ====== philosophers (scala) [default], iteration 27 completed (924.639 ms) ====== [2025-06-18T22:25:07.055Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-18T22:25:07.055Z] GC before operation: completed in 12.257 ms, heap usage 19.625 MB -> 5.004 MB. [2025-06-18T22:25:07.734Z] Camera thread performed 122 scans. [2025-06-18T22:25:07.734Z] ====== philosophers (scala) [default], iteration 28 completed (936.639 ms) ====== [2025-06-18T22:25:07.734Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-18T22:25:07.734Z] GC before operation: completed in 13.861 ms, heap usage 30.128 MB -> 5.005 MB. [2025-06-18T22:25:08.838Z] Camera thread performed 122 scans. [2025-06-18T22:25:08.838Z] ====== philosophers (scala) [default], iteration 29 completed (947.175 ms) ====== [2025-06-18T22:25:08.838Z] [2025-06-18T22:25:08.742+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2025-06-18T22:25:08.838Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk21_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17502851224747\renaissance-philosophers_0\harness-222438-5444867859132088304: harness-222438-5444867859132088304\scala-stm\lib\scala-library-2.13.15.jar: The process cannot access the file because it is being used by another process [2025-06-18T22:25:08.838Z] [2025-06-18T22:25:08.742+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2025-06-18T22:25:08.838Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk21_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17502851224747\renaissance-philosophers_0\harness-222438-5444867859132088304: harness-222438-5444867859132088304\scala-stm\lib\scala-library-2.13.15.jar: The process cannot access the file because it is being used by another process [2025-06-18T22:25:08.838Z] ----------------------------------- [2025-06-18T22:25:08.838Z] renaissance-philosophers_0_PASSED [2025-06-18T22:25:08.838Z] ----------------------------------- [2025-06-18T22:25:09.161Z] [2025-06-18T22:25:09.161Z] TEST TEARDOWN: [2025-06-18T22:25:09.161Z] Nothing to be done for teardown. [2025-06-18T22:25:09.161Z] renaissance-philosophers_0 Finish Time: Wed Jun 18 22:25:08 2025 Epoch Time (ms): 1750285508978