[2024-11-17T07:41:52.570Z] Running test renaissance-log-regression_0 ... [2024-11-17T07:41:52.570Z] =============================================== [2024-11-17T07:41:52.570Z] renaissance-log-regression_0 Start Time: Sun Nov 17 07:41:51 2024 Epoch Time (ms): 1731829311992 [2024-11-17T07:41:52.570Z] variation: NoOptions [2024-11-17T07:41:52.570Z] JVM_OPTIONS: [2024-11-17T07:41:52.570Z] { \ [2024-11-17T07:41:52.570Z] echo ""; echo "TEST SETUP:"; \ [2024-11-17T07:41:52.570Z] echo "Nothing to be done for setup."; \ [2024-11-17T07:41:52.570Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-log-regression_0"; \ [2024-11-17T07:41:52.570Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-log-regression_0"; \ [2024-11-17T07:41:52.570Z] echo ""; echo "TESTING:"; \ [2024-11-17T07:41:52.570Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-17T07:41:52.570Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-17T07:41:52.570Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-17T07:41:52.570Z] echo "Nothing to be done for teardown."; \ [2024-11-17T07:41:52.570Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/TestTargetResult"; [2024-11-17T07:41:52.570Z] [2024-11-17T07:41:52.570Z] TEST SETUP: [2024-11-17T07:41:52.570Z] Nothing to be done for setup. [2024-11-17T07:41:52.570Z] [2024-11-17T07:41:52.570Z] TESTING: [2024-11-17T07:42:00.109Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-17T07:42:06.292Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-17T07:42:09.045Z] WARNING: An illegal reflective access operation has occurred [2024-11-17T07:42:09.045Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17318272656887/renaissance-log-regression_0/harness-074154-12240124048952595982/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-17T07:42:09.045Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-17T07:42:09.045Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-17T07:42:09.045Z] WARNING: All illegal access operations will be denied in a future release [2024-11-17T07:42:16.614Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-17T07:42:16.615Z] GC before operation: completed in 209.869 ms, heap usage 64.662 MB -> 33.776 MB. [2024-11-17T07:42:48.553Z] ====== log-regression (apache-spark) [default], iteration 0 completed (28763.559 ms) ====== [2024-11-17T07:42:48.553Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-17T07:42:48.553Z] GC before operation: completed in 359.484 ms, heap usage 244.134 MB -> 111.933 MB. [2024-11-17T07:42:56.306Z] ====== log-regression (apache-spark) [default], iteration 1 completed (10017.664 ms) ====== [2024-11-17T07:42:56.306Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-17T07:42:56.306Z] GC before operation: completed in 353.775 ms, heap usage 259.676 MB -> 112.457 MB. [2024-11-17T07:43:05.444Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8955.074 ms) ====== [2024-11-17T07:43:05.444Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-17T07:43:05.444Z] GC before operation: completed in 291.316 ms, heap usage 279.955 MB -> 112.893 MB. [2024-11-17T07:43:13.676Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8359.848 ms) ====== [2024-11-17T07:43:13.676Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-17T07:43:13.676Z] GC before operation: completed in 295.321 ms, heap usage 240.067 MB -> 113.009 MB. [2024-11-17T07:43:21.272Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7094.035 ms) ====== [2024-11-17T07:43:21.272Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-17T07:43:21.272Z] GC before operation: completed in 259.678 ms, heap usage 229.356 MB -> 113.224 MB. [2024-11-17T07:43:27.518Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6025.475 ms) ====== [2024-11-17T07:43:27.518Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-17T07:43:27.518Z] GC before operation: completed in 297.158 ms, heap usage 238.632 MB -> 113.412 MB. [2024-11-17T07:43:33.766Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6245.564 ms) ====== [2024-11-17T07:43:33.766Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-17T07:43:33.766Z] GC before operation: completed in 332.021 ms, heap usage 306.040 MB -> 113.777 MB. [2024-11-17T07:43:40.075Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5214.364 ms) ====== [2024-11-17T07:43:40.075Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-17T07:43:40.075Z] GC before operation: completed in 348.826 ms, heap usage 259.328 MB -> 113.834 MB. [2024-11-17T07:43:45.073Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5360.363 ms) ====== [2024-11-17T07:43:45.073Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-17T07:43:45.073Z] GC before operation: completed in 288.830 ms, heap usage 240.054 MB -> 113.973 MB. [2024-11-17T07:43:51.317Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5789.206 ms) ====== [2024-11-17T07:43:51.317Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-17T07:43:51.317Z] GC before operation: completed in 306.057 ms, heap usage 248.321 MB -> 114.224 MB. [2024-11-17T07:43:57.540Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6244.908 ms) ====== [2024-11-17T07:43:57.541Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-17T07:43:58.417Z] GC before operation: completed in 448.172 ms, heap usage 268.218 MB -> 114.551 MB. [2024-11-17T07:44:03.409Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5704.958 ms) ====== [2024-11-17T07:44:03.409Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-17T07:44:04.288Z] GC before operation: completed in 280.656 ms, heap usage 258.492 MB -> 114.600 MB. [2024-11-17T07:44:09.306Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5623.263 ms) ====== [2024-11-17T07:44:09.306Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-17T07:44:10.185Z] GC before operation: completed in 337.625 ms, heap usage 315.304 MB -> 115.784 MB. [2024-11-17T07:44:16.420Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6244.941 ms) ====== [2024-11-17T07:44:16.420Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-17T07:44:16.420Z] GC before operation: completed in 354.430 ms, heap usage 238.959 MB -> 114.925 MB. [2024-11-17T07:44:24.029Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6750.355 ms) ====== [2024-11-17T07:44:24.029Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-17T07:44:24.029Z] GC before operation: completed in 465.495 ms, heap usage 240.287 MB -> 115.094 MB. [2024-11-17T07:44:30.225Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6175.234 ms) ====== [2024-11-17T07:44:30.225Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-17T07:44:30.225Z] GC before operation: completed in 383.458 ms, heap usage 239.731 MB -> 115.369 MB. [2024-11-17T07:44:36.430Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5929.504 ms) ====== [2024-11-17T07:44:36.430Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-17T07:44:36.430Z] GC before operation: completed in 361.327 ms, heap usage 335.560 MB -> 115.782 MB. [2024-11-17T07:44:41.985Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5547.464 ms) ====== [2024-11-17T07:44:41.985Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-17T07:44:42.863Z] GC before operation: completed in 394.193 ms, heap usage 240.497 MB -> 115.739 MB. [2024-11-17T07:44:47.862Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5474.528 ms) ====== [2024-11-17T07:44:47.862Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-17T07:44:48.743Z] GC before operation: completed in 342.873 ms, heap usage 325.106 MB -> 116.263 MB. [2024-11-17T07:44:53.706Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5547.448 ms) ====== [2024-11-17T07:44:55.499Z] ----------------------------------- [2024-11-17T07:44:55.499Z] renaissance-log-regression_0_PASSED [2024-11-17T07:44:55.499Z] ----------------------------------- [2024-11-17T07:44:55.499Z] [2024-11-17T07:44:55.499Z] TEST TEARDOWN: [2024-11-17T07:44:55.499Z] Nothing to be done for teardown. [2024-11-17T07:44:55.500Z] renaissance-log-regression_0 Finish Time: Sun Nov 17 07:44:54 2024 Epoch Time (ms): 1731829494573