[2024-08-10T01:52:51.688Z] Running test renaissance-log-regression_0 ... [2024-08-10T01:52:51.688Z] =============================================== [2024-08-10T01:52:51.688Z] renaissance-log-regression_0 Start Time: Fri Aug 9 20:52:51 2024 Epoch Time (ms): 1723254771568 [2024-08-10T01:52:51.688Z] variation: NoOptions [2024-08-10T01:52:51.688Z] JVM_OPTIONS: [2024-08-10T01:52:51.688Z] { \ [2024-08-10T01:52:51.688Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T01:52:51.688Z] echo "Nothing to be done for setup."; \ [2024-08-10T01:52:51.688Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723254269989/renaissance-log-regression_0"; \ [2024-08-10T01:52:51.688Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723254269989/renaissance-log-regression_0"; \ [2024-08-10T01:52:51.688Z] echo ""; echo "TESTING:"; \ [2024-08-10T01:52:51.688Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+2/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723254269989/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-10T01:52:51.688Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723254269989/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T01:52:51.688Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T01:52:51.688Z] echo "Nothing to be done for teardown."; \ [2024-08-10T01:52:51.688Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723254269989/TestTargetResult"; [2024-08-10T01:52:51.688Z] [2024-08-10T01:52:51.688Z] TEST SETUP: [2024-08-10T01:52:51.688Z] Nothing to be done for setup. [2024-08-10T01:52:51.688Z] [2024-08-10T01:52:51.688Z] TESTING: [2024-08-10T01:52:55.214Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T01:52:56.636Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2024-08-10T01:52:59.713Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-10T01:52:59.714Z] GC before operation: completed in 72.885 ms, heap usage 39.917 MB -> 35.707 MB. [2024-08-10T01:53:07.383Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7088.589 ms) ====== [2024-08-10T01:53:07.383Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-10T01:53:07.383Z] GC before operation: completed in 128.703 ms, heap usage 633.640 MB -> 119.856 MB. [2024-08-10T01:53:08.821Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2010.372 ms) ====== [2024-08-10T01:53:08.821Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-10T01:53:08.821Z] GC before operation: completed in 60.211 ms, heap usage 359.679 MB -> 119.823 MB. [2024-08-10T01:53:10.246Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1844.213 ms) ====== [2024-08-10T01:53:10.246Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-10T01:53:10.935Z] GC before operation: completed in 89.956 ms, heap usage 507.531 MB -> 120.659 MB. [2024-08-10T01:53:12.367Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1944.173 ms) ====== [2024-08-10T01:53:12.367Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-10T01:53:12.367Z] GC before operation: completed in 89.500 ms, heap usage 273.338 MB -> 120.372 MB. [2024-08-10T01:53:14.586Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1930.245 ms) ====== [2024-08-10T01:53:14.586Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-10T01:53:14.586Z] GC before operation: completed in 70.382 ms, heap usage 273.122 MB -> 120.743 MB. [2024-08-10T01:53:16.830Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1947.919 ms) ====== [2024-08-10T01:53:16.830Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-10T01:53:16.830Z] GC before operation: completed in 65.082 ms, heap usage 570.372 MB -> 121.862 MB. [2024-08-10T01:53:18.260Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1773.280 ms) ====== [2024-08-10T01:53:18.260Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-10T01:53:18.260Z] GC before operation: completed in 68.426 ms, heap usage 335.830 MB -> 121.607 MB. [2024-08-10T01:53:20.485Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1883.426 ms) ====== [2024-08-10T01:53:20.485Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-10T01:53:20.485Z] GC before operation: completed in 92.575 ms, heap usage 244.952 MB -> 121.748 MB. [2024-08-10T01:53:21.905Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1693.175 ms) ====== [2024-08-10T01:53:21.905Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-10T01:53:22.624Z] GC before operation: completed in 107.853 ms, heap usage 544.642 MB -> 123.055 MB. [2024-08-10T01:53:24.051Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1716.845 ms) ====== [2024-08-10T01:53:24.051Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-10T01:53:24.051Z] GC before operation: completed in 69.698 ms, heap usage 261.552 MB -> 122.481 MB. [2024-08-10T01:53:26.283Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1732.592 ms) ====== [2024-08-10T01:53:26.283Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-10T01:53:26.283Z] GC before operation: completed in 104.839 ms, heap usage 509.270 MB -> 123.662 MB. [2024-08-10T01:53:27.719Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1721.490 ms) ====== [2024-08-10T01:53:27.719Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-10T01:53:27.719Z] GC before operation: completed in 80.334 ms, heap usage 602.963 MB -> 124.046 MB. [2024-08-10T01:53:29.942Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1773.984 ms) ====== [2024-08-10T01:53:29.942Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-10T01:53:29.942Z] GC before operation: completed in 75.387 ms, heap usage 272.020 MB -> 123.522 MB. [2024-08-10T01:53:31.380Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1686.975 ms) ====== [2024-08-10T01:53:31.380Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-10T01:53:31.380Z] GC before operation: completed in 78.625 ms, heap usage 570.149 MB -> 124.678 MB. [2024-08-10T01:53:32.802Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1751.196 ms) ====== [2024-08-10T01:53:32.802Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-10T01:53:33.499Z] GC before operation: completed in 74.362 ms, heap usage 370.523 MB -> 124.458 MB. [2024-08-10T01:53:34.934Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1764.093 ms) ====== [2024-08-10T01:53:34.934Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-10T01:53:34.934Z] GC before operation: completed in 70.273 ms, heap usage 299.621 MB -> 124.651 MB. [2024-08-10T01:53:36.357Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1644.423 ms) ====== [2024-08-10T01:53:36.357Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-10T01:53:37.046Z] GC before operation: completed in 69.972 ms, heap usage 399.982 MB -> 125.216 MB. [2024-08-10T01:53:38.475Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1631.800 ms) ====== [2024-08-10T01:53:38.475Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-10T01:53:38.475Z] GC before operation: completed in 93.715 ms, heap usage 340.048 MB -> 125.518 MB. [2024-08-10T01:53:39.905Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1747.772 ms) ====== [2024-08-10T01:53:39.905Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-10T01:53:40.592Z] GC before operation: completed in 106.687 ms, heap usage 557.884 MB -> 126.467 MB. [2024-08-10T01:53:42.021Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1689.047 ms) ====== [2024-08-10T01:53:42.708Z] ----------------------------------- [2024-08-10T01:53:42.708Z] renaissance-log-regression_0_PASSED [2024-08-10T01:53:42.708Z] ----------------------------------- [2024-08-10T01:53:42.708Z] [2024-08-10T01:53:42.708Z] TEST TEARDOWN: [2024-08-10T01:53:42.708Z] Nothing to be done for teardown. [2024-08-10T01:53:42.708Z] renaissance-log-regression_0 Finish Time: Fri Aug 9 20:53:42 2024 Epoch Time (ms): 1723254822157