[2024-06-07T23:01:50.128Z] Running test renaissance-log-regression_0 ... [2024-06-07T23:01:50.128Z] =============================================== [2024-06-07T23:01:50.128Z] renaissance-log-regression_0 Start Time: Fri Jun 7 23:01:49 2024 Epoch Time (ms): 1717801309537 [2024-06-07T23:01:50.128Z] variation: NoOptions [2024-06-07T23:01:50.128Z] JVM_OPTIONS: [2024-06-07T23:01:50.128Z] { \ [2024-06-07T23:01:50.128Z] echo ""; echo "TEST SETUP:"; \ [2024-06-07T23:01:50.128Z] echo "Nothing to be done for setup."; \ [2024-06-07T23:01:50.128Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17178005333360/renaissance-log-regression_0"; \ [2024-06-07T23:01:50.128Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17178005333360/renaissance-log-regression_0"; \ [2024-06-07T23:01:50.128Z] echo ""; echo "TESTING:"; \ [2024-06-07T23:01:50.128Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17178005333360/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-06-07T23:01:50.129Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17178005333360/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-07T23:01:50.129Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-07T23:01:50.129Z] echo "Nothing to be done for teardown."; \ [2024-06-07T23:01:50.129Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17178005333360/TestTargetResult"; [2024-06-07T23:01:50.129Z] [2024-06-07T23:01:50.129Z] TEST SETUP: [2024-06-07T23:01:50.129Z] Nothing to be done for setup. [2024-06-07T23:01:50.129Z] [2024-06-07T23:01:50.129Z] TESTING: [2024-06-07T23:01:53.097Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-07T23:01:55.014Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-06-07T23:01:56.959Z] WARNING: An illegal reflective access operation has occurred [2024-06-07T23:01:56.959Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/output_17178005333360/renaissance-log-regression_0/harness-230150-6318812383084499552/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-06-07T23:01:56.959Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-06-07T23:01:56.959Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-06-07T23:01:56.959Z] WARNING: All illegal access operations will be denied in a future release [2024-06-07T23:01:58.876Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-06-07T23:01:58.877Z] GC before operation: completed in 61.440 ms, heap usage 43.804 MB -> 33.762 MB. [2024-06-07T23:02:06.890Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7804.098 ms) ====== [2024-06-07T23:02:06.890Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-06-07T23:02:06.890Z] GC before operation: completed in 104.355 ms, heap usage 283.336 MB -> 111.958 MB. [2024-06-07T23:02:09.855Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2273.405 ms) ====== [2024-06-07T23:02:09.855Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-06-07T23:02:09.855Z] GC before operation: completed in 90.339 ms, heap usage 258.250 MB -> 112.451 MB. [2024-06-07T23:02:11.773Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2012.518 ms) ====== [2024-06-07T23:02:11.773Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-06-07T23:02:11.773Z] GC before operation: completed in 80.382 ms, heap usage 260.412 MB -> 112.882 MB. [2024-06-07T23:02:13.696Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1940.481 ms) ====== [2024-06-07T23:02:13.696Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-06-07T23:02:13.696Z] GC before operation: completed in 82.017 ms, heap usage 278.356 MB -> 113.148 MB. [2024-06-07T23:02:15.615Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1860.188 ms) ====== [2024-06-07T23:02:15.615Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-06-07T23:02:15.615Z] GC before operation: completed in 88.697 ms, heap usage 240.394 MB -> 113.288 MB. [2024-06-07T23:02:17.532Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1700.034 ms) ====== [2024-06-07T23:02:17.532Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-06-07T23:02:17.532Z] GC before operation: completed in 90.148 ms, heap usage 338.517 MB -> 113.703 MB. [2024-06-07T23:02:19.452Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1669.761 ms) ====== [2024-06-07T23:02:19.452Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-06-07T23:02:19.452Z] GC before operation: completed in 87.885 ms, heap usage 285.669 MB -> 113.803 MB. [2024-06-07T23:02:20.391Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1663.671 ms) ====== [2024-06-07T23:02:20.391Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-06-07T23:02:20.391Z] GC before operation: completed in 86.247 ms, heap usage 258.469 MB -> 113.942 MB. [2024-06-07T23:02:22.310Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1687.009 ms) ====== [2024-06-07T23:02:22.310Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-06-07T23:02:22.310Z] GC before operation: completed in 101.367 ms, heap usage 295.694 MB -> 114.229 MB. [2024-06-07T23:02:24.231Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1658.295 ms) ====== [2024-06-07T23:02:24.231Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-06-07T23:02:24.231Z] GC before operation: completed in 91.737 ms, heap usage 296.477 MB -> 114.385 MB. [2024-06-07T23:02:26.149Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1635.159 ms) ====== [2024-06-07T23:02:26.149Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-06-07T23:02:26.149Z] GC before operation: completed in 93.305 ms, heap usage 305.112 MB -> 114.619 MB. [2024-06-07T23:02:28.066Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1614.690 ms) ====== [2024-06-07T23:02:28.066Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-06-07T23:02:28.066Z] GC before operation: completed in 94.313 ms, heap usage 249.194 MB -> 114.678 MB. [2024-06-07T23:02:29.004Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1546.792 ms) ====== [2024-06-07T23:02:29.004Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-06-07T23:02:29.004Z] GC before operation: completed in 92.237 ms, heap usage 314.953 MB -> 115.288 MB. [2024-06-07T23:02:30.928Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1602.502 ms) ====== [2024-06-07T23:02:30.928Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-06-07T23:02:30.928Z] GC before operation: completed in 94.178 ms, heap usage 324.100 MB -> 115.237 MB. [2024-06-07T23:02:32.850Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1592.173 ms) ====== [2024-06-07T23:02:32.850Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-06-07T23:02:32.850Z] GC before operation: completed in 92.830 ms, heap usage 267.840 MB -> 115.289 MB. [2024-06-07T23:02:34.780Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1524.732 ms) ====== [2024-06-07T23:02:34.780Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-06-07T23:02:34.780Z] GC before operation: completed in 99.858 ms, heap usage 267.408 MB -> 115.479 MB. [2024-06-07T23:02:35.715Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1526.424 ms) ====== [2024-06-07T23:02:35.715Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-06-07T23:02:35.715Z] GC before operation: completed in 96.961 ms, heap usage 296.957 MB -> 115.763 MB. [2024-06-07T23:02:37.633Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1527.567 ms) ====== [2024-06-07T23:02:37.633Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-06-07T23:02:37.633Z] GC before operation: completed in 98.181 ms, heap usage 230.606 MB -> 115.812 MB. [2024-06-07T23:02:39.553Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1616.671 ms) ====== [2024-06-07T23:02:39.553Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-06-07T23:02:39.553Z] GC before operation: completed in 96.298 ms, heap usage 316.967 MB -> 116.185 MB. [2024-06-07T23:02:41.471Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1588.571 ms) ====== [2024-06-07T23:02:41.472Z] ----------------------------------- [2024-06-07T23:02:41.472Z] renaissance-log-regression_0_PASSED [2024-06-07T23:02:41.472Z] ----------------------------------- [2024-06-07T23:02:41.472Z] [2024-06-07T23:02:41.472Z] TEST TEARDOWN: [2024-06-07T23:02:41.472Z] Nothing to be done for teardown. [2024-06-07T23:02:41.472Z] renaissance-log-regression_0 Finish Time: Fri Jun 7 23:02:40 2024 Epoch Time (ms): 1717801360696