[2025-02-20T07:05:09.345Z] Running test renaissance-log-regression_0 ... [2025-02-20T07:05:09.345Z] =============================================== [2025-02-20T07:05:09.345Z] renaissance-log-regression_0 Start Time: Thu Feb 20 02:05:09 2025 Epoch Time (ms): 1740035109148 [2025-02-20T07:05:09.345Z] variation: NoOptions [2025-02-20T07:05:09.345Z] JVM_OPTIONS: [2025-02-20T07:05:09.345Z] { \ [2025-02-20T07:05:09.345Z] echo ""; echo "TEST SETUP:"; \ [2025-02-20T07:05:09.345Z] echo "Nothing to be done for setup."; \ [2025-02-20T07:05:09.345Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17400342377445/renaissance-log-regression_0"; \ [2025-02-20T07:05:09.345Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17400342377445/renaissance-log-regression_0"; \ [2025-02-20T07:05:09.345Z] echo ""; echo "TESTING:"; \ [2025-02-20T07:05:09.345Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17400342377445/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-20T07:05:09.345Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17400342377445/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-20T07:05:09.345Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-20T07:05:09.345Z] echo "Nothing to be done for teardown."; \ [2025-02-20T07:05:09.345Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17400342377445/TestTargetResult"; [2025-02-20T07:05:09.345Z] [2025-02-20T07:05:09.345Z] TEST SETUP: [2025-02-20T07:05:09.345Z] Nothing to be done for setup. [2025-02-20T07:05:09.345Z] [2025-02-20T07:05:09.345Z] TESTING: [2025-02-20T07:05:13.283Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-20T07:05:14.849Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-02-20T07:05:16.244Z] WARNING: An illegal reflective access operation has occurred [2025-02-20T07:05:16.244Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17400342377445/renaissance-log-regression_0/harness-020509-11087752179575539708/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-20T07:05:16.244Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-20T07:05:16.244Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-20T07:05:16.244Z] WARNING: All illegal access operations will be denied in a future release [2025-02-20T07:05:18.465Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-20T07:05:18.465Z] GC before operation: completed in 142.070 ms, heap usage 72.483 MB -> 33.754 MB. [2025-02-20T07:05:31.389Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12397.954 ms) ====== [2025-02-20T07:05:31.389Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-20T07:05:31.389Z] GC before operation: completed in 141.351 ms, heap usage 254.203 MB -> 109.199 MB. [2025-02-20T07:05:36.556Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5006.853 ms) ====== [2025-02-20T07:05:36.556Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-20T07:05:36.556Z] GC before operation: completed in 186.056 ms, heap usage 235.003 MB -> 109.608 MB. [2025-02-20T07:05:40.777Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4463.261 ms) ====== [2025-02-20T07:05:40.777Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-20T07:05:40.777Z] GC before operation: completed in 122.687 ms, heap usage 260.013 MB -> 110.085 MB. [2025-02-20T07:05:44.762Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3792.327 ms) ====== [2025-02-20T07:05:44.762Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-20T07:05:44.762Z] GC before operation: completed in 224.745 ms, heap usage 225.451 MB -> 110.276 MB. [2025-02-20T07:05:48.908Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3695.805 ms) ====== [2025-02-20T07:05:48.908Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-20T07:05:48.908Z] GC before operation: completed in 144.899 ms, heap usage 270.751 MB -> 110.552 MB. [2025-02-20T07:05:52.996Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4004.114 ms) ====== [2025-02-20T07:05:52.996Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-20T07:05:52.996Z] GC before operation: completed in 169.386 ms, heap usage 259.598 MB -> 110.723 MB. [2025-02-20T07:05:56.168Z] 25/02/20 02:05:55 WARN BlockManager: Asked to remove block broadcast_310, which does not exist [2025-02-20T07:05:56.847Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3968.641 ms) ====== [2025-02-20T07:05:56.847Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-20T07:05:56.847Z] GC before operation: completed in 159.747 ms, heap usage 277.571 MB -> 110.955 MB. [2025-02-20T07:06:01.992Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4337.866 ms) ====== [2025-02-20T07:06:01.992Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-20T07:06:01.992Z] GC before operation: completed in 216.106 ms, heap usage 224.923 MB -> 111.008 MB. [2025-02-20T07:06:06.196Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4243.075 ms) ====== [2025-02-20T07:06:06.196Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-20T07:06:06.196Z] GC before operation: completed in 189.342 ms, heap usage 225.021 MB -> 111.183 MB. [2025-02-20T07:06:09.715Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3885.319 ms) ====== [2025-02-20T07:06:09.715Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-20T07:06:10.481Z] GC before operation: completed in 355.211 ms, heap usage 289.629 MB -> 111.493 MB. [2025-02-20T07:06:14.535Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3975.922 ms) ====== [2025-02-20T07:06:14.535Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-20T07:06:14.535Z] GC before operation: completed in 152.238 ms, heap usage 279.925 MB -> 111.649 MB. [2025-02-20T07:06:17.662Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3585.949 ms) ====== [2025-02-20T07:06:17.662Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-20T07:06:18.338Z] GC before operation: completed in 189.426 ms, heap usage 234.730 MB -> 111.682 MB. [2025-02-20T07:06:21.369Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3388.886 ms) ====== [2025-02-20T07:06:21.369Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-20T07:06:21.369Z] GC before operation: completed in 157.334 ms, heap usage 253.211 MB -> 111.901 MB. [2025-02-20T07:06:24.418Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2981.591 ms) ====== [2025-02-20T07:06:24.418Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-20T07:06:25.088Z] GC before operation: completed in 180.142 ms, heap usage 254.275 MB -> 112.052 MB. [2025-02-20T07:06:28.202Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3045.625 ms) ====== [2025-02-20T07:06:28.202Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-20T07:06:28.202Z] GC before operation: completed in 147.441 ms, heap usage 260.905 MB -> 112.283 MB. [2025-02-20T07:06:31.296Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3298.236 ms) ====== [2025-02-20T07:06:31.296Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-20T07:06:31.296Z] GC before operation: completed in 185.215 ms, heap usage 271.796 MB -> 112.494 MB. [2025-02-20T07:06:35.313Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3425.525 ms) ====== [2025-02-20T07:06:35.313Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-20T07:06:35.313Z] GC before operation: completed in 152.594 ms, heap usage 235.620 MB -> 112.606 MB. [2025-02-20T07:06:38.396Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3113.447 ms) ====== [2025-02-20T07:06:38.396Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-20T07:06:38.396Z] GC before operation: completed in 194.658 ms, heap usage 272.189 MB -> 112.870 MB. [2025-02-20T07:06:41.428Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3230.028 ms) ====== [2025-02-20T07:06:41.428Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-20T07:06:42.126Z] GC before operation: completed in 189.418 ms, heap usage 225.410 MB -> 112.950 MB. [2025-02-20T07:06:45.130Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2953.808 ms) ====== [2025-02-20T07:06:45.130Z] ----------------------------------- [2025-02-20T07:06:45.130Z] renaissance-log-regression_0_PASSED [2025-02-20T07:06:45.130Z] ----------------------------------- [2025-02-20T07:06:45.130Z] [2025-02-20T07:06:45.130Z] TEST TEARDOWN: [2025-02-20T07:06:45.130Z] Nothing to be done for teardown. [2025-02-20T07:06:45.130Z] renaissance-log-regression_0 Finish Time: Thu Feb 20 02:06:44 2025 Epoch Time (ms): 1740035204708