[2025-11-19T22:40:31.565Z] Running test renaissance-log-regression_0 ... [2025-11-19T22:40:31.565Z] =============================================== [2025-11-19T22:40:31.565Z] renaissance-log-regression_0 Start Time: Wed Nov 19 22:40:30 2025 Epoch Time (ms): 1763592030781 [2025-11-19T22:40:31.565Z] variation: NoOptions [2025-11-19T22:40:31.565Z] JVM_OPTIONS: [2025-11-19T22:40:31.565Z] { \ [2025-11-19T22:40:31.565Z] echo ""; echo "TEST SETUP:"; \ [2025-11-19T22:40:31.565Z] echo "Nothing to be done for setup."; \ [2025-11-19T22:40:31.565Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17635905594377/renaissance-log-regression_0"; \ [2025-11-19T22:40:31.565Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17635905594377/renaissance-log-regression_0"; \ [2025-11-19T22:40:31.565Z] echo ""; echo "TESTING:"; \ [2025-11-19T22:40:31.565Z] "/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_17635905594377/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-19T22:40:31.565Z] 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_17635905594377/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-19T22:40:31.565Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-19T22:40:31.565Z] echo "Nothing to be done for teardown."; \ [2025-11-19T22:40:31.565Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17635905594377/TestTargetResult"; [2025-11-19T22:40:31.565Z] [2025-11-19T22:40:31.565Z] TEST SETUP: [2025-11-19T22:40:31.565Z] Nothing to be done for setup. [2025-11-19T22:40:31.565Z] [2025-11-19T22:40:31.565Z] TESTING: [2025-11-19T22:40:39.874Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-11-19T22:40:42.421Z] WARNING: An illegal reflective access operation has occurred [2025-11-19T22:40:42.421Z] 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_17635905594377/renaissance-log-regression_0/harness-224031-8653300577569064792/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-19T22:40:42.421Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-19T22:40:42.421Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-19T22:40:42.421Z] WARNING: All illegal access operations will be denied in a future release [2025-11-19T22:40:44.014Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-19T22:40:44.014Z] GC before operation: completed in 87.112 ms, heap usage 86.362 MB -> 35.282 MB. [2025-11-19T22:41:00.157Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14883.994 ms) ====== [2025-11-19T22:41:00.158Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-19T22:41:00.158Z] GC before operation: completed in 137.014 ms, heap usage 777.167 MB -> 132.957 MB. [2025-11-19T22:41:02.912Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2989.145 ms) ====== [2025-11-19T22:41:02.912Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-19T22:41:02.912Z] GC before operation: completed in 118.672 ms, heap usage 601.086 MB -> 133.255 MB. [2025-11-19T22:41:05.396Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2698.612 ms) ====== [2025-11-19T22:41:05.396Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-19T22:41:05.396Z] GC before operation: completed in 187.087 ms, heap usage 632.317 MB -> 133.579 MB. [2025-11-19T22:41:07.875Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2664.124 ms) ====== [2025-11-19T22:41:07.875Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-19T22:41:07.875Z] GC before operation: completed in 134.008 ms, heap usage 670.236 MB -> 134.149 MB. [2025-11-19T22:41:10.345Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2524.608 ms) ====== [2025-11-19T22:41:10.345Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-19T22:41:11.116Z] GC before operation: completed in 133.876 ms, heap usage 453.543 MB -> 134.024 MB. [2025-11-19T22:41:13.588Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2522.260 ms) ====== [2025-11-19T22:41:13.588Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-19T22:41:13.588Z] GC before operation: completed in 134.669 ms, heap usage 445.835 MB -> 134.379 MB. [2025-11-19T22:41:16.067Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2407.613 ms) ====== [2025-11-19T22:41:16.067Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-19T22:41:16.067Z] GC before operation: completed in 147.861 ms, heap usage 646.144 MB -> 135.283 MB. [2025-11-19T22:41:20.256Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2422.831 ms) ====== [2025-11-19T22:41:20.256Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-19T22:41:20.256Z] GC before operation: completed in 165.622 ms, heap usage 511.534 MB -> 135.320 MB. [2025-11-19T22:41:21.037Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2405.347 ms) ====== [2025-11-19T22:41:21.037Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-19T22:41:21.037Z] GC before operation: completed in 182.092 ms, heap usage 312.607 MB -> 135.189 MB. [2025-11-19T22:41:23.519Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2323.641 ms) ====== [2025-11-19T22:41:23.519Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-19T22:41:23.519Z] GC before operation: completed in 146.704 ms, heap usage 454.549 MB -> 135.880 MB. [2025-11-19T22:41:26.216Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2180.922 ms) ====== [2025-11-19T22:41:26.216Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-19T22:41:26.216Z] GC before operation: completed in 185.361 ms, heap usage 776.353 MB -> 137.135 MB. [2025-11-19T22:41:28.696Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2355.197 ms) ====== [2025-11-19T22:41:28.696Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-19T22:41:28.696Z] GC before operation: completed in 184.859 ms, heap usage 690.477 MB -> 137.193 MB. [2025-11-19T22:41:31.200Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2366.292 ms) ====== [2025-11-19T22:41:31.200Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-19T22:41:31.200Z] GC before operation: completed in 200.011 ms, heap usage 468.750 MB -> 137.052 MB. [2025-11-19T22:41:33.744Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2260.085 ms) ====== [2025-11-19T22:41:33.744Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-19T22:41:33.744Z] GC before operation: completed in 190.942 ms, heap usage 468.150 MB -> 137.390 MB. [2025-11-19T22:41:36.221Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2204.110 ms) ====== [2025-11-19T22:41:36.221Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-19T22:41:36.221Z] GC before operation: completed in 147.095 ms, heap usage 278.579 MB -> 137.252 MB. [2025-11-19T22:41:37.831Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2131.772 ms) ====== [2025-11-19T22:41:37.831Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-19T22:41:39.578Z] GC before operation: completed in 202.066 ms, heap usage 795.383 MB -> 139.071 MB. [2025-11-19T22:41:39.578Z] 22:41:39.414 WARN [block-manager-storage-async-thread-pool-56] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_1165_piece0, which does not exist [2025-11-19T22:41:40.349Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2243.737 ms) ====== [2025-11-19T22:41:40.349Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-19T22:41:40.349Z] GC before operation: completed in 148.745 ms, heap usage 316.413 MB -> 138.275 MB. [2025-11-19T22:41:42.810Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2211.500 ms) ====== [2025-11-19T22:41:42.810Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-19T22:41:42.810Z] GC before operation: completed in 174.082 ms, heap usage 330.284 MB -> 138.598 MB. [2025-11-19T22:41:45.284Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2156.828 ms) ====== [2025-11-19T22:41:45.284Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-19T22:41:45.284Z] GC before operation: completed in 199.833 ms, heap usage 965.793 MB -> 140.735 MB. [2025-11-19T22:41:47.777Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2165.573 ms) ====== [2025-11-19T22:41:47.777Z] ----------------------------------- [2025-11-19T22:41:47.777Z] renaissance-log-regression_0_PASSED [2025-11-19T22:41:47.777Z] ----------------------------------- [2025-11-19T22:41:47.777Z] [2025-11-19T22:41:47.777Z] TEST TEARDOWN: [2025-11-19T22:41:47.777Z] Nothing to be done for teardown. [2025-11-19T22:41:47.777Z] renaissance-log-regression_0 Finish Time: Wed Nov 19 22:41:47 2025 Epoch Time (ms): 1763592107677