[2024-12-05T07:47:57.441Z] Running test renaissance-log-regression_0 ... [2024-12-05T07:47:57.441Z] =============================================== [2024-12-05T07:47:57.441Z] renaissance-log-regression_0 Start Time: Thu Dec 5 07:47:57 2024 Epoch Time (ms): 1733384877044 [2024-12-05T07:47:57.441Z] variation: NoOptions [2024-12-05T07:47:57.441Z] JVM_OPTIONS: [2024-12-05T07:47:57.441Z] { \ [2024-12-05T07:47:57.441Z] echo ""; echo "TEST SETUP:"; \ [2024-12-05T07:47:57.441Z] echo "Nothing to be done for setup."; \ [2024-12-05T07:47:57.441Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/renaissance-log-regression_0"; \ [2024-12-05T07:47:57.441Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/renaissance-log-regression_0"; \ [2024-12-05T07:47:57.441Z] echo ""; echo "TESTING:"; \ [2024-12-05T07:47:57.441Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-12-05T07:47:57.441Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-05T07:47:57.441Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-05T07:47:57.441Z] echo "Nothing to be done for teardown."; \ [2024-12-05T07:47:57.441Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/TestTargetResult"; [2024-12-05T07:47:57.441Z] [2024-12-05T07:47:57.441Z] TEST SETUP: [2024-12-05T07:47:57.441Z] Nothing to be done for setup. [2024-12-05T07:47:57.441Z] [2024-12-05T07:47:57.441Z] TESTING: [2024-12-05T07:48:04.046Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-05T07:48:07.280Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-12-05T07:48:13.576Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-12-05T07:48:13.576Z] GC before operation: completed in 299.992 ms, heap usage 54.298 MB -> 34.901 MB. [2024-12-05T07:48:34.978Z] ====== log-regression (apache-spark) [default], iteration 0 completed (18572.349 ms) ====== [2024-12-05T07:48:34.978Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-12-05T07:48:34.978Z] GC before operation: completed in 802.054 ms, heap usage 355.058 MB -> 110.808 MB. [2024-12-05T07:48:39.286Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6018.874 ms) ====== [2024-12-05T07:48:39.286Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-12-05T07:48:39.286Z] GC before operation: completed in 176.275 ms, heap usage 275.001 MB -> 110.830 MB. [2024-12-05T07:48:44.437Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5307.785 ms) ====== [2024-12-05T07:48:44.437Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-12-05T07:48:45.251Z] GC before operation: completed in 145.503 ms, heap usage 268.238 MB -> 111.213 MB. [2024-12-05T07:48:46.666Z] 24/12/05 07:48:46 WARN BlockManager: Asked to remove block broadcast_149, which does not exist [2024-12-05T07:48:50.960Z] ====== log-regression (apache-spark) [default], iteration 3 completed (5648.364 ms) ====== [2024-12-05T07:48:50.960Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-12-05T07:48:50.960Z] GC before operation: completed in 217.571 ms, heap usage 279.254 MB -> 111.542 MB. [2024-12-05T07:48:56.542Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5846.966 ms) ====== [2024-12-05T07:48:56.542Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-12-05T07:48:57.449Z] GC before operation: completed in 257.975 ms, heap usage 259.694 MB -> 111.656 MB. [2024-12-05T07:49:01.498Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4502.738 ms) ====== [2024-12-05T07:49:01.498Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-12-05T07:49:01.498Z] GC before operation: completed in 183.124 ms, heap usage 257.368 MB -> 111.827 MB. [2024-12-05T07:49:06.658Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4561.487 ms) ====== [2024-12-05T07:49:06.658Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-12-05T07:49:06.658Z] GC before operation: completed in 400.868 ms, heap usage 277.068 MB -> 112.069 MB. [2024-12-05T07:49:13.146Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6196.235 ms) ====== [2024-12-05T07:49:13.146Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-12-05T07:49:13.146Z] GC before operation: completed in 256.506 ms, heap usage 239.983 MB -> 112.239 MB. [2024-12-05T07:49:17.299Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4214.092 ms) ====== [2024-12-05T07:49:17.299Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-12-05T07:49:17.299Z] GC before operation: completed in 139.029 ms, heap usage 348.846 MB -> 112.632 MB. [2024-12-05T07:49:22.646Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5596.992 ms) ====== [2024-12-05T07:49:22.646Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-12-05T07:49:23.308Z] GC before operation: completed in 188.767 ms, heap usage 265.371 MB -> 112.579 MB. [2024-12-05T07:49:24.785Z] 24/12/05 07:49:24 WARN BlockManager: Asked to remove block broadcast_480, which does not exist [2024-12-05T07:49:27.924Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4756.571 ms) ====== [2024-12-05T07:49:27.924Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-12-05T07:49:27.924Z] GC before operation: completed in 284.494 ms, heap usage 244.240 MB -> 112.678 MB. [2024-12-05T07:49:32.979Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4215.450 ms) ====== [2024-12-05T07:49:32.979Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-12-05T07:49:32.979Z] GC before operation: completed in 222.410 ms, heap usage 282.228 MB -> 113.032 MB. [2024-12-05T07:49:38.391Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5339.352 ms) ====== [2024-12-05T07:49:38.391Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-12-05T07:49:38.391Z] GC before operation: completed in 239.181 ms, heap usage 317.006 MB -> 113.226 MB. [2024-12-05T07:49:43.721Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5162.140 ms) ====== [2024-12-05T07:49:43.721Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-12-05T07:49:43.721Z] GC before operation: completed in 322.616 ms, heap usage 345.514 MB -> 113.479 MB. [2024-12-05T07:49:50.256Z] ====== log-regression (apache-spark) [default], iteration 14 completed (5857.656 ms) ====== [2024-12-05T07:49:50.256Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-12-05T07:49:50.256Z] GC before operation: completed in 405.171 ms, heap usage 273.229 MB -> 113.504 MB. [2024-12-05T07:49:54.862Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4862.886 ms) ====== [2024-12-05T07:49:54.862Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-12-05T07:49:54.862Z] GC before operation: completed in 185.757 ms, heap usage 255.360 MB -> 113.752 MB. [2024-12-05T07:49:58.994Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4322.447 ms) ====== [2024-12-05T07:49:58.994Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-12-05T07:49:59.726Z] GC before operation: completed in 313.392 ms, heap usage 298.521 MB -> 114.031 MB. [2024-12-05T07:50:04.218Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4919.686 ms) ====== [2024-12-05T07:50:04.218Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-12-05T07:50:05.092Z] GC before operation: completed in 371.050 ms, heap usage 229.256 MB -> 114.003 MB. [2024-12-05T07:50:10.527Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5068.716 ms) ====== [2024-12-05T07:50:10.527Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-12-05T07:50:10.527Z] GC before operation: completed in 344.456 ms, heap usage 333.789 MB -> 114.472 MB. [2024-12-05T07:50:15.778Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5328.207 ms) ====== [2024-12-05T07:50:16.445Z] ----------------------------------- [2024-12-05T07:50:16.445Z] renaissance-log-regression_0_PASSED [2024-12-05T07:50:16.445Z] ----------------------------------- [2024-12-05T07:50:16.445Z] [2024-12-05T07:50:16.445Z] TEST TEARDOWN: [2024-12-05T07:50:16.445Z] Nothing to be done for teardown. [2024-12-05T07:50:16.445Z] renaissance-log-regression_0 Finish Time: Thu Dec 5 07:50:15 2024 Epoch Time (ms): 1733385015921