[2024-08-17T03:42:06.599Z] Running test renaissance-log-regression_0 ... [2024-08-17T03:42:06.599Z] =============================================== [2024-08-17T03:42:06.599Z] renaissance-log-regression_0 Start Time: Fri Aug 16 22:42:06 2024 Epoch Time (ms): 1723866126175 [2024-08-17T03:42:06.599Z] variation: NoOptions [2024-08-17T03:42:06.599Z] JVM_OPTIONS: [2024-08-17T03:42:06.599Z] { \ [2024-08-17T03:42:06.599Z] echo ""; echo "TEST SETUP:"; \ [2024-08-17T03:42:06.599Z] echo "Nothing to be done for setup."; \ [2024-08-17T03:42:06.599Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17238653631865/renaissance-log-regression_0"; \ [2024-08-17T03:42:06.599Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17238653631865/renaissance-log-regression_0"; \ [2024-08-17T03:42:06.599Z] echo ""; echo "TESTING:"; \ [2024-08-17T03:42:06.599Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-11.0.25+3/bin/..//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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17238653631865/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-17T03:42:06.599Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17238653631865/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-17T03:42:06.599Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-17T03:42:06.599Z] echo "Nothing to be done for teardown."; \ [2024-08-17T03:42:06.599Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17238653631865/TestTargetResult"; [2024-08-17T03:42:06.599Z] [2024-08-17T03:42:06.599Z] TEST SETUP: [2024-08-17T03:42:06.599Z] Nothing to be done for setup. [2024-08-17T03:42:06.599Z] [2024-08-17T03:42:06.599Z] TESTING: [2024-08-17T03:42:09.667Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-17T03:42:11.873Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2024-08-17T03:42:12.553Z] WARNING: An illegal reflective access operation has occurred [2024-08-17T03:42:12.553Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/output_17238653631865/renaissance-log-regression_0/harness-224206-2496527236393160653/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-17T03:42:12.553Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-17T03:42:12.553Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-17T03:42:12.553Z] WARNING: All illegal access operations will be denied in a future release [2024-08-17T03:42:14.751Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-17T03:42:15.433Z] GC before operation: completed in 61.216 ms, heap usage 66.265 MB -> 34.475 MB. [2024-08-17T03:42:24.588Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9276.807 ms) ====== [2024-08-17T03:42:24.588Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-17T03:42:24.588Z] GC before operation: completed in 110.703 ms, heap usage 274.802 MB -> 117.665 MB. [2024-08-17T03:42:27.662Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2627.523 ms) ====== [2024-08-17T03:42:27.662Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-17T03:42:27.662Z] GC before operation: completed in 104.771 ms, heap usage 281.594 MB -> 118.341 MB. [2024-08-17T03:42:29.886Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2226.631 ms) ====== [2024-08-17T03:42:29.886Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-17T03:42:29.886Z] GC before operation: completed in 94.668 ms, heap usage 405.176 MB -> 119.037 MB. [2024-08-17T03:42:32.091Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2176.010 ms) ====== [2024-08-17T03:42:32.091Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-17T03:42:32.091Z] GC before operation: completed in 110.630 ms, heap usage 259.957 MB -> 119.005 MB. [2024-08-17T03:42:34.403Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2025.277 ms) ====== [2024-08-17T03:42:34.403Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-17T03:42:34.403Z] GC before operation: completed in 101.378 ms, heap usage 260.592 MB -> 119.347 MB. [2024-08-17T03:42:36.601Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2191.659 ms) ====== [2024-08-17T03:42:36.601Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-17T03:42:36.601Z] GC before operation: completed in 93.707 ms, heap usage 349.994 MB -> 119.991 MB. [2024-08-17T03:42:38.023Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2024.432 ms) ====== [2024-08-17T03:42:38.024Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-17T03:42:38.722Z] GC before operation: completed in 111.504 ms, heap usage 302.879 MB -> 120.175 MB. [2024-08-17T03:42:40.138Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1984.840 ms) ====== [2024-08-17T03:42:40.138Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-17T03:42:40.823Z] GC before operation: completed in 113.638 ms, heap usage 261.114 MB -> 120.342 MB. [2024-08-17T03:42:43.055Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2121.421 ms) ====== [2024-08-17T03:42:43.055Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-17T03:42:43.055Z] GC before operation: completed in 115.627 ms, heap usage 314.556 MB -> 120.893 MB. [2024-08-17T03:42:45.281Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2201.900 ms) ====== [2024-08-17T03:42:45.281Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-17T03:42:45.281Z] GC before operation: completed in 108.768 ms, heap usage 468.760 MB -> 121.643 MB. [2024-08-17T03:42:47.495Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1992.236 ms) ====== [2024-08-17T03:42:47.495Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-17T03:42:47.495Z] GC before operation: completed in 136.581 ms, heap usage 332.048 MB -> 121.587 MB. [2024-08-17T03:42:48.904Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1785.386 ms) ====== [2024-08-17T03:42:48.904Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-17T03:42:48.904Z] GC before operation: completed in 137.308 ms, heap usage 508.507 MB -> 122.480 MB. [2024-08-17T03:42:51.104Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2068.589 ms) ====== [2024-08-17T03:42:51.104Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-17T03:42:51.104Z] GC before operation: completed in 106.849 ms, heap usage 331.331 MB -> 122.209 MB. [2024-08-17T03:42:53.344Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2024.007 ms) ====== [2024-08-17T03:42:53.344Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-17T03:42:53.344Z] GC before operation: completed in 127.045 ms, heap usage 279.622 MB -> 122.463 MB. [2024-08-17T03:42:55.554Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1788.049 ms) ====== [2024-08-17T03:42:55.554Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-17T03:42:55.554Z] GC before operation: completed in 121.771 ms, heap usage 305.128 MB -> 122.902 MB. [2024-08-17T03:42:57.803Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2130.324 ms) ====== [2024-08-17T03:42:57.803Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-17T03:42:57.803Z] GC before operation: completed in 139.240 ms, heap usage 334.282 MB -> 123.290 MB. [2024-08-17T03:43:00.022Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2028.165 ms) ====== [2024-08-17T03:43:00.022Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-17T03:43:00.022Z] GC before operation: completed in 107.826 ms, heap usage 265.136 MB -> 123.400 MB. [2024-08-17T03:43:01.437Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2000.709 ms) ====== [2024-08-17T03:43:01.437Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-17T03:43:02.121Z] GC before operation: completed in 110.409 ms, heap usage 298.328 MB -> 123.845 MB. [2024-08-17T03:43:04.330Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2068.372 ms) ====== [2024-08-17T03:43:04.330Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-17T03:43:04.330Z] GC before operation: completed in 144.367 ms, heap usage 340.933 MB -> 124.392 MB. [2024-08-17T03:43:06.181Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2086.437 ms) ====== [2024-08-17T03:43:06.877Z] ----------------------------------- [2024-08-17T03:43:06.877Z] renaissance-log-regression_0_PASSED [2024-08-17T03:43:06.877Z] ----------------------------------- [2024-08-17T03:43:06.877Z] [2024-08-17T03:43:06.877Z] TEST TEARDOWN: [2024-08-17T03:43:06.877Z] Nothing to be done for teardown. [2024-08-17T03:43:06.877Z] renaissance-log-regression_0 Finish Time: Fri Aug 16 22:43:06 2024 Epoch Time (ms): 1723866186384