[2024-08-28T20:57:38.970Z] Running test renaissance-log-regression_0 ... [2024-08-28T20:57:38.970Z] =============================================== [2024-08-28T20:57:38.970Z] renaissance-log-regression_0 Start Time: Wed Aug 28 20:57:38 2024 Epoch Time (ms): 1724878658592 [2024-08-28T20:57:38.970Z] variation: NoOptions [2024-08-28T20:57:38.970Z] JVM_OPTIONS: [2024-08-28T20:57:38.970Z] { \ [2024-08-28T20:57:38.970Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T20:57:38.970Z] echo "Nothing to be done for setup."; \ [2024-08-28T20:57:38.970Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17248779586869/renaissance-log-regression_0"; \ [2024-08-28T20:57:38.970Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17248779586869/renaissance-log-regression_0"; \ [2024-08-28T20:57:38.970Z] echo ""; echo "TESTING:"; \ [2024-08-28T20:57:38.970Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17248779586869/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-28T20:57:38.970Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17248779586869/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T20:57:38.970Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T20:57:38.970Z] echo "Nothing to be done for teardown."; \ [2024-08-28T20:57:38.970Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17248779586869/TestTargetResult"; [2024-08-28T20:57:38.970Z] [2024-08-28T20:57:38.970Z] TEST SETUP: [2024-08-28T20:57:38.970Z] Nothing to be done for setup. [2024-08-28T20:57:38.970Z] [2024-08-28T20:57:38.970Z] TESTING: [2024-08-28T20:57:43.052Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T20:57:44.973Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2024-08-28T20:57:50.292Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-28T20:57:50.292Z] GC before operation: completed in 41.132 ms, heap usage 54.077 MB -> 37.152 MB. [2024-08-28T20:57:59.903Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9116.037 ms) ====== [2024-08-28T20:57:59.903Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-28T20:57:59.903Z] GC before operation: completed in 107.038 ms, heap usage 639.871 MB -> 122.223 MB. [2024-08-28T20:58:01.822Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2715.052 ms) ====== [2024-08-28T20:58:01.822Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-28T20:58:01.822Z] GC before operation: completed in 77.967 ms, heap usage 225.411 MB -> 122.003 MB. [2024-08-28T20:58:03.742Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2406.874 ms) ====== [2024-08-28T20:58:03.742Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-28T20:58:04.682Z] GC before operation: completed in 88.983 ms, heap usage 413.991 MB -> 122.877 MB. [2024-08-28T20:58:06.604Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2184.903 ms) ====== [2024-08-28T20:58:06.604Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-28T20:58:06.604Z] GC before operation: completed in 91.833 ms, heap usage 250.698 MB -> 122.735 MB. [2024-08-28T20:58:08.525Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2096.324 ms) ====== [2024-08-28T20:58:08.525Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-28T20:58:08.525Z] GC before operation: completed in 100.503 ms, heap usage 465.418 MB -> 123.692 MB. [2024-08-28T20:58:10.444Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1997.220 ms) ====== [2024-08-28T20:58:10.444Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-28T20:58:10.444Z] GC before operation: completed in 111.539 ms, heap usage 441.844 MB -> 123.903 MB. [2024-08-28T20:58:13.410Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2007.584 ms) ====== [2024-08-28T20:58:13.410Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-28T20:58:13.410Z] GC before operation: completed in 119.969 ms, heap usage 259.911 MB -> 123.845 MB. [2024-08-28T20:58:15.330Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2043.755 ms) ====== [2024-08-28T20:58:15.330Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-28T20:58:15.330Z] GC before operation: completed in 114.121 ms, heap usage 563.492 MB -> 125.031 MB. [2024-08-28T20:58:17.252Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1880.483 ms) ====== [2024-08-28T20:58:17.252Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-28T20:58:17.252Z] GC before operation: completed in 112.614 ms, heap usage 254.018 MB -> 124.568 MB. [2024-08-28T20:58:19.176Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1884.566 ms) ====== [2024-08-28T20:58:19.176Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-28T20:58:19.176Z] GC before operation: completed in 111.347 ms, heap usage 249.881 MB -> 124.832 MB. [2024-08-28T20:58:21.099Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1892.555 ms) ====== [2024-08-28T20:58:21.099Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-28T20:58:21.099Z] GC before operation: completed in 115.963 ms, heap usage 234.836 MB -> 125.218 MB. [2024-08-28T20:58:23.021Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1726.074 ms) ====== [2024-08-28T20:58:23.021Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-28T20:58:23.021Z] GC before operation: completed in 116.130 ms, heap usage 709.871 MB -> 126.842 MB. [2024-08-28T20:58:24.943Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1743.451 ms) ====== [2024-08-28T20:58:24.943Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-28T20:58:24.943Z] GC before operation: completed in 115.829 ms, heap usage 414.700 MB -> 126.450 MB. [2024-08-28T20:58:26.861Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1698.783 ms) ====== [2024-08-28T20:58:26.862Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-28T20:58:26.862Z] GC before operation: completed in 130.971 ms, heap usage 463.790 MB -> 126.916 MB. [2024-08-28T20:58:28.781Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1683.129 ms) ====== [2024-08-28T20:58:28.781Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-28T20:58:28.781Z] GC before operation: completed in 121.595 ms, heap usage 274.897 MB -> 126.760 MB. [2024-08-28T20:58:29.717Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1605.494 ms) ====== [2024-08-28T20:58:29.717Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-28T20:58:30.805Z] GC before operation: completed in 129.387 ms, heap usage 463.879 MB -> 127.506 MB. [2024-08-28T20:58:31.745Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1711.742 ms) ====== [2024-08-28T20:58:31.745Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-28T20:58:31.745Z] GC before operation: completed in 135.076 ms, heap usage 465.217 MB -> 127.954 MB. [2024-08-28T20:58:33.665Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1577.156 ms) ====== [2024-08-28T20:58:33.665Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-28T20:58:33.665Z] GC before operation: completed in 126.821 ms, heap usage 462.456 MB -> 128.305 MB. [2024-08-28T20:58:35.589Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1612.316 ms) ====== [2024-08-28T20:58:35.589Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-28T20:58:35.589Z] GC before operation: completed in 131.921 ms, heap usage 485.623 MB -> 128.562 MB. [2024-08-28T20:58:37.520Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1709.941 ms) ====== [2024-08-28T20:58:37.520Z] ----------------------------------- [2024-08-28T20:58:37.520Z] renaissance-log-regression_0_PASSED [2024-08-28T20:58:37.520Z] ----------------------------------- [2024-08-28T20:58:37.520Z] [2024-08-28T20:58:37.520Z] TEST TEARDOWN: [2024-08-28T20:58:37.520Z] Nothing to be done for teardown. [2024-08-28T20:58:37.520Z] renaissance-log-regression_0 Finish Time: Wed Aug 28 20:58:37 2024 Epoch Time (ms): 1724878717119