[2025-08-14T02:48:21.019Z] Running test renaissance-log-regression_0 ... [2025-08-14T02:48:21.019Z] =============================================== [2025-08-14T02:48:21.019Z] renaissance-log-regression_0 Start Time: Thu Aug 14 02:48:20 2025 Epoch Time (ms): 1755139700714 [2025-08-14T02:48:21.019Z] variation: NoOptions [2025-08-14T02:48:21.019Z] JVM_OPTIONS: [2025-08-14T02:48:21.019Z] { \ [2025-08-14T02:48:21.019Z] echo ""; echo "TEST SETUP:"; \ [2025-08-14T02:48:21.019Z] echo "Nothing to be done for setup."; \ [2025-08-14T02:48:21.019Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/renaissance-log-regression_0"; \ [2025-08-14T02:48:21.019Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/renaissance-log-regression_0"; \ [2025-08-14T02:48:21.019Z] echo ""; echo "TESTING:"; \ [2025-08-14T02:48:21.019Z] "/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_17551344801251/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-14T02:48:21.019Z] 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_17551344801251/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-14T02:48:21.019Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-14T02:48:21.019Z] echo "Nothing to be done for teardown."; \ [2025-08-14T02:48:21.019Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/TestTargetResult"; [2025-08-14T02:48:21.019Z] [2025-08-14T02:48:21.019Z] TEST SETUP: [2025-08-14T02:48:21.019Z] Nothing to be done for setup. [2025-08-14T02:48:21.019Z] [2025-08-14T02:48:21.019Z] TESTING: [2025-08-14T02:48:31.930Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-14T02:48:34.117Z] WARNING: An illegal reflective access operation has occurred [2025-08-14T02:48:34.117Z] 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_17551344801251/renaissance-log-regression_0/harness-024822-7928617695131417556/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-14T02:48:34.117Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-14T02:48:34.117Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-14T02:48:34.117Z] WARNING: All illegal access operations will be denied in a future release [2025-08-14T02:48:36.366Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-14T02:48:36.366Z] GC before operation: completed in 183.835 ms, heap usage 78.767 MB -> 34.392 MB. [2025-08-14T02:49:05.374Z] ====== log-regression (apache-spark) [default], iteration 0 completed (26220.829 ms) ====== [2025-08-14T02:49:05.374Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-14T02:49:05.374Z] GC before operation: completed in 318.801 ms, heap usage 282.494 MB -> 115.604 MB. [2025-08-14T02:49:11.592Z] ====== log-regression (apache-spark) [default], iteration 1 completed (8324.153 ms) ====== [2025-08-14T02:49:11.592Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-14T02:49:11.592Z] GC before operation: completed in 307.446 ms, heap usage 247.742 MB -> 115.979 MB. [2025-08-14T02:49:17.949Z] ====== log-regression (apache-spark) [default], iteration 2 completed (6132.332 ms) ====== [2025-08-14T02:49:17.949Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-14T02:49:17.949Z] GC before operation: completed in 266.564 ms, heap usage 230.852 MB -> 116.289 MB. [2025-08-14T02:49:24.276Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6097.401 ms) ====== [2025-08-14T02:49:24.276Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-14T02:49:24.276Z] GC before operation: completed in 163.141 ms, heap usage 271.252 MB -> 116.757 MB. [2025-08-14T02:49:33.547Z] ====== log-regression (apache-spark) [default], iteration 4 completed (8933.020 ms) ====== [2025-08-14T02:49:33.547Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-14T02:49:33.547Z] GC before operation: completed in 160.269 ms, heap usage 232.633 MB -> 116.773 MB. [2025-08-14T02:49:39.824Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6589.156 ms) ====== [2025-08-14T02:49:39.824Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-14T02:49:39.824Z] GC before operation: completed in 261.728 ms, heap usage 246.809 MB -> 116.976 MB. [2025-08-14T02:49:46.324Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6154.523 ms) ====== [2025-08-14T02:49:46.324Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-14T02:49:46.324Z] GC before operation: completed in 313.324 ms, heap usage 232.469 MB -> 117.155 MB. [2025-08-14T02:49:52.629Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6079.089 ms) ====== [2025-08-14T02:49:52.629Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-14T02:49:52.629Z] GC before operation: completed in 281.983 ms, heap usage 313.335 MB -> 117.673 MB. [2025-08-14T02:49:58.772Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5889.865 ms) ====== [2025-08-14T02:49:58.772Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-14T02:49:58.772Z] GC before operation: completed in 233.497 ms, heap usage 260.189 MB -> 117.624 MB. [2025-08-14T02:50:05.191Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5688.782 ms) ====== [2025-08-14T02:50:05.191Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-14T02:50:05.191Z] GC before operation: completed in 379.594 ms, heap usage 247.586 MB -> 117.790 MB. [2025-08-14T02:50:09.649Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4760.673 ms) ====== [2025-08-14T02:50:09.649Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-14T02:50:10.297Z] GC before operation: completed in 285.948 ms, heap usage 266.268 MB -> 117.968 MB. [2025-08-14T02:50:15.322Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4564.957 ms) ====== [2025-08-14T02:50:15.322Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-14T02:50:15.322Z] GC before operation: completed in 315.078 ms, heap usage 250.505 MB -> 118.131 MB. [2025-08-14T02:50:20.678Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5534.851 ms) ====== [2025-08-14T02:50:20.678Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-14T02:50:20.678Z] GC before operation: completed in 276.679 ms, heap usage 269.934 MB -> 118.391 MB. [2025-08-14T02:50:25.762Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5140.952 ms) ====== [2025-08-14T02:50:25.762Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-14T02:50:26.439Z] GC before operation: completed in 273.839 ms, heap usage 227.138 MB -> 118.394 MB. [2025-08-14T02:50:31.471Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4963.550 ms) ====== [2025-08-14T02:50:31.471Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-14T02:50:31.471Z] GC before operation: completed in 363.364 ms, heap usage 360.198 MB -> 119.063 MB. [2025-08-14T02:50:37.772Z] ====== log-regression (apache-spark) [default], iteration 15 completed (5469.413 ms) ====== [2025-08-14T02:50:37.772Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-14T02:50:37.772Z] GC before operation: completed in 327.226 ms, heap usage 333.590 MB -> 119.120 MB. [2025-08-14T02:50:44.077Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6048.021 ms) ====== [2025-08-14T02:50:44.077Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-14T02:50:44.077Z] GC before operation: completed in 202.659 ms, heap usage 229.386 MB -> 118.986 MB. [2025-08-14T02:50:49.504Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6207.352 ms) ====== [2025-08-14T02:50:49.504Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-14T02:50:50.241Z] GC before operation: completed in 411.188 ms, heap usage 249.969 MB -> 119.236 MB. [2025-08-14T02:50:55.452Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5401.001 ms) ====== [2025-08-14T02:50:55.452Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-14T02:50:56.191Z] GC before operation: completed in 268.149 ms, heap usage 273.571 MB -> 119.400 MB. [2025-08-14T02:51:03.988Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6126.910 ms) ====== [2025-08-14T02:51:03.988Z] ----------------------------------- [2025-08-14T02:51:03.988Z] renaissance-log-regression_0_PASSED [2025-08-14T02:51:03.988Z] ----------------------------------- [2025-08-14T02:51:03.988Z] [2025-08-14T02:51:03.988Z] TEST TEARDOWN: [2025-08-14T02:51:03.988Z] Nothing to be done for teardown. [2025-08-14T02:51:03.988Z] renaissance-log-regression_0 Finish Time: Thu Aug 14 02:51:02 2025 Epoch Time (ms): 1755139862038