[2024-11-14T00:32:46.459Z] Running test renaissance-log-regression_0 ... [2024-11-14T00:32:46.459Z] =============================================== [2024-11-14T00:32:46.459Z] renaissance-log-regression_0 Start Time: Thu Nov 14 00:32:45 2024 Epoch Time (ms): 1731544365506 [2024-11-14T00:32:46.459Z] variation: NoOptions [2024-11-14T00:32:46.459Z] JVM_OPTIONS: [2024-11-14T00:32:46.459Z] { \ [2024-11-14T00:32:46.459Z] echo ""; echo "TEST SETUP:"; \ [2024-11-14T00:32:46.459Z] echo "Nothing to be done for setup."; \ [2024-11-14T00:32:46.459Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17315435913636/renaissance-log-regression_0"; \ [2024-11-14T00:32:46.459Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17315435913636/renaissance-log-regression_0"; \ [2024-11-14T00:32:46.459Z] echo ""; echo "TESTING:"; \ [2024-11-14T00:32:46.460Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17315435913636/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-14T00:32:46.460Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17315435913636/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-14T00:32:46.460Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-14T00:32:46.460Z] echo "Nothing to be done for teardown."; \ [2024-11-14T00:32:46.460Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17315435913636/TestTargetResult"; [2024-11-14T00:32:46.460Z] [2024-11-14T00:32:46.460Z] TEST SETUP: [2024-11-14T00:32:46.460Z] Nothing to be done for setup. [2024-11-14T00:32:46.460Z] [2024-11-14T00:32:46.460Z] TESTING: [2024-11-14T00:32:49.429Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-14T00:32:51.350Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-14T00:32:52.285Z] WARNING: An illegal reflective access operation has occurred [2024-11-14T00:32:52.285Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17315435913636/renaissance-log-regression_0/harness-003246-4710500086840332338/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-14T00:32:52.285Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-14T00:32:52.285Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-14T00:32:52.285Z] WARNING: All illegal access operations will be denied in a future release [2024-11-14T00:32:55.250Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-14T00:32:55.250Z] GC before operation: completed in 62.454 ms, heap usage 52.392 MB -> 33.772 MB. [2024-11-14T00:33:03.276Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8128.690 ms) ====== [2024-11-14T00:33:03.276Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-14T00:33:03.276Z] GC before operation: completed in 86.871 ms, heap usage 309.012 MB -> 112.009 MB. [2024-11-14T00:33:06.248Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2361.173 ms) ====== [2024-11-14T00:33:06.248Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-14T00:33:06.248Z] GC before operation: completed in 91.785 ms, heap usage 315.308 MB -> 112.560 MB. [2024-11-14T00:33:08.169Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2157.278 ms) ====== [2024-11-14T00:33:08.169Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-14T00:33:08.169Z] GC before operation: completed in 90.424 ms, heap usage 238.336 MB -> 112.800 MB. [2024-11-14T00:33:10.113Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1920.595 ms) ====== [2024-11-14T00:33:10.113Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-14T00:33:10.113Z] GC before operation: completed in 86.184 ms, heap usage 277.236 MB -> 113.112 MB. [2024-11-14T00:33:12.033Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1795.860 ms) ====== [2024-11-14T00:33:12.033Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-14T00:33:12.033Z] GC before operation: completed in 87.225 ms, heap usage 239.511 MB -> 113.243 MB. [2024-11-14T00:33:13.953Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1866.847 ms) ====== [2024-11-14T00:33:13.954Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-14T00:33:13.954Z] GC before operation: completed in 92.092 ms, heap usage 335.871 MB -> 113.663 MB. [2024-11-14T00:33:15.875Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1891.523 ms) ====== [2024-11-14T00:33:15.875Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-14T00:33:15.875Z] GC before operation: completed in 100.062 ms, heap usage 229.336 MB -> 113.659 MB. [2024-11-14T00:33:17.799Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1822.917 ms) ====== [2024-11-14T00:33:17.799Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-14T00:33:17.799Z] GC before operation: completed in 88.985 ms, heap usage 306.759 MB -> 114.022 MB. [2024-11-14T00:33:19.718Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1641.722 ms) ====== [2024-11-14T00:33:19.718Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-14T00:33:19.718Z] GC before operation: completed in 90.692 ms, heap usage 286.370 MB -> 114.190 MB. [2024-11-14T00:33:21.637Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1663.693 ms) ====== [2024-11-14T00:33:21.637Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-14T00:33:21.637Z] GC before operation: completed in 98.911 ms, heap usage 355.315 MB -> 114.522 MB. [2024-11-14T00:33:23.559Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1718.667 ms) ====== [2024-11-14T00:33:23.559Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-14T00:33:23.559Z] GC before operation: completed in 110.447 ms, heap usage 295.955 MB -> 114.573 MB. [2024-11-14T00:33:24.505Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1702.701 ms) ====== [2024-11-14T00:33:24.505Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-14T00:33:24.505Z] GC before operation: completed in 96.999 ms, heap usage 334.835 MB -> 114.848 MB. [2024-11-14T00:33:26.434Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1678.786 ms) ====== [2024-11-14T00:33:26.434Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-14T00:33:26.434Z] GC before operation: completed in 100.811 ms, heap usage 295.905 MB -> 114.948 MB. [2024-11-14T00:33:28.357Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1691.967 ms) ====== [2024-11-14T00:33:28.357Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-14T00:33:28.357Z] GC before operation: completed in 94.487 ms, heap usage 258.524 MB -> 115.006 MB. [2024-11-14T00:33:30.279Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1651.052 ms) ====== [2024-11-14T00:33:30.279Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-14T00:33:30.279Z] GC before operation: completed in 102.284 ms, heap usage 267.282 MB -> 115.242 MB. [2024-11-14T00:33:32.201Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1773.757 ms) ====== [2024-11-14T00:33:32.201Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-14T00:33:32.201Z] GC before operation: completed in 98.885 ms, heap usage 278.284 MB -> 115.475 MB. [2024-11-14T00:33:34.123Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1740.118 ms) ====== [2024-11-14T00:33:34.123Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-14T00:33:34.123Z] GC before operation: completed in 98.658 ms, heap usage 248.964 MB -> 115.663 MB. [2024-11-14T00:33:36.046Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1725.039 ms) ====== [2024-11-14T00:33:36.046Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-14T00:33:36.046Z] GC before operation: completed in 97.205 ms, heap usage 259.517 MB -> 115.850 MB. [2024-11-14T00:33:36.982Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1574.228 ms) ====== [2024-11-14T00:33:36.982Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-14T00:33:37.917Z] GC before operation: completed in 102.016 ms, heap usage 277.585 MB -> 116.089 MB. [2024-11-14T00:33:38.861Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1689.643 ms) ====== [2024-11-14T00:33:39.796Z] ----------------------------------- [2024-11-14T00:33:39.796Z] renaissance-log-regression_0_PASSED [2024-11-14T00:33:39.796Z] ----------------------------------- [2024-11-14T00:33:39.796Z] [2024-11-14T00:33:39.796Z] TEST TEARDOWN: [2024-11-14T00:33:39.796Z] Nothing to be done for teardown. [2024-11-14T00:33:39.796Z] renaissance-log-regression_0 Finish Time: Thu Nov 14 00:33:38 2024 Epoch Time (ms): 1731544418983