[2024-08-01T01:08:15.569Z] Running test renaissance-log-regression_0 ... [2024-08-01T01:08:15.569Z] =============================================== [2024-08-01T01:08:15.569Z] renaissance-log-regression_0 Start Time: Wed Jul 31 21:08:15 2024 Epoch Time (ms): 1722474495206 [2024-08-01T01:08:15.569Z] variation: NoOptions [2024-08-01T01:08:15.569Z] JVM_OPTIONS: [2024-08-01T01:08:15.569Z] { \ [2024-08-01T01:08:15.569Z] echo ""; echo "TEST SETUP:"; \ [2024-08-01T01:08:15.569Z] echo "Nothing to be done for setup."; \ [2024-08-01T01:08:15.569Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/renaissance-log-regression_0"; \ [2024-08-01T01:08:15.569Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/renaissance-log-regression_0"; \ [2024-08-01T01:08:15.569Z] echo ""; echo "TESTING:"; \ [2024-08-01T01:08:15.569Z] "/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_17224737612761/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-01T01:08:15.569Z] 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_17224737612761/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-01T01:08:15.569Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-01T01:08:15.569Z] echo "Nothing to be done for teardown."; \ [2024-08-01T01:08:15.569Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/TestTargetResult"; [2024-08-01T01:08:15.569Z] [2024-08-01T01:08:15.569Z] TEST SETUP: [2024-08-01T01:08:15.569Z] Nothing to be done for setup. [2024-08-01T01:08:15.569Z] [2024-08-01T01:08:15.569Z] TESTING: [2024-08-01T01:08:17.606Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-01T01:08:18.902Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-01T01:08:19.858Z] WARNING: An illegal reflective access operation has occurred [2024-08-01T01:08:19.858Z] 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_17224737612761/renaissance-log-regression_0/harness-210815-17999104641472169125/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-01T01:08:19.858Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-01T01:08:19.858Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-01T01:08:19.858Z] WARNING: All illegal access operations will be denied in a future release [2024-08-01T01:08:21.982Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-01T01:08:21.982Z] GC before operation: completed in 84.675 ms, heap usage 68.317 MB -> 33.765 MB. [2024-08-01T01:08:30.732Z] 24/07/31 21:08:30 WARN BlockManager: Asked to remove block broadcast_16, which does not exist [2024-08-01T01:08:33.573Z] 24/07/31 21:08:32 WARN BlockManager: Asked to remove block broadcast_32_piece0, which does not exist [2024-08-01T01:08:34.193Z] 24/07/31 21:08:33 WARN BlockManager: Asked to remove block broadcast_42_piece0, which does not exist [2024-08-01T01:08:34.193Z] 24/07/31 21:08:33 WARN BlockManager: Asked to remove block broadcast_42, which does not exist [2024-08-01T01:08:34.193Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12617.077 ms) ====== [2024-08-01T01:08:34.193Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-01T01:08:34.193Z] GC before operation: completed in 101.516 ms, heap usage 264.324 MB -> 109.231 MB. [2024-08-01T01:08:37.073Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2936.049 ms) ====== [2024-08-01T01:08:37.073Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-01T01:08:37.683Z] GC before operation: completed in 87.785 ms, heap usage 234.418 MB -> 109.612 MB. [2024-08-01T01:08:40.534Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2714.095 ms) ====== [2024-08-01T01:08:40.534Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-01T01:08:40.534Z] GC before operation: completed in 112.543 ms, heap usage 262.896 MB -> 110.073 MB. [2024-08-01T01:08:43.417Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2928.536 ms) ====== [2024-08-01T01:08:43.417Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-01T01:08:43.417Z] GC before operation: completed in 127.492 ms, heap usage 271.473 MB -> 110.357 MB. [2024-08-01T01:08:46.202Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2855.029 ms) ====== [2024-08-01T01:08:46.202Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-01T01:08:46.202Z] GC before operation: completed in 115.964 ms, heap usage 234.927 MB -> 110.457 MB. [2024-08-01T01:08:49.053Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2437.243 ms) ====== [2024-08-01T01:08:49.053Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-01T01:08:49.053Z] GC before operation: completed in 82.877 ms, heap usage 288.128 MB -> 110.780 MB. [2024-08-01T01:08:51.094Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2555.229 ms) ====== [2024-08-01T01:08:51.094Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-01T01:08:51.740Z] GC before operation: completed in 76.117 ms, heap usage 253.669 MB -> 110.877 MB. [2024-08-01T01:08:53.732Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2141.691 ms) ====== [2024-08-01T01:08:53.732Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-01T01:08:53.732Z] GC before operation: completed in 59.801 ms, heap usage 253.475 MB -> 111.057 MB. [2024-08-01T01:08:55.803Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2188.588 ms) ====== [2024-08-01T01:08:55.803Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-01T01:08:55.803Z] GC before operation: completed in 79.162 ms, heap usage 252.666 MB -> 111.265 MB. [2024-08-01T01:08:58.622Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2305.562 ms) ====== [2024-08-01T01:08:58.622Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-01T01:08:58.622Z] GC before operation: completed in 181.850 ms, heap usage 234.296 MB -> 111.398 MB. [2024-08-01T01:08:59.920Z] 24/07/31 21:08:59 WARN BlockManager: Asked to remove block broadcast_482, which does not exist [2024-08-01T01:09:01.329Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2660.004 ms) ====== [2024-08-01T01:09:01.329Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-01T01:09:01.329Z] GC before operation: completed in 149.799 ms, heap usage 225.348 MB -> 111.546 MB. [2024-08-01T01:09:04.213Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2665.538 ms) ====== [2024-08-01T01:09:04.213Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-01T01:09:04.213Z] GC before operation: completed in 115.129 ms, heap usage 241.924 MB -> 111.761 MB. [2024-08-01T01:09:05.590Z] 24/07/31 21:09:05 WARN BlockManager: Asked to remove block broadcast_576, which does not exist [2024-08-01T01:09:06.223Z] 24/07/31 21:09:06 WARN BlockManager: Asked to remove block broadcast_606, which does not exist [2024-08-01T01:09:06.851Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2666.402 ms) ====== [2024-08-01T01:09:06.851Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-01T01:09:06.851Z] GC before operation: completed in 90.658 ms, heap usage 252.903 MB -> 111.930 MB. [2024-08-01T01:09:08.883Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2353.480 ms) ====== [2024-08-01T01:09:08.883Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-01T01:09:09.562Z] GC before operation: completed in 81.861 ms, heap usage 234.948 MB -> 112.063 MB. [2024-08-01T01:09:12.401Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2809.744 ms) ====== [2024-08-01T01:09:12.401Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-01T01:09:12.401Z] GC before operation: completed in 131.303 ms, heap usage 253.068 MB -> 112.258 MB. [2024-08-01T01:09:15.253Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2922.441 ms) ====== [2024-08-01T01:09:15.253Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-01T01:09:15.253Z] GC before operation: completed in 126.690 ms, heap usage 270.443 MB -> 112.543 MB. [2024-08-01T01:09:17.272Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2344.469 ms) ====== [2024-08-01T01:09:17.272Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-01T01:09:17.888Z] GC before operation: completed in 150.637 ms, heap usage 254.109 MB -> 112.685 MB. [2024-08-01T01:09:19.902Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2247.984 ms) ====== [2024-08-01T01:09:19.902Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-01T01:09:19.902Z] GC before operation: completed in 109.079 ms, heap usage 253.604 MB -> 112.856 MB. [2024-08-01T01:09:21.888Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2003.429 ms) ====== [2024-08-01T01:09:21.889Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-01T01:09:21.889Z] GC before operation: completed in 149.095 ms, heap usage 325.241 MB -> 113.184 MB. [2024-08-01T01:09:24.684Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2077.854 ms) ====== [2024-08-01T01:09:24.684Z] ----------------------------------- [2024-08-01T01:09:24.684Z] renaissance-log-regression_0_PASSED [2024-08-01T01:09:24.684Z] ----------------------------------- [2024-08-01T01:09:24.684Z] [2024-08-01T01:09:24.684Z] TEST TEARDOWN: [2024-08-01T01:09:24.684Z] Nothing to be done for teardown. [2024-08-01T01:09:24.684Z] renaissance-log-regression_0 Finish Time: Wed Jul 31 21:09:24 2024 Epoch Time (ms): 1722474564202