[2024-11-27T21:29:46.109Z] Running test renaissance-log-regression_0 ... [2024-11-27T21:29:46.109Z] =============================================== [2024-11-27T21:29:46.109Z] renaissance-log-regression_0 Start Time: Wed Nov 27 21:29:45 2024 Epoch Time (ms): 1732742985101 [2024-11-27T21:29:46.109Z] variation: NoOptions [2024-11-27T21:29:46.109Z] JVM_OPTIONS: [2024-11-27T21:29:46.109Z] { \ [2024-11-27T21:29:46.109Z] echo ""; echo "TEST SETUP:"; \ [2024-11-27T21:29:46.109Z] echo "Nothing to be done for setup."; \ [2024-11-27T21:29:46.109Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17327423034453/renaissance-log-regression_0"; \ [2024-11-27T21:29:46.109Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17327423034453/renaissance-log-regression_0"; \ [2024-11-27T21:29:46.109Z] echo ""; echo "TESTING:"; \ [2024-11-27T21:29:46.109Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17327423034453/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-27T21:29:46.109Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17327423034453/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-27T21:29:46.109Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-27T21:29:46.109Z] echo "Nothing to be done for teardown."; \ [2024-11-27T21:29:46.109Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17327423034453/TestTargetResult"; [2024-11-27T21:29:46.109Z] [2024-11-27T21:29:46.109Z] TEST SETUP: [2024-11-27T21:29:46.109Z] Nothing to be done for setup. [2024-11-27T21:29:46.109Z] [2024-11-27T21:29:46.109Z] TESTING: [2024-11-27T21:29:49.090Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-27T21:29:50.029Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-27T21:29:53.009Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-27T21:29:53.009Z] GC before operation: completed in 53.686 ms, heap usage 70.491 MB -> 35.025 MB. [2024-11-27T21:29:59.676Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6281.570 ms) ====== [2024-11-27T21:29:59.676Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-27T21:29:59.676Z] GC before operation: completed in 67.772 ms, heap usage 294.312 MB -> 113.230 MB. [2024-11-27T21:30:00.628Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1729.662 ms) ====== [2024-11-27T21:30:00.628Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-27T21:30:00.628Z] GC before operation: completed in 51.533 ms, heap usage 277.555 MB -> 113.854 MB. [2024-11-27T21:30:02.572Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1551.345 ms) ====== [2024-11-27T21:30:02.572Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-27T21:30:02.572Z] GC before operation: completed in 59.895 ms, heap usage 431.427 MB -> 114.627 MB. [2024-11-27T21:30:04.502Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1397.133 ms) ====== [2024-11-27T21:30:04.502Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-27T21:30:04.502Z] GC before operation: completed in 79.097 ms, heap usage 417.304 MB -> 114.750 MB. [2024-11-27T21:30:05.442Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1384.312 ms) ====== [2024-11-27T21:30:05.442Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-27T21:30:05.442Z] GC before operation: completed in 80.086 ms, heap usage 402.822 MB -> 114.898 MB. [2024-11-27T21:30:07.371Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1355.727 ms) ====== [2024-11-27T21:30:07.371Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-27T21:30:07.371Z] GC before operation: completed in 66.508 ms, heap usage 514.892 MB -> 115.509 MB. [2024-11-27T21:30:08.312Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1250.597 ms) ====== [2024-11-27T21:30:08.312Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-27T21:30:08.312Z] GC before operation: completed in 64.967 ms, heap usage 410.032 MB -> 115.402 MB. [2024-11-27T21:30:09.253Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1319.835 ms) ====== [2024-11-27T21:30:09.253Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-27T21:30:09.253Z] GC before operation: completed in 77.329 ms, heap usage 414.164 MB -> 115.608 MB. [2024-11-27T21:30:11.184Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1294.968 ms) ====== [2024-11-27T21:30:11.184Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-27T21:30:11.184Z] GC before operation: completed in 88.083 ms, heap usage 406.939 MB -> 115.834 MB. [2024-11-27T21:30:12.124Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1320.180 ms) ====== [2024-11-27T21:30:12.124Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-27T21:30:12.124Z] GC before operation: completed in 88.837 ms, heap usage 397.199 MB -> 116.011 MB. [2024-11-27T21:30:14.054Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1272.310 ms) ====== [2024-11-27T21:30:14.054Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-27T21:30:14.054Z] GC before operation: completed in 91.534 ms, heap usage 396.393 MB -> 116.241 MB. [2024-11-27T21:30:14.994Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1331.398 ms) ====== [2024-11-27T21:30:14.994Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-27T21:30:14.994Z] GC before operation: completed in 82.841 ms, heap usage 396.404 MB -> 116.416 MB. [2024-11-27T21:30:16.925Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1210.311 ms) ====== [2024-11-27T21:30:16.925Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-27T21:30:16.925Z] GC before operation: completed in 88.410 ms, heap usage 405.576 MB -> 116.630 MB. [2024-11-27T21:30:17.874Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1192.487 ms) ====== [2024-11-27T21:30:17.874Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-27T21:30:17.874Z] GC before operation: completed in 97.504 ms, heap usage 405.500 MB -> 116.871 MB. [2024-11-27T21:30:18.829Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1222.005 ms) ====== [2024-11-27T21:30:18.829Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-27T21:30:18.829Z] GC before operation: completed in 100.797 ms, heap usage 369.201 MB -> 116.910 MB. [2024-11-27T21:30:20.766Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1267.257 ms) ====== [2024-11-27T21:30:20.766Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-27T21:30:20.766Z] GC before operation: completed in 99.432 ms, heap usage 368.640 MB -> 117.151 MB. [2024-11-27T21:30:21.707Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1237.235 ms) ====== [2024-11-27T21:30:21.707Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-27T21:30:21.707Z] GC before operation: completed in 86.659 ms, heap usage 322.143 MB -> 117.209 MB. [2024-11-27T21:30:23.638Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1216.290 ms) ====== [2024-11-27T21:30:23.638Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-27T21:30:23.638Z] GC before operation: completed in 86.658 ms, heap usage 377.099 MB -> 117.566 MB. [2024-11-27T21:30:24.578Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1207.472 ms) ====== [2024-11-27T21:30:24.578Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-27T21:30:24.578Z] GC before operation: completed in 85.031 ms, heap usage 395.059 MB -> 117.828 MB. [2024-11-27T21:30:25.519Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1277.044 ms) ====== [2024-11-27T21:30:26.458Z] ----------------------------------- [2024-11-27T21:30:26.458Z] renaissance-log-regression_0_PASSED [2024-11-27T21:30:26.458Z] ----------------------------------- [2024-11-27T21:30:26.458Z] [2024-11-27T21:30:26.458Z] TEST TEARDOWN: [2024-11-27T21:30:26.458Z] Nothing to be done for teardown. [2024-11-27T21:30:26.458Z] renaissance-log-regression_0 Finish Time: Wed Nov 27 21:30:25 2024 Epoch Time (ms): 1732743025485