[2025-03-30T21:15:14.120Z] Running test renaissance-philosophers_0 ... [2025-03-30T21:15:14.120Z] =============================================== [2025-03-30T21:15:14.120Z] renaissance-philosophers_0 Start Time: Sun Mar 30 21:15:12 2025 Epoch Time (ms): 1743369312505 [2025-03-30T21:15:14.120Z] variation: NoOptions [2025-03-30T21:15:14.120Z] JVM_OPTIONS: [2025-03-30T21:15:14.120Z] { \ [2025-03-30T21:15:14.120Z] echo ""; echo "TEST SETUP:"; \ [2025-03-30T21:15:14.120Z] echo "Nothing to be done for setup."; \ [2025-03-30T21:15:14.120Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17433678387351/renaissance-philosophers_0"; \ [2025-03-30T21:15:14.120Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17433678387351/renaissance-philosophers_0"; \ [2025-03-30T21:15:14.120Z] echo ""; echo "TESTING:"; \ [2025-03-30T21:15:14.120Z] "/home/jenkins/workspace/Test_openjdk11_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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17433678387351/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-03-30T21:15:14.120Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17433678387351/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-30T21:15:14.120Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-30T21:15:14.120Z] echo "Nothing to be done for teardown."; \ [2025-03-30T21:15:14.120Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17433678387351/TestTargetResult"; [2025-03-30T21:15:14.120Z] [2025-03-30T21:15:14.120Z] TEST SETUP: [2025-03-30T21:15:14.120Z] Nothing to be done for setup. [2025-03-30T21:15:14.120Z] [2025-03-30T21:15:14.120Z] TESTING: [2025-03-30T21:15:16.601Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-03-30T21:15:16.601Z] GC before operation: completed in 83.327 ms, heap usage 17.456 MB -> 4.603 MB. [2025-03-30T21:15:30.620Z] Camera thread performed 122 scans. [2025-03-30T21:15:30.620Z] ====== philosophers (scala) [default], iteration 0 completed (12516.221 ms) ====== [2025-03-30T21:15:30.621Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-03-30T21:15:30.621Z] GC before operation: completed in 71.348 ms, heap usage 9.983 MB -> 5.010 MB. [2025-03-30T21:15:34.147Z] Camera thread performed 122 scans. [2025-03-30T21:15:34.147Z] ====== philosophers (scala) [default], iteration 1 completed (4971.599 ms) ====== [2025-03-30T21:15:34.148Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-03-30T21:15:34.148Z] GC before operation: completed in 55.771 ms, heap usage 31.232 MB -> 5.010 MB. [2025-03-30T21:15:39.813Z] Camera thread performed 122 scans. [2025-03-30T21:15:39.813Z] ====== philosophers (scala) [default], iteration 2 completed (5781.633 ms) ====== [2025-03-30T21:15:39.813Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-03-30T21:15:39.813Z] GC before operation: completed in 52.877 ms, heap usage 31.237 MB -> 5.016 MB. [2025-03-30T21:15:45.603Z] Camera thread performed 122 scans. [2025-03-30T21:15:45.603Z] ====== philosophers (scala) [default], iteration 3 completed (5151.922 ms) ====== [2025-03-30T21:15:45.603Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-03-30T21:15:45.603Z] GC before operation: completed in 46.078 ms, heap usage 12.591 MB -> 5.017 MB. [2025-03-30T21:15:50.141Z] Camera thread performed 122 scans. [2025-03-30T21:15:50.141Z] ====== philosophers (scala) [default], iteration 4 completed (5154.657 ms) ====== [2025-03-30T21:15:50.141Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-03-30T21:15:50.141Z] GC before operation: completed in 39.985 ms, heap usage 10.126 MB -> 5.017 MB. [2025-03-30T21:15:56.518Z] Camera thread performed 122 scans. [2025-03-30T21:15:56.518Z] ====== philosophers (scala) [default], iteration 5 completed (5175.519 ms) ====== [2025-03-30T21:15:56.518Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-03-30T21:15:56.518Z] GC before operation: completed in 66.674 ms, heap usage 26.033 MB -> 5.017 MB. [2025-03-30T21:16:00.025Z] Camera thread performed 122 scans. [2025-03-30T21:16:00.025Z] ====== philosophers (scala) [default], iteration 6 completed (4547.285 ms) ====== [2025-03-30T21:16:00.025Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-03-30T21:16:00.025Z] GC before operation: completed in 60.195 ms, heap usage 34.499 MB -> 5.021 MB. [2025-03-30T21:16:05.759Z] Camera thread performed 122 scans. [2025-03-30T21:16:05.759Z] ====== philosophers (scala) [default], iteration 7 completed (5199.636 ms) ====== [2025-03-30T21:16:05.759Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-03-30T21:16:05.759Z] GC before operation: completed in 68.481 ms, heap usage 40.154 MB -> 5.021 MB. [2025-03-30T21:16:10.344Z] Camera thread performed 122 scans. [2025-03-30T21:16:10.344Z] ====== philosophers (scala) [default], iteration 8 completed (4927.102 ms) ====== [2025-03-30T21:16:10.344Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-03-30T21:16:10.344Z] GC before operation: completed in 62.791 ms, heap usage 33.923 MB -> 5.022 MB. [2025-03-30T21:16:16.060Z] Camera thread performed 122 scans. [2025-03-30T21:16:16.060Z] ====== philosophers (scala) [default], iteration 9 completed (4905.382 ms) ====== [2025-03-30T21:16:16.060Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-03-30T21:16:16.060Z] GC before operation: completed in 53.496 ms, heap usage 10.262 MB -> 5.021 MB. [2025-03-30T21:16:20.636Z] Camera thread performed 122 scans. [2025-03-30T21:16:20.636Z] ====== philosophers (scala) [default], iteration 10 completed (5266.109 ms) ====== [2025-03-30T21:16:20.636Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-03-30T21:16:20.636Z] GC before operation: completed in 50.356 ms, heap usage 34.160 MB -> 5.021 MB. [2025-03-30T21:16:26.398Z] Camera thread performed 122 scans. [2025-03-30T21:16:26.398Z] ====== philosophers (scala) [default], iteration 11 completed (4851.199 ms) ====== [2025-03-30T21:16:26.398Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-03-30T21:16:26.398Z] GC before operation: completed in 65.504 ms, heap usage 45.481 MB -> 5.021 MB. [2025-03-30T21:16:32.157Z] Camera thread performed 122 scans. [2025-03-30T21:16:32.157Z] ====== philosophers (scala) [default], iteration 12 completed (5751.422 ms) ====== [2025-03-30T21:16:32.157Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-03-30T21:16:32.157Z] GC before operation: completed in 85.106 ms, heap usage 32.480 MB -> 5.022 MB. [2025-03-30T21:16:37.930Z] Camera thread performed 122 scans. [2025-03-30T21:16:37.930Z] ====== philosophers (scala) [default], iteration 13 completed (6136.277 ms) ====== [2025-03-30T21:16:37.930Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-03-30T21:16:37.930Z] GC before operation: completed in 49.519 ms, heap usage 10.140 MB -> 5.022 MB. [2025-03-30T21:16:44.915Z] Camera thread performed 122 scans. [2025-03-30T21:16:44.915Z] ====== philosophers (scala) [default], iteration 14 completed (6504.152 ms) ====== [2025-03-30T21:16:44.915Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-03-30T21:16:44.915Z] GC before operation: completed in 53.806 ms, heap usage 40.256 MB -> 5.022 MB. [2025-03-30T21:16:50.659Z] Camera thread performed 122 scans. [2025-03-30T21:16:50.659Z] ====== philosophers (scala) [default], iteration 15 completed (6009.814 ms) ====== [2025-03-30T21:16:50.659Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-03-30T21:16:50.659Z] GC before operation: completed in 54.116 ms, heap usage 14.581 MB -> 5.022 MB. [2025-03-30T21:16:58.423Z] Camera thread performed 122 scans. [2025-03-30T21:16:58.423Z] ====== philosophers (scala) [default], iteration 16 completed (7411.822 ms) ====== [2025-03-30T21:16:58.423Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-03-30T21:16:58.423Z] GC before operation: completed in 90.769 ms, heap usage 48.786 MB -> 5.022 MB. [2025-03-30T21:17:05.528Z] Camera thread performed 122 scans. [2025-03-30T21:17:05.528Z] ====== philosophers (scala) [default], iteration 17 completed (6793.410 ms) ====== [2025-03-30T21:17:05.528Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-03-30T21:17:05.528Z] GC before operation: completed in 95.446 ms, heap usage 37.537 MB -> 5.022 MB. [2025-03-30T21:17:11.308Z] Camera thread performed 122 scans. [2025-03-30T21:17:11.308Z] ====== philosophers (scala) [default], iteration 18 completed (5674.920 ms) ====== [2025-03-30T21:17:11.308Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-03-30T21:17:11.308Z] GC before operation: completed in 98.677 ms, heap usage 13.468 MB -> 5.022 MB. [2025-03-30T21:17:17.032Z] Camera thread performed 122 scans. [2025-03-30T21:17:17.032Z] ====== philosophers (scala) [default], iteration 19 completed (6303.098 ms) ====== [2025-03-30T21:17:17.032Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-03-30T21:17:17.032Z] GC before operation: completed in 87.984 ms, heap usage 25.254 MB -> 5.022 MB. [2025-03-30T21:17:24.119Z] Camera thread performed 122 scans. [2025-03-30T21:17:24.119Z] ====== philosophers (scala) [default], iteration 20 completed (6496.290 ms) ====== [2025-03-30T21:17:24.119Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-03-30T21:17:24.119Z] GC before operation: completed in 76.900 ms, heap usage 20.675 MB -> 5.022 MB. [2025-03-30T21:17:29.887Z] Camera thread performed 122 scans. [2025-03-30T21:17:29.888Z] ====== philosophers (scala) [default], iteration 21 completed (5598.742 ms) ====== [2025-03-30T21:17:29.888Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-03-30T21:17:29.888Z] GC before operation: completed in 93.427 ms, heap usage 32.518 MB -> 5.022 MB. [2025-03-30T21:17:34.498Z] Camera thread performed 122 scans. [2025-03-30T21:17:34.498Z] ====== philosophers (scala) [default], iteration 22 completed (5542.710 ms) ====== [2025-03-30T21:17:34.498Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-03-30T21:17:35.296Z] GC before operation: completed in 87.774 ms, heap usage 11.909 MB -> 5.022 MB. [2025-03-30T21:17:42.343Z] Camera thread performed 122 scans. [2025-03-30T21:17:42.343Z] ====== philosophers (scala) [default], iteration 23 completed (6872.461 ms) ====== [2025-03-30T21:17:42.343Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-03-30T21:17:42.343Z] GC before operation: completed in 124.825 ms, heap usage 18.257 MB -> 5.022 MB. [2025-03-30T21:17:48.038Z] Camera thread performed 122 scans. [2025-03-30T21:17:48.038Z] ====== philosophers (scala) [default], iteration 24 completed (6364.285 ms) ====== [2025-03-30T21:17:48.038Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-03-30T21:17:48.038Z] GC before operation: completed in 107.413 ms, heap usage 22.036 MB -> 5.023 MB. [2025-03-30T21:17:53.842Z] Camera thread performed 122 scans. [2025-03-30T21:17:53.842Z] ====== philosophers (scala) [default], iteration 25 completed (5396.636 ms) ====== [2025-03-30T21:17:53.842Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-03-30T21:17:53.842Z] GC before operation: completed in 71.257 ms, heap usage 6.549 MB -> 5.023 MB. [2025-03-30T21:17:59.844Z] Camera thread performed 122 scans. [2025-03-30T21:17:59.844Z] ====== philosophers (scala) [default], iteration 26 completed (6258.115 ms) ====== [2025-03-30T21:17:59.844Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-03-30T21:17:59.844Z] GC before operation: completed in 90.069 ms, heap usage 22.558 MB -> 5.024 MB. [2025-03-30T21:18:06.958Z] Camera thread performed 122 scans. [2025-03-30T21:18:06.958Z] ====== philosophers (scala) [default], iteration 27 completed (6891.339 ms) ====== [2025-03-30T21:18:06.958Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-03-30T21:18:06.958Z] GC before operation: completed in 82.151 ms, heap usage 23.006 MB -> 5.023 MB. [2025-03-30T21:18:14.014Z] Camera thread performed 122 scans. [2025-03-30T21:18:14.014Z] ====== philosophers (scala) [default], iteration 28 completed (6652.765 ms) ====== [2025-03-30T21:18:14.014Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-03-30T21:18:14.014Z] GC before operation: completed in 106.066 ms, heap usage 44.883 MB -> 5.023 MB. [2025-03-30T21:18:20.990Z] Camera thread performed 122 scans. [2025-03-30T21:18:20.990Z] ====== philosophers (scala) [default], iteration 29 completed (7353.186 ms) ====== [2025-03-30T21:18:22.600Z] ----------------------------------- [2025-03-30T21:18:22.600Z] renaissance-philosophers_0_PASSED [2025-03-30T21:18:22.600Z] ----------------------------------- [2025-03-30T21:18:22.600Z] [2025-03-30T21:18:22.600Z] TEST TEARDOWN: [2025-03-30T21:18:22.600Z] Nothing to be done for teardown. [2025-03-30T21:18:22.600Z] renaissance-philosophers_0 Finish Time: Sun Mar 30 21:18:21 2025 Epoch Time (ms): 1743369501997