[2024-10-29T22:12:22.407Z] Running test renaissance-log-regression_0 ... [2024-10-29T22:12:22.407Z] =============================================== [2024-10-29T22:12:22.407Z] renaissance-log-regression_0 Start Time: Tue Oct 29 22:12:21 2024 Epoch Time (ms): 1730239941853 [2024-10-29T22:12:22.407Z] variation: NoOptions [2024-10-29T22:12:22.407Z] JVM_OPTIONS: [2024-10-29T22:12:22.407Z] { \ [2024-10-29T22:12:22.407Z] echo ""; echo "TEST SETUP:"; \ [2024-10-29T22:12:22.407Z] echo "Nothing to be done for setup."; \ [2024-10-29T22:12:22.407Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17302391083690/renaissance-log-regression_0"; \ [2024-10-29T22:12:22.407Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17302391083690/renaissance-log-regression_0"; \ [2024-10-29T22:12:22.407Z] echo ""; echo "TESTING:"; \ [2024-10-29T22:12:22.407Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17302391083690/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-10-29T22:12:22.407Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17302391083690/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-29T22:12:22.407Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-29T22:12:22.407Z] echo "Nothing to be done for teardown."; \ [2024-10-29T22:12:22.408Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17302391083690/TestTargetResult"; [2024-10-29T22:12:22.408Z] [2024-10-29T22:12:22.408Z] TEST SETUP: [2024-10-29T22:12:22.408Z] Nothing to be done for setup. [2024-10-29T22:12:22.408Z] [2024-10-29T22:12:22.408Z] TESTING: [2024-10-29T22:12:26.464Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-29T22:12:28.372Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-29T22:12:33.630Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-10-29T22:12:33.630Z] GC before operation: completed in 175.463 ms, heap usage 84.090 MB -> 26.048 MB. [2024-10-29T22:12:43.192Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10228.624 ms) ====== [2024-10-29T22:12:43.192Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-10-29T22:12:44.121Z] GC before operation: completed in 361.969 ms, heap usage 156.327 MB -> 99.588 MB. [2024-10-29T22:12:46.032Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2436.228 ms) ====== [2024-10-29T22:12:46.032Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-10-29T22:12:46.962Z] GC before operation: completed in 242.726 ms, heap usage 244.627 MB -> 100.716 MB. [2024-10-29T22:12:48.873Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2440.247 ms) ====== [2024-10-29T22:12:48.873Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-10-29T22:12:48.873Z] GC before operation: completed in 190.293 ms, heap usage 209.791 MB -> 100.880 MB. [2024-10-29T22:12:50.784Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1963.532 ms) ====== [2024-10-29T22:12:50.784Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-10-29T22:12:51.720Z] GC before operation: completed in 195.829 ms, heap usage 307.088 MB -> 102.080 MB. [2024-10-29T22:12:53.627Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1929.953 ms) ====== [2024-10-29T22:12:53.627Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-10-29T22:12:53.627Z] GC before operation: completed in 224.415 ms, heap usage 273.563 MB -> 102.041 MB. [2024-10-29T22:12:55.534Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1892.439 ms) ====== [2024-10-29T22:12:55.534Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-10-29T22:12:55.534Z] GC before operation: completed in 194.032 ms, heap usage 272.705 MB -> 102.450 MB. [2024-10-29T22:12:57.440Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1892.069 ms) ====== [2024-10-29T22:12:57.440Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-10-29T22:12:57.440Z] GC before operation: completed in 232.252 ms, heap usage 277.488 MB -> 102.488 MB. [2024-10-29T22:12:59.353Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1758.006 ms) ====== [2024-10-29T22:12:59.353Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-10-29T22:12:59.353Z] GC before operation: completed in 182.233 ms, heap usage 193.787 MB -> 102.050 MB. [2024-10-29T22:13:01.259Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1622.719 ms) ====== [2024-10-29T22:13:01.259Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-10-29T22:13:01.259Z] GC before operation: completed in 214.384 ms, heap usage 228.242 MB -> 102.594 MB. [2024-10-29T22:13:03.448Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1682.075 ms) ====== [2024-10-29T22:13:03.448Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-10-29T22:13:03.448Z] GC before operation: completed in 196.240 ms, heap usage 331.612 MB -> 103.751 MB. [2024-10-29T22:13:05.357Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1616.938 ms) ====== [2024-10-29T22:13:05.357Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-10-29T22:13:05.357Z] GC before operation: completed in 250.413 ms, heap usage 182.402 MB -> 102.830 MB. [2024-10-29T22:13:07.274Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1650.952 ms) ====== [2024-10-29T22:13:07.274Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-10-29T22:13:07.274Z] GC before operation: completed in 207.259 ms, heap usage 210.547 MB -> 103.227 MB. [2024-10-29T22:13:09.184Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1598.508 ms) ====== [2024-10-29T22:13:09.184Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-10-29T22:13:09.184Z] GC before operation: completed in 211.757 ms, heap usage 137.593 MB -> 103.178 MB. [2024-10-29T22:13:11.092Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1618.792 ms) ====== [2024-10-29T22:13:11.092Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-10-29T22:13:11.092Z] GC before operation: completed in 170.340 ms, heap usage 217.728 MB -> 103.758 MB. [2024-10-29T22:13:12.023Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1645.612 ms) ====== [2024-10-29T22:13:12.023Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-10-29T22:13:12.954Z] GC before operation: completed in 222.064 ms, heap usage 301.022 MB -> 104.808 MB. [2024-10-29T22:13:13.886Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1598.183 ms) ====== [2024-10-29T22:13:13.886Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-10-29T22:13:14.815Z] GC before operation: completed in 228.983 ms, heap usage 217.646 MB -> 104.271 MB. [2024-10-29T22:13:15.745Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1528.540 ms) ====== [2024-10-29T22:13:15.745Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-10-29T22:13:15.745Z] GC before operation: completed in 191.585 ms, heap usage 396.265 MB -> 106.118 MB. [2024-10-29T22:13:17.655Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1534.499 ms) ====== [2024-10-29T22:13:17.655Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-10-29T22:13:17.655Z] GC before operation: completed in 221.334 ms, heap usage 274.218 MB -> 105.268 MB. [2024-10-29T22:13:19.567Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1440.522 ms) ====== [2024-10-29T22:13:19.567Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-10-29T22:13:19.567Z] GC before operation: completed in 210.661 ms, heap usage 268.465 MB -> 105.398 MB. [2024-10-29T22:13:21.473Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1501.714 ms) ====== [2024-10-29T22:13:21.473Z] ----------------------------------- [2024-10-29T22:13:21.474Z] renaissance-log-regression_0_PASSED [2024-10-29T22:13:21.474Z] ----------------------------------- [2024-10-29T22:13:21.474Z] [2024-10-29T22:13:21.474Z] TEST TEARDOWN: [2024-10-29T22:13:21.474Z] Nothing to be done for teardown. [2024-10-29T22:13:21.474Z] renaissance-log-regression_0 Finish Time: Tue Oct 29 22:13:20 2024 Epoch Time (ms): 1730240000821