[2025-05-29T00:01:05.025Z] Running test renaissance-philosophers_0 ... [2025-05-29T00:01:05.025Z] =============================================== [2025-05-29T00:01:05.025Z] renaissance-philosophers_0 Start Time: Thu May 29 00:01:04 2025 Epoch Time (ms): 1748476864324 [2025-05-29T00:01:05.025Z] variation: NoOptions [2025-05-29T00:01:05.025Z] JVM_OPTIONS: [2025-05-29T00:01:05.025Z] { \ [2025-05-29T00:01:05.025Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T00:01:05.025Z] echo "Nothing to be done for setup."; \ [2025-05-29T00:01:05.025Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484748485130/renaissance-philosophers_0"; \ [2025-05-29T00:01:05.025Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484748485130/renaissance-philosophers_0"; \ [2025-05-29T00:01:05.025Z] echo ""; echo "TESTING:"; \ [2025-05-29T00:01:05.025Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_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 "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484748485130/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-29T00:01:05.026Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484748485130/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T00:01:05.026Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T00:01:05.026Z] echo "Nothing to be done for teardown."; \ [2025-05-29T00:01:05.026Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484748485130/TestTargetResult"; [2025-05-29T00:01:05.026Z] [2025-05-29T00:01:05.026Z] TEST SETUP: [2025-05-29T00:01:05.026Z] Nothing to be done for setup. [2025-05-29T00:01:05.026Z] [2025-05-29T00:01:05.026Z] TESTING: [2025-05-29T00:01:05.836Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-29T00:01:05.836Z] GC before operation: completed in 13.744 ms, heap usage 14.508 MB -> 4.690 MB. [2025-05-29T00:01:10.100Z] Camera thread performed 122 scans. [2025-05-29T00:01:10.100Z] ====== philosophers (scala) [default], iteration 0 completed (4130.892 ms) ====== [2025-05-29T00:01:10.100Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-29T00:01:10.100Z] GC before operation: completed in 15.724 ms, heap usage 15.844 MB -> 5.055 MB. [2025-05-29T00:01:11.972Z] Camera thread performed 122 scans. [2025-05-29T00:01:11.972Z] ====== philosophers (scala) [default], iteration 1 completed (1990.969 ms) ====== [2025-05-29T00:01:11.972Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-29T00:01:11.972Z] GC before operation: completed in 12.390 ms, heap usage 61.766 MB -> 5.055 MB. [2025-05-29T00:01:13.889Z] Camera thread performed 122 scans. [2025-05-29T00:01:13.889Z] ====== philosophers (scala) [default], iteration 2 completed (1844.644 ms) ====== [2025-05-29T00:01:13.889Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-29T00:01:13.889Z] GC before operation: completed in 11.595 ms, heap usage 62.266 MB -> 5.055 MB. [2025-05-29T00:01:15.873Z] Camera thread performed 122 scans. [2025-05-29T00:01:15.873Z] ====== philosophers (scala) [default], iteration 3 completed (1873.798 ms) ====== [2025-05-29T00:01:15.873Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-29T00:01:15.873Z] GC before operation: completed in 11.745 ms, heap usage 36.810 MB -> 5.057 MB. [2025-05-29T00:01:17.795Z] Camera thread performed 122 scans. [2025-05-29T00:01:17.795Z] ====== philosophers (scala) [default], iteration 4 completed (1836.289 ms) ====== [2025-05-29T00:01:17.795Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-29T00:01:17.795Z] GC before operation: completed in 13.685 ms, heap usage 48.343 MB -> 5.057 MB. [2025-05-29T00:01:19.110Z] Camera thread performed 122 scans. [2025-05-29T00:01:19.110Z] ====== philosophers (scala) [default], iteration 5 completed (1817.353 ms) ====== [2025-05-29T00:01:19.110Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-29T00:01:19.110Z] GC before operation: completed in 11.284 ms, heap usage 5.811 MB -> 5.057 MB. [2025-05-29T00:01:20.997Z] Camera thread performed 122 scans. [2025-05-29T00:01:20.997Z] ====== philosophers (scala) [default], iteration 6 completed (1856.517 ms) ====== [2025-05-29T00:01:20.997Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-29T00:01:20.997Z] GC before operation: completed in 11.604 ms, heap usage 74.689 MB -> 5.057 MB. [2025-05-29T00:01:23.520Z] Camera thread performed 122 scans. [2025-05-29T00:01:23.520Z] ====== philosophers (scala) [default], iteration 7 completed (1947.649 ms) ====== [2025-05-29T00:01:23.520Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-29T00:01:23.520Z] GC before operation: completed in 11.451 ms, heap usage 46.172 MB -> 5.057 MB. [2025-05-29T00:01:24.829Z] Camera thread performed 122 scans. [2025-05-29T00:01:24.830Z] ====== philosophers (scala) [default], iteration 8 completed (1798.628 ms) ====== [2025-05-29T00:01:24.830Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-29T00:01:24.830Z] GC before operation: completed in 11.787 ms, heap usage 57.679 MB -> 5.058 MB. [2025-05-29T00:01:26.695Z] Camera thread performed 122 scans. [2025-05-29T00:01:26.695Z] ====== philosophers (scala) [default], iteration 9 completed (1973.259 ms) ====== [2025-05-29T00:01:26.695Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-29T00:01:26.695Z] GC before operation: completed in 11.401 ms, heap usage 34.702 MB -> 5.058 MB. [2025-05-29T00:01:28.573Z] Camera thread performed 122 scans. [2025-05-29T00:01:28.573Z] ====== philosophers (scala) [default], iteration 10 completed (1793.964 ms) ====== [2025-05-29T00:01:28.573Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-29T00:01:28.573Z] GC before operation: completed in 11.409 ms, heap usage 39.121 MB -> 5.058 MB. [2025-05-29T00:01:30.475Z] Camera thread performed 122 scans. [2025-05-29T00:01:30.475Z] ====== philosophers (scala) [default], iteration 11 completed (1811.134 ms) ====== [2025-05-29T00:01:30.475Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-29T00:01:30.475Z] GC before operation: completed in 11.792 ms, heap usage 77.276 MB -> 5.058 MB. [2025-05-29T00:01:32.374Z] Camera thread performed 122 scans. [2025-05-29T00:01:32.374Z] ====== philosophers (scala) [default], iteration 12 completed (1821.809 ms) ====== [2025-05-29T00:01:32.374Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-29T00:01:32.374Z] GC before operation: completed in 11.523 ms, heap usage 24.275 MB -> 5.058 MB. [2025-05-29T00:01:34.249Z] Camera thread performed 122 scans. [2025-05-29T00:01:34.249Z] ====== philosophers (scala) [default], iteration 13 completed (1794.119 ms) ====== [2025-05-29T00:01:34.249Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-29T00:01:34.249Z] GC before operation: completed in 11.432 ms, heap usage 10.785 MB -> 5.058 MB. [2025-05-29T00:01:36.133Z] Camera thread performed 122 scans. [2025-05-29T00:01:36.133Z] ====== philosophers (scala) [default], iteration 14 completed (1860.981 ms) ====== [2025-05-29T00:01:36.133Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-29T00:01:36.133Z] GC before operation: completed in 11.622 ms, heap usage 79.693 MB -> 5.059 MB. [2025-05-29T00:01:38.060Z] Camera thread performed 122 scans. [2025-05-29T00:01:38.060Z] ====== philosophers (scala) [default], iteration 15 completed (1798.457 ms) ====== [2025-05-29T00:01:38.060Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-29T00:01:38.060Z] GC before operation: completed in 11.500 ms, heap usage 38.274 MB -> 5.059 MB. [2025-05-29T00:01:39.537Z] Camera thread performed 122 scans. [2025-05-29T00:01:39.537Z] ====== philosophers (scala) [default], iteration 16 completed (1866.058 ms) ====== [2025-05-29T00:01:39.537Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-29T00:01:39.537Z] GC before operation: completed in 11.362 ms, heap usage 21.096 MB -> 5.059 MB. [2025-05-29T00:01:41.437Z] Camera thread performed 122 scans. [2025-05-29T00:01:41.438Z] ====== philosophers (scala) [default], iteration 17 completed (1858.535 ms) ====== [2025-05-29T00:01:41.438Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-29T00:01:41.438Z] GC before operation: completed in 11.358 ms, heap usage 26.130 MB -> 5.059 MB. [2025-05-29T00:01:43.323Z] Camera thread performed 122 scans. [2025-05-29T00:01:43.323Z] ====== philosophers (scala) [default], iteration 18 completed (1801.892 ms) ====== [2025-05-29T00:01:43.323Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-29T00:01:43.323Z] GC before operation: completed in 13.453 ms, heap usage 49.220 MB -> 5.059 MB. [2025-05-29T00:01:45.267Z] Camera thread performed 122 scans. [2025-05-29T00:01:45.267Z] ====== philosophers (scala) [default], iteration 19 completed (1797.801 ms) ====== [2025-05-29T00:01:45.267Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-29T00:01:45.267Z] GC before operation: completed in 11.287 ms, heap usage 31.269 MB -> 5.059 MB. [2025-05-29T00:01:47.183Z] Camera thread performed 122 scans. [2025-05-29T00:01:47.183Z] ====== philosophers (scala) [default], iteration 20 completed (1901.946 ms) ====== [2025-05-29T00:01:47.183Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-29T00:01:47.183Z] GC before operation: completed in 11.594 ms, heap usage 62.213 MB -> 5.060 MB. [2025-05-29T00:01:49.075Z] Camera thread performed 122 scans. [2025-05-29T00:01:49.075Z] ====== philosophers (scala) [default], iteration 21 completed (1820.929 ms) ====== [2025-05-29T00:01:49.075Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-29T00:01:49.075Z] GC before operation: completed in 11.646 ms, heap usage 58.680 MB -> 5.059 MB. [2025-05-29T00:01:50.958Z] Camera thread performed 122 scans. [2025-05-29T00:01:50.958Z] ====== philosophers (scala) [default], iteration 22 completed (1775.089 ms) ====== [2025-05-29T00:01:50.958Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-29T00:01:50.958Z] GC before operation: completed in 11.534 ms, heap usage 50.653 MB -> 5.060 MB. [2025-05-29T00:01:52.861Z] Camera thread performed 122 scans. [2025-05-29T00:01:52.861Z] ====== philosophers (scala) [default], iteration 23 completed (1782.860 ms) ====== [2025-05-29T00:01:52.861Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-29T00:01:52.861Z] GC before operation: completed in 11.982 ms, heap usage 61.111 MB -> 5.059 MB. [2025-05-29T00:01:54.180Z] Camera thread performed 122 scans. [2025-05-29T00:01:54.180Z] ====== philosophers (scala) [default], iteration 24 completed (1799.491 ms) ====== [2025-05-29T00:01:54.180Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-29T00:01:54.180Z] GC before operation: completed in 11.864 ms, heap usage 32.677 MB -> 5.060 MB. [2025-05-29T00:01:56.697Z] Camera thread performed 122 scans. [2025-05-29T00:01:56.697Z] ====== philosophers (scala) [default], iteration 25 completed (1912.744 ms) ====== [2025-05-29T00:01:56.697Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-29T00:01:56.697Z] GC before operation: completed in 11.520 ms, heap usage 12.774 MB -> 5.060 MB. [2025-05-29T00:01:58.013Z] Camera thread performed 122 scans. [2025-05-29T00:01:58.013Z] ====== philosophers (scala) [default], iteration 26 completed (1825.713 ms) ====== [2025-05-29T00:01:58.013Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-29T00:01:58.013Z] GC before operation: completed in 11.599 ms, heap usage 75.098 MB -> 5.060 MB. [2025-05-29T00:02:00.536Z] Camera thread performed 122 scans. [2025-05-29T00:02:00.536Z] ====== philosophers (scala) [default], iteration 27 completed (2014.785 ms) ====== [2025-05-29T00:02:00.536Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-29T00:02:00.536Z] GC before operation: completed in 11.419 ms, heap usage 58.704 MB -> 5.060 MB. [2025-05-29T00:02:02.422Z] Camera thread performed 122 scans. [2025-05-29T00:02:02.422Z] ====== philosophers (scala) [default], iteration 28 completed (1918.044 ms) ====== [2025-05-29T00:02:02.422Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-29T00:02:02.422Z] GC before operation: completed in 11.422 ms, heap usage 8.777 MB -> 5.060 MB. [2025-05-29T00:02:04.296Z] Camera thread performed 122 scans. [2025-05-29T00:02:04.296Z] ====== philosophers (scala) [default], iteration 29 completed (1892.933 ms) ====== [2025-05-29T00:02:04.296Z] ----------------------------------- [2025-05-29T00:02:04.296Z] renaissance-philosophers_0_PASSED [2025-05-29T00:02:04.296Z] ----------------------------------- [2025-05-29T00:02:04.296Z] [2025-05-29T00:02:04.296Z] TEST TEARDOWN: [2025-05-29T00:02:04.296Z] Nothing to be done for teardown. [2025-05-29T00:02:04.296Z] renaissance-philosophers_0 Finish Time: Thu May 29 00:02:04 2025 Epoch Time (ms): 1748476924036