[2025-06-18T22:44:29.600Z] Running test renaissance-philosophers_0 ... [2025-06-18T22:44:29.600Z] =============================================== [2025-06-18T22:44:29.600Z] renaissance-philosophers_0 Start Time: Wed Jun 18 22:44:25 2025 Epoch Time (ms): 1750286665666 [2025-06-18T22:44:29.600Z] variation: NoOptions [2025-06-18T22:44:29.600Z] JVM_OPTIONS: [2025-06-18T22:44:29.600Z] { \ [2025-06-18T22:44:29.600Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T22:44:29.600Z] echo "Nothing to be done for setup."; \ [2025-06-18T22:44:29.600Z] mkdir -p "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17502845576140/renaissance-philosophers_0"; \ [2025-06-18T22:44:29.600Z] cd "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17502845576140/renaissance-philosophers_0"; \ [2025-06-18T22:44:29.600Z] echo ""; echo "TESTING:"; \ [2025-06-18T22:44:29.600Z] "/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_17502845576140/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-18T22:44:29.600Z] 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_17502845576140/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T22:44:29.600Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T22:44:29.600Z] echo "Nothing to be done for teardown."; \ [2025-06-18T22:44:29.600Z] } 2>&1 | tee -a "/ssd/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17502845576140/TestTargetResult"; [2025-06-18T22:44:29.600Z] [2025-06-18T22:44:29.600Z] TEST SETUP: [2025-06-18T22:44:29.600Z] Nothing to be done for setup. [2025-06-18T22:44:29.600Z] [2025-06-18T22:44:29.600Z] TESTING: [2025-06-18T22:44:29.600Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-18T22:44:29.600Z] GC before operation: completed in 58.975 ms, heap usage 15.041 MB -> 4.662 MB. [2025-06-18T22:45:11.184Z] Camera thread performed 122 scans. [2025-06-18T22:45:11.184Z] ====== philosophers (scala) [default], iteration 0 completed (39519.661 ms) ====== [2025-06-18T22:45:11.184Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-18T22:45:11.184Z] GC before operation: completed in 56.125 ms, heap usage 8.453 MB -> 5.083 MB. [2025-06-18T22:45:42.044Z] Camera thread performed 122 scans. [2025-06-18T22:45:42.044Z] ====== philosophers (scala) [default], iteration 1 completed (32083.421 ms) ====== [2025-06-18T22:45:42.044Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-18T22:45:42.044Z] GC before operation: completed in 48.852 ms, heap usage 27.443 MB -> 5.087 MB. [2025-06-18T22:46:16.786Z] Camera thread performed 122 scans. [2025-06-18T22:46:16.787Z] ====== philosophers (scala) [default], iteration 2 completed (32937.969 ms) ====== [2025-06-18T22:46:16.787Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-18T22:46:16.787Z] GC before operation: completed in 42.896 ms, heap usage 10.496 MB -> 5.087 MB. [2025-06-18T22:46:51.658Z] Camera thread performed 122 scans. [2025-06-18T22:46:51.658Z] ====== philosophers (scala) [default], iteration 3 completed (32580.748 ms) ====== [2025-06-18T22:46:51.658Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-18T22:46:51.658Z] GC before operation: completed in 55.239 ms, heap usage 11.567 MB -> 5.089 MB. [2025-06-18T22:47:20.777Z] Camera thread performed 122 scans. [2025-06-18T22:47:20.777Z] ====== philosophers (scala) [default], iteration 4 completed (33105.584 ms) ====== [2025-06-18T22:47:20.777Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-18T22:47:20.777Z] GC before operation: completed in 44.096 ms, heap usage 31.296 MB -> 5.089 MB. [2025-06-18T22:47:55.518Z] Camera thread performed 122 scans. [2025-06-18T22:47:55.518Z] ====== philosophers (scala) [default], iteration 5 completed (32352.659 ms) ====== [2025-06-18T22:47:55.518Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-18T22:47:55.518Z] GC before operation: completed in 38.690 ms, heap usage 11.403 MB -> 5.091 MB. [2025-06-18T22:48:24.772Z] Camera thread performed 122 scans. [2025-06-18T22:48:24.773Z] ====== philosophers (scala) [default], iteration 6 completed (32324.818 ms) ====== [2025-06-18T22:48:24.773Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-18T22:48:24.773Z] GC before operation: completed in 50.117 ms, heap usage 28.466 MB -> 5.091 MB. [2025-06-18T22:48:59.525Z] Camera thread performed 122 scans. [2025-06-18T22:48:59.525Z] ====== philosophers (scala) [default], iteration 7 completed (33032.320 ms) ====== [2025-06-18T22:48:59.525Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-18T22:48:59.525Z] GC before operation: completed in 60.505 ms, heap usage 15.233 MB -> 5.091 MB. [2025-06-18T22:49:34.293Z] Camera thread performed 122 scans. [2025-06-18T22:49:34.293Z] ====== philosophers (scala) [default], iteration 8 completed (33187.866 ms) ====== [2025-06-18T22:49:34.293Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-18T22:49:34.293Z] GC before operation: completed in 43.542 ms, heap usage 6.716 MB -> 5.091 MB. [2025-06-18T22:50:09.089Z] Camera thread performed 122 scans. [2025-06-18T22:50:09.089Z] ====== philosophers (scala) [default], iteration 9 completed (33511.377 ms) ====== [2025-06-18T22:50:09.089Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-18T22:50:09.089Z] GC before operation: completed in 47.160 ms, heap usage 22.408 MB -> 5.092 MB. [2025-06-18T22:50:38.208Z] Camera thread performed 122 scans. [2025-06-18T22:50:38.208Z] ====== philosophers (scala) [default], iteration 10 completed (33002.110 ms) ====== [2025-06-18T22:50:38.208Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-18T22:50:38.209Z] GC before operation: completed in 62.514 ms, heap usage 24.110 MB -> 5.092 MB. [2025-06-18T22:51:13.046Z] Camera thread performed 122 scans. [2025-06-18T22:51:13.046Z] ====== philosophers (scala) [default], iteration 11 completed (32913.563 ms) ====== [2025-06-18T22:51:13.046Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-18T22:51:13.046Z] GC before operation: completed in 44.493 ms, heap usage 6.499 MB -> 5.092 MB. [2025-06-18T22:51:47.802Z] Camera thread performed 122 scans. [2025-06-18T22:51:47.802Z] ====== philosophers (scala) [default], iteration 12 completed (33554.082 ms) ====== [2025-06-18T22:51:47.802Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-18T22:51:47.802Z] GC before operation: completed in 59.669 ms, heap usage 23.789 MB -> 5.093 MB. [2025-06-18T22:52:16.963Z] Camera thread performed 122 scans. [2025-06-18T22:52:16.963Z] ====== philosophers (scala) [default], iteration 13 completed (31996.269 ms) ====== [2025-06-18T22:52:16.963Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-18T22:52:16.963Z] GC before operation: completed in 71.897 ms, heap usage 9.341 MB -> 5.093 MB. [2025-06-18T22:52:51.827Z] Camera thread performed 122 scans. [2025-06-18T22:52:51.827Z] ====== philosophers (scala) [default], iteration 14 completed (32282.060 ms) ====== [2025-06-18T22:52:51.827Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-18T22:52:51.827Z] GC before operation: completed in 42.589 ms, heap usage 10.306 MB -> 4.403 MB. [2025-06-18T22:53:26.610Z] Camera thread performed 122 scans. [2025-06-18T22:53:26.610Z] ====== philosophers (scala) [default], iteration 15 completed (33007.283 ms) ====== [2025-06-18T22:53:26.610Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-18T22:53:26.610Z] GC before operation: completed in 38.880 ms, heap usage 11.397 MB -> 4.403 MB. [2025-06-18T22:53:55.809Z] Camera thread performed 122 scans. [2025-06-18T22:53:55.809Z] ====== philosophers (scala) [default], iteration 16 completed (33205.876 ms) ====== [2025-06-18T22:53:55.809Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-18T22:53:55.809Z] GC before operation: completed in 43.094 ms, heap usage 22.079 MB -> 3.752 MB. [2025-06-18T22:54:30.605Z] Camera thread performed 122 scans. [2025-06-18T22:54:30.605Z] ====== philosophers (scala) [default], iteration 17 completed (32812.234 ms) ====== [2025-06-18T22:54:30.605Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-18T22:54:30.605Z] GC before operation: completed in 38.819 ms, heap usage 8.078 MB -> 3.752 MB. [2025-06-18T22:55:05.576Z] Camera thread performed 122 scans. [2025-06-18T22:55:05.576Z] ====== philosophers (scala) [default], iteration 18 completed (32793.186 ms) ====== [2025-06-18T22:55:05.576Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-18T22:55:05.576Z] GC before operation: completed in 41.358 ms, heap usage 12.323 MB -> 3.752 MB. [2025-06-18T22:55:36.407Z] Camera thread performed 122 scans. [2025-06-18T22:55:36.407Z] ====== philosophers (scala) [default], iteration 19 completed (32810.955 ms) ====== [2025-06-18T22:55:36.407Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-18T22:55:36.407Z] GC before operation: completed in 41.649 ms, heap usage 26.534 MB -> 3.752 MB. [2025-06-18T22:56:11.191Z] Camera thread performed 122 scans. [2025-06-18T22:56:11.191Z] ====== philosophers (scala) [default], iteration 20 completed (33194.226 ms) ====== [2025-06-18T22:56:11.191Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-18T22:56:11.191Z] GC before operation: completed in 51.650 ms, heap usage 11.539 MB -> 3.752 MB. [2025-06-18T22:56:40.491Z] Camera thread performed 122 scans. [2025-06-18T22:56:40.491Z] ====== philosophers (scala) [default], iteration 21 completed (32723.023 ms) ====== [2025-06-18T22:56:40.491Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-18T22:56:40.491Z] GC before operation: completed in 52.314 ms, heap usage 7.267 MB -> 3.752 MB. [2025-06-18T22:57:15.284Z] Camera thread performed 122 scans. [2025-06-18T22:57:15.284Z] ====== philosophers (scala) [default], iteration 22 completed (32788.128 ms) ====== [2025-06-18T22:57:15.284Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-18T22:57:15.284Z] GC before operation: completed in 52.087 ms, heap usage 24.498 MB -> 3.753 MB. [2025-06-18T22:57:50.107Z] Camera thread performed 122 scans. [2025-06-18T22:57:50.107Z] ====== philosophers (scala) [default], iteration 23 completed (33085.504 ms) ====== [2025-06-18T22:57:50.107Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-18T22:57:50.107Z] GC before operation: completed in 50.240 ms, heap usage 14.226 MB -> 3.752 MB. [2025-06-18T22:58:19.232Z] Camera thread performed 122 scans. [2025-06-18T22:58:19.232Z] ====== philosophers (scala) [default], iteration 24 completed (32736.798 ms) ====== [2025-06-18T22:58:19.232Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-18T22:58:19.232Z] GC before operation: completed in 37.341 ms, heap usage 24.543 MB -> 3.753 MB. [2025-06-18T22:58:54.009Z] Camera thread performed 122 scans. [2025-06-18T22:58:54.009Z] ====== philosophers (scala) [default], iteration 25 completed (31893.700 ms) ====== [2025-06-18T22:58:54.009Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-18T22:58:54.009Z] GC before operation: completed in 39.325 ms, heap usage 28.125 MB -> 3.752 MB. [2025-06-18T22:59:23.160Z] Camera thread performed 122 scans. [2025-06-18T22:59:23.160Z] ====== philosophers (scala) [default], iteration 26 completed (32970.753 ms) ====== [2025-06-18T22:59:23.160Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-18T22:59:23.160Z] GC before operation: completed in 42.848 ms, heap usage 6.006 MB -> 3.753 MB. [2025-06-18T22:59:57.950Z] Camera thread performed 122 scans. [2025-06-18T22:59:57.950Z] ====== philosophers (scala) [default], iteration 27 completed (32422.481 ms) ====== [2025-06-18T22:59:57.950Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-18T22:59:57.950Z] GC before operation: completed in 51.884 ms, heap usage 10.222 MB -> 3.752 MB. [2025-06-18T23:00:32.758Z] Camera thread performed 122 scans. [2025-06-18T23:00:32.758Z] ====== philosophers (scala) [default], iteration 28 completed (32610.147 ms) ====== [2025-06-18T23:00:32.758Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-18T23:00:32.758Z] GC before operation: completed in 49.455 ms, heap usage 26.231 MB -> 3.752 MB. [2025-06-18T23:01:01.851Z] Camera thread performed 122 scans. [2025-06-18T23:01:01.851Z] ====== philosophers (scala) [default], iteration 29 completed (33168.994 ms) ====== [2025-06-18T23:01:02.266Z] ----------------------------------- [2025-06-18T23:01:02.266Z] renaissance-philosophers_0_PASSED [2025-06-18T23:01:02.266Z] ----------------------------------- [2025-06-18T23:01:02.266Z] [2025-06-18T23:01:02.266Z] TEST TEARDOWN: [2025-06-18T23:01:02.266Z] Nothing to be done for teardown. [2025-06-18T23:01:02.266Z] renaissance-philosophers_0 Finish Time: Wed Jun 18 23:01:01 2025 Epoch Time (ms): 1750287661927