[2025-12-11T01:07:42.694Z] Running test renaissance-log-regression_0 ... [2025-12-11T01:07:42.694Z] =============================================== [2025-12-11T01:07:42.694Z] renaissance-log-regression_0 Start Time: Thu Dec 11 01:07:42 2025 Epoch Time (ms): 1765415262632 [2025-12-11T01:07:43.028Z] variation: NoOptions [2025-12-11T01:07:43.028Z] JVM_OPTIONS: [2025-12-11T01:07:43.028Z] { \ [2025-12-11T01:07:43.028Z] echo ""; echo "TEST SETUP:"; \ [2025-12-11T01:07:43.028Z] echo "Nothing to be done for setup."; \ [2025-12-11T01:07:43.028Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-log-regression_0"; \ [2025-12-11T01:07:43.028Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-log-regression_0"; \ [2025-12-11T01:07:43.028Z] echo ""; echo "TESTING:"; \ [2025-12-11T01:07:43.028Z] "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_17654126173741\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-12-11T01:07:43.028Z] 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_17654126173741\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-11T01:07:43.028Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-11T01:07:43.028Z] echo "Nothing to be done for teardown."; \ [2025-12-11T01:07:43.028Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\TestTargetResult"; [2025-12-11T01:07:43.028Z] [2025-12-11T01:07:43.028Z] TEST SETUP: [2025-12-11T01:07:43.028Z] Nothing to be done for setup. [2025-12-11T01:07:43.028Z] [2025-12-11T01:07:43.028Z] TESTING: [2025-12-11T01:08:02.132Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-11T01:08:02.132Z] WARNING: An illegal reflective access operation has occurred [2025-12-11T01:08:02.132Z] 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_17654126173741/renaissance-log-regression_0/harness-010744-10207474867500779162/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-12-11T01:08:02.132Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-12-11T01:08:02.132Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-12-11T01:08:02.132Z] WARNING: All illegal access operations will be denied in a future release [2025-12-11T01:08:02.952Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-11T01:08:02.952Z] GC before operation: completed in 83.004 ms, heap usage 63.963 MB -> 34.553 MB. [2025-12-11T01:08:19.849Z] ====== log-regression (apache-spark) [default], iteration 0 completed (16941.866 ms) ====== [2025-12-11T01:08:19.849Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-11T01:08:20.216Z] GC before operation: completed in 191.869 ms, heap usage 255.718 MB -> 117.003 MB. [2025-12-11T01:08:29.249Z] ====== log-regression (apache-spark) [default], iteration 1 completed (8679.565 ms) ====== [2025-12-11T01:08:29.249Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-11T01:08:29.249Z] GC before operation: completed in 162.003 ms, heap usage 232.600 MB -> 116.024 MB. [2025-12-11T01:08:32.645Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3934.713 ms) ====== [2025-12-11T01:08:32.645Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-11T01:08:33.255Z] GC before operation: completed in 219.976 ms, heap usage 320.310 MB -> 116.680 MB. [2025-12-11T01:08:36.544Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3378.643 ms) ====== [2025-12-11T01:08:36.544Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-11T01:08:36.544Z] GC before operation: completed in 160.186 ms, heap usage 249.562 MB -> 116.732 MB. [2025-12-11T01:08:40.102Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3403.475 ms) ====== [2025-12-11T01:08:40.102Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-11T01:08:40.102Z] GC before operation: completed in 168.616 ms, heap usage 241.247 MB -> 116.925 MB. [2025-12-11T01:08:44.029Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3433.468 ms) ====== [2025-12-11T01:08:44.029Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-11T01:08:44.029Z] GC before operation: completed in 179.188 ms, heap usage 260.232 MB -> 117.196 MB. [2025-12-11T01:08:47.646Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3895.913 ms) ====== [2025-12-11T01:08:47.646Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-11T01:08:48.017Z] GC before operation: completed in 180.390 ms, heap usage 249.515 MB -> 117.365 MB. [2025-12-11T01:08:51.529Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3386.326 ms) ====== [2025-12-11T01:08:51.529Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-11T01:08:51.529Z] GC before operation: completed in 176.168 ms, heap usage 304.275 MB -> 117.720 MB. [2025-12-11T01:08:55.129Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3255.897 ms) ====== [2025-12-11T01:08:55.129Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-11T01:08:55.129Z] GC before operation: completed in 183.786 ms, heap usage 279.948 MB -> 117.832 MB. [2025-12-11T01:08:58.380Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3211.729 ms) ====== [2025-12-11T01:08:58.380Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-11T01:08:58.380Z] GC before operation: completed in 163.047 ms, heap usage 281.019 MB -> 118.000 MB. [2025-12-11T01:09:01.565Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2706.069 ms) ====== [2025-12-11T01:09:01.565Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-11T01:09:01.565Z] GC before operation: completed in 175.574 ms, heap usage 258.667 MB -> 118.101 MB. [2025-12-11T01:09:04.914Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3451.468 ms) ====== [2025-12-11T01:09:04.914Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-11T01:09:04.914Z] GC before operation: completed in 258.601 ms, heap usage 226.512 MB -> 118.198 MB. [2025-12-11T01:09:08.321Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3499.260 ms) ====== [2025-12-11T01:09:08.321Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-11T01:09:08.665Z] GC before operation: completed in 182.411 ms, heap usage 246.954 MB -> 118.368 MB. [2025-12-11T01:09:11.196Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2242.810 ms) ====== [2025-12-11T01:09:11.196Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-11T01:09:11.196Z] GC before operation: completed in 180.069 ms, heap usage 240.134 MB -> 118.627 MB. [2025-12-11T01:09:12.973Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1971.279 ms) ====== [2025-12-11T01:09:12.973Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-11T01:09:12.973Z] GC before operation: completed in 189.269 ms, heap usage 454.889 MB -> 119.624 MB. [2025-12-11T01:09:16.144Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2992.925 ms) ====== [2025-12-11T01:09:16.144Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-11T01:09:16.144Z] GC before operation: completed in 200.299 ms, heap usage 289.768 MB -> 119.078 MB. [2025-12-11T01:09:20.338Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3304.577 ms) ====== [2025-12-11T01:09:20.339Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-11T01:09:20.339Z] GC before operation: completed in 197.585 ms, heap usage 226.882 MB -> 119.101 MB. [2025-12-11T01:09:22.894Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3073.646 ms) ====== [2025-12-11T01:09:22.894Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-11T01:09:23.241Z] GC before operation: completed in 231.155 ms, heap usage 258.396 MB -> 119.381 MB. [2025-12-11T01:09:26.592Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3147.488 ms) ====== [2025-12-11T01:09:26.592Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-11T01:09:26.592Z] GC before operation: completed in 186.717 ms, heap usage 280.539 MB -> 119.622 MB. [2025-12-11T01:09:28.902Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2605.704 ms) ====== [2025-12-11T01:09:29.339Z] ----------------------------------- [2025-12-11T01:09:29.339Z] renaissance-log-regression_0_PASSED [2025-12-11T01:09:29.339Z] ----------------------------------- [2025-12-11T01:09:29.684Z] [2025-12-11T01:09:29.684Z] TEST TEARDOWN: [2025-12-11T01:09:29.684Z] Nothing to be done for teardown. [2025-12-11T01:09:29.684Z] renaissance-log-regression_0 Finish Time: Thu Dec 11 01:09:29 2025 Epoch Time (ms): 1765415369534