[2024-10-03T07:41:23.412Z] Running test renaissance-log-regression_0 ... [2024-10-03T07:41:23.412Z] =============================================== [2024-10-03T07:41:23.412Z] renaissance-log-regression_0 Start Time: Thu Oct 3 07:41:22 2024 Epoch Time (ms): 1727941282731 [2024-10-03T07:41:23.412Z] variation: NoOptions [2024-10-03T07:41:23.412Z] JVM_OPTIONS: [2024-10-03T07:41:23.412Z] { \ [2024-10-03T07:41:23.412Z] echo ""; echo "TEST SETUP:"; \ [2024-10-03T07:41:23.412Z] echo "Nothing to be done for setup."; \ [2024-10-03T07:41:23.412Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17279402866251/renaissance-log-regression_0"; \ [2024-10-03T07:41:23.412Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17279402866251/renaissance-log-regression_0"; \ [2024-10-03T07:41:23.412Z] echo ""; echo "TESTING:"; \ [2024-10-03T07:41:23.412Z] "/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_17279402866251/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-10-03T07:41:23.412Z] 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_17279402866251/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-03T07:41:23.412Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-03T07:41:23.412Z] echo "Nothing to be done for teardown."; \ [2024-10-03T07:41:23.412Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17279402866251/TestTargetResult"; [2024-10-03T07:41:23.412Z] [2024-10-03T07:41:23.412Z] TEST SETUP: [2024-10-03T07:41:23.412Z] Nothing to be done for setup. [2024-10-03T07:41:23.412Z] [2024-10-03T07:41:23.412Z] TESTING: [2024-10-03T07:41:27.471Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-03T07:41:29.996Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-03T07:41:32.529Z] WARNING: An illegal reflective access operation has occurred [2024-10-03T07:41:32.529Z] 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_17279402866251/renaissance-log-regression_0/harness-074123-9195491574236033585/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-10-03T07:41:32.529Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-10-03T07:41:32.529Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-10-03T07:41:32.529Z] WARNING: All illegal access operations will be denied in a future release [2024-10-03T07:41:36.045Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-10-03T07:41:36.045Z] GC before operation: completed in 91.339 ms, heap usage 80.797 MB -> 33.774 MB. [2024-10-03T07:41:50.144Z] ====== log-regression (apache-spark) [default], iteration 0 completed (13698.005 ms) ====== [2024-10-03T07:41:50.144Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-10-03T07:41:50.144Z] GC before operation: completed in 112.773 ms, heap usage 291.062 MB -> 112.024 MB. [2024-10-03T07:41:52.689Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3419.895 ms) ====== [2024-10-03T07:41:52.689Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-10-03T07:41:52.689Z] GC before operation: completed in 148.836 ms, heap usage 237.715 MB -> 112.532 MB. [2024-10-03T07:41:56.202Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3283.624 ms) ====== [2024-10-03T07:41:56.202Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-10-03T07:41:56.202Z] GC before operation: completed in 117.331 ms, heap usage 278.009 MB -> 112.905 MB. [2024-10-03T07:41:58.734Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2401.413 ms) ====== [2024-10-03T07:41:58.734Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-10-03T07:41:58.734Z] GC before operation: completed in 114.281 ms, heap usage 242.304 MB -> 113.051 MB. [2024-10-03T07:42:01.271Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2408.357 ms) ====== [2024-10-03T07:42:01.271Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-10-03T07:42:01.271Z] GC before operation: completed in 106.888 ms, heap usage 239.027 MB -> 113.269 MB. [2024-10-03T07:42:03.803Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2353.001 ms) ====== [2024-10-03T07:42:03.803Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-10-03T07:42:03.803Z] GC before operation: completed in 128.206 ms, heap usage 305.958 MB -> 113.624 MB. [2024-10-03T07:42:06.334Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2253.270 ms) ====== [2024-10-03T07:42:06.334Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-10-03T07:42:06.334Z] GC before operation: completed in 128.395 ms, heap usage 238.646 MB -> 113.661 MB. [2024-10-03T07:42:08.881Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2203.770 ms) ====== [2024-10-03T07:42:08.881Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-10-03T07:42:08.881Z] GC before operation: completed in 143.313 ms, heap usage 296.384 MB -> 114.011 MB. [2024-10-03T07:42:10.528Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2256.111 ms) ====== [2024-10-03T07:42:10.529Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-10-03T07:42:11.320Z] GC before operation: completed in 113.261 ms, heap usage 295.377 MB -> 114.199 MB. [2024-10-03T07:42:12.956Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1964.137 ms) ====== [2024-10-03T07:42:12.957Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-10-03T07:42:12.957Z] GC before operation: completed in 110.050 ms, heap usage 267.768 MB -> 114.332 MB. [2024-10-03T07:42:15.487Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1895.350 ms) ====== [2024-10-03T07:42:15.487Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-10-03T07:42:15.487Z] GC before operation: completed in 128.410 ms, heap usage 229.644 MB -> 114.426 MB. [2024-10-03T07:42:17.113Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1883.072 ms) ====== [2024-10-03T07:42:17.113Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-10-03T07:42:17.113Z] GC before operation: completed in 139.022 ms, heap usage 285.668 MB -> 114.760 MB. [2024-10-03T07:42:18.750Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1941.432 ms) ====== [2024-10-03T07:42:18.750Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-10-03T07:42:19.541Z] GC before operation: completed in 143.785 ms, heap usage 247.516 MB -> 114.853 MB. [2024-10-03T07:42:21.172Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2108.732 ms) ====== [2024-10-03T07:42:21.172Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-10-03T07:42:21.172Z] GC before operation: completed in 106.541 ms, heap usage 248.836 MB -> 115.044 MB. [2024-10-03T07:42:23.701Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1915.630 ms) ====== [2024-10-03T07:42:23.701Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-10-03T07:42:23.701Z] GC before operation: completed in 132.858 ms, heap usage 277.258 MB -> 115.252 MB. [2024-10-03T07:42:25.339Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1756.940 ms) ====== [2024-10-03T07:42:25.339Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-10-03T07:42:25.339Z] GC before operation: completed in 150.183 ms, heap usage 240.448 MB -> 115.378 MB. [2024-10-03T07:42:26.979Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1686.131 ms) ====== [2024-10-03T07:42:26.979Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-10-03T07:42:26.979Z] GC before operation: completed in 128.879 ms, heap usage 381.716 MB -> 115.984 MB. [2024-10-03T07:42:28.617Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1714.631 ms) ====== [2024-10-03T07:42:28.617Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-10-03T07:42:29.402Z] GC before operation: completed in 125.518 ms, heap usage 363.649 MB -> 116.124 MB. [2024-10-03T07:42:31.027Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1895.902 ms) ====== [2024-10-03T07:42:31.027Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-10-03T07:42:31.027Z] GC before operation: completed in 193.183 ms, heap usage 248.903 MB -> 116.036 MB. [2024-10-03T07:42:32.911Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1601.043 ms) ====== [2024-10-03T07:42:32.911Z] ----------------------------------- [2024-10-03T07:42:32.911Z] renaissance-log-regression_0_PASSED [2024-10-03T07:42:32.911Z] ----------------------------------- [2024-10-03T07:42:32.911Z] [2024-10-03T07:42:32.911Z] TEST TEARDOWN: [2024-10-03T07:42:32.911Z] Nothing to be done for teardown. [2024-10-03T07:42:32.911Z] renaissance-log-regression_0 Finish Time: Thu Oct 3 07:42:32 2024 Epoch Time (ms): 1727941352706