[2025-07-16T04:43:34.666Z] Running test renaissance-log-regression_0 ... [2025-07-16T04:43:34.666Z] =============================================== [2025-07-16T04:43:34.666Z] renaissance-log-regression_0 Start Time: Wed Jul 16 04:43:33 2025 Epoch Time (ms): 1752641013637 [2025-07-16T04:43:34.666Z] variation: NoOptions [2025-07-16T04:43:34.666Z] JVM_OPTIONS: [2025-07-16T04:43:34.666Z] { \ [2025-07-16T04:43:34.666Z] echo ""; echo "TEST SETUP:"; \ [2025-07-16T04:43:34.666Z] echo "Nothing to be done for setup."; \ [2025-07-16T04:43:34.666Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17526397241551/renaissance-log-regression_0"; \ [2025-07-16T04:43:34.666Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17526397241551/renaissance-log-regression_0"; \ [2025-07-16T04:43:34.666Z] echo ""; echo "TESTING:"; \ [2025-07-16T04:43:34.666Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/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_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17526397241551/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-16T04:43:34.666Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17526397241551/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-16T04:43:34.666Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-16T04:43:34.666Z] echo "Nothing to be done for teardown."; \ [2025-07-16T04:43:34.666Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17526397241551/TestTargetResult"; [2025-07-16T04:43:34.666Z] [2025-07-16T04:43:34.666Z] TEST SETUP: [2025-07-16T04:43:34.666Z] Nothing to be done for setup. [2025-07-16T04:43:34.666Z] [2025-07-16T04:43:34.666Z] TESTING: [2025-07-16T04:43:40.058Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-16T04:43:42.016Z] WARNING: An illegal reflective access operation has occurred [2025-07-16T04:43:42.017Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17526397241551/renaissance-log-regression_0/harness-044334-14912428875325562082/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-07-16T04:43:42.017Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-07-16T04:43:42.017Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-07-16T04:43:42.017Z] WARNING: All illegal access operations will be denied in a future release [2025-07-16T04:43:42.970Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-16T04:43:42.970Z] GC before operation: completed in 56.789 ms, heap usage 63.730 MB -> 34.389 MB. [2025-07-16T04:43:56.500Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12048.836 ms) ====== [2025-07-16T04:43:56.500Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-16T04:43:56.500Z] GC before operation: completed in 120.609 ms, heap usage 423.294 MB -> 118.858 MB. [2025-07-16T04:43:58.462Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2817.772 ms) ====== [2025-07-16T04:43:58.462Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-16T04:43:58.462Z] GC before operation: completed in 112.068 ms, heap usage 287.123 MB -> 118.942 MB. [2025-07-16T04:44:01.492Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2519.650 ms) ====== [2025-07-16T04:44:01.492Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-16T04:44:01.492Z] GC before operation: completed in 97.369 ms, heap usage 288.844 MB -> 119.414 MB. [2025-07-16T04:44:03.452Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2141.530 ms) ====== [2025-07-16T04:44:03.452Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-16T04:44:03.452Z] GC before operation: completed in 100.939 ms, heap usage 405.660 MB -> 119.959 MB. [2025-07-16T04:44:05.418Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2226.606 ms) ====== [2025-07-16T04:44:05.418Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-16T04:44:05.418Z] GC before operation: completed in 104.905 ms, heap usage 294.828 MB -> 119.878 MB. [2025-07-16T04:44:07.382Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2172.866 ms) ====== [2025-07-16T04:44:07.382Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-16T04:44:08.341Z] GC before operation: completed in 104.918 ms, heap usage 320.774 MB -> 120.156 MB. [2025-07-16T04:44:10.303Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2101.813 ms) ====== [2025-07-16T04:44:10.303Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-16T04:44:10.303Z] GC before operation: completed in 103.600 ms, heap usage 265.154 MB -> 120.189 MB. [2025-07-16T04:44:12.266Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2047.583 ms) ====== [2025-07-16T04:44:12.266Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-16T04:44:12.266Z] GC before operation: completed in 106.451 ms, heap usage 404.898 MB -> 120.798 MB. [2025-07-16T04:44:14.231Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1891.426 ms) ====== [2025-07-16T04:44:14.232Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-16T04:44:14.232Z] GC before operation: completed in 108.118 ms, heap usage 255.556 MB -> 120.501 MB. [2025-07-16T04:44:16.193Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1936.505 ms) ====== [2025-07-16T04:44:16.193Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-16T04:44:16.193Z] GC before operation: completed in 107.275 ms, heap usage 292.713 MB -> 120.877 MB. [2025-07-16T04:44:19.160Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1918.287 ms) ====== [2025-07-16T04:44:19.160Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-16T04:44:19.160Z] GC before operation: completed in 102.402 ms, heap usage 281.975 MB -> 120.990 MB. [2025-07-16T04:44:20.142Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2022.978 ms) ====== [2025-07-16T04:44:20.142Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-16T04:44:20.142Z] GC before operation: completed in 110.845 ms, heap usage 328.765 MB -> 121.357 MB. [2025-07-16T04:44:22.102Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2084.572 ms) ====== [2025-07-16T04:44:22.102Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-16T04:44:23.055Z] GC before operation: completed in 115.062 ms, heap usage 344.277 MB -> 121.609 MB. [2025-07-16T04:44:25.024Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1977.057 ms) ====== [2025-07-16T04:44:25.024Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-16T04:44:25.024Z] GC before operation: completed in 113.731 ms, heap usage 367.834 MB -> 121.893 MB. [2025-07-16T04:44:26.984Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1977.882 ms) ====== [2025-07-16T04:44:26.984Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-16T04:44:26.984Z] GC before operation: completed in 110.005 ms, heap usage 366.931 MB -> 122.094 MB. [2025-07-16T04:44:28.946Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1896.798 ms) ====== [2025-07-16T04:44:28.946Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-16T04:44:28.946Z] GC before operation: completed in 108.901 ms, heap usage 300.976 MB -> 122.089 MB. [2025-07-16T04:44:30.908Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1884.106 ms) ====== [2025-07-16T04:44:30.908Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-16T04:44:30.908Z] GC before operation: completed in 112.210 ms, heap usage 373.746 MB -> 122.548 MB. [2025-07-16T04:44:32.866Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1859.167 ms) ====== [2025-07-16T04:44:32.866Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-16T04:44:32.866Z] GC before operation: completed in 115.210 ms, heap usage 410.052 MB -> 122.801 MB. [2025-07-16T04:44:34.826Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1797.575 ms) ====== [2025-07-16T04:44:34.826Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-16T04:44:34.826Z] GC before operation: completed in 112.437 ms, heap usage 265.475 MB -> 122.585 MB. [2025-07-16T04:44:36.784Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1951.499 ms) ====== [2025-07-16T04:44:36.784Z] ----------------------------------- [2025-07-16T04:44:36.784Z] renaissance-log-regression_0_PASSED [2025-07-16T04:44:36.784Z] ----------------------------------- [2025-07-16T04:44:36.784Z] [2025-07-16T04:44:36.784Z] TEST TEARDOWN: [2025-07-16T04:44:36.784Z] Nothing to be done for teardown. [2025-07-16T04:44:36.784Z] renaissance-log-regression_0 Finish Time: Wed Jul 16 04:44:36 2025 Epoch Time (ms): 1752641076476