[2025-05-08T03:29:05.668Z] Running test renaissance-philosophers_0 ... [2025-05-08T03:29:05.668Z] =============================================== [2025-05-08T03:29:05.668Z] renaissance-philosophers_0 Start Time: Thu May 8 03:29:05 2025 Epoch Time (ms): 1746674945531 [2025-05-08T03:29:05.994Z] variation: NoOptions [2025-05-08T03:29:05.994Z] JVM_OPTIONS: [2025-05-08T03:29:05.994Z] { \ [2025-05-08T03:29:05.994Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T03:29:05.994Z] echo "Nothing to be done for setup."; \ [2025-05-08T03:29:05.994Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17466744811015\\renaissance-philosophers_0"; \ [2025-05-08T03:29:05.994Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17466744811015\\renaissance-philosophers_0"; \ [2025-05-08T03:29:05.994Z] echo ""; echo "TESTING:"; \ [2025-05-08T03:29:05.994Z] "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_17466744811015\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-05-08T03:29:05.994Z] 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_17466744811015\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T03:29:05.994Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T03:29:05.994Z] echo "Nothing to be done for teardown."; \ [2025-05-08T03:29:05.994Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17466744811015\\TestTargetResult"; [2025-05-08T03:29:05.994Z] [2025-05-08T03:29:05.994Z] TEST SETUP: [2025-05-08T03:29:05.994Z] Nothing to be done for setup. [2025-05-08T03:29:05.994Z] [2025-05-08T03:29:05.994Z] TESTING: [2025-05-08T03:29:07.100Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-08T03:29:07.100Z] GC before operation: completed in 9.389 ms, heap usage 4.246 MB -> 4.163 MB. [2025-05-08T03:29:10.752Z] Camera thread performed 122 scans. [2025-05-08T03:29:10.752Z] ====== philosophers (scala) [default], iteration 0 completed (3457.667 ms) ====== [2025-05-08T03:29:10.752Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-08T03:29:10.752Z] GC before operation: completed in 12.421 ms, heap usage 7.450 MB -> 4.731 MB. [2025-05-08T03:29:13.601Z] Camera thread performed 122 scans. [2025-05-08T03:29:13.601Z] ====== philosophers (scala) [default], iteration 1 completed (3009.371 ms) ====== [2025-05-08T03:29:13.601Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-08T03:29:13.601Z] GC before operation: completed in 10.426 ms, heap usage 6.389 MB -> 4.907 MB. [2025-05-08T03:29:17.257Z] Camera thread performed 122 scans. [2025-05-08T03:29:17.257Z] ====== philosophers (scala) [default], iteration 2 completed (3052.996 ms) ====== [2025-05-08T03:29:17.257Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-08T03:29:17.257Z] GC before operation: completed in 13.615 ms, heap usage 8.993 MB -> 4.447 MB. [2025-05-08T03:29:20.109Z] Camera thread performed 122 scans. [2025-05-08T03:29:20.109Z] ====== philosophers (scala) [default], iteration 3 completed (3189.995 ms) ====== [2025-05-08T03:29:20.109Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-08T03:29:20.109Z] GC before operation: completed in 12.861 ms, heap usage 6.651 MB -> 4.418 MB. [2025-05-08T03:29:22.974Z] Camera thread performed 122 scans. [2025-05-08T03:29:22.974Z] ====== philosophers (scala) [default], iteration 4 completed (3013.006 ms) ====== [2025-05-08T03:29:22.974Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-08T03:29:22.974Z] GC before operation: completed in 10.760 ms, heap usage 5.081 MB -> 3.878 MB. [2025-05-08T03:29:26.648Z] Camera thread performed 122 scans. [2025-05-08T03:29:26.648Z] ====== philosophers (scala) [default], iteration 5 completed (3155.975 ms) ====== [2025-05-08T03:29:26.648Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-08T03:29:26.648Z] GC before operation: completed in 8.685 ms, heap usage 5.841 MB -> 3.881 MB. [2025-05-08T03:29:29.515Z] Camera thread performed 122 scans. [2025-05-08T03:29:29.515Z] ====== philosophers (scala) [default], iteration 6 completed (3052.967 ms) ====== [2025-05-08T03:29:29.515Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-08T03:29:29.515Z] GC before operation: completed in 10.005 ms, heap usage 6.674 MB -> 3.347 MB. [2025-05-08T03:29:31.721Z] Camera thread performed 122 scans. [2025-05-08T03:29:31.721Z] ====== philosophers (scala) [default], iteration 7 completed (2728.205 ms) ====== [2025-05-08T03:29:31.721Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-08T03:29:31.721Z] GC before operation: completed in 8.798 ms, heap usage 6.819 MB -> 3.523 MB. [2025-05-08T03:29:35.394Z] Camera thread performed 122 scans. [2025-05-08T03:29:35.394Z] ====== philosophers (scala) [default], iteration 8 completed (2962.365 ms) ====== [2025-05-08T03:29:35.394Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-08T03:29:35.394Z] GC before operation: completed in 8.965 ms, heap usage 6.908 MB -> 3.698 MB. [2025-05-08T03:29:38.349Z] Camera thread performed 122 scans. [2025-05-08T03:29:38.349Z] ====== philosophers (scala) [default], iteration 9 completed (3079.868 ms) ====== [2025-05-08T03:29:38.349Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-08T03:29:38.349Z] GC before operation: completed in 8.641 ms, heap usage 5.319 MB -> 3.875 MB. [2025-05-08T03:29:44.846Z] Camera thread performed 122 scans. [2025-05-08T03:29:44.847Z] ====== philosophers (scala) [default], iteration 10 completed (3127.752 ms) ====== [2025-05-08T03:29:44.847Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-08T03:29:44.847Z] GC before operation: completed in 8.971 ms, heap usage 4.492 MB -> 3.348 MB. [2025-05-08T03:29:44.847Z] Camera thread performed 122 scans. [2025-05-08T03:29:44.847Z] ====== philosophers (scala) [default], iteration 11 completed (3022.764 ms) ====== [2025-05-08T03:29:44.847Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-08T03:29:44.847Z] GC before operation: completed in 8.501 ms, heap usage 4.582 MB -> 3.524 MB. [2025-05-08T03:29:49.035Z] Camera thread performed 122 scans. [2025-05-08T03:29:49.035Z] ====== philosophers (scala) [default], iteration 12 completed (3063.001 ms) ====== [2025-05-08T03:29:49.035Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-08T03:29:49.035Z] GC before operation: completed in 8.798 ms, heap usage 6.783 MB -> 3.701 MB. [2025-05-08T03:29:52.065Z] Camera thread performed 122 scans. [2025-05-08T03:29:52.065Z] ====== philosophers (scala) [default], iteration 13 completed (3121.507 ms) ====== [2025-05-08T03:29:52.065Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-08T03:29:52.065Z] GC before operation: completed in 9.044 ms, heap usage 8.091 MB -> 3.876 MB. [2025-05-08T03:29:54.770Z] Camera thread performed 122 scans. [2025-05-08T03:29:54.770Z] ====== philosophers (scala) [default], iteration 14 completed (2865.435 ms) ====== [2025-05-08T03:29:54.770Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-08T03:29:54.770Z] GC before operation: completed in 8.909 ms, heap usage 7.447 MB -> 3.348 MB. [2025-05-08T03:29:58.393Z] Camera thread performed 122 scans. [2025-05-08T03:29:58.393Z] ====== philosophers (scala) [default], iteration 15 completed (3007.459 ms) ====== [2025-05-08T03:29:58.393Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-08T03:29:58.393Z] GC before operation: completed in 8.535 ms, heap usage 3.796 MB -> 3.525 MB. [2025-05-08T03:29:59.426Z] Camera thread performed 122 scans. [2025-05-08T03:29:59.426Z] ====== philosophers (scala) [default], iteration 16 completed (3183.345 ms) ====== [2025-05-08T03:29:59.426Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-08T03:29:59.426Z] GC before operation: completed in 8.586 ms, heap usage 6.481 MB -> 3.700 MB. [2025-05-08T03:30:02.734Z] Camera thread performed 122 scans. [2025-05-08T03:30:02.734Z] ====== philosophers (scala) [default], iteration 17 completed (3010.450 ms) ====== [2025-05-08T03:30:02.734Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-08T03:30:02.734Z] GC before operation: completed in 8.556 ms, heap usage 5.228 MB -> 3.876 MB. [2025-05-08T03:30:05.603Z] Camera thread performed 122 scans. [2025-05-08T03:30:05.603Z] ====== philosophers (scala) [default], iteration 18 completed (3145.806 ms) ====== [2025-05-08T03:30:05.603Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-08T03:30:05.603Z] GC before operation: completed in 8.601 ms, heap usage 5.570 MB -> 3.348 MB. [2025-05-08T03:30:08.473Z] Camera thread performed 122 scans. [2025-05-08T03:30:08.473Z] ====== philosophers (scala) [default], iteration 19 completed (3062.439 ms) ====== [2025-05-08T03:30:08.473Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-08T03:30:08.473Z] GC before operation: completed in 8.604 ms, heap usage 6.072 MB -> 3.526 MB. [2025-05-08T03:30:11.367Z] Camera thread performed 122 scans. [2025-05-08T03:30:11.367Z] ====== philosophers (scala) [default], iteration 20 completed (2924.287 ms) ====== [2025-05-08T03:30:11.367Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-08T03:30:11.693Z] GC before operation: completed in 8.582 ms, heap usage 4.306 MB -> 3.701 MB. [2025-05-08T03:30:14.561Z] Camera thread performed 122 scans. [2025-05-08T03:30:14.561Z] ====== philosophers (scala) [default], iteration 21 completed (3001.985 ms) ====== [2025-05-08T03:30:14.561Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-08T03:30:14.561Z] GC before operation: completed in 8.687 ms, heap usage 6.370 MB -> 3.876 MB. [2025-05-08T03:30:18.233Z] Camera thread performed 122 scans. [2025-05-08T03:30:18.233Z] ====== philosophers (scala) [default], iteration 22 completed (3054.051 ms) ====== [2025-05-08T03:30:18.233Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-08T03:30:18.233Z] GC before operation: completed in 9.015 ms, heap usage 7.976 MB -> 3.349 MB. [2025-05-08T03:30:21.106Z] Camera thread performed 122 scans. [2025-05-08T03:30:21.106Z] ====== philosophers (scala) [default], iteration 23 completed (3070.003 ms) ====== [2025-05-08T03:30:21.106Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-08T03:30:21.106Z] GC before operation: completed in 8.765 ms, heap usage 6.679 MB -> 3.525 MB. [2025-05-08T03:30:23.293Z] Camera thread performed 122 scans. [2025-05-08T03:30:23.293Z] ====== philosophers (scala) [default], iteration 24 completed (2660.634 ms) ====== [2025-05-08T03:30:23.293Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-08T03:30:23.293Z] GC before operation: completed in 8.974 ms, heap usage 7.595 MB -> 3.701 MB. [2025-05-08T03:30:26.184Z] Camera thread performed 122 scans. [2025-05-08T03:30:26.184Z] ====== philosophers (scala) [default], iteration 25 completed (2922.709 ms) ====== [2025-05-08T03:30:26.184Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-08T03:30:26.184Z] GC before operation: completed in 8.513 ms, heap usage 6.324 MB -> 3.876 MB. [2025-05-08T03:30:29.865Z] Camera thread performed 122 scans. [2025-05-08T03:30:29.865Z] ====== philosophers (scala) [default], iteration 26 completed (2943.134 ms) ====== [2025-05-08T03:30:29.865Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-08T03:30:29.865Z] GC before operation: completed in 8.785 ms, heap usage 6.713 MB -> 3.349 MB. [2025-05-08T03:30:32.735Z] Camera thread performed 122 scans. [2025-05-08T03:30:32.735Z] ====== philosophers (scala) [default], iteration 27 completed (3000.144 ms) ====== [2025-05-08T03:30:32.735Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-08T03:30:32.735Z] GC before operation: completed in 9.012 ms, heap usage 6.789 MB -> 3.525 MB. [2025-05-08T03:30:34.924Z] Camera thread performed 122 scans. [2025-05-08T03:30:34.924Z] ====== philosophers (scala) [default], iteration 28 completed (2770.846 ms) ====== [2025-05-08T03:30:34.924Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-08T03:30:34.924Z] GC before operation: completed in 8.661 ms, heap usage 5.658 MB -> 3.701 MB. [2025-05-08T03:30:38.580Z] Camera thread performed 122 scans. [2025-05-08T03:30:38.580Z] ====== philosophers (scala) [default], iteration 29 completed (2952.754 ms) ====== [2025-05-08T03:30:38.580Z] ----------------------------------- [2025-05-08T03:30:38.580Z] renaissance-philosophers_0_PASSED [2025-05-08T03:30:38.580Z] ----------------------------------- [2025-05-08T03:30:38.580Z] [2025-05-08T03:30:38.580Z] TEST TEARDOWN: [2025-05-08T03:30:38.580Z] Nothing to be done for teardown. [2025-05-08T03:30:38.580Z] renaissance-philosophers_0 Finish Time: Thu May 8 03:30:38 2025 Epoch Time (ms): 1746675038053