[2025-02-05T22:29:17.036Z] Running test renaissance-log-regression_0 ... [2025-02-05T22:29:17.036Z] =============================================== [2025-02-05T22:29:17.427Z] renaissance-log-regression_0 Start Time: Wed Feb 5 17:29:16 2025 Epoch Time (ms): 1738794556992 [2025-02-05T22:29:17.427Z] variation: NoOptions [2025-02-05T22:29:17.427Z] JVM_OPTIONS: [2025-02-05T22:29:17.427Z] { \ [2025-02-05T22:29:17.427Z] echo ""; echo "TEST SETUP:"; \ [2025-02-05T22:29:17.427Z] echo "Nothing to be done for setup."; \ [2025-02-05T22:29:17.427Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17387942506025/renaissance-log-regression_0"; \ [2025-02-05T22:29:17.427Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17387942506025/renaissance-log-regression_0"; \ [2025-02-05T22:29:17.427Z] echo ""; echo "TESTING:"; \ [2025-02-05T22:29:17.427Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17387942506025/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-05T22:29:17.427Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17387942506025/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-05T22:29:17.427Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-05T22:29:17.427Z] echo "Nothing to be done for teardown."; \ [2025-02-05T22:29:17.427Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17387942506025/TestTargetResult"; [2025-02-05T22:29:17.427Z] [2025-02-05T22:29:17.427Z] TEST SETUP: [2025-02-05T22:29:17.427Z] Nothing to be done for setup. [2025-02-05T22:29:17.427Z] [2025-02-05T22:29:17.427Z] TESTING: [2025-02-05T22:29:18.769Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-05T22:29:19.607Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-02-05T22:29:20.450Z] WARNING: An illegal reflective access operation has occurred [2025-02-05T22:29:20.450Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/output_17387942506025/renaissance-log-regression_0/harness-172917-1836975764406191811/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-05T22:29:20.450Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-05T22:29:20.450Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-05T22:29:20.450Z] WARNING: All illegal access operations will be denied in a future release [2025-02-05T22:29:21.286Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-05T22:29:21.682Z] GC before operation: completed in 32.745 ms, heap usage 70.939 MB -> 33.857 MB. [2025-02-05T22:29:25.073Z] ====== log-regression (apache-spark) [default], iteration 0 completed (3672.727 ms) ====== [2025-02-05T22:29:25.073Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-05T22:29:25.073Z] GC before operation: completed in 36.427 ms, heap usage 246.174 MB -> 109.364 MB. [2025-02-05T22:29:26.467Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1155.828 ms) ====== [2025-02-05T22:29:26.467Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-05T22:29:26.467Z] GC before operation: completed in 37.345 ms, heap usage 261.233 MB -> 109.907 MB. [2025-02-05T22:29:27.313Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1106.991 ms) ====== [2025-02-05T22:29:27.313Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-05T22:29:27.706Z] GC before operation: completed in 33.826 ms, heap usage 264.836 MB -> 110.312 MB. [2025-02-05T22:29:28.539Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1086.792 ms) ====== [2025-02-05T22:29:28.539Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-05T22:29:28.539Z] GC before operation: completed in 36.883 ms, heap usage 338.319 MB -> 110.735 MB. [2025-02-05T22:29:29.373Z] ====== log-regression (apache-spark) [default], iteration 4 completed (803.833 ms) ====== [2025-02-05T22:29:29.373Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-05T22:29:29.373Z] GC before operation: completed in 36.422 ms, heap usage 246.555 MB -> 110.708 MB. [2025-02-05T22:29:30.213Z] ====== log-regression (apache-spark) [default], iteration 5 completed (791.844 ms) ====== [2025-02-05T22:29:30.213Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-05T22:29:30.213Z] GC before operation: completed in 39.377 ms, heap usage 310.668 MB -> 111.047 MB. [2025-02-05T22:29:31.061Z] ====== log-regression (apache-spark) [default], iteration 6 completed (832.193 ms) ====== [2025-02-05T22:29:31.061Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-05T22:29:31.061Z] GC before operation: completed in 35.115 ms, heap usage 226.627 MB -> 111.048 MB. [2025-02-05T22:29:31.906Z] ====== log-regression (apache-spark) [default], iteration 7 completed (727.839 ms) ====== [2025-02-05T22:29:31.906Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-05T22:29:31.906Z] GC before operation: completed in 46.369 ms, heap usage 234.858 MB -> 111.355 MB. [2025-02-05T22:29:32.794Z] ====== log-regression (apache-spark) [default], iteration 8 completed (781.041 ms) ====== [2025-02-05T22:29:32.794Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-05T22:29:32.794Z] GC before operation: completed in 47.343 ms, heap usage 225.918 MB -> 111.418 MB. [2025-02-05T22:29:33.632Z] ====== log-regression (apache-spark) [default], iteration 9 completed (745.117 ms) ====== [2025-02-05T22:29:33.632Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-05T22:29:33.632Z] GC before operation: completed in 56.677 ms, heap usage 329.694 MB -> 111.828 MB. [2025-02-05T22:29:34.491Z] ====== log-regression (apache-spark) [default], iteration 10 completed (781.619 ms) ====== [2025-02-05T22:29:34.491Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-05T22:29:34.491Z] GC before operation: completed in 41.426 ms, heap usage 262.080 MB -> 111.894 MB. [2025-02-05T22:29:35.355Z] ====== log-regression (apache-spark) [default], iteration 11 completed (798.396 ms) ====== [2025-02-05T22:29:35.355Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-05T22:29:35.355Z] GC before operation: completed in 45.224 ms, heap usage 244.259 MB -> 112.013 MB. [2025-02-05T22:29:35.771Z] ====== log-regression (apache-spark) [default], iteration 12 completed (692.429 ms) ====== [2025-02-05T22:29:35.771Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-05T22:29:36.178Z] GC before operation: completed in 60.316 ms, heap usage 255.923 MB -> 112.228 MB. [2025-02-05T22:29:36.591Z] ====== log-regression (apache-spark) [default], iteration 13 completed (728.934 ms) ====== [2025-02-05T22:29:36.591Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-05T22:29:37.007Z] GC before operation: completed in 45.574 ms, heap usage 301.211 MB -> 112.546 MB. [2025-02-05T22:29:37.406Z] ====== log-regression (apache-spark) [default], iteration 14 completed (769.117 ms) ====== [2025-02-05T22:29:37.406Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-05T22:29:37.805Z] GC before operation: completed in 48.001 ms, heap usage 309.805 MB -> 112.734 MB. [2025-02-05T22:29:38.213Z] ====== log-regression (apache-spark) [default], iteration 15 completed (715.276 ms) ====== [2025-02-05T22:29:38.213Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-05T22:29:38.213Z] GC before operation: completed in 41.341 ms, heap usage 273.192 MB -> 112.872 MB. [2025-02-05T22:29:39.060Z] ====== log-regression (apache-spark) [default], iteration 16 completed (762.130 ms) ====== [2025-02-05T22:29:39.060Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-05T22:29:39.060Z] GC before operation: completed in 42.100 ms, heap usage 262.970 MB -> 112.994 MB. [2025-02-05T22:29:39.910Z] ====== log-regression (apache-spark) [default], iteration 17 completed (684.802 ms) ====== [2025-02-05T22:29:39.910Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-05T22:29:39.910Z] GC before operation: completed in 42.839 ms, heap usage 311.271 MB -> 113.275 MB. [2025-02-05T22:29:40.758Z] ====== log-regression (apache-spark) [default], iteration 18 completed (754.133 ms) ====== [2025-02-05T22:29:40.758Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-05T22:29:40.758Z] GC before operation: completed in 43.350 ms, heap usage 227.699 MB -> 113.270 MB. [2025-02-05T22:29:41.620Z] ====== log-regression (apache-spark) [default], iteration 19 completed (741.765 ms) ====== [2025-02-05T22:29:41.620Z] ----------------------------------- [2025-02-05T22:29:41.620Z] renaissance-log-regression_0_PASSED [2025-02-05T22:29:41.620Z] ----------------------------------- [2025-02-05T22:29:41.620Z] [2025-02-05T22:29:41.620Z] TEST TEARDOWN: [2025-02-05T22:29:41.620Z] Nothing to be done for teardown. [2025-02-05T22:29:41.620Z] renaissance-log-regression_0 Finish Time: Wed Feb 5 17:29:41 2025 Epoch Time (ms): 1738794581341