[2024-08-10T10:04:58.129Z] Running test renaissance-log-regression_0 ... [2024-08-10T10:04:58.445Z] =============================================== [2024-08-10T10:04:58.445Z] renaissance-log-regression_0 Start Time: Sat Aug 10 10:04:58 2024 Epoch Time (ms): 1723284298310 [2024-08-10T10:04:58.761Z] variation: NoOptions [2024-08-10T10:04:58.761Z] JVM_OPTIONS: [2024-08-10T10:04:58.761Z] { \ [2024-08-10T10:04:58.761Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T10:04:58.761Z] echo "Nothing to be done for setup."; \ [2024-08-10T10:04:58.761Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232831628287\\renaissance-log-regression_0"; \ [2024-08-10T10:04:58.761Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232831628287\\renaissance-log-regression_0"; \ [2024-08-10T10:04:58.761Z] echo ""; echo "TESTING:"; \ [2024-08-10T10:04:58.761Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232831628287\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-08-10T10:04:58.761Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232831628287\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T10:04:58.761Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T10:04:58.761Z] echo "Nothing to be done for teardown."; \ [2024-08-10T10:04:58.761Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232831628287\\TestTargetResult"; [2024-08-10T10:04:59.075Z] [2024-08-10T10:04:59.075Z] TEST SETUP: [2024-08-10T10:04:59.075Z] Nothing to be done for setup. [2024-08-10T10:04:59.075Z] [2024-08-10T10:04:59.075Z] TESTING: [2024-08-10T10:05:09.612Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T10:05:11.195Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-10T10:05:12.774Z] WARNING: An illegal reflective access operation has occurred [2024-08-10T10:05:12.774Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/TKG/output_17232831628287/renaissance-log-regression_0/harness-100459-15830567068666738412/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-10T10:05:12.774Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-10T10:05:12.774Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-10T10:05:12.774Z] WARNING: All illegal access operations will be denied in a future release [2024-08-10T10:05:14.928Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-10T10:05:14.928Z] GC before operation: completed in 45.696 ms, heap usage 65.346 MB -> 34.283 MB. [2024-08-10T10:05:22.173Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7616.433 ms) ====== [2024-08-10T10:05:22.173Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-10T10:05:22.607Z] GC before operation: completed in 108.440 ms, heap usage 325.831 MB -> 112.574 MB. [2024-08-10T10:05:25.048Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2508.875 ms) ====== [2024-08-10T10:05:25.048Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-10T10:05:25.048Z] GC before operation: completed in 114.392 ms, heap usage 318.376 MB -> 113.082 MB. [2024-08-10T10:05:27.441Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1958.446 ms) ====== [2024-08-10T10:05:27.441Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-10T10:05:27.441Z] GC before operation: completed in 85.772 ms, heap usage 298.821 MB -> 113.440 MB. [2024-08-10T10:05:29.053Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1909.401 ms) ====== [2024-08-10T10:05:29.053Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-10T10:05:29.053Z] GC before operation: completed in 79.957 ms, heap usage 280.667 MB -> 113.653 MB. [2024-08-10T10:05:30.798Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1632.018 ms) ====== [2024-08-10T10:05:30.798Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-10T10:05:30.798Z] GC before operation: completed in 78.673 ms, heap usage 296.189 MB -> 113.885 MB. [2024-08-10T10:05:32.422Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1601.842 ms) ====== [2024-08-10T10:05:32.423Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-10T10:05:32.423Z] GC before operation: completed in 84.023 ms, heap usage 277.222 MB -> 114.043 MB. [2024-08-10T10:05:34.177Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1478.629 ms) ====== [2024-08-10T10:05:34.177Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-10T10:05:34.177Z] GC before operation: completed in 85.583 ms, heap usage 316.222 MB -> 114.322 MB. [2024-08-10T10:05:35.330Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1445.284 ms) ====== [2024-08-10T10:05:35.330Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-10T10:05:35.778Z] GC before operation: completed in 92.840 ms, heap usage 306.487 MB -> 114.538 MB. [2024-08-10T10:05:36.894Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1433.658 ms) ====== [2024-08-10T10:05:36.894Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-10T10:05:36.894Z] GC before operation: completed in 87.638 ms, heap usage 324.372 MB -> 114.781 MB. [2024-08-10T10:05:38.621Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1525.865 ms) ====== [2024-08-10T10:05:38.622Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-10T10:05:38.622Z] GC before operation: completed in 85.954 ms, heap usage 248.067 MB -> 114.805 MB. [2024-08-10T10:05:40.242Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1450.115 ms) ====== [2024-08-10T10:05:40.242Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-10T10:05:40.242Z] GC before operation: completed in 96.014 ms, heap usage 239.131 MB -> 114.907 MB. [2024-08-10T10:05:41.847Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1518.042 ms) ====== [2024-08-10T10:05:41.847Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-10T10:05:41.847Z] GC before operation: completed in 90.040 ms, heap usage 249.325 MB -> 115.147 MB. [2024-08-10T10:05:43.460Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1441.103 ms) ====== [2024-08-10T10:05:43.460Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-10T10:05:43.460Z] GC before operation: completed in 91.934 ms, heap usage 238.673 MB -> 115.292 MB. [2024-08-10T10:05:44.630Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1376.782 ms) ====== [2024-08-10T10:05:44.630Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-10T10:05:45.086Z] GC before operation: completed in 102.748 ms, heap usage 304.850 MB -> 115.660 MB. [2024-08-10T10:05:46.265Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1389.471 ms) ====== [2024-08-10T10:05:46.265Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-10T10:05:46.265Z] GC before operation: completed in 93.644 ms, heap usage 324.156 MB -> 115.893 MB. [2024-08-10T10:05:47.951Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1356.303 ms) ====== [2024-08-10T10:05:47.951Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-10T10:05:47.951Z] GC before operation: completed in 89.680 ms, heap usage 297.217 MB -> 116.034 MB. [2024-08-10T10:05:49.134Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1288.178 ms) ====== [2024-08-10T10:05:49.134Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-10T10:05:49.134Z] GC before operation: completed in 97.869 ms, heap usage 297.649 MB -> 116.196 MB. [2024-08-10T10:05:50.261Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1224.194 ms) ====== [2024-08-10T10:05:50.261Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-10T10:05:50.623Z] GC before operation: completed in 109.873 ms, heap usage 382.241 MB -> 116.625 MB. [2024-08-10T10:05:51.833Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1383.157 ms) ====== [2024-08-10T10:05:51.833Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-10T10:05:51.833Z] GC before operation: completed in 93.046 ms, heap usage 279.012 MB -> 116.538 MB. [2024-08-10T10:05:53.543Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1306.106 ms) ====== [2024-08-10T10:05:53.543Z] ----------------------------------- [2024-08-10T10:05:53.543Z] renaissance-log-regression_0_PASSED [2024-08-10T10:05:53.543Z] ----------------------------------- [2024-08-10T10:05:53.543Z] [2024-08-10T10:05:53.543Z] TEST TEARDOWN: [2024-08-10T10:05:53.543Z] Nothing to be done for teardown. [2024-08-10T10:05:53.847Z] renaissance-log-regression_0 Finish Time: Sat Aug 10 10:05:53 2024 Epoch Time (ms): 1723284353680