[2025-11-20T04:55:26.599Z] Running test renaissance-philosophers_0 ... [2025-11-20T04:55:26.599Z] =============================================== [2025-11-20T04:55:26.599Z] renaissance-philosophers_0 Start Time: Thu Nov 20 04:55:26 2025 Epoch Time (ms): 1763614526479 [2025-11-20T04:55:26.599Z] variation: NoOptions [2025-11-20T04:55:26.946Z] JVM_OPTIONS: [2025-11-20T04:55:26.946Z] { \ [2025-11-20T04:55:26.946Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T04:55:26.946Z] echo "Nothing to be done for setup."; \ [2025-11-20T04:55:26.946Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636134014875\\renaissance-philosophers_0"; \ [2025-11-20T04:55:26.946Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636134014875\\renaissance-philosophers_0"; \ [2025-11-20T04:55:26.946Z] echo ""; echo "TESTING:"; \ [2025-11-20T04:55:26.946Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636134014875\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-11-20T04:55:26.946Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636134014875\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T04:55:26.946Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T04:55:26.946Z] echo "Nothing to be done for teardown."; \ [2025-11-20T04:55:26.946Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17636134014875\\TestTargetResult"; [2025-11-20T04:55:26.946Z] [2025-11-20T04:55:26.946Z] TEST SETUP: [2025-11-20T04:55:26.946Z] Nothing to be done for setup. [2025-11-20T04:55:26.946Z] [2025-11-20T04:55:26.946Z] TESTING: [2025-11-20T04:55:28.100Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-20T04:55:28.100Z] GC before operation: completed in 9.323 ms, heap usage 4.250 MB -> 4.162 MB. [2025-11-20T04:55:31.891Z] Camera thread performed 122 scans. [2025-11-20T04:55:31.891Z] ====== philosophers (scala) [default], iteration 0 completed (3391.216 ms) ====== [2025-11-20T04:55:31.891Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-20T04:55:31.891Z] GC before operation: completed in 13.156 ms, heap usage 8.133 MB -> 4.730 MB. [2025-11-20T04:55:34.848Z] Camera thread performed 122 scans. [2025-11-20T04:55:34.848Z] ====== philosophers (scala) [default], iteration 1 completed (3121.031 ms) ====== [2025-11-20T04:55:34.848Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-20T04:55:34.848Z] GC before operation: completed in 10.309 ms, heap usage 5.978 MB -> 4.908 MB. [2025-11-20T04:55:37.811Z] Camera thread performed 122 scans. [2025-11-20T04:55:37.811Z] ====== philosophers (scala) [default], iteration 2 completed (3103.732 ms) ====== [2025-11-20T04:55:37.811Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-20T04:55:37.811Z] GC before operation: completed in 14.283 ms, heap usage 8.671 MB -> 4.440 MB. [2025-11-20T04:55:40.882Z] Camera thread performed 122 scans. [2025-11-20T04:55:40.882Z] ====== philosophers (scala) [default], iteration 3 completed (2992.350 ms) ====== [2025-11-20T04:55:40.882Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-20T04:55:40.882Z] GC before operation: completed in 11.932 ms, heap usage 6.071 MB -> 4.417 MB. [2025-11-20T04:55:43.841Z] Camera thread performed 122 scans. [2025-11-20T04:55:43.841Z] ====== philosophers (scala) [default], iteration 4 completed (3110.432 ms) ====== [2025-11-20T04:55:43.841Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-20T04:55:43.841Z] GC before operation: completed in 11.528 ms, heap usage 5.492 MB -> 3.878 MB. [2025-11-20T04:55:47.602Z] Camera thread performed 122 scans. [2025-11-20T04:55:47.602Z] ====== philosophers (scala) [default], iteration 5 completed (3125.225 ms) ====== [2025-11-20T04:55:47.602Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-20T04:55:47.602Z] GC before operation: completed in 9.711 ms, heap usage 7.219 MB -> 3.879 MB. [2025-11-20T04:55:49.870Z] Camera thread performed 122 scans. [2025-11-20T04:55:49.870Z] ====== philosophers (scala) [default], iteration 6 completed (2962.004 ms) ====== [2025-11-20T04:55:49.870Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-20T04:55:49.870Z] GC before operation: completed in 10.477 ms, heap usage 8.074 MB -> 3.347 MB. [2025-11-20T04:55:53.634Z] Camera thread performed 122 scans. [2025-11-20T04:55:53.634Z] ====== philosophers (scala) [default], iteration 7 completed (3241.888 ms) ====== [2025-11-20T04:55:53.634Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-20T04:55:53.634Z] GC before operation: completed in 9.206 ms, heap usage 7.087 MB -> 3.522 MB. [2025-11-20T04:55:56.604Z] Camera thread performed 122 scans. [2025-11-20T04:55:56.604Z] ====== philosophers (scala) [default], iteration 8 completed (2921.135 ms) ====== [2025-11-20T04:55:56.604Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-20T04:55:56.604Z] GC before operation: completed in 9.390 ms, heap usage 7.154 MB -> 3.698 MB. [2025-11-20T04:55:59.566Z] Camera thread performed 122 scans. [2025-11-20T04:55:59.566Z] ====== philosophers (scala) [default], iteration 9 completed (3055.576 ms) ====== [2025-11-20T04:55:59.566Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-20T04:55:59.566Z] GC before operation: completed in 9.390 ms, heap usage 4.485 MB -> 3.874 MB. [2025-11-20T04:56:02.522Z] Camera thread performed 122 scans. [2025-11-20T04:56:02.522Z] ====== philosophers (scala) [default], iteration 10 completed (3186.382 ms) ====== [2025-11-20T04:56:02.522Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-20T04:56:02.522Z] GC before operation: completed in 9.802 ms, heap usage 8.240 MB -> 3.347 MB. [2025-11-20T04:56:05.476Z] Camera thread performed 122 scans. [2025-11-20T04:56:05.476Z] ====== philosophers (scala) [default], iteration 11 completed (3153.204 ms) ====== [2025-11-20T04:56:05.476Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-20T04:56:05.476Z] GC before operation: completed in 8.927 ms, heap usage 5.622 MB -> 3.524 MB. [2025-11-20T04:56:09.231Z] Camera thread performed 122 scans. [2025-11-20T04:56:09.231Z] ====== philosophers (scala) [default], iteration 12 completed (3077.556 ms) ====== [2025-11-20T04:56:09.231Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-20T04:56:09.231Z] GC before operation: completed in 9.410 ms, heap usage 7.281 MB -> 3.700 MB. [2025-11-20T04:56:12.214Z] Camera thread performed 122 scans. [2025-11-20T04:56:12.214Z] ====== philosophers (scala) [default], iteration 13 completed (3174.389 ms) ====== [2025-11-20T04:56:12.214Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-20T04:56:12.214Z] GC before operation: completed in 9.141 ms, heap usage 7.054 MB -> 3.876 MB. [2025-11-20T04:56:15.150Z] Camera thread performed 122 scans. [2025-11-20T04:56:15.150Z] ====== philosophers (scala) [default], iteration 14 completed (3191.993 ms) ====== [2025-11-20T04:56:15.150Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-20T04:56:15.150Z] GC before operation: completed in 8.915 ms, heap usage 6.052 MB -> 3.347 MB. [2025-11-20T04:56:18.110Z] Camera thread performed 122 scans. [2025-11-20T04:56:18.110Z] ====== philosophers (scala) [default], iteration 15 completed (3165.651 ms) ====== [2025-11-20T04:56:18.110Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-20T04:56:18.110Z] GC before operation: completed in 9.617 ms, heap usage 6.441 MB -> 3.524 MB. [2025-11-20T04:56:21.887Z] Camera thread performed 122 scans. [2025-11-20T04:56:21.887Z] ====== philosophers (scala) [default], iteration 16 completed (3302.677 ms) ====== [2025-11-20T04:56:21.887Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-20T04:56:21.887Z] GC before operation: completed in 8.890 ms, heap usage 4.573 MB -> 3.701 MB. [2025-11-20T04:56:24.828Z] Camera thread performed 122 scans. [2025-11-20T04:56:24.828Z] ====== philosophers (scala) [default], iteration 17 completed (2820.553 ms) ====== [2025-11-20T04:56:24.828Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-20T04:56:24.828Z] GC before operation: completed in 8.945 ms, heap usage 5.104 MB -> 3.876 MB. [2025-11-20T04:56:27.767Z] Camera thread performed 122 scans. [2025-11-20T04:56:27.767Z] ====== philosophers (scala) [default], iteration 18 completed (2905.756 ms) ====== [2025-11-20T04:56:27.767Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-20T04:56:27.767Z] GC before operation: completed in 9.465 ms, heap usage 7.556 MB -> 3.348 MB. [2025-11-20T04:56:30.705Z] Camera thread performed 122 scans. [2025-11-20T04:56:30.705Z] ====== philosophers (scala) [default], iteration 19 completed (3169.725 ms) ====== [2025-11-20T04:56:30.705Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-20T04:56:30.705Z] GC before operation: completed in 9.542 ms, heap usage 7.776 MB -> 3.524 MB. [2025-11-20T04:56:33.658Z] Camera thread performed 122 scans. [2025-11-20T04:56:33.658Z] ====== philosophers (scala) [default], iteration 20 completed (3212.045 ms) ====== [2025-11-20T04:56:33.658Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-20T04:56:33.658Z] GC before operation: completed in 9.168 ms, heap usage 6.414 MB -> 3.701 MB. [2025-11-20T04:56:37.430Z] Camera thread performed 122 scans. [2025-11-20T04:56:37.430Z] ====== philosophers (scala) [default], iteration 21 completed (3166.741 ms) ====== [2025-11-20T04:56:37.430Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-20T04:56:37.430Z] GC before operation: completed in 9.216 ms, heap usage 7.284 MB -> 3.877 MB. [2025-11-20T04:56:40.387Z] Camera thread performed 122 scans. [2025-11-20T04:56:40.387Z] ====== philosophers (scala) [default], iteration 22 completed (3235.821 ms) ====== [2025-11-20T04:56:40.387Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-20T04:56:40.387Z] GC before operation: completed in 9.503 ms, heap usage 8.157 MB -> 3.348 MB. [2025-11-20T04:56:43.370Z] Camera thread performed 122 scans. [2025-11-20T04:56:43.370Z] ====== philosophers (scala) [default], iteration 23 completed (3038.814 ms) ====== [2025-11-20T04:56:43.370Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-20T04:56:43.370Z] GC before operation: completed in 9.984 ms, heap usage 7.887 MB -> 3.524 MB. [2025-11-20T04:56:46.354Z] Camera thread performed 122 scans. [2025-11-20T04:56:46.354Z] ====== philosophers (scala) [default], iteration 24 completed (3054.506 ms) ====== [2025-11-20T04:56:46.354Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-20T04:56:46.354Z] GC before operation: completed in 9.571 ms, heap usage 6.935 MB -> 3.701 MB. [2025-11-20T04:56:49.303Z] Camera thread performed 122 scans. [2025-11-20T04:56:49.303Z] ====== philosophers (scala) [default], iteration 25 completed (2853.580 ms) ====== [2025-11-20T04:56:49.303Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-20T04:56:49.303Z] GC before operation: completed in 8.841 ms, heap usage 4.468 MB -> 3.877 MB. [2025-11-20T04:56:52.252Z] Camera thread performed 122 scans. [2025-11-20T04:56:52.252Z] ====== philosophers (scala) [default], iteration 26 completed (3072.092 ms) ====== [2025-11-20T04:56:52.252Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-20T04:56:52.252Z] GC before operation: completed in 8.596 ms, heap usage 4.430 MB -> 3.348 MB. [2025-11-20T04:56:55.216Z] Camera thread performed 122 scans. [2025-11-20T04:56:55.216Z] ====== philosophers (scala) [default], iteration 27 completed (3152.014 ms) ====== [2025-11-20T04:56:55.216Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-20T04:56:55.216Z] GC before operation: completed in 9.181 ms, heap usage 6.291 MB -> 3.524 MB. [2025-11-20T04:56:58.993Z] Camera thread performed 122 scans. [2025-11-20T04:56:58.993Z] ====== philosophers (scala) [default], iteration 28 completed (3203.180 ms) ====== [2025-11-20T04:56:58.993Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-20T04:56:58.993Z] GC before operation: completed in 9.068 ms, heap usage 4.990 MB -> 3.701 MB. [2025-11-20T04:57:01.949Z] Camera thread performed 122 scans. [2025-11-20T04:57:01.949Z] ====== philosophers (scala) [default], iteration 29 completed (3206.410 ms) ====== [2025-11-20T04:57:01.950Z] ----------------------------------- [2025-11-20T04:57:01.950Z] renaissance-philosophers_0_PASSED [2025-11-20T04:57:01.950Z] ----------------------------------- [2025-11-20T04:57:01.950Z] [2025-11-20T04:57:01.950Z] TEST TEARDOWN: [2025-11-20T04:57:01.950Z] Nothing to be done for teardown. [2025-11-20T04:57:01.950Z] renaissance-philosophers_0 Finish Time: Thu Nov 20 04:57:01 2025 Epoch Time (ms): 1763614621804