[2025-05-17T02:57:27.066Z] Running test renaissance-log-regression_0 ... [2025-05-17T02:57:27.066Z] =============================================== [2025-05-17T02:57:27.066Z] renaissance-log-regression_0 Start Time: Sat May 17 02:57:26 2025 Epoch Time (ms): 1747450646899 [2025-05-17T02:57:27.066Z] variation: NoOptions [2025-05-17T02:57:27.066Z] JVM_OPTIONS: [2025-05-17T02:57:27.066Z] { \ [2025-05-17T02:57:27.066Z] echo ""; echo "TEST SETUP:"; \ [2025-05-17T02:57:27.066Z] echo "Nothing to be done for setup."; \ [2025-05-17T02:57:27.066Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17474472053568/renaissance-log-regression_0"; \ [2025-05-17T02:57:27.066Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17474472053568/renaissance-log-regression_0"; \ [2025-05-17T02:57:27.066Z] echo ""; echo "TESTING:"; \ [2025-05-17T02:57:27.066Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17474472053568/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-17T02:57:27.066Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17474472053568/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-17T02:57:27.066Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-17T02:57:27.066Z] echo "Nothing to be done for teardown."; \ [2025-05-17T02:57:27.066Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17474472053568/TestTargetResult"; [2025-05-17T02:57:27.066Z] [2025-05-17T02:57:27.066Z] TEST SETUP: [2025-05-17T02:57:27.066Z] Nothing to be done for setup. [2025-05-17T02:57:27.066Z] [2025-05-17T02:57:27.066Z] TESTING: [2025-05-17T02:57:34.508Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-17T02:57:35.147Z] WARNING: An illegal reflective access operation has occurred [2025-05-17T02:57:35.147Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17474472053568/renaissance-log-regression_0/harness-025727-8962714829574459996/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-17T02:57:35.147Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-17T02:57:35.147Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-17T02:57:35.147Z] WARNING: All illegal access operations will be denied in a future release [2025-05-17T02:57:36.557Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-17T02:57:37.220Z] GC before operation: completed in 82.646 ms, heap usage 50.315 MB -> 34.376 MB. [2025-05-17T02:57:54.605Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17899.381 ms) ====== [2025-05-17T02:57:54.605Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-17T02:57:55.254Z] GC before operation: completed in 185.962 ms, heap usage 275.315 MB -> 115.590 MB. [2025-05-17T02:58:00.077Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4656.116 ms) ====== [2025-05-17T02:58:00.077Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-17T02:58:00.077Z] GC before operation: completed in 167.014 ms, heap usage 269.813 MB -> 116.036 MB. [2025-05-17T02:58:03.875Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3947.726 ms) ====== [2025-05-17T02:58:03.875Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-17T02:58:03.875Z] GC before operation: completed in 176.262 ms, heap usage 228.696 MB -> 116.282 MB. [2025-05-17T02:58:09.327Z] ====== log-regression (apache-spark) [default], iteration 3 completed (5011.130 ms) ====== [2025-05-17T02:58:09.327Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-17T02:58:09.327Z] GC before operation: completed in 334.611 ms, heap usage 228.967 MB -> 116.581 MB. [2025-05-17T02:58:13.286Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3946.540 ms) ====== [2025-05-17T02:58:13.286Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-17T02:58:13.286Z] GC before operation: completed in 141.317 ms, heap usage 296.272 MB -> 117.021 MB. [2025-05-17T02:58:18.341Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4671.829 ms) ====== [2025-05-17T02:58:18.341Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-17T02:58:18.341Z] GC before operation: completed in 208.964 ms, heap usage 272.315 MB -> 117.118 MB. [2025-05-17T02:58:22.251Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4011.763 ms) ====== [2025-05-17T02:58:22.251Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-17T02:58:22.251Z] GC before operation: completed in 207.127 ms, heap usage 237.435 MB -> 117.146 MB. [2025-05-17T02:58:26.058Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3343.401 ms) ====== [2025-05-17T02:58:26.058Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-17T02:58:26.058Z] GC before operation: completed in 146.886 ms, heap usage 227.381 MB -> 117.333 MB. [2025-05-17T02:58:29.942Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3811.057 ms) ====== [2025-05-17T02:58:29.942Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-17T02:58:29.942Z] GC before operation: completed in 185.667 ms, heap usage 269.745 MB -> 117.672 MB. [2025-05-17T02:58:33.785Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3711.046 ms) ====== [2025-05-17T02:58:33.785Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-17T02:58:33.785Z] GC before operation: completed in 236.885 ms, heap usage 226.024 MB -> 117.697 MB. [2025-05-17T02:58:37.671Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3699.404 ms) ====== [2025-05-17T02:58:37.671Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-17T02:58:37.671Z] GC before operation: completed in 199.418 ms, heap usage 237.103 MB -> 117.866 MB. [2025-05-17T02:58:42.577Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4203.786 ms) ====== [2025-05-17T02:58:42.577Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-17T02:58:42.577Z] GC before operation: completed in 186.441 ms, heap usage 278.902 MB -> 118.225 MB. [2025-05-17T02:58:45.525Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3496.890 ms) ====== [2025-05-17T02:58:45.525Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-17T02:58:46.193Z] GC before operation: completed in 202.802 ms, heap usage 251.346 MB -> 118.316 MB. [2025-05-17T02:58:49.576Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3604.216 ms) ====== [2025-05-17T02:58:49.576Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-17T02:58:49.576Z] GC before operation: completed in 256.564 ms, heap usage 248.679 MB -> 118.478 MB. [2025-05-17T02:58:53.420Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3699.307 ms) ====== [2025-05-17T02:58:53.420Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-17T02:58:54.078Z] GC before operation: completed in 256.920 ms, heap usage 227.360 MB -> 118.572 MB. [2025-05-17T02:58:57.926Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3676.734 ms) ====== [2025-05-17T02:58:57.926Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-17T02:58:57.926Z] GC before operation: completed in 177.536 ms, heap usage 249.416 MB -> 118.859 MB. [2025-05-17T02:59:00.877Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3243.225 ms) ====== [2025-05-17T02:59:00.877Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-17T02:59:00.877Z] GC before operation: completed in 201.813 ms, heap usage 336.954 MB -> 119.377 MB. [2025-05-17T02:59:04.676Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3348.263 ms) ====== [2025-05-17T02:59:04.676Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-17T02:59:04.676Z] GC before operation: completed in 181.237 ms, heap usage 336.213 MB -> 119.557 MB. [2025-05-17T02:59:08.571Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3918.169 ms) ====== [2025-05-17T02:59:08.571Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-17T02:59:08.571Z] GC before operation: completed in 230.936 ms, heap usage 252.285 MB -> 119.384 MB. [2025-05-17T02:59:13.579Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4149.058 ms) ====== [2025-05-17T02:59:13.579Z] ----------------------------------- [2025-05-17T02:59:13.579Z] renaissance-log-regression_0_PASSED [2025-05-17T02:59:13.579Z] ----------------------------------- [2025-05-17T02:59:13.579Z] [2025-05-17T02:59:13.579Z] TEST TEARDOWN: [2025-05-17T02:59:13.579Z] Nothing to be done for teardown. [2025-05-17T02:59:13.579Z] renaissance-log-regression_0 Finish Time: Sat May 17 02:59:13 2025 Epoch Time (ms): 1747450753182