[2025-11-05T23:48:16.107Z] Running test renaissance-philosophers_0 ... [2025-11-05T23:48:16.464Z] =============================================== [2025-11-05T23:48:16.464Z] renaissance-philosophers_0 Start Time: Wed Nov 5 23:48:16 2025 Epoch Time (ms): 1762386496195 [2025-11-05T23:48:16.464Z] variation: NoOptions [2025-11-05T23:48:16.464Z] JVM_OPTIONS: [2025-11-05T23:48:16.464Z] { \ [2025-11-05T23:48:16.464Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T23:48:16.464Z] echo "Nothing to be done for setup."; \ [2025-11-05T23:48:16.464Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-philosophers_0"; \ [2025-11-05T23:48:16.464Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-philosophers_0"; \ [2025-11-05T23:48:16.464Z] echo ""; echo "TESTING:"; \ [2025-11-05T23:48:16.464Z] "c:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-11-05T23:48:16.465Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T23:48:16.465Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T23:48:16.465Z] echo "Nothing to be done for teardown."; \ [2025-11-05T23:48:16.465Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\TestTargetResult"; [2025-11-05T23:48:16.465Z] [2025-11-05T23:48:16.465Z] TEST SETUP: [2025-11-05T23:48:16.465Z] Nothing to be done for setup. [2025-11-05T23:48:16.465Z] [2025-11-05T23:48:16.465Z] TESTING: [2025-11-05T23:48:17.647Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-05T23:48:17.647Z] GC before operation: completed in 10.660 ms, heap usage 15.969 MB -> 4.584 MB. [2025-11-05T23:48:19.421Z] Camera thread performed 122 scans. [2025-11-05T23:48:19.421Z] ====== philosophers (scala) [default], iteration 0 completed (1835.950 ms) ====== [2025-11-05T23:48:19.421Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-05T23:48:19.421Z] GC before operation: completed in 12.783 ms, heap usage 71.785 MB -> 4.960 MB. [2025-11-05T23:48:21.142Z] Camera thread performed 122 scans. [2025-11-05T23:48:21.142Z] ====== philosophers (scala) [default], iteration 1 completed (1469.013 ms) ====== [2025-11-05T23:48:21.142Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-05T23:48:21.142Z] GC before operation: completed in 10.573 ms, heap usage 65.569 MB -> 4.960 MB. [2025-11-05T23:48:22.394Z] Camera thread performed 122 scans. [2025-11-05T23:48:22.394Z] ====== philosophers (scala) [default], iteration 2 completed (1491.752 ms) ====== [2025-11-05T23:48:22.394Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-05T23:48:22.394Z] GC before operation: completed in 10.064 ms, heap usage 56.338 MB -> 4.961 MB. [2025-11-05T23:48:24.121Z] Camera thread performed 122 scans. [2025-11-05T23:48:24.121Z] ====== philosophers (scala) [default], iteration 3 completed (1394.529 ms) ====== [2025-11-05T23:48:24.121Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-05T23:48:24.121Z] GC before operation: completed in 9.546 ms, heap usage 42.154 MB -> 4.961 MB. [2025-11-05T23:48:25.320Z] Camera thread performed 122 scans. [2025-11-05T23:48:25.320Z] ====== philosophers (scala) [default], iteration 4 completed (1488.272 ms) ====== [2025-11-05T23:48:25.320Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-05T23:48:25.320Z] GC before operation: completed in 9.351 ms, heap usage 19.898 MB -> 4.961 MB. [2025-11-05T23:48:27.023Z] Camera thread performed 122 scans. [2025-11-05T23:48:27.023Z] ====== philosophers (scala) [default], iteration 5 completed (1481.214 ms) ====== [2025-11-05T23:48:27.023Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-05T23:48:27.023Z] GC before operation: completed in 12.773 ms, heap usage 18.516 MB -> 4.965 MB. [2025-11-05T23:48:28.222Z] Camera thread performed 122 scans. [2025-11-05T23:48:28.222Z] ====== philosophers (scala) [default], iteration 6 completed (1386.703 ms) ====== [2025-11-05T23:48:28.222Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-05T23:48:28.222Z] GC before operation: completed in 9.462 ms, heap usage 14.041 MB -> 4.967 MB. [2025-11-05T23:48:29.919Z] Camera thread performed 122 scans. [2025-11-05T23:48:29.919Z] ====== philosophers (scala) [default], iteration 7 completed (1480.008 ms) ====== [2025-11-05T23:48:29.919Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-05T23:48:29.919Z] GC before operation: completed in 10.011 ms, heap usage 30.579 MB -> 4.967 MB. [2025-11-05T23:48:31.637Z] Camera thread performed 122 scans. [2025-11-05T23:48:31.637Z] ====== philosophers (scala) [default], iteration 8 completed (1490.749 ms) ====== [2025-11-05T23:48:31.637Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-05T23:48:31.637Z] GC before operation: completed in 10.313 ms, heap usage 62.183 MB -> 4.967 MB. [2025-11-05T23:48:32.834Z] Camera thread performed 122 scans. [2025-11-05T23:48:32.834Z] ====== philosophers (scala) [default], iteration 9 completed (1494.130 ms) ====== [2025-11-05T23:48:32.834Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-05T23:48:32.834Z] GC before operation: completed in 9.898 ms, heap usage 29.826 MB -> 4.967 MB. [2025-11-05T23:48:34.550Z] Camera thread performed 122 scans. [2025-11-05T23:48:34.550Z] ====== philosophers (scala) [default], iteration 10 completed (1470.336 ms) ====== [2025-11-05T23:48:34.550Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-05T23:48:34.550Z] GC before operation: completed in 10.333 ms, heap usage 61.825 MB -> 4.967 MB. [2025-11-05T23:48:35.743Z] Camera thread performed 122 scans. [2025-11-05T23:48:35.743Z] ====== philosophers (scala) [default], iteration 11 completed (1450.026 ms) ====== [2025-11-05T23:48:35.743Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-05T23:48:35.743Z] GC before operation: completed in 10.052 ms, heap usage 69.101 MB -> 4.968 MB. [2025-11-05T23:48:36.939Z] Camera thread performed 122 scans. [2025-11-05T23:48:36.939Z] ====== philosophers (scala) [default], iteration 12 completed (1337.040 ms) ====== [2025-11-05T23:48:36.939Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-05T23:48:37.301Z] GC before operation: completed in 10.262 ms, heap usage 44.982 MB -> 4.968 MB. [2025-11-05T23:48:38.506Z] Camera thread performed 122 scans. [2025-11-05T23:48:38.506Z] ====== philosophers (scala) [default], iteration 13 completed (1419.453 ms) ====== [2025-11-05T23:48:38.506Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-05T23:48:38.506Z] GC before operation: completed in 10.228 ms, heap usage 28.176 MB -> 4.968 MB. [2025-11-05T23:48:40.220Z] Camera thread performed 122 scans. [2025-11-05T23:48:40.220Z] ====== philosophers (scala) [default], iteration 14 completed (1498.986 ms) ====== [2025-11-05T23:48:40.220Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-05T23:48:40.220Z] GC before operation: completed in 9.676 ms, heap usage 8.971 MB -> 4.968 MB. [2025-11-05T23:48:41.412Z] Camera thread performed 122 scans. [2025-11-05T23:48:41.412Z] ====== philosophers (scala) [default], iteration 15 completed (1526.431 ms) ====== [2025-11-05T23:48:41.412Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-05T23:48:41.769Z] GC before operation: completed in 9.629 ms, heap usage 52.143 MB -> 4.968 MB. [2025-11-05T23:48:42.972Z] Camera thread performed 122 scans. [2025-11-05T23:48:42.972Z] ====== philosophers (scala) [default], iteration 16 completed (1477.386 ms) ====== [2025-11-05T23:48:42.972Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-05T23:48:42.972Z] GC before operation: completed in 9.963 ms, heap usage 35.499 MB -> 4.968 MB. [2025-11-05T23:48:44.696Z] Camera thread performed 122 scans. [2025-11-05T23:48:44.696Z] ====== philosophers (scala) [default], iteration 17 completed (1484.667 ms) ====== [2025-11-05T23:48:44.696Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-05T23:48:44.696Z] GC before operation: completed in 10.172 ms, heap usage 44.503 MB -> 4.968 MB. [2025-11-05T23:48:46.001Z] Camera thread performed 122 scans. [2025-11-05T23:48:46.001Z] ====== philosophers (scala) [default], iteration 18 completed (1448.024 ms) ====== [2025-11-05T23:48:46.001Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-05T23:48:46.001Z] GC before operation: completed in 9.742 ms, heap usage 22.174 MB -> 4.968 MB. [2025-11-05T23:48:47.720Z] Camera thread performed 122 scans. [2025-11-05T23:48:47.720Z] ====== philosophers (scala) [default], iteration 19 completed (1478.494 ms) ====== [2025-11-05T23:48:47.720Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-05T23:48:47.720Z] GC before operation: completed in 10.413 ms, heap usage 64.989 MB -> 4.968 MB. [2025-11-05T23:48:48.916Z] Camera thread performed 122 scans. [2025-11-05T23:48:48.916Z] ====== philosophers (scala) [default], iteration 20 completed (1392.555 ms) ====== [2025-11-05T23:48:48.916Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-05T23:48:48.916Z] GC before operation: completed in 11.224 ms, heap usage 47.450 MB -> 4.969 MB. [2025-11-05T23:48:50.113Z] Camera thread performed 122 scans. [2025-11-05T23:48:50.113Z] ====== philosophers (scala) [default], iteration 21 completed (1322.600 ms) ====== [2025-11-05T23:48:50.113Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-05T23:48:50.113Z] GC before operation: completed in 11.428 ms, heap usage 18.456 MB -> 4.969 MB. [2025-11-05T23:48:51.834Z] Camera thread performed 122 scans. [2025-11-05T23:48:51.834Z] ====== philosophers (scala) [default], iteration 22 completed (1410.147 ms) ====== [2025-11-05T23:48:51.834Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-05T23:48:51.834Z] GC before operation: completed in 10.560 ms, heap usage 81.950 MB -> 4.969 MB. [2025-11-05T23:48:53.032Z] Camera thread performed 122 scans. [2025-11-05T23:48:53.032Z] ====== philosophers (scala) [default], iteration 23 completed (1479.919 ms) ====== [2025-11-05T23:48:53.032Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-05T23:48:53.032Z] GC before operation: completed in 10.196 ms, heap usage 43.115 MB -> 4.969 MB. [2025-11-05T23:48:54.752Z] Camera thread performed 122 scans. [2025-11-05T23:48:54.752Z] ====== philosophers (scala) [default], iteration 24 completed (1492.231 ms) ====== [2025-11-05T23:48:54.752Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-05T23:48:54.752Z] GC before operation: completed in 9.676 ms, heap usage 21.185 MB -> 4.969 MB. [2025-11-05T23:48:56.477Z] Camera thread performed 122 scans. [2025-11-05T23:48:56.477Z] ====== philosophers (scala) [default], iteration 25 completed (1477.204 ms) ====== [2025-11-05T23:48:56.477Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-05T23:48:56.477Z] GC before operation: completed in 9.844 ms, heap usage 32.817 MB -> 4.969 MB. [2025-11-05T23:48:57.694Z] Camera thread performed 122 scans. [2025-11-05T23:48:57.694Z] ====== philosophers (scala) [default], iteration 26 completed (1483.872 ms) ====== [2025-11-05T23:48:57.694Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-05T23:48:57.694Z] GC before operation: completed in 9.653 ms, heap usage 58.944 MB -> 4.969 MB. [2025-11-05T23:48:59.418Z] Camera thread performed 122 scans. [2025-11-05T23:48:59.418Z] ====== philosophers (scala) [default], iteration 27 completed (1430.603 ms) ====== [2025-11-05T23:48:59.418Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-05T23:48:59.418Z] GC before operation: completed in 10.441 ms, heap usage 82.161 MB -> 4.969 MB. [2025-11-05T23:49:00.619Z] Camera thread performed 122 scans. [2025-11-05T23:49:00.619Z] ====== philosophers (scala) [default], iteration 28 completed (1499.704 ms) ====== [2025-11-05T23:49:00.619Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-05T23:49:00.619Z] GC before operation: completed in 10.435 ms, heap usage 46.144 MB -> 4.970 MB. [2025-11-05T23:49:02.323Z] Camera thread performed 122 scans. [2025-11-05T23:49:02.323Z] ====== philosophers (scala) [default], iteration 29 completed (1385.600 ms) ====== [2025-11-05T23:49:02.323Z] ----------------------------------- [2025-11-05T23:49:02.323Z] renaissance-philosophers_0_PASSED [2025-11-05T23:49:02.323Z] ----------------------------------- [2025-11-05T23:49:02.323Z] [2025-11-05T23:49:02.323Z] TEST TEARDOWN: [2025-11-05T23:49:02.323Z] Nothing to be done for teardown. [2025-11-05T23:49:02.323Z] renaissance-philosophers_0 Finish Time: Wed Nov 5 23:49:02 2025 Epoch Time (ms): 1762386542073