[2025-08-28T13:14:32.065Z] Running test renaissance-log-regression_0 ... [2025-08-28T13:14:32.065Z] =============================================== [2025-08-28T13:14:32.065Z] renaissance-log-regression_0 Start Time: Thu Aug 28 13:14:31 2025 Epoch Time (ms): 1756386871464 [2025-08-28T13:14:32.065Z] variation: NoOptions [2025-08-28T13:14:32.065Z] JVM_OPTIONS: [2025-08-28T13:14:32.065Z] { \ [2025-08-28T13:14:32.065Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T13:14:32.065Z] echo "Nothing to be done for setup."; \ [2025-08-28T13:14:32.065Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-log-regression_0"; \ [2025-08-28T13:14:32.065Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-log-regression_0"; \ [2025-08-28T13:14:32.065Z] echo ""; echo "TESTING:"; \ [2025-08-28T13:14:32.065Z] "/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_17563834421410/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-28T13:14:32.065Z] 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_17563834421410/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T13:14:32.065Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T13:14:32.065Z] echo "Nothing to be done for teardown."; \ [2025-08-28T13:14:32.065Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/TestTargetResult"; [2025-08-28T13:14:32.065Z] [2025-08-28T13:14:32.065Z] TEST SETUP: [2025-08-28T13:14:32.065Z] Nothing to be done for setup. [2025-08-28T13:14:32.065Z] [2025-08-28T13:14:32.065Z] TESTING: [2025-08-28T13:14:40.713Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-28T13:14:42.013Z] WARNING: An illegal reflective access operation has occurred [2025-08-28T13:14:42.013Z] 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_17563834421410/renaissance-log-regression_0/harness-131432-2043198287743901671/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-28T13:14:42.013Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-28T13:14:42.013Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-28T13:14:42.013Z] WARNING: All illegal access operations will be denied in a future release [2025-08-28T13:14:43.380Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-28T13:14:43.380Z] GC before operation: completed in 133.843 ms, heap usage 76.928 MB -> 34.392 MB. [2025-08-28T13:15:03.635Z] ====== log-regression (apache-spark) [default], iteration 0 completed (20070.757 ms) ====== [2025-08-28T13:15:03.636Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-28T13:15:03.636Z] GC before operation: completed in 194.417 ms, heap usage 260.393 MB -> 115.583 MB. [2025-08-28T13:15:10.232Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6579.379 ms) ====== [2025-08-28T13:15:10.232Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-28T13:15:10.232Z] GC before operation: completed in 208.365 ms, heap usage 229.931 MB -> 115.905 MB. [2025-08-28T13:15:16.229Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5574.093 ms) ====== [2025-08-28T13:15:16.229Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-28T13:15:16.229Z] GC before operation: completed in 202.769 ms, heap usage 229.218 MB -> 116.290 MB. [2025-08-28T13:15:20.975Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4587.921 ms) ====== [2025-08-28T13:15:20.975Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-28T13:15:20.975Z] GC before operation: completed in 185.771 ms, heap usage 292.969 MB -> 116.838 MB. [2025-08-28T13:15:25.837Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4674.097 ms) ====== [2025-08-28T13:15:25.837Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-28T13:15:25.837Z] GC before operation: completed in 193.162 ms, heap usage 229.671 MB -> 117.026 MB. [2025-08-28T13:15:30.611Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4251.822 ms) ====== [2025-08-28T13:15:30.611Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-28T13:15:30.611Z] GC before operation: completed in 168.384 ms, heap usage 269.103 MB -> 117.063 MB. [2025-08-28T13:15:35.392Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4485.013 ms) ====== [2025-08-28T13:15:35.392Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-28T13:15:35.392Z] GC before operation: completed in 265.682 ms, heap usage 237.582 MB -> 117.168 MB. [2025-08-28T13:15:39.177Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4346.512 ms) ====== [2025-08-28T13:15:39.177Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-28T13:15:39.781Z] GC before operation: completed in 229.446 ms, heap usage 290.313 MB -> 117.508 MB. [2025-08-28T13:15:43.572Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4205.453 ms) ====== [2025-08-28T13:15:43.573Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-28T13:15:44.215Z] GC before operation: completed in 229.722 ms, heap usage 278.922 MB -> 117.710 MB. [2025-08-28T13:15:49.406Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4713.072 ms) ====== [2025-08-28T13:15:49.406Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-28T13:15:49.406Z] GC before operation: completed in 269.012 ms, heap usage 269.518 MB -> 117.785 MB. [2025-08-28T13:15:53.178Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4352.381 ms) ====== [2025-08-28T13:15:53.178Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-28T13:15:53.793Z] GC before operation: completed in 223.354 ms, heap usage 249.901 MB -> 117.979 MB. [2025-08-28T13:15:58.670Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4458.041 ms) ====== [2025-08-28T13:15:58.670Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-28T13:15:58.670Z] GC before operation: completed in 202.327 ms, heap usage 248.930 MB -> 118.152 MB. [2025-08-28T13:16:02.848Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4215.112 ms) ====== [2025-08-28T13:16:02.848Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-28T13:16:02.848Z] GC before operation: completed in 275.935 ms, heap usage 294.299 MB -> 118.497 MB. [2025-08-28T13:16:07.765Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4733.380 ms) ====== [2025-08-28T13:16:07.765Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-28T13:16:07.765Z] GC before operation: completed in 269.020 ms, heap usage 259.757 MB -> 118.490 MB. [2025-08-28T13:16:11.660Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4160.295 ms) ====== [2025-08-28T13:16:11.660Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-28T13:16:12.283Z] GC before operation: completed in 279.214 ms, heap usage 289.532 MB -> 118.752 MB. [2025-08-28T13:16:17.151Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4707.526 ms) ====== [2025-08-28T13:16:17.151Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-28T13:16:17.151Z] GC before operation: completed in 249.453 ms, heap usage 226.650 MB -> 118.806 MB. [2025-08-28T13:16:20.909Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3579.959 ms) ====== [2025-08-28T13:16:20.909Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-28T13:16:20.909Z] GC before operation: completed in 244.279 ms, heap usage 268.378 MB -> 119.093 MB. [2025-08-28T13:16:24.673Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3766.310 ms) ====== [2025-08-28T13:16:24.673Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-28T13:16:25.297Z] GC before operation: completed in 272.361 ms, heap usage 302.444 MB -> 119.420 MB. [2025-08-28T13:16:29.088Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3999.914 ms) ====== [2025-08-28T13:16:29.088Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-28T13:16:29.088Z] GC before operation: completed in 320.435 ms, heap usage 252.462 MB -> 119.417 MB. [2025-08-28T13:16:32.963Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3888.169 ms) ====== [2025-08-28T13:16:33.581Z] ----------------------------------- [2025-08-28T13:16:33.581Z] renaissance-log-regression_0_PASSED [2025-08-28T13:16:33.581Z] ----------------------------------- [2025-08-28T13:16:33.581Z] [2025-08-28T13:16:33.581Z] TEST TEARDOWN: [2025-08-28T13:16:33.581Z] Nothing to be done for teardown. [2025-08-28T13:16:33.581Z] renaissance-log-regression_0 Finish Time: Thu Aug 28 13:16:33 2025 Epoch Time (ms): 1756386993386