[2024-10-03T08:29:14.865Z] Running test renaissance-log-regression_0 ... [2024-10-03T08:29:14.865Z] =============================================== [2024-10-03T08:29:14.865Z] renaissance-log-regression_0 Start Time: Thu Oct 3 08:29:14 2024 Epoch Time (ms): 1727944154823 [2024-10-03T08:29:15.185Z] variation: NoOptions [2024-10-03T08:29:15.185Z] JVM_OPTIONS: [2024-10-03T08:29:15.185Z] { \ [2024-10-03T08:29:15.185Z] echo ""; echo "TEST SETUP:"; \ [2024-10-03T08:29:15.185Z] echo "Nothing to be done for setup."; \ [2024-10-03T08:29:15.185Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\renaissance-log-regression_0"; \ [2024-10-03T08:29:15.185Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\renaissance-log-regression_0"; \ [2024-10-03T08:29:15.186Z] echo ""; echo "TESTING:"; \ [2024-10-03T08:29:15.186Z] "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_17279429786926\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-10-03T08:29:15.186Z] 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_17279429786926\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-03T08:29:15.186Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-03T08:29:15.186Z] echo "Nothing to be done for teardown."; \ [2024-10-03T08:29:15.186Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\TestTargetResult"; [2024-10-03T08:29:15.186Z] [2024-10-03T08:29:15.186Z] TEST SETUP: [2024-10-03T08:29:15.186Z] Nothing to be done for setup. [2024-10-03T08:29:15.186Z] [2024-10-03T08:29:15.186Z] TESTING: [2024-10-03T08:29:25.842Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-03T08:29:27.444Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-03T08:29:28.546Z] WARNING: An illegal reflective access operation has occurred [2024-10-03T08:29:28.546Z] 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_17279429786926/renaissance-log-regression_0/harness-082915-3380202706526333062/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-10-03T08:29:28.546Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-10-03T08:29:28.546Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-10-03T08:29:28.546Z] WARNING: All illegal access operations will be denied in a future release [2024-10-03T08:29:30.725Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-10-03T08:29:30.725Z] GC before operation: completed in 47.948 ms, heap usage 82.594 MB -> 34.272 MB. [2024-10-03T08:29:39.783Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7870.013 ms) ====== [2024-10-03T08:29:39.783Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-10-03T08:29:39.783Z] GC before operation: completed in 127.471 ms, heap usage 327.465 MB -> 112.564 MB. [2024-10-03T08:29:42.188Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3115.618 ms) ====== [2024-10-03T08:29:42.188Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-10-03T08:29:42.188Z] GC before operation: completed in 81.201 ms, heap usage 239.565 MB -> 112.948 MB. [2024-10-03T08:29:44.550Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2111.521 ms) ====== [2024-10-03T08:29:44.550Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-10-03T08:29:44.550Z] GC before operation: completed in 79.101 ms, heap usage 277.248 MB -> 113.401 MB. [2024-10-03T08:29:45.707Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1645.473 ms) ====== [2024-10-03T08:29:45.707Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-10-03T08:29:46.181Z] GC before operation: completed in 94.462 ms, heap usage 231.344 MB -> 113.530 MB. [2024-10-03T08:29:47.344Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1482.251 ms) ====== [2024-10-03T08:29:47.344Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-10-03T08:29:47.344Z] GC before operation: completed in 99.639 ms, heap usage 316.122 MB -> 113.930 MB. [2024-10-03T08:29:49.012Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1364.009 ms) ====== [2024-10-03T08:29:49.012Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-10-03T08:29:49.012Z] GC before operation: completed in 82.292 ms, heap usage 390.591 MB -> 114.303 MB. [2024-10-03T08:29:50.776Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1539.916 ms) ====== [2024-10-03T08:29:50.776Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-10-03T08:29:50.776Z] GC before operation: completed in 96.129 ms, heap usage 285.335 MB -> 114.266 MB. [2024-10-03T08:29:52.007Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1363.237 ms) ====== [2024-10-03T08:29:52.007Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-10-03T08:29:52.007Z] GC before operation: completed in 90.372 ms, heap usage 360.489 MB -> 114.648 MB. [2024-10-03T08:29:53.805Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1323.649 ms) ====== [2024-10-03T08:29:53.805Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-10-03T08:29:53.805Z] GC before operation: completed in 92.844 ms, heap usage 350.433 MB -> 114.774 MB. [2024-10-03T08:29:54.985Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1564.561 ms) ====== [2024-10-03T08:29:54.985Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-10-03T08:29:54.985Z] GC before operation: completed in 98.507 ms, heap usage 319.989 MB -> 114.922 MB. [2024-10-03T08:29:56.882Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1407.593 ms) ====== [2024-10-03T08:29:56.882Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-10-03T08:29:56.882Z] GC before operation: completed in 90.471 ms, heap usage 385.457 MB -> 115.276 MB. [2024-10-03T08:29:58.076Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1391.442 ms) ====== [2024-10-03T08:29:58.076Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-10-03T08:29:58.076Z] GC before operation: completed in 92.871 ms, heap usage 409.095 MB -> 115.566 MB. [2024-10-03T08:29:59.488Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1475.711 ms) ====== [2024-10-03T08:29:59.488Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-10-03T08:29:59.864Z] GC before operation: completed in 84.777 ms, heap usage 239.324 MB -> 115.324 MB. [2024-10-03T08:30:01.075Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1343.617 ms) ====== [2024-10-03T08:30:01.075Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-10-03T08:30:01.075Z] GC before operation: completed in 89.585 ms, heap usage 283.630 MB -> 115.648 MB. [2024-10-03T08:30:02.882Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1351.688 ms) ====== [2024-10-03T08:30:02.882Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-10-03T08:30:02.882Z] GC before operation: completed in 105.805 ms, heap usage 283.167 MB -> 115.791 MB. [2024-10-03T08:30:04.156Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1546.734 ms) ====== [2024-10-03T08:30:04.156Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-10-03T08:30:04.156Z] GC before operation: completed in 104.040 ms, heap usage 240.627 MB -> 115.886 MB. [2024-10-03T08:30:05.318Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1220.537 ms) ====== [2024-10-03T08:30:05.318Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-10-03T08:30:05.772Z] GC before operation: completed in 112.241 ms, heap usage 317.097 MB -> 116.321 MB. [2024-10-03T08:30:07.016Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1372.475 ms) ====== [2024-10-03T08:30:07.016Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-10-03T08:30:07.016Z] GC before operation: completed in 95.981 ms, heap usage 293.338 MB -> 116.421 MB. [2024-10-03T08:30:08.173Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1252.755 ms) ====== [2024-10-03T08:30:08.173Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-10-03T08:30:08.670Z] GC before operation: completed in 108.034 ms, heap usage 247.099 MB -> 116.521 MB. [2024-10-03T08:30:09.804Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1437.980 ms) ====== [2024-10-03T08:30:10.121Z] ----------------------------------- [2024-10-03T08:30:10.121Z] renaissance-log-regression_0_PASSED [2024-10-03T08:30:10.121Z] ----------------------------------- [2024-10-03T08:30:10.121Z] [2024-10-03T08:30:10.121Z] TEST TEARDOWN: [2024-10-03T08:30:10.121Z] Nothing to be done for teardown. [2024-10-03T08:30:10.442Z] renaissance-log-regression_0 Finish Time: Thu Oct 3 08:30:10 2024 Epoch Time (ms): 1727944210262