[2025-05-22T23:56:38.579Z] Running test renaissance-log-regression_0 ... [2025-05-22T23:56:38.579Z] =============================================== [2025-05-22T23:56:38.579Z] renaissance-log-regression_0 Start Time: Thu May 22 23:56:38 2025 Epoch Time (ms): 1747958198161 [2025-05-22T23:56:38.579Z] variation: NoOptions [2025-05-22T23:56:38.579Z] JVM_OPTIONS: [2025-05-22T23:56:38.579Z] { \ [2025-05-22T23:56:38.579Z] echo ""; echo "TEST SETUP:"; \ [2025-05-22T23:56:38.579Z] echo "Nothing to be done for setup."; \ [2025-05-22T23:56:38.579Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17479545628644/renaissance-log-regression_0"; \ [2025-05-22T23:56:38.579Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17479545628644/renaissance-log-regression_0"; \ [2025-05-22T23:56:38.579Z] echo ""; echo "TESTING:"; \ [2025-05-22T23:56:38.580Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_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_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17479545628644/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-22T23:56:38.580Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17479545628644/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-22T23:56:38.580Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-22T23:56:38.580Z] echo "Nothing to be done for teardown."; \ [2025-05-22T23:56:38.580Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17479545628644/TestTargetResult"; [2025-05-22T23:56:38.580Z] [2025-05-22T23:56:38.580Z] TEST SETUP: [2025-05-22T23:56:38.580Z] Nothing to be done for setup. [2025-05-22T23:56:38.580Z] [2025-05-22T23:56:38.580Z] TESTING: [2025-05-22T23:56:40.822Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-05-22T23:56:40.822Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/output_17479545628644/renaissance-log-regression_0/launcher-235638-185704421428986075/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-05-22T23:56:40.822Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-05-22T23:56:40.822Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-05-22T23:57:03.804Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-22T23:57:12.803Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-22T23:57:12.803Z] GC before operation: completed in 200.492 ms, heap usage 41.090 MB -> 35.327 MB. [2025-05-22T23:58:10.431Z] ====== log-regression (apache-spark) [default], iteration 0 completed (48528.555 ms) ====== [2025-05-22T23:58:10.431Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-22T23:58:10.431Z] GC before operation: completed in 495.657 ms, heap usage 335.467 MB -> 122.942 MB. [2025-05-22T23:58:11.583Z] ====== log-regression (apache-spark) [default], iteration 1 completed (9744.898 ms) ====== [2025-05-22T23:58:11.583Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-22T23:58:11.914Z] GC before operation: completed in 561.895 ms, heap usage 713.935 MB -> 124.465 MB. [2025-05-22T23:58:20.787Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7612.507 ms) ====== [2025-05-22T23:58:20.787Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-22T23:58:20.787Z] GC before operation: completed in 594.578 ms, heap usage 482.134 MB -> 124.144 MB. [2025-05-22T23:58:28.060Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6890.091 ms) ====== [2025-05-22T23:58:28.060Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-22T23:58:28.060Z] GC before operation: completed in 612.760 ms, heap usage 718.062 MB -> 125.184 MB. [2025-05-22T23:58:35.570Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6763.623 ms) ====== [2025-05-22T23:58:35.570Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-22T23:58:35.570Z] GC before operation: completed in 632.374 ms, heap usage 373.539 MB -> 124.460 MB. [2025-05-22T23:58:41.470Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6469.690 ms) ====== [2025-05-22T23:58:41.470Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-22T23:58:42.172Z] GC before operation: completed in 700.267 ms, heap usage 372.212 MB -> 124.519 MB. [2025-05-22T23:58:48.052Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5884.861 ms) ====== [2025-05-22T23:58:48.052Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-22T23:58:48.787Z] GC before operation: completed in 727.006 ms, heap usage 709.883 MB -> 125.811 MB. [2025-05-22T23:58:56.041Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6048.437 ms) ====== [2025-05-22T23:58:56.041Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-22T23:58:56.041Z] GC before operation: completed in 711.995 ms, heap usage 380.746 MB -> 125.037 MB. [2025-05-22T23:59:01.945Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5726.060 ms) ====== [2025-05-22T23:59:01.945Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-22T23:59:01.945Z] GC before operation: completed in 722.824 ms, heap usage 330.998 MB -> 125.164 MB. [2025-05-22T23:59:09.215Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6164.297 ms) ====== [2025-05-22T23:59:09.215Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-22T23:59:09.215Z] GC before operation: completed in 733.010 ms, heap usage 399.266 MB -> 125.536 MB. [2025-05-22T23:59:15.795Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5992.562 ms) ====== [2025-05-22T23:59:15.795Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-22T23:59:15.795Z] GC before operation: completed in 753.082 ms, heap usage 485.691 MB -> 126.072 MB. [2025-05-22T23:59:21.692Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5670.531 ms) ====== [2025-05-22T23:59:21.692Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-22T23:59:22.026Z] GC before operation: completed in 772.824 ms, heap usage 724.577 MB -> 126.930 MB. [2025-05-22T23:59:27.932Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5518.971 ms) ====== [2025-05-22T23:59:27.932Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-22T23:59:28.260Z] GC before operation: completed in 768.309 ms, heap usage 724.044 MB -> 127.128 MB. [2025-05-22T23:59:34.155Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5184.869 ms) ====== [2025-05-22T23:59:34.155Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-22T23:59:34.481Z] GC before operation: completed in 794.117 ms, heap usage 718.403 MB -> 127.328 MB. [2025-05-22T23:59:40.377Z] ====== log-regression (apache-spark) [default], iteration 14 completed (5341.632 ms) ====== [2025-05-22T23:59:40.377Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-22T23:59:40.377Z] GC before operation: completed in 792.230 ms, heap usage 691.983 MB -> 127.440 MB. [2025-05-22T23:59:46.261Z] ====== log-regression (apache-spark) [default], iteration 15 completed (5462.274 ms) ====== [2025-05-22T23:59:46.261Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-22T23:59:46.974Z] GC before operation: completed in 806.265 ms, heap usage 653.883 MB -> 127.470 MB. [2025-05-22T23:59:53.006Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5509.273 ms) ====== [2025-05-22T23:59:53.006Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-22T23:59:53.006Z] GC before operation: completed in 825.357 ms, heap usage 713.524 MB -> 127.873 MB. [2025-05-22T23:59:58.914Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5466.924 ms) ====== [2025-05-22T23:59:58.914Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-22T23:59:59.627Z] GC before operation: completed in 825.474 ms, heap usage 301.150 MB -> 126.835 MB. [2025-05-23T00:00:05.516Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5259.447 ms) ====== [2025-05-23T00:00:05.516Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-23T00:00:05.516Z] GC before operation: completed in 827.051 ms, heap usage 714.257 MB -> 128.351 MB. [2025-05-23T00:00:11.459Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5570.742 ms) ====== [2025-05-23T00:00:12.594Z] ----------------------------------- [2025-05-23T00:00:12.594Z] renaissance-log-regression_0_PASSED [2025-05-23T00:00:12.594Z] ----------------------------------- [2025-05-23T00:00:12.935Z] [2025-05-23T00:00:12.935Z] TEST TEARDOWN: [2025-05-23T00:00:12.935Z] Nothing to be done for teardown. [2025-05-23T00:00:12.935Z] renaissance-log-regression_0 Finish Time: Fri May 23 00:00:12 2025 Epoch Time (ms): 1747958412729