[2024-12-05T03:05:11.112Z] Running test renaissance-log-regression_0 ... [2024-12-05T03:05:11.112Z] =============================================== [2024-12-05T03:05:11.112Z] renaissance-log-regression_0 Start Time: Thu Dec 5 03:05:10 2024 Epoch Time (ms): 1733367910967 [2024-12-05T03:05:11.112Z] variation: NoOptions [2024-12-05T03:05:11.112Z] JVM_OPTIONS: [2024-12-05T03:05:11.112Z] { \ [2024-12-05T03:05:11.112Z] echo ""; echo "TEST SETUP:"; \ [2024-12-05T03:05:11.112Z] echo "Nothing to be done for setup."; \ [2024-12-05T03:05:11.112Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17333621009543/renaissance-log-regression_0"; \ [2024-12-05T03:05:11.112Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17333621009543/renaissance-log-regression_0"; \ [2024-12-05T03:05:11.112Z] echo ""; echo "TESTING:"; \ [2024-12-05T03:05:11.112Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17333621009543/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-12-05T03:05:11.112Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17333621009543/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-05T03:05:11.112Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-05T03:05:11.112Z] echo "Nothing to be done for teardown."; \ [2024-12-05T03:05:11.112Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17333621009543/TestTargetResult"; [2024-12-05T03:05:11.112Z] [2024-12-05T03:05:11.112Z] TEST SETUP: [2024-12-05T03:05:11.112Z] Nothing to be done for setup. [2024-12-05T03:05:11.112Z] [2024-12-05T03:05:11.112Z] TESTING: [2024-12-05T03:05:31.374Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-05T03:05:46.149Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-12-05T03:06:20.180Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-12-05T03:06:20.180Z] GC before operation: completed in 327.606 ms, heap usage 60.132 MB -> 35.073 MB. [2024-12-05T03:07:36.538Z] ====== log-regression (apache-spark) [default], iteration 0 completed (72001.988 ms) ====== [2024-12-05T03:07:36.538Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-12-05T03:07:36.538Z] GC before operation: completed in 623.821 ms, heap usage 285.403 MB -> 113.184 MB. [2024-12-05T03:07:51.260Z] ====== log-regression (apache-spark) [default], iteration 1 completed (21457.524 ms) ====== [2024-12-05T03:07:51.260Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-12-05T03:07:51.260Z] GC before operation: completed in 608.391 ms, heap usage 370.675 MB -> 113.960 MB. [2024-12-05T03:08:11.613Z] ====== log-regression (apache-spark) [default], iteration 2 completed (20115.973 ms) ====== [2024-12-05T03:08:11.613Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-12-05T03:08:11.613Z] GC before operation: completed in 402.194 ms, heap usage 359.309 MB -> 114.386 MB. [2024-12-05T03:08:28.940Z] ====== log-regression (apache-spark) [default], iteration 3 completed (17673.119 ms) ====== [2024-12-05T03:08:28.941Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-12-05T03:08:29.625Z] GC before operation: completed in 723.526 ms, heap usage 349.555 MB -> 114.579 MB. [2024-12-05T03:08:44.888Z] ====== log-regression (apache-spark) [default], iteration 4 completed (14708.785 ms) ====== [2024-12-05T03:08:44.888Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-12-05T03:08:44.888Z] GC before operation: completed in 685.886 ms, heap usage 340.730 MB -> 114.868 MB. [2024-12-05T03:08:59.616Z] ====== log-regression (apache-spark) [default], iteration 5 completed (12981.832 ms) ====== [2024-12-05T03:08:59.616Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-12-05T03:08:59.616Z] GC before operation: completed in 565.477 ms, heap usage 298.200 MB -> 114.888 MB. [2024-12-05T03:09:10.183Z] ====== log-regression (apache-spark) [default], iteration 6 completed (11578.986 ms) ====== [2024-12-05T03:09:10.183Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-12-05T03:09:10.827Z] GC before operation: completed in 520.523 ms, heap usage 373.550 MB -> 115.298 MB. [2024-12-05T03:09:25.340Z] ====== log-regression (apache-spark) [default], iteration 7 completed (13249.237 ms) ====== [2024-12-05T03:09:25.340Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-12-05T03:09:25.340Z] GC before operation: completed in 467.283 ms, heap usage 305.158 MB -> 116.789 MB. [2024-12-05T03:09:37.666Z] ====== log-regression (apache-spark) [default], iteration 8 completed (11935.198 ms) ====== [2024-12-05T03:09:37.666Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-12-05T03:09:37.666Z] GC before operation: completed in 578.190 ms, heap usage 267.762 MB -> 115.423 MB. [2024-12-05T03:09:48.740Z] ====== log-regression (apache-spark) [default], iteration 9 completed (10643.298 ms) ====== [2024-12-05T03:09:48.740Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-12-05T03:09:48.740Z] GC before operation: completed in 548.895 ms, heap usage 313.713 MB -> 115.796 MB. [2024-12-05T03:09:59.202Z] ====== log-regression (apache-spark) [default], iteration 10 completed (11197.054 ms) ====== [2024-12-05T03:09:59.202Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-12-05T03:09:59.844Z] GC before operation: completed in 510.066 ms, heap usage 298.193 MB -> 115.804 MB. [2024-12-05T03:10:12.271Z] ====== log-regression (apache-spark) [default], iteration 11 completed (12021.507 ms) ====== [2024-12-05T03:10:12.271Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-12-05T03:10:12.910Z] GC before operation: completed in 917.315 ms, heap usage 232.143 MB -> 115.883 MB. [2024-12-05T03:10:25.323Z] ====== log-regression (apache-spark) [default], iteration 12 completed (10979.580 ms) ====== [2024-12-05T03:10:25.323Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-12-05T03:10:25.323Z] GC before operation: completed in 761.567 ms, heap usage 360.546 MB -> 116.395 MB. [2024-12-05T03:10:35.722Z] ====== log-regression (apache-spark) [default], iteration 13 completed (11321.607 ms) ====== [2024-12-05T03:10:35.722Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-12-05T03:10:36.356Z] GC before operation: completed in 576.442 ms, heap usage 358.659 MB -> 116.664 MB. [2024-12-05T03:10:46.793Z] ====== log-regression (apache-spark) [default], iteration 14 completed (9816.765 ms) ====== [2024-12-05T03:10:46.793Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-12-05T03:10:46.793Z] GC before operation: completed in 696.922 ms, heap usage 314.378 MB -> 116.729 MB. [2024-12-05T03:10:57.228Z] ====== log-regression (apache-spark) [default], iteration 15 completed (9815.223 ms) ====== [2024-12-05T03:10:57.228Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-12-05T03:10:57.228Z] GC before operation: completed in 660.998 ms, heap usage 331.223 MB -> 116.954 MB. [2024-12-05T03:11:09.530Z] ====== log-regression (apache-spark) [default], iteration 16 completed (10696.413 ms) ====== [2024-12-05T03:11:09.530Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-12-05T03:11:09.530Z] GC before operation: completed in 600.936 ms, heap usage 350.342 MB -> 117.268 MB. [2024-12-05T03:11:24.124Z] ====== log-regression (apache-spark) [default], iteration 17 completed (13705.069 ms) ====== [2024-12-05T03:11:24.124Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-12-05T03:11:24.124Z] GC before operation: completed in 669.857 ms, heap usage 312.100 MB -> 117.316 MB. [2024-12-05T03:11:34.532Z] ====== log-regression (apache-spark) [default], iteration 18 completed (10223.175 ms) ====== [2024-12-05T03:11:34.532Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-12-05T03:11:34.532Z] GC before operation: completed in 553.730 ms, heap usage 245.978 MB -> 117.349 MB. [2024-12-05T03:11:43.302Z] ====== log-regression (apache-spark) [default], iteration 19 completed (9477.751 ms) ====== [2024-12-05T03:11:46.273Z] ----------------------------------- [2024-12-05T03:11:46.273Z] renaissance-log-regression_0_PASSED [2024-12-05T03:11:46.273Z] ----------------------------------- [2024-12-05T03:11:46.273Z] [2024-12-05T03:11:46.273Z] TEST TEARDOWN: [2024-12-05T03:11:46.273Z] Nothing to be done for teardown. [2024-12-05T03:11:46.273Z] renaissance-log-regression_0 Finish Time: Thu Dec 5 03:11:45 2024 Epoch Time (ms): 1733368305509