[2025-06-08T09:25:29.312Z] Running test renaissance-log-regression_0 ... [2025-06-08T09:25:29.312Z] =============================================== [2025-06-08T09:25:29.312Z] renaissance-log-regression_0 Start Time: Sun Jun 8 09:25:28 2025 Epoch Time (ms): 1749374728649 [2025-06-08T09:25:29.312Z] variation: NoOptions [2025-06-08T09:25:29.312Z] JVM_OPTIONS: [2025-06-08T09:25:29.312Z] { \ [2025-06-08T09:25:29.312Z] echo ""; echo "TEST SETUP:"; \ [2025-06-08T09:25:29.312Z] echo "Nothing to be done for setup."; \ [2025-06-08T09:25:29.312Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17493697211068/renaissance-log-regression_0"; \ [2025-06-08T09:25:29.312Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17493697211068/renaissance-log-regression_0"; \ [2025-06-08T09:25:29.312Z] echo ""; echo "TESTING:"; \ [2025-06-08T09:25:29.312Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-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_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17493697211068/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-08T09:25:29.312Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17493697211068/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-08T09:25:29.312Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-08T09:25:29.313Z] echo "Nothing to be done for teardown."; \ [2025-06-08T09:25:29.313Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17493697211068/TestTargetResult"; [2025-06-08T09:25:29.313Z] [2025-06-08T09:25:29.313Z] TEST SETUP: [2025-06-08T09:25:29.313Z] Nothing to be done for setup. [2025-06-08T09:25:29.313Z] [2025-06-08T09:25:29.313Z] TESTING: [2025-06-08T09:25:30.119Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-06-08T09:25:30.119Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17493697211068/renaissance-log-regression_0/launcher-092528-7926663600173330428/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-06-08T09:25:30.119Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-06-08T09:25:30.119Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-06-08T09:25:42.163Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-08T09:25:49.293Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-08T09:25:49.293Z] GC before operation: completed in 125.830 ms, heap usage 60.369 MB -> 35.065 MB. [2025-06-08T09:26:25.186Z] ====== log-regression (apache-spark) [default], iteration 0 completed (31202.518 ms) ====== [2025-06-08T09:26:25.186Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-08T09:26:25.186Z] GC before operation: completed in 300.313 ms, heap usage 514.690 MB -> 119.993 MB. [2025-06-08T09:26:29.360Z] ====== log-regression (apache-spark) [default], iteration 1 completed (8035.047 ms) ====== [2025-06-08T09:26:29.360Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-08T09:26:29.360Z] GC before operation: completed in 333.922 ms, heap usage 607.214 MB -> 120.844 MB. [2025-06-08T09:26:36.623Z] ====== log-regression (apache-spark) [default], iteration 2 completed (6730.750 ms) ====== [2025-06-08T09:26:36.623Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-08T09:26:36.623Z] GC before operation: completed in 282.740 ms, heap usage 264.406 MB -> 120.192 MB. [2025-06-08T09:26:42.443Z] ====== log-regression (apache-spark) [default], iteration 3 completed (5751.402 ms) ====== [2025-06-08T09:26:42.443Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-08T09:26:42.443Z] GC before operation: completed in 259.508 ms, heap usage 549.802 MB -> 121.306 MB. [2025-06-08T09:26:47.133Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4886.420 ms) ====== [2025-06-08T09:26:47.133Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-08T09:26:47.954Z] GC before operation: completed in 346.687 ms, heap usage 471.603 MB -> 121.422 MB. [2025-06-08T09:26:52.629Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4750.524 ms) ====== [2025-06-08T09:26:52.629Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-08T09:26:52.629Z] GC before operation: completed in 351.806 ms, heap usage 295.041 MB -> 121.103 MB. [2025-06-08T09:26:57.305Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4029.877 ms) ====== [2025-06-08T09:26:57.305Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-08T09:26:57.305Z] GC before operation: completed in 272.273 ms, heap usage 302.428 MB -> 121.236 MB. [2025-06-08T09:27:01.967Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4801.237 ms) ====== [2025-06-08T09:27:01.967Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-08T09:27:01.967Z] GC before operation: completed in 302.994 ms, heap usage 354.963 MB -> 121.680 MB. [2025-06-08T09:27:06.671Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4487.384 ms) ====== [2025-06-08T09:27:06.671Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-08T09:27:06.671Z] GC before operation: completed in 258.893 ms, heap usage 587.615 MB -> 122.527 MB. [2025-06-08T09:27:11.491Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4089.989 ms) ====== [2025-06-08T09:27:11.491Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-08T09:27:11.491Z] GC before operation: completed in 293.126 ms, heap usage 667.535 MB -> 123.104 MB. [2025-06-08T09:27:16.715Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4761.834 ms) ====== [2025-06-08T09:27:16.715Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-08T09:27:16.715Z] GC before operation: completed in 297.957 ms, heap usage 861.324 MB -> 123.610 MB. [2025-06-08T09:27:21.365Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4564.502 ms) ====== [2025-06-08T09:27:21.365Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-08T09:27:21.365Z] GC before operation: completed in 314.463 ms, heap usage 663.544 MB -> 123.513 MB. [2025-06-08T09:27:26.035Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4551.086 ms) ====== [2025-06-08T09:27:26.035Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-08T09:27:26.035Z] GC before operation: completed in 272.289 ms, heap usage 265.324 MB -> 122.493 MB. [2025-06-08T09:27:29.618Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3609.364 ms) ====== [2025-06-08T09:27:29.618Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-08T09:27:30.426Z] GC before operation: completed in 306.161 ms, heap usage 594.970 MB -> 123.649 MB. [2025-06-08T09:27:34.035Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3704.336 ms) ====== [2025-06-08T09:27:34.035Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-08T09:27:34.035Z] GC before operation: completed in 353.586 ms, heap usage 638.661 MB -> 124.053 MB. [2025-06-08T09:27:38.739Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3995.575 ms) ====== [2025-06-08T09:27:38.739Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-08T09:27:38.739Z] GC before operation: completed in 335.932 ms, heap usage 665.663 MB -> 124.168 MB. [2025-06-08T09:27:43.421Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4646.721 ms) ====== [2025-06-08T09:27:43.421Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-08T09:27:43.421Z] GC before operation: completed in 373.038 ms, heap usage 667.225 MB -> 124.281 MB. [2025-06-08T09:27:48.117Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4079.802 ms) ====== [2025-06-08T09:27:48.117Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-08T09:27:48.117Z] GC before operation: completed in 381.727 ms, heap usage 666.193 MB -> 124.525 MB. [2025-06-08T09:27:51.739Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4080.055 ms) ====== [2025-06-08T09:27:51.739Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-08T09:27:52.565Z] GC before operation: completed in 370.346 ms, heap usage 217.329 MB -> 124.367 MB. [2025-06-08T09:27:57.254Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4389.335 ms) ====== [2025-06-08T09:27:57.254Z] ----------------------------------- [2025-06-08T09:27:57.254Z] renaissance-log-regression_0_PASSED [2025-06-08T09:27:57.254Z] ----------------------------------- [2025-06-08T09:27:57.254Z] [2025-06-08T09:27:57.254Z] TEST TEARDOWN: [2025-06-08T09:27:57.254Z] Nothing to be done for teardown. [2025-06-08T09:27:57.254Z] renaissance-log-regression_0 Finish Time: Sun Jun 8 09:27:57 2025 Epoch Time (ms): 1749374877057