[2024-08-14T21:41:00.907Z] Running test renaissance-log-regression_0 ... [2024-08-14T21:41:00.907Z] =============================================== [2024-08-14T21:41:00.907Z] renaissance-log-regression_0 Start Time: Wed Aug 14 17:41:00 2024 Epoch Time (ms): 1723671660596 [2024-08-14T21:41:00.907Z] variation: NoOptions [2024-08-14T21:41:00.907Z] JVM_OPTIONS: [2024-08-14T21:41:00.907Z] { \ [2024-08-14T21:41:00.907Z] echo ""; echo "TEST SETUP:"; \ [2024-08-14T21:41:00.907Z] echo "Nothing to be done for setup."; \ [2024-08-14T21:41:00.907Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236713918350/renaissance-log-regression_0"; \ [2024-08-14T21:41:00.907Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236713918350/renaissance-log-regression_0"; \ [2024-08-14T21:41:00.907Z] echo ""; echo "TESTING:"; \ [2024-08-14T21:41:00.907Z] "/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_17236713918350/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-14T21:41:00.907Z] 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_17236713918350/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-14T21:41:00.907Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-14T21:41:00.907Z] echo "Nothing to be done for teardown."; \ [2024-08-14T21:41:00.907Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236713918350/TestTargetResult"; [2024-08-14T21:41:00.907Z] [2024-08-14T21:41:00.907Z] TEST SETUP: [2024-08-14T21:41:00.907Z] Nothing to be done for setup. [2024-08-14T21:41:00.907Z] [2024-08-14T21:41:00.907Z] TESTING: [2024-08-14T21:41:02.675Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-14T21:41:03.470Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-14T21:41:04.234Z] WARNING: An illegal reflective access operation has occurred [2024-08-14T21:41:04.234Z] 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_17236713918350/renaissance-log-regression_0/harness-174100-5732357879049013695/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-14T21:41:04.234Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-14T21:41:04.234Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-14T21:41:04.234Z] WARNING: All illegal access operations will be denied in a future release [2024-08-14T21:41:05.459Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-14T21:41:05.459Z] GC before operation: completed in 27.730 ms, heap usage 67.155 MB -> 33.875 MB. [2024-08-14T21:41:09.440Z] ====== log-regression (apache-spark) [default], iteration 0 completed (3849.831 ms) ====== [2024-08-14T21:41:09.440Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-14T21:41:09.440Z] GC before operation: completed in 52.682 ms, heap usage 237.107 MB -> 109.382 MB. [2024-08-14T21:41:10.213Z] ====== log-regression (apache-spark) [default], iteration 1 completed (978.303 ms) ====== [2024-08-14T21:41:10.213Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-14T21:41:10.213Z] GC before operation: completed in 40.744 ms, heap usage 236.112 MB -> 109.923 MB. [2024-08-14T21:41:11.430Z] ====== log-regression (apache-spark) [default], iteration 2 completed (882.988 ms) ====== [2024-08-14T21:41:11.430Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-14T21:41:11.430Z] GC before operation: completed in 35.945 ms, heap usage 305.012 MB -> 110.520 MB. [2024-08-14T21:41:12.181Z] ====== log-regression (apache-spark) [default], iteration 3 completed (889.129 ms) ====== [2024-08-14T21:41:12.181Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-14T21:41:12.181Z] GC before operation: completed in 34.594 ms, heap usage 255.425 MB -> 110.534 MB. [2024-08-14T21:41:12.931Z] ====== log-regression (apache-spark) [default], iteration 4 completed (842.729 ms) ====== [2024-08-14T21:41:12.932Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-14T21:41:12.932Z] GC before operation: completed in 36.184 ms, heap usage 253.337 MB -> 110.731 MB. [2024-08-14T21:41:13.701Z] ====== log-regression (apache-spark) [default], iteration 5 completed (805.455 ms) ====== [2024-08-14T21:41:13.702Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-14T21:41:13.702Z] GC before operation: completed in 37.547 ms, heap usage 318.113 MB -> 111.113 MB. [2024-08-14T21:41:14.914Z] ====== log-regression (apache-spark) [default], iteration 6 completed (813.247 ms) ====== [2024-08-14T21:41:14.914Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-14T21:41:14.914Z] GC before operation: completed in 38.246 ms, heap usage 243.737 MB -> 111.118 MB. [2024-08-14T21:41:15.271Z] ====== log-regression (apache-spark) [default], iteration 7 completed (741.184 ms) ====== [2024-08-14T21:41:15.271Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-14T21:41:15.622Z] GC before operation: completed in 39.092 ms, heap usage 272.435 MB -> 111.361 MB. [2024-08-14T21:41:16.394Z] ====== log-regression (apache-spark) [default], iteration 8 completed (835.859 ms) ====== [2024-08-14T21:41:16.394Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-14T21:41:16.394Z] GC before operation: completed in 39.262 ms, heap usage 225.395 MB -> 111.490 MB. [2024-08-14T21:41:17.147Z] ====== log-regression (apache-spark) [default], iteration 9 completed (718.121 ms) ====== [2024-08-14T21:41:17.148Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-14T21:41:17.148Z] GC before operation: completed in 42.278 ms, heap usage 318.538 MB -> 111.858 MB. [2024-08-14T21:41:17.925Z] ====== log-regression (apache-spark) [default], iteration 10 completed (857.159 ms) ====== [2024-08-14T21:41:17.925Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-14T21:41:17.925Z] GC before operation: completed in 41.501 ms, heap usage 291.315 MB -> 111.950 MB. [2024-08-14T21:41:18.711Z] ====== log-regression (apache-spark) [default], iteration 11 completed (719.190 ms) ====== [2024-08-14T21:41:18.711Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-14T21:41:18.711Z] GC before operation: completed in 60.133 ms, heap usage 254.319 MB -> 112.067 MB. [2024-08-14T21:41:19.478Z] ====== log-regression (apache-spark) [default], iteration 12 completed (713.398 ms) ====== [2024-08-14T21:41:19.478Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-14T21:41:19.478Z] GC before operation: completed in 44.678 ms, heap usage 310.781 MB -> 112.384 MB. [2024-08-14T21:41:20.259Z] ====== log-regression (apache-spark) [default], iteration 13 completed (722.251 ms) ====== [2024-08-14T21:41:20.259Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-14T21:41:20.259Z] GC before operation: completed in 42.253 ms, heap usage 299.384 MB -> 112.573 MB. [2024-08-14T21:41:21.021Z] ====== log-regression (apache-spark) [default], iteration 14 completed (709.413 ms) ====== [2024-08-14T21:41:21.021Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-14T21:41:21.021Z] GC before operation: completed in 42.923 ms, heap usage 281.156 MB -> 112.713 MB. [2024-08-14T21:41:21.793Z] ====== log-regression (apache-spark) [default], iteration 15 completed (768.584 ms) ====== [2024-08-14T21:41:21.793Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-14T21:41:21.793Z] GC before operation: completed in 44.926 ms, heap usage 299.295 MB -> 112.923 MB. [2024-08-14T21:41:22.563Z] ====== log-regression (apache-spark) [default], iteration 16 completed (708.663 ms) ====== [2024-08-14T21:41:22.563Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-14T21:41:22.563Z] GC before operation: completed in 45.099 ms, heap usage 291.516 MB -> 113.071 MB. [2024-08-14T21:41:23.312Z] ====== log-regression (apache-spark) [default], iteration 17 completed (751.693 ms) ====== [2024-08-14T21:41:23.312Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-14T21:41:23.312Z] GC before operation: completed in 45.091 ms, heap usage 282.266 MB -> 113.247 MB. [2024-08-14T21:41:24.080Z] ====== log-regression (apache-spark) [default], iteration 18 completed (720.788 ms) ====== [2024-08-14T21:41:24.080Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-14T21:41:24.080Z] GC before operation: completed in 47.041 ms, heap usage 227.425 MB -> 113.285 MB. [2024-08-14T21:41:24.838Z] ====== log-regression (apache-spark) [default], iteration 19 completed (731.861 ms) ====== [2024-08-14T21:41:25.190Z] ----------------------------------- [2024-08-14T21:41:25.190Z] renaissance-log-regression_0_PASSED [2024-08-14T21:41:25.190Z] ----------------------------------- [2024-08-14T21:41:25.190Z] [2024-08-14T21:41:25.190Z] TEST TEARDOWN: [2024-08-14T21:41:25.190Z] Nothing to be done for teardown. [2024-08-14T21:41:25.190Z] renaissance-log-regression_0 Finish Time: Wed Aug 14 17:41:24 2024 Epoch Time (ms): 1723671684802