[2025-09-24T23:35:54.541Z] Running test renaissance-philosophers_0 ... [2025-09-24T23:35:54.541Z] =============================================== [2025-09-24T23:35:54.541Z] renaissance-philosophers_0 Start Time: Wed Sep 24 23:35:50 2025 Epoch Time (ms): 1758756950074 [2025-09-24T23:35:54.541Z] variation: NoOptions [2025-09-24T23:35:54.541Z] JVM_OPTIONS: [2025-09-24T23:35:54.541Z] { \ [2025-09-24T23:35:54.541Z] echo ""; echo "TEST SETUP:"; \ [2025-09-24T23:35:54.541Z] echo "Nothing to be done for setup."; \ [2025-09-24T23:35:54.541Z] mkdir -p "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17587545193407/renaissance-philosophers_0"; \ [2025-09-24T23:35:54.541Z] cd "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17587545193407/renaissance-philosophers_0"; \ [2025-09-24T23:35:54.541Z] echo ""; echo "TESTING:"; \ [2025-09-24T23:35:54.541Z] "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/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 "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17587545193407/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-24T23:35:54.541Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/..; rm -f -r "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17587545193407/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-24T23:35:54.541Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-24T23:35:54.541Z] echo "Nothing to be done for teardown."; \ [2025-09-24T23:35:54.541Z] } 2>&1 | tee -a "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17587545193407/TestTargetResult"; [2025-09-24T23:35:54.541Z] [2025-09-24T23:35:54.541Z] TEST SETUP: [2025-09-24T23:35:54.541Z] Nothing to be done for setup. [2025-09-24T23:35:54.541Z] [2025-09-24T23:35:54.541Z] TESTING: [2025-09-24T23:35:54.541Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-24T23:35:54.541Z] GC before operation: completed in 55.355 ms, heap usage 15.057 MB -> 4.665 MB. [2025-09-24T23:36:36.329Z] Camera thread performed 122 scans. [2025-09-24T23:36:36.329Z] ====== philosophers (scala) [default], iteration 0 completed (36834.769 ms) ====== [2025-09-24T23:36:36.329Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-24T23:36:36.330Z] GC before operation: completed in 52.843 ms, heap usage 30.642 MB -> 5.074 MB. [2025-09-24T23:37:05.646Z] Camera thread performed 122 scans. [2025-09-24T23:37:05.646Z] ====== philosophers (scala) [default], iteration 1 completed (31670.399 ms) ====== [2025-09-24T23:37:05.646Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-24T23:37:05.646Z] GC before operation: completed in 65.100 ms, heap usage 25.825 MB -> 5.076 MB. [2025-09-24T23:37:40.658Z] Camera thread performed 122 scans. [2025-09-24T23:37:40.658Z] ====== philosophers (scala) [default], iteration 2 completed (33102.282 ms) ====== [2025-09-24T23:37:40.658Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-24T23:37:40.658Z] GC before operation: completed in 45.987 ms, heap usage 31.447 MB -> 5.077 MB. [2025-09-24T23:38:10.016Z] Camera thread performed 122 scans. [2025-09-24T23:38:10.016Z] ====== philosophers (scala) [default], iteration 3 completed (33275.620 ms) ====== [2025-09-24T23:38:10.016Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-24T23:38:10.016Z] GC before operation: completed in 45.160 ms, heap usage 21.952 MB -> 5.077 MB. [2025-09-24T23:38:45.027Z] Camera thread performed 122 scans. [2025-09-24T23:38:45.027Z] ====== philosophers (scala) [default], iteration 4 completed (31465.066 ms) ====== [2025-09-24T23:38:45.027Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-24T23:38:45.027Z] GC before operation: completed in 48.601 ms, heap usage 31.215 MB -> 5.078 MB. [2025-09-24T23:39:14.372Z] Camera thread performed 122 scans. [2025-09-24T23:39:14.372Z] ====== philosophers (scala) [default], iteration 5 completed (33295.734 ms) ====== [2025-09-24T23:39:14.372Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-24T23:39:14.372Z] GC before operation: completed in 50.914 ms, heap usage 23.978 MB -> 5.080 MB. [2025-09-24T23:39:49.483Z] Camera thread performed 122 scans. [2025-09-24T23:39:49.483Z] ====== philosophers (scala) [default], iteration 6 completed (33306.540 ms) ====== [2025-09-24T23:39:49.483Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-24T23:39:49.483Z] GC before operation: completed in 59.896 ms, heap usage 9.049 MB -> 5.080 MB. [2025-09-24T23:40:18.833Z] Camera thread performed 122 scans. [2025-09-24T23:40:18.833Z] ====== philosophers (scala) [default], iteration 7 completed (31681.136 ms) ====== [2025-09-24T23:40:18.833Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-24T23:40:18.833Z] GC before operation: completed in 37.164 ms, heap usage 21.124 MB -> 5.080 MB. [2025-09-24T23:40:53.927Z] Camera thread performed 122 scans. [2025-09-24T23:40:53.927Z] ====== philosophers (scala) [default], iteration 8 completed (32227.747 ms) ====== [2025-09-24T23:40:53.927Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-24T23:40:53.927Z] GC before operation: completed in 61.671 ms, heap usage 13.300 MB -> 5.080 MB. [2025-09-24T23:41:29.007Z] Camera thread performed 122 scans. [2025-09-24T23:41:29.007Z] ====== philosophers (scala) [default], iteration 9 completed (32906.407 ms) ====== [2025-09-24T23:41:29.007Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-24T23:41:29.007Z] GC before operation: completed in 32.606 ms, heap usage 26.670 MB -> 5.082 MB. [2025-09-24T23:41:58.524Z] Camera thread performed 122 scans. [2025-09-24T23:41:58.524Z] ====== philosophers (scala) [default], iteration 10 completed (32783.003 ms) ====== [2025-09-24T23:41:58.524Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-24T23:41:58.524Z] GC before operation: completed in 38.027 ms, heap usage 16.731 MB -> 5.082 MB. [2025-09-24T23:42:33.717Z] Camera thread performed 122 scans. [2025-09-24T23:42:33.717Z] ====== philosophers (scala) [default], iteration 11 completed (32388.152 ms) ====== [2025-09-24T23:42:33.717Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-24T23:42:33.717Z] GC before operation: completed in 66.903 ms, heap usage 8.418 MB -> 5.082 MB. [2025-09-24T23:43:03.103Z] Camera thread performed 122 scans. [2025-09-24T23:43:03.103Z] ====== philosophers (scala) [default], iteration 12 completed (32716.022 ms) ====== [2025-09-24T23:43:03.103Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-24T23:43:03.103Z] GC before operation: completed in 45.444 ms, heap usage 31.079 MB -> 5.082 MB. [2025-09-24T23:43:38.203Z] Camera thread performed 122 scans. [2025-09-24T23:43:38.203Z] ====== philosophers (scala) [default], iteration 13 completed (33434.728 ms) ====== [2025-09-24T23:43:38.203Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-24T23:43:38.203Z] GC before operation: completed in 50.867 ms, heap usage 12.558 MB -> 5.082 MB. [2025-09-24T23:44:13.258Z] Camera thread performed 122 scans. [2025-09-24T23:44:13.258Z] ====== philosophers (scala) [default], iteration 14 completed (32742.302 ms) ====== [2025-09-24T23:44:13.258Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-24T23:44:13.258Z] GC before operation: completed in 59.668 ms, heap usage 33.300 MB -> 4.395 MB. [2025-09-24T23:44:42.661Z] Camera thread performed 122 scans. [2025-09-24T23:44:42.661Z] ====== philosophers (scala) [default], iteration 15 completed (32310.970 ms) ====== [2025-09-24T23:44:42.661Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-24T23:44:42.661Z] GC before operation: completed in 55.684 ms, heap usage 26.488 MB -> 4.395 MB. [2025-09-24T23:45:17.804Z] Camera thread performed 122 scans. [2025-09-24T23:45:17.804Z] ====== philosophers (scala) [default], iteration 16 completed (33066.677 ms) ====== [2025-09-24T23:45:17.804Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-24T23:45:17.804Z] GC before operation: completed in 44.500 ms, heap usage 11.504 MB -> 3.726 MB. [2025-09-24T23:45:47.213Z] Camera thread performed 122 scans. [2025-09-24T23:45:47.213Z] ====== philosophers (scala) [default], iteration 17 completed (32601.234 ms) ====== [2025-09-24T23:45:47.213Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-24T23:45:47.213Z] GC before operation: completed in 34.986 ms, heap usage 25.179 MB -> 3.726 MB. [2025-09-24T23:46:22.227Z] Camera thread performed 122 scans. [2025-09-24T23:46:22.227Z] ====== philosophers (scala) [default], iteration 18 completed (33527.177 ms) ====== [2025-09-24T23:46:22.227Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-24T23:46:22.227Z] GC before operation: completed in 43.647 ms, heap usage 14.205 MB -> 3.727 MB. [2025-09-24T23:46:51.867Z] Camera thread performed 122 scans. [2025-09-24T23:46:51.867Z] ====== philosophers (scala) [default], iteration 19 completed (31890.996 ms) ====== [2025-09-24T23:46:51.867Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-24T23:46:51.867Z] GC before operation: completed in 37.563 ms, heap usage 18.053 MB -> 3.726 MB. [2025-09-24T23:47:26.971Z] Camera thread performed 122 scans. [2025-09-24T23:47:26.971Z] ====== philosophers (scala) [default], iteration 20 completed (32675.589 ms) ====== [2025-09-24T23:47:26.971Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-24T23:47:26.971Z] GC before operation: completed in 50.763 ms, heap usage 27.336 MB -> 3.727 MB. [2025-09-24T23:48:02.074Z] Camera thread performed 122 scans. [2025-09-24T23:48:02.074Z] ====== philosophers (scala) [default], iteration 21 completed (33204.177 ms) ====== [2025-09-24T23:48:02.074Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-24T23:48:02.074Z] GC before operation: completed in 35.662 ms, heap usage 5.653 MB -> 3.726 MB. [2025-09-24T23:48:31.419Z] Camera thread performed 122 scans. [2025-09-24T23:48:31.419Z] ====== philosophers (scala) [default], iteration 22 completed (31638.759 ms) ====== [2025-09-24T23:48:31.419Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-24T23:48:31.419Z] GC before operation: completed in 58.356 ms, heap usage 30.643 MB -> 3.727 MB. [2025-09-24T23:49:06.454Z] Camera thread performed 122 scans. [2025-09-24T23:49:06.454Z] ====== philosophers (scala) [default], iteration 23 completed (32669.085 ms) ====== [2025-09-24T23:49:06.454Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-24T23:49:06.454Z] GC before operation: completed in 48.260 ms, heap usage 23.202 MB -> 3.727 MB. [2025-09-24T23:49:35.812Z] Camera thread performed 122 scans. [2025-09-24T23:49:35.813Z] ====== philosophers (scala) [default], iteration 24 completed (32789.526 ms) ====== [2025-09-24T23:49:35.813Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-24T23:49:35.813Z] GC before operation: completed in 40.244 ms, heap usage 32.756 MB -> 3.727 MB. [2025-09-24T23:50:10.817Z] Camera thread performed 122 scans. [2025-09-24T23:50:10.817Z] ====== philosophers (scala) [default], iteration 25 completed (31780.334 ms) ====== [2025-09-24T23:50:10.817Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-24T23:50:10.817Z] GC before operation: completed in 37.135 ms, heap usage 24.158 MB -> 3.726 MB. [2025-09-24T23:50:40.181Z] Camera thread performed 122 scans. [2025-09-24T23:50:40.181Z] ====== philosophers (scala) [default], iteration 26 completed (31920.810 ms) ====== [2025-09-24T23:50:40.181Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-24T23:50:40.181Z] GC before operation: completed in 38.642 ms, heap usage 12.547 MB -> 3.727 MB. [2025-09-24T23:51:15.381Z] Camera thread performed 122 scans. [2025-09-24T23:51:15.381Z] ====== philosophers (scala) [default], iteration 27 completed (32452.781 ms) ====== [2025-09-24T23:51:15.381Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-24T23:51:15.381Z] GC before operation: completed in 33.264 ms, heap usage 29.448 MB -> 3.727 MB. [2025-09-24T23:51:44.732Z] Camera thread performed 122 scans. [2025-09-24T23:51:44.732Z] ====== philosophers (scala) [default], iteration 28 completed (32329.936 ms) ====== [2025-09-24T23:51:44.732Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-24T23:51:44.732Z] GC before operation: completed in 35.467 ms, heap usage 31.362 MB -> 3.727 MB. [2025-09-24T23:52:19.706Z] Camera thread performed 122 scans. [2025-09-24T23:52:19.706Z] ====== philosophers (scala) [default], iteration 29 completed (32879.823 ms) ====== [2025-09-24T23:52:19.706Z] ----------------------------------- [2025-09-24T23:52:19.706Z] renaissance-philosophers_0_PASSED [2025-09-24T23:52:19.706Z] ----------------------------------- [2025-09-24T23:52:19.706Z] [2025-09-24T23:52:19.706Z] TEST TEARDOWN: [2025-09-24T23:52:19.706Z] Nothing to be done for teardown. [2025-09-24T23:52:19.706Z] renaissance-philosophers_0 Finish Time: Wed Sep 24 23:52:17 2025 Epoch Time (ms): 1758757937210