[2024-11-21T21:53:08.655Z] Running test renaissance-log-regression_0 ... [2024-11-21T21:53:08.655Z] =============================================== [2024-11-21T21:53:08.655Z] renaissance-log-regression_0 Start Time: Thu Nov 21 21:53:08 2024 Epoch Time (ms): 1732225988557 [2024-11-21T21:53:08.655Z] variation: NoOptions [2024-11-21T21:53:09.003Z] JVM_OPTIONS: [2024-11-21T21:53:09.003Z] { \ [2024-11-21T21:53:09.003Z] echo ""; echo "TEST SETUP:"; \ [2024-11-21T21:53:09.003Z] echo "Nothing to be done for setup."; \ [2024-11-21T21:53:09.003Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322248501064\\renaissance-log-regression_0"; \ [2024-11-21T21:53:09.003Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322248501064\\renaissance-log-regression_0"; \ [2024-11-21T21:53:09.003Z] echo ""; echo "TESTING:"; \ [2024-11-21T21:53:09.003Z] "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_17322248501064\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-21T21:53:09.003Z] 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_17322248501064\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-21T21:53:09.003Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-21T21:53:09.003Z] echo "Nothing to be done for teardown."; \ [2024-11-21T21:53:09.003Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322248501064\\TestTargetResult"; [2024-11-21T21:53:09.003Z] [2024-11-21T21:53:09.003Z] TEST SETUP: [2024-11-21T21:53:09.003Z] Nothing to be done for setup. [2024-11-21T21:53:09.003Z] [2024-11-21T21:53:09.003Z] TESTING: [2024-11-21T21:53:19.679Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-21T21:53:21.304Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-21T21:53:22.917Z] WARNING: An illegal reflective access operation has occurred [2024-11-21T21:53:22.917Z] 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_17322248501064/renaissance-log-regression_0/harness-215309-2028016889824971619/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-21T21:53:22.917Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-21T21:53:22.917Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-21T21:53:22.917Z] WARNING: All illegal access operations will be denied in a future release [2024-11-21T21:53:25.115Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-21T21:53:25.115Z] GC before operation: completed in 45.275 ms, heap usage 63.674 MB -> 34.269 MB. [2024-11-21T21:53:34.262Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7939.609 ms) ====== [2024-11-21T21:53:34.262Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-21T21:53:34.262Z] GC before operation: completed in 104.524 ms, heap usage 272.307 MB -> 112.461 MB. [2024-11-21T21:53:35.515Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2666.174 ms) ====== [2024-11-21T21:53:35.515Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-21T21:53:35.515Z] GC before operation: completed in 87.571 ms, heap usage 231.218 MB -> 112.938 MB. [2024-11-21T21:53:37.912Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1889.662 ms) ====== [2024-11-21T21:53:37.912Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-21T21:53:37.912Z] GC before operation: completed in 87.273 ms, heap usage 354.278 MB -> 113.584 MB. [2024-11-21T21:53:39.633Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1770.310 ms) ====== [2024-11-21T21:53:39.633Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-21T21:53:39.633Z] GC before operation: completed in 87.303 ms, heap usage 320.406 MB -> 113.716 MB. [2024-11-21T21:53:40.866Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1487.032 ms) ====== [2024-11-21T21:53:40.866Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-21T21:53:41.249Z] GC before operation: completed in 94.521 ms, heap usage 383.214 MB -> 114.073 MB. [2024-11-21T21:53:43.022Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1620.247 ms) ====== [2024-11-21T21:53:43.022Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-21T21:53:43.022Z] GC before operation: completed in 86.449 ms, heap usage 324.948 MB -> 114.174 MB. [2024-11-21T21:53:44.709Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1555.452 ms) ====== [2024-11-21T21:53:44.709Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-21T21:53:44.709Z] GC before operation: completed in 88.145 ms, heap usage 285.835 MB -> 114.272 MB. [2024-11-21T21:53:45.842Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1489.409 ms) ====== [2024-11-21T21:53:45.842Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-21T21:53:45.842Z] GC before operation: completed in 85.150 ms, heap usage 267.483 MB -> 114.445 MB. [2024-11-21T21:53:47.729Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1386.078 ms) ====== [2024-11-21T21:53:47.730Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-21T21:53:47.730Z] GC before operation: completed in 87.915 ms, heap usage 305.856 MB -> 114.736 MB. [2024-11-21T21:53:48.926Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1447.510 ms) ====== [2024-11-21T21:53:48.926Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-21T21:53:48.926Z] GC before operation: completed in 103.908 ms, heap usage 336.505 MB -> 114.977 MB. [2024-11-21T21:53:50.598Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1481.741 ms) ====== [2024-11-21T21:53:50.598Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-21T21:53:50.598Z] GC before operation: completed in 99.999 ms, heap usage 247.970 MB -> 114.951 MB. [2024-11-21T21:53:52.267Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1498.184 ms) ====== [2024-11-21T21:53:52.267Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-21T21:53:52.267Z] GC before operation: completed in 90.160 ms, heap usage 249.090 MB -> 115.145 MB. [2024-11-21T21:53:53.979Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1490.051 ms) ====== [2024-11-21T21:53:53.979Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-21T21:53:53.979Z] GC before operation: completed in 91.657 ms, heap usage 229.464 MB -> 115.288 MB. [2024-11-21T21:53:55.183Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1359.866 ms) ====== [2024-11-21T21:53:55.183Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-21T21:53:55.183Z] GC before operation: completed in 92.699 ms, heap usage 259.918 MB -> 115.524 MB. [2024-11-21T21:53:56.930Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1407.330 ms) ====== [2024-11-21T21:53:56.930Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-21T21:53:56.930Z] GC before operation: completed in 96.463 ms, heap usage 259.815 MB -> 115.708 MB. [2024-11-21T21:53:58.116Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1395.740 ms) ====== [2024-11-21T21:53:58.116Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-21T21:53:58.117Z] GC before operation: completed in 91.693 ms, heap usage 296.952 MB -> 116.030 MB. [2024-11-21T21:53:59.890Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1447.414 ms) ====== [2024-11-21T21:53:59.890Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-21T21:53:59.890Z] GC before operation: completed in 92.852 ms, heap usage 257.946 MB -> 116.103 MB. [2024-11-21T21:54:01.180Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1334.881 ms) ====== [2024-11-21T21:54:01.180Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-21T21:54:01.180Z] GC before operation: completed in 94.872 ms, heap usage 277.473 MB -> 116.339 MB. [2024-11-21T21:54:02.921Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1335.538 ms) ====== [2024-11-21T21:54:02.921Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-21T21:54:02.921Z] GC before operation: completed in 95.049 ms, heap usage 279.086 MB -> 116.532 MB. [2024-11-21T21:54:04.086Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1377.737 ms) ====== [2024-11-21T21:54:04.400Z] ----------------------------------- [2024-11-21T21:54:04.400Z] renaissance-log-regression_0_PASSED [2024-11-21T21:54:04.400Z] ----------------------------------- [2024-11-21T21:54:04.400Z] [2024-11-21T21:54:04.400Z] TEST TEARDOWN: [2024-11-21T21:54:04.400Z] Nothing to be done for teardown. [2024-11-21T21:54:04.714Z] renaissance-log-regression_0 Finish Time: Thu Nov 21 21:54:04 2024 Epoch Time (ms): 1732226044361