[2024-08-01T21:55:09.824Z] Running test renaissance-log-regression_0 ... [2024-08-01T21:55:09.824Z] =============================================== [2024-08-01T21:55:09.824Z] renaissance-log-regression_0 Start Time: Thu Aug 1 21:55:08 2024 Epoch Time (ms): 1722549308997 [2024-08-01T21:55:09.824Z] variation: NoOptions [2024-08-01T21:55:09.824Z] JVM_OPTIONS: [2024-08-01T21:55:09.824Z] { \ [2024-08-01T21:55:09.824Z] echo ""; echo "TEST SETUP:"; \ [2024-08-01T21:55:09.824Z] echo "Nothing to be done for setup."; \ [2024-08-01T21:55:09.824Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17225485449689/renaissance-log-regression_0"; \ [2024-08-01T21:55:09.824Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17225485449689/renaissance-log-regression_0"; \ [2024-08-01T21:55:09.824Z] echo ""; echo "TESTING:"; \ [2024-08-01T21:55:09.824Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17225485449689/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-01T21:55:09.824Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17225485449689/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-01T21:55:09.824Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-01T21:55:09.824Z] echo "Nothing to be done for teardown."; \ [2024-08-01T21:55:09.824Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17225485449689/TestTargetResult"; [2024-08-01T21:55:09.824Z] [2024-08-01T21:55:09.824Z] TEST SETUP: [2024-08-01T21:55:09.824Z] Nothing to be done for setup. [2024-08-01T21:55:09.824Z] [2024-08-01T21:55:09.824Z] TESTING: [2024-08-01T21:55:12.779Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-01T21:55:15.737Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-01T21:55:19.801Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-01T21:55:19.801Z] GC before operation: completed in 122.050 ms, heap usage 126.661 MB -> 27.989 MB. [2024-08-01T21:55:31.126Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9850.899 ms) ====== [2024-08-01T21:55:31.126Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-01T21:55:31.126Z] GC before operation: completed in 331.186 ms, heap usage 384.347 MB -> 101.398 MB. [2024-08-01T21:55:32.057Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2251.059 ms) ====== [2024-08-01T21:55:32.057Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-01T21:55:32.986Z] GC before operation: completed in 230.198 ms, heap usage 308.106 MB -> 101.178 MB. [2024-08-01T21:55:34.932Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2058.365 ms) ====== [2024-08-01T21:55:34.932Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-01T21:55:34.932Z] GC before operation: completed in 182.564 ms, heap usage 310.989 MB -> 101.833 MB. [2024-08-01T21:55:36.845Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1955.919 ms) ====== [2024-08-01T21:55:36.845Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-01T21:55:36.845Z] GC before operation: completed in 181.600 ms, heap usage 350.848 MB -> 102.383 MB. [2024-08-01T21:55:39.980Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1741.032 ms) ====== [2024-08-01T21:55:39.980Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-01T21:55:39.980Z] GC before operation: completed in 179.329 ms, heap usage 193.935 MB -> 101.346 MB. [2024-08-01T21:55:40.910Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1738.629 ms) ====== [2024-08-01T21:55:40.910Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-01T21:55:40.910Z] GC before operation: completed in 179.357 ms, heap usage 375.597 MB -> 103.190 MB. [2024-08-01T21:55:42.820Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1581.159 ms) ====== [2024-08-01T21:55:42.820Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-01T21:55:42.820Z] GC before operation: completed in 165.181 ms, heap usage 353.914 MB -> 103.240 MB. [2024-08-01T21:55:44.729Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1605.992 ms) ====== [2024-08-01T21:55:44.729Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-01T21:55:44.729Z] GC before operation: completed in 196.243 ms, heap usage 350.517 MB -> 103.442 MB. [2024-08-01T21:55:46.655Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2197.187 ms) ====== [2024-08-01T21:55:46.655Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-01T21:55:46.655Z] GC before operation: completed in 181.241 ms, heap usage 376.520 MB -> 103.989 MB. [2024-08-01T21:55:48.568Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1532.430 ms) ====== [2024-08-01T21:55:48.568Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-01T21:55:48.568Z] GC before operation: completed in 181.566 ms, heap usage 221.519 MB -> 102.894 MB. [2024-08-01T21:55:50.478Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1499.235 ms) ====== [2024-08-01T21:55:50.478Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-01T21:55:50.478Z] GC before operation: completed in 218.707 ms, heap usage 254.667 MB -> 103.507 MB. [2024-08-01T21:55:51.406Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1549.187 ms) ====== [2024-08-01T21:55:51.406Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-01T21:55:52.334Z] GC before operation: completed in 185.233 ms, heap usage 411.987 MB -> 105.049 MB. [2024-08-01T21:55:53.263Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1500.768 ms) ====== [2024-08-01T21:55:53.263Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-01T21:55:54.191Z] GC before operation: completed in 218.071 ms, heap usage 354.693 MB -> 104.785 MB. [2024-08-01T21:55:55.166Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1406.611 ms) ====== [2024-08-01T21:55:55.166Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-01T21:55:55.166Z] GC before operation: completed in 181.961 ms, heap usage 342.869 MB -> 105.086 MB. [2024-08-01T21:55:57.148Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1399.093 ms) ====== [2024-08-01T21:55:57.148Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-01T21:55:57.148Z] GC before operation: completed in 198.465 ms, heap usage 350.579 MB -> 105.184 MB. [2024-08-01T21:55:58.081Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1452.831 ms) ====== [2024-08-01T21:55:58.081Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-01T21:55:59.012Z] GC before operation: completed in 178.183 ms, heap usage 363.215 MB -> 105.609 MB. [2024-08-01T21:55:59.942Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1380.093 ms) ====== [2024-08-01T21:55:59.942Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-01T21:55:59.942Z] GC before operation: completed in 195.770 ms, heap usage 352.647 MB -> 105.771 MB. [2024-08-01T21:56:01.851Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1352.945 ms) ====== [2024-08-01T21:56:01.851Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-01T21:56:01.851Z] GC before operation: completed in 198.089 ms, heap usage 368.959 MB -> 106.069 MB. [2024-08-01T21:56:02.780Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1361.466 ms) ====== [2024-08-01T21:56:02.780Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-01T21:56:02.780Z] GC before operation: completed in 173.053 ms, heap usage 422.082 MB -> 106.815 MB. [2024-08-01T21:56:04.694Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1369.885 ms) ====== [2024-08-01T21:56:04.694Z] ----------------------------------- [2024-08-01T21:56:04.694Z] renaissance-log-regression_0_PASSED [2024-08-01T21:56:04.694Z] ----------------------------------- [2024-08-01T21:56:04.694Z] [2024-08-01T21:56:04.694Z] TEST TEARDOWN: [2024-08-01T21:56:04.694Z] Nothing to be done for teardown. [2024-08-01T21:56:04.694Z] renaissance-log-regression_0 Finish Time: Thu Aug 1 21:56:04 2024 Epoch Time (ms): 1722549364284