[2024-10-02T21:30:56.484Z] Running test renaissance-log-regression_0 ... [2024-10-02T21:30:56.484Z] =============================================== [2024-10-02T21:30:56.484Z] renaissance-log-regression_0 Start Time: Wed Oct 2 21:30:55 2024 Epoch Time (ms): 1727904655771 [2024-10-02T21:30:56.484Z] variation: NoOptions [2024-10-02T21:30:56.484Z] JVM_OPTIONS: [2024-10-02T21:30:56.484Z] { \ [2024-10-02T21:30:56.484Z] echo ""; echo "TEST SETUP:"; \ [2024-10-02T21:30:56.484Z] echo "Nothing to be done for setup."; \ [2024-10-02T21:30:56.484Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17279037051102/renaissance-log-regression_0"; \ [2024-10-02T21:30:56.484Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17279037051102/renaissance-log-regression_0"; \ [2024-10-02T21:30:56.484Z] echo ""; echo "TESTING:"; \ [2024-10-02T21:30:56.484Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17279037051102/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-10-02T21:30:56.484Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17279037051102/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-02T21:30:56.484Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-02T21:30:56.484Z] echo "Nothing to be done for teardown."; \ [2024-10-02T21:30:56.484Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17279037051102/TestTargetResult"; [2024-10-02T21:30:56.484Z] [2024-10-02T21:30:56.484Z] TEST SETUP: [2024-10-02T21:30:56.484Z] Nothing to be done for setup. [2024-10-02T21:30:56.484Z] [2024-10-02T21:30:56.484Z] TESTING: [2024-10-02T21:31:00.570Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-02T21:31:04.636Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2024-10-02T21:31:07.587Z] WARNING: An illegal reflective access operation has occurred [2024-10-02T21:31:07.587Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17279037051102/renaissance-log-regression_0/harness-213057-7184599218544921051/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-10-02T21:31:07.587Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-10-02T21:31:07.587Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-10-02T21:31:07.587Z] WARNING: All illegal access operations will be denied in a future release [2024-10-02T21:31:12.856Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-10-02T21:31:12.856Z] GC before operation: completed in 80.196 ms, heap usage 69.616 MB -> 34.435 MB. [2024-10-02T21:31:28.383Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14874.501 ms) ====== [2024-10-02T21:31:28.383Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-10-02T21:31:28.383Z] GC before operation: completed in 258.797 ms, heap usage 855.461 MB -> 119.317 MB. [2024-10-02T21:31:32.451Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4264.802 ms) ====== [2024-10-02T21:31:32.451Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-10-02T21:31:32.451Z] GC before operation: completed in 156.349 ms, heap usage 284.943 MB -> 118.356 MB. [2024-10-02T21:31:36.528Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4108.448 ms) ====== [2024-10-02T21:31:36.528Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-10-02T21:31:36.528Z] GC before operation: completed in 217.945 ms, heap usage 331.542 MB -> 118.806 MB. [2024-10-02T21:31:39.489Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3386.129 ms) ====== [2024-10-02T21:31:39.489Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-10-02T21:31:39.489Z] GC before operation: completed in 169.396 ms, heap usage 363.304 MB -> 119.266 MB. [2024-10-02T21:31:43.569Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3440.498 ms) ====== [2024-10-02T21:31:43.569Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-10-02T21:31:43.569Z] GC before operation: completed in 176.526 ms, heap usage 305.358 MB -> 119.515 MB. [2024-10-02T21:31:46.522Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2998.133 ms) ====== [2024-10-02T21:31:46.522Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-10-02T21:31:46.522Z] GC before operation: completed in 169.560 ms, heap usage 371.868 MB -> 120.030 MB. [2024-10-02T21:31:49.485Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3066.859 ms) ====== [2024-10-02T21:31:49.485Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-10-02T21:31:50.417Z] GC before operation: completed in 180.833 ms, heap usage 280.563 MB -> 120.086 MB. [2024-10-02T21:31:53.375Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3116.827 ms) ====== [2024-10-02T21:31:53.375Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-10-02T21:31:53.375Z] GC before operation: completed in 175.754 ms, heap usage 313.969 MB -> 120.566 MB. [2024-10-02T21:31:56.326Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2750.721 ms) ====== [2024-10-02T21:31:56.327Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-10-02T21:31:56.327Z] GC before operation: completed in 181.459 ms, heap usage 315.130 MB -> 120.876 MB. [2024-10-02T21:31:59.280Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2782.623 ms) ====== [2024-10-02T21:31:59.280Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-10-02T21:31:59.280Z] GC before operation: completed in 189.790 ms, heap usage 370.705 MB -> 121.420 MB. [2024-10-02T21:32:02.235Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2819.333 ms) ====== [2024-10-02T21:32:02.235Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-10-02T21:32:02.235Z] GC before operation: completed in 181.073 ms, heap usage 279.548 MB -> 121.464 MB. [2024-10-02T21:32:05.237Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2616.356 ms) ====== [2024-10-02T21:32:05.237Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-10-02T21:32:05.237Z] GC before operation: completed in 202.032 ms, heap usage 372.199 MB -> 122.060 MB. [2024-10-02T21:32:08.189Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2780.436 ms) ====== [2024-10-02T21:32:08.189Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-10-02T21:32:08.189Z] GC before operation: completed in 195.340 ms, heap usage 476.332 MB -> 122.703 MB. [2024-10-02T21:32:11.179Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2763.973 ms) ====== [2024-10-02T21:32:11.180Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-10-02T21:32:11.180Z] GC before operation: completed in 203.839 ms, heap usage 247.420 MB -> 122.346 MB. [2024-10-02T21:32:13.095Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2564.362 ms) ====== [2024-10-02T21:32:13.095Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-10-02T21:32:14.090Z] GC before operation: completed in 202.458 ms, heap usage 385.193 MB -> 123.092 MB. [2024-10-02T21:32:17.058Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2840.835 ms) ====== [2024-10-02T21:32:17.058Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-10-02T21:32:17.058Z] GC before operation: completed in 194.588 ms, heap usage 282.137 MB -> 123.125 MB. [2024-10-02T21:32:20.022Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2702.566 ms) ====== [2024-10-02T21:32:20.022Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-10-02T21:32:20.022Z] GC before operation: completed in 197.114 ms, heap usage 304.365 MB -> 123.614 MB. [2024-10-02T21:32:21.940Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2285.938 ms) ====== [2024-10-02T21:32:21.940Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-10-02T21:32:21.940Z] GC before operation: completed in 212.819 ms, heap usage 316.501 MB -> 123.965 MB. [2024-10-02T21:32:24.899Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2708.822 ms) ====== [2024-10-02T21:32:24.899Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-10-02T21:32:24.899Z] GC before operation: completed in 209.708 ms, heap usage 299.708 MB -> 124.151 MB. [2024-10-02T21:32:27.860Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2559.435 ms) ====== [2024-10-02T21:32:27.860Z] ----------------------------------- [2024-10-02T21:32:27.860Z] renaissance-log-regression_0_PASSED [2024-10-02T21:32:27.860Z] ----------------------------------- [2024-10-02T21:32:27.860Z] [2024-10-02T21:32:27.860Z] TEST TEARDOWN: [2024-10-02T21:32:27.860Z] Nothing to be done for teardown. [2024-10-02T21:32:27.860Z] renaissance-log-regression_0 Finish Time: Wed Oct 2 21:32:27 2024 Epoch Time (ms): 1727904747718