[2025-08-25T21:56:00.407Z] Running test renaissance-log-regression_0 ... [2025-08-25T21:56:00.407Z] =============================================== [2025-08-25T21:56:00.407Z] renaissance-log-regression_0 Start Time: Mon Aug 25 21:55:59 2025 Epoch Time (ms): 1756158959733 [2025-08-25T21:56:00.407Z] variation: NoOptions [2025-08-25T21:56:00.407Z] JVM_OPTIONS: [2025-08-25T21:56:00.407Z] { \ [2025-08-25T21:56:00.407Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T21:56:00.407Z] echo "Nothing to be done for setup."; \ [2025-08-25T21:56:00.407Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17561575395701/renaissance-log-regression_0"; \ [2025-08-25T21:56:00.407Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17561575395701/renaissance-log-regression_0"; \ [2025-08-25T21:56:00.407Z] echo ""; echo "TESTING:"; \ [2025-08-25T21:56:00.407Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_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_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17561575395701/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-25T21:56:00.408Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17561575395701/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T21:56:00.408Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T21:56:00.408Z] echo "Nothing to be done for teardown."; \ [2025-08-25T21:56:00.408Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17561575395701/TestTargetResult"; [2025-08-25T21:56:00.408Z] [2025-08-25T21:56:00.408Z] TEST SETUP: [2025-08-25T21:56:00.408Z] Nothing to be done for setup. [2025-08-25T21:56:00.408Z] [2025-08-25T21:56:00.408Z] TESTING: [2025-08-25T21:56:05.774Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-25T21:56:07.726Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T21:56:07.726Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17561575395701/renaissance-log-regression_0/harness-215600-247219491224378977/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T21:56:07.726Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T21:56:07.726Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T21:56:07.726Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T21:56:09.677Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-25T21:56:09.678Z] GC before operation: completed in 52.909 ms, heap usage 50.844 MB -> 34.394 MB. [2025-08-25T21:56:21.945Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11659.887 ms) ====== [2025-08-25T21:56:21.945Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-25T21:56:21.945Z] GC before operation: completed in 103.360 ms, heap usage 443.017 MB -> 118.873 MB. [2025-08-25T21:56:23.900Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2711.584 ms) ====== [2025-08-25T21:56:23.900Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-25T21:56:23.900Z] GC before operation: completed in 94.212 ms, heap usage 273.409 MB -> 118.888 MB. [2025-08-25T21:56:25.850Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2131.733 ms) ====== [2025-08-25T21:56:25.850Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-25T21:56:25.850Z] GC before operation: completed in 83.941 ms, heap usage 300.349 MB -> 119.350 MB. [2025-08-25T21:56:27.804Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2034.588 ms) ====== [2025-08-25T21:56:27.804Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-25T21:56:27.804Z] GC before operation: completed in 88.715 ms, heap usage 340.916 MB -> 119.686 MB. [2025-08-25T21:56:29.758Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1847.232 ms) ====== [2025-08-25T21:56:29.758Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-25T21:56:29.758Z] GC before operation: completed in 86.711 ms, heap usage 282.832 MB -> 119.742 MB. [2025-08-25T21:56:31.710Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1931.484 ms) ====== [2025-08-25T21:56:31.711Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-25T21:56:31.711Z] GC before operation: completed in 89.971 ms, heap usage 356.344 MB -> 120.182 MB. [2025-08-25T21:56:33.664Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1832.831 ms) ====== [2025-08-25T21:56:33.664Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-25T21:56:33.664Z] GC before operation: completed in 86.663 ms, heap usage 238.519 MB -> 120.027 MB. [2025-08-25T21:56:35.618Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1847.593 ms) ====== [2025-08-25T21:56:35.618Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-25T21:56:35.618Z] GC before operation: completed in 87.888 ms, heap usage 318.874 MB -> 120.485 MB. [2025-08-25T21:56:37.572Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1849.628 ms) ====== [2025-08-25T21:56:37.572Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-25T21:56:37.572Z] GC before operation: completed in 91.146 ms, heap usage 347.295 MB -> 120.783 MB. [2025-08-25T21:56:39.524Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1890.197 ms) ====== [2025-08-25T21:56:39.524Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-25T21:56:39.524Z] GC before operation: completed in 91.819 ms, heap usage 311.729 MB -> 120.915 MB. [2025-08-25T21:56:41.485Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1817.976 ms) ====== [2025-08-25T21:56:41.485Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-25T21:56:41.485Z] GC before operation: completed in 96.468 ms, heap usage 282.420 MB -> 120.947 MB. [2025-08-25T21:56:43.438Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1819.731 ms) ====== [2025-08-25T21:56:43.438Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-25T21:56:43.438Z] GC before operation: completed in 97.583 ms, heap usage 373.244 MB -> 121.472 MB. [2025-08-25T21:56:45.396Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1831.730 ms) ====== [2025-08-25T21:56:45.396Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-25T21:56:45.396Z] GC before operation: completed in 94.567 ms, heap usage 237.965 MB -> 121.234 MB. [2025-08-25T21:56:47.352Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1818.701 ms) ====== [2025-08-25T21:56:47.352Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-25T21:56:47.352Z] GC before operation: completed in 92.306 ms, heap usage 282.503 MB -> 121.598 MB. [2025-08-25T21:56:49.305Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1712.713 ms) ====== [2025-08-25T21:56:49.305Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-25T21:56:49.305Z] GC before operation: completed in 93.960 ms, heap usage 427.334 MB -> 122.206 MB. [2025-08-25T21:56:51.260Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1767.325 ms) ====== [2025-08-25T21:56:51.260Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-25T21:56:51.260Z] GC before operation: completed in 94.899 ms, heap usage 261.943 MB -> 121.951 MB. [2025-08-25T21:56:53.215Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1779.675 ms) ====== [2025-08-25T21:56:53.215Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-25T21:56:53.215Z] GC before operation: completed in 113.345 ms, heap usage 287.381 MB -> 122.249 MB. [2025-08-25T21:56:55.195Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1839.935 ms) ====== [2025-08-25T21:56:55.195Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-25T21:56:55.195Z] GC before operation: completed in 111.274 ms, heap usage 341.234 MB -> 122.608 MB. [2025-08-25T21:56:57.147Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1903.615 ms) ====== [2025-08-25T21:56:57.147Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-25T21:56:57.147Z] GC before operation: completed in 112.724 ms, heap usage 283.042 MB -> 122.561 MB. [2025-08-25T21:56:59.100Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1938.065 ms) ====== [2025-08-25T21:56:59.100Z] ----------------------------------- [2025-08-25T21:56:59.100Z] renaissance-log-regression_0_PASSED [2025-08-25T21:56:59.100Z] ----------------------------------- [2025-08-25T21:56:59.100Z] [2025-08-25T21:56:59.100Z] TEST TEARDOWN: [2025-08-25T21:56:59.100Z] Nothing to be done for teardown. [2025-08-25T21:56:59.100Z] renaissance-log-regression_0 Finish Time: Mon Aug 25 21:56:58 2025 Epoch Time (ms): 1756159018854