[2024-10-02T21:04:57.528Z] Running test renaissance-log-regression_0 ... [2024-10-02T21:04:57.528Z] =============================================== [2024-10-02T21:04:57.528Z] renaissance-log-regression_0 Start Time: Wed Oct 2 21:04:57 2024 Epoch Time (ms): 1727903097112 [2024-10-02T21:04:57.528Z] variation: NoOptions [2024-10-02T21:04:57.528Z] JVM_OPTIONS: [2024-10-02T21:04:57.528Z] { \ [2024-10-02T21:04:57.528Z] echo ""; echo "TEST SETUP:"; \ [2024-10-02T21:04:57.528Z] echo "Nothing to be done for setup."; \ [2024-10-02T21:04:57.528Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/renaissance-log-regression_0"; \ [2024-10-02T21:04:57.528Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/renaissance-log-regression_0"; \ [2024-10-02T21:04:57.528Z] echo ""; echo "TESTING:"; \ [2024-10-02T21:04:57.528Z] "/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_17279023323367/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-10-02T21:04:57.528Z] 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_17279023323367/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-02T21:04:57.528Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-02T21:04:57.528Z] echo "Nothing to be done for teardown."; \ [2024-10-02T21:04:57.528Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/TestTargetResult"; [2024-10-02T21:04:57.528Z] [2024-10-02T21:04:57.528Z] TEST SETUP: [2024-10-02T21:04:57.528Z] Nothing to be done for setup. [2024-10-02T21:04:57.528Z] [2024-10-02T21:04:57.528Z] TESTING: [2024-10-02T21:05:01.752Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-02T21:05:02.699Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-02T21:05:03.642Z] WARNING: An illegal reflective access operation has occurred [2024-10-02T21:05:03.642Z] 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_17279023323367/renaissance-log-regression_0/harness-210457-1780570293811914620/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-10-02T21:05:03.642Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-10-02T21:05:03.642Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-10-02T21:05:03.642Z] WARNING: All illegal access operations will be denied in a future release [2024-10-02T21:05:06.639Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-10-02T21:05:06.639Z] GC before operation: completed in 44.449 ms, heap usage 59.333 MB -> 33.781 MB. [2024-10-02T21:05:14.747Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7560.700 ms) ====== [2024-10-02T21:05:14.747Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-10-02T21:05:14.747Z] GC before operation: completed in 84.179 ms, heap usage 322.326 MB -> 112.074 MB. [2024-10-02T21:05:16.696Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2323.005 ms) ====== [2024-10-02T21:05:16.696Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-10-02T21:05:16.696Z] GC before operation: completed in 76.627 ms, heap usage 295.438 MB -> 112.523 MB. [2024-10-02T21:05:18.638Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1891.241 ms) ====== [2024-10-02T21:05:18.638Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-10-02T21:05:18.638Z] GC before operation: completed in 81.772 ms, heap usage 278.012 MB -> 112.922 MB. [2024-10-02T21:05:20.581Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1767.822 ms) ====== [2024-10-02T21:05:20.581Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-10-02T21:05:20.581Z] GC before operation: completed in 75.480 ms, heap usage 229.002 MB -> 113.046 MB. [2024-10-02T21:05:21.534Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1655.936 ms) ====== [2024-10-02T21:05:21.534Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-10-02T21:05:21.534Z] GC before operation: completed in 72.842 ms, heap usage 238.946 MB -> 113.267 MB. [2024-10-02T21:05:23.469Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1502.876 ms) ====== [2024-10-02T21:05:23.470Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-10-02T21:05:23.470Z] GC before operation: completed in 74.503 ms, heap usage 276.956 MB -> 113.549 MB. [2024-10-02T21:05:25.405Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1606.125 ms) ====== [2024-10-02T21:05:25.405Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-10-02T21:05:25.405Z] GC before operation: completed in 70.750 ms, heap usage 294.419 MB -> 113.788 MB. [2024-10-02T21:05:26.346Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1525.729 ms) ====== [2024-10-02T21:05:26.346Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-10-02T21:05:27.290Z] GC before operation: completed in 75.663 ms, heap usage 238.649 MB -> 113.833 MB. [2024-10-02T21:05:28.233Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1560.540 ms) ====== [2024-10-02T21:05:28.233Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-10-02T21:05:28.233Z] GC before operation: completed in 79.114 ms, heap usage 275.634 MB -> 114.097 MB. [2024-10-02T21:05:30.174Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1525.147 ms) ====== [2024-10-02T21:05:30.174Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-10-02T21:05:30.174Z] GC before operation: completed in 92.174 ms, heap usage 315.903 MB -> 114.382 MB. [2024-10-02T21:05:32.111Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1490.002 ms) ====== [2024-10-02T21:05:32.111Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-10-02T21:05:32.111Z] GC before operation: completed in 77.366 ms, heap usage 295.275 MB -> 114.557 MB. [2024-10-02T21:05:33.065Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1578.243 ms) ====== [2024-10-02T21:05:33.065Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-10-02T21:05:33.065Z] GC before operation: completed in 82.142 ms, heap usage 228.662 MB -> 114.608 MB. [2024-10-02T21:05:35.023Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1488.342 ms) ====== [2024-10-02T21:05:35.023Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-10-02T21:05:35.023Z] GC before operation: completed in 80.028 ms, heap usage 257.396 MB -> 114.843 MB. [2024-10-02T21:05:35.967Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1512.885 ms) ====== [2024-10-02T21:05:35.967Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-10-02T21:05:36.910Z] GC before operation: completed in 82.076 ms, heap usage 305.578 MB -> 115.168 MB. [2024-10-02T21:05:37.855Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1456.090 ms) ====== [2024-10-02T21:05:37.855Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-10-02T21:05:37.855Z] GC before operation: completed in 82.345 ms, heap usage 258.537 MB -> 115.180 MB. [2024-10-02T21:05:39.792Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1462.609 ms) ====== [2024-10-02T21:05:39.792Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-10-02T21:05:39.792Z] GC before operation: completed in 84.874 ms, heap usage 249.000 MB -> 115.410 MB. [2024-10-02T21:05:40.739Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1458.522 ms) ====== [2024-10-02T21:05:40.739Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-10-02T21:05:40.739Z] GC before operation: completed in 81.385 ms, heap usage 315.340 MB -> 115.751 MB. [2024-10-02T21:05:42.675Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1508.104 ms) ====== [2024-10-02T21:05:42.675Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-10-02T21:05:42.675Z] GC before operation: completed in 83.372 ms, heap usage 279.166 MB -> 115.852 MB. [2024-10-02T21:05:44.609Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1488.980 ms) ====== [2024-10-02T21:05:44.609Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-10-02T21:05:44.609Z] GC before operation: completed in 84.304 ms, heap usage 258.462 MB -> 115.999 MB. [2024-10-02T21:05:45.553Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1456.779 ms) ====== [2024-10-02T21:05:45.553Z] ----------------------------------- [2024-10-02T21:05:45.553Z] renaissance-log-regression_0_PASSED [2024-10-02T21:05:45.553Z] ----------------------------------- [2024-10-02T21:05:45.553Z] [2024-10-02T21:05:45.553Z] TEST TEARDOWN: [2024-10-02T21:05:45.553Z] Nothing to be done for teardown. [2024-10-02T21:05:45.553Z] renaissance-log-regression_0 Finish Time: Wed Oct 2 21:05:45 2024 Epoch Time (ms): 1727903145455