[2024-09-27T12:31:41.960Z] Running test renaissance-log-regression_0 ... [2024-09-27T12:31:41.960Z] =============================================== [2024-09-27T12:31:41.960Z] renaissance-log-regression_0 Start Time: Fri Sep 27 12:31:41 2024 Epoch Time (ms): 1727440301787 [2024-09-27T12:31:41.960Z] variation: NoOptions [2024-09-27T12:31:41.960Z] JVM_OPTIONS: [2024-09-27T12:31:41.960Z] { \ [2024-09-27T12:31:41.960Z] echo ""; echo "TEST SETUP:"; \ [2024-09-27T12:31:41.960Z] echo "Nothing to be done for setup."; \ [2024-09-27T12:31:41.960Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17274365276368/renaissance-log-regression_0"; \ [2024-09-27T12:31:41.960Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17274365276368/renaissance-log-regression_0"; \ [2024-09-27T12:31:41.960Z] echo ""; echo "TESTING:"; \ [2024-09-27T12:31:41.960Z] "/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_17274365276368/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-27T12:31:41.960Z] 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_17274365276368/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-27T12:31:41.960Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-27T12:31:41.960Z] echo "Nothing to be done for teardown."; \ [2024-09-27T12:31:41.960Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17274365276368/TestTargetResult"; [2024-09-27T12:31:41.960Z] [2024-09-27T12:31:41.960Z] TEST SETUP: [2024-09-27T12:31:41.960Z] Nothing to be done for setup. [2024-09-27T12:31:41.960Z] [2024-09-27T12:31:41.960Z] TESTING: [2024-09-27T12:31:54.316Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-27T12:32:07.614Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-27T12:32:24.549Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-27T12:32:24.549Z] GC before operation: completed in 911.887 ms, heap usage 100.456 MB -> 28.067 MB. [2024-09-27T12:33:23.185Z] ====== log-regression (apache-spark) [default], iteration 0 completed (52836.781 ms) ====== [2024-09-27T12:33:23.185Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-27T12:33:23.185Z] GC before operation: completed in 2254.642 ms, heap usage 478.437 MB -> 103.161 MB. [2024-09-27T12:33:32.252Z] ====== log-regression (apache-spark) [default], iteration 1 completed (13165.574 ms) ====== [2024-09-27T12:33:32.252Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-27T12:33:33.085Z] GC before operation: completed in 1480.367 ms, heap usage 249.685 MB -> 101.683 MB. [2024-09-27T12:33:43.793Z] ====== log-regression (apache-spark) [default], iteration 2 completed (9873.464 ms) ====== [2024-09-27T12:33:43.793Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-27T12:33:44.624Z] GC before operation: completed in 1390.596 ms, heap usage 206.323 MB -> 101.841 MB. [2024-09-27T12:33:53.751Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8780.594 ms) ====== [2024-09-27T12:33:53.751Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-27T12:33:55.189Z] GC before operation: completed in 1424.004 ms, heap usage 498.865 MB -> 104.666 MB. [2024-09-27T12:34:02.748Z] ====== log-regression (apache-spark) [default], iteration 4 completed (8312.070 ms) ====== [2024-09-27T12:34:02.748Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-27T12:34:04.508Z] GC before operation: completed in 1583.617 ms, heap usage 518.558 MB -> 105.060 MB. [2024-09-27T12:34:13.460Z] ====== log-regression (apache-spark) [default], iteration 5 completed (8232.611 ms) ====== [2024-09-27T12:34:13.460Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-27T12:34:14.303Z] GC before operation: completed in 1021.869 ms, heap usage 515.329 MB -> 105.311 MB. [2024-09-27T12:34:20.372Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6642.917 ms) ====== [2024-09-27T12:34:20.372Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-27T12:34:22.116Z] GC before operation: completed in 1025.420 ms, heap usage 518.906 MB -> 105.674 MB. [2024-09-27T12:34:28.152Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6692.814 ms) ====== [2024-09-27T12:34:28.152Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-27T12:34:29.982Z] GC before operation: completed in 1266.817 ms, heap usage 518.853 MB -> 106.062 MB. [2024-09-27T12:34:37.360Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7373.579 ms) ====== [2024-09-27T12:34:37.360Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-27T12:34:38.200Z] GC before operation: completed in 1138.502 ms, heap usage 518.529 MB -> 106.222 MB. [2024-09-27T12:34:45.573Z] ====== log-regression (apache-spark) [default], iteration 9 completed (7299.601 ms) ====== [2024-09-27T12:34:45.573Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-27T12:34:46.420Z] GC before operation: completed in 1015.719 ms, heap usage 510.076 MB -> 106.352 MB. [2024-09-27T12:34:53.800Z] ====== log-regression (apache-spark) [default], iteration 10 completed (7254.523 ms) ====== [2024-09-27T12:34:53.800Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-27T12:34:54.655Z] GC before operation: completed in 1078.826 ms, heap usage 512.881 MB -> 106.626 MB. [2024-09-27T12:35:02.062Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6816.864 ms) ====== [2024-09-27T12:35:02.062Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-27T12:35:02.896Z] GC before operation: completed in 1246.978 ms, heap usage 521.994 MB -> 106.943 MB. [2024-09-27T12:35:10.314Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6859.215 ms) ====== [2024-09-27T12:35:10.314Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-27T12:35:10.314Z] GC before operation: completed in 804.910 ms, heap usage 519.708 MB -> 107.284 MB. [2024-09-27T12:35:17.141Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6504.426 ms) ====== [2024-09-27T12:35:17.141Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-27T12:35:17.985Z] GC before operation: completed in 818.375 ms, heap usage 521.475 MB -> 107.469 MB. [2024-09-27T12:35:25.529Z] ====== log-regression (apache-spark) [default], iteration 14 completed (7124.477 ms) ====== [2024-09-27T12:35:25.529Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-27T12:35:26.410Z] GC before operation: completed in 1101.443 ms, heap usage 519.966 MB -> 107.700 MB. [2024-09-27T12:35:35.508Z] ====== log-regression (apache-spark) [default], iteration 15 completed (8228.044 ms) ====== [2024-09-27T12:35:35.508Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-27T12:35:35.508Z] GC before operation: completed in 1219.553 ms, heap usage 512.787 MB -> 107.864 MB. [2024-09-27T12:35:43.144Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6844.362 ms) ====== [2024-09-27T12:35:43.144Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-27T12:35:43.992Z] GC before operation: completed in 1099.181 ms, heap usage 520.167 MB -> 108.283 MB. [2024-09-27T12:35:50.066Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6172.896 ms) ====== [2024-09-27T12:35:50.066Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-27T12:35:50.960Z] GC before operation: completed in 1031.121 ms, heap usage 514.487 MB -> 108.356 MB. [2024-09-27T12:35:58.434Z] ====== log-regression (apache-spark) [default], iteration 18 completed (7083.857 ms) ====== [2024-09-27T12:35:58.434Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-27T12:35:58.434Z] GC before operation: completed in 917.904 ms, heap usage 512.449 MB -> 108.599 MB. [2024-09-27T12:36:06.028Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6697.427 ms) ====== [2024-09-27T12:36:07.806Z] ----------------------------------- [2024-09-27T12:36:07.806Z] renaissance-log-regression_0_PASSED [2024-09-27T12:36:07.806Z] ----------------------------------- [2024-09-27T12:36:07.806Z] [2024-09-27T12:36:07.806Z] TEST TEARDOWN: [2024-09-27T12:36:07.806Z] Nothing to be done for teardown. [2024-09-27T12:36:07.806Z] renaissance-log-regression_0 Finish Time: Fri Sep 27 12:36:07 2024 Epoch Time (ms): 1727440567307