[2024-09-26T22:44:34.842Z] Running test renaissance-log-regression_0 ... [2024-09-26T22:44:34.842Z] =============================================== [2024-09-26T22:44:34.842Z] renaissance-log-regression_0 Start Time: Thu Sep 26 22:44:33 2024 Epoch Time (ms): 1727390673655 [2024-09-26T22:44:34.842Z] variation: NoOptions [2024-09-26T22:44:34.842Z] JVM_OPTIONS: [2024-09-26T22:44:34.842Z] { \ [2024-09-26T22:44:34.842Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T22:44:34.842Z] echo "Nothing to be done for setup."; \ [2024-09-26T22:44:34.842Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17273896177516/renaissance-log-regression_0"; \ [2024-09-26T22:44:34.842Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17273896177516/renaissance-log-regression_0"; \ [2024-09-26T22:44:34.842Z] echo ""; echo "TESTING:"; \ [2024-09-26T22:44:34.842Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17273896177516/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-26T22:44:34.842Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17273896177516/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T22:44:34.842Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T22:44:34.842Z] echo "Nothing to be done for teardown."; \ [2024-09-26T22:44:34.842Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17273896177516/TestTargetResult"; [2024-09-26T22:44:34.842Z] [2024-09-26T22:44:34.842Z] TEST SETUP: [2024-09-26T22:44:34.842Z] Nothing to be done for setup. [2024-09-26T22:44:34.842Z] [2024-09-26T22:44:34.842Z] TESTING: [2024-09-26T22:44:38.955Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T22:44:43.066Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2024-09-26T22:44:52.727Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-26T22:44:53.670Z] GC before operation: completed in 252.342 ms, heap usage 81.310 MB -> 28.584 MB. [2024-09-26T22:45:11.900Z] ====== log-regression (apache-spark) [default], iteration 0 completed (16981.838 ms) ====== [2024-09-26T22:45:11.900Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-26T22:45:11.900Z] GC before operation: completed in 479.274 ms, heap usage 1.284 GB -> 112.002 MB. [2024-09-26T22:45:14.894Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3800.645 ms) ====== [2024-09-26T22:45:14.894Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-26T22:45:14.894Z] GC before operation: completed in 401.608 ms, heap usage 1.282 GB -> 111.958 MB. [2024-09-26T22:45:17.887Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3426.171 ms) ====== [2024-09-26T22:45:17.887Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-26T22:45:18.832Z] GC before operation: completed in 382.662 ms, heap usage 1.187 GB -> 112.857 MB. [2024-09-26T22:45:21.831Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3034.754 ms) ====== [2024-09-26T22:45:21.831Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-26T22:45:21.831Z] GC before operation: completed in 360.915 ms, heap usage 1.024 GB -> 112.871 MB. [2024-09-26T22:45:24.825Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2759.031 ms) ====== [2024-09-26T22:45:24.825Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-26T22:45:24.825Z] GC before operation: completed in 353.658 ms, heap usage 1.013 GB -> 113.319 MB. [2024-09-26T22:45:27.818Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2786.986 ms) ====== [2024-09-26T22:45:27.819Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-26T22:45:28.763Z] GC before operation: completed in 363.968 ms, heap usage 1.015 GB -> 113.762 MB. [2024-09-26T22:45:30.744Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2703.302 ms) ====== [2024-09-26T22:45:30.744Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-26T22:45:31.688Z] GC before operation: completed in 359.998 ms, heap usage 1.016 GB -> 114.172 MB. [2024-09-26T22:45:33.626Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2552.232 ms) ====== [2024-09-26T22:45:33.626Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-26T22:45:34.570Z] GC before operation: completed in 369.340 ms, heap usage 1.026 GB -> 114.656 MB. [2024-09-26T22:45:36.510Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2457.651 ms) ====== [2024-09-26T22:45:36.510Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-26T22:45:37.456Z] GC before operation: completed in 353.165 ms, heap usage 1.027 GB -> 115.080 MB. [2024-09-26T22:45:39.398Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2460.799 ms) ====== [2024-09-26T22:45:39.398Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-26T22:45:40.342Z] GC before operation: completed in 350.201 ms, heap usage 1.025 GB -> 115.531 MB. [2024-09-26T22:45:42.281Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2446.664 ms) ====== [2024-09-26T22:45:42.281Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-26T22:45:43.225Z] GC before operation: completed in 376.624 ms, heap usage 1.051 GB -> 115.995 MB. [2024-09-26T22:45:45.165Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2309.725 ms) ====== [2024-09-26T22:45:45.165Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-26T22:45:45.165Z] GC before operation: completed in 363.247 ms, heap usage 1.037 GB -> 116.424 MB. [2024-09-26T22:45:47.818Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2343.678 ms) ====== [2024-09-26T22:45:47.818Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-26T22:45:47.818Z] GC before operation: completed in 377.930 ms, heap usage 1.041 GB -> 116.811 MB. [2024-09-26T22:45:50.809Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2268.954 ms) ====== [2024-09-26T22:45:50.809Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-26T22:45:50.809Z] GC before operation: completed in 369.461 ms, heap usage 1.041 GB -> 117.268 MB. [2024-09-26T22:45:52.746Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2062.267 ms) ====== [2024-09-26T22:45:52.746Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-26T22:45:53.691Z] GC before operation: completed in 272.373 ms, heap usage 1.039 GB -> 117.704 MB. [2024-09-26T22:45:55.627Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2094.771 ms) ====== [2024-09-26T22:45:55.627Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-26T22:45:55.627Z] GC before operation: completed in 417.974 ms, heap usage 1.041 GB -> 118.172 MB. [2024-09-26T22:45:57.568Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2138.727 ms) ====== [2024-09-26T22:45:57.568Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-26T22:45:58.509Z] GC before operation: completed in 384.519 ms, heap usage 1.036 GB -> 118.625 MB. [2024-09-26T22:46:00.440Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2022.021 ms) ====== [2024-09-26T22:46:00.440Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-26T22:46:00.440Z] GC before operation: completed in 428.404 ms, heap usage 1.039 GB -> 119.003 MB. [2024-09-26T22:46:03.444Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2136.132 ms) ====== [2024-09-26T22:46:03.444Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-26T22:46:03.444Z] GC before operation: completed in 365.571 ms, heap usage 1.038 GB -> 119.435 MB. [2024-09-26T22:46:05.377Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2229.547 ms) ====== [2024-09-26T22:46:06.319Z] ----------------------------------- [2024-09-26T22:46:06.319Z] renaissance-log-regression_0_PASSED [2024-09-26T22:46:06.319Z] ----------------------------------- [2024-09-26T22:46:06.319Z] [2024-09-26T22:46:06.319Z] TEST TEARDOWN: [2024-09-26T22:46:06.319Z] Nothing to be done for teardown. [2024-09-26T22:46:06.319Z] renaissance-log-regression_0 Finish Time: Thu Sep 26 22:46:05 2024 Epoch Time (ms): 1727390765631