[2024-08-16T16:08:13.393Z] Running test renaissance-log-regression_0 ... [2024-08-16T16:08:13.393Z] =============================================== [2024-08-16T16:08:13.393Z] renaissance-log-regression_0 Start Time: Fri Aug 16 12:08:13 2024 Epoch Time (ms): 1723824493074 [2024-08-16T16:08:13.393Z] variation: NoOptions [2024-08-16T16:08:13.393Z] JVM_OPTIONS: [2024-08-16T16:08:13.393Z] { \ [2024-08-16T16:08:13.393Z] echo ""; echo "TEST SETUP:"; \ [2024-08-16T16:08:13.393Z] echo "Nothing to be done for setup."; \ [2024-08-16T16:08:13.393Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17238242051455/renaissance-log-regression_0"; \ [2024-08-16T16:08:13.393Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17238242051455/renaissance-log-regression_0"; \ [2024-08-16T16:08:13.393Z] echo ""; echo "TESTING:"; \ [2024-08-16T16:08:13.393Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/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/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17238242051455/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-16T16:08:13.393Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17238242051455/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-16T16:08:13.393Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-16T16:08:13.393Z] echo "Nothing to be done for teardown."; \ [2024-08-16T16:08:13.393Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17238242051455/TestTargetResult"; [2024-08-16T16:08:13.393Z] [2024-08-16T16:08:13.393Z] TEST SETUP: [2024-08-16T16:08:13.393Z] Nothing to be done for setup. [2024-08-16T16:08:13.393Z] [2024-08-16T16:08:13.393Z] TESTING: [2024-08-16T16:08:15.154Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-16T16:08:15.916Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-16T16:08:16.668Z] WARNING: An illegal reflective access operation has occurred [2024-08-16T16:08:16.668Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/output_17238242051455/renaissance-log-regression_0/harness-120813-15035131214037751892/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-16T16:08:16.668Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-16T16:08:16.668Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-16T16:08:16.668Z] WARNING: All illegal access operations will be denied in a future release [2024-08-16T16:08:17.471Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-16T16:08:17.471Z] GC before operation: completed in 25.753 ms, heap usage 52.646 MB -> 33.868 MB. [2024-08-16T16:08:21.421Z] ====== log-regression (apache-spark) [default], iteration 0 completed (3636.867 ms) ====== [2024-08-16T16:08:21.421Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-16T16:08:21.421Z] GC before operation: completed in 39.006 ms, heap usage 287.200 MB -> 109.476 MB. [2024-08-16T16:08:22.203Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1048.028 ms) ====== [2024-08-16T16:08:22.203Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-16T16:08:22.203Z] GC before operation: completed in 37.793 ms, heap usage 244.636 MB -> 109.893 MB. [2024-08-16T16:08:22.965Z] ====== log-regression (apache-spark) [default], iteration 2 completed (803.632 ms) ====== [2024-08-16T16:08:22.965Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-16T16:08:22.965Z] GC before operation: completed in 38.246 ms, heap usage 247.915 MB -> 110.315 MB. [2024-08-16T16:08:23.710Z] ====== log-regression (apache-spark) [default], iteration 3 completed (717.855 ms) ====== [2024-08-16T16:08:23.711Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-16T16:08:23.711Z] GC before operation: completed in 36.250 ms, heap usage 227.065 MB -> 110.490 MB. [2024-08-16T16:08:24.481Z] ====== log-regression (apache-spark) [default], iteration 4 completed (762.960 ms) ====== [2024-08-16T16:08:24.481Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-16T16:08:24.481Z] GC before operation: completed in 37.210 ms, heap usage 236.262 MB -> 110.692 MB. [2024-08-16T16:08:25.250Z] ====== log-regression (apache-spark) [default], iteration 5 completed (678.321 ms) ====== [2024-08-16T16:08:25.250Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-16T16:08:25.250Z] GC before operation: completed in 36.476 ms, heap usage 245.014 MB -> 110.940 MB. [2024-08-16T16:08:26.057Z] ====== log-regression (apache-spark) [default], iteration 6 completed (704.061 ms) ====== [2024-08-16T16:08:26.057Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-16T16:08:26.057Z] GC before operation: completed in 39.900 ms, heap usage 318.387 MB -> 111.311 MB. [2024-08-16T16:08:26.842Z] ====== log-regression (apache-spark) [default], iteration 7 completed (691.121 ms) ====== [2024-08-16T16:08:26.842Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-16T16:08:26.842Z] GC before operation: completed in 38.625 ms, heap usage 329.767 MB -> 111.496 MB. [2024-08-16T16:08:27.602Z] ====== log-regression (apache-spark) [default], iteration 8 completed (682.423 ms) ====== [2024-08-16T16:08:27.602Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-16T16:08:27.602Z] GC before operation: completed in 38.248 ms, heap usage 309.708 MB -> 111.640 MB. [2024-08-16T16:08:28.356Z] ====== log-regression (apache-spark) [default], iteration 9 completed (636.942 ms) ====== [2024-08-16T16:08:28.356Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-16T16:08:28.356Z] GC before operation: completed in 39.599 ms, heap usage 311.143 MB -> 111.821 MB. [2024-08-16T16:08:28.927Z] ====== log-regression (apache-spark) [default], iteration 10 completed (656.120 ms) ====== [2024-08-16T16:08:28.927Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-16T16:08:28.927Z] GC before operation: completed in 44.229 ms, heap usage 291.923 MB -> 111.954 MB. [2024-08-16T16:08:29.678Z] ====== log-regression (apache-spark) [default], iteration 11 completed (673.435 ms) ====== [2024-08-16T16:08:29.678Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-16T16:08:29.678Z] GC before operation: completed in 42.198 ms, heap usage 226.281 MB -> 111.997 MB. [2024-08-16T16:08:30.433Z] ====== log-regression (apache-spark) [default], iteration 12 completed (672.200 ms) ====== [2024-08-16T16:08:30.433Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-16T16:08:30.433Z] GC before operation: completed in 51.502 ms, heap usage 280.766 MB -> 112.355 MB. [2024-08-16T16:08:30.836Z] ====== log-regression (apache-spark) [default], iteration 13 completed (633.447 ms) ====== [2024-08-16T16:08:30.836Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-16T16:08:31.193Z] GC before operation: completed in 43.258 ms, heap usage 228.446 MB -> 112.399 MB. [2024-08-16T16:08:31.949Z] ====== log-regression (apache-spark) [default], iteration 14 completed (736.912 ms) ====== [2024-08-16T16:08:31.949Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-16T16:08:31.949Z] GC before operation: completed in 42.132 ms, heap usage 226.590 MB -> 112.502 MB. [2024-08-16T16:08:32.311Z] ====== log-regression (apache-spark) [default], iteration 15 completed (646.105 ms) ====== [2024-08-16T16:08:32.312Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-16T16:08:32.672Z] GC before operation: completed in 44.138 ms, heap usage 281.680 MB -> 112.868 MB. [2024-08-16T16:08:33.041Z] ====== log-regression (apache-spark) [default], iteration 16 completed (678.831 ms) ====== [2024-08-16T16:08:33.041Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-16T16:08:33.407Z] GC before operation: completed in 43.825 ms, heap usage 255.774 MB -> 112.971 MB. [2024-08-16T16:08:33.772Z] ====== log-regression (apache-spark) [default], iteration 17 completed (612.390 ms) ====== [2024-08-16T16:08:33.772Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-16T16:08:33.772Z] GC before operation: completed in 43.332 ms, heap usage 262.528 MB -> 113.195 MB. [2024-08-16T16:08:34.544Z] ====== log-regression (apache-spark) [default], iteration 18 completed (649.366 ms) ====== [2024-08-16T16:08:34.544Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-16T16:08:34.544Z] GC before operation: completed in 43.411 ms, heap usage 254.612 MB -> 113.328 MB. [2024-08-16T16:08:35.293Z] ====== log-regression (apache-spark) [default], iteration 19 completed (675.916 ms) ====== [2024-08-16T16:08:35.293Z] ----------------------------------- [2024-08-16T16:08:35.293Z] renaissance-log-regression_0_PASSED [2024-08-16T16:08:35.293Z] ----------------------------------- [2024-08-16T16:08:35.293Z] [2024-08-16T16:08:35.293Z] TEST TEARDOWN: [2024-08-16T16:08:35.293Z] Nothing to be done for teardown. [2024-08-16T16:08:35.293Z] renaissance-log-regression_0 Finish Time: Fri Aug 16 12:08:35 2024 Epoch Time (ms): 1723824515169