[2024-11-08T17:12:11.673Z] Running test renaissance-log-regression_0 ... [2024-11-08T17:12:11.673Z] =============================================== [2024-11-08T17:12:11.673Z] renaissance-log-regression_0 Start Time: Fri Nov 8 12:12:11 2024 Epoch Time (ms): 1731085931325 [2024-11-08T17:12:11.673Z] variation: NoOptions [2024-11-08T17:12:11.673Z] JVM_OPTIONS: [2024-11-08T17:12:11.673Z] { \ [2024-11-08T17:12:11.673Z] echo ""; echo "TEST SETUP:"; \ [2024-11-08T17:12:11.674Z] echo "Nothing to be done for setup."; \ [2024-11-08T17:12:11.674Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17310839864583/renaissance-log-regression_0"; \ [2024-11-08T17:12:11.674Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17310839864583/renaissance-log-regression_0"; \ [2024-11-08T17:12:11.674Z] echo ""; echo "TESTING:"; \ [2024-11-08T17:12:11.674Z] "/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_17310839864583/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-08T17:12:11.674Z] 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_17310839864583/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-08T17:12:11.674Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-08T17:12:11.674Z] echo "Nothing to be done for teardown."; \ [2024-11-08T17:12:11.674Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17310839864583/TestTargetResult"; [2024-11-08T17:12:11.674Z] [2024-11-08T17:12:11.674Z] TEST SETUP: [2024-11-08T17:12:11.674Z] Nothing to be done for setup. [2024-11-08T17:12:11.674Z] [2024-11-08T17:12:11.674Z] TESTING: [2024-11-08T17:12:18.154Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-08T17:12:22.753Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-11-08T17:12:29.985Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-08T17:12:29.985Z] GC before operation: completed in 258.076 ms, heap usage 93.911 MB -> 35.097 MB. [2024-11-08T17:13:01.435Z] ====== log-regression (apache-spark) [default], iteration 0 completed (29023.279 ms) ====== [2024-11-08T17:13:01.435Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-08T17:13:01.435Z] GC before operation: completed in 144.992 ms, heap usage 288.937 MB -> 110.515 MB. [2024-11-08T17:13:10.217Z] ====== log-regression (apache-spark) [default], iteration 1 completed (11428.191 ms) ====== [2024-11-08T17:13:10.217Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-08T17:13:10.995Z] GC before operation: completed in 375.477 ms, heap usage 251.066 MB -> 110.965 MB. [2024-11-08T17:13:20.734Z] ====== log-regression (apache-spark) [default], iteration 2 completed (9208.128 ms) ====== [2024-11-08T17:13:20.734Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-08T17:13:20.734Z] GC before operation: completed in 179.644 ms, heap usage 261.551 MB -> 111.308 MB. [2024-11-08T17:13:31.485Z] ====== log-regression (apache-spark) [default], iteration 3 completed (11543.318 ms) ====== [2024-11-08T17:13:31.485Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-08T17:13:32.232Z] GC before operation: completed in 465.518 ms, heap usage 250.100 MB -> 111.612 MB. [2024-11-08T17:13:42.002Z] ====== log-regression (apache-spark) [default], iteration 4 completed (9187.723 ms) ====== [2024-11-08T17:13:42.003Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-08T17:13:42.003Z] GC before operation: completed in 413.418 ms, heap usage 236.111 MB -> 111.835 MB. [2024-11-08T17:13:51.034Z] ====== log-regression (apache-spark) [default], iteration 5 completed (8790.845 ms) ====== [2024-11-08T17:13:51.034Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-08T17:13:51.034Z] GC before operation: completed in 483.264 ms, heap usage 234.635 MB -> 112.554 MB. [2024-11-08T17:14:01.671Z] ====== log-regression (apache-spark) [default], iteration 6 completed (9702.712 ms) ====== [2024-11-08T17:14:01.671Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-08T17:14:01.671Z] GC before operation: completed in 286.047 ms, heap usage 245.934 MB -> 112.143 MB. [2024-11-08T17:14:08.920Z] ====== log-regression (apache-spark) [default], iteration 7 completed (7180.080 ms) ====== [2024-11-08T17:14:08.920Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-08T17:14:08.920Z] GC before operation: completed in 317.039 ms, heap usage 303.594 MB -> 112.522 MB. [2024-11-08T17:14:17.840Z] ====== log-regression (apache-spark) [default], iteration 8 completed (9106.360 ms) ====== [2024-11-08T17:14:17.840Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-08T17:14:17.840Z] GC before operation: completed in 254.202 ms, heap usage 249.820 MB -> 112.573 MB. [2024-11-08T17:14:28.651Z] ====== log-regression (apache-spark) [default], iteration 9 completed (10004.428 ms) ====== [2024-11-08T17:14:28.651Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-08T17:14:28.651Z] GC before operation: completed in 544.538 ms, heap usage 284.926 MB -> 112.795 MB. [2024-11-08T17:14:38.262Z] ====== log-regression (apache-spark) [default], iteration 10 completed (9082.838 ms) ====== [2024-11-08T17:14:38.262Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-08T17:14:38.262Z] GC before operation: completed in 557.860 ms, heap usage 243.083 MB -> 112.930 MB. [2024-11-08T17:14:44.885Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6895.116 ms) ====== [2024-11-08T17:14:44.885Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-08T17:14:44.885Z] GC before operation: completed in 144.662 ms, heap usage 229.285 MB -> 113.014 MB. [2024-11-08T17:14:50.769Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5636.819 ms) ====== [2024-11-08T17:14:50.769Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-08T17:14:50.769Z] GC before operation: completed in 222.955 ms, heap usage 255.380 MB -> 113.317 MB. [2024-11-08T17:14:57.905Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6247.996 ms) ====== [2024-11-08T17:14:57.905Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-08T17:14:57.905Z] GC before operation: completed in 313.653 ms, heap usage 230.875 MB -> 113.432 MB. [2024-11-08T17:15:03.914Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6630.080 ms) ====== [2024-11-08T17:15:03.914Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-08T17:15:04.635Z] GC before operation: completed in 413.516 ms, heap usage 262.997 MB -> 113.682 MB. [2024-11-08T17:15:13.529Z] ====== log-regression (apache-spark) [default], iteration 15 completed (8368.666 ms) ====== [2024-11-08T17:15:13.529Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-08T17:15:13.529Z] GC before operation: completed in 638.695 ms, heap usage 236.201 MB -> 113.790 MB. [2024-11-08T17:15:20.853Z] ====== log-regression (apache-spark) [default], iteration 16 completed (7380.965 ms) ====== [2024-11-08T17:15:20.853Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-08T17:15:21.546Z] GC before operation: completed in 263.038 ms, heap usage 246.404 MB -> 114.022 MB. [2024-11-08T17:15:27.604Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6017.752 ms) ====== [2024-11-08T17:15:27.604Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-08T17:15:27.604Z] GC before operation: completed in 329.314 ms, heap usage 284.245 MB -> 114.275 MB. [2024-11-08T17:15:35.802Z] ====== log-regression (apache-spark) [default], iteration 18 completed (7887.990 ms) ====== [2024-11-08T17:15:35.802Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-08T17:15:35.802Z] GC before operation: completed in 197.689 ms, heap usage 256.549 MB -> 114.389 MB. [2024-11-08T17:15:43.675Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6555.603 ms) ====== [2024-11-08T17:15:44.683Z] ----------------------------------- [2024-11-08T17:15:44.683Z] renaissance-log-regression_0_PASSED [2024-11-08T17:15:44.683Z] ----------------------------------- [2024-11-08T17:15:44.683Z] [2024-11-08T17:15:44.683Z] TEST TEARDOWN: [2024-11-08T17:15:44.683Z] Nothing to be done for teardown. [2024-11-08T17:15:44.683Z] renaissance-log-regression_0 Finish Time: Fri Nov 8 12:15:43 2024 Epoch Time (ms): 1731086143125