[2025-05-08T10:14:22.348Z] Running test renaissance-log-regression_0 ... [2025-05-08T10:14:22.658Z] =============================================== [2025-05-08T10:14:22.658Z] renaissance-log-regression_0 Start Time: Thu May 8 10:14:22 2025 Epoch Time (ms): 1746699262452 [2025-05-08T10:14:22.658Z] variation: NoOptions [2025-05-08T10:14:22.658Z] JVM_OPTIONS: [2025-05-08T10:14:22.658Z] { \ [2025-05-08T10:14:22.658Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T10:14:22.658Z] echo "Nothing to be done for setup."; \ [2025-05-08T10:14:22.659Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17466972283983\\renaissance-log-regression_0"; \ [2025-05-08T10:14:22.659Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17466972283983\\renaissance-log-regression_0"; \ [2025-05-08T10:14:22.659Z] echo ""; echo "TESTING:"; \ [2025-05-08T10:14:22.659Z] "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_17466972283983\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-05-08T10:14:22.659Z] 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_17466972283983\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T10:14:22.659Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T10:14:22.659Z] echo "Nothing to be done for teardown."; \ [2025-05-08T10:14:22.659Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17466972283983\\TestTargetResult"; [2025-05-08T10:14:22.986Z] [2025-05-08T10:14:22.986Z] TEST SETUP: [2025-05-08T10:14:22.986Z] Nothing to be done for setup. [2025-05-08T10:14:22.986Z] [2025-05-08T10:14:22.986Z] TESTING: [2025-05-08T10:14:35.889Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-08T10:14:37.007Z] WARNING: An illegal reflective access operation has occurred [2025-05-08T10:14:37.007Z] 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_17466972283983/renaissance-log-regression_0/harness-101423-332807078887862907/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-08T10:14:37.007Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-08T10:14:37.007Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-08T10:14:37.007Z] WARNING: All illegal access operations will be denied in a future release [2025-05-08T10:14:38.620Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-08T10:14:38.620Z] GC before operation: completed in 63.395 ms, heap usage 76.803 MB -> 34.911 MB. [2025-05-08T10:14:51.721Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11728.475 ms) ====== [2025-05-08T10:14:51.721Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-08T10:14:51.721Z] GC before operation: completed in 113.024 ms, heap usage 399.858 MB -> 119.241 MB. [2025-05-08T10:14:54.286Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4151.612 ms) ====== [2025-05-08T10:14:54.286Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-08T10:14:54.663Z] GC before operation: completed in 93.806 ms, heap usage 264.280 MB -> 119.406 MB. [2025-05-08T10:14:56.981Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2575.835 ms) ====== [2025-05-08T10:14:56.981Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-08T10:14:56.981Z] GC before operation: completed in 103.248 ms, heap usage 247.301 MB -> 119.726 MB. [2025-05-08T10:14:59.269Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1923.594 ms) ====== [2025-05-08T10:14:59.269Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-08T10:14:59.269Z] GC before operation: completed in 105.239 ms, heap usage 289.041 MB -> 120.126 MB. [2025-05-08T10:15:00.940Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1862.775 ms) ====== [2025-05-08T10:15:00.940Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-08T10:15:01.265Z] GC before operation: completed in 116.675 ms, heap usage 245.909 MB -> 120.195 MB. [2025-05-08T10:15:03.000Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1931.737 ms) ====== [2025-05-08T10:15:03.000Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-08T10:15:03.337Z] GC before operation: completed in 110.159 ms, heap usage 311.991 MB -> 120.642 MB. [2025-05-08T10:15:05.019Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1695.898 ms) ====== [2025-05-08T10:15:05.019Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-08T10:15:05.019Z] GC before operation: completed in 114.567 ms, heap usage 230.603 MB -> 120.613 MB. [2025-05-08T10:15:06.661Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1663.844 ms) ====== [2025-05-08T10:15:06.661Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-08T10:15:06.661Z] GC before operation: completed in 125.335 ms, heap usage 291.423 MB -> 120.973 MB. [2025-05-08T10:15:08.350Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1652.666 ms) ====== [2025-05-08T10:15:08.350Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-08T10:15:08.682Z] GC before operation: completed in 127.518 ms, heap usage 339.136 MB -> 121.238 MB. [2025-05-08T10:15:10.405Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1766.253 ms) ====== [2025-05-08T10:15:10.405Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-08T10:15:10.406Z] GC before operation: completed in 125.240 ms, heap usage 236.750 MB -> 121.190 MB. [2025-05-08T10:15:12.183Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1594.725 ms) ====== [2025-05-08T10:15:12.183Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-08T10:15:12.183Z] GC before operation: completed in 122.996 ms, heap usage 384.373 MB -> 121.806 MB. [2025-05-08T10:15:13.860Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1697.020 ms) ====== [2025-05-08T10:15:13.860Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-08T10:15:13.860Z] GC before operation: completed in 121.929 ms, heap usage 301.543 MB -> 121.763 MB. [2025-05-08T10:15:15.603Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1648.998 ms) ====== [2025-05-08T10:15:15.604Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-08T10:15:16.012Z] GC before operation: completed in 121.099 ms, heap usage 327.822 MB -> 122.043 MB. [2025-05-08T10:15:17.730Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1639.920 ms) ====== [2025-05-08T10:15:17.730Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-08T10:15:17.730Z] GC before operation: completed in 119.015 ms, heap usage 315.729 MB -> 122.236 MB. [2025-05-08T10:15:19.436Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1646.165 ms) ====== [2025-05-08T10:15:19.436Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-08T10:15:19.436Z] GC before operation: completed in 121.996 ms, heap usage 264.546 MB -> 122.234 MB. [2025-05-08T10:15:21.178Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1749.535 ms) ====== [2025-05-08T10:15:21.178Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-08T10:15:21.178Z] GC before operation: completed in 123.704 ms, heap usage 302.310 MB -> 122.556 MB. [2025-05-08T10:15:22.939Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1640.522 ms) ====== [2025-05-08T10:15:22.939Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-08T10:15:22.939Z] GC before operation: completed in 123.306 ms, heap usage 285.477 MB -> 122.777 MB. [2025-05-08T10:15:24.727Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1661.356 ms) ====== [2025-05-08T10:15:24.727Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-08T10:15:24.727Z] GC before operation: completed in 124.446 ms, heap usage 338.553 MB -> 123.065 MB. [2025-05-08T10:15:26.531Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1565.852 ms) ====== [2025-05-08T10:15:26.531Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-08T10:15:26.531Z] GC before operation: completed in 145.950 ms, heap usage 374.419 MB -> 123.428 MB. [2025-05-08T10:15:28.256Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1649.176 ms) ====== [2025-05-08T10:15:28.567Z] ----------------------------------- [2025-05-08T10:15:28.567Z] renaissance-log-regression_0_PASSED [2025-05-08T10:15:28.567Z] ----------------------------------- [2025-05-08T10:15:28.567Z] [2025-05-08T10:15:28.567Z] TEST TEARDOWN: [2025-05-08T10:15:28.567Z] Nothing to be done for teardown. [2025-05-08T10:15:28.567Z] renaissance-log-regression_0 Finish Time: Thu May 8 10:15:28 2025 Epoch Time (ms): 1746699328499