[2025-02-07T04:19:25.116Z] Running test renaissance-log-regression_0 ... [2025-02-07T04:19:25.116Z] =============================================== [2025-02-07T04:19:25.440Z] renaissance-log-regression_0 Start Time: Fri Feb 7 04:19:25 2025 Epoch Time (ms): 1738901965124 [2025-02-07T04:19:25.440Z] variation: NoOptions [2025-02-07T04:19:25.440Z] JVM_OPTIONS: [2025-02-07T04:19:25.440Z] { \ [2025-02-07T04:19:25.440Z] echo ""; echo "TEST SETUP:"; \ [2025-02-07T04:19:25.440Z] echo "Nothing to be done for setup."; \ [2025-02-07T04:19:25.440Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\renaissance-log-regression_0"; \ [2025-02-07T04:19:25.440Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\renaissance-log-regression_0"; \ [2025-02-07T04:19:25.440Z] echo ""; echo "TESTING:"; \ [2025-02-07T04:19:25.440Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-02-07T04:19:25.440Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-07T04:19:25.440Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-07T04:19:25.440Z] echo "Nothing to be done for teardown."; \ [2025-02-07T04:19:25.440Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\TestTargetResult"; [2025-02-07T04:19:25.440Z] [2025-02-07T04:19:25.440Z] TEST SETUP: [2025-02-07T04:19:25.440Z] Nothing to be done for setup. [2025-02-07T04:19:25.440Z] [2025-02-07T04:19:25.440Z] TESTING: [2025-02-07T04:19:38.350Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-07T04:19:40.543Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-07T04:19:41.660Z] WARNING: An illegal reflective access operation has occurred [2025-02-07T04:19:41.660Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17389007255844/renaissance-log-regression_0/harness-041926-5735091635454798700/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-07T04:19:41.660Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-07T04:19:41.660Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-07T04:19:41.660Z] WARNING: All illegal access operations will be denied in a future release [2025-02-07T04:19:44.523Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-07T04:19:44.523Z] GC before operation: completed in 51.724 ms, heap usage 83.522 MB -> 34.266 MB. [2025-02-07T04:19:53.593Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8370.954 ms) ====== [2025-02-07T04:19:53.593Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-07T04:19:53.593Z] GC before operation: completed in 127.337 ms, heap usage 253.012 MB -> 112.381 MB. [2025-02-07T04:19:56.705Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3593.567 ms) ====== [2025-02-07T04:19:56.705Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-07T04:19:56.705Z] GC before operation: completed in 85.167 ms, heap usage 247.207 MB -> 112.927 MB. [2025-02-07T04:19:58.448Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2188.894 ms) ====== [2025-02-07T04:19:58.448Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-07T04:19:58.808Z] GC before operation: completed in 130.744 ms, heap usage 251.674 MB -> 113.316 MB. [2025-02-07T04:20:00.544Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1975.920 ms) ====== [2025-02-07T04:20:00.544Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-07T04:20:00.878Z] GC before operation: completed in 101.230 ms, heap usage 285.370 MB -> 113.642 MB. [2025-02-07T04:20:02.683Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1874.829 ms) ====== [2025-02-07T04:20:02.683Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-07T04:20:02.683Z] GC before operation: completed in 92.367 ms, heap usage 285.883 MB -> 113.872 MB. [2025-02-07T04:20:04.428Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1546.049 ms) ====== [2025-02-07T04:20:04.428Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-07T04:20:04.428Z] GC before operation: completed in 107.684 ms, heap usage 285.725 MB -> 114.069 MB. [2025-02-07T04:20:06.131Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1695.898 ms) ====== [2025-02-07T04:20:06.131Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-07T04:20:06.131Z] GC before operation: completed in 99.911 ms, heap usage 323.656 MB -> 114.350 MB. [2025-02-07T04:20:07.869Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1596.175 ms) ====== [2025-02-07T04:20:07.870Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-07T04:20:07.870Z] GC before operation: completed in 100.163 ms, heap usage 363.607 MB -> 114.630 MB. [2025-02-07T04:20:09.569Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1588.341 ms) ====== [2025-02-07T04:20:09.569Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-07T04:20:09.569Z] GC before operation: completed in 96.531 ms, heap usage 248.619 MB -> 114.555 MB. [2025-02-07T04:20:11.236Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1574.626 ms) ====== [2025-02-07T04:20:11.236Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-07T04:20:11.236Z] GC before operation: completed in 108.262 ms, heap usage 276.784 MB -> 114.818 MB. [2025-02-07T04:20:12.872Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1540.272 ms) ====== [2025-02-07T04:20:12.872Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-07T04:20:12.872Z] GC before operation: completed in 108.372 ms, heap usage 305.822 MB -> 115.103 MB. [2025-02-07T04:20:14.531Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1510.139 ms) ====== [2025-02-07T04:20:14.531Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-07T04:20:14.531Z] GC before operation: completed in 102.966 ms, heap usage 325.805 MB -> 115.343 MB. [2025-02-07T04:20:16.202Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1614.237 ms) ====== [2025-02-07T04:20:16.202Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-07T04:20:16.202Z] GC before operation: completed in 103.777 ms, heap usage 305.640 MB -> 115.488 MB. [2025-02-07T04:20:17.926Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1611.841 ms) ====== [2025-02-07T04:20:17.926Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-07T04:20:17.926Z] GC before operation: completed in 108.741 ms, heap usage 241.461 MB -> 115.493 MB. [2025-02-07T04:20:19.613Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1493.746 ms) ====== [2025-02-07T04:20:19.613Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-07T04:20:19.613Z] GC before operation: completed in 107.190 ms, heap usage 295.184 MB -> 115.815 MB. [2025-02-07T04:20:21.335Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1504.728 ms) ====== [2025-02-07T04:20:21.335Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-07T04:20:21.335Z] GC before operation: completed in 107.050 ms, heap usage 268.496 MB -> 115.962 MB. [2025-02-07T04:20:22.539Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1469.698 ms) ====== [2025-02-07T04:20:22.539Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-07T04:20:22.946Z] GC before operation: completed in 115.356 ms, heap usage 267.809 MB -> 116.166 MB. [2025-02-07T04:20:24.096Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1443.832 ms) ====== [2025-02-07T04:20:24.096Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-07T04:20:24.484Z] GC before operation: completed in 107.988 ms, heap usage 325.114 MB -> 116.492 MB. [2025-02-07T04:20:26.155Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1505.668 ms) ====== [2025-02-07T04:20:26.155Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-07T04:20:26.155Z] GC before operation: completed in 110.156 ms, heap usage 324.834 MB -> 116.683 MB. [2025-02-07T04:20:27.396Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1532.929 ms) ====== [2025-02-07T04:20:27.723Z] ----------------------------------- [2025-02-07T04:20:27.723Z] renaissance-log-regression_0_PASSED [2025-02-07T04:20:27.723Z] ----------------------------------- [2025-02-07T04:20:28.039Z] [2025-02-07T04:20:28.039Z] TEST TEARDOWN: [2025-02-07T04:20:28.039Z] Nothing to be done for teardown. [2025-02-07T04:20:28.039Z] renaissance-log-regression_0 Finish Time: Fri Feb 7 04:20:27 2025 Epoch Time (ms): 1738902027811