[2024-09-25T22:14:31.021Z] Running test renaissance-log-regression_0 ... [2024-09-25T22:14:31.021Z] =============================================== [2024-09-25T22:14:31.021Z] renaissance-log-regression_0 Start Time: Wed Sep 25 18:14:30 2024 Epoch Time (ms): 1727302470741 [2024-09-25T22:14:31.021Z] variation: NoOptions [2024-09-25T22:14:31.021Z] JVM_OPTIONS: [2024-09-25T22:14:31.021Z] { \ [2024-09-25T22:14:31.021Z] echo ""; echo "TEST SETUP:"; \ [2024-09-25T22:14:31.021Z] echo "Nothing to be done for setup."; \ [2024-09-25T22:14:31.021Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273005053256/renaissance-log-regression_0"; \ [2024-09-25T22:14:31.021Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273005053256/renaissance-log-regression_0"; \ [2024-09-25T22:14:31.021Z] echo ""; echo "TESTING:"; \ [2024-09-25T22:14:31.021Z] "/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_17273005053256/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-25T22:14:31.021Z] 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_17273005053256/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-25T22:14:31.021Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-25T22:14:31.021Z] echo "Nothing to be done for teardown."; \ [2024-09-25T22:14:31.021Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273005053256/TestTargetResult"; [2024-09-25T22:14:31.021Z] [2024-09-25T22:14:31.021Z] TEST SETUP: [2024-09-25T22:14:31.021Z] Nothing to be done for setup. [2024-09-25T22:14:31.021Z] [2024-09-25T22:14:31.021Z] TESTING: [2024-09-25T22:14:35.979Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-25T22:14:41.591Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-09-25T22:14:48.138Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-25T22:14:48.138Z] GC before operation: completed in 158.715 ms, heap usage 44.441 MB -> 35.095 MB. [2024-09-25T22:15:09.310Z] ====== log-regression (apache-spark) [default], iteration 0 completed (19725.578 ms) ====== [2024-09-25T22:15:09.310Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-25T22:15:09.310Z] GC before operation: completed in 187.722 ms, heap usage 263.348 MB -> 110.506 MB. [2024-09-25T22:15:15.781Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7274.429 ms) ====== [2024-09-25T22:15:15.781Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-25T22:15:15.781Z] GC before operation: completed in 211.273 ms, heap usage 265.907 MB -> 111.085 MB. [2024-09-25T22:15:20.834Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4898.376 ms) ====== [2024-09-25T22:15:20.834Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-25T22:15:20.834Z] GC before operation: completed in 218.643 ms, heap usage 236.931 MB -> 111.397 MB. [2024-09-25T22:15:24.876Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4399.342 ms) ====== [2024-09-25T22:15:24.876Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-25T22:15:25.569Z] GC before operation: completed in 201.416 ms, heap usage 317.565 MB -> 111.853 MB. [2024-09-25T22:15:33.132Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6764.332 ms) ====== [2024-09-25T22:15:33.132Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-25T22:15:33.132Z] GC before operation: completed in 135.289 ms, heap usage 238.941 MB -> 111.973 MB. [2024-09-25T22:15:43.411Z] ====== log-regression (apache-spark) [default], iteration 5 completed (9881.807 ms) ====== [2024-09-25T22:15:43.412Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-25T22:15:43.412Z] GC before operation: completed in 204.410 ms, heap usage 274.268 MB -> 112.116 MB. [2024-09-25T22:15:51.056Z] ====== log-regression (apache-spark) [default], iteration 6 completed (8221.410 ms) ====== [2024-09-25T22:15:51.056Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-25T22:15:51.056Z] GC before operation: completed in 146.557 ms, heap usage 274.368 MB -> 112.347 MB. [2024-09-25T22:15:56.007Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5287.461 ms) ====== [2024-09-25T22:15:56.007Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-25T22:15:56.007Z] GC before operation: completed in 287.453 ms, heap usage 346.354 MB -> 112.721 MB. [2024-09-25T22:16:02.216Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5687.542 ms) ====== [2024-09-25T22:16:02.217Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-25T22:16:02.217Z] GC before operation: completed in 419.830 ms, heap usage 228.240 MB -> 112.597 MB. [2024-09-25T22:16:08.420Z] 24/09/25 18:16:07 WARN BlockManager: Asked to remove block broadcast_455, which does not exist [2024-09-25T22:16:11.327Z] ====== log-regression (apache-spark) [default], iteration 9 completed (8729.368 ms) ====== [2024-09-25T22:16:11.327Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-25T22:16:11.327Z] GC before operation: completed in 276.213 ms, heap usage 265.008 MB -> 112.835 MB. [2024-09-25T22:16:20.480Z] ====== log-regression (apache-spark) [default], iteration 10 completed (8989.550 ms) ====== [2024-09-25T22:16:20.480Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-25T22:16:21.315Z] GC before operation: completed in 439.192 ms, heap usage 300.063 MB -> 113.120 MB. [2024-09-25T22:16:28.216Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6944.226 ms) ====== [2024-09-25T22:16:28.216Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-25T22:16:29.057Z] GC before operation: completed in 400.418 ms, heap usage 257.550 MB -> 113.143 MB. [2024-09-25T22:16:36.268Z] ====== log-regression (apache-spark) [default], iteration 12 completed (7892.533 ms) ====== [2024-09-25T22:16:36.268Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-25T22:16:37.484Z] GC before operation: completed in 591.908 ms, heap usage 318.136 MB -> 113.548 MB. [2024-09-25T22:16:43.169Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6784.893 ms) ====== [2024-09-25T22:16:43.169Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-25T22:16:43.849Z] GC before operation: completed in 295.985 ms, heap usage 264.458 MB -> 113.558 MB. [2024-09-25T22:16:49.939Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6013.539 ms) ====== [2024-09-25T22:16:49.939Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-25T22:16:49.939Z] GC before operation: completed in 245.686 ms, heap usage 347.827 MB -> 113.978 MB. [2024-09-25T22:16:57.999Z] ====== log-regression (apache-spark) [default], iteration 15 completed (7293.027 ms) ====== [2024-09-25T22:16:57.999Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-25T22:16:57.999Z] GC before operation: completed in 200.116 ms, heap usage 277.977 MB -> 113.894 MB. [2024-09-25T22:17:05.249Z] ====== log-regression (apache-spark) [default], iteration 16 completed (7334.636 ms) ====== [2024-09-25T22:17:05.249Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-25T22:17:05.249Z] GC before operation: completed in 363.298 ms, heap usage 336.184 MB -> 114.319 MB. [2024-09-25T22:17:11.937Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5897.312 ms) ====== [2024-09-25T22:17:11.937Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-25T22:17:11.937Z] GC before operation: completed in 567.037 ms, heap usage 281.268 MB -> 114.355 MB. [2024-09-25T22:17:18.910Z] ====== log-regression (apache-spark) [default], iteration 18 completed (7127.571 ms) ====== [2024-09-25T22:17:18.911Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-25T22:17:18.911Z] GC before operation: completed in 223.206 ms, heap usage 246.311 MB -> 114.423 MB. [2024-09-25T22:17:24.686Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5579.128 ms) ====== [2024-09-25T22:17:26.145Z] ----------------------------------- [2024-09-25T22:17:26.145Z] renaissance-log-regression_0_PASSED [2024-09-25T22:17:26.145Z] ----------------------------------- [2024-09-25T22:17:26.145Z] [2024-09-25T22:17:26.145Z] TEST TEARDOWN: [2024-09-25T22:17:26.145Z] Nothing to be done for teardown. [2024-09-25T22:17:26.145Z] renaissance-log-regression_0 Finish Time: Wed Sep 25 18:17:25 2024 Epoch Time (ms): 1727302645560