[2025-03-30T21:36:44.132Z] Running test renaissance-log-regression_0 ... [2025-03-30T21:36:44.132Z] =============================================== [2025-03-30T21:36:44.132Z] renaissance-log-regression_0 Start Time: Sun Mar 30 17:36:44 2025 Epoch Time (ms): 1743370604012 [2025-03-30T21:36:44.132Z] variation: NoOptions [2025-03-30T21:36:44.132Z] JVM_OPTIONS: [2025-03-30T21:36:44.132Z] { \ [2025-03-30T21:36:44.132Z] echo ""; echo "TEST SETUP:"; \ [2025-03-30T21:36:44.132Z] echo "Nothing to be done for setup."; \ [2025-03-30T21:36:44.132Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17433701098864/renaissance-log-regression_0"; \ [2025-03-30T21:36:44.132Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17433701098864/renaissance-log-regression_0"; \ [2025-03-30T21:36:44.132Z] echo ""; echo "TESTING:"; \ [2025-03-30T21:36:44.132Z] "/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_17433701098864/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-03-30T21:36:44.132Z] 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_17433701098864/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-30T21:36:44.132Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-30T21:36:44.132Z] echo "Nothing to be done for teardown."; \ [2025-03-30T21:36:44.132Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17433701098864/TestTargetResult"; [2025-03-30T21:36:44.132Z] [2025-03-30T21:36:44.132Z] TEST SETUP: [2025-03-30T21:36:44.132Z] Nothing to be done for setup. [2025-03-30T21:36:44.132Z] [2025-03-30T21:36:44.132Z] TESTING: [2025-03-30T21:36:47.278Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-03-30T21:36:47.662Z] WARNING: An illegal reflective access operation has occurred [2025-03-30T21:36:47.662Z] 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_17433701098864/renaissance-log-regression_0/harness-173644-6172358086262905140/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-03-30T21:36:47.662Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-30T21:36:47.662Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-30T21:36:47.662Z] WARNING: All illegal access operations will be denied in a future release [2025-03-30T21:36:48.446Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-03-30T21:36:48.446Z] GC before operation: completed in 24.481 ms, heap usage 56.420 MB -> 34.487 MB. [2025-03-30T21:36:54.678Z] ====== log-regression (apache-spark) [default], iteration 0 completed (5314.306 ms) ====== [2025-03-30T21:36:54.678Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-03-30T21:36:54.678Z] GC before operation: completed in 44.654 ms, heap usage 280.535 MB -> 115.834 MB. [2025-03-30T21:36:55.038Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1146.818 ms) ====== [2025-03-30T21:36:55.038Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-03-30T21:36:55.038Z] GC before operation: completed in 41.196 ms, heap usage 317.529 MB -> 116.416 MB. [2025-03-30T21:36:56.280Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1068.467 ms) ====== [2025-03-30T21:36:56.280Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-03-30T21:36:56.280Z] GC before operation: completed in 37.611 ms, heap usage 253.422 MB -> 116.681 MB. [2025-03-30T21:36:57.060Z] ====== log-regression (apache-spark) [default], iteration 3 completed (875.809 ms) ====== [2025-03-30T21:36:57.060Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-03-30T21:36:57.060Z] GC before operation: completed in 38.749 ms, heap usage 242.021 MB -> 116.848 MB. [2025-03-30T21:36:57.853Z] ====== log-regression (apache-spark) [default], iteration 4 completed (875.387 ms) ====== [2025-03-30T21:36:57.853Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-03-30T21:36:57.853Z] GC before operation: completed in 39.308 ms, heap usage 282.728 MB -> 117.244 MB. [2025-03-30T21:36:58.627Z] ====== log-regression (apache-spark) [default], iteration 5 completed (881.723 ms) ====== [2025-03-30T21:36:58.627Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-03-30T21:36:59.007Z] GC before operation: completed in 44.223 ms, heap usage 289.457 MB -> 117.450 MB. [2025-03-30T21:36:59.800Z] ====== log-regression (apache-spark) [default], iteration 6 completed (808.447 ms) ====== [2025-03-30T21:36:59.800Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-03-30T21:36:59.800Z] GC before operation: completed in 40.668 ms, heap usage 354.123 MB -> 117.896 MB. [2025-03-30T21:37:00.584Z] ====== log-regression (apache-spark) [default], iteration 7 completed (827.124 ms) ====== [2025-03-30T21:37:00.584Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-03-30T21:37:00.584Z] GC before operation: completed in 42.066 ms, heap usage 241.596 MB -> 117.688 MB. [2025-03-30T21:37:01.415Z] ====== log-regression (apache-spark) [default], iteration 8 completed (751.657 ms) ====== [2025-03-30T21:37:01.415Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-03-30T21:37:01.415Z] GC before operation: completed in 42.398 ms, heap usage 306.333 MB -> 118.132 MB. [2025-03-30T21:37:02.208Z] ====== log-regression (apache-spark) [default], iteration 9 completed (795.417 ms) ====== [2025-03-30T21:37:02.208Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-03-30T21:37:02.208Z] GC before operation: completed in 44.182 ms, heap usage 364.280 MB -> 118.501 MB. [2025-03-30T21:37:03.023Z] ====== log-regression (apache-spark) [default], iteration 10 completed (771.821 ms) ====== [2025-03-30T21:37:03.023Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-03-30T21:37:03.023Z] GC before operation: completed in 44.317 ms, heap usage 276.105 MB -> 118.441 MB. [2025-03-30T21:37:03.831Z] ====== log-regression (apache-spark) [default], iteration 11 completed (753.466 ms) ====== [2025-03-30T21:37:03.831Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-03-30T21:37:03.831Z] GC before operation: completed in 43.705 ms, heap usage 326.105 MB -> 118.761 MB. [2025-03-30T21:37:04.648Z] ====== log-regression (apache-spark) [default], iteration 12 completed (703.000 ms) ====== [2025-03-30T21:37:04.648Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-03-30T21:37:04.648Z] GC before operation: completed in 44.725 ms, heap usage 258.327 MB -> 118.706 MB. [2025-03-30T21:37:05.457Z] ====== log-regression (apache-spark) [default], iteration 13 completed (768.465 ms) ====== [2025-03-30T21:37:05.457Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-03-30T21:37:05.457Z] GC before operation: completed in 44.885 ms, heap usage 289.195 MB -> 118.979 MB. [2025-03-30T21:37:06.243Z] ====== log-regression (apache-spark) [default], iteration 14 completed (759.378 ms) ====== [2025-03-30T21:37:06.243Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-03-30T21:37:06.243Z] GC before operation: completed in 45.683 ms, heap usage 241.242 MB -> 119.012 MB. [2025-03-30T21:37:07.033Z] ====== log-regression (apache-spark) [default], iteration 15 completed (728.646 ms) ====== [2025-03-30T21:37:07.033Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-03-30T21:37:07.033Z] GC before operation: completed in 46.153 ms, heap usage 324.992 MB -> 119.571 MB. [2025-03-30T21:37:07.836Z] ====== log-regression (apache-spark) [default], iteration 16 completed (744.837 ms) ====== [2025-03-30T21:37:07.836Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-03-30T21:37:07.836Z] GC before operation: completed in 50.755 ms, heap usage 289.283 MB -> 119.602 MB. [2025-03-30T21:37:08.621Z] ====== log-regression (apache-spark) [default], iteration 17 completed (723.619 ms) ====== [2025-03-30T21:37:08.621Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-03-30T21:37:08.621Z] GC before operation: completed in 46.369 ms, heap usage 242.091 MB -> 119.635 MB. [2025-03-30T21:37:09.406Z] ====== log-regression (apache-spark) [default], iteration 18 completed (709.150 ms) ====== [2025-03-30T21:37:09.406Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-03-30T21:37:09.406Z] GC before operation: completed in 47.395 ms, heap usage 314.015 MB -> 120.068 MB. [2025-03-30T21:37:10.178Z] ====== log-regression (apache-spark) [default], iteration 19 completed (741.802 ms) ====== [2025-03-30T21:37:10.178Z] ----------------------------------- [2025-03-30T21:37:10.178Z] renaissance-log-regression_0_PASSED [2025-03-30T21:37:10.178Z] ----------------------------------- [2025-03-30T21:37:10.178Z] [2025-03-30T21:37:10.178Z] TEST TEARDOWN: [2025-03-30T21:37:10.178Z] Nothing to be done for teardown. [2025-03-30T21:37:10.178Z] renaissance-log-regression_0 Finish Time: Sun Mar 30 17:37:09 2025 Epoch Time (ms): 1743370629879