[2025-06-12T05:37:20.266Z] Running test renaissance-log-regression_0 ... [2025-06-12T05:37:20.266Z] =============================================== [2025-06-12T05:37:20.266Z] renaissance-log-regression_0 Start Time: Thu Jun 12 01:37:19 2025 Epoch Time (ms): 1749706639640 [2025-06-12T05:37:20.266Z] variation: NoOptions [2025-06-12T05:37:20.266Z] JVM_OPTIONS: [2025-06-12T05:37:20.266Z] { \ [2025-06-12T05:37:20.266Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T05:37:20.266Z] echo "Nothing to be done for setup."; \ [2025-06-12T05:37:20.266Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17497035125400/renaissance-log-regression_0"; \ [2025-06-12T05:37:20.266Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17497035125400/renaissance-log-regression_0"; \ [2025-06-12T05:37:20.266Z] echo ""; echo "TESTING:"; \ [2025-06-12T05:37:20.266Z] "/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_17497035125400/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-12T05:37:20.266Z] 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_17497035125400/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T05:37:20.266Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T05:37:20.266Z] echo "Nothing to be done for teardown."; \ [2025-06-12T05:37:20.266Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17497035125400/TestTargetResult"; [2025-06-12T05:37:20.266Z] [2025-06-12T05:37:20.266Z] TEST SETUP: [2025-06-12T05:37:20.266Z] Nothing to be done for setup. [2025-06-12T05:37:20.266Z] [2025-06-12T05:37:20.266Z] TESTING: [2025-06-12T05:37:28.130Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-12T05:37:29.575Z] WARNING: An illegal reflective access operation has occurred [2025-06-12T05:37:29.575Z] 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_17497035125400/renaissance-log-regression_0/harness-013720-12231443741787973762/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-06-12T05:37:29.575Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-06-12T05:37:29.575Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-06-12T05:37:29.575Z] WARNING: All illegal access operations will be denied in a future release [2025-06-12T05:37:30.970Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-12T05:37:31.675Z] GC before operation: completed in 133.587 ms, heap usage 78.095 MB -> 34.403 MB. [2025-06-12T05:37:52.801Z] ====== log-regression (apache-spark) [default], iteration 0 completed (21651.824 ms) ====== [2025-06-12T05:37:52.801Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-12T05:37:53.554Z] GC before operation: completed in 170.725 ms, heap usage 240.460 MB -> 115.553 MB. [2025-06-12T05:37:59.910Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6905.116 ms) ====== [2025-06-12T05:37:59.910Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-12T05:38:00.608Z] GC before operation: completed in 208.623 ms, heap usage 237.296 MB -> 115.929 MB. [2025-06-12T05:38:05.744Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5008.457 ms) ====== [2025-06-12T05:38:05.744Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-12T05:38:05.744Z] GC before operation: completed in 205.729 ms, heap usage 250.207 MB -> 116.406 MB. [2025-06-12T05:38:10.800Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4590.114 ms) ====== [2025-06-12T05:38:10.800Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-12T05:38:10.800Z] GC before operation: completed in 193.439 ms, heap usage 282.193 MB -> 116.782 MB. [2025-06-12T05:38:15.821Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5084.683 ms) ====== [2025-06-12T05:38:15.821Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-12T05:38:15.821Z] GC before operation: completed in 187.689 ms, heap usage 284.021 MB -> 116.974 MB. [2025-06-12T05:38:21.364Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5438.825 ms) ====== [2025-06-12T05:38:21.364Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-12T05:38:21.364Z] GC before operation: completed in 249.031 ms, heap usage 247.590 MB -> 117.072 MB. [2025-06-12T05:38:25.290Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4190.157 ms) ====== [2025-06-12T05:38:25.290Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-12T05:38:25.948Z] GC before operation: completed in 207.773 ms, heap usage 269.672 MB -> 117.262 MB. [2025-06-12T05:38:29.960Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3976.881 ms) ====== [2025-06-12T05:38:29.960Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-12T05:38:29.960Z] GC before operation: completed in 329.980 ms, heap usage 250.176 MB -> 117.447 MB. [2025-06-12T05:38:35.085Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4566.279 ms) ====== [2025-06-12T05:38:35.085Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-12T05:38:35.085Z] GC before operation: completed in 223.986 ms, heap usage 237.648 MB -> 117.559 MB. [2025-06-12T05:38:39.263Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4323.452 ms) ====== [2025-06-12T05:38:39.264Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-12T05:38:39.264Z] GC before operation: completed in 282.406 ms, heap usage 235.128 MB -> 117.727 MB. [2025-06-12T05:38:44.340Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4355.188 ms) ====== [2025-06-12T05:38:44.340Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-12T05:38:44.340Z] GC before operation: completed in 312.226 ms, heap usage 236.269 MB -> 117.995 MB. [2025-06-12T05:38:48.495Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4181.263 ms) ====== [2025-06-12T05:38:48.495Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-12T05:38:48.495Z] GC before operation: completed in 228.773 ms, heap usage 251.144 MB -> 118.184 MB. [2025-06-12T05:38:52.609Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4215.759 ms) ====== [2025-06-12T05:38:52.609Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-12T05:38:52.609Z] GC before operation: completed in 180.906 ms, heap usage 289.627 MB -> 118.432 MB. [2025-06-12T05:38:56.583Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4025.235 ms) ====== [2025-06-12T05:38:56.583Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-12T05:38:57.280Z] GC before operation: completed in 250.528 ms, heap usage 274.420 MB -> 118.619 MB. [2025-06-12T05:39:01.298Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4074.963 ms) ====== [2025-06-12T05:39:01.298Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-12T05:39:01.298Z] GC before operation: completed in 264.580 ms, heap usage 251.167 MB -> 118.697 MB. [2025-06-12T05:39:05.687Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3896.344 ms) ====== [2025-06-12T05:39:05.687Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-12T05:39:05.687Z] GC before operation: completed in 151.762 ms, heap usage 290.836 MB -> 118.995 MB. [2025-06-12T05:39:09.064Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3191.373 ms) ====== [2025-06-12T05:39:09.064Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-12T05:39:09.064Z] GC before operation: completed in 320.954 ms, heap usage 280.162 MB -> 119.184 MB. [2025-06-12T05:39:13.121Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3957.348 ms) ====== [2025-06-12T05:39:13.121Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-12T05:39:13.121Z] GC before operation: completed in 242.229 ms, heap usage 282.943 MB -> 119.341 MB. [2025-06-12T05:39:18.240Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4790.992 ms) ====== [2025-06-12T05:39:18.241Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-12T05:39:18.241Z] GC before operation: completed in 316.837 ms, heap usage 249.121 MB -> 119.436 MB. [2025-06-12T05:39:23.379Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4505.977 ms) ====== [2025-06-12T05:39:23.379Z] ----------------------------------- [2025-06-12T05:39:23.379Z] renaissance-log-regression_0_PASSED [2025-06-12T05:39:23.379Z] ----------------------------------- [2025-06-12T05:39:23.379Z] [2025-06-12T05:39:23.379Z] TEST TEARDOWN: [2025-06-12T05:39:23.379Z] Nothing to be done for teardown. [2025-06-12T05:39:23.379Z] renaissance-log-regression_0 Finish Time: Thu Jun 12 01:39:23 2025 Epoch Time (ms): 1749706763113