[2025-05-29T02:40:59.112Z] Running test renaissance-log-regression_0 ... [2025-05-29T02:40:59.112Z] =============================================== [2025-05-29T02:40:59.112Z] renaissance-log-regression_0 Start Time: Thu May 29 02:40:58 2025 Epoch Time (ms): 1748486458762 [2025-05-29T02:40:59.112Z] variation: NoOptions [2025-05-29T02:40:59.112Z] JVM_OPTIONS: [2025-05-29T02:40:59.112Z] { \ [2025-05-29T02:40:59.112Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T02:40:59.112Z] echo "Nothing to be done for setup."; \ [2025-05-29T02:40:59.112Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-log-regression_0"; \ [2025-05-29T02:40:59.112Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-log-regression_0"; \ [2025-05-29T02:40:59.112Z] echo ""; echo "TESTING:"; \ [2025-05-29T02:40:59.112Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-29T02:40:59.112Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T02:40:59.112Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T02:40:59.112Z] echo "Nothing to be done for teardown."; \ [2025-05-29T02:40:59.112Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484850835836/TestTargetResult"; [2025-05-29T02:40:59.112Z] [2025-05-29T02:40:59.112Z] TEST SETUP: [2025-05-29T02:40:59.112Z] Nothing to be done for setup. [2025-05-29T02:40:59.112Z] [2025-05-29T02:40:59.112Z] TESTING: [2025-05-29T02:41:07.344Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-05-29T02:41:09.796Z] WARNING: An illegal reflective access operation has occurred [2025-05-29T02:41:09.796Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17484850835836/renaissance-log-regression_0/harness-024059-7117146984967573776/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-29T02:41:09.796Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-29T02:41:09.796Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-29T02:41:09.796Z] WARNING: All illegal access operations will be denied in a future release [2025-05-29T02:41:11.374Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-29T02:41:12.141Z] GC before operation: completed in 87.412 ms, heap usage 92.450 MB -> 35.282 MB. [2025-05-29T02:41:25.817Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14228.566 ms) ====== [2025-05-29T02:41:25.817Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-29T02:41:25.817Z] GC before operation: completed in 140.357 ms, heap usage 356.458 MB -> 131.832 MB. [2025-05-29T02:41:29.220Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2985.388 ms) ====== [2025-05-29T02:41:29.220Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-29T02:41:29.220Z] GC before operation: completed in 130.830 ms, heap usage 318.534 MB -> 132.370 MB. [2025-05-29T02:41:31.671Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2570.174 ms) ====== [2025-05-29T02:41:31.671Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-29T02:41:31.671Z] GC before operation: completed in 131.260 ms, heap usage 952.016 MB -> 134.350 MB. [2025-05-29T02:41:34.119Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2503.588 ms) ====== [2025-05-29T02:41:34.119Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-29T02:41:34.891Z] GC before operation: completed in 122.238 ms, heap usage 283.764 MB -> 133.000 MB. [2025-05-29T02:41:37.352Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2370.361 ms) ====== [2025-05-29T02:41:37.352Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-29T02:41:37.352Z] GC before operation: completed in 153.264 ms, heap usage 958.032 MB -> 135.240 MB. [2025-05-29T02:41:39.801Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2444.366 ms) ====== [2025-05-29T02:41:39.801Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-29T02:41:39.801Z] GC before operation: completed in 142.913 ms, heap usage 363.609 MB -> 134.038 MB. [2025-05-29T02:41:42.257Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2187.489 ms) ====== [2025-05-29T02:41:42.257Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-29T02:41:42.257Z] GC before operation: completed in 159.801 ms, heap usage 884.355 MB -> 135.821 MB. [2025-05-29T02:41:44.708Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2295.654 ms) ====== [2025-05-29T02:41:44.708Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-29T02:41:44.708Z] GC before operation: completed in 148.116 ms, heap usage 787.493 MB -> 135.953 MB. [2025-05-29T02:41:46.298Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2149.556 ms) ====== [2025-05-29T02:41:46.298Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-29T02:41:47.061Z] GC before operation: completed in 159.125 ms, heap usage 1.022 GB -> 137.052 MB. [2025-05-29T02:41:49.528Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2265.488 ms) ====== [2025-05-29T02:41:49.528Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-29T02:41:49.528Z] GC before operation: completed in 136.864 ms, heap usage 331.172 MB -> 135.500 MB. [2025-05-29T02:41:51.103Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2171.366 ms) ====== [2025-05-29T02:41:51.103Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-29T02:41:51.865Z] GC before operation: completed in 170.491 ms, heap usage 1.200 GB -> 138.285 MB. [2025-05-29T02:41:53.443Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2238.283 ms) ====== [2025-05-29T02:41:53.443Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-29T02:41:54.205Z] GC before operation: completed in 164.445 ms, heap usage 612.976 MB -> 136.934 MB. [2025-05-29T02:41:55.798Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2187.255 ms) ====== [2025-05-29T02:41:55.798Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-29T02:41:56.569Z] GC before operation: completed in 141.515 ms, heap usage 577.271 MB -> 137.320 MB. [2025-05-29T02:41:58.146Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2109.276 ms) ====== [2025-05-29T02:41:58.146Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-29T02:41:58.921Z] GC before operation: completed in 157.802 ms, heap usage 432.298 MB -> 137.272 MB. [2025-05-29T02:42:00.506Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2226.108 ms) ====== [2025-05-29T02:42:00.506Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-29T02:42:01.268Z] GC before operation: completed in 171.768 ms, heap usage 332.354 MB -> 137.335 MB. [2025-05-29T02:42:02.849Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2122.440 ms) ====== [2025-05-29T02:42:02.849Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-29T02:42:03.618Z] GC before operation: completed in 212.224 ms, heap usage 922.258 MB -> 139.331 MB. [2025-05-29T02:42:05.196Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2166.209 ms) ====== [2025-05-29T02:42:05.197Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-29T02:42:05.971Z] GC before operation: completed in 157.252 ms, heap usage 765.775 MB -> 139.378 MB. [2025-05-29T02:42:07.552Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2097.126 ms) ====== [2025-05-29T02:42:07.552Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-29T02:42:07.552Z] GC before operation: completed in 156.269 ms, heap usage 623.768 MB -> 139.235 MB. [2025-05-29T02:42:10.005Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2081.448 ms) ====== [2025-05-29T02:42:10.005Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-29T02:42:10.005Z] GC before operation: completed in 210.085 ms, heap usage 411.398 MB -> 139.031 MB. [2025-05-29T02:42:12.461Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2169.458 ms) ====== [2025-05-29T02:42:12.461Z] ----------------------------------- [2025-05-29T02:42:12.461Z] renaissance-log-regression_0_PASSED [2025-05-29T02:42:12.461Z] ----------------------------------- [2025-05-29T02:42:12.461Z] [2025-05-29T02:42:12.461Z] TEST TEARDOWN: [2025-05-29T02:42:12.461Z] Nothing to be done for teardown. [2025-05-29T02:42:12.461Z] renaissance-log-regression_0 Finish Time: Thu May 29 02:42:12 2025 Epoch Time (ms): 1748486532302