[2025-03-06T00:49:16.201Z] Running test renaissance-log-regression_0 ... [2025-03-06T00:49:16.519Z] =============================================== [2025-03-06T00:49:16.519Z] renaissance-log-regression_0 Start Time: Thu Mar 6 00:49:16 2025 Epoch Time (ms): 1741222156301 [2025-03-06T00:49:16.519Z] variation: NoOptions [2025-03-06T00:49:16.519Z] JVM_OPTIONS: [2025-03-06T00:49:16.519Z] { \ [2025-03-06T00:49:16.519Z] echo ""; echo "TEST SETUP:"; \ [2025-03-06T00:49:16.519Z] echo "Nothing to be done for setup."; \ [2025-03-06T00:49:16.519Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\renaissance-log-regression_0"; \ [2025-03-06T00:49:16.519Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\renaissance-log-regression_0"; \ [2025-03-06T00:49:16.519Z] echo ""; echo "TESTING:"; \ [2025-03-06T00:49:16.519Z] "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_17412209919842\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-03-06T00:49:16.519Z] 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_17412209919842\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-06T00:49:16.519Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-06T00:49:16.519Z] echo "Nothing to be done for teardown."; \ [2025-03-06T00:49:16.519Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\TestTargetResult"; [2025-03-06T00:49:16.838Z] [2025-03-06T00:49:16.838Z] TEST SETUP: [2025-03-06T00:49:16.838Z] Nothing to be done for setup. [2025-03-06T00:49:16.838Z] [2025-03-06T00:49:16.838Z] TESTING: [2025-03-06T00:49:29.822Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-03-06T00:49:31.465Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-06T00:49:32.595Z] WARNING: An illegal reflective access operation has occurred [2025-03-06T00:49:32.595Z] 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_17412209919842/renaissance-log-regression_0/harness-004917-5701705409464124479/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-03-06T00:49:32.595Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-06T00:49:32.595Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-06T00:49:32.595Z] WARNING: All illegal access operations will be denied in a future release [2025-03-06T00:49:34.947Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-03-06T00:49:34.947Z] GC before operation: completed in 49.938 ms, heap usage 87.172 MB -> 34.264 MB. [2025-03-06T00:49:44.073Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8470.356 ms) ====== [2025-03-06T00:49:44.073Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-03-06T00:49:44.073Z] GC before operation: completed in 112.406 ms, heap usage 303.102 MB -> 112.492 MB. [2025-03-06T00:49:47.289Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3234.176 ms) ====== [2025-03-06T00:49:47.289Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-03-06T00:49:47.289Z] GC before operation: completed in 87.604 ms, heap usage 228.368 MB -> 112.917 MB. [2025-03-06T00:49:48.992Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1807.172 ms) ====== [2025-03-06T00:49:48.992Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-03-06T00:49:48.992Z] GC before operation: completed in 114.760 ms, heap usage 291.304 MB -> 113.445 MB. [2025-03-06T00:49:50.643Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1662.935 ms) ====== [2025-03-06T00:49:50.643Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-03-06T00:49:50.643Z] GC before operation: completed in 119.528 ms, heap usage 372.181 MB -> 113.853 MB. [2025-03-06T00:49:53.067Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1972.640 ms) ====== [2025-03-06T00:49:53.067Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-03-06T00:49:53.067Z] GC before operation: completed in 110.672 ms, heap usage 260.383 MB -> 113.812 MB. [2025-03-06T00:49:54.294Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1505.656 ms) ====== [2025-03-06T00:49:54.294Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-03-06T00:49:54.294Z] GC before operation: completed in 111.287 ms, heap usage 335.361 MB -> 114.159 MB. [2025-03-06T00:49:56.010Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1483.252 ms) ====== [2025-03-06T00:49:56.010Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-03-06T00:49:56.010Z] GC before operation: completed in 102.690 ms, heap usage 355.243 MB -> 114.394 MB. [2025-03-06T00:49:57.230Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1459.507 ms) ====== [2025-03-06T00:49:57.230Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-03-06T00:49:57.671Z] GC before operation: completed in 108.481 ms, heap usage 281.563 MB -> 114.399 MB. [2025-03-06T00:49:58.923Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1578.462 ms) ====== [2025-03-06T00:49:58.923Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-03-06T00:49:59.341Z] GC before operation: completed in 110.227 ms, heap usage 239.605 MB -> 114.514 MB. [2025-03-06T00:50:00.573Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1541.862 ms) ====== [2025-03-06T00:50:00.573Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-03-06T00:50:01.041Z] GC before operation: completed in 102.993 ms, heap usage 258.688 MB -> 114.758 MB. [2025-03-06T00:50:02.232Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1489.493 ms) ====== [2025-03-06T00:50:02.232Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-03-06T00:50:02.232Z] GC before operation: completed in 110.359 ms, heap usage 325.729 MB -> 115.129 MB. [2025-03-06T00:50:04.009Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1454.468 ms) ====== [2025-03-06T00:50:04.009Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-03-06T00:50:04.009Z] GC before operation: completed in 111.923 ms, heap usage 315.580 MB -> 115.302 MB. [2025-03-06T00:50:05.663Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1503.560 ms) ====== [2025-03-06T00:50:05.663Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-03-06T00:50:05.663Z] GC before operation: completed in 104.489 ms, heap usage 259.612 MB -> 115.312 MB. [2025-03-06T00:50:07.446Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1510.707 ms) ====== [2025-03-06T00:50:07.446Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-03-06T00:50:07.447Z] GC before operation: completed in 104.082 ms, heap usage 344.447 MB -> 115.735 MB. [2025-03-06T00:50:08.710Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1441.117 ms) ====== [2025-03-06T00:50:08.710Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-03-06T00:50:08.710Z] GC before operation: completed in 100.459 ms, heap usage 297.281 MB -> 115.783 MB. [2025-03-06T00:50:09.894Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1285.056 ms) ====== [2025-03-06T00:50:09.894Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-03-06T00:50:10.253Z] GC before operation: completed in 110.980 ms, heap usage 287.383 MB -> 116.013 MB. [2025-03-06T00:50:11.383Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1344.587 ms) ====== [2025-03-06T00:50:11.383Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-03-06T00:50:11.794Z] GC before operation: completed in 133.543 ms, heap usage 294.162 MB -> 116.224 MB. [2025-03-06T00:50:12.972Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1440.612 ms) ====== [2025-03-06T00:50:12.972Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-03-06T00:50:13.316Z] GC before operation: completed in 127.223 ms, heap usage 316.482 MB -> 116.461 MB. [2025-03-06T00:50:14.486Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1347.905 ms) ====== [2025-03-06T00:50:14.486Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-03-06T00:50:14.486Z] GC before operation: completed in 99.661 ms, heap usage 306.509 MB -> 116.608 MB. [2025-03-06T00:50:16.224Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1433.809 ms) ====== [2025-03-06T00:50:16.224Z] ----------------------------------- [2025-03-06T00:50:16.224Z] renaissance-log-regression_0_PASSED [2025-03-06T00:50:16.224Z] ----------------------------------- [2025-03-06T00:50:16.557Z] [2025-03-06T00:50:16.557Z] TEST TEARDOWN: [2025-03-06T00:50:16.557Z] Nothing to be done for teardown. [2025-03-06T00:50:16.557Z] renaissance-log-regression_0 Finish Time: Thu Mar 6 00:50:16 2025 Epoch Time (ms): 1741222216404