[2025-06-12T14:00:43.293Z] Running test renaissance-philosophers_0 ... [2025-06-12T14:00:43.293Z] =============================================== [2025-06-12T14:00:43.618Z] renaissance-philosophers_0 Start Time: Thu Jun 12 14:00:43 2025 Epoch Time (ms): 1749736843338 [2025-06-12T14:00:43.618Z] variation: NoOptions [2025-06-12T14:00:43.618Z] JVM_OPTIONS: [2025-06-12T14:00:43.618Z] { \ [2025-06-12T14:00:43.618Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T14:00:43.618Z] echo "Nothing to be done for setup."; \ [2025-06-12T14:00:43.618Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497346318379\\renaissance-philosophers_0"; \ [2025-06-12T14:00:43.618Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497346318379\\renaissance-philosophers_0"; \ [2025-06-12T14:00:43.618Z] echo ""; echo "TESTING:"; \ [2025-06-12T14:00:43.618Z] "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_17497346318379\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-06-12T14:00:43.618Z] 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_17497346318379\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T14:00:43.618Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T14:00:43.618Z] echo "Nothing to be done for teardown."; \ [2025-06-12T14:00:43.618Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497346318379\\TestTargetResult"; [2025-06-12T14:00:43.947Z] [2025-06-12T14:00:43.947Z] TEST SETUP: [2025-06-12T14:00:43.947Z] Nothing to be done for setup. [2025-06-12T14:00:43.947Z] [2025-06-12T14:00:43.947Z] TESTING: [2025-06-12T14:00:45.134Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-12T14:00:45.134Z] GC before operation: completed in 12.168 ms, heap usage 15.336 MB -> 4.713 MB. [2025-06-12T14:00:46.832Z] Camera thread performed 122 scans. [2025-06-12T14:00:46.832Z] ====== philosophers (scala) [default], iteration 0 completed (1739.767 ms) ====== [2025-06-12T14:00:46.832Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-12T14:00:46.832Z] GC before operation: completed in 12.028 ms, heap usage 60.115 MB -> 4.968 MB. [2025-06-12T14:00:47.944Z] Camera thread performed 122 scans. [2025-06-12T14:00:47.944Z] ====== philosophers (scala) [default], iteration 1 completed (1209.404 ms) ====== [2025-06-12T14:00:47.944Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-12T14:00:47.944Z] GC before operation: completed in 10.248 ms, heap usage 62.154 MB -> 4.968 MB. [2025-06-12T14:00:49.547Z] Camera thread performed 122 scans. [2025-06-12T14:00:49.547Z] ====== philosophers (scala) [default], iteration 2 completed (1426.822 ms) ====== [2025-06-12T14:00:49.547Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-12T14:00:49.547Z] GC before operation: completed in 10.193 ms, heap usage 79.770 MB -> 4.970 MB. [2025-06-12T14:00:50.657Z] Camera thread performed 122 scans. [2025-06-12T14:00:50.657Z] ====== philosophers (scala) [default], iteration 3 completed (1277.433 ms) ====== [2025-06-12T14:00:50.657Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-12T14:00:50.657Z] GC before operation: completed in 9.680 ms, heap usage 78.253 MB -> 4.970 MB. [2025-06-12T14:00:52.243Z] Camera thread performed 122 scans. [2025-06-12T14:00:52.243Z] ====== philosophers (scala) [default], iteration 4 completed (1361.489 ms) ====== [2025-06-12T14:00:52.243Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-12T14:00:52.243Z] GC before operation: completed in 9.702 ms, heap usage 41.197 MB -> 4.970 MB. [2025-06-12T14:00:53.354Z] Camera thread performed 122 scans. [2025-06-12T14:00:53.354Z] ====== philosophers (scala) [default], iteration 5 completed (1328.285 ms) ====== [2025-06-12T14:00:53.354Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-12T14:00:53.354Z] GC before operation: completed in 9.606 ms, heap usage 59.206 MB -> 4.973 MB. [2025-06-12T14:00:54.461Z] Camera thread performed 122 scans. [2025-06-12T14:00:54.462Z] ====== philosophers (scala) [default], iteration 6 completed (1273.188 ms) ====== [2025-06-12T14:00:54.462Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-12T14:00:54.462Z] GC before operation: completed in 9.557 ms, heap usage 58.078 MB -> 4.975 MB. [2025-06-12T14:00:56.062Z] Camera thread performed 122 scans. [2025-06-12T14:00:56.062Z] ====== philosophers (scala) [default], iteration 7 completed (1296.433 ms) ====== [2025-06-12T14:00:56.062Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-12T14:00:56.062Z] GC before operation: completed in 9.998 ms, heap usage 59.518 MB -> 4.975 MB. [2025-06-12T14:00:57.172Z] Camera thread performed 122 scans. [2025-06-12T14:00:57.172Z] ====== philosophers (scala) [default], iteration 8 completed (1356.790 ms) ====== [2025-06-12T14:00:57.172Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-12T14:00:57.172Z] GC before operation: completed in 9.485 ms, heap usage 23.855 MB -> 4.976 MB. [2025-06-12T14:00:58.775Z] Camera thread performed 122 scans. [2025-06-12T14:00:58.775Z] ====== philosophers (scala) [default], iteration 9 completed (1394.040 ms) ====== [2025-06-12T14:00:58.775Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-12T14:00:58.775Z] GC before operation: completed in 9.405 ms, heap usage 22.595 MB -> 4.976 MB. [2025-06-12T14:00:59.886Z] Camera thread performed 122 scans. [2025-06-12T14:00:59.886Z] ====== philosophers (scala) [default], iteration 10 completed (1299.609 ms) ====== [2025-06-12T14:00:59.886Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-12T14:00:59.886Z] GC before operation: completed in 9.497 ms, heap usage 23.184 MB -> 4.976 MB. [2025-06-12T14:01:01.471Z] Camera thread performed 122 scans. [2025-06-12T14:01:01.471Z] ====== philosophers (scala) [default], iteration 11 completed (1361.247 ms) ====== [2025-06-12T14:01:01.471Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-12T14:01:01.471Z] GC before operation: completed in 9.070 ms, heap usage 8.189 MB -> 4.976 MB. [2025-06-12T14:01:02.582Z] Camera thread performed 122 scans. [2025-06-12T14:01:02.582Z] ====== philosophers (scala) [default], iteration 12 completed (1368.061 ms) ====== [2025-06-12T14:01:02.582Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-12T14:01:02.582Z] GC before operation: completed in 9.185 ms, heap usage 10.587 MB -> 4.977 MB. [2025-06-12T14:01:04.182Z] Camera thread performed 122 scans. [2025-06-12T14:01:04.182Z] ====== philosophers (scala) [default], iteration 13 completed (1341.052 ms) ====== [2025-06-12T14:01:04.182Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-12T14:01:04.182Z] GC before operation: completed in 9.882 ms, heap usage 83.595 MB -> 4.977 MB. [2025-06-12T14:01:05.290Z] Camera thread performed 122 scans. [2025-06-12T14:01:05.290Z] ====== philosophers (scala) [default], iteration 14 completed (1314.300 ms) ====== [2025-06-12T14:01:05.290Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-12T14:01:05.290Z] GC before operation: completed in 9.879 ms, heap usage 57.189 MB -> 4.977 MB. [2025-06-12T14:01:06.892Z] Camera thread performed 122 scans. [2025-06-12T14:01:06.892Z] ====== philosophers (scala) [default], iteration 15 completed (1378.259 ms) ====== [2025-06-12T14:01:06.892Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-12T14:01:06.892Z] GC before operation: completed in 10.055 ms, heap usage 44.026 MB -> 4.977 MB. [2025-06-12T14:01:08.001Z] Camera thread performed 122 scans. [2025-06-12T14:01:08.001Z] ====== philosophers (scala) [default], iteration 16 completed (1371.957 ms) ====== [2025-06-12T14:01:08.001Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-12T14:01:08.001Z] GC before operation: completed in 9.626 ms, heap usage 68.975 MB -> 4.977 MB. [2025-06-12T14:01:09.606Z] Camera thread performed 122 scans. [2025-06-12T14:01:09.606Z] ====== philosophers (scala) [default], iteration 17 completed (1390.321 ms) ====== [2025-06-12T14:01:09.606Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-12T14:01:09.606Z] GC before operation: completed in 9.860 ms, heap usage 21.006 MB -> 4.977 MB. [2025-06-12T14:01:11.208Z] Camera thread performed 122 scans. [2025-06-12T14:01:11.208Z] ====== philosophers (scala) [default], iteration 18 completed (1340.427 ms) ====== [2025-06-12T14:01:11.208Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-12T14:01:11.208Z] GC before operation: completed in 9.273 ms, heap usage 32.684 MB -> 4.977 MB. [2025-06-12T14:01:12.318Z] Camera thread performed 122 scans. [2025-06-12T14:01:12.318Z] ====== philosophers (scala) [default], iteration 19 completed (1431.228 ms) ====== [2025-06-12T14:01:12.318Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-12T14:01:12.318Z] GC before operation: completed in 9.479 ms, heap usage 31.994 MB -> 4.977 MB. [2025-06-12T14:01:13.902Z] Camera thread performed 122 scans. [2025-06-12T14:01:13.902Z] ====== philosophers (scala) [default], iteration 20 completed (1342.182 ms) ====== [2025-06-12T14:01:13.902Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-12T14:01:13.902Z] GC before operation: completed in 11.147 ms, heap usage 44.001 MB -> 4.977 MB. [2025-06-12T14:01:15.026Z] Camera thread performed 122 scans. [2025-06-12T14:01:15.026Z] ====== philosophers (scala) [default], iteration 21 completed (1344.913 ms) ====== [2025-06-12T14:01:15.026Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-12T14:01:15.026Z] GC before operation: completed in 9.666 ms, heap usage 28.183 MB -> 4.977 MB. [2025-06-12T14:01:16.629Z] Camera thread performed 122 scans. [2025-06-12T14:01:16.629Z] ====== philosophers (scala) [default], iteration 22 completed (1317.500 ms) ====== [2025-06-12T14:01:16.629Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-12T14:01:16.629Z] GC before operation: completed in 10.364 ms, heap usage 29.078 MB -> 4.978 MB. [2025-06-12T14:01:17.738Z] Camera thread performed 122 scans. [2025-06-12T14:01:17.738Z] ====== philosophers (scala) [default], iteration 23 completed (1316.334 ms) ====== [2025-06-12T14:01:17.738Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-12T14:01:17.738Z] GC before operation: completed in 9.638 ms, heap usage 14.975 MB -> 4.977 MB. [2025-06-12T14:01:19.341Z] Camera thread performed 122 scans. [2025-06-12T14:01:19.341Z] ====== philosophers (scala) [default], iteration 24 completed (1372.285 ms) ====== [2025-06-12T14:01:19.341Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-12T14:01:19.341Z] GC before operation: completed in 9.476 ms, heap usage 32.029 MB -> 4.978 MB. [2025-06-12T14:01:20.449Z] Camera thread performed 122 scans. [2025-06-12T14:01:20.449Z] ====== philosophers (scala) [default], iteration 25 completed (1277.457 ms) ====== [2025-06-12T14:01:20.449Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-12T14:01:20.449Z] GC before operation: completed in 10.526 ms, heap usage 48.055 MB -> 4.977 MB. [2025-06-12T14:01:22.047Z] Camera thread performed 122 scans. [2025-06-12T14:01:22.047Z] ====== philosophers (scala) [default], iteration 26 completed (1408.750 ms) ====== [2025-06-12T14:01:22.047Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-12T14:01:22.047Z] GC before operation: completed in 9.524 ms, heap usage 13.621 MB -> 4.978 MB. [2025-06-12T14:01:23.154Z] Camera thread performed 122 scans. [2025-06-12T14:01:23.154Z] ====== philosophers (scala) [default], iteration 27 completed (1253.999 ms) ====== [2025-06-12T14:01:23.154Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-12T14:01:23.154Z] GC before operation: completed in 9.303 ms, heap usage 15.522 MB -> 4.978 MB. [2025-06-12T14:01:24.259Z] Camera thread performed 122 scans. [2025-06-12T14:01:24.259Z] ====== philosophers (scala) [default], iteration 28 completed (1329.673 ms) ====== [2025-06-12T14:01:24.259Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-12T14:01:24.259Z] GC before operation: completed in 10.020 ms, heap usage 82.576 MB -> 4.978 MB. [2025-06-12T14:01:25.855Z] Camera thread performed 122 scans. [2025-06-12T14:01:25.855Z] ====== philosophers (scala) [default], iteration 29 completed (1345.293 ms) ====== [2025-06-12T14:01:25.855Z] ----------------------------------- [2025-06-12T14:01:25.855Z] renaissance-philosophers_0_PASSED [2025-06-12T14:01:25.855Z] ----------------------------------- [2025-06-12T14:01:25.855Z] [2025-06-12T14:01:25.855Z] TEST TEARDOWN: [2025-06-12T14:01:25.855Z] Nothing to be done for teardown. [2025-06-12T14:01:26.167Z] renaissance-philosophers_0 Finish Time: Thu Jun 12 14:01:25 2025 Epoch Time (ms): 1749736885852