[2025-03-05T22:38:42.655Z] Running test renaissance-log-regression_0 ... [2025-03-05T22:38:42.655Z] =============================================== [2025-03-05T22:38:42.655Z] renaissance-log-regression_0 Start Time: Wed Mar 5 22:38:41 2025 Epoch Time (ms): 1741214321876 [2025-03-05T22:38:42.655Z] variation: NoOptions [2025-03-05T22:38:42.655Z] JVM_OPTIONS: [2025-03-05T22:38:42.655Z] { \ [2025-03-05T22:38:42.655Z] echo ""; echo "TEST SETUP:"; \ [2025-03-05T22:38:42.655Z] echo "Nothing to be done for setup."; \ [2025-03-05T22:38:42.655Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17412135471852/renaissance-log-regression_0"; \ [2025-03-05T22:38:42.655Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17412135471852/renaissance-log-regression_0"; \ [2025-03-05T22:38:42.655Z] echo ""; echo "TESTING:"; \ [2025-03-05T22:38:42.655Z] "/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_17412135471852/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-03-05T22:38:42.655Z] 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_17412135471852/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-05T22:38:42.655Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-05T22:38:42.655Z] echo "Nothing to be done for teardown."; \ [2025-03-05T22:38:42.655Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17412135471852/TestTargetResult"; [2025-03-05T22:38:42.655Z] [2025-03-05T22:38:42.655Z] TEST SETUP: [2025-03-05T22:38:42.655Z] Nothing to be done for setup. [2025-03-05T22:38:42.655Z] [2025-03-05T22:38:42.655Z] TESTING: [2025-03-05T22:38:45.667Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-03-05T22:38:47.618Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-05T22:38:48.565Z] WARNING: An illegal reflective access operation has occurred [2025-03-05T22:38:48.565Z] 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_17412135471852/renaissance-log-regression_0/harness-223842-11652040901594775057/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-03-05T22:38:48.565Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-05T22:38:48.565Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-05T22:38:48.565Z] WARNING: All illegal access operations will be denied in a future release [2025-03-05T22:38:51.568Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-03-05T22:38:51.568Z] GC before operation: completed in 58.292 ms, heap usage 67.747 MB -> 33.766 MB. [2025-03-05T22:38:59.728Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7804.601 ms) ====== [2025-03-05T22:38:59.728Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-03-05T22:38:59.728Z] GC before operation: completed in 92.151 ms, heap usage 360.799 MB -> 112.127 MB. [2025-03-05T22:39:01.676Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2119.250 ms) ====== [2025-03-05T22:39:01.676Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-03-05T22:39:01.676Z] GC before operation: completed in 83.330 ms, heap usage 266.444 MB -> 112.493 MB. [2025-03-05T22:39:03.628Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1942.126 ms) ====== [2025-03-05T22:39:03.628Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-03-05T22:39:03.628Z] GC before operation: completed in 81.631 ms, heap usage 231.712 MB -> 112.796 MB. [2025-03-05T22:39:05.593Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1817.708 ms) ====== [2025-03-05T22:39:05.593Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-03-05T22:39:05.593Z] GC before operation: completed in 91.468 ms, heap usage 240.507 MB -> 113.043 MB. [2025-03-05T22:39:07.557Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1703.380 ms) ====== [2025-03-05T22:39:07.557Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-03-05T22:39:07.557Z] GC before operation: completed in 81.821 ms, heap usage 324.936 MB -> 113.490 MB. [2025-03-05T22:39:09.457Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1698.217 ms) ====== [2025-03-05T22:39:09.457Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-03-05T22:39:09.457Z] GC before operation: completed in 86.339 ms, heap usage 259.373 MB -> 113.497 MB. [2025-03-05T22:39:10.407Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1753.742 ms) ====== [2025-03-05T22:39:10.407Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-03-05T22:39:11.358Z] GC before operation: completed in 92.046 ms, heap usage 248.391 MB -> 113.690 MB. [2025-03-05T22:39:12.308Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1431.534 ms) ====== [2025-03-05T22:39:12.308Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-03-05T22:39:12.308Z] GC before operation: completed in 86.917 ms, heap usage 375.457 MB -> 114.195 MB. [2025-03-05T22:39:14.294Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1607.848 ms) ====== [2025-03-05T22:39:14.294Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-03-05T22:39:14.294Z] GC before operation: completed in 82.656 ms, heap usage 305.757 MB -> 114.226 MB. [2025-03-05T22:39:16.242Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1679.759 ms) ====== [2025-03-05T22:39:16.242Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-03-05T22:39:16.243Z] GC before operation: completed in 99.890 ms, heap usage 267.928 MB -> 114.321 MB. [2025-03-05T22:39:17.190Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1584.476 ms) ====== [2025-03-05T22:39:17.190Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-03-05T22:39:17.190Z] GC before operation: completed in 92.223 ms, heap usage 238.877 MB -> 114.418 MB. [2025-03-05T22:39:19.141Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1622.051 ms) ====== [2025-03-05T22:39:19.141Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-03-05T22:39:19.141Z] GC before operation: completed in 96.282 ms, heap usage 258.303 MB -> 114.657 MB. [2025-03-05T22:39:21.146Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1505.207 ms) ====== [2025-03-05T22:39:21.146Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-03-05T22:39:21.146Z] GC before operation: completed in 98.573 ms, heap usage 237.963 MB -> 114.797 MB. [2025-03-05T22:39:22.097Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1626.068 ms) ====== [2025-03-05T22:39:22.097Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-03-05T22:39:23.052Z] GC before operation: completed in 118.207 ms, heap usage 238.783 MB -> 114.985 MB. [2025-03-05T22:39:24.009Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1638.689 ms) ====== [2025-03-05T22:39:24.009Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-03-05T22:39:24.009Z] GC before operation: completed in 93.784 ms, heap usage 315.425 MB -> 115.321 MB. [2025-03-05T22:39:26.009Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1571.448 ms) ====== [2025-03-05T22:39:26.009Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-03-05T22:39:26.009Z] GC before operation: completed in 93.242 ms, heap usage 259.404 MB -> 115.416 MB. [2025-03-05T22:39:27.966Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1607.689 ms) ====== [2025-03-05T22:39:27.966Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-03-05T22:39:27.966Z] GC before operation: completed in 94.498 ms, heap usage 258.977 MB -> 115.647 MB. [2025-03-05T22:39:29.915Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1645.951 ms) ====== [2025-03-05T22:39:29.915Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-03-05T22:39:29.915Z] GC before operation: completed in 95.044 ms, heap usage 315.863 MB -> 115.980 MB. [2025-03-05T22:39:30.881Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1535.182 ms) ====== [2025-03-05T22:39:30.881Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-03-05T22:39:30.881Z] GC before operation: completed in 98.311 ms, heap usage 278.787 MB -> 116.082 MB. [2025-03-05T22:39:32.825Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1602.911 ms) ====== [2025-03-05T22:39:32.825Z] ----------------------------------- [2025-03-05T22:39:32.825Z] renaissance-log-regression_0_PASSED [2025-03-05T22:39:32.825Z] ----------------------------------- [2025-03-05T22:39:32.825Z] [2025-03-05T22:39:32.825Z] TEST TEARDOWN: [2025-03-05T22:39:32.825Z] Nothing to be done for teardown. [2025-03-05T22:39:32.825Z] renaissance-log-regression_0 Finish Time: Wed Mar 5 22:39:32 2025 Epoch Time (ms): 1741214372471