[2025-06-27T21:19:26.193Z] Running test renaissance-log-regression_0 ... [2025-06-27T21:19:26.193Z] =============================================== [2025-06-27T21:19:26.193Z] renaissance-log-regression_0 Start Time: Fri Jun 27 21:19:25 2025 Epoch Time (ms): 1751059165834 [2025-06-27T21:19:26.193Z] variation: NoOptions [2025-06-27T21:19:26.193Z] JVM_OPTIONS: [2025-06-27T21:19:26.193Z] { \ [2025-06-27T21:19:26.193Z] echo ""; echo "TEST SETUP:"; \ [2025-06-27T21:19:26.193Z] echo "Nothing to be done for setup."; \ [2025-06-27T21:19:26.193Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17510574384059/renaissance-log-regression_0"; \ [2025-06-27T21:19:26.193Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17510574384059/renaissance-log-regression_0"; \ [2025-06-27T21:19:26.193Z] echo ""; echo "TESTING:"; \ [2025-06-27T21:19:26.193Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17510574384059/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-27T21:19:26.193Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17510574384059/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-27T21:19:26.193Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-27T21:19:26.193Z] echo "Nothing to be done for teardown."; \ [2025-06-27T21:19:26.193Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17510574384059/TestTargetResult"; [2025-06-27T21:19:26.193Z] [2025-06-27T21:19:26.193Z] TEST SETUP: [2025-06-27T21:19:26.193Z] Nothing to be done for setup. [2025-06-27T21:19:26.193Z] [2025-06-27T21:19:26.193Z] TESTING: [2025-06-27T21:20:08.099Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-06-27T21:20:20.053Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-27T21:20:20.053Z] GC before operation: completed in 127.729 ms, heap usage 91.450 MB -> 34.776 MB. [2025-06-27T21:20:39.239Z] ====== log-regression (apache-spark) [default], iteration 0 completed (19418.675 ms) ====== [2025-06-27T21:20:39.239Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-27T21:20:39.239Z] GC before operation: completed in 117.838 ms, heap usage 825.180 MB -> 133.228 MB. [2025-06-27T21:20:41.019Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2143.608 ms) ====== [2025-06-27T21:20:41.019Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-27T21:20:41.019Z] GC before operation: completed in 109.505 ms, heap usage 344.402 MB -> 132.399 MB. [2025-06-27T21:20:43.517Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1899.358 ms) ====== [2025-06-27T21:20:43.517Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-27T21:20:43.517Z] GC before operation: completed in 108.302 ms, heap usage 676.094 MB -> 133.961 MB. [2025-06-27T21:20:45.123Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1864.839 ms) ====== [2025-06-27T21:20:45.123Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-27T21:20:45.123Z] GC before operation: completed in 119.796 ms, heap usage 445.320 MB -> 133.688 MB. [2025-06-27T21:20:46.719Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1821.847 ms) ====== [2025-06-27T21:20:46.719Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-27T21:20:46.719Z] GC before operation: completed in 113.634 ms, heap usage 685.415 MB -> 134.758 MB. [2025-06-27T21:20:49.212Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1777.780 ms) ====== [2025-06-27T21:20:49.212Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-27T21:20:49.212Z] GC before operation: completed in 122.777 ms, heap usage 326.422 MB -> 134.107 MB. [2025-06-27T21:20:50.814Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1683.438 ms) ====== [2025-06-27T21:20:50.814Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-27T21:20:50.814Z] GC before operation: completed in 123.595 ms, heap usage 650.569 MB -> 135.547 MB. [2025-06-27T21:20:52.423Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1692.844 ms) ====== [2025-06-27T21:20:52.423Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-27T21:20:52.423Z] GC before operation: completed in 124.876 ms, heap usage 505.865 MB -> 135.413 MB. [2025-06-27T21:20:54.019Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1730.468 ms) ====== [2025-06-27T21:20:54.019Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-27T21:20:54.793Z] GC before operation: completed in 126.288 ms, heap usage 341.414 MB -> 135.283 MB. [2025-06-27T21:20:56.401Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1827.032 ms) ====== [2025-06-27T21:20:56.401Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-27T21:20:56.401Z] GC before operation: completed in 131.197 ms, heap usage 645.168 MB -> 136.565 MB. [2025-06-27T21:20:59.637Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1696.023 ms) ====== [2025-06-27T21:20:59.637Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-27T21:20:59.637Z] GC before operation: completed in 115.151 ms, heap usage 621.637 MB -> 136.856 MB. [2025-06-27T21:20:59.637Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1726.622 ms) ====== [2025-06-27T21:20:59.637Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-27T21:21:00.422Z] GC before operation: completed in 118.116 ms, heap usage 727.875 MB -> 137.683 MB. [2025-06-27T21:21:02.019Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1605.632 ms) ====== [2025-06-27T21:21:02.019Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-27T21:21:02.019Z] GC before operation: completed in 127.988 ms, heap usage 522.954 MB -> 137.368 MB. [2025-06-27T21:21:03.629Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1656.428 ms) ====== [2025-06-27T21:21:03.629Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-27T21:21:03.629Z] GC before operation: completed in 109.831 ms, heap usage 583.452 MB -> 137.835 MB. [2025-06-27T21:21:05.244Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1613.018 ms) ====== [2025-06-27T21:21:05.244Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-27T21:21:05.244Z] GC before operation: completed in 106.371 ms, heap usage 524.156 MB -> 138.065 MB. [2025-06-27T21:21:06.840Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1616.961 ms) ====== [2025-06-27T21:21:06.840Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-27T21:21:06.840Z] GC before operation: completed in 111.514 ms, heap usage 964.752 MB -> 139.898 MB. [2025-06-27T21:21:08.440Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1619.281 ms) ====== [2025-06-27T21:21:08.440Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-27T21:21:08.440Z] GC before operation: completed in 112.983 ms, heap usage 333.530 MB -> 138.426 MB. [2025-06-27T21:21:10.044Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1636.231 ms) ====== [2025-06-27T21:21:10.044Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-27T21:21:10.822Z] GC before operation: completed in 111.657 ms, heap usage 780.716 MB -> 140.206 MB. [2025-06-27T21:21:12.648Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1740.579 ms) ====== [2025-06-27T21:21:12.648Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-27T21:21:12.648Z] GC before operation: completed in 120.293 ms, heap usage 465.942 MB -> 139.549 MB. [2025-06-27T21:21:14.244Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1641.298 ms) ====== [2025-06-27T21:21:15.016Z] ----------------------------------- [2025-06-27T21:21:15.016Z] renaissance-log-regression_0_PASSED [2025-06-27T21:21:15.016Z] ----------------------------------- [2025-06-27T21:21:15.016Z] [2025-06-27T21:21:15.016Z] TEST TEARDOWN: [2025-06-27T21:21:15.016Z] Nothing to be done for teardown. [2025-06-27T21:21:15.016Z] renaissance-log-regression_0 Finish Time: Fri Jun 27 21:21:14 2025 Epoch Time (ms): 1751059274629