[2025-05-28T21:06:58.380Z] Running test renaissance-log-regression_0 ... [2025-05-28T21:06:58.380Z] =============================================== [2025-05-28T21:06:58.380Z] renaissance-log-regression_0 Start Time: Wed May 28 21:06:57 2025 Epoch Time (ms): 1748466417784 [2025-05-28T21:06:58.380Z] variation: NoOptions [2025-05-28T21:06:58.380Z] JVM_OPTIONS: [2025-05-28T21:06:58.380Z] { \ [2025-05-28T21:06:58.380Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T21:06:58.380Z] echo "Nothing to be done for setup."; \ [2025-05-28T21:06:58.380Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-log-regression_0"; \ [2025-05-28T21:06:58.380Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-log-regression_0"; \ [2025-05-28T21:06:58.380Z] echo ""; echo "TESTING:"; \ [2025-05-28T21:06:58.380Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-28T21:06:58.380Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T21:06:58.380Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T21:06:58.380Z] echo "Nothing to be done for teardown."; \ [2025-05-28T21:06:58.380Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/TestTargetResult"; [2025-05-28T21:06:58.380Z] [2025-05-28T21:06:58.380Z] TEST SETUP: [2025-05-28T21:06:58.380Z] Nothing to be done for setup. [2025-05-28T21:06:58.380Z] [2025-05-28T21:06:58.380Z] TESTING: [2025-05-28T21:07:06.558Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-05-28T21:07:11.379Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-28T21:07:11.379Z] GC before operation: completed in 48.223 ms, heap usage 50.663 MB -> 36.738 MB. [2025-05-28T21:07:29.815Z] ====== log-regression (apache-spark) [default], iteration 0 completed (16453.428 ms) ====== [2025-05-28T21:07:29.815Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-28T21:07:29.816Z] GC before operation: completed in 114.555 ms, heap usage 310.205 MB -> 127.652 MB. [2025-05-28T21:07:31.770Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3431.281 ms) ====== [2025-05-28T21:07:31.770Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-28T21:07:31.770Z] GC before operation: completed in 123.060 ms, heap usage 533.716 MB -> 128.937 MB. [2025-05-28T21:07:34.795Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2992.700 ms) ====== [2025-05-28T21:07:34.795Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-28T21:07:34.795Z] GC before operation: completed in 108.165 ms, heap usage 397.441 MB -> 128.985 MB. [2025-05-28T21:07:37.796Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2830.722 ms) ====== [2025-05-28T21:07:37.796Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-28T21:07:37.796Z] GC before operation: completed in 116.943 ms, heap usage 423.017 MB -> 129.542 MB. [2025-05-28T21:07:40.800Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2768.890 ms) ====== [2025-05-28T21:07:40.800Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-28T21:07:40.800Z] GC before operation: completed in 118.278 ms, heap usage 371.574 MB -> 129.756 MB. [2025-05-28T21:07:42.772Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2564.857 ms) ====== [2025-05-28T21:07:42.772Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-28T21:07:42.772Z] GC before operation: completed in 121.816 ms, heap usage 370.438 MB -> 130.142 MB. [2025-05-28T21:07:45.790Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2574.454 ms) ====== [2025-05-28T21:07:45.790Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-28T21:07:45.790Z] GC before operation: completed in 129.859 ms, heap usage 267.798 MB -> 130.130 MB. [2025-05-28T21:07:48.780Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2446.761 ms) ====== [2025-05-28T21:07:48.780Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-28T21:07:48.780Z] GC before operation: completed in 119.019 ms, heap usage 296.784 MB -> 130.674 MB. [2025-05-28T21:07:50.766Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2219.604 ms) ====== [2025-05-28T21:07:50.766Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-28T21:07:50.766Z] GC before operation: completed in 118.451 ms, heap usage 350.667 MB -> 131.225 MB. [2025-05-28T21:07:52.739Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2211.721 ms) ====== [2025-05-28T21:07:52.739Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-28T21:07:52.739Z] GC before operation: completed in 124.563 ms, heap usage 445.754 MB -> 131.774 MB. [2025-05-28T21:07:55.740Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2228.989 ms) ====== [2025-05-28T21:07:55.740Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-28T21:07:55.741Z] GC before operation: completed in 122.893 ms, heap usage 432.112 MB -> 132.077 MB. [2025-05-28T21:07:57.682Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2392.473 ms) ====== [2025-05-28T21:07:57.682Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-28T21:07:57.682Z] GC before operation: completed in 135.974 ms, heap usage 623.732 MB -> 132.971 MB. [2025-05-28T21:08:00.681Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2328.938 ms) ====== [2025-05-28T21:08:00.681Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-28T21:08:00.681Z] GC before operation: completed in 132.271 ms, heap usage 423.295 MB -> 132.806 MB. [2025-05-28T21:08:02.617Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2275.178 ms) ====== [2025-05-28T21:08:02.618Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-28T21:08:02.618Z] GC before operation: completed in 141.513 ms, heap usage 343.528 MB -> 132.929 MB. [2025-05-28T21:08:05.611Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2278.905 ms) ====== [2025-05-28T21:08:05.611Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-28T21:08:05.611Z] GC before operation: completed in 153.104 ms, heap usage 225.374 MB -> 132.804 MB. [2025-05-28T21:08:07.551Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2402.516 ms) ====== [2025-05-28T21:08:07.551Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-28T21:08:07.551Z] GC before operation: completed in 162.536 ms, heap usage 595.975 MB -> 134.450 MB. [2025-05-28T21:08:10.538Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2210.237 ms) ====== [2025-05-28T21:08:10.538Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-28T21:08:10.538Z] GC before operation: completed in 203.792 ms, heap usage 383.409 MB -> 134.163 MB. [2025-05-28T21:08:12.475Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2247.653 ms) ====== [2025-05-28T21:08:12.475Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-28T21:08:12.475Z] GC before operation: completed in 133.754 ms, heap usage 712.974 MB -> 135.583 MB. [2025-05-28T21:08:15.477Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2220.253 ms) ====== [2025-05-28T21:08:15.477Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-28T21:08:15.477Z] GC before operation: completed in 133.535 ms, heap usage 312.083 MB -> 134.651 MB. [2025-05-28T21:08:17.415Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2186.198 ms) ====== [2025-05-28T21:08:17.415Z] ----------------------------------- [2025-05-28T21:08:17.415Z] renaissance-log-regression_0_PASSED [2025-05-28T21:08:17.415Z] ----------------------------------- [2025-05-28T21:08:17.415Z] [2025-05-28T21:08:17.415Z] TEST TEARDOWN: [2025-05-28T21:08:17.415Z] Nothing to be done for teardown. [2025-05-28T21:08:17.415Z] renaissance-log-regression_0 Finish Time: Wed May 28 21:08:17 2025 Epoch Time (ms): 1748466497205