[2025-02-12T21:52:31.362Z] Running test renaissance-log-regression_0 ... [2025-02-12T21:52:31.362Z] =============================================== [2025-02-12T21:52:31.362Z] renaissance-log-regression_0 Start Time: Wed Feb 12 21:52:30 2025 Epoch Time (ms): 1739397150521 [2025-02-12T21:52:31.362Z] variation: NoOptions [2025-02-12T21:52:31.362Z] JVM_OPTIONS: [2025-02-12T21:52:31.362Z] { \ [2025-02-12T21:52:31.362Z] echo ""; echo "TEST SETUP:"; \ [2025-02-12T21:52:31.362Z] echo "Nothing to be done for setup."; \ [2025-02-12T21:52:31.362Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17393964736666/renaissance-log-regression_0"; \ [2025-02-12T21:52:31.362Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17393964736666/renaissance-log-regression_0"; \ [2025-02-12T21:52:31.362Z] echo ""; echo "TESTING:"; \ [2025-02-12T21:52:31.362Z] "/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_17393964736666/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-12T21:52:31.362Z] 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_17393964736666/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-12T21:52:31.362Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-12T21:52:31.362Z] echo "Nothing to be done for teardown."; \ [2025-02-12T21:52:31.362Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17393964736666/TestTargetResult"; [2025-02-12T21:52:31.362Z] [2025-02-12T21:52:31.362Z] TEST SETUP: [2025-02-12T21:52:31.362Z] Nothing to be done for setup. [2025-02-12T21:52:31.362Z] [2025-02-12T21:52:31.362Z] TESTING: [2025-02-12T21:52:34.422Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-12T21:52:35.380Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-12T21:52:38.408Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-12T21:52:38.408Z] GC before operation: completed in 47.339 ms, heap usage 70.280 MB -> 35.120 MB. [2025-02-12T21:52:45.137Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6045.596 ms) ====== [2025-02-12T21:52:45.137Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-12T21:52:45.137Z] GC before operation: completed in 86.433 ms, heap usage 301.342 MB -> 113.247 MB. [2025-02-12T21:52:46.092Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1651.907 ms) ====== [2025-02-12T21:52:46.092Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-12T21:52:46.092Z] GC before operation: completed in 51.620 ms, heap usage 455.741 MB -> 114.236 MB. [2025-02-12T21:52:48.054Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1458.475 ms) ====== [2025-02-12T21:52:48.054Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-12T21:52:48.054Z] GC before operation: completed in 71.349 ms, heap usage 423.899 MB -> 114.643 MB. [2025-02-12T21:52:49.010Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1386.055 ms) ====== [2025-02-12T21:52:49.010Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-12T21:52:49.010Z] GC before operation: completed in 78.426 ms, heap usage 651.612 MB -> 115.364 MB. [2025-02-12T21:52:50.971Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1289.390 ms) ====== [2025-02-12T21:52:50.971Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-12T21:52:50.971Z] GC before operation: completed in 67.368 ms, heap usage 402.605 MB -> 115.069 MB. [2025-02-12T21:52:51.946Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1237.474 ms) ====== [2025-02-12T21:52:51.946Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-12T21:52:51.946Z] GC before operation: completed in 71.327 ms, heap usage 429.997 MB -> 115.286 MB. [2025-02-12T21:52:52.901Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1222.772 ms) ====== [2025-02-12T21:52:52.901Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-12T21:52:53.894Z] GC before operation: completed in 79.035 ms, heap usage 410.184 MB -> 115.487 MB. [2025-02-12T21:52:54.852Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1247.795 ms) ====== [2025-02-12T21:52:54.852Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-12T21:52:54.852Z] GC before operation: completed in 86.224 ms, heap usage 408.551 MB -> 115.726 MB. [2025-02-12T21:52:55.826Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1202.727 ms) ====== [2025-02-12T21:52:55.826Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-12T21:52:55.826Z] GC before operation: completed in 81.173 ms, heap usage 408.123 MB -> 115.941 MB. [2025-02-12T21:52:56.784Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1170.633 ms) ====== [2025-02-12T21:52:56.784Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-12T21:52:57.742Z] GC before operation: completed in 85.397 ms, heap usage 396.897 MB -> 116.072 MB. [2025-02-12T21:52:58.698Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1218.905 ms) ====== [2025-02-12T21:52:58.698Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-12T21:52:58.698Z] GC before operation: completed in 93.993 ms, heap usage 443.903 MB -> 116.395 MB. [2025-02-12T21:52:59.655Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1297.159 ms) ====== [2025-02-12T21:52:59.655Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-12T21:52:59.655Z] GC before operation: completed in 110.289 ms, heap usage 397.390 MB -> 116.500 MB. [2025-02-12T21:53:01.635Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1270.915 ms) ====== [2025-02-12T21:53:01.635Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-12T21:53:01.635Z] GC before operation: completed in 92.857 ms, heap usage 302.997 MB -> 116.439 MB. [2025-02-12T21:53:02.593Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1206.401 ms) ====== [2025-02-12T21:53:02.593Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-12T21:53:02.593Z] GC before operation: completed in 86.155 ms, heap usage 283.893 MB -> 116.677 MB. [2025-02-12T21:53:03.548Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1239.846 ms) ====== [2025-02-12T21:53:03.548Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-12T21:53:03.548Z] GC before operation: completed in 75.422 ms, heap usage 256.322 MB -> 116.759 MB. [2025-02-12T21:53:05.508Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1157.053 ms) ====== [2025-02-12T21:53:05.508Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-12T21:53:05.508Z] GC before operation: completed in 75.365 ms, heap usage 237.433 MB -> 116.971 MB. [2025-02-12T21:53:06.586Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1165.711 ms) ====== [2025-02-12T21:53:06.586Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-12T21:53:06.586Z] GC before operation: completed in 79.498 ms, heap usage 529.554 MB -> 117.857 MB. [2025-02-12T21:53:07.544Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1179.706 ms) ====== [2025-02-12T21:53:07.544Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-12T21:53:07.544Z] GC before operation: completed in 79.447 ms, heap usage 368.421 MB -> 117.607 MB. [2025-02-12T21:53:08.521Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1129.427 ms) ====== [2025-02-12T21:53:08.521Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-12T21:53:08.521Z] GC before operation: completed in 76.350 ms, heap usage 379.980 MB -> 117.874 MB. [2025-02-12T21:53:10.481Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1235.628 ms) ====== [2025-02-12T21:53:10.481Z] ----------------------------------- [2025-02-12T21:53:10.481Z] renaissance-log-regression_0_PASSED [2025-02-12T21:53:10.481Z] ----------------------------------- [2025-02-12T21:53:10.481Z] [2025-02-12T21:53:10.481Z] TEST TEARDOWN: [2025-02-12T21:53:10.481Z] Nothing to be done for teardown. [2025-02-12T21:53:10.481Z] renaissance-log-regression_0 Finish Time: Wed Feb 12 21:53:09 2025 Epoch Time (ms): 1739397189816