[2025-05-29T06:06:06.658Z] Running test renaissance-philosophers_0 ... [2025-05-29T06:06:06.658Z] =============================================== [2025-05-29T06:06:06.658Z] renaissance-philosophers_0 Start Time: Thu May 29 07:06:06 2025 Epoch Time (ms): 1748498766445 [2025-05-29T06:06:06.658Z] variation: NoOptions [2025-05-29T06:06:06.658Z] JVM_OPTIONS: [2025-05-29T06:06:06.658Z] { \ [2025-05-29T06:06:06.658Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T06:06:06.658Z] echo "Nothing to be done for setup."; \ [2025-05-29T06:06:06.658Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17484832519162/renaissance-philosophers_0"; \ [2025-05-29T06:06:06.658Z] cd "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17484832519162/renaissance-philosophers_0"; \ [2025-05-29T06:06:06.658Z] echo ""; echo "TESTING:"; \ [2025-05-29T06:06:06.659Z] "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17484832519162/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-29T06:06:06.659Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17484832519162/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T06:06:06.659Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T06:06:06.659Z] echo "Nothing to be done for teardown."; \ [2025-05-29T06:06:06.659Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17484832519162/TestTargetResult"; [2025-05-29T06:06:06.659Z] [2025-05-29T06:06:06.659Z] TEST SETUP: [2025-05-29T06:06:06.659Z] Nothing to be done for setup. [2025-05-29T06:06:06.659Z] [2025-05-29T06:06:06.659Z] TESTING: [2025-05-29T06:06:07.895Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-29T06:06:07.895Z] GC before operation: completed in 13.024 ms, heap usage 6.503 MB -> 4.432 MB. [2025-05-29T06:06:17.057Z] Camera thread performed 122 scans. [2025-05-29T06:06:17.057Z] ====== philosophers (scala) [default], iteration 0 completed (8448.444 ms) ====== [2025-05-29T06:06:17.057Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-29T06:06:17.057Z] GC before operation: completed in 18.009 ms, heap usage 63.510 MB -> 4.859 MB. [2025-05-29T06:06:18.276Z] Camera thread performed 122 scans. [2025-05-29T06:06:18.276Z] ====== philosophers (scala) [default], iteration 1 completed (2049.779 ms) ====== [2025-05-29T06:06:18.276Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-29T06:06:18.276Z] GC before operation: completed in 12.408 ms, heap usage 58.189 MB -> 4.863 MB. [2025-05-29T06:06:20.033Z] Camera thread performed 122 scans. [2025-05-29T06:06:20.033Z] ====== philosophers (scala) [default], iteration 2 completed (1603.961 ms) ====== [2025-05-29T06:06:20.033Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-29T06:06:20.033Z] GC before operation: completed in 20.721 ms, heap usage 63.625 MB -> 4.865 MB. [2025-05-29T06:06:21.795Z] Camera thread performed 122 scans. [2025-05-29T06:06:21.795Z] ====== philosophers (scala) [default], iteration 3 completed (1590.813 ms) ====== [2025-05-29T06:06:21.795Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-29T06:06:21.795Z] GC before operation: completed in 22.156 ms, heap usage 47.700 MB -> 4.867 MB. [2025-05-29T06:06:23.553Z] Camera thread performed 122 scans. [2025-05-29T06:06:23.553Z] ====== philosophers (scala) [default], iteration 4 completed (1680.337 ms) ====== [2025-05-29T06:06:23.553Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-29T06:06:23.553Z] GC before operation: completed in 19.877 ms, heap usage 55.405 MB -> 4.868 MB. [2025-05-29T06:06:25.311Z] Camera thread performed 122 scans. [2025-05-29T06:06:25.311Z] ====== philosophers (scala) [default], iteration 5 completed (1775.881 ms) ====== [2025-05-29T06:06:25.311Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-29T06:06:25.311Z] GC before operation: completed in 30.880 ms, heap usage 6.127 MB -> 4.868 MB. [2025-05-29T06:06:26.531Z] Camera thread performed 122 scans. [2025-05-29T06:06:26.531Z] ====== philosophers (scala) [default], iteration 6 completed (1554.841 ms) ====== [2025-05-29T06:06:26.531Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-29T06:06:26.531Z] GC before operation: completed in 23.858 ms, heap usage 14.313 MB -> 4.868 MB. [2025-05-29T06:06:28.290Z] Camera thread performed 122 scans. [2025-05-29T06:06:28.290Z] ====== philosophers (scala) [default], iteration 7 completed (1683.596 ms) ====== [2025-05-29T06:06:28.291Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-29T06:06:28.291Z] GC before operation: completed in 23.939 ms, heap usage 74.265 MB -> 4.869 MB. [2025-05-29T06:06:30.053Z] Camera thread performed 122 scans. [2025-05-29T06:06:30.053Z] ====== philosophers (scala) [default], iteration 8 completed (1682.025 ms) ====== [2025-05-29T06:06:30.053Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-29T06:06:30.053Z] GC before operation: completed in 16.951 ms, heap usage 107.616 MB -> 4.870 MB. [2025-05-29T06:06:31.810Z] Camera thread performed 122 scans. [2025-05-29T06:06:31.810Z] ====== philosophers (scala) [default], iteration 9 completed (1769.484 ms) ====== [2025-05-29T06:06:31.810Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-29T06:06:31.810Z] GC before operation: completed in 27.419 ms, heap usage 79.651 MB -> 4.870 MB. [2025-05-29T06:06:33.584Z] Camera thread performed 122 scans. [2025-05-29T06:06:33.584Z] ====== philosophers (scala) [default], iteration 10 completed (1610.266 ms) ====== [2025-05-29T06:06:33.584Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-29T06:06:33.584Z] GC before operation: completed in 20.367 ms, heap usage 21.063 MB -> 4.870 MB. [2025-05-29T06:06:35.342Z] Camera thread performed 122 scans. [2025-05-29T06:06:35.342Z] ====== philosophers (scala) [default], iteration 11 completed (1603.815 ms) ====== [2025-05-29T06:06:35.342Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-29T06:06:35.342Z] GC before operation: completed in 19.083 ms, heap usage 79.562 MB -> 4.870 MB. [2025-05-29T06:06:37.097Z] Camera thread performed 122 scans. [2025-05-29T06:06:37.097Z] ====== philosophers (scala) [default], iteration 12 completed (1687.771 ms) ====== [2025-05-29T06:06:37.097Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-29T06:06:37.097Z] GC before operation: completed in 31.474 ms, heap usage 8.267 MB -> 4.871 MB. [2025-05-29T06:06:38.859Z] Camera thread performed 122 scans. [2025-05-29T06:06:38.859Z] ====== philosophers (scala) [default], iteration 13 completed (1716.677 ms) ====== [2025-05-29T06:06:38.859Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-29T06:06:38.859Z] GC before operation: completed in 23.367 ms, heap usage 65.254 MB -> 4.871 MB. [2025-05-29T06:06:40.618Z] Camera thread performed 122 scans. [2025-05-29T06:06:40.618Z] ====== philosophers (scala) [default], iteration 14 completed (1667.768 ms) ====== [2025-05-29T06:06:40.618Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-29T06:06:40.618Z] GC before operation: completed in 23.302 ms, heap usage 99.019 MB -> 4.872 MB. [2025-05-29T06:06:41.877Z] Camera thread performed 122 scans. [2025-05-29T06:06:41.877Z] ====== philosophers (scala) [default], iteration 15 completed (1753.952 ms) ====== [2025-05-29T06:06:41.877Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-29T06:06:42.230Z] GC before operation: completed in 23.753 ms, heap usage 96.902 MB -> 4.872 MB. [2025-05-29T06:06:43.988Z] Camera thread performed 122 scans. [2025-05-29T06:06:43.989Z] ====== philosophers (scala) [default], iteration 16 completed (1627.828 ms) ====== [2025-05-29T06:06:43.989Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-29T06:06:43.989Z] GC before operation: completed in 25.403 ms, heap usage 83.926 MB -> 4.872 MB. [2025-05-29T06:06:45.745Z] Camera thread performed 122 scans. [2025-05-29T06:06:45.746Z] ====== philosophers (scala) [default], iteration 17 completed (1760.876 ms) ====== [2025-05-29T06:06:45.746Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-29T06:06:45.746Z] GC before operation: completed in 21.926 ms, heap usage 58.596 MB -> 4.872 MB. [2025-05-29T06:06:47.499Z] Camera thread performed 122 scans. [2025-05-29T06:06:47.499Z] ====== philosophers (scala) [default], iteration 18 completed (1644.097 ms) ====== [2025-05-29T06:06:47.499Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-29T06:06:47.499Z] GC before operation: completed in 24.161 ms, heap usage 92.252 MB -> 4.872 MB. [2025-05-29T06:06:48.719Z] Camera thread performed 122 scans. [2025-05-29T06:06:48.719Z] ====== philosophers (scala) [default], iteration 19 completed (1687.278 ms) ====== [2025-05-29T06:06:48.719Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-29T06:06:48.719Z] GC before operation: completed in 29.746 ms, heap usage 110.952 MB -> 4.872 MB. [2025-05-29T06:06:50.472Z] Camera thread performed 122 scans. [2025-05-29T06:06:50.472Z] ====== philosophers (scala) [default], iteration 20 completed (1736.516 ms) ====== [2025-05-29T06:06:50.472Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-29T06:06:50.472Z] GC before operation: completed in 24.310 ms, heap usage 9.062 MB -> 4.872 MB. [2025-05-29T06:06:52.237Z] Camera thread performed 122 scans. [2025-05-29T06:06:52.237Z] ====== philosophers (scala) [default], iteration 21 completed (1691.153 ms) ====== [2025-05-29T06:06:52.237Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-29T06:06:52.237Z] GC before operation: completed in 21.722 ms, heap usage 144.260 MB -> 4.872 MB. [2025-05-29T06:06:54.612Z] Camera thread performed 122 scans. [2025-05-29T06:06:54.612Z] ====== philosophers (scala) [default], iteration 22 completed (1810.571 ms) ====== [2025-05-29T06:06:54.612Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-29T06:06:54.612Z] GC before operation: completed in 21.119 ms, heap usage 124.926 MB -> 4.872 MB. [2025-05-29T06:06:56.375Z] Camera thread performed 122 scans. [2025-05-29T06:06:56.375Z] ====== philosophers (scala) [default], iteration 23 completed (1928.887 ms) ====== [2025-05-29T06:06:56.375Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-29T06:06:56.375Z] GC before operation: completed in 23.245 ms, heap usage 71.878 MB -> 4.872 MB. [2025-05-29T06:06:58.132Z] Camera thread performed 122 scans. [2025-05-29T06:06:58.132Z] ====== philosophers (scala) [default], iteration 24 completed (1847.207 ms) ====== [2025-05-29T06:06:58.132Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-29T06:06:58.132Z] GC before operation: completed in 25.789 ms, heap usage 88.258 MB -> 4.872 MB. [2025-05-29T06:06:59.889Z] Camera thread performed 122 scans. [2025-05-29T06:06:59.889Z] ====== philosophers (scala) [default], iteration 25 completed (1850.046 ms) ====== [2025-05-29T06:06:59.889Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-29T06:06:59.889Z] GC before operation: completed in 22.366 ms, heap usage 74.257 MB -> 4.872 MB. [2025-05-29T06:07:01.651Z] Camera thread performed 122 scans. [2025-05-29T06:07:01.651Z] ====== philosophers (scala) [default], iteration 26 completed (1763.046 ms) ====== [2025-05-29T06:07:01.651Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-29T06:07:01.651Z] GC before operation: completed in 26.003 ms, heap usage 75.616 MB -> 4.872 MB. [2025-05-29T06:07:03.407Z] Camera thread performed 122 scans. [2025-05-29T06:07:03.407Z] ====== philosophers (scala) [default], iteration 27 completed (1811.572 ms) ====== [2025-05-29T06:07:03.407Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-29T06:07:03.407Z] GC before operation: completed in 22.062 ms, heap usage 131.579 MB -> 4.872 MB. [2025-05-29T06:07:05.173Z] Camera thread performed 122 scans. [2025-05-29T06:07:05.173Z] ====== philosophers (scala) [default], iteration 28 completed (1735.715 ms) ====== [2025-05-29T06:07:05.173Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-29T06:07:05.173Z] GC before operation: completed in 22.845 ms, heap usage 13.692 MB -> 4.872 MB. [2025-05-29T06:07:07.559Z] Camera thread performed 122 scans. [2025-05-29T06:07:07.559Z] ====== philosophers (scala) [default], iteration 29 completed (1851.889 ms) ====== [2025-05-29T06:07:07.559Z] ----------------------------------- [2025-05-29T06:07:07.559Z] renaissance-philosophers_0_PASSED [2025-05-29T06:07:07.559Z] ----------------------------------- [2025-05-29T06:07:07.559Z] [2025-05-29T06:07:07.559Z] TEST TEARDOWN: [2025-05-29T06:07:07.559Z] Nothing to be done for teardown. [2025-05-29T06:07:07.559Z] renaissance-philosophers_0 Finish Time: Thu May 29 07:07:07 2025 Epoch Time (ms): 1748498827217