[2025-01-10T13:17:22.697Z] Running test renaissance-log-regression_0 ... [2025-01-10T13:17:22.697Z] =============================================== [2025-01-10T13:17:22.697Z] renaissance-log-regression_0 Start Time: Fri Jan 10 13:17:21 2025 Epoch Time (ms): 1736515041926 [2025-01-10T13:17:22.697Z] variation: NoOptions [2025-01-10T13:17:22.697Z] JVM_OPTIONS: [2025-01-10T13:17:22.697Z] { \ [2025-01-10T13:17:22.697Z] echo ""; echo "TEST SETUP:"; \ [2025-01-10T13:17:22.697Z] echo "Nothing to be done for setup."; \ [2025-01-10T13:17:22.697Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365099005399/renaissance-log-regression_0"; \ [2025-01-10T13:17:22.697Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365099005399/renaissance-log-regression_0"; \ [2025-01-10T13:17:22.697Z] echo ""; echo "TESTING:"; \ [2025-01-10T13:17:22.697Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365099005399/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-01-10T13:17:22.697Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365099005399/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-10T13:17:22.697Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-10T13:17:22.697Z] echo "Nothing to be done for teardown."; \ [2025-01-10T13:17:22.697Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365099005399/TestTargetResult"; [2025-01-10T13:17:22.697Z] [2025-01-10T13:17:22.698Z] TEST SETUP: [2025-01-10T13:17:22.698Z] Nothing to be done for setup. [2025-01-10T13:17:22.698Z] [2025-01-10T13:17:22.698Z] TESTING: [2025-01-10T13:17:32.629Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-10T13:17:46.542Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-01-10T13:18:05.452Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-01-10T13:18:05.452Z] GC before operation: completed in 1154.969 ms, heap usage 71.493 MB -> 27.223 MB. [2025-01-10T13:19:12.797Z] ====== log-regression (apache-spark) [default], iteration 0 completed (61528.603 ms) ====== [2025-01-10T13:19:12.797Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-01-10T13:19:12.797Z] GC before operation: completed in 2294.911 ms, heap usage 279.702 MB -> 101.610 MB. [2025-01-10T13:19:26.719Z] ====== log-regression (apache-spark) [default], iteration 1 completed (16581.185 ms) ====== [2025-01-10T13:19:26.719Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-01-10T13:19:27.523Z] GC before operation: completed in 1389.052 ms, heap usage 266.973 MB -> 101.908 MB. [2025-01-10T13:19:40.003Z] ====== log-regression (apache-spark) [default], iteration 2 completed (11686.479 ms) ====== [2025-01-10T13:19:40.003Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-01-10T13:19:40.779Z] GC before operation: completed in 1439.448 ms, heap usage 250.031 MB -> 102.312 MB. [2025-01-10T13:19:50.814Z] ====== log-regression (apache-spark) [default], iteration 3 completed (9589.318 ms) ====== [2025-01-10T13:19:50.815Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-01-10T13:19:51.602Z] GC before operation: completed in 1461.470 ms, heap usage 553.678 MB -> 105.319 MB. [2025-01-10T13:20:01.758Z] ====== log-regression (apache-spark) [default], iteration 4 completed (9951.385 ms) ====== [2025-01-10T13:20:01.758Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-01-10T13:20:03.363Z] GC before operation: completed in 1783.989 ms, heap usage 557.890 MB -> 105.565 MB. [2025-01-10T13:20:11.849Z] ====== log-regression (apache-spark) [default], iteration 5 completed (8128.844 ms) ====== [2025-01-10T13:20:11.849Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-01-10T13:20:13.463Z] GC before operation: completed in 1388.620 ms, heap usage 527.793 MB -> 105.660 MB. [2025-01-10T13:20:21.880Z] ====== log-regression (apache-spark) [default], iteration 6 completed (7935.005 ms) ====== [2025-01-10T13:20:21.881Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-01-10T13:20:21.881Z] GC before operation: completed in 1183.197 ms, heap usage 519.964 MB -> 105.912 MB. [2025-01-10T13:20:31.854Z] ====== log-regression (apache-spark) [default], iteration 7 completed (9586.800 ms) ====== [2025-01-10T13:20:31.854Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-01-10T13:20:33.556Z] GC before operation: completed in 1220.684 ms, heap usage 511.159 MB -> 106.055 MB. [2025-01-10T13:20:42.044Z] ====== log-regression (apache-spark) [default], iteration 8 completed (9059.302 ms) ====== [2025-01-10T13:20:42.044Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-01-10T13:20:43.664Z] GC before operation: completed in 1195.399 ms, heap usage 526.262 MB -> 106.418 MB. [2025-01-10T13:20:52.804Z] ====== log-regression (apache-spark) [default], iteration 9 completed (9390.602 ms) ====== [2025-01-10T13:20:52.804Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-01-10T13:20:53.573Z] GC before operation: completed in 964.137 ms, heap usage 509.397 MB -> 106.557 MB. [2025-01-10T13:21:01.894Z] ====== log-regression (apache-spark) [default], iteration 10 completed (8188.280 ms) ====== [2025-01-10T13:21:01.894Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-01-10T13:21:02.666Z] GC before operation: completed in 1009.014 ms, heap usage 518.635 MB -> 106.905 MB. [2025-01-10T13:21:11.073Z] ====== log-regression (apache-spark) [default], iteration 11 completed (7715.742 ms) ====== [2025-01-10T13:21:11.073Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-01-10T13:21:11.882Z] GC before operation: completed in 1000.804 ms, heap usage 508.852 MB -> 107.035 MB. [2025-01-10T13:21:18.917Z] ====== log-regression (apache-spark) [default], iteration 12 completed (7134.104 ms) ====== [2025-01-10T13:21:18.917Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-01-10T13:21:19.694Z] GC before operation: completed in 846.433 ms, heap usage 503.052 MB -> 107.353 MB. [2025-01-10T13:21:26.582Z] ====== log-regression (apache-spark) [default], iteration 13 completed (7110.458 ms) ====== [2025-01-10T13:21:26.582Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-01-10T13:21:28.193Z] GC before operation: completed in 1264.644 ms, heap usage 496.976 MB -> 107.514 MB. [2025-01-10T13:21:35.128Z] ====== log-regression (apache-spark) [default], iteration 14 completed (7146.151 ms) ====== [2025-01-10T13:21:35.128Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-01-10T13:21:35.894Z] GC before operation: completed in 930.741 ms, heap usage 500.959 MB -> 107.758 MB. [2025-01-10T13:21:44.176Z] ====== log-regression (apache-spark) [default], iteration 15 completed (7361.610 ms) ====== [2025-01-10T13:21:44.176Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-01-10T13:21:44.934Z] GC before operation: completed in 1113.442 ms, heap usage 502.090 MB -> 107.963 MB. [2025-01-10T13:21:53.282Z] ====== log-regression (apache-spark) [default], iteration 16 completed (8652.182 ms) ====== [2025-01-10T13:21:53.282Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-01-10T13:21:54.057Z] GC before operation: completed in 1008.059 ms, heap usage 503.343 MB -> 108.337 MB. [2025-01-10T13:22:04.049Z] ====== log-regression (apache-spark) [default], iteration 17 completed (9568.723 ms) ====== [2025-01-10T13:22:04.049Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-01-10T13:22:04.837Z] GC before operation: completed in 1234.199 ms, heap usage 503.087 MB -> 108.614 MB. [2025-01-10T13:22:12.371Z] ====== log-regression (apache-spark) [default], iteration 18 completed (7655.117 ms) ====== [2025-01-10T13:22:12.371Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-01-10T13:22:14.054Z] GC before operation: completed in 1112.520 ms, heap usage 509.793 MB -> 108.802 MB. [2025-01-10T13:22:21.342Z] ====== log-regression (apache-spark) [default], iteration 19 completed (7582.799 ms) ====== [2025-01-10T13:22:22.922Z] ----------------------------------- [2025-01-10T13:22:22.922Z] renaissance-log-regression_0_PASSED [2025-01-10T13:22:22.922Z] ----------------------------------- [2025-01-10T13:22:22.922Z] [2025-01-10T13:22:22.922Z] TEST TEARDOWN: [2025-01-10T13:22:22.922Z] Nothing to be done for teardown. [2025-01-10T13:22:22.922Z] renaissance-log-regression_0 Finish Time: Fri Jan 10 13:22:22 2025 Epoch Time (ms): 1736515342233