[2025-02-27T02:00:57.726Z] Running test renaissance-log-regression_0 ... [2025-02-27T02:00:57.726Z] =============================================== [2025-02-27T02:00:57.726Z] renaissance-log-regression_0 Start Time: Wed Feb 26 18:00:56 2025 Epoch Time (ms): 1740621656896 [2025-02-27T02:00:57.726Z] variation: NoOptions [2025-02-27T02:00:57.726Z] JVM_OPTIONS: [2025-02-27T02:00:57.726Z] { \ [2025-02-27T02:00:57.726Z] echo ""; echo "TEST SETUP:"; \ [2025-02-27T02:00:57.726Z] echo "Nothing to be done for setup."; \ [2025-02-27T02:00:57.726Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406208911499/renaissance-log-regression_0"; \ [2025-02-27T02:00:57.726Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406208911499/renaissance-log-regression_0"; \ [2025-02-27T02:00:57.726Z] echo ""; echo "TESTING:"; \ [2025-02-27T02:00:57.726Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/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_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406208911499/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-27T02:00:57.726Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406208911499/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-27T02:00:57.726Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-27T02:00:57.726Z] echo "Nothing to be done for teardown."; \ [2025-02-27T02:00:57.726Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406208911499/TestTargetResult"; [2025-02-27T02:00:57.726Z] [2025-02-27T02:00:57.726Z] TEST SETUP: [2025-02-27T02:00:57.726Z] Nothing to be done for setup. [2025-02-27T02:00:57.726Z] [2025-02-27T02:00:57.726Z] TESTING: [2025-02-27T02:01:02.004Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-27T02:01:02.817Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-02-27T02:01:04.674Z] WARNING: An illegal reflective access operation has occurred [2025-02-27T02:01:04.674Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/output_17406208911499/renaissance-log-regression_0/harness-180057-15565369065857099656/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-27T02:01:04.674Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-27T02:01:04.674Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-27T02:01:04.674Z] WARNING: All illegal access operations will be denied in a future release [2025-02-27T02:01:06.538Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-27T02:01:06.538Z] GC before operation: completed in 57.700 ms, heap usage 75.431 MB -> 33.842 MB. [2025-02-27T02:01:18.395Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12119.158 ms) ====== [2025-02-27T02:01:18.395Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-27T02:01:18.783Z] GC before operation: completed in 194.930 ms, heap usage 406.286 MB -> 109.712 MB. [2025-02-27T02:01:22.261Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3281.346 ms) ====== [2025-02-27T02:01:22.262Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-27T02:01:22.262Z] GC before operation: completed in 155.412 ms, heap usage 417.200 MB -> 110.303 MB. [2025-02-27T02:01:25.878Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3397.632 ms) ====== [2025-02-27T02:01:25.878Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-27T02:01:25.878Z] GC before operation: completed in 151.970 ms, heap usage 409.582 MB -> 110.667 MB. [2025-02-27T02:01:29.253Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3597.580 ms) ====== [2025-02-27T02:01:29.253Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-27T02:01:29.253Z] GC before operation: completed in 173.020 ms, heap usage 479.504 MB -> 111.128 MB. [2025-02-27T02:01:32.668Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3071.484 ms) ====== [2025-02-27T02:01:32.668Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-27T02:01:32.668Z] GC before operation: completed in 135.064 ms, heap usage 268.009 MB -> 110.774 MB. [2025-02-27T02:01:36.145Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3381.098 ms) ====== [2025-02-27T02:01:36.145Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-27T02:01:36.145Z] GC before operation: completed in 164.214 ms, heap usage 246.711 MB -> 110.928 MB. [2025-02-27T02:01:38.874Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2713.117 ms) ====== [2025-02-27T02:01:38.874Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-27T02:01:39.287Z] GC before operation: completed in 203.557 ms, heap usage 333.181 MB -> 111.284 MB. [2025-02-27T02:01:42.708Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3246.363 ms) ====== [2025-02-27T02:01:42.708Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-27T02:01:42.708Z] GC before operation: completed in 202.981 ms, heap usage 538.855 MB -> 112.015 MB. [2025-02-27T02:01:46.304Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3144.325 ms) ====== [2025-02-27T02:01:46.304Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-27T02:01:46.304Z] GC before operation: completed in 163.702 ms, heap usage 356.805 MB -> 111.745 MB. [2025-02-27T02:01:48.947Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3086.918 ms) ====== [2025-02-27T02:01:48.947Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-27T02:01:49.331Z] GC before operation: completed in 203.235 ms, heap usage 399.415 MB -> 112.018 MB. [2025-02-27T02:01:51.930Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2789.956 ms) ====== [2025-02-27T02:01:51.930Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-27T02:01:51.930Z] GC before operation: completed in 172.871 ms, heap usage 396.548 MB -> 112.206 MB. [2025-02-27T02:01:54.494Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2491.179 ms) ====== [2025-02-27T02:01:54.494Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-27T02:01:54.876Z] GC before operation: completed in 222.300 ms, heap usage 259.228 MB -> 112.067 MB. [2025-02-27T02:01:57.602Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2716.966 ms) ====== [2025-02-27T02:01:57.602Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-27T02:01:57.602Z] GC before operation: completed in 151.589 ms, heap usage 584.732 MB -> 113.040 MB. [2025-02-27T02:02:01.080Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2811.262 ms) ====== [2025-02-27T02:02:01.080Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-27T02:02:01.080Z] GC before operation: completed in 171.732 ms, heap usage 351.227 MB -> 112.643 MB. [2025-02-27T02:02:03.071Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2355.883 ms) ====== [2025-02-27T02:02:03.071Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-27T02:02:03.071Z] GC before operation: completed in 171.111 ms, heap usage 471.166 MB -> 113.099 MB. [2025-02-27T02:02:05.728Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2330.535 ms) ====== [2025-02-27T02:02:05.728Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-27T02:02:05.728Z] GC before operation: completed in 135.014 ms, heap usage 523.280 MB -> 113.458 MB. [2025-02-27T02:02:07.709Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1834.683 ms) ====== [2025-02-27T02:02:07.709Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-27T02:02:07.709Z] GC before operation: completed in 149.832 ms, heap usage 364.290 MB -> 113.235 MB. [2025-02-27T02:02:09.610Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1888.400 ms) ====== [2025-02-27T02:02:09.610Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-27T02:02:09.610Z] GC before operation: completed in 156.371 ms, heap usage 295.071 MB -> 113.261 MB. [2025-02-27T02:02:11.520Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1525.487 ms) ====== [2025-02-27T02:02:11.520Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-27T02:02:11.520Z] GC before operation: completed in 91.810 ms, heap usage 479.433 MB -> 113.902 MB. [2025-02-27T02:02:12.817Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1318.329 ms) ====== [2025-02-27T02:02:12.817Z] ----------------------------------- [2025-02-27T02:02:12.817Z] renaissance-log-regression_0_PASSED [2025-02-27T02:02:12.817Z] ----------------------------------- [2025-02-27T02:02:12.817Z] [2025-02-27T02:02:12.817Z] TEST TEARDOWN: [2025-02-27T02:02:12.818Z] Nothing to be done for teardown. [2025-02-27T02:02:12.818Z] renaissance-log-regression_0 Finish Time: Wed Feb 26 18:02:12 2025 Epoch Time (ms): 1740621732053