[2025-02-06T02:58:13.630Z] Running test renaissance-log-regression_0 ... [2025-02-06T02:58:13.630Z] =============================================== [2025-02-06T02:58:13.630Z] renaissance-log-regression_0 Start Time: Thu Feb 6 02:58:13 2025 Epoch Time (ms): 1738810693388 [2025-02-06T02:58:13.630Z] variation: NoOptions [2025-02-06T02:58:13.630Z] JVM_OPTIONS: [2025-02-06T02:58:13.630Z] { \ [2025-02-06T02:58:13.630Z] echo ""; echo "TEST SETUP:"; \ [2025-02-06T02:58:13.630Z] echo "Nothing to be done for setup."; \ [2025-02-06T02:58:13.630Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1738810181584/renaissance-log-regression_0"; \ [2025-02-06T02:58:13.630Z] cd "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1738810181584/renaissance-log-regression_0"; \ [2025-02-06T02:58:13.630Z] echo ""; echo "TESTING:"; \ [2025-02-06T02:58:13.630Z] "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/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 "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1738810181584/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-06T02:58:13.630Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1738810181584/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-06T02:58:13.630Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-06T02:58:13.630Z] echo "Nothing to be done for teardown."; \ [2025-02-06T02:58:13.630Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1738810181584/TestTargetResult"; [2025-02-06T02:58:13.630Z] [2025-02-06T02:58:13.630Z] TEST SETUP: [2025-02-06T02:58:13.630Z] Nothing to be done for setup. [2025-02-06T02:58:13.630Z] [2025-02-06T02:58:13.630Z] TESTING: [2025-02-06T02:58:17.604Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-06T02:58:19.371Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-02-06T02:58:21.141Z] WARNING: An illegal reflective access operation has occurred [2025-02-06T02:58:21.141Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/output_1738810181584/renaissance-log-regression_0/harness-025814-8192942642030054530/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-06T02:58:21.141Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-06T02:58:21.141Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-06T02:58:21.141Z] WARNING: All illegal access operations will be denied in a future release [2025-02-06T02:58:23.537Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-06T02:58:23.537Z] GC before operation: completed in 39.371 ms, heap usage 68.479 MB -> 34.438 MB. [2025-02-06T02:58:37.054Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12077.752 ms) ====== [2025-02-06T02:58:37.054Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-06T02:58:37.054Z] GC before operation: completed in 86.413 ms, heap usage 410.842 MB -> 117.773 MB. [2025-02-06T02:58:40.183Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4256.495 ms) ====== [2025-02-06T02:58:40.183Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-06T02:58:40.183Z] GC before operation: completed in 91.956 ms, heap usage 313.085 MB -> 118.314 MB. [2025-02-06T02:58:44.175Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3790.668 ms) ====== [2025-02-06T02:58:44.175Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-06T02:58:44.175Z] GC before operation: completed in 100.208 ms, heap usage 358.030 MB -> 118.651 MB. [2025-02-06T02:58:47.297Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3505.335 ms) ====== [2025-02-06T02:58:47.297Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-06T02:58:47.297Z] GC before operation: completed in 100.338 ms, heap usage 534.080 MB -> 119.088 MB. [2025-02-06T02:58:51.289Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3247.916 ms) ====== [2025-02-06T02:58:51.289Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-06T02:58:51.289Z] GC before operation: completed in 95.773 ms, heap usage 401.780 MB -> 119.344 MB. [2025-02-06T02:58:54.422Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3250.485 ms) ====== [2025-02-06T02:58:54.422Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-06T02:58:54.422Z] GC before operation: completed in 115.589 ms, heap usage 738.375 MB -> 119.794 MB. [2025-02-06T02:58:57.566Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3251.050 ms) ====== [2025-02-06T02:58:57.566Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-06T02:58:57.566Z] GC before operation: completed in 131.290 ms, heap usage 351.693 MB -> 120.065 MB. [2025-02-06T02:59:01.585Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3480.271 ms) ====== [2025-02-06T02:59:01.585Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-06T02:59:01.585Z] GC before operation: completed in 133.775 ms, heap usage 484.478 MB -> 120.378 MB. [2025-02-06T02:59:04.711Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3336.612 ms) ====== [2025-02-06T02:59:04.711Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-06T02:59:04.711Z] GC before operation: completed in 132.232 ms, heap usage 463.122 MB -> 120.727 MB. [2025-02-06T02:59:07.845Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3194.044 ms) ====== [2025-02-06T02:59:07.845Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-06T02:59:07.845Z] GC before operation: completed in 113.385 ms, heap usage 335.561 MB -> 121.066 MB. [2025-02-06T02:59:10.968Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3207.512 ms) ====== [2025-02-06T02:59:10.968Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-06T02:59:11.324Z] GC before operation: completed in 114.057 ms, heap usage 667.058 MB -> 121.411 MB. [2025-02-06T02:59:14.460Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3154.438 ms) ====== [2025-02-06T02:59:14.460Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-06T02:59:14.460Z] GC before operation: completed in 116.815 ms, heap usage 678.808 MB -> 121.812 MB. [2025-02-06T02:59:17.611Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3052.938 ms) ====== [2025-02-06T02:59:17.611Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-06T02:59:17.611Z] GC before operation: completed in 123.001 ms, heap usage 589.149 MB -> 122.096 MB. [2025-02-06T02:59:20.751Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3200.585 ms) ====== [2025-02-06T02:59:20.751Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-06T02:59:21.147Z] GC before operation: completed in 127.606 ms, heap usage 621.922 MB -> 122.409 MB. [2025-02-06T02:59:24.297Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3067.653 ms) ====== [2025-02-06T02:59:24.297Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-06T02:59:24.297Z] GC before operation: completed in 119.659 ms, heap usage 590.929 MB -> 122.751 MB. [2025-02-06T02:59:27.480Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3084.748 ms) ====== [2025-02-06T02:59:27.480Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-06T02:59:27.480Z] GC before operation: completed in 109.144 ms, heap usage 716.470 MB -> 123.200 MB. [2025-02-06T02:59:30.625Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3189.793 ms) ====== [2025-02-06T02:59:30.625Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-06T02:59:30.625Z] GC before operation: completed in 127.888 ms, heap usage 508.016 MB -> 123.357 MB. [2025-02-06T02:59:33.763Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3140.755 ms) ====== [2025-02-06T02:59:33.763Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-06T02:59:34.120Z] GC before operation: completed in 118.369 ms, heap usage 313.697 MB -> 123.757 MB. [2025-02-06T02:59:37.246Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3094.741 ms) ====== [2025-02-06T02:59:37.246Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-06T02:59:37.246Z] GC before operation: completed in 121.638 ms, heap usage 461.250 MB -> 124.039 MB. [2025-02-06T02:59:40.390Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3120.095 ms) ====== [2025-02-06T02:59:40.749Z] ----------------------------------- [2025-02-06T02:59:40.749Z] renaissance-log-regression_0_PASSED [2025-02-06T02:59:40.749Z] ----------------------------------- [2025-02-06T02:59:40.749Z] [2025-02-06T02:59:40.749Z] TEST TEARDOWN: [2025-02-06T02:59:40.749Z] Nothing to be done for teardown. [2025-02-06T02:59:40.749Z] renaissance-log-regression_0 Finish Time: Thu Feb 6 02:59:40 2025 Epoch Time (ms): 1738810780481