[2024-09-04T23:54:32.422Z] Running test renaissance-log-regression_0 ... [2024-09-04T23:54:32.422Z] =============================================== [2024-09-04T23:54:32.422Z] renaissance-log-regression_0 Start Time: Wed Sep 4 19:54:31 2024 Epoch Time (ms): 1725494071539 [2024-09-04T23:54:32.422Z] variation: NoOptions [2024-09-04T23:54:32.422Z] JVM_OPTIONS: [2024-09-04T23:54:32.422Z] { \ [2024-09-04T23:54:32.422Z] echo ""; echo "TEST SETUP:"; \ [2024-09-04T23:54:32.422Z] echo "Nothing to be done for setup."; \ [2024-09-04T23:54:32.422Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17254929383975/renaissance-log-regression_0"; \ [2024-09-04T23:54:32.422Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17254929383975/renaissance-log-regression_0"; \ [2024-09-04T23:54:32.422Z] echo ""; echo "TESTING:"; \ [2024-09-04T23:54:32.422Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17254929383975/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-04T23:54:32.422Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17254929383975/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-04T23:54:32.422Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-04T23:54:32.422Z] echo "Nothing to be done for teardown."; \ [2024-09-04T23:54:32.422Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17254929383975/TestTargetResult"; [2024-09-04T23:54:32.422Z] [2024-09-04T23:54:32.422Z] TEST SETUP: [2024-09-04T23:54:32.422Z] Nothing to be done for setup. [2024-09-04T23:54:32.422Z] [2024-09-04T23:54:32.422Z] TESTING: [2024-09-04T23:54:35.470Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-04T23:54:37.789Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-09-04T23:54:41.788Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-04T23:54:41.788Z] GC before operation: completed in 124.174 ms, heap usage 61.678 MB -> 35.010 MB. [2024-09-04T23:54:54.654Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11905.968 ms) ====== [2024-09-04T23:54:54.654Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-04T23:54:54.654Z] GC before operation: completed in 156.892 ms, heap usage 284.619 MB -> 110.531 MB. [2024-09-04T23:54:58.708Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4951.931 ms) ====== [2024-09-04T23:54:58.708Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-04T23:54:58.708Z] GC before operation: completed in 104.554 ms, heap usage 341.342 MB -> 111.194 MB. [2024-09-04T23:55:02.318Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3427.674 ms) ====== [2024-09-04T23:55:02.318Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-04T23:55:02.318Z] GC before operation: completed in 92.916 ms, heap usage 279.362 MB -> 111.437 MB. [2024-09-04T23:55:05.310Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3049.500 ms) ====== [2024-09-04T23:55:05.310Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-04T23:55:05.310Z] GC before operation: completed in 124.058 ms, heap usage 284.243 MB -> 111.805 MB. [2024-09-04T23:55:09.336Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3391.883 ms) ====== [2024-09-04T23:55:09.336Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-04T23:55:09.336Z] GC before operation: completed in 105.094 ms, heap usage 300.467 MB -> 112.078 MB. [2024-09-04T23:55:12.472Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2995.746 ms) ====== [2024-09-04T23:55:12.472Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-04T23:55:12.472Z] GC before operation: completed in 133.169 ms, heap usage 317.246 MB -> 112.231 MB. [2024-09-04T23:55:15.537Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3344.489 ms) ====== [2024-09-04T23:55:15.537Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-04T23:55:15.537Z] GC before operation: completed in 162.008 ms, heap usage 294.511 MB -> 112.444 MB. [2024-09-04T23:55:18.589Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3061.066 ms) ====== [2024-09-04T23:55:18.590Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-04T23:55:18.590Z] GC before operation: completed in 196.781 ms, heap usage 292.755 MB -> 112.605 MB. [2024-09-04T23:55:22.508Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3434.426 ms) ====== [2024-09-04T23:55:22.508Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-04T23:55:22.508Z] GC before operation: completed in 129.903 ms, heap usage 282.021 MB -> 112.883 MB. [2024-09-04T23:55:25.482Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2651.340 ms) ====== [2024-09-04T23:55:25.483Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-04T23:55:25.483Z] GC before operation: completed in 137.743 ms, heap usage 247.752 MB -> 112.847 MB. [2024-09-04T23:55:28.500Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2839.748 ms) ====== [2024-09-04T23:55:28.500Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-04T23:55:28.500Z] GC before operation: completed in 133.762 ms, heap usage 255.068 MB -> 112.994 MB. [2024-09-04T23:55:31.609Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2816.085 ms) ====== [2024-09-04T23:55:31.609Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-04T23:55:31.609Z] GC before operation: completed in 155.586 ms, heap usage 226.789 MB -> 113.197 MB. [2024-09-04T23:55:33.000Z] 24/09/04 19:55:32 WARN BlockManager: Asked to remove block broadcast_576, which does not exist [2024-09-04T23:55:34.398Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2742.853 ms) ====== [2024-09-04T23:55:34.398Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-04T23:55:34.398Z] GC before operation: completed in 148.390 ms, heap usage 272.204 MB -> 113.391 MB. [2024-09-04T23:55:36.649Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2599.796 ms) ====== [2024-09-04T23:55:36.649Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-04T23:55:36.649Z] GC before operation: completed in 192.193 ms, heap usage 255.211 MB -> 113.540 MB. [2024-09-04T23:55:39.780Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3007.838 ms) ====== [2024-09-04T23:55:39.780Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-04T23:55:39.780Z] GC before operation: completed in 108.697 ms, heap usage 262.395 MB -> 113.768 MB. [2024-09-04T23:55:42.880Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2714.623 ms) ====== [2024-09-04T23:55:42.880Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-04T23:55:42.880Z] GC before operation: completed in 168.339 ms, heap usage 316.827 MB -> 114.177 MB. [2024-09-04T23:55:46.319Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3296.694 ms) ====== [2024-09-04T23:55:46.319Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-04T23:55:46.319Z] GC before operation: completed in 155.616 ms, heap usage 228.217 MB -> 114.279 MB. [2024-09-04T23:55:50.308Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3495.757 ms) ====== [2024-09-04T23:55:50.308Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-04T23:55:50.308Z] GC before operation: completed in 152.111 ms, heap usage 299.401 MB -> 114.394 MB. [2024-09-04T23:55:52.491Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2236.843 ms) ====== [2024-09-04T23:55:52.491Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-04T23:55:52.491Z] GC before operation: completed in 178.095 ms, heap usage 264.506 MB -> 114.516 MB. [2024-09-04T23:55:56.515Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3502.162 ms) ====== [2024-09-04T23:55:56.515Z] ----------------------------------- [2024-09-04T23:55:56.515Z] renaissance-log-regression_0_PASSED [2024-09-04T23:55:56.515Z] ----------------------------------- [2024-09-04T23:55:56.515Z] [2024-09-04T23:55:56.515Z] TEST TEARDOWN: [2024-09-04T23:55:56.515Z] Nothing to be done for teardown. [2024-09-04T23:55:56.515Z] renaissance-log-regression_0 Finish Time: Wed Sep 4 19:55:56 2024 Epoch Time (ms): 1725494156324