[2025-10-02T04:23:40.684Z] Running test renaissance-log-regression_0 ... [2025-10-02T04:23:40.684Z] =============================================== [2025-10-02T04:23:40.684Z] renaissance-log-regression_0 Start Time: Thu Oct 2 04:23:40 2025 Epoch Time (ms): 1759379020261 [2025-10-02T04:23:40.684Z] variation: NoOptions [2025-10-02T04:23:40.684Z] JVM_OPTIONS: [2025-10-02T04:23:40.684Z] { \ [2025-10-02T04:23:40.684Z] echo ""; echo "TEST SETUP:"; \ [2025-10-02T04:23:40.684Z] echo "Nothing to be done for setup."; \ [2025-10-02T04:23:40.684Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593759068396/renaissance-log-regression_0"; \ [2025-10-02T04:23:40.684Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593759068396/renaissance-log-regression_0"; \ [2025-10-02T04:23:40.684Z] echo ""; echo "TESTING:"; \ [2025-10-02T04:23:40.684Z] "/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_17593759068396/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-02T04:23:40.684Z] 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_17593759068396/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-02T04:23:40.685Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-02T04:23:40.685Z] echo "Nothing to be done for teardown."; \ [2025-10-02T04:23:40.685Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593759068396/TestTargetResult"; [2025-10-02T04:23:40.685Z] [2025-10-02T04:23:40.685Z] TEST SETUP: [2025-10-02T04:23:40.685Z] Nothing to be done for setup. [2025-10-02T04:23:40.685Z] [2025-10-02T04:23:40.685Z] TESTING: [2025-10-02T04:23:49.428Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-10-02T04:23:50.789Z] WARNING: An illegal reflective access operation has occurred [2025-10-02T04:23:50.789Z] 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_17593759068396/renaissance-log-regression_0/harness-042341-10412618208867663498/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-10-02T04:23:50.789Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-10-02T04:23:50.789Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-10-02T04:23:50.789Z] WARNING: All illegal access operations will be denied in a future release [2025-10-02T04:23:52.094Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-02T04:23:52.094Z] GC before operation: completed in 308.453 ms, heap usage 52.224 MB -> 34.376 MB. [2025-10-02T04:24:16.067Z] ====== log-regression (apache-spark) [default], iteration 0 completed (20662.263 ms) ====== [2025-10-02T04:24:16.067Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-02T04:24:16.067Z] GC before operation: completed in 207.677 ms, heap usage 288.154 MB -> 115.686 MB. [2025-10-02T04:24:19.896Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6380.339 ms) ====== [2025-10-02T04:24:19.896Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-02T04:24:20.523Z] GC before operation: completed in 515.928 ms, heap usage 270.609 MB -> 116.066 MB. [2025-10-02T04:24:25.545Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4982.191 ms) ====== [2025-10-02T04:24:25.545Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-02T04:24:25.545Z] GC before operation: completed in 135.543 ms, heap usage 224.945 MB -> 116.289 MB. [2025-10-02T04:24:29.335Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3871.145 ms) ====== [2025-10-02T04:24:29.335Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-02T04:24:29.335Z] GC before operation: completed in 137.479 ms, heap usage 273.325 MB -> 116.758 MB. [2025-10-02T04:24:34.165Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4819.711 ms) ====== [2025-10-02T04:24:34.165Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-02T04:24:34.165Z] GC before operation: completed in 165.399 ms, heap usage 339.458 MB -> 117.186 MB. [2025-10-02T04:24:38.946Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4457.866 ms) ====== [2025-10-02T04:24:38.946Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-02T04:24:38.946Z] GC before operation: completed in 186.641 ms, heap usage 291.671 MB -> 117.232 MB. [2025-10-02T04:24:45.002Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5376.961 ms) ====== [2025-10-02T04:24:45.002Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-02T04:24:45.002Z] GC before operation: completed in 215.610 ms, heap usage 281.093 MB -> 117.322 MB. [2025-10-02T04:24:49.845Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5527.648 ms) ====== [2025-10-02T04:24:49.845Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-02T04:24:50.467Z] GC before operation: completed in 277.286 ms, heap usage 229.031 MB -> 117.344 MB. [2025-10-02T04:24:54.414Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3833.253 ms) ====== [2025-10-02T04:24:54.414Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-02T04:24:54.414Z] GC before operation: completed in 374.466 ms, heap usage 251.450 MB -> 117.628 MB. [2025-10-02T04:24:59.379Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4701.519 ms) ====== [2025-10-02T04:24:59.379Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-02T04:24:59.380Z] GC before operation: completed in 237.491 ms, heap usage 226.795 MB -> 117.725 MB. [2025-10-02T04:25:03.360Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3742.984 ms) ====== [2025-10-02T04:25:03.360Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-02T04:25:03.360Z] GC before operation: completed in 167.643 ms, heap usage 259.027 MB -> 117.975 MB. [2025-10-02T04:25:08.394Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4561.264 ms) ====== [2025-10-02T04:25:08.394Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-02T04:25:08.394Z] GC before operation: completed in 345.062 ms, heap usage 251.117 MB -> 118.178 MB. [2025-10-02T04:25:11.781Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3522.387 ms) ====== [2025-10-02T04:25:11.781Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-02T04:25:11.781Z] GC before operation: completed in 206.246 ms, heap usage 248.441 MB -> 118.354 MB. [2025-10-02T04:25:16.746Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4492.284 ms) ====== [2025-10-02T04:25:16.746Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-02T04:25:16.746Z] GC before operation: completed in 157.139 ms, heap usage 289.695 MB -> 118.602 MB. [2025-10-02T04:25:21.588Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4243.689 ms) ====== [2025-10-02T04:25:21.588Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-02T04:25:21.588Z] GC before operation: completed in 229.906 ms, heap usage 314.156 MB -> 118.937 MB. [2025-10-02T04:25:26.479Z] ====== log-regression (apache-spark) [default], iteration 15 completed (5089.233 ms) ====== [2025-10-02T04:25:26.479Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-02T04:25:26.479Z] GC before operation: completed in 197.566 ms, heap usage 226.568 MB -> 118.818 MB. [2025-10-02T04:25:30.395Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4205.645 ms) ====== [2025-10-02T04:25:30.395Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-02T04:25:31.023Z] GC before operation: completed in 279.769 ms, heap usage 270.735 MB -> 119.177 MB. [2025-10-02T04:25:34.869Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3812.301 ms) ====== [2025-10-02T04:25:34.869Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-02T04:25:34.869Z] GC before operation: completed in 305.647 ms, heap usage 252.883 MB -> 119.271 MB. [2025-10-02T04:25:39.803Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4296.282 ms) ====== [2025-10-02T04:25:39.803Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-02T04:25:39.803Z] GC before operation: completed in 251.562 ms, heap usage 227.147 MB -> 119.364 MB. [2025-10-02T04:25:44.795Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5235.890 ms) ====== [2025-10-02T04:25:45.457Z] ----------------------------------- [2025-10-02T04:25:45.457Z] renaissance-log-regression_0_PASSED [2025-10-02T04:25:45.457Z] ----------------------------------- [2025-10-02T04:25:45.457Z] [2025-10-02T04:25:45.457Z] TEST TEARDOWN: [2025-10-02T04:25:45.457Z] Nothing to be done for teardown. [2025-10-02T04:25:45.457Z] renaissance-log-regression_0 Finish Time: Thu Oct 2 04:25:45 2025 Epoch Time (ms): 1759379145239