[2025-05-07T11:57:28.040Z] Running test renaissance-log-regression_0 ... [2025-05-07T11:57:28.040Z] =============================================== [2025-05-07T11:57:28.040Z] renaissance-log-regression_0 Start Time: Wed May 7 07:57:27 2025 Epoch Time (ms): 1746619047709 [2025-05-07T11:57:28.040Z] variation: NoOptions [2025-05-07T11:57:28.040Z] JVM_OPTIONS: [2025-05-07T11:57:28.040Z] { \ [2025-05-07T11:57:28.040Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T11:57:28.040Z] echo "Nothing to be done for setup."; \ [2025-05-07T11:57:28.041Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17466184215392/renaissance-log-regression_0"; \ [2025-05-07T11:57:28.041Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17466184215392/renaissance-log-regression_0"; \ [2025-05-07T11:57:28.041Z] echo ""; echo "TESTING:"; \ [2025-05-07T11:57:28.041Z] "/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_17466184215392/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-07T11:57:28.041Z] 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_17466184215392/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T11:57:28.041Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T11:57:28.041Z] echo "Nothing to be done for teardown."; \ [2025-05-07T11:57:28.041Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17466184215392/TestTargetResult"; [2025-05-07T11:57:28.041Z] [2025-05-07T11:57:28.041Z] TEST SETUP: [2025-05-07T11:57:28.041Z] Nothing to be done for setup. [2025-05-07T11:57:28.041Z] [2025-05-07T11:57:28.041Z] TESTING: [2025-05-07T11:57:30.594Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-07T11:57:31.417Z] WARNING: An illegal reflective access operation has occurred [2025-05-07T11:57:31.417Z] 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_17466184215392/renaissance-log-regression_0/harness-075728-14489660322228923185/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-07T11:57:31.417Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-07T11:57:31.417Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-07T11:57:31.417Z] WARNING: All illegal access operations will be denied in a future release [2025-05-07T11:57:31.803Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-07T11:57:31.803Z] GC before operation: completed in 23.865 ms, heap usage 37.539 MB -> 34.478 MB. [2025-05-07T11:57:38.243Z] ====== log-regression (apache-spark) [default], iteration 0 completed (5414.479 ms) ====== [2025-05-07T11:57:38.243Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-07T11:57:38.243Z] GC before operation: completed in 44.046 ms, heap usage 245.890 MB -> 115.761 MB. [2025-05-07T11:57:38.629Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1309.004 ms) ====== [2025-05-07T11:57:38.629Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-07T11:57:38.629Z] GC before operation: completed in 46.590 ms, heap usage 242.509 MB -> 116.186 MB. [2025-05-07T11:57:39.960Z] ====== log-regression (apache-spark) [default], iteration 2 completed (940.348 ms) ====== [2025-05-07T11:57:39.960Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-07T11:57:39.960Z] GC before operation: completed in 38.013 ms, heap usage 303.014 MB -> 116.772 MB. [2025-05-07T11:57:40.796Z] ====== log-regression (apache-spark) [default], iteration 3 completed (942.286 ms) ====== [2025-05-07T11:57:40.796Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-07T11:57:40.796Z] GC before operation: completed in 44.978 ms, heap usage 227.859 MB -> 116.838 MB. [2025-05-07T11:57:41.640Z] ====== log-regression (apache-spark) [default], iteration 4 completed (828.437 ms) ====== [2025-05-07T11:57:41.640Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-07T11:57:41.640Z] GC before operation: completed in 41.071 ms, heap usage 281.201 MB -> 117.214 MB. [2025-05-07T11:57:42.480Z] ====== log-regression (apache-spark) [default], iteration 5 completed (831.858 ms) ====== [2025-05-07T11:57:42.480Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-07T11:57:42.480Z] GC before operation: completed in 39.311 ms, heap usage 265.202 MB -> 117.339 MB. [2025-05-07T11:57:43.318Z] ====== log-regression (apache-spark) [default], iteration 6 completed (826.902 ms) ====== [2025-05-07T11:57:43.318Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-07T11:57:43.318Z] GC before operation: completed in 50.428 ms, heap usage 290.599 MB -> 117.653 MB. [2025-05-07T11:57:44.143Z] ====== log-regression (apache-spark) [default], iteration 7 completed (774.993 ms) ====== [2025-05-07T11:57:44.143Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-07T11:57:44.143Z] GC before operation: completed in 43.545 ms, heap usage 281.225 MB -> 117.850 MB. [2025-05-07T11:57:44.969Z] ====== log-regression (apache-spark) [default], iteration 8 completed (731.309 ms) ====== [2025-05-07T11:57:44.969Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-07T11:57:44.969Z] GC before operation: completed in 42.989 ms, heap usage 387.953 MB -> 118.376 MB. [2025-05-07T11:57:45.837Z] ====== log-regression (apache-spark) [default], iteration 9 completed (759.050 ms) ====== [2025-05-07T11:57:45.837Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-07T11:57:45.837Z] GC before operation: completed in 43.461 ms, heap usage 377.640 MB -> 118.562 MB. [2025-05-07T11:57:46.687Z] ====== log-regression (apache-spark) [default], iteration 10 completed (746.427 ms) ====== [2025-05-07T11:57:46.687Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-07T11:57:46.687Z] GC before operation: completed in 43.458 ms, heap usage 253.903 MB -> 118.335 MB. [2025-05-07T11:57:47.513Z] ====== log-regression (apache-spark) [default], iteration 11 completed (731.535 ms) ====== [2025-05-07T11:57:47.514Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-07T11:57:47.514Z] GC before operation: completed in 45.074 ms, heap usage 264.999 MB -> 118.529 MB. [2025-05-07T11:57:48.346Z] ====== log-regression (apache-spark) [default], iteration 12 completed (793.147 ms) ====== [2025-05-07T11:57:48.346Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-07T11:57:48.346Z] GC before operation: completed in 44.482 ms, heap usage 275.541 MB -> 118.800 MB. [2025-05-07T11:57:48.736Z] ====== log-regression (apache-spark) [default], iteration 13 completed (737.266 ms) ====== [2025-05-07T11:57:48.736Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-07T11:57:49.130Z] GC before operation: completed in 45.349 ms, heap usage 355.020 MB -> 119.235 MB. [2025-05-07T11:57:49.972Z] ====== log-regression (apache-spark) [default], iteration 14 completed (776.396 ms) ====== [2025-05-07T11:57:49.972Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-07T11:57:49.972Z] GC before operation: completed in 47.183 ms, heap usage 387.848 MB -> 119.460 MB. [2025-05-07T11:57:50.802Z] ====== log-regression (apache-spark) [default], iteration 15 completed (768.689 ms) ====== [2025-05-07T11:57:50.802Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-07T11:57:50.802Z] GC before operation: completed in 48.376 ms, heap usage 252.085 MB -> 119.284 MB. [2025-05-07T11:57:51.193Z] ====== log-regression (apache-spark) [default], iteration 16 completed (727.318 ms) ====== [2025-05-07T11:57:51.193Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-07T11:57:51.193Z] GC before operation: completed in 47.048 ms, heap usage 323.613 MB -> 119.650 MB. [2025-05-07T11:57:52.025Z] ====== log-regression (apache-spark) [default], iteration 17 completed (775.733 ms) ====== [2025-05-07T11:57:52.025Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-07T11:57:52.025Z] GC before operation: completed in 52.346 ms, heap usage 241.484 MB -> 119.591 MB. [2025-05-07T11:57:52.849Z] ====== log-regression (apache-spark) [default], iteration 18 completed (713.689 ms) ====== [2025-05-07T11:57:52.849Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-07T11:57:52.849Z] GC before operation: completed in 47.063 ms, heap usage 240.214 MB -> 119.776 MB. [2025-05-07T11:57:53.683Z] ====== log-regression (apache-spark) [default], iteration 19 completed (722.650 ms) ====== [2025-05-07T11:57:53.683Z] ----------------------------------- [2025-05-07T11:57:53.683Z] renaissance-log-regression_0_PASSED [2025-05-07T11:57:53.683Z] ----------------------------------- [2025-05-07T11:57:53.683Z] [2025-05-07T11:57:53.683Z] TEST TEARDOWN: [2025-05-07T11:57:53.683Z] Nothing to be done for teardown. [2025-05-07T11:57:54.108Z] renaissance-log-regression_0 Finish Time: Wed May 7 07:57:53 2025 Epoch Time (ms): 1746619073647