[2024-08-08T02:43:31.041Z] Running test renaissance-log-regression_0 ... [2024-08-08T02:43:31.041Z] =============================================== [2024-08-08T02:43:31.041Z] renaissance-log-regression_0 Start Time: Thu Aug 8 02:43:30 2024 Epoch Time (ms): 1723085010271 [2024-08-08T02:43:31.041Z] variation: NoOptions [2024-08-08T02:43:31.041Z] JVM_OPTIONS: [2024-08-08T02:43:31.041Z] { \ [2024-08-08T02:43:31.041Z] echo ""; echo "TEST SETUP:"; \ [2024-08-08T02:43:31.041Z] echo "Nothing to be done for setup."; \ [2024-08-08T02:43:31.041Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17230842831066/renaissance-log-regression_0"; \ [2024-08-08T02:43:31.041Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17230842831066/renaissance-log-regression_0"; \ [2024-08-08T02:43:31.041Z] echo ""; echo "TESTING:"; \ [2024-08-08T02:43:31.041Z] "/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_17230842831066/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-08T02:43:31.041Z] 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_17230842831066/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-08T02:43:31.041Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-08T02:43:31.041Z] echo "Nothing to be done for teardown."; \ [2024-08-08T02:43:31.041Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17230842831066/TestTargetResult"; [2024-08-08T02:43:31.041Z] [2024-08-08T02:43:31.041Z] TEST SETUP: [2024-08-08T02:43:31.041Z] Nothing to be done for setup. [2024-08-08T02:43:31.041Z] [2024-08-08T02:43:31.041Z] TESTING: [2024-08-08T02:43:33.756Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-08T02:43:34.350Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-08T02:43:35.585Z] WARNING: An illegal reflective access operation has occurred [2024-08-08T02:43:35.585Z] 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_17230842831066/renaissance-log-regression_0/harness-024331-1928823833195257738/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-08T02:43:35.585Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-08T02:43:35.585Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-08T02:43:35.585Z] WARNING: All illegal access operations will be denied in a future release [2024-08-08T02:43:36.826Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-08T02:43:36.826Z] GC before operation: completed in 65.047 ms, heap usage 47.334 MB -> 33.728 MB. [2024-08-08T02:43:45.122Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8090.210 ms) ====== [2024-08-08T02:43:45.122Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-08T02:43:45.122Z] GC before operation: completed in 126.482 ms, heap usage 275.674 MB -> 109.218 MB. [2024-08-08T02:43:47.874Z] 24/08/08 02:43:47 WARN BlockManager: Asked to remove block broadcast_67, which does not exist [2024-08-08T02:43:49.119Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3628.462 ms) ====== [2024-08-08T02:43:49.119Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-08T02:43:49.119Z] GC before operation: completed in 132.311 ms, heap usage 234.283 MB -> 109.589 MB. [2024-08-08T02:43:52.728Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3171.294 ms) ====== [2024-08-08T02:43:52.729Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-08T02:43:52.729Z] GC before operation: completed in 111.733 ms, heap usage 253.861 MB -> 109.998 MB. [2024-08-08T02:43:55.476Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2985.409 ms) ====== [2024-08-08T02:43:55.476Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-08T02:43:55.476Z] GC before operation: completed in 95.245 ms, heap usage 259.861 MB -> 110.347 MB. [2024-08-08T02:43:58.233Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2521.437 ms) ====== [2024-08-08T02:43:58.233Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-08T02:43:58.233Z] GC before operation: completed in 103.796 ms, heap usage 289.474 MB -> 110.592 MB. [2024-08-08T02:44:00.992Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2624.036 ms) ====== [2024-08-08T02:44:00.992Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-08T02:44:00.992Z] GC before operation: completed in 122.084 ms, heap usage 270.724 MB -> 110.736 MB. [2024-08-08T02:44:03.723Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2901.384 ms) ====== [2024-08-08T02:44:03.723Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-08T02:44:03.723Z] GC before operation: completed in 116.504 ms, heap usage 234.274 MB -> 110.822 MB. [2024-08-08T02:44:06.477Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2904.016 ms) ====== [2024-08-08T02:44:06.477Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-08T02:44:07.071Z] GC before operation: completed in 136.214 ms, heap usage 278.015 MB -> 111.139 MB. [2024-08-08T02:44:09.785Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2568.541 ms) ====== [2024-08-08T02:44:09.785Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-08T02:44:09.785Z] GC before operation: completed in 117.078 ms, heap usage 251.451 MB -> 111.215 MB. [2024-08-08T02:44:11.729Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2413.157 ms) ====== [2024-08-08T02:44:11.729Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-08T02:44:12.341Z] GC before operation: completed in 130.876 ms, heap usage 234.126 MB -> 111.341 MB. [2024-08-08T02:44:14.277Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2392.963 ms) ====== [2024-08-08T02:44:14.277Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-08T02:44:14.870Z] GC before operation: completed in 101.507 ms, heap usage 234.610 MB -> 111.522 MB. [2024-08-08T02:44:17.622Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2621.167 ms) ====== [2024-08-08T02:44:17.622Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-08T02:44:17.622Z] GC before operation: completed in 148.052 ms, heap usage 253.306 MB -> 111.769 MB. [2024-08-08T02:44:19.604Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2521.972 ms) ====== [2024-08-08T02:44:19.604Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-08T02:44:20.196Z] GC before operation: completed in 131.866 ms, heap usage 261.973 MB -> 111.967 MB. [2024-08-08T02:44:23.282Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2596.608 ms) ====== [2024-08-08T02:44:23.282Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-08T02:44:23.282Z] GC before operation: completed in 137.628 ms, heap usage 243.156 MB -> 112.092 MB. [2024-08-08T02:44:25.268Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2622.414 ms) ====== [2024-08-08T02:44:25.268Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-08T02:44:25.268Z] GC before operation: completed in 128.296 ms, heap usage 235.484 MB -> 112.215 MB. [2024-08-08T02:44:28.034Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2516.038 ms) ====== [2024-08-08T02:44:28.034Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-08T02:44:28.034Z] GC before operation: completed in 116.836 ms, heap usage 270.705 MB -> 112.590 MB. [2024-08-08T02:44:30.787Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2610.238 ms) ====== [2024-08-08T02:44:30.787Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-08T02:44:30.787Z] GC before operation: completed in 123.142 ms, heap usage 236.208 MB -> 112.597 MB. [2024-08-08T02:44:33.537Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2575.197 ms) ====== [2024-08-08T02:44:33.537Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-08T02:44:33.537Z] GC before operation: completed in 140.043 ms, heap usage 270.785 MB -> 112.860 MB. [2024-08-08T02:44:36.256Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2521.204 ms) ====== [2024-08-08T02:44:36.256Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-08T02:44:36.256Z] GC before operation: completed in 95.568 ms, heap usage 279.712 MB -> 113.077 MB. [2024-08-08T02:44:38.982Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2473.354 ms) ====== [2024-08-08T02:44:38.982Z] ----------------------------------- [2024-08-08T02:44:38.982Z] renaissance-log-regression_0_PASSED [2024-08-08T02:44:38.982Z] ----------------------------------- [2024-08-08T02:44:38.982Z] [2024-08-08T02:44:38.982Z] TEST TEARDOWN: [2024-08-08T02:44:38.982Z] Nothing to be done for teardown. [2024-08-08T02:44:38.982Z] renaissance-log-regression_0 Finish Time: Thu Aug 8 02:44:38 2024 Epoch Time (ms): 1723085078599