[2025-01-07T23:00:28.611Z] Running test renaissance-log-regression_0 ... [2025-01-07T23:00:28.611Z] =============================================== [2025-01-07T23:00:28.611Z] renaissance-log-regression_0 Start Time: Tue Jan 7 23:00:27 2025 Epoch Time (ms): 1736290827949 [2025-01-07T23:00:28.611Z] variation: NoOptions [2025-01-07T23:00:28.611Z] JVM_OPTIONS: [2025-01-07T23:00:28.611Z] { \ [2025-01-07T23:00:28.611Z] echo ""; echo "TEST SETUP:"; \ [2025-01-07T23:00:28.611Z] echo "Nothing to be done for setup."; \ [2025-01-07T23:00:28.611Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17362871702120/renaissance-log-regression_0"; \ [2025-01-07T23:00:28.611Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17362871702120/renaissance-log-regression_0"; \ [2025-01-07T23:00:28.611Z] echo ""; echo "TESTING:"; \ [2025-01-07T23:00:28.611Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17362871702120/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-01-07T23:00:28.611Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17362871702120/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-07T23:00:28.611Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-07T23:00:28.611Z] echo "Nothing to be done for teardown."; \ [2025-01-07T23:00:28.611Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17362871702120/TestTargetResult"; [2025-01-07T23:00:28.611Z] [2025-01-07T23:00:28.611Z] TEST SETUP: [2025-01-07T23:00:28.611Z] Nothing to be done for setup. [2025-01-07T23:00:28.611Z] [2025-01-07T23:00:28.611Z] TESTING: [2025-01-07T23:00:38.543Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-07T23:00:52.457Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-01-07T23:01:11.417Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-01-07T23:01:11.417Z] GC before operation: completed in 868.849 ms, heap usage 51.120 MB -> 24.775 MB. [2025-01-07T23:02:00.291Z] ====== log-regression (apache-spark) [default], iteration 0 completed (48782.672 ms) ====== [2025-01-07T23:02:00.291Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-01-07T23:02:01.361Z] GC before operation: completed in 2047.351 ms, heap usage 378.788 MB -> 102.142 MB. [2025-01-07T23:02:17.573Z] ====== log-regression (apache-spark) [default], iteration 1 completed (15994.850 ms) ====== [2025-01-07T23:02:17.573Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-01-07T23:02:19.182Z] GC before operation: completed in 1603.483 ms, heap usage 266.542 MB -> 101.691 MB. [2025-01-07T23:02:30.860Z] ====== log-regression (apache-spark) [default], iteration 2 completed (11172.015 ms) ====== [2025-01-07T23:02:30.860Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-01-07T23:02:31.623Z] GC before operation: completed in 1349.689 ms, heap usage 237.620 MB -> 102.020 MB. [2025-01-07T23:02:43.318Z] ====== log-regression (apache-spark) [default], iteration 3 completed (11520.042 ms) ====== [2025-01-07T23:02:43.318Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-01-07T23:02:44.089Z] GC before operation: completed in 1276.533 ms, heap usage 239.312 MB -> 102.557 MB. [2025-01-07T23:02:53.930Z] ====== log-regression (apache-spark) [default], iteration 4 completed (9532.371 ms) ====== [2025-01-07T23:02:53.930Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-01-07T23:02:55.525Z] GC before operation: completed in 1256.790 ms, heap usage 235.938 MB -> 102.725 MB. [2025-01-07T23:03:02.497Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7719.302 ms) ====== [2025-01-07T23:03:02.497Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-01-07T23:03:04.133Z] GC before operation: completed in 966.637 ms, heap usage 223.531 MB -> 102.780 MB. [2025-01-07T23:03:11.746Z] ====== log-regression (apache-spark) [default], iteration 6 completed (8272.647 ms) ====== [2025-01-07T23:03:11.746Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-01-07T23:03:13.413Z] GC before operation: completed in 1141.078 ms, heap usage 205.924 MB -> 102.923 MB. [2025-01-07T23:03:20.356Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6945.063 ms) ====== [2025-01-07T23:03:20.356Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-01-07T23:03:21.162Z] GC before operation: completed in 777.467 ms, heap usage 211.180 MB -> 103.327 MB. [2025-01-07T23:03:28.139Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6961.217 ms) ====== [2025-01-07T23:03:28.139Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-01-07T23:03:28.938Z] GC before operation: completed in 791.742 ms, heap usage 202.261 MB -> 103.514 MB. [2025-01-07T23:03:35.924Z] ====== log-regression (apache-spark) [default], iteration 9 completed (7113.888 ms) ====== [2025-01-07T23:03:35.924Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-01-07T23:03:37.548Z] GC before operation: completed in 1294.980 ms, heap usage 218.390 MB -> 103.852 MB. [2025-01-07T23:03:44.586Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6883.387 ms) ====== [2025-01-07T23:03:44.586Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-01-07T23:03:45.400Z] GC before operation: completed in 1035.460 ms, heap usage 207.626 MB -> 104.005 MB. [2025-01-07T23:03:52.404Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6867.631 ms) ====== [2025-01-07T23:03:52.404Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-01-07T23:03:52.404Z] GC before operation: completed in 700.097 ms, heap usage 216.591 MB -> 104.295 MB. [2025-01-07T23:03:59.433Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6167.159 ms) ====== [2025-01-07T23:03:59.433Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-01-07T23:03:59.433Z] GC before operation: completed in 740.666 ms, heap usage 210.139 MB -> 104.583 MB. [2025-01-07T23:04:07.007Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6885.578 ms) ====== [2025-01-07T23:04:07.007Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-01-07T23:04:07.007Z] GC before operation: completed in 901.124 ms, heap usage 201.489 MB -> 104.702 MB. [2025-01-07T23:04:14.013Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6283.412 ms) ====== [2025-01-07T23:04:14.013Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-01-07T23:04:14.847Z] GC before operation: completed in 1008.970 ms, heap usage 207.159 MB -> 105.099 MB. [2025-01-07T23:04:21.794Z] ====== log-regression (apache-spark) [default], iteration 15 completed (7012.968 ms) ====== [2025-01-07T23:04:21.794Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-01-07T23:04:22.592Z] GC before operation: completed in 715.920 ms, heap usage 214.734 MB -> 105.331 MB. [2025-01-07T23:04:28.262Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5780.207 ms) ====== [2025-01-07T23:04:28.262Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-01-07T23:04:29.062Z] GC before operation: completed in 659.723 ms, heap usage 209.308 MB -> 105.664 MB. [2025-01-07T23:04:33.574Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4776.484 ms) ====== [2025-01-07T23:04:33.574Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-01-07T23:04:34.362Z] GC before operation: completed in 645.219 ms, heap usage 208.435 MB -> 105.896 MB. [2025-01-07T23:04:39.994Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5260.686 ms) ====== [2025-01-07T23:04:39.994Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-01-07T23:04:39.994Z] GC before operation: completed in 682.538 ms, heap usage 208.002 MB -> 106.019 MB. [2025-01-07T23:04:47.131Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6253.973 ms) ====== [2025-01-07T23:04:48.724Z] ----------------------------------- [2025-01-07T23:04:48.724Z] renaissance-log-regression_0_PASSED [2025-01-07T23:04:48.724Z] ----------------------------------- [2025-01-07T23:04:48.724Z] [2025-01-07T23:04:48.724Z] TEST TEARDOWN: [2025-01-07T23:04:48.724Z] Nothing to be done for teardown. [2025-01-07T23:04:48.724Z] renaissance-log-regression_0 Finish Time: Tue Jan 7 23:04:48 2025 Epoch Time (ms): 1736291088302