[2025-07-02T14:33:10.042Z] Running test renaissance-log-regression_0 ... [2025-07-02T14:33:10.042Z] =============================================== [2025-07-02T14:33:10.464Z] renaissance-log-regression_0 Start Time: Wed Jul 2 07:33:09 2025 Epoch Time (ms): 1751466789856 [2025-07-02T14:33:10.464Z] variation: NoOptions [2025-07-02T14:33:10.464Z] JVM_OPTIONS: [2025-07-02T14:33:10.464Z] { \ [2025-07-02T14:33:10.464Z] echo ""; echo "TEST SETUP:"; \ [2025-07-02T14:33:10.464Z] echo "Nothing to be done for setup."; \ [2025-07-02T14:33:10.464Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17514630661805/renaissance-log-regression_0"; \ [2025-07-02T14:33:10.464Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17514630661805/renaissance-log-regression_0"; \ [2025-07-02T14:33:10.464Z] echo ""; echo "TESTING:"; \ [2025-07-02T14:33:10.464Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17514630661805/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-02T14:33:10.464Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17514630661805/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-02T14:33:10.464Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-02T14:33:10.464Z] echo "Nothing to be done for teardown."; \ [2025-07-02T14:33:10.464Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17514630661805/TestTargetResult"; [2025-07-02T14:33:10.464Z] [2025-07-02T14:33:10.464Z] TEST SETUP: [2025-07-02T14:33:10.465Z] Nothing to be done for setup. [2025-07-02T14:33:10.465Z] [2025-07-02T14:33:10.465Z] TESTING: [2025-07-02T14:33:27.999Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-07-02T14:33:36.572Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-02T14:33:36.573Z] GC before operation: completed in 94.667 ms, heap usage 68.389 MB -> 34.742 MB. [2025-07-02T14:34:06.847Z] ====== log-regression (apache-spark) [default], iteration 0 completed (27667.610 ms) ====== [2025-07-02T14:34:06.847Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-02T14:34:06.847Z] GC before operation: completed in 244.128 ms, heap usage 830.718 MB -> 118.525 MB. [2025-07-02T14:34:10.691Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7035.631 ms) ====== [2025-07-02T14:34:10.691Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-02T14:34:10.691Z] GC before operation: completed in 175.835 ms, heap usage 362.217 MB -> 117.740 MB. [2025-07-02T14:34:16.772Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5953.465 ms) ====== [2025-07-02T14:34:16.772Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-02T14:34:16.772Z] GC before operation: completed in 123.536 ms, heap usage 373.372 MB -> 118.259 MB. [2025-07-02T14:34:24.031Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6166.758 ms) ====== [2025-07-02T14:34:24.031Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-02T14:34:24.031Z] GC before operation: completed in 183.495 ms, heap usage 226.294 MB -> 118.142 MB. [2025-07-02T14:34:28.823Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5198.144 ms) ====== [2025-07-02T14:34:28.823Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-02T14:34:28.823Z] GC before operation: completed in 227.599 ms, heap usage 450.143 MB -> 118.948 MB. [2025-07-02T14:34:33.907Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4730.649 ms) ====== [2025-07-02T14:34:33.907Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-02T14:34:33.907Z] GC before operation: completed in 158.394 ms, heap usage 334.273 MB -> 118.841 MB. [2025-07-02T14:34:37.833Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4268.887 ms) ====== [2025-07-02T14:34:37.833Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-02T14:34:38.289Z] GC before operation: completed in 183.964 ms, heap usage 267.756 MB -> 118.839 MB. [2025-07-02T14:34:42.163Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3972.842 ms) ====== [2025-07-02T14:34:42.163Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-02T14:34:42.163Z] GC before operation: completed in 228.323 ms, heap usage 374.179 MB -> 119.394 MB. [2025-07-02T14:34:46.599Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4089.063 ms) ====== [2025-07-02T14:34:46.599Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-02T14:34:46.599Z] GC before operation: completed in 201.889 ms, heap usage 485.432 MB -> 119.990 MB. [2025-07-02T14:34:51.422Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4279.709 ms) ====== [2025-07-02T14:34:51.422Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-02T14:34:51.422Z] GC before operation: completed in 190.088 ms, heap usage 451.337 MB -> 120.137 MB. [2025-07-02T14:34:56.368Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4868.276 ms) ====== [2025-07-02T14:34:56.368Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-02T14:34:56.368Z] GC before operation: completed in 220.333 ms, heap usage 274.141 MB -> 119.687 MB. [2025-07-02T14:35:01.601Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4799.012 ms) ====== [2025-07-02T14:35:01.601Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-02T14:35:01.601Z] GC before operation: completed in 325.704 ms, heap usage 372.342 MB -> 120.240 MB. [2025-07-02T14:35:05.954Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4671.886 ms) ====== [2025-07-02T14:35:05.954Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-02T14:35:06.516Z] GC before operation: completed in 251.747 ms, heap usage 378.891 MB -> 120.465 MB. Calling Pipeline was cancelled [2025-07-02T14:35:08.283Z] Sending interrupt signal to process [2025-07-02T14:35:09.254Z] make[4]: *** [renaissance-log-regression_0] Terminated: 15 [2025-07-02T14:35:09.254Z] make[3]: *** [extended.perf-renaissance] Error 2 [2025-07-02T14:35:09.254Z] make[2]: *** [extended.perf-perf] Error 2 [2025-07-02T14:35:09.254Z] make[1]: *** [extended.perf-..] Terminated: 15 [2025-07-02T14:35:09.254Z] make: *** [_extended.perf] Error 2 [2025-07-02T14:35:09.422Z] script returned exit code 2 [Pipeline] sh [2025-07-02T14:35:10.948Z] ++ uname [2025-07-02T14:35:10.948Z] + '[' Darwin = AIX ']' [2025-07-02T14:35:10.948Z] ++ uname [2025-07-02T14:35:10.948Z] + '[' Darwin = SunOS ']' [2025-07-02T14:35:10.948Z] ++ uname [2025-07-02T14:35:10.948Z] + '[' Darwin = '*BSD' ']' [2025-07-02T14:35:10.948Z] + MAKE=make [2025-07-02T14:35:10.948Z] + make -f ./aqa-tests/TKG/testEnv.mk testEnvTeardown [2025-07-02T14:35:10.948Z] make: Nothing to be done for `testEnvTeardown'. [Pipeline] } [2025-07-02T14:35:10.872Z] $ ssh-agent -k [2025-07-02T14:35:10.946Z] unset SSH_AUTH_SOCK; [2025-07-02T14:35:10.946Z] unset SSH_AGENT_PID; [2025-07-02T14:35:10.946Z] echo Agent pid 3648 killed; [2025-07-02T14:35:11.251Z] [ssh-agent] Stopped. [Pipeline] // sshagent [Pipeline] echo [2025-07-02T14:35:11.301Z] no DaCapo-h2 metric found [Pipeline] echo [2025-07-02T14:35:11.317Z] Could not find test result, set build result to FAILURE. [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Post) [Pipeline] step [2025-07-02T14:35:11.417Z] TAP Reports Processing: START [2025-07-02T14:35:11.418Z] Looking for TAP results report in workspace using pattern: aqa-tests/TKG/**/*.tap [2025-07-02T14:35:11.509Z] Did not find any matching files. Setting build result to FAILURE. [Pipeline] echo [2025-07-02T14:35:11.525Z] Saving aqa-tests/testenv/testenv.properties file on jenkins. [Pipeline] archiveArtifacts [2025-07-02T14:35:11.565Z] Archiving artifacts [2025-07-02T14:35:12.055Z] Recording fingerprints [Pipeline] echo [2025-07-02T14:35:12.134Z] Saving aqa-tests/TKG/**/*.tap file on jenkins. [Pipeline] archiveArtifacts [2025-07-02T14:35:12.166Z] Archiving artifacts [Pipeline] sh [2025-07-02T14:35:12.906Z] + tar -cf benchmark_test_output.tar.gz ./aqa-tests/TKG/output_17514630661805 [Pipeline] echo [2025-07-02T14:35:12.989Z] ARTIFACTORY_SERVER is not set. Saving artifacts on jenkins. [Pipeline] archiveArtifacts [2025-07-02T14:35:13.026Z] Archiving artifacts [2025-07-02T14:35:13.109Z] Recording fingerprints [Pipeline] findFiles [Pipeline] junit [2025-07-02T14:35:14.434Z] Recording test results [2025-07-02T14:35:16.833Z] No test report files were found. Configuration error? [2025-07-02T14:35:19.337Z] [Checks API] No suitable checks publisher found. [Pipeline] } [Pipeline] // stage [Pipeline] echo [2025-07-02T14:35:19.369Z] PROCESSCATCH: Terminating any hung/left over test processes: [Pipeline] sh [2025-07-02T14:35:19.902Z] + aqa-tests/terminateTestProcesses.sh admin [2025-07-02T14:35:19.902Z] Mac type machine.. do not terminate any remaining processes as Orka mac VMs are not process isolated, see: https://github.com/adoptium/aqa-tests/issues/4964 [Pipeline] cleanWs [2025-07-02T14:35:20.045Z] [WS-CLEANUP] Deleting project workspace... [2025-07-02T14:35:20.045Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... Click here to forcibly terminate running steps [2025-07-02T14:35:29.788Z] [WS-CLEANUP] done [Pipeline] sh [2025-07-02T14:35:30.376Z] + find /cores -name '*core*' -print -exec rm -f '{}' ';' [2025-07-02T14:35:30.376Z] /cores [Pipeline] } [Pipeline] // timeout [Pipeline] timeout [2025-07-02T14:35:30.531Z] Timeout set to expire in 5 min 0 sec [Pipeline] { [Pipeline] echo [2025-07-02T14:35:30.545Z] Test_openjdk17_hs_extended.perf_x86-64_mac #202 result is FAILURE. Checking console log for specific errors... [Pipeline] } [Pipeline] // timeout [Pipeline] } [Pipeline] // node [Pipeline] } [Pipeline] // stage [Pipeline] } [Pipeline] // timestamps [Pipeline] End of Pipeline Finished: ABORTED