[2025-05-07T14:15:39.352Z] Running test renaissance-philosophers_0 ... [2025-05-07T14:15:39.352Z] =============================================== [2025-05-07T14:15:39.352Z] renaissance-philosophers_0 Start Time: Wed May 7 14:15:38 2025 Epoch Time (ms): 1746627338951 [2025-05-07T14:15:39.352Z] variation: NoOptions [2025-05-07T14:15:39.352Z] JVM_OPTIONS: [2025-05-07T14:15:39.352Z] { \ [2025-05-07T14:15:39.352Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T14:15:39.352Z] echo "Nothing to be done for setup."; \ [2025-05-07T14:15:39.352Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-philosophers_0"; \ [2025-05-07T14:15:39.352Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-philosophers_0"; \ [2025-05-07T14:15:39.352Z] echo ""; echo "TESTING:"; \ [2025-05-07T14:15:39.352Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-07T14:15:39.352Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T14:15:39.352Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T14:15:39.352Z] echo "Nothing to be done for teardown."; \ [2025-05-07T14:15:39.352Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1746623986768/TestTargetResult"; [2025-05-07T14:15:39.352Z] [2025-05-07T14:15:39.352Z] TEST SETUP: [2025-05-07T14:15:39.352Z] Nothing to be done for setup. [2025-05-07T14:15:39.352Z] [2025-05-07T14:15:39.352Z] TESTING: [2025-05-07T14:15:44.044Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-07T14:15:44.044Z] GC before operation: completed in 28.621 ms, heap usage 7.309 MB -> 4.293 MB. [2025-05-07T14:15:54.012Z] Camera thread performed 122 scans. [2025-05-07T14:15:54.012Z] ====== philosophers (scala) [default], iteration 0 completed (9708.993 ms) ====== [2025-05-07T14:15:54.012Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-07T14:15:54.012Z] GC before operation: completed in 21.110 ms, heap usage 198.466 MB -> 4.726 MB. [2025-05-07T14:16:00.948Z] Camera thread performed 122 scans. [2025-05-07T14:16:00.948Z] ====== philosophers (scala) [default], iteration 1 completed (6461.763 ms) ====== [2025-05-07T14:16:00.948Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-07T14:16:00.948Z] GC before operation: completed in 19.037 ms, heap usage 179.310 MB -> 4.727 MB. [2025-05-07T14:16:07.886Z] Camera thread performed 122 scans. [2025-05-07T14:16:07.886Z] ====== philosophers (scala) [default], iteration 2 completed (7017.368 ms) ====== [2025-05-07T14:16:07.886Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-07T14:16:07.886Z] GC before operation: completed in 19.378 ms, heap usage 23.445 MB -> 4.729 MB. [2025-05-07T14:16:13.545Z] Camera thread performed 122 scans. [2025-05-07T14:16:13.545Z] ====== philosophers (scala) [default], iteration 3 completed (6684.410 ms) ====== [2025-05-07T14:16:13.545Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-07T14:16:13.545Z] GC before operation: completed in 19.431 ms, heap usage 80.973 MB -> 4.730 MB. [2025-05-07T14:16:20.485Z] Camera thread performed 122 scans. [2025-05-07T14:16:20.485Z] ====== philosophers (scala) [default], iteration 4 completed (6931.484 ms) ====== [2025-05-07T14:16:20.485Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-07T14:16:20.485Z] GC before operation: completed in 19.122 ms, heap usage 133.760 MB -> 4.730 MB. [2025-05-07T14:16:27.420Z] Camera thread performed 122 scans. [2025-05-07T14:16:27.420Z] ====== philosophers (scala) [default], iteration 5 completed (6360.879 ms) ====== [2025-05-07T14:16:27.420Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-07T14:16:27.420Z] GC before operation: completed in 19.468 ms, heap usage 158.913 MB -> 4.730 MB. [2025-05-07T14:16:34.683Z] Camera thread performed 122 scans. [2025-05-07T14:16:34.683Z] ====== philosophers (scala) [default], iteration 6 completed (6821.708 ms) ====== [2025-05-07T14:16:34.683Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-07T14:16:34.683Z] GC before operation: completed in 18.929 ms, heap usage 34.452 MB -> 4.730 MB. [2025-05-07T14:16:40.343Z] Camera thread performed 122 scans. [2025-05-07T14:16:40.343Z] ====== philosophers (scala) [default], iteration 7 completed (6662.829 ms) ====== [2025-05-07T14:16:40.343Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-07T14:16:40.343Z] GC before operation: completed in 20.432 ms, heap usage 102.516 MB -> 4.730 MB. [2025-05-07T14:16:48.707Z] Camera thread performed 122 scans. [2025-05-07T14:16:48.707Z] ====== philosophers (scala) [default], iteration 8 completed (7062.786 ms) ====== [2025-05-07T14:16:48.707Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-07T14:16:48.707Z] GC before operation: completed in 20.379 ms, heap usage 210.119 MB -> 4.731 MB. [2025-05-07T14:16:54.369Z] Camera thread performed 122 scans. [2025-05-07T14:16:54.369Z] ====== philosophers (scala) [default], iteration 9 completed (6652.279 ms) ====== [2025-05-07T14:16:54.369Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-07T14:16:54.369Z] GC before operation: completed in 20.331 ms, heap usage 117.843 MB -> 4.731 MB. [2025-05-07T14:17:01.315Z] Camera thread performed 122 scans. [2025-05-07T14:17:01.315Z] ====== philosophers (scala) [default], iteration 10 completed (6639.533 ms) ====== [2025-05-07T14:17:01.315Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-07T14:17:01.315Z] GC before operation: completed in 20.335 ms, heap usage 82.906 MB -> 4.731 MB. [2025-05-07T14:17:08.348Z] Camera thread performed 122 scans. [2025-05-07T14:17:08.348Z] ====== philosophers (scala) [default], iteration 11 completed (6703.525 ms) ====== [2025-05-07T14:17:08.348Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-07T14:17:08.348Z] GC before operation: completed in 20.927 ms, heap usage 252.365 MB -> 4.731 MB. [2025-05-07T14:17:15.595Z] Camera thread performed 122 scans. [2025-05-07T14:17:15.595Z] ====== philosophers (scala) [default], iteration 12 completed (6684.175 ms) ====== [2025-05-07T14:17:15.595Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-07T14:17:15.595Z] GC before operation: completed in 21.363 ms, heap usage 153.874 MB -> 4.731 MB. [2025-05-07T14:17:21.302Z] Camera thread performed 122 scans. [2025-05-07T14:17:21.302Z] ====== philosophers (scala) [default], iteration 13 completed (6684.021 ms) ====== [2025-05-07T14:17:21.302Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-07T14:17:21.302Z] GC before operation: completed in 19.706 ms, heap usage 164.833 MB -> 4.731 MB. [2025-05-07T14:17:28.326Z] Camera thread performed 122 scans. [2025-05-07T14:17:28.326Z] ====== philosophers (scala) [default], iteration 14 completed (6527.599 ms) ====== [2025-05-07T14:17:28.326Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-07T14:17:28.326Z] GC before operation: completed in 19.224 ms, heap usage 26.451 MB -> 4.731 MB. [2025-05-07T14:17:35.285Z] Camera thread performed 122 scans. [2025-05-07T14:17:35.285Z] ====== philosophers (scala) [default], iteration 15 completed (6801.327 ms) ====== [2025-05-07T14:17:35.285Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-07T14:17:35.285Z] GC before operation: completed in 19.897 ms, heap usage 41.941 MB -> 4.731 MB. [2025-05-07T14:17:42.226Z] Camera thread performed 122 scans. [2025-05-07T14:17:42.226Z] ====== philosophers (scala) [default], iteration 16 completed (6747.296 ms) ====== [2025-05-07T14:17:42.226Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-07T14:17:42.226Z] GC before operation: completed in 21.087 ms, heap usage 229.843 MB -> 4.731 MB. [2025-05-07T14:17:47.896Z] Camera thread performed 122 scans. [2025-05-07T14:17:47.896Z] ====== philosophers (scala) [default], iteration 17 completed (6577.202 ms) ====== [2025-05-07T14:17:47.896Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-07T14:17:47.896Z] GC before operation: completed in 21.050 ms, heap usage 226.437 MB -> 4.731 MB. [2025-05-07T14:17:55.027Z] Camera thread performed 122 scans. [2025-05-07T14:17:55.027Z] ====== philosophers (scala) [default], iteration 18 completed (6445.203 ms) ====== [2025-05-07T14:17:55.027Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-07T14:17:55.027Z] GC before operation: completed in 20.574 ms, heap usage 169.439 MB -> 4.732 MB. [2025-05-07T14:18:01.971Z] Camera thread performed 122 scans. [2025-05-07T14:18:01.971Z] ====== philosophers (scala) [default], iteration 19 completed (6742.350 ms) ====== [2025-05-07T14:18:01.971Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-07T14:18:01.971Z] GC before operation: completed in 20.143 ms, heap usage 149.119 MB -> 4.731 MB. [2025-05-07T14:18:07.642Z] Camera thread performed 122 scans. [2025-05-07T14:18:07.642Z] ====== philosophers (scala) [default], iteration 20 completed (6330.141 ms) ====== [2025-05-07T14:18:07.642Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-07T14:18:07.642Z] GC before operation: completed in 21.041 ms, heap usage 193.790 MB -> 4.732 MB. [2025-05-07T14:18:14.583Z] Camera thread performed 122 scans. [2025-05-07T14:18:14.583Z] ====== philosophers (scala) [default], iteration 21 completed (6311.645 ms) ====== [2025-05-07T14:18:14.583Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-07T14:18:14.583Z] GC before operation: completed in 20.415 ms, heap usage 130.729 MB -> 4.731 MB. [2025-05-07T14:18:21.521Z] Camera thread performed 122 scans. [2025-05-07T14:18:21.521Z] ====== philosophers (scala) [default], iteration 22 completed (6946.621 ms) ====== [2025-05-07T14:18:21.521Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-07T14:18:21.521Z] GC before operation: completed in 23.466 ms, heap usage 97.828 MB -> 4.732 MB. [2025-05-07T14:18:28.465Z] Camera thread performed 122 scans. [2025-05-07T14:18:28.465Z] ====== philosophers (scala) [default], iteration 23 completed (6903.371 ms) ====== [2025-05-07T14:18:28.465Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-07T14:18:28.465Z] GC before operation: completed in 22.556 ms, heap usage 17.889 MB -> 4.732 MB. [2025-05-07T14:18:34.336Z] Camera thread performed 122 scans. [2025-05-07T14:18:34.336Z] ====== philosophers (scala) [default], iteration 24 completed (6698.576 ms) ====== [2025-05-07T14:18:34.336Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-07T14:18:34.336Z] GC before operation: completed in 21.362 ms, heap usage 189.824 MB -> 4.732 MB. [2025-05-07T14:18:41.277Z] Camera thread performed 122 scans. [2025-05-07T14:18:41.277Z] ====== philosophers (scala) [default], iteration 25 completed (6559.288 ms) ====== [2025-05-07T14:18:41.277Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-07T14:18:41.277Z] GC before operation: completed in 21.750 ms, heap usage 200.829 MB -> 4.731 MB. [2025-05-07T14:18:48.218Z] Camera thread performed 122 scans. [2025-05-07T14:18:48.218Z] ====== philosophers (scala) [default], iteration 26 completed (6694.142 ms) ====== [2025-05-07T14:18:48.218Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-07T14:18:48.218Z] GC before operation: completed in 23.170 ms, heap usage 172.184 MB -> 4.732 MB. [2025-05-07T14:18:55.161Z] Camera thread performed 122 scans. [2025-05-07T14:18:55.161Z] ====== philosophers (scala) [default], iteration 27 completed (6460.813 ms) ====== [2025-05-07T14:18:55.161Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-07T14:18:55.161Z] GC before operation: completed in 20.714 ms, heap usage 177.449 MB -> 4.732 MB. [2025-05-07T14:19:00.825Z] Camera thread performed 122 scans. [2025-05-07T14:19:00.825Z] ====== philosophers (scala) [default], iteration 28 completed (6529.365 ms) ====== [2025-05-07T14:19:00.825Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-07T14:19:00.825Z] GC before operation: completed in 22.603 ms, heap usage 227.450 MB -> 4.732 MB. [2025-05-07T14:19:07.782Z] Camera thread performed 122 scans. [2025-05-07T14:19:07.782Z] ====== philosophers (scala) [default], iteration 29 completed (6325.768 ms) ====== [2025-05-07T14:19:07.782Z] ----------------------------------- [2025-05-07T14:19:07.782Z] renaissance-philosophers_0_PASSED [2025-05-07T14:19:07.782Z] ----------------------------------- [2025-05-07T14:19:07.782Z] [2025-05-07T14:19:07.782Z] TEST TEARDOWN: [2025-05-07T14:19:07.782Z] Nothing to be done for teardown. [2025-05-07T14:19:07.782Z] renaissance-philosophers_0 Finish Time: Wed May 7 14:19:07 2025 Epoch Time (ms): 1746627547012