[2025-03-06T00:34:13.987Z] Running test renaissance-log-regression_0 ... [2025-03-06T00:34:13.987Z] =============================================== [2025-03-06T00:34:13.987Z] renaissance-log-regression_0 Start Time: Thu Mar 6 00:34:13 2025 Epoch Time (ms): 1741221253661 [2025-03-06T00:34:13.987Z] variation: NoOptions [2025-03-06T00:34:13.987Z] JVM_OPTIONS: [2025-03-06T00:34:13.987Z] { \ [2025-03-06T00:34:13.987Z] echo ""; echo "TEST SETUP:"; \ [2025-03-06T00:34:13.987Z] echo "Nothing to be done for setup."; \ [2025-03-06T00:34:13.987Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/renaissance-log-regression_0"; \ [2025-03-06T00:34:13.987Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/renaissance-log-regression_0"; \ [2025-03-06T00:34:13.987Z] echo ""; echo "TESTING:"; \ [2025-03-06T00:34:13.987Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-03-06T00:34:13.987Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-06T00:34:13.987Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-06T00:34:13.987Z] echo "Nothing to be done for teardown."; \ [2025-03-06T00:34:13.987Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/TestTargetResult"; [2025-03-06T00:34:13.987Z] [2025-03-06T00:34:13.987Z] TEST SETUP: [2025-03-06T00:34:13.987Z] Nothing to be done for setup. [2025-03-06T00:34:13.987Z] [2025-03-06T00:34:13.987Z] TESTING: [2025-03-06T00:34:25.732Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-03-06T00:34:36.112Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-06T00:34:43.053Z] WARNING: An illegal reflective access operation has occurred [2025-03-06T00:34:43.053Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17412166579484/renaissance-log-regression_0/harness-003416-8762136906376943104/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-03-06T00:34:43.053Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-06T00:34:43.053Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-06T00:34:43.053Z] WARNING: All illegal access operations will be denied in a future release [2025-03-06T00:34:56.756Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-03-06T00:34:56.756Z] GC before operation: completed in 472.394 ms, heap usage 45.726 MB -> 33.806 MB. [2025-03-06T00:36:04.082Z] ====== log-regression (apache-spark) [default], iteration 0 completed (62460.960 ms) ====== [2025-03-06T00:36:04.082Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-03-06T00:36:04.082Z] GC before operation: completed in 700.311 ms, heap usage 202.643 MB -> 111.956 MB. [2025-03-06T00:36:18.253Z] ====== log-regression (apache-spark) [default], iteration 1 completed (17741.509 ms) ====== [2025-03-06T00:36:18.253Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-03-06T00:36:18.253Z] GC before operation: completed in 770.328 ms, heap usage 265.872 MB -> 112.512 MB. [2025-03-06T00:36:31.517Z] ====== log-regression (apache-spark) [default], iteration 2 completed (13605.018 ms) ====== [2025-03-06T00:36:31.517Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-03-06T00:36:31.517Z] GC before operation: completed in 580.790 ms, heap usage 288.644 MB -> 112.948 MB. [2025-03-06T00:36:46.545Z] ====== log-regression (apache-spark) [default], iteration 3 completed (13202.841 ms) ====== [2025-03-06T00:36:46.545Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-03-06T00:36:46.545Z] GC before operation: completed in 578.300 ms, heap usage 276.521 MB -> 113.108 MB. [2025-03-06T00:36:59.361Z] ====== log-regression (apache-spark) [default], iteration 4 completed (13928.337 ms) ====== [2025-03-06T00:36:59.361Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-03-06T00:37:00.232Z] GC before operation: completed in 590.292 ms, heap usage 256.795 MB -> 113.284 MB. [2025-03-06T00:37:11.178Z] ====== log-regression (apache-spark) [default], iteration 5 completed (11355.472 ms) ====== [2025-03-06T00:37:11.178Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-03-06T00:37:12.825Z] GC before operation: completed in 479.430 ms, heap usage 257.128 MB -> 113.473 MB. [2025-03-06T00:37:23.771Z] ====== log-regression (apache-spark) [default], iteration 6 completed (11425.397 ms) ====== [2025-03-06T00:37:23.771Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-03-06T00:37:23.771Z] GC before operation: completed in 587.957 ms, heap usage 248.143 MB -> 113.658 MB. [2025-03-06T00:37:34.765Z] ====== log-regression (apache-spark) [default], iteration 7 completed (10562.094 ms) ====== [2025-03-06T00:37:34.765Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-03-06T00:37:34.765Z] GC before operation: completed in 697.640 ms, heap usage 284.945 MB -> 113.932 MB. [2025-03-06T00:37:46.399Z] ====== log-regression (apache-spark) [default], iteration 8 completed (10484.002 ms) ====== [2025-03-06T00:37:46.399Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-03-06T00:37:46.399Z] GC before operation: completed in 560.701 ms, heap usage 238.979 MB -> 113.985 MB. [2025-03-06T00:37:55.490Z] ====== log-regression (apache-spark) [default], iteration 9 completed (9546.626 ms) ====== [2025-03-06T00:37:55.490Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-03-06T00:37:56.379Z] GC before operation: completed in 563.364 ms, heap usage 295.085 MB -> 114.306 MB. [2025-03-06T00:38:05.548Z] ====== log-regression (apache-spark) [default], iteration 10 completed (9088.709 ms) ====== [2025-03-06T00:38:05.548Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-03-06T00:38:06.426Z] GC before operation: completed in 649.097 ms, heap usage 276.548 MB -> 115.449 MB. [2025-03-06T00:38:17.508Z] ====== log-regression (apache-spark) [default], iteration 11 completed (10530.366 ms) ====== [2025-03-06T00:38:17.508Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-03-06T00:38:17.508Z] GC before operation: completed in 801.576 ms, heap usage 266.014 MB -> 114.674 MB. [2025-03-06T00:38:28.498Z] ====== log-regression (apache-spark) [default], iteration 12 completed (10799.748 ms) ====== [2025-03-06T00:38:28.498Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-03-06T00:38:28.498Z] GC before operation: completed in 577.104 ms, heap usage 258.619 MB -> 114.841 MB. [2025-03-06T00:38:39.444Z] ====== log-regression (apache-spark) [default], iteration 13 completed (9682.851 ms) ====== [2025-03-06T00:38:39.444Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-03-06T00:38:39.444Z] GC before operation: completed in 651.241 ms, heap usage 296.238 MB -> 115.141 MB. [2025-03-06T00:38:48.921Z] ====== log-regression (apache-spark) [default], iteration 14 completed (10155.347 ms) ====== [2025-03-06T00:38:48.922Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-03-06T00:38:49.805Z] GC before operation: completed in 670.470 ms, heap usage 249.109 MB -> 115.215 MB. [2025-03-06T00:38:59.058Z] ====== log-regression (apache-spark) [default], iteration 15 completed (9459.886 ms) ====== [2025-03-06T00:38:59.058Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-03-06T00:38:59.964Z] GC before operation: completed in 627.652 ms, heap usage 286.221 MB -> 115.531 MB. [2025-03-06T00:39:10.911Z] ====== log-regression (apache-spark) [default], iteration 16 completed (9560.463 ms) ====== [2025-03-06T00:39:10.911Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-03-06T00:39:10.911Z] GC before operation: completed in 520.856 ms, heap usage 297.211 MB -> 115.730 MB. [2025-03-06T00:39:18.612Z] ====== log-regression (apache-spark) [default], iteration 17 completed (7750.744 ms) ====== [2025-03-06T00:39:18.612Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-03-06T00:39:18.612Z] GC before operation: completed in 599.598 ms, heap usage 334.212 MB -> 116.127 MB. [2025-03-06T00:39:27.814Z] ====== log-regression (apache-spark) [default], iteration 18 completed (9103.197 ms) ====== [2025-03-06T00:39:27.814Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-03-06T00:39:28.731Z] GC before operation: completed in 691.695 ms, heap usage 268.000 MB -> 116.050 MB. [2025-03-06T00:39:39.874Z] ====== log-regression (apache-spark) [default], iteration 19 completed (11853.728 ms) ====== [2025-03-06T00:39:41.681Z] ----------------------------------- [2025-03-06T00:39:41.681Z] renaissance-log-regression_0_PASSED [2025-03-06T00:39:41.681Z] ----------------------------------- [2025-03-06T00:39:41.681Z] [2025-03-06T00:39:41.681Z] TEST TEARDOWN: [2025-03-06T00:39:41.681Z] Nothing to be done for teardown. [2025-03-06T00:39:41.681Z] renaissance-log-regression_0 Finish Time: Thu Mar 6 00:39:41 2025 Epoch Time (ms): 1741221581140