[2024-08-16T22:41:20.402Z] Running test renaissance-log-regression_0 ... [2024-08-16T22:41:20.402Z] =============================================== [2024-08-16T22:41:20.402Z] renaissance-log-regression_0 Start Time: Fri Aug 16 22:41:20 2024 Epoch Time (ms): 1723848080263 [2024-08-16T22:41:20.402Z] variation: NoOptions [2024-08-16T22:41:20.402Z] JVM_OPTIONS: [2024-08-16T22:41:20.402Z] { \ [2024-08-16T22:41:20.402Z] echo ""; echo "TEST SETUP:"; \ [2024-08-16T22:41:20.402Z] echo "Nothing to be done for setup."; \ [2024-08-16T22:41:20.402Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238471998043/renaissance-log-regression_0"; \ [2024-08-16T22:41:20.402Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238471998043/renaissance-log-regression_0"; \ [2024-08-16T22:41:20.402Z] echo ""; echo "TESTING:"; \ [2024-08-16T22:41:20.402Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/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_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238471998043/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-16T22:41:20.402Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238471998043/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-16T22:41:20.402Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-16T22:41:20.402Z] echo "Nothing to be done for teardown."; \ [2024-08-16T22:41:20.402Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17238471998043/TestTargetResult"; [2024-08-16T22:41:20.402Z] [2024-08-16T22:41:20.402Z] TEST SETUP: [2024-08-16T22:41:20.402Z] Nothing to be done for setup. [2024-08-16T22:41:20.402Z] [2024-08-16T22:41:20.402Z] TESTING: [2024-08-16T22:41:24.433Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-16T22:41:25.041Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-16T22:41:27.792Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-16T22:41:28.399Z] GC before operation: completed in 66.994 ms, heap usage 43.213 MB -> 34.926 MB. [2024-08-16T22:41:36.849Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8817.732 ms) ====== [2024-08-16T22:41:36.849Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-16T22:41:36.849Z] GC before operation: completed in 140.984 ms, heap usage 241.567 MB -> 110.281 MB. [2024-08-16T22:41:40.601Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3524.947 ms) ====== [2024-08-16T22:41:40.601Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-16T22:41:40.601Z] GC before operation: completed in 136.441 ms, heap usage 286.324 MB -> 110.873 MB. [2024-08-16T22:41:43.558Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3046.519 ms) ====== [2024-08-16T22:41:43.558Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-16T22:41:44.183Z] GC before operation: completed in 109.993 ms, heap usage 249.410 MB -> 111.201 MB. [2024-08-16T22:41:46.999Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2647.597 ms) ====== [2024-08-16T22:41:46.999Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-16T22:41:46.999Z] GC before operation: completed in 94.505 ms, heap usage 316.033 MB -> 111.661 MB. [2024-08-16T22:41:49.039Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2561.321 ms) ====== [2024-08-16T22:41:49.039Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-16T22:41:49.039Z] GC before operation: completed in 107.886 ms, heap usage 313.447 MB -> 111.792 MB. [2024-08-16T22:41:51.842Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2437.733 ms) ====== [2024-08-16T22:41:51.842Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-16T22:41:51.842Z] GC before operation: completed in 80.996 ms, heap usage 237.977 MB -> 111.830 MB. [2024-08-16T22:41:53.847Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2196.765 ms) ====== [2024-08-16T22:41:53.847Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-16T22:41:53.847Z] GC before operation: completed in 75.611 ms, heap usage 238.950 MB -> 112.017 MB. [2024-08-16T22:41:56.649Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2203.706 ms) ====== [2024-08-16T22:41:56.649Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-16T22:41:56.649Z] GC before operation: completed in 126.260 ms, heap usage 246.386 MB -> 112.204 MB. [2024-08-16T22:41:58.648Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2343.487 ms) ====== [2024-08-16T22:41:58.648Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-16T22:41:58.648Z] GC before operation: completed in 101.572 ms, heap usage 262.450 MB -> 112.458 MB. [2024-08-16T22:42:01.490Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2209.436 ms) ====== [2024-08-16T22:42:01.491Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-16T22:42:01.491Z] GC before operation: completed in 102.613 ms, heap usage 263.505 MB -> 112.683 MB. [2024-08-16T22:42:03.458Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2085.832 ms) ====== [2024-08-16T22:42:03.458Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-16T22:42:03.458Z] GC before operation: completed in 97.368 ms, heap usage 271.813 MB -> 112.806 MB. [2024-08-16T22:42:05.837Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2306.486 ms) ====== [2024-08-16T22:42:05.837Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-16T22:42:05.837Z] GC before operation: completed in 101.872 ms, heap usage 291.380 MB -> 113.035 MB. [2024-08-16T22:42:07.804Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2087.182 ms) ====== [2024-08-16T22:42:07.804Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-16T22:42:07.804Z] GC before operation: completed in 86.632 ms, heap usage 272.676 MB -> 113.254 MB. [2024-08-16T22:42:10.571Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2211.103 ms) ====== [2024-08-16T22:42:10.571Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-16T22:42:10.571Z] GC before operation: completed in 130.574 ms, heap usage 281.642 MB -> 113.387 MB. [2024-08-16T22:42:12.587Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2284.042 ms) ====== [2024-08-16T22:42:12.587Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-16T22:42:12.587Z] GC before operation: completed in 102.975 ms, heap usage 255.012 MB -> 113.492 MB. [2024-08-16T22:42:15.371Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2316.947 ms) ====== [2024-08-16T22:42:15.372Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-16T22:42:15.372Z] GC before operation: completed in 116.513 ms, heap usage 290.547 MB -> 113.872 MB. [2024-08-16T22:42:17.474Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2337.394 ms) ====== [2024-08-16T22:42:17.474Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-16T22:42:17.474Z] GC before operation: completed in 125.242 ms, heap usage 245.776 MB -> 113.975 MB. [2024-08-16T22:42:20.314Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2517.283 ms) ====== [2024-08-16T22:42:20.315Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-16T22:42:20.315Z] GC before operation: completed in 105.529 ms, heap usage 245.011 MB -> 114.007 MB. [2024-08-16T22:42:22.381Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2425.030 ms) ====== [2024-08-16T22:42:22.381Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-16T22:42:22.974Z] GC before operation: completed in 102.051 ms, heap usage 238.080 MB -> 114.219 MB. [2024-08-16T22:42:24.967Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1995.857 ms) ====== [2024-08-16T22:42:24.967Z] ----------------------------------- [2024-08-16T22:42:24.968Z] renaissance-log-regression_0_PASSED [2024-08-16T22:42:24.968Z] ----------------------------------- [2024-08-16T22:42:24.968Z] [2024-08-16T22:42:24.968Z] TEST TEARDOWN: [2024-08-16T22:42:24.968Z] Nothing to be done for teardown. [2024-08-16T22:42:24.968Z] renaissance-log-regression_0 Finish Time: Fri Aug 16 22:42:24 2024 Epoch Time (ms): 1723848144742