[2024-08-29T02:57:24.865Z] Running test renaissance-log-regression_0 ... [2024-08-29T02:57:24.865Z] =============================================== [2024-08-29T02:57:25.308Z] renaissance-log-regression_0 Start Time: Wed Aug 28 19:57:23 2024 Epoch Time (ms): 1724900243898 [2024-08-29T02:57:25.308Z] variation: NoOptions [2024-08-29T02:57:25.308Z] JVM_OPTIONS: [2024-08-29T02:57:25.308Z] { \ [2024-08-29T02:57:25.308Z] echo ""; echo "TEST SETUP:"; \ [2024-08-29T02:57:25.308Z] echo "Nothing to be done for setup."; \ [2024-08-29T02:57:25.308Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17248981116111/renaissance-log-regression_0"; \ [2024-08-29T02:57:25.308Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17248981116111/renaissance-log-regression_0"; \ [2024-08-29T02:57:25.308Z] echo ""; echo "TESTING:"; \ [2024-08-29T02:57:25.308Z] "/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_17248981116111/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-29T02:57:25.308Z] 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_17248981116111/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-29T02:57:25.308Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-29T02:57:25.308Z] echo "Nothing to be done for teardown."; \ [2024-08-29T02:57:25.308Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17248981116111/TestTargetResult"; [2024-08-29T02:57:25.308Z] [2024-08-29T02:57:25.308Z] TEST SETUP: [2024-08-29T02:57:25.308Z] Nothing to be done for setup. [2024-08-29T02:57:25.308Z] [2024-08-29T02:57:25.308Z] TESTING: [2024-08-29T02:57:46.775Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-29T02:57:48.241Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-29T02:57:53.634Z] WARNING: An illegal reflective access operation has occurred [2024-08-29T02:57:53.634Z] 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_17248981116111/renaissance-log-regression_0/harness-195726-15410963625261379604/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-29T02:57:53.634Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-29T02:57:53.634Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-29T02:57:53.634Z] WARNING: All illegal access operations will be denied in a future release [2024-08-29T02:58:00.871Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-29T02:58:01.284Z] GC before operation: completed in 374.793 ms, heap usage 91.359 MB -> 33.854 MB. [2024-08-29T02:58:38.126Z] ====== log-regression (apache-spark) [default], iteration 0 completed (33311.718 ms) ====== [2024-08-29T02:58:38.126Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-29T02:58:38.126Z] GC before operation: completed in 296.219 ms, heap usage 258.344 MB -> 109.480 MB. [2024-08-29T02:58:43.355Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7656.596 ms) ====== [2024-08-29T02:58:43.355Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-29T02:58:43.355Z] GC before operation: completed in 195.716 ms, heap usage 284.169 MB -> 110.018 MB. [2024-08-29T02:58:50.858Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7830.824 ms) ====== [2024-08-29T02:58:50.858Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-29T02:58:50.858Z] GC before operation: completed in 244.815 ms, heap usage 283.683 MB -> 110.392 MB. [2024-08-29T02:58:58.611Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7714.469 ms) ====== [2024-08-29T02:58:58.611Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-29T02:58:59.055Z] GC before operation: completed in 573.065 ms, heap usage 350.616 MB -> 110.790 MB. [2024-08-29T02:59:05.385Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6081.340 ms) ====== [2024-08-29T02:59:05.385Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-29T02:59:05.385Z] GC before operation: completed in 181.215 ms, heap usage 339.481 MB -> 110.985 MB. [2024-08-29T02:59:11.620Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6069.661 ms) ====== [2024-08-29T02:59:11.620Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-29T02:59:11.620Z] GC before operation: completed in 202.241 ms, heap usage 248.597 MB -> 110.943 MB. [2024-08-29T02:59:18.023Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6537.834 ms) ====== [2024-08-29T02:59:18.023Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-29T02:59:19.171Z] GC before operation: completed in 983.575 ms, heap usage 410.080 MB -> 111.534 MB. [2024-08-29T02:59:25.516Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5796.463 ms) ====== [2024-08-29T02:59:25.516Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-29T02:59:25.516Z] GC before operation: completed in 378.662 ms, heap usage 222.892 MB -> 111.257 MB. [2024-08-29T02:59:31.903Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6629.808 ms) ====== [2024-08-29T02:59:31.903Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-29T02:59:31.903Z] GC before operation: completed in 225.801 ms, heap usage 601.376 MB -> 112.352 MB. [2024-08-29T02:59:38.204Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6064.694 ms) ====== [2024-08-29T02:59:38.204Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-29T02:59:38.204Z] GC before operation: completed in 235.721 ms, heap usage 292.895 MB -> 111.745 MB. [2024-08-29T02:59:42.010Z] 24/08/28 19:59:40 WARN BlockManager: Asked to remove block broadcast_490, which does not exist [2024-08-29T02:59:43.554Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5318.887 ms) ====== [2024-08-29T02:59:43.555Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-29T02:59:44.046Z] GC before operation: completed in 209.487 ms, heap usage 374.018 MB -> 112.152 MB. [2024-08-29T02:59:49.920Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5610.725 ms) ====== [2024-08-29T02:59:49.920Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-29T02:59:49.920Z] GC before operation: completed in 548.758 ms, heap usage 387.619 MB -> 112.377 MB. [2024-08-29T02:59:56.358Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6012.740 ms) ====== [2024-08-29T02:59:56.358Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-29T02:59:56.359Z] GC before operation: completed in 218.538 ms, heap usage 244.185 MB -> 112.178 MB. [2024-08-29T03:00:02.325Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5251.046 ms) ====== [2024-08-29T03:00:02.325Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-29T03:00:02.325Z] GC before operation: completed in 202.808 ms, heap usage 412.011 MB -> 112.772 MB. [2024-08-29T03:00:08.396Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6687.053 ms) ====== [2024-08-29T03:00:08.396Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-29T03:00:08.851Z] GC before operation: completed in 241.136 ms, heap usage 429.286 MB -> 113.003 MB. [2024-08-29T03:00:15.258Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6327.613 ms) ====== [2024-08-29T03:00:15.258Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-29T03:00:15.258Z] GC before operation: completed in 231.008 ms, heap usage 263.973 MB -> 112.815 MB. [2024-08-29T03:00:24.281Z] ====== log-regression (apache-spark) [default], iteration 16 completed (8173.947 ms) ====== [2024-08-29T03:00:24.281Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-29T03:00:24.791Z] GC before operation: completed in 1255.365 ms, heap usage 234.895 MB -> 112.917 MB. [2024-08-29T03:00:34.569Z] ====== log-regression (apache-spark) [default], iteration 17 completed (9089.973 ms) ====== [2024-08-29T03:00:34.569Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-29T03:00:34.569Z] GC before operation: completed in 249.274 ms, heap usage 257.745 MB -> 113.179 MB. [2024-08-29T03:00:38.266Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3680.402 ms) ====== [2024-08-29T03:00:38.266Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-29T03:00:38.697Z] GC before operation: completed in 1118.836 ms, heap usage 306.533 MB -> 113.440 MB. [2024-08-29T03:00:44.631Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5875.716 ms) ====== [2024-08-29T03:00:45.609Z] ----------------------------------- [2024-08-29T03:00:45.609Z] renaissance-log-regression_0_PASSED [2024-08-29T03:00:45.609Z] ----------------------------------- [2024-08-29T03:00:45.609Z] [2024-08-29T03:00:45.609Z] TEST TEARDOWN: [2024-08-29T03:00:45.609Z] Nothing to be done for teardown. [2024-08-29T03:00:45.609Z] renaissance-log-regression_0 Finish Time: Wed Aug 28 20:00:44 2024 Epoch Time (ms): 1724900444607