[2024-12-07T00:44:47.247Z] Running test renaissance-log-regression_0 ... [2024-12-07T00:44:47.247Z] =============================================== [2024-12-07T00:44:47.865Z] renaissance-log-regression_0 Start Time: Sat Dec 7 00:44:47 2024 Epoch Time (ms): 1733532287205 [2024-12-07T00:44:47.865Z] variation: NoOptions [2024-12-07T00:44:47.865Z] JVM_OPTIONS: [2024-12-07T00:44:47.865Z] { \ [2024-12-07T00:44:47.865Z] echo ""; echo "TEST SETUP:"; \ [2024-12-07T00:44:47.865Z] echo "Nothing to be done for setup."; \ [2024-12-07T00:44:47.865Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17335238305491/renaissance-log-regression_0"; \ [2024-12-07T00:44:47.865Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17335238305491/renaissance-log-regression_0"; \ [2024-12-07T00:44:47.865Z] echo ""; echo "TESTING:"; \ [2024-12-07T00:44:47.865Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17335238305491/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-12-07T00:44:47.865Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17335238305491/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-07T00:44:47.865Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-07T00:44:47.865Z] echo "Nothing to be done for teardown."; \ [2024-12-07T00:44:47.865Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17335238305491/TestTargetResult"; [2024-12-07T00:44:47.865Z] [2024-12-07T00:44:47.865Z] TEST SETUP: [2024-12-07T00:44:47.865Z] Nothing to be done for setup. [2024-12-07T00:44:47.865Z] [2024-12-07T00:44:47.865Z] TESTING: [2024-12-07T00:45:27.167Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-07T00:45:54.975Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-12-07T00:46:11.942Z] WARNING: An illegal reflective access operation has occurred [2024-12-07T00:46:11.942Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17335238305491/renaissance-log-regression_0/harness-004456-16161248898768867408/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-12-07T00:46:11.942Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-12-07T00:46:11.942Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-12-07T00:46:11.942Z] WARNING: All illegal access operations will be denied in a future release [2024-12-07T00:46:57.557Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-12-07T00:46:57.557Z] GC before operation: completed in 803.156 ms, heap usage 63.239 MB -> 33.819 MB. [2024-12-07T00:49:14.082Z] ====== log-regression (apache-spark) [default], iteration 0 completed (129728.486 ms) ====== [2024-12-07T00:49:14.082Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-12-07T00:49:14.082Z] GC before operation: completed in 1018.677 ms, heap usage 240.838 MB -> 111.951 MB. [2024-12-07T00:49:47.023Z] ====== log-regression (apache-spark) [default], iteration 1 completed (39982.152 ms) ====== [2024-12-07T00:49:47.023Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-12-07T00:49:47.023Z] GC before operation: completed in 1255.488 ms, heap usage 238.802 MB -> 112.429 MB. [2024-12-07T00:50:26.812Z] ====== log-regression (apache-spark) [default], iteration 2 completed (40617.747 ms) ====== [2024-12-07T00:50:26.812Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-12-07T00:50:26.812Z] GC before operation: completed in 1276.705 ms, heap usage 268.019 MB -> 112.890 MB. [2024-12-07T00:50:59.692Z] ====== log-regression (apache-spark) [default], iteration 3 completed (31737.261 ms) ====== [2024-12-07T00:50:59.692Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-12-07T00:50:59.692Z] GC before operation: completed in 1207.196 ms, heap usage 228.975 MB -> 113.015 MB. [2024-12-07T00:51:23.616Z] ====== log-regression (apache-spark) [default], iteration 4 completed (23593.872 ms) ====== [2024-12-07T00:51:23.616Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-12-07T00:51:24.240Z] GC before operation: completed in 1228.612 ms, heap usage 341.845 MB -> 113.502 MB. [2024-12-07T00:51:48.152Z] ====== log-regression (apache-spark) [default], iteration 5 completed (23728.023 ms) ====== [2024-12-07T00:51:48.152Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-12-07T00:51:49.462Z] GC before operation: completed in 1161.111 ms, heap usage 239.857 MB -> 113.423 MB. [2024-12-07T00:52:13.205Z] ====== log-regression (apache-spark) [default], iteration 6 completed (21624.994 ms) ====== [2024-12-07T00:52:13.206Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-12-07T00:52:13.206Z] GC before operation: completed in 1050.575 ms, heap usage 239.990 MB -> 113.614 MB. [2024-12-07T00:52:33.753Z] ====== log-regression (apache-spark) [default], iteration 7 completed (21076.386 ms) ====== [2024-12-07T00:52:33.753Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-12-07T00:52:34.388Z] GC before operation: completed in 1179.296 ms, heap usage 277.042 MB -> 113.887 MB. [2024-12-07T00:52:58.168Z] ====== log-regression (apache-spark) [default], iteration 8 completed (23480.175 ms) ====== [2024-12-07T00:52:58.168Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-12-07T00:52:58.786Z] GC before operation: completed in 1154.434 ms, heap usage 285.867 MB -> 114.130 MB. [2024-12-07T00:53:22.585Z] ====== log-regression (apache-spark) [default], iteration 9 completed (21029.180 ms) ====== [2024-12-07T00:53:22.585Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-12-07T00:53:22.585Z] GC before operation: completed in 1223.108 ms, heap usage 249.928 MB -> 114.224 MB. [2024-12-07T00:53:42.851Z] ====== log-regression (apache-spark) [default], iteration 10 completed (20384.369 ms) ====== [2024-12-07T00:53:42.851Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-12-07T00:53:42.851Z] GC before operation: completed in 1169.206 ms, heap usage 286.447 MB -> 114.504 MB. [2024-12-07T00:54:03.708Z] ====== log-regression (apache-spark) [default], iteration 11 completed (19811.028 ms) ====== [2024-12-07T00:54:03.708Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-12-07T00:54:03.708Z] GC before operation: completed in 1199.345 ms, heap usage 316.236 MB -> 114.750 MB. [2024-12-07T00:54:28.145Z] ====== log-regression (apache-spark) [default], iteration 12 completed (23147.532 ms) ====== [2024-12-07T00:54:28.145Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-12-07T00:54:28.145Z] GC before operation: completed in 1151.357 ms, heap usage 287.520 MB -> 114.878 MB. [2024-12-07T00:54:48.490Z] ====== log-regression (apache-spark) [default], iteration 13 completed (19553.014 ms) ====== [2024-12-07T00:54:48.490Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-12-07T00:54:49.134Z] GC before operation: completed in 1415.818 ms, heap usage 317.054 MB -> 115.185 MB. [2024-12-07T00:55:13.505Z] ====== log-regression (apache-spark) [default], iteration 14 completed (22176.808 ms) ====== [2024-12-07T00:55:13.505Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-12-07T00:55:13.505Z] GC before operation: completed in 1071.724 ms, heap usage 249.480 MB -> 115.183 MB. [2024-12-07T00:55:37.925Z] ====== log-regression (apache-spark) [default], iteration 15 completed (22485.682 ms) ====== [2024-12-07T00:55:37.925Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-12-07T00:55:37.925Z] GC before operation: completed in 1280.856 ms, heap usage 268.777 MB -> 115.453 MB. [2024-12-07T00:55:55.598Z] ====== log-regression (apache-spark) [default], iteration 16 completed (17963.174 ms) ====== [2024-12-07T00:55:55.598Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-12-07T00:55:55.598Z] GC before operation: completed in 1593.754 ms, heap usage 291.943 MB -> 115.712 MB. [2024-12-07T00:56:15.770Z] ====== log-regression (apache-spark) [default], iteration 17 completed (19549.827 ms) ====== [2024-12-07T00:56:15.770Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-12-07T00:56:16.385Z] GC before operation: completed in 1124.729 ms, heap usage 216.885 MB -> 115.688 MB. [2024-12-07T00:56:36.394Z] ====== log-regression (apache-spark) [default], iteration 18 completed (18603.775 ms) ====== [2024-12-07T00:56:36.394Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-12-07T00:56:36.394Z] GC before operation: completed in 1214.195 ms, heap usage 299.627 MB -> 116.179 MB. [2024-12-07T00:57:00.003Z] ====== log-regression (apache-spark) [default], iteration 19 completed (21100.985 ms) ====== [2024-12-07T00:57:03.123Z] ----------------------------------- [2024-12-07T00:57:03.123Z] renaissance-log-regression_0_PASSED [2024-12-07T00:57:03.123Z] ----------------------------------- [2024-12-07T00:57:03.123Z] [2024-12-07T00:57:03.123Z] TEST TEARDOWN: [2024-12-07T00:57:03.123Z] Nothing to be done for teardown. [2024-12-07T00:57:03.123Z] renaissance-log-regression_0 Finish Time: Sat Dec 7 00:57:02 2024 Epoch Time (ms): 1733533022066