[2024-11-25T04:47:14.742Z] Running test renaissance-log-regression_0 ... [2024-11-25T04:47:14.743Z] =============================================== [2024-11-25T04:47:14.743Z] renaissance-log-regression_0 Start Time: Mon Nov 25 04:47:14 2024 Epoch Time (ms): 1732510034546 [2024-11-25T04:47:14.743Z] variation: NoOptions [2024-11-25T04:47:14.743Z] JVM_OPTIONS: [2024-11-25T04:47:14.743Z] { \ [2024-11-25T04:47:14.743Z] echo ""; echo "TEST SETUP:"; \ [2024-11-25T04:47:14.743Z] echo "Nothing to be done for setup."; \ [2024-11-25T04:47:14.743Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17325089071198\\renaissance-log-regression_0"; \ [2024-11-25T04:47:14.743Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17325089071198\\renaissance-log-regression_0"; \ [2024-11-25T04:47:14.743Z] echo ""; echo "TESTING:"; \ [2024-11-25T04:47:14.743Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17325089071198\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-25T04:47:14.743Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17325089071198\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-25T04:47:14.743Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-25T04:47:14.743Z] echo "Nothing to be done for teardown."; \ [2024-11-25T04:47:14.743Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17325089071198\\TestTargetResult"; [2024-11-25T04:47:15.072Z] [2024-11-25T04:47:15.072Z] TEST SETUP: [2024-11-25T04:47:15.072Z] Nothing to be done for setup. [2024-11-25T04:47:15.072Z] [2024-11-25T04:47:15.072Z] TESTING: [2024-11-25T04:47:25.758Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-25T04:47:26.877Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-25T04:47:28.498Z] WARNING: An illegal reflective access operation has occurred [2024-11-25T04:47:28.498Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17325089071198/renaissance-log-regression_0/harness-044715-11663302022418122229/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-25T04:47:28.498Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-25T04:47:28.498Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-25T04:47:28.498Z] WARNING: All illegal access operations will be denied in a future release [2024-11-25T04:47:30.128Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-25T04:47:30.445Z] GC before operation: completed in 50.737 ms, heap usage 78.187 MB -> 34.270 MB. [2024-11-25T04:47:39.463Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7935.511 ms) ====== [2024-11-25T04:47:39.463Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-25T04:47:39.463Z] GC before operation: completed in 81.493 ms, heap usage 252.964 MB -> 112.382 MB. [2024-11-25T04:47:41.265Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2844.326 ms) ====== [2024-11-25T04:47:41.265Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-25T04:47:41.265Z] GC before operation: completed in 102.431 ms, heap usage 239.910 MB -> 112.913 MB. [2024-11-25T04:47:43.803Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2151.587 ms) ====== [2024-11-25T04:47:43.803Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-25T04:47:43.803Z] GC before operation: completed in 78.211 ms, heap usage 251.527 MB -> 113.360 MB. [2024-11-25T04:47:45.698Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1762.243 ms) ====== [2024-11-25T04:47:45.698Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-25T04:47:45.698Z] GC before operation: completed in 83.430 ms, heap usage 241.533 MB -> 113.552 MB. [2024-11-25T04:47:46.850Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1565.251 ms) ====== [2024-11-25T04:47:46.850Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-25T04:47:47.229Z] GC before operation: completed in 91.695 ms, heap usage 326.875 MB -> 113.995 MB. [2024-11-25T04:47:48.376Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1364.609 ms) ====== [2024-11-25T04:47:48.376Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-25T04:47:48.376Z] GC before operation: completed in 85.802 ms, heap usage 384.680 MB -> 114.288 MB. [2024-11-25T04:47:50.266Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1695.055 ms) ====== [2024-11-25T04:47:50.266Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-25T04:47:50.266Z] GC before operation: completed in 88.709 ms, heap usage 281.177 MB -> 114.255 MB. [2024-11-25T04:47:51.982Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1420.753 ms) ====== [2024-11-25T04:47:51.982Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-25T04:47:51.982Z] GC before operation: completed in 91.689 ms, heap usage 386.526 MB -> 114.748 MB. [2024-11-25T04:47:53.223Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1407.095 ms) ====== [2024-11-25T04:47:53.223Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-25T04:47:53.223Z] GC before operation: completed in 85.256 ms, heap usage 248.963 MB -> 114.633 MB. [2024-11-25T04:47:54.943Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1457.898 ms) ====== [2024-11-25T04:47:54.943Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-25T04:47:54.943Z] GC before operation: completed in 102.350 ms, heap usage 238.827 MB -> 114.742 MB. [2024-11-25T04:47:56.160Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1391.162 ms) ====== [2024-11-25T04:47:56.161Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-25T04:47:56.161Z] GC before operation: completed in 92.516 ms, heap usage 315.507 MB -> 115.114 MB. [2024-11-25T04:47:57.862Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1379.084 ms) ====== [2024-11-25T04:47:57.862Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-25T04:47:57.862Z] GC before operation: completed in 94.201 ms, heap usage 306.215 MB -> 115.308 MB. [2024-11-25T04:47:59.034Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1330.335 ms) ====== [2024-11-25T04:47:59.034Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-25T04:47:59.426Z] GC before operation: completed in 94.930 ms, heap usage 285.926 MB -> 115.450 MB. [2024-11-25T04:48:00.701Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1223.616 ms) ====== [2024-11-25T04:48:00.701Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-25T04:48:00.701Z] GC before operation: completed in 101.129 ms, heap usage 259.446 MB -> 115.548 MB. [2024-11-25T04:48:01.856Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1379.504 ms) ====== [2024-11-25T04:48:01.856Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-25T04:48:01.856Z] GC before operation: completed in 94.459 ms, heap usage 248.686 MB -> 115.732 MB. [2024-11-25T04:48:03.698Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1346.696 ms) ====== [2024-11-25T04:48:03.698Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-25T04:48:03.698Z] GC before operation: completed in 95.009 ms, heap usage 298.074 MB -> 116.056 MB. [2024-11-25T04:48:04.881Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1508.633 ms) ====== [2024-11-25T04:48:04.882Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-25T04:48:05.307Z] GC before operation: completed in 91.814 ms, heap usage 249.392 MB -> 116.125 MB. [2024-11-25T04:48:06.544Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1456.082 ms) ====== [2024-11-25T04:48:06.544Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-25T04:48:06.544Z] GC before operation: completed in 97.741 ms, heap usage 304.473 MB -> 116.453 MB. [2024-11-25T04:48:08.358Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1412.864 ms) ====== [2024-11-25T04:48:08.358Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-25T04:48:08.358Z] GC before operation: completed in 104.860 ms, heap usage 267.958 MB -> 116.559 MB. [2024-11-25T04:48:09.496Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1439.745 ms) ====== [2024-11-25T04:48:09.823Z] ----------------------------------- [2024-11-25T04:48:09.823Z] renaissance-log-regression_0_PASSED [2024-11-25T04:48:09.823Z] ----------------------------------- [2024-11-25T04:48:10.141Z] [2024-11-25T04:48:10.141Z] TEST TEARDOWN: [2024-11-25T04:48:10.141Z] Nothing to be done for teardown. [2024-11-25T04:48:10.141Z] renaissance-log-regression_0 Finish Time: Mon Nov 25 04:48:10 2024 Epoch Time (ms): 1732510090029