[2024-07-31T22:15:43.931Z] Running test renaissance-log-regression_0 ... [2024-07-31T22:15:43.931Z] =============================================== [2024-07-31T22:15:43.931Z] renaissance-log-regression_0 Start Time: Wed Jul 31 22:15:43 2024 Epoch Time (ms): 1722464143455 [2024-07-31T22:15:43.931Z] variation: NoOptions [2024-07-31T22:15:43.931Z] JVM_OPTIONS: [2024-07-31T22:15:43.931Z] { \ [2024-07-31T22:15:43.931Z] echo ""; echo "TEST SETUP:"; \ [2024-07-31T22:15:43.931Z] echo "Nothing to be done for setup."; \ [2024-07-31T22:15:43.931Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224635699248/renaissance-log-regression_0"; \ [2024-07-31T22:15:43.931Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224635699248/renaissance-log-regression_0"; \ [2024-07-31T22:15:43.931Z] echo ""; echo "TESTING:"; \ [2024-07-31T22:15:43.931Z] "/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_17224635699248/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-07-31T22:15:43.931Z] 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_17224635699248/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-07-31T22:15:43.931Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-07-31T22:15:43.931Z] echo "Nothing to be done for teardown."; \ [2024-07-31T22:15:43.931Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224635699248/TestTargetResult"; [2024-07-31T22:15:43.931Z] [2024-07-31T22:15:43.931Z] TEST SETUP: [2024-07-31T22:15:43.931Z] Nothing to be done for setup. [2024-07-31T22:15:43.931Z] [2024-07-31T22:15:43.931Z] TESTING: [2024-07-31T22:15:45.853Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-07-31T22:15:47.081Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-07-31T22:15:49.777Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-07-31T22:15:49.777Z] GC before operation: completed in 42.521 ms, heap usage 62.051 MB -> 35.104 MB. [2024-07-31T22:15:56.611Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6370.495 ms) ====== [2024-07-31T22:15:56.611Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-07-31T22:15:56.611Z] GC before operation: completed in 83.942 ms, heap usage 335.172 MB -> 110.556 MB. [2024-07-31T22:15:58.536Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2220.922 ms) ====== [2024-07-31T22:15:58.536Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-07-31T22:15:58.536Z] GC before operation: completed in 59.817 ms, heap usage 265.446 MB -> 110.850 MB. [2024-07-31T22:16:00.459Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1939.574 ms) ====== [2024-07-31T22:16:00.459Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-07-31T22:16:00.459Z] GC before operation: completed in 69.131 ms, heap usage 303.613 MB -> 111.346 MB. [2024-07-31T22:16:02.380Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2018.878 ms) ====== [2024-07-31T22:16:02.380Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-07-31T22:16:02.380Z] GC before operation: completed in 88.630 ms, heap usage 246.958 MB -> 111.492 MB. [2024-07-31T22:16:04.301Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1644.803 ms) ====== [2024-07-31T22:16:04.301Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-07-31T22:16:04.301Z] GC before operation: completed in 65.455 ms, heap usage 275.850 MB -> 111.742 MB. [2024-07-31T22:16:05.526Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1608.323 ms) ====== [2024-07-31T22:16:05.526Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-07-31T22:16:06.124Z] GC before operation: completed in 64.906 ms, heap usage 281.394 MB -> 112.006 MB. [2024-07-31T22:16:07.351Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1561.795 ms) ====== [2024-07-31T22:16:07.351Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-07-31T22:16:07.351Z] GC before operation: completed in 63.051 ms, heap usage 280.711 MB -> 112.268 MB. [2024-07-31T22:16:09.283Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1513.043 ms) ====== [2024-07-31T22:16:09.283Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-07-31T22:16:09.283Z] GC before operation: completed in 113.412 ms, heap usage 273.578 MB -> 112.392 MB. [2024-07-31T22:16:10.594Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1715.596 ms) ====== [2024-07-31T22:16:10.594Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-07-31T22:16:10.594Z] GC before operation: completed in 68.807 ms, heap usage 273.340 MB -> 112.556 MB. [2024-07-31T22:16:13.286Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1937.870 ms) ====== [2024-07-31T22:16:13.286Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-07-31T22:16:13.286Z] GC before operation: completed in 80.570 ms, heap usage 281.535 MB -> 112.831 MB. [2024-07-31T22:16:14.538Z] 24/07/31 22:16:14 WARN BlockManager: Asked to remove block broadcast_514, which does not exist [2024-07-31T22:16:14.538Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1505.667 ms) ====== [2024-07-31T22:16:14.538Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-07-31T22:16:14.538Z] GC before operation: completed in 60.922 ms, heap usage 236.960 MB -> 112.809 MB. [2024-07-31T22:16:15.765Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1427.617 ms) ====== [2024-07-31T22:16:15.765Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-07-31T22:16:15.765Z] GC before operation: completed in 84.549 ms, heap usage 245.167 MB -> 113.017 MB. [2024-07-31T22:16:17.686Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1617.832 ms) ====== [2024-07-31T22:16:17.686Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-07-31T22:16:17.686Z] GC before operation: completed in 90.614 ms, heap usage 237.172 MB -> 113.132 MB. [2024-07-31T22:16:18.911Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1474.722 ms) ====== [2024-07-31T22:16:18.911Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-07-31T22:16:19.507Z] GC before operation: completed in 62.645 ms, heap usage 291.357 MB -> 113.491 MB. [2024-07-31T22:16:20.094Z] 24/07/31 22:16:19 WARN BlockManager: Asked to remove block broadcast_676, which does not exist [2024-07-31T22:16:20.688Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1535.935 ms) ====== [2024-07-31T22:16:20.688Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-07-31T22:16:20.688Z] GC before operation: completed in 85.062 ms, heap usage 280.048 MB -> 113.591 MB. [2024-07-31T22:16:22.608Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1460.778 ms) ====== [2024-07-31T22:16:22.608Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-07-31T22:16:22.608Z] GC before operation: completed in 84.167 ms, heap usage 281.522 MB -> 113.876 MB. [2024-07-31T22:16:23.833Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1531.224 ms) ====== [2024-07-31T22:16:23.834Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-07-31T22:16:23.834Z] GC before operation: completed in 99.879 ms, heap usage 261.924 MB -> 113.991 MB. [2024-07-31T22:16:25.757Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1702.098 ms) ====== [2024-07-31T22:16:25.757Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-07-31T22:16:25.757Z] GC before operation: completed in 73.628 ms, heap usage 280.168 MB -> 114.201 MB. [2024-07-31T22:16:27.688Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1507.472 ms) ====== [2024-07-31T22:16:27.688Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-07-31T22:16:27.688Z] GC before operation: completed in 99.669 ms, heap usage 262.499 MB -> 114.355 MB. [2024-07-31T22:16:28.913Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1552.458 ms) ====== [2024-07-31T22:16:29.506Z] ----------------------------------- [2024-07-31T22:16:29.507Z] renaissance-log-regression_0_PASSED [2024-07-31T22:16:29.507Z] ----------------------------------- [2024-07-31T22:16:29.507Z] [2024-07-31T22:16:29.507Z] TEST TEARDOWN: [2024-07-31T22:16:29.507Z] Nothing to be done for teardown. [2024-07-31T22:16:29.507Z] renaissance-log-regression_0 Finish Time: Wed Jul 31 22:16:28 2024 Epoch Time (ms): 1722464188910