[2025-05-30T02:11:56.708Z] Running test renaissance-log-regression_0 ... [2025-05-30T02:11:56.708Z] =============================================== [2025-05-30T02:11:56.708Z] renaissance-log-regression_0 Start Time: Fri May 30 02:11:42 2025 Epoch Time (ms): 1748571102289 [2025-05-30T02:11:56.708Z] variation: NoOptions [2025-05-30T02:11:56.708Z] JVM_OPTIONS: [2025-05-30T02:11:56.708Z] { \ [2025-05-30T02:11:56.708Z] echo ""; echo "TEST SETUP:"; \ [2025-05-30T02:11:56.708Z] echo "Nothing to be done for setup."; \ [2025-05-30T02:11:56.708Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"; \ [2025-05-30T02:11:56.708Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"; \ [2025-05-30T02:11:56.708Z] echo ""; echo "TESTING:"; \ [2025-05-30T02:11:56.708Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-30T02:11:56.708Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-30T02:11:56.708Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-30T02:11:56.708Z] echo "Nothing to be done for teardown."; \ [2025-05-30T02:11:56.708Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/TestTargetResult"; [2025-05-30T02:11:56.708Z] [2025-05-30T02:11:56.708Z] TEST SETUP: [2025-05-30T02:11:56.708Z] Nothing to be done for setup. [2025-05-30T02:11:56.708Z] [2025-05-30T02:11:56.708Z] TESTING: [2025-05-30T02:11:56.708Z] make[4]: *** [autoGen.mk:214: renaissance-log-regression_0] Terminated [2025-05-30T02:11:56.708Z] make[3]: *** [/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/settings.mk:362: extended.perf-renaissance] Error 143 [2025-05-30T02:11:56.708Z] make[3]: Leaving directory '/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/perf' [2025-05-30T02:11:56.708Z] make[2]: *** [/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/settings.mk:362: extended.perf-perf] Error 2 [2025-05-30T02:11:56.708Z] make[2]: Leaving directory '/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests' [2025-05-30T02:11:56.708Z] make[1]: *** [settings.mk:362: extended.perf-..] Error 2 [2025-05-30T02:11:56.708Z] make[1]: Leaving directory '/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG' [2025-05-30T02:11:56.708Z] make: *** [makefile:62: _extended.perf] Error 2 [2025-05-30T02:11:56.708Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-30T02:11:56.708Z] WARNING: An illegal reflective access operation has occurred [2025-05-30T02:11:56.708Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17484812908539/renaissance-log-regression_0/harness-021143-16279821288355884265/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-30T02:11:56.708Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-30T02:11:56.708Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-30T02:11:56.708Z] WARNING: All illegal access operations will be denied in a future release [2025-05-30T02:11:56.708Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-30T02:11:56.708Z] GC before operation: completed in 113.970 ms, heap usage 59.835 MB -> 34.167 MB. [2025-05-30T02:11:56.766Z] script returned exit code 2 [Pipeline] sh [2025-05-30T02:11:57.339Z] ++ uname [2025-05-30T02:11:57.340Z] + '[' Linux = AIX ']' [2025-05-30T02:11:57.340Z] ++ uname [2025-05-30T02:11:57.340Z] + '[' Linux = SunOS ']' [2025-05-30T02:11:57.340Z] ++ uname [2025-05-30T02:11:57.340Z] + '[' Linux = '*BSD' ']' [2025-05-30T02:11:57.340Z] + MAKE=make [2025-05-30T02:11:57.340Z] + make -f ./aqa-tests/TKG/testEnv.mk testEnvTeardown [2025-05-30T02:11:57.340Z] make: Nothing to be done for 'testEnvTeardown'. [Pipeline] } [2025-05-30T02:11:57.396Z] Xvfb stopping [Pipeline] // wrap [Pipeline] echo [2025-05-30T02:11:57.504Z] Could not find test result, set build result to FAILURE. [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Post) [Pipeline] step [2025-05-30T02:11:57.593Z] TAP Reports Processing: START [2025-05-30T02:11:57.593Z] Looking for TAP results report in workspace using pattern: aqa-tests/TKG/**/*.tap [2025-05-30T02:11:57.674Z] Did not find any matching files. Setting build result to FAILURE. [Pipeline] echo [2025-05-30T02:11:57.684Z] Saving aqa-tests/testenv/testenv.properties file on jenkins. [Pipeline] archiveArtifacts [2025-05-30T02:11:57.712Z] Archiving artifacts [2025-05-30T02:11:57.761Z] Recording fingerprints [Pipeline] echo [2025-05-30T02:11:57.800Z] Saving aqa-tests/TKG/**/*.tap file on jenkins. [Pipeline] archiveArtifacts [2025-05-30T02:11:57.826Z] Archiving artifacts [Pipeline] sh [2025-05-30T02:11:58.332Z] + tar -cf benchmark_test_output.tar.gz ./aqa-tests/TKG/output_17484812908539 [Pipeline] echo [2025-05-30T02:11:59.525Z] ARTIFACTORY_SERVER is not set. Saving artifacts on jenkins. [Pipeline] archiveArtifacts [2025-05-30T02:11:59.554Z] Archiving artifacts [2025-05-30T02:12:41.639Z] Body did not finish within grace period; terminating with extreme prejudice [Pipeline] } [Pipeline] // stage [Pipeline] echo [2025-05-30T02:12:41.684Z] PROCESSCATCH: Terminating any hung/left over test processes: [Pipeline] sh [2025-05-30T02:12:42.153Z] + aqa-tests/terminateTestProcesses.sh jenkins [2025-05-30T02:12:42.153Z] Unix type machine.. [2025-05-30T02:12:42.153Z] Running on a Linux host [2025-05-30T02:12:42.153Z] 0::/user.slice/user-1001.sl 2887556 S ? 00:00:00 /bin/sh -c { \ echo "";?echo "TEST SETUP:"; \ echo "Nothing to be done for setup."; \ mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"; \ cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"; \ echo "";?echo "TESTING:"; \ "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"/log-regression.json" log-regression; \ ?if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ echo "";?echo "TEST TEARDOWN:"; \ echo "Nothing to be done for teardown."; \ } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/TestTargetResult"; [2025-05-30T02:12:42.154Z] 0::/user.slice/user-1001.sl 2887559 S ? 00:01:20 /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar --json /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484812908539/renaissance-log-regression_0/log-regression.json log-regression [2025-05-30T02:12:42.154Z] Boooo - there are rogue processes kicking about [2025-05-30T02:12:42.154Z] Issuing a kill to all processes shown above [2025-05-30T02:12:42.154Z] Sleeping for 10 seconds... [2025-05-30T02:12:51.209Z] Recording fingerprints [2025-05-30T02:12:52.942Z] Processes stopped successfully [Pipeline] cleanWs [2025-05-30T02:12:53.050Z] [WS-CLEANUP] Deleting project workspace... [2025-05-30T02:12:53.050Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2025-05-30T02:12:59.356Z] [WS-CLEANUP] done [Pipeline] sh [2025-05-30T02:12:59.848Z] + find /tmp -name '*core*' -print -exec rm -f '{}' ';' [2025-05-30T02:12:59.848Z] + true [Pipeline] } [Pipeline] // timeout [Pipeline] echo [2025-05-30T02:12:59.944Z] Exception: org.jenkinsci.plugins.workflow.steps.FlowInterruptedException [Pipeline] timeout [2025-05-30T02:12:59.949Z] Timeout set to expire in 5 min 0 sec [Pipeline] { [Pipeline] } [Pipeline] // timeout [Pipeline] } [Pipeline] // node [Pipeline] } [Pipeline] // stage [Pipeline] echo [2025-05-30T02:13:00.084Z] SETUP_LABEL: ci.role.test [Pipeline] stage [Pipeline] { (Parallel Tests) [Pipeline] parallel [2025-05-30T02:13:00.122Z] No branches to run [Pipeline] // parallel [Pipeline] node [2025-05-30T02:13:00.172Z] Running on test-docker-ubi9-armv8l-1 in /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux [Pipeline] { [Pipeline] cleanWs [2025-05-30T02:13:00.459Z] [WS-CLEANUP] Deleting project workspace... [2025-05-30T02:13:00.459Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2025-05-30T02:13:00.588Z] [WS-CLEANUP] done [Pipeline] findFiles [Pipeline] cleanWs [2025-05-30T02:13:00.998Z] [WS-CLEANUP] Deleting project workspace... [2025-05-30T02:13:00.999Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2025-05-30T02:13:01.128Z] [WS-CLEANUP] done [Pipeline] } [Pipeline] // node [Pipeline] } [Pipeline] // stage [Pipeline] } [Pipeline] // timestamps [Pipeline] End of Pipeline Finished: ABORTED