[2024-11-14T07:50:46.470Z] Running test renaissance-log-regression_0 ... [2024-11-14T07:50:46.470Z] =============================================== [2024-11-14T07:50:46.470Z] renaissance-log-regression_0 Start Time: Thu Nov 14 07:50:46 2024 Epoch Time (ms): 1731570646294 [2024-11-14T07:50:46.470Z] variation: NoOptions [2024-11-14T07:50:46.470Z] JVM_OPTIONS: [2024-11-14T07:50:46.470Z] { \ [2024-11-14T07:50:46.470Z] echo ""; echo "TEST SETUP:"; \ [2024-11-14T07:50:46.470Z] echo "Nothing to be done for setup."; \ [2024-11-14T07:50:46.470Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17315698171927/renaissance-log-regression_0"; \ [2024-11-14T07:50:46.470Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17315698171927/renaissance-log-regression_0"; \ [2024-11-14T07:50:46.470Z] echo ""; echo "TESTING:"; \ [2024-11-14T07:50:46.470Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17315698171927/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-14T07:50:46.470Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17315698171927/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-14T07:50:46.470Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-14T07:50:46.470Z] echo "Nothing to be done for teardown."; \ [2024-11-14T07:50:46.470Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17315698171927/TestTargetResult"; [2024-11-14T07:50:46.470Z] [2024-11-14T07:50:46.470Z] TEST SETUP: [2024-11-14T07:50:46.470Z] Nothing to be done for setup. [2024-11-14T07:50:46.470Z] [2024-11-14T07:50:46.470Z] TESTING: [2024-11-14T07:50:52.030Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-14T07:50:54.479Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-11-14T07:50:56.057Z] WARNING: An illegal reflective access operation has occurred [2024-11-14T07:50:56.057Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17315698171927/renaissance-log-regression_0/harness-075047-17013075471440095946/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-14T07:50:56.057Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-14T07:50:56.057Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-14T07:50:56.057Z] WARNING: All illegal access operations will be denied in a future release [2024-11-14T07:50:59.449Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-14T07:50:59.449Z] GC before operation: completed in 76.444 ms, heap usage 203.063 MB -> 35.310 MB. [2024-11-14T07:51:09.281Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9536.999 ms) ====== [2024-11-14T07:51:09.281Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-14T07:51:09.281Z] GC before operation: completed in 115.876 ms, heap usage 534.836 MB -> 126.102 MB. [2024-11-14T07:51:11.734Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2571.534 ms) ====== [2024-11-14T07:51:11.734Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-14T07:51:11.734Z] GC before operation: completed in 88.708 ms, heap usage 407.431 MB -> 126.541 MB. [2024-11-14T07:51:14.187Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2238.463 ms) ====== [2024-11-14T07:51:14.187Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-14T07:51:14.187Z] GC before operation: completed in 105.599 ms, heap usage 360.375 MB -> 126.808 MB. [2024-11-14T07:51:16.655Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2171.046 ms) ====== [2024-11-14T07:51:16.655Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-14T07:51:16.655Z] GC before operation: completed in 99.816 ms, heap usage 357.817 MB -> 127.141 MB. [2024-11-14T07:51:18.228Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2076.765 ms) ====== [2024-11-14T07:51:18.228Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-14T07:51:18.991Z] GC before operation: completed in 108.559 ms, heap usage 541.521 MB -> 128.015 MB. [2024-11-14T07:51:20.565Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2055.317 ms) ====== [2024-11-14T07:51:20.565Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-14T07:51:20.565Z] GC before operation: completed in 110.148 ms, heap usage 358.081 MB -> 127.887 MB. [2024-11-14T07:51:23.007Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1927.118 ms) ====== [2024-11-14T07:51:23.007Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-14T07:51:23.007Z] GC before operation: completed in 105.215 ms, heap usage 452.323 MB -> 128.448 MB. [2024-11-14T07:51:24.582Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1881.177 ms) ====== [2024-11-14T07:51:24.582Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-14T07:51:24.582Z] GC before operation: completed in 138.762 ms, heap usage 279.624 MB -> 128.375 MB. [2024-11-14T07:51:27.027Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1760.337 ms) ====== [2024-11-14T07:51:27.027Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-14T07:51:27.027Z] GC before operation: completed in 135.318 ms, heap usage 454.966 MB -> 129.156 MB. [2024-11-14T07:51:28.597Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1983.048 ms) ====== [2024-11-14T07:51:28.597Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-14T07:51:28.597Z] GC before operation: completed in 147.567 ms, heap usage 281.086 MB -> 129.101 MB. [2024-11-14T07:51:31.047Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1679.568 ms) ====== [2024-11-14T07:51:31.047Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-14T07:51:31.047Z] GC before operation: completed in 156.539 ms, heap usage 938.758 MB -> 131.194 MB. [2024-11-14T07:51:32.636Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1769.350 ms) ====== [2024-11-14T07:51:32.636Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-14T07:51:32.636Z] GC before operation: completed in 170.979 ms, heap usage 421.259 MB -> 130.151 MB. [2024-11-14T07:51:34.215Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1841.842 ms) ====== [2024-11-14T07:51:34.215Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-14T07:51:34.976Z] GC before operation: completed in 145.769 ms, heap usage 304.444 MB -> 130.236 MB. [2024-11-14T07:51:36.568Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1790.408 ms) ====== [2024-11-14T07:51:36.568Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-14T07:51:36.568Z] GC before operation: completed in 168.587 ms, heap usage 461.883 MB -> 131.009 MB. [2024-11-14T07:51:38.140Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1717.412 ms) ====== [2024-11-14T07:51:38.140Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-14T07:51:38.913Z] GC before operation: completed in 176.591 ms, heap usage 534.378 MB -> 131.459 MB. [2024-11-14T07:51:40.499Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1837.466 ms) ====== [2024-11-14T07:51:40.499Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-14T07:51:40.499Z] GC before operation: completed in 157.914 ms, heap usage 325.471 MB -> 131.380 MB. [2024-11-14T07:51:42.080Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1631.857 ms) ====== [2024-11-14T07:51:42.080Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-14T07:51:42.080Z] GC before operation: completed in 143.827 ms, heap usage 627.376 MB -> 132.619 MB. [2024-11-14T07:51:44.527Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1779.678 ms) ====== [2024-11-14T07:51:44.527Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-14T07:51:44.527Z] GC before operation: completed in 110.944 ms, heap usage 287.651 MB -> 132.004 MB. [2024-11-14T07:51:46.100Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1728.259 ms) ====== [2024-11-14T07:51:46.100Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-14T07:51:46.100Z] GC before operation: completed in 142.496 ms, heap usage 327.221 MB -> 132.393 MB. [2024-11-14T07:51:47.674Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1770.320 ms) ====== [2024-11-14T07:51:48.435Z] ----------------------------------- [2024-11-14T07:51:48.435Z] renaissance-log-regression_0_PASSED [2024-11-14T07:51:48.435Z] ----------------------------------- [2024-11-14T07:51:48.435Z] [2024-11-14T07:51:48.435Z] TEST TEARDOWN: [2024-11-14T07:51:48.435Z] Nothing to be done for teardown. [2024-11-14T07:51:48.435Z] renaissance-log-regression_0 Finish Time: Thu Nov 14 07:51:47 2024 Epoch Time (ms): 1731570707848