[2024-11-15T21:20:54.589Z] Running test renaissance-log-regression_0 ... [2024-11-15T21:20:54.589Z] =============================================== [2024-11-15T21:20:54.589Z] renaissance-log-regression_0 Start Time: Fri Nov 15 21:20:53 2024 Epoch Time (ms): 1731705653885 [2024-11-15T21:20:54.589Z] variation: NoOptions [2024-11-15T21:20:54.589Z] JVM_OPTIONS: [2024-11-15T21:20:54.589Z] { \ [2024-11-15T21:20:54.589Z] echo ""; echo "TEST SETUP:"; \ [2024-11-15T21:20:54.589Z] echo "Nothing to be done for setup."; \ [2024-11-15T21:20:54.589Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317049706210/renaissance-log-regression_0"; \ [2024-11-15T21:20:54.589Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317049706210/renaissance-log-regression_0"; \ [2024-11-15T21:20:54.589Z] echo ""; echo "TESTING:"; \ [2024-11-15T21:20:54.589Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317049706210/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-15T21:20:54.589Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317049706210/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-15T21:20:54.589Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-15T21:20:54.589Z] echo "Nothing to be done for teardown."; \ [2024-11-15T21:20:54.589Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317049706210/TestTargetResult"; [2024-11-15T21:20:54.589Z] [2024-11-15T21:20:54.589Z] TEST SETUP: [2024-11-15T21:20:54.589Z] Nothing to be done for setup. [2024-11-15T21:20:54.589Z] [2024-11-15T21:20:54.589Z] TESTING: [2024-11-15T21:20:57.662Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-15T21:20:59.446Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-15T21:21:01.702Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-15T21:21:01.702Z] GC before operation: completed in 66.538 ms, heap usage 92.917 MB -> 35.111 MB. [2024-11-15T21:21:08.286Z] ====== log-regression (apache-spark) [default], iteration 0 completed (5835.042 ms) ====== [2024-11-15T21:21:08.286Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-15T21:21:08.286Z] GC before operation: completed in 69.451 ms, heap usage 536.782 MB -> 113.729 MB. [2024-11-15T21:21:09.219Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1627.154 ms) ====== [2024-11-15T21:21:09.219Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-15T21:21:09.219Z] GC before operation: completed in 54.579 ms, heap usage 428.091 MB -> 114.103 MB. [2024-11-15T21:21:11.135Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1459.484 ms) ====== [2024-11-15T21:21:11.135Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-15T21:21:11.135Z] GC before operation: completed in 55.376 ms, heap usage 442.883 MB -> 114.572 MB. [2024-11-15T21:21:12.067Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1347.623 ms) ====== [2024-11-15T21:21:12.067Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-15T21:21:12.067Z] GC before operation: completed in 61.728 ms, heap usage 416.318 MB -> 114.761 MB. [2024-11-15T21:21:13.983Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1256.742 ms) ====== [2024-11-15T21:21:13.983Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-15T21:21:13.983Z] GC before operation: completed in 56.635 ms, heap usage 416.238 MB -> 114.970 MB. [2024-11-15T21:21:14.918Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1316.243 ms) ====== [2024-11-15T21:21:14.918Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-15T21:21:14.918Z] GC before operation: completed in 61.686 ms, heap usage 412.053 MB -> 115.249 MB. [2024-11-15T21:21:15.851Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1251.385 ms) ====== [2024-11-15T21:21:15.851Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-15T21:21:15.851Z] GC before operation: completed in 63.003 ms, heap usage 411.115 MB -> 115.488 MB. [2024-11-15T21:21:17.774Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1206.177 ms) ====== [2024-11-15T21:21:17.774Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-15T21:21:17.774Z] GC before operation: completed in 79.296 ms, heap usage 400.150 MB -> 115.560 MB. [2024-11-15T21:21:18.707Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1246.862 ms) ====== [2024-11-15T21:21:18.707Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-15T21:21:18.707Z] GC before operation: completed in 67.149 ms, heap usage 414.580 MB -> 115.791 MB. [2024-11-15T21:21:19.641Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1196.269 ms) ====== [2024-11-15T21:21:19.641Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-15T21:21:20.577Z] GC before operation: completed in 75.112 ms, heap usage 432.271 MB -> 116.047 MB. [2024-11-15T21:21:21.511Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1170.658 ms) ====== [2024-11-15T21:21:21.511Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-15T21:21:21.511Z] GC before operation: completed in 67.585 ms, heap usage 411.588 MB -> 116.214 MB. [2024-11-15T21:21:22.445Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1226.985 ms) ====== [2024-11-15T21:21:22.445Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-15T21:21:22.445Z] GC before operation: completed in 63.822 ms, heap usage 424.458 MB -> 116.482 MB. [2024-11-15T21:21:23.378Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1078.119 ms) ====== [2024-11-15T21:21:23.378Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-15T21:21:23.378Z] GC before operation: completed in 70.826 ms, heap usage 405.976 MB -> 116.543 MB. [2024-11-15T21:21:25.298Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1117.975 ms) ====== [2024-11-15T21:21:25.298Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-15T21:21:25.298Z] GC before operation: completed in 70.774 ms, heap usage 411.752 MB -> 116.825 MB. [2024-11-15T21:21:26.233Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1116.066 ms) ====== [2024-11-15T21:21:26.233Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-15T21:21:26.233Z] GC before operation: completed in 65.231 ms, heap usage 395.557 MB -> 117.007 MB. [2024-11-15T21:21:27.166Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1117.793 ms) ====== [2024-11-15T21:21:27.166Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-15T21:21:27.166Z] GC before operation: completed in 69.005 ms, heap usage 407.094 MB -> 117.225 MB. [2024-11-15T21:21:28.103Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1182.974 ms) ====== [2024-11-15T21:21:28.103Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-15T21:21:29.044Z] GC before operation: completed in 66.135 ms, heap usage 532.887 MB -> 117.738 MB. [2024-11-15T21:21:29.977Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1097.644 ms) ====== [2024-11-15T21:21:29.977Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-15T21:21:29.977Z] GC before operation: completed in 68.983 ms, heap usage 395.630 MB -> 117.590 MB. [2024-11-15T21:21:30.911Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1114.948 ms) ====== [2024-11-15T21:21:30.911Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-15T21:21:30.911Z] GC before operation: completed in 67.344 ms, heap usage 379.583 MB -> 117.794 MB. [2024-11-15T21:21:31.867Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1108.262 ms) ====== [2024-11-15T21:21:31.867Z] ----------------------------------- [2024-11-15T21:21:31.867Z] renaissance-log-regression_0_PASSED [2024-11-15T21:21:31.867Z] ----------------------------------- [2024-11-15T21:21:31.867Z] [2024-11-15T21:21:31.867Z] TEST TEARDOWN: [2024-11-15T21:21:31.867Z] Nothing to be done for teardown. [2024-11-15T21:21:31.867Z] renaissance-log-regression_0 Finish Time: Fri Nov 15 21:21:31 2024 Epoch Time (ms): 1731705691769