[2025-07-31T00:57:16.906Z] Running test renaissance-log-regression_0 ... [2025-07-31T00:57:16.906Z] =============================================== [2025-07-31T00:57:16.906Z] renaissance-log-regression_0 Start Time: Thu Jul 31 00:57:14 2025 Epoch Time (ms): 1753923434254 [2025-07-31T00:57:16.906Z] variation: NoOptions [2025-07-31T00:57:16.906Z] JVM_OPTIONS: [2025-07-31T00:57:16.906Z] { \ [2025-07-31T00:57:16.906Z] echo ""; echo "TEST SETUP:"; \ [2025-07-31T00:57:16.906Z] echo "Nothing to be done for setup."; \ [2025-07-31T00:57:16.906Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-log-regression_0"; \ [2025-07-31T00:57:16.906Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-log-regression_0"; \ [2025-07-31T00:57:16.906Z] echo ""; echo "TESTING:"; \ [2025-07-31T00:57:16.906Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/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_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-31T00:57:16.906Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-31T00:57:16.906Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-31T00:57:16.906Z] echo "Nothing to be done for teardown."; \ [2025-07-31T00:57:16.906Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/TestTargetResult"; [2025-07-31T00:57:16.906Z] [2025-07-31T00:57:16.906Z] TEST SETUP: [2025-07-31T00:57:16.906Z] Nothing to be done for setup. [2025-07-31T00:57:16.906Z] [2025-07-31T00:57:16.906Z] TESTING: [2025-07-31T00:57:42.760Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-31T00:57:56.717Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-31T00:57:57.505Z] GC before operation: completed in 344.920 ms, heap usage 73.259 MB -> 35.211 MB. [2025-07-31T00:59:17.322Z] ====== log-regression (apache-spark) [default], iteration 0 completed (69244.315 ms) ====== [2025-07-31T00:59:17.322Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-31T00:59:17.322Z] GC before operation: completed in 575.425 ms, heap usage 368.601 MB -> 119.787 MB. [2025-07-31T00:59:25.838Z] ====== log-regression (apache-spark) [default], iteration 1 completed (18375.131 ms) ====== [2025-07-31T00:59:25.838Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-31T00:59:25.838Z] GC before operation: completed in 528.781 ms, heap usage 717.781 MB -> 121.358 MB. [2025-07-31T00:59:42.241Z] ====== log-regression (apache-spark) [default], iteration 2 completed (16247.202 ms) ====== [2025-07-31T00:59:42.241Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-31T00:59:43.036Z] GC before operation: completed in 493.183 ms, heap usage 365.960 MB -> 120.750 MB. [2025-07-31T00:59:57.075Z] ====== log-regression (apache-spark) [default], iteration 3 completed (12415.032 ms) ====== [2025-07-31T00:59:57.075Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-31T00:59:57.075Z] GC before operation: completed in 435.830 ms, heap usage 587.901 MB -> 121.698 MB. [2025-07-31T01:00:07.282Z] ====== log-regression (apache-spark) [default], iteration 4 completed (11004.647 ms) ====== [2025-07-31T01:00:07.282Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-31T01:00:07.282Z] GC before operation: completed in 570.988 ms, heap usage 320.672 MB -> 121.079 MB. [2025-07-31T01:00:17.385Z] ====== log-regression (apache-spark) [default], iteration 5 completed (9824.750 ms) ====== [2025-07-31T01:00:17.385Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-31T01:00:17.385Z] GC before operation: completed in 509.712 ms, heap usage 370.999 MB -> 121.473 MB. [2025-07-31T01:00:29.559Z] ====== log-regression (apache-spark) [default], iteration 6 completed (10561.933 ms) ====== [2025-07-31T01:00:29.559Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-31T01:00:29.559Z] GC before operation: completed in 394.889 ms, heap usage 264.966 MB -> 121.377 MB. [2025-07-31T01:00:38.079Z] ====== log-regression (apache-spark) [default], iteration 7 completed (9059.849 ms) ====== [2025-07-31T01:00:38.079Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-31T01:00:38.079Z] GC before operation: completed in 541.773 ms, heap usage 527.877 MB -> 122.404 MB. [2025-07-31T01:00:48.149Z] ====== log-regression (apache-spark) [default], iteration 8 completed (9244.133 ms) ====== [2025-07-31T01:00:48.149Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-31T01:00:48.149Z] GC before operation: completed in 478.684 ms, heap usage 273.520 MB -> 121.803 MB. [2025-07-31T01:00:56.793Z] ====== log-regression (apache-spark) [default], iteration 9 completed (8775.345 ms) ====== [2025-07-31T01:00:56.793Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-31T01:00:57.570Z] GC before operation: completed in 478.673 ms, heap usage 514.147 MB -> 122.900 MB. [2025-07-31T01:01:04.474Z] ====== log-regression (apache-spark) [default], iteration 10 completed (7331.469 ms) ====== [2025-07-31T01:01:04.474Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-31T01:01:05.251Z] GC before operation: completed in 630.702 ms, heap usage 596.183 MB -> 123.296 MB. [2025-07-31T01:01:13.672Z] ====== log-regression (apache-spark) [default], iteration 11 completed (8295.499 ms) ====== [2025-07-31T01:01:13.672Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-31T01:01:14.474Z] GC before operation: completed in 537.109 ms, heap usage 556.300 MB -> 123.361 MB. [2025-07-31T01:01:22.966Z] ====== log-regression (apache-spark) [default], iteration 12 completed (8719.692 ms) ====== [2025-07-31T01:01:22.966Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-31T01:01:23.781Z] GC before operation: completed in 472.668 ms, heap usage 656.866 MB -> 123.835 MB. [2025-07-31T01:01:32.119Z] ====== log-regression (apache-spark) [default], iteration 13 completed (8743.655 ms) ====== [2025-07-31T01:01:32.119Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-31T01:01:32.956Z] GC before operation: completed in 713.658 ms, heap usage 511.764 MB -> 124.357 MB. [2025-07-31T01:01:41.563Z] ====== log-regression (apache-spark) [default], iteration 14 completed (9028.528 ms) ====== [2025-07-31T01:01:41.563Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-31T01:01:42.344Z] GC before operation: completed in 596.290 ms, heap usage 289.766 MB -> 123.122 MB. [2025-07-31T01:01:47.591Z] 01:01:46.929 WARN [block-manager-storage-async-thread-pool-43] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_718, which does not exist [2025-07-31T01:01:52.249Z] ====== log-regression (apache-spark) [default], iteration 15 completed (9486.270 ms) ====== [2025-07-31T01:01:52.249Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-31T01:01:52.249Z] GC before operation: completed in 657.189 ms, heap usage 233.239 MB -> 123.239 MB. [2025-07-31T01:02:02.507Z] ====== log-regression (apache-spark) [default], iteration 16 completed (8925.146 ms) ====== [2025-07-31T01:02:02.507Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-31T01:02:02.507Z] GC before operation: completed in 508.592 ms, heap usage 252.404 MB -> 123.526 MB. [2025-07-31T01:02:11.086Z] ====== log-regression (apache-spark) [default], iteration 17 completed (8624.354 ms) ====== [2025-07-31T01:02:11.087Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-31T01:02:11.087Z] GC before operation: completed in 590.194 ms, heap usage 865.307 MB -> 125.366 MB. [2025-07-31T01:02:19.606Z] ====== log-regression (apache-spark) [default], iteration 18 completed (7837.394 ms) ====== [2025-07-31T01:02:19.606Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-31T01:02:19.606Z] GC before operation: completed in 573.614 ms, heap usage 440.765 MB -> 124.439 MB. [2025-07-31T01:02:30.039Z] ====== log-regression (apache-spark) [default], iteration 19 completed (9569.759 ms) ====== [2025-07-31T01:02:31.656Z] ----------------------------------- [2025-07-31T01:02:31.656Z] renaissance-log-regression_0_PASSED [2025-07-31T01:02:31.656Z] ----------------------------------- [2025-07-31T01:02:31.656Z] [2025-07-31T01:02:31.656Z] TEST TEARDOWN: [2025-07-31T01:02:31.656Z] Nothing to be done for teardown. [2025-07-31T01:02:31.656Z] renaissance-log-regression_0 Finish Time: Thu Jul 31 01:02:30 2025 Epoch Time (ms): 1753923750843