[2025-11-20T17:21:47.922Z] Running test renaissance-log-regression_0 ... [2025-11-20T17:21:47.922Z] =============================================== [2025-11-20T17:21:47.922Z] renaissance-log-regression_0 Start Time: Thu Nov 20 17:21:47 2025 Epoch Time (ms): 1763659307768 [2025-11-20T17:21:47.922Z] variation: NoOptions [2025-11-20T17:21:48.264Z] JVM_OPTIONS: [2025-11-20T17:21:48.264Z] { \ [2025-11-20T17:21:48.264Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T17:21:48.264Z] echo "Nothing to be done for setup."; \ [2025-11-20T17:21:48.264Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\renaissance-log-regression_0"; \ [2025-11-20T17:21:48.264Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\renaissance-log-regression_0"; \ [2025-11-20T17:21:48.264Z] echo ""; echo "TESTING:"; \ [2025-11-20T17:21:48.264Z] "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_17636570004825\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-11-20T17:21:48.264Z] 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_17636570004825\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T17:21:48.264Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T17:21:48.264Z] echo "Nothing to be done for teardown."; \ [2025-11-20T17:21:48.264Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\TestTargetResult"; [2025-11-20T17:21:48.264Z] [2025-11-20T17:21:48.264Z] TEST SETUP: [2025-11-20T17:21:48.264Z] Nothing to be done for setup. [2025-11-20T17:21:48.264Z] [2025-11-20T17:21:48.264Z] TESTING: [2025-11-20T17:22:04.058Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-20T17:22:04.058Z] WARNING: An illegal reflective access operation has occurred [2025-11-20T17:22:04.058Z] 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_17636570004825/renaissance-log-regression_0/harness-172148-14725875019292577753/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-20T17:22:04.058Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-20T17:22:04.058Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-20T17:22:04.058Z] WARNING: All illegal access operations will be denied in a future release [2025-11-20T17:22:05.720Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-20T17:22:05.720Z] GC before operation: completed in 66.222 ms, heap usage 69.498 MB -> 34.909 MB. [2025-11-20T17:22:19.293Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12039.091 ms) ====== [2025-11-20T17:22:19.293Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-20T17:22:19.293Z] GC before operation: completed in 140.306 ms, heap usage 372.872 MB -> 119.150 MB. [2025-11-20T17:22:21.993Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3958.260 ms) ====== [2025-11-20T17:22:21.993Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-20T17:22:21.993Z] GC before operation: completed in 119.661 ms, heap usage 316.894 MB -> 119.584 MB. [2025-11-20T17:22:24.647Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2750.675 ms) ====== [2025-11-20T17:22:24.648Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-20T17:22:24.648Z] GC before operation: completed in 117.825 ms, heap usage 331.876 MB -> 119.982 MB. [2025-11-20T17:22:26.479Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1908.448 ms) ====== [2025-11-20T17:22:26.479Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-20T17:22:26.826Z] GC before operation: completed in 130.219 ms, heap usage 267.545 MB -> 120.059 MB. [2025-11-20T17:22:28.556Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1894.005 ms) ====== [2025-11-20T17:22:28.556Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-20T17:22:28.960Z] GC before operation: completed in 144.384 ms, heap usage 278.281 MB -> 120.295 MB. [2025-11-20T17:22:30.815Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1827.786 ms) ====== [2025-11-20T17:22:30.815Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-20T17:22:30.815Z] GC before operation: completed in 149.271 ms, heap usage 256.656 MB -> 120.399 MB. [2025-11-20T17:22:32.758Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1768.293 ms) ====== [2025-11-20T17:22:32.758Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-20T17:22:32.758Z] GC before operation: completed in 126.884 ms, heap usage 311.014 MB -> 120.761 MB. [2025-11-20T17:22:34.615Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1802.866 ms) ====== [2025-11-20T17:22:34.615Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-20T17:22:34.615Z] GC before operation: completed in 123.866 ms, heap usage 301.653 MB -> 120.958 MB. [2025-11-20T17:22:36.464Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1772.738 ms) ====== [2025-11-20T17:22:36.464Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-20T17:22:36.464Z] GC before operation: completed in 117.227 ms, heap usage 280.740 MB -> 121.092 MB. [2025-11-20T17:22:38.240Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1605.430 ms) ====== [2025-11-20T17:22:38.240Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-20T17:22:38.240Z] GC before operation: completed in 130.021 ms, heap usage 293.704 MB -> 121.381 MB. [2025-11-20T17:22:39.978Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1657.205 ms) ====== [2025-11-20T17:22:39.978Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-20T17:22:39.978Z] GC before operation: completed in 119.606 ms, heap usage 270.894 MB -> 121.462 MB. [2025-11-20T17:22:41.849Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1753.154 ms) ====== [2025-11-20T17:22:41.849Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-20T17:22:41.849Z] GC before operation: completed in 116.621 ms, heap usage 329.301 MB -> 121.827 MB. [2025-11-20T17:22:43.636Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1671.727 ms) ====== [2025-11-20T17:22:43.636Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-20T17:22:43.636Z] GC before operation: completed in 123.807 ms, heap usage 346.082 MB -> 122.109 MB. [2025-11-20T17:22:45.393Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1643.315 ms) ====== [2025-11-20T17:22:45.393Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-20T17:22:45.393Z] GC before operation: completed in 125.382 ms, heap usage 367.137 MB -> 122.402 MB. [2025-11-20T17:22:47.234Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1687.365 ms) ====== [2025-11-20T17:22:47.234Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-20T17:22:47.234Z] GC before operation: completed in 129.208 ms, heap usage 446.495 MB -> 122.769 MB. [2025-11-20T17:22:48.979Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1536.012 ms) ====== [2025-11-20T17:22:48.979Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-20T17:22:48.979Z] GC before operation: completed in 139.696 ms, heap usage 237.378 MB -> 122.396 MB. [2025-11-20T17:22:50.836Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1527.119 ms) ====== [2025-11-20T17:22:50.836Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-20T17:22:50.836Z] GC before operation: completed in 138.972 ms, heap usage 236.947 MB -> 122.614 MB. [2025-11-20T17:22:52.016Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1536.009 ms) ====== [2025-11-20T17:22:52.016Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-20T17:22:52.421Z] GC before operation: completed in 138.690 ms, heap usage 382.772 MB -> 123.222 MB. [2025-11-20T17:22:53.649Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1510.368 ms) ====== [2025-11-20T17:22:53.649Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-20T17:22:54.082Z] GC before operation: completed in 137.570 ms, heap usage 411.061 MB -> 123.497 MB. [2025-11-20T17:22:55.309Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1586.190 ms) ====== [2025-11-20T17:22:55.652Z] ----------------------------------- [2025-11-20T17:22:55.652Z] renaissance-log-regression_0_PASSED [2025-11-20T17:22:55.652Z] ----------------------------------- [2025-11-20T17:22:55.979Z] [2025-11-20T17:22:55.979Z] TEST TEARDOWN: [2025-11-20T17:22:55.979Z] Nothing to be done for teardown. [2025-11-20T17:22:55.980Z] renaissance-log-regression_0 Finish Time: Thu Nov 20 17:22:55 2025 Epoch Time (ms): 1763659375839