[2024-08-22T10:45:46.081Z] Running test renaissance-log-regression_0 ... [2024-08-22T10:45:46.081Z] =============================================== [2024-08-22T10:45:46.081Z] renaissance-log-regression_0 Start Time: Thu Aug 22 10:45:45 2024 Epoch Time (ms): 1724323545371 [2024-08-22T10:45:46.081Z] variation: NoOptions [2024-08-22T10:45:46.081Z] JVM_OPTIONS: [2024-08-22T10:45:46.081Z] { \ [2024-08-22T10:45:46.081Z] echo ""; echo "TEST SETUP:"; \ [2024-08-22T10:45:46.081Z] echo "Nothing to be done for setup."; \ [2024-08-22T10:45:46.081Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17243227179404/renaissance-log-regression_0"; \ [2024-08-22T10:45:46.081Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17243227179404/renaissance-log-regression_0"; \ [2024-08-22T10:45:46.081Z] echo ""; echo "TESTING:"; \ [2024-08-22T10:45:46.081Z] "/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_17243227179404/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-22T10:45:46.081Z] 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_17243227179404/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-22T10:45:46.081Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-22T10:45:46.081Z] echo "Nothing to be done for teardown."; \ [2024-08-22T10:45:46.081Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17243227179404/TestTargetResult"; [2024-08-22T10:45:46.081Z] [2024-08-22T10:45:46.081Z] TEST SETUP: [2024-08-22T10:45:46.081Z] Nothing to be done for setup. [2024-08-22T10:45:46.081Z] [2024-08-22T10:45:46.081Z] TESTING: [2024-08-22T10:45:50.551Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-22T10:45:53.034Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-08-22T10:45:54.634Z] WARNING: An illegal reflective access operation has occurred [2024-08-22T10:45:54.634Z] 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_17243227179404/renaissance-log-regression_0/harness-104546-7913261891592152608/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-22T10:45:54.634Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-22T10:45:54.634Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-22T10:45:54.634Z] WARNING: All illegal access operations will be denied in a future release [2024-08-22T10:45:58.075Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-22T10:45:58.075Z] GC before operation: completed in 87.061 ms, heap usage 199.544 MB -> 35.310 MB. [2024-08-22T10:46:08.002Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9601.676 ms) ====== [2024-08-22T10:46:08.002Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-22T10:46:08.003Z] GC before operation: completed in 104.786 ms, heap usage 527.024 MB -> 126.173 MB. [2024-08-22T10:46:10.485Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2764.207 ms) ====== [2024-08-22T10:46:10.485Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-22T10:46:10.485Z] GC before operation: completed in 121.397 ms, heap usage 405.100 MB -> 126.511 MB. [2024-08-22T10:46:12.963Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2174.736 ms) ====== [2024-08-22T10:46:12.963Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-22T10:46:12.963Z] GC before operation: completed in 142.981 ms, heap usage 313.002 MB -> 126.694 MB. [2024-08-22T10:46:14.558Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1971.915 ms) ====== [2024-08-22T10:46:14.558Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-22T10:46:15.339Z] GC before operation: completed in 103.176 ms, heap usage 628.183 MB -> 127.875 MB. [2024-08-22T10:46:16.952Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2120.407 ms) ====== [2024-08-22T10:46:16.952Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-22T10:46:16.952Z] GC before operation: completed in 95.961 ms, heap usage 320.706 MB -> 127.375 MB. [2024-08-22T10:46:19.441Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1902.351 ms) ====== [2024-08-22T10:46:19.441Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-22T10:46:19.441Z] GC before operation: completed in 102.122 ms, heap usage 663.797 MB -> 128.731 MB. [2024-08-22T10:46:21.040Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1877.132 ms) ====== [2024-08-22T10:46:21.041Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-22T10:46:21.041Z] GC before operation: completed in 108.025 ms, heap usage 399.300 MB -> 128.315 MB. [2024-08-22T10:46:23.519Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1873.404 ms) ====== [2024-08-22T10:46:23.519Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-22T10:46:23.519Z] GC before operation: completed in 108.880 ms, heap usage 264.841 MB -> 128.408 MB. [2024-08-22T10:46:25.115Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1767.620 ms) ====== [2024-08-22T10:46:25.115Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-22T10:46:25.115Z] GC before operation: completed in 133.744 ms, heap usage 868.558 MB -> 130.292 MB. [2024-08-22T10:46:26.730Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1819.124 ms) ====== [2024-08-22T10:46:26.730Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-22T10:46:26.730Z] GC before operation: completed in 129.189 ms, heap usage 380.361 MB -> 129.273 MB. [2024-08-22T10:46:29.211Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1724.973 ms) ====== [2024-08-22T10:46:29.211Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-22T10:46:29.211Z] GC before operation: completed in 156.588 ms, heap usage 466.278 MB -> 129.952 MB. [2024-08-22T10:46:30.807Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1877.138 ms) ====== [2024-08-22T10:46:30.807Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-22T10:46:30.807Z] GC before operation: completed in 112.772 ms, heap usage 267.115 MB -> 129.811 MB. [2024-08-22T10:46:32.403Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1773.526 ms) ====== [2024-08-22T10:46:32.403Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-22T10:46:33.180Z] GC before operation: completed in 147.895 ms, heap usage 541.620 MB -> 130.890 MB. [2024-08-22T10:46:34.776Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1801.092 ms) ====== [2024-08-22T10:46:34.776Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-22T10:46:34.776Z] GC before operation: completed in 121.661 ms, heap usage 246.239 MB -> 130.415 MB. [2024-08-22T10:46:36.379Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1694.398 ms) ====== [2024-08-22T10:46:36.379Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-22T10:46:36.379Z] GC before operation: completed in 155.983 ms, heap usage 400.813 MB -> 131.119 MB. [2024-08-22T10:46:37.975Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1718.991 ms) ====== [2024-08-22T10:46:37.976Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-22T10:46:38.760Z] GC before operation: completed in 119.151 ms, heap usage 598.503 MB -> 132.024 MB. [2024-08-22T10:46:40.363Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1718.812 ms) ====== [2024-08-22T10:46:40.363Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-22T10:46:40.363Z] GC before operation: completed in 128.604 ms, heap usage 482.158 MB -> 132.129 MB. [2024-08-22T10:46:41.986Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1699.348 ms) ====== [2024-08-22T10:46:41.986Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-22T10:46:41.986Z] GC before operation: completed in 108.937 ms, heap usage 423.286 MB -> 132.378 MB. [2024-08-22T10:46:43.583Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1696.312 ms) ====== [2024-08-22T10:46:43.583Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-22T10:46:43.583Z] GC before operation: completed in 124.512 ms, heap usage 281.841 MB -> 132.327 MB. [2024-08-22T10:46:46.062Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1692.201 ms) ====== [2024-08-22T10:46:46.062Z] ----------------------------------- [2024-08-22T10:46:46.062Z] renaissance-log-regression_0_PASSED [2024-08-22T10:46:46.062Z] ----------------------------------- [2024-08-22T10:46:46.062Z] [2024-08-22T10:46:46.062Z] TEST TEARDOWN: [2024-08-22T10:46:46.062Z] Nothing to be done for teardown. [2024-08-22T10:46:46.062Z] renaissance-log-regression_0 Finish Time: Thu Aug 22 10:46:45 2024 Epoch Time (ms): 1724323605564