[2024-08-16T16:34:38.764Z] Running test renaissance-log-regression_0 ... [2024-08-16T16:34:38.764Z] =============================================== [2024-08-16T16:34:38.764Z] renaissance-log-regression_0 Start Time: Fri Aug 16 12:34:38 2024 Epoch Time (ms): 1723826078281 [2024-08-16T16:34:38.764Z] variation: NoOptions [2024-08-16T16:34:38.764Z] JVM_OPTIONS: [2024-08-16T16:34:38.764Z] { \ [2024-08-16T16:34:38.764Z] echo ""; echo "TEST SETUP:"; \ [2024-08-16T16:34:38.764Z] echo "Nothing to be done for setup."; \ [2024-08-16T16:34:38.764Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238247553785/renaissance-log-regression_0"; \ [2024-08-16T16:34:38.764Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238247553785/renaissance-log-regression_0"; \ [2024-08-16T16:34:38.764Z] echo ""; echo "TESTING:"; \ [2024-08-16T16:34:38.764Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/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_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238247553785/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-16T16:34:38.764Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238247553785/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-16T16:34:38.764Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-16T16:34:38.764Z] echo "Nothing to be done for teardown."; \ [2024-08-16T16:34:38.764Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238247553785/TestTargetResult"; [2024-08-16T16:34:38.764Z] [2024-08-16T16:34:38.764Z] TEST SETUP: [2024-08-16T16:34:38.764Z] Nothing to be done for setup. [2024-08-16T16:34:38.764Z] [2024-08-16T16:34:38.764Z] TESTING: [2024-08-16T16:34:42.467Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-16T16:34:43.769Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-16T16:34:45.220Z] WARNING: An illegal reflective access operation has occurred [2024-08-16T16:34:45.220Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/output_17238247553785/renaissance-log-regression_0/harness-123439-7551947569432862737/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-16T16:34:45.220Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-16T16:34:45.220Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-16T16:34:45.220Z] WARNING: All illegal access operations will be denied in a future release [2024-08-16T16:34:46.551Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-16T16:34:47.176Z] GC before operation: completed in 64.277 ms, heap usage 60.101 MB -> 33.764 MB. [2024-08-16T16:34:59.500Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12392.700 ms) ====== [2024-08-16T16:34:59.500Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-16T16:34:59.500Z] GC before operation: completed in 166.639 ms, heap usage 243.238 MB -> 109.190 MB. [2024-08-16T16:35:04.426Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4674.080 ms) ====== [2024-08-16T16:35:04.426Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-16T16:35:04.426Z] GC before operation: completed in 216.142 ms, heap usage 272.646 MB -> 109.700 MB. [2024-08-16T16:35:08.297Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3975.533 ms) ====== [2024-08-16T16:35:08.297Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-16T16:35:08.298Z] GC before operation: completed in 125.658 ms, heap usage 274.185 MB -> 110.091 MB. [2024-08-16T16:35:12.170Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3810.444 ms) ====== [2024-08-16T16:35:12.170Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-16T16:35:12.170Z] GC before operation: completed in 145.139 ms, heap usage 225.218 MB -> 110.301 MB. [2024-08-16T16:35:17.012Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4195.466 ms) ====== [2024-08-16T16:35:17.012Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-16T16:35:17.012Z] GC before operation: completed in 120.047 ms, heap usage 271.385 MB -> 110.564 MB. [2024-08-16T16:35:20.819Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3945.831 ms) ====== [2024-08-16T16:35:20.819Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-16T16:35:20.819Z] GC before operation: completed in 162.017 ms, heap usage 253.113 MB -> 110.701 MB. [2024-08-16T16:35:24.658Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3639.018 ms) ====== [2024-08-16T16:35:24.658Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-16T16:35:24.658Z] GC before operation: completed in 136.883 ms, heap usage 260.997 MB -> 110.929 MB. [2024-08-16T16:35:28.417Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3955.449 ms) ====== [2024-08-16T16:35:28.417Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-16T16:35:29.138Z] GC before operation: completed in 158.669 ms, heap usage 235.105 MB -> 111.008 MB. [2024-08-16T16:35:33.035Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3776.664 ms) ====== [2024-08-16T16:35:33.035Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-16T16:35:33.035Z] GC before operation: completed in 224.549 ms, heap usage 270.208 MB -> 111.303 MB. [2024-08-16T16:35:37.210Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3664.402 ms) ====== [2024-08-16T16:35:37.210Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-16T16:35:37.210Z] GC before operation: completed in 285.661 ms, heap usage 242.842 MB -> 111.422 MB. [2024-08-16T16:35:40.206Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3454.046 ms) ====== [2024-08-16T16:35:40.206Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-16T16:35:40.206Z] GC before operation: completed in 182.766 ms, heap usage 224.173 MB -> 111.583 MB. [2024-08-16T16:35:44.103Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3408.076 ms) ====== [2024-08-16T16:35:44.103Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-16T16:35:44.103Z] GC before operation: completed in 160.370 ms, heap usage 252.869 MB -> 111.804 MB. [2024-08-16T16:35:47.986Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3877.806 ms) ====== [2024-08-16T16:35:47.986Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-16T16:35:47.986Z] GC before operation: completed in 134.472 ms, heap usage 260.940 MB -> 112.087 MB. [2024-08-16T16:35:51.879Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3897.579 ms) ====== [2024-08-16T16:35:51.879Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-16T16:35:51.879Z] GC before operation: completed in 140.118 ms, heap usage 271.181 MB -> 112.177 MB. [2024-08-16T16:35:55.418Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3349.703 ms) ====== [2024-08-16T16:35:55.418Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-16T16:35:55.418Z] GC before operation: completed in 175.212 ms, heap usage 288.766 MB -> 112.394 MB. [2024-08-16T16:35:58.584Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3362.387 ms) ====== [2024-08-16T16:35:58.584Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-16T16:35:59.366Z] GC before operation: completed in 161.082 ms, heap usage 235.747 MB -> 112.451 MB. [2024-08-16T16:36:03.266Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3812.644 ms) ====== [2024-08-16T16:36:03.266Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-16T16:36:03.266Z] GC before operation: completed in 181.663 ms, heap usage 236.390 MB -> 112.637 MB. [2024-08-16T16:36:06.286Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3565.275 ms) ====== [2024-08-16T16:36:06.286Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-16T16:36:06.916Z] GC before operation: completed in 200.021 ms, heap usage 270.512 MB -> 112.883 MB. [2024-08-16T16:36:10.726Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3621.601 ms) ====== [2024-08-16T16:36:10.726Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-16T16:36:10.726Z] GC before operation: completed in 223.948 ms, heap usage 271.402 MB -> 113.053 MB. [2024-08-16T16:36:13.628Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3135.341 ms) ====== [2024-08-16T16:36:14.246Z] ----------------------------------- [2024-08-16T16:36:14.246Z] renaissance-log-regression_0_PASSED [2024-08-16T16:36:14.246Z] ----------------------------------- [2024-08-16T16:36:14.246Z] [2024-08-16T16:36:14.246Z] TEST TEARDOWN: [2024-08-16T16:36:14.246Z] Nothing to be done for teardown. [2024-08-16T16:36:14.246Z] renaissance-log-regression_0 Finish Time: Fri Aug 16 12:36:13 2024 Epoch Time (ms): 1723826173946