[2025-02-05T23:59:44.103Z] Running test renaissance-log-regression_0 ... [2025-02-05T23:59:44.103Z] =============================================== [2025-02-05T23:59:44.103Z] renaissance-log-regression_0 Start Time: Wed Feb 5 23:59:43 2025 Epoch Time (ms): 1738799983314 [2025-02-05T23:59:44.103Z] variation: NoOptions [2025-02-05T23:59:44.103Z] JVM_OPTIONS: [2025-02-05T23:59:44.103Z] { \ [2025-02-05T23:59:44.103Z] echo ""; echo "TEST SETUP:"; \ [2025-02-05T23:59:44.103Z] echo "Nothing to be done for setup."; \ [2025-02-05T23:59:44.103Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17387991961701/renaissance-log-regression_0"; \ [2025-02-05T23:59:44.103Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17387991961701/renaissance-log-regression_0"; \ [2025-02-05T23:59:44.103Z] echo ""; echo "TESTING:"; \ [2025-02-05T23:59:44.103Z] "/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_17387991961701/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-05T23:59:44.103Z] 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_17387991961701/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-05T23:59:44.103Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-05T23:59:44.103Z] echo "Nothing to be done for teardown."; \ [2025-02-05T23:59:44.103Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17387991961701/TestTargetResult"; [2025-02-05T23:59:44.103Z] [2025-02-05T23:59:44.103Z] TEST SETUP: [2025-02-05T23:59:44.103Z] Nothing to be done for setup. [2025-02-05T23:59:44.103Z] [2025-02-05T23:59:44.103Z] TESTING: [2025-02-05T23:59:47.294Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-05T23:59:49.253Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-05T23:59:50.203Z] WARNING: An illegal reflective access operation has occurred [2025-02-05T23:59:50.203Z] 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_17387991961701/renaissance-log-regression_0/harness-235944-8195788924337179690/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-05T23:59:50.203Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-05T23:59:50.203Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-05T23:59:50.203Z] WARNING: All illegal access operations will be denied in a future release [2025-02-05T23:59:57.985Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-05T23:59:57.985Z] GC before operation: completed in 55.306 ms, heap usage 54.388 MB -> 33.756 MB. [2025-02-06T00:00:00.284Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7475.097 ms) ====== [2025-02-06T00:00:00.284Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-06T00:00:00.284Z] GC before operation: completed in 92.713 ms, heap usage 312.315 MB -> 111.996 MB. [2025-02-06T00:00:03.316Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2391.890 ms) ====== [2025-02-06T00:00:03.316Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-06T00:00:03.316Z] GC before operation: completed in 119.147 ms, heap usage 242.912 MB -> 112.363 MB. [2025-02-06T00:00:05.279Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2138.769 ms) ====== [2025-02-06T00:00:05.279Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-06T00:00:05.279Z] GC before operation: completed in 79.193 ms, heap usage 243.571 MB -> 112.778 MB. [2025-02-06T00:00:07.389Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1920.254 ms) ====== [2025-02-06T00:00:07.389Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-06T00:00:07.389Z] GC before operation: completed in 79.946 ms, heap usage 264.877 MB -> 113.060 MB. [2025-02-06T00:00:09.345Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1808.157 ms) ====== [2025-02-06T00:00:09.345Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-06T00:00:09.345Z] GC before operation: completed in 81.232 ms, heap usage 249.063 MB -> 113.273 MB. [2025-02-06T00:00:10.296Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1668.144 ms) ====== [2025-02-06T00:00:10.296Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-06T00:00:10.296Z] GC before operation: completed in 83.829 ms, heap usage 320.264 MB -> 113.606 MB. [2025-02-06T00:00:12.247Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1785.540 ms) ====== [2025-02-06T00:00:12.247Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-06T00:00:12.247Z] GC before operation: completed in 79.979 ms, heap usage 257.449 MB -> 113.657 MB. [2025-02-06T00:00:14.207Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1612.921 ms) ====== [2025-02-06T00:00:14.207Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-06T00:00:14.207Z] GC before operation: completed in 81.138 ms, heap usage 334.720 MB -> 114.017 MB. [2025-02-06T00:00:16.162Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1781.273 ms) ====== [2025-02-06T00:00:16.162Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-06T00:00:16.162Z] GC before operation: completed in 80.666 ms, heap usage 266.769 MB -> 114.079 MB. [2025-02-06T00:00:18.123Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1629.139 ms) ====== [2025-02-06T00:00:18.123Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-06T00:00:18.123Z] GC before operation: completed in 79.844 ms, heap usage 285.967 MB -> 114.332 MB. [2025-02-06T00:00:20.090Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1671.331 ms) ====== [2025-02-06T00:00:20.090Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-06T00:00:20.090Z] GC before operation: completed in 80.176 ms, heap usage 248.047 MB -> 114.460 MB. [2025-02-06T00:00:21.053Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1525.875 ms) ====== [2025-02-06T00:00:21.053Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-06T00:00:21.053Z] GC before operation: completed in 83.125 ms, heap usage 373.290 MB -> 114.934 MB. [2025-02-06T00:00:23.010Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1655.112 ms) ====== [2025-02-06T00:00:23.010Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-06T00:00:23.010Z] GC before operation: completed in 86.169 ms, heap usage 248.807 MB -> 114.837 MB. [2025-02-06T00:00:24.966Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1568.127 ms) ====== [2025-02-06T00:00:24.966Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-06T00:00:24.966Z] GC before operation: completed in 90.684 ms, heap usage 238.947 MB -> 114.944 MB. [2025-02-06T00:00:25.922Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1671.066 ms) ====== [2025-02-06T00:00:25.922Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-06T00:00:26.873Z] GC before operation: completed in 89.254 ms, heap usage 248.949 MB -> 115.178 MB. [2025-02-06T00:00:27.825Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1598.425 ms) ====== [2025-02-06T00:00:27.825Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-06T00:00:27.825Z] GC before operation: completed in 89.997 ms, heap usage 258.066 MB -> 115.412 MB. [2025-02-06T00:00:29.780Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1514.777 ms) ====== [2025-02-06T00:00:29.781Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-06T00:00:29.781Z] GC before operation: completed in 79.438 ms, heap usage 315.826 MB -> 115.783 MB. [2025-02-06T00:00:31.735Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1629.519 ms) ====== [2025-02-06T00:00:31.735Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-06T00:00:31.735Z] GC before operation: completed in 87.583 ms, heap usage 297.564 MB -> 115.930 MB. [2025-02-06T00:00:32.686Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1582.950 ms) ====== [2025-02-06T00:00:32.686Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-06T00:00:32.686Z] GC before operation: completed in 85.682 ms, heap usage 297.906 MB -> 116.080 MB. [2025-02-06T00:00:34.645Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1633.136 ms) ====== [2025-02-06T00:00:34.645Z] ----------------------------------- [2025-02-06T00:00:34.645Z] renaissance-log-regression_0_PASSED [2025-02-06T00:00:34.645Z] ----------------------------------- [2025-02-06T00:00:34.645Z] [2025-02-06T00:00:34.645Z] TEST TEARDOWN: [2025-02-06T00:00:34.645Z] Nothing to be done for teardown. [2025-02-06T00:00:34.645Z] renaissance-log-regression_0 Finish Time: Thu Feb 6 00:00:34 2025 Epoch Time (ms): 1738800034425