[2024-10-30T22:14:22.347Z] Running test renaissance-finagle-http_0 ... [2024-10-30T22:14:22.347Z] =============================================== [2024-10-30T22:14:22.347Z] renaissance-finagle-http_0 Start Time: Wed Oct 30 22:14:22 2024 Epoch Time (ms): 1730326462096 [2024-10-30T22:14:22.347Z] variation: NoOptions [2024-10-30T22:14:22.347Z] JVM_OPTIONS: [2024-10-30T22:14:22.347Z] { \ [2024-10-30T22:14:22.347Z] echo ""; echo "TEST SETUP:"; \ [2024-10-30T22:14:22.347Z] echo "Nothing to be done for setup."; \ [2024-10-30T22:14:22.347Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17303264601530/renaissance-finagle-http_0"; \ [2024-10-30T22:14:22.347Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17303264601530/renaissance-finagle-http_0"; \ [2024-10-30T22:14:22.347Z] echo ""; echo "TESTING:"; \ [2024-10-30T22:14:22.347Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_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_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17303264601530/renaissance-finagle-http_0"/finagle-http.json" finagle-http; \ [2024-10-30T22:14:22.347Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-finagle-http_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17303264601530/renaissance-finagle-http_0"; else echo "-----------------------------------"; echo "renaissance-finagle-http_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-30T22:14:22.347Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-30T22:14:22.347Z] echo "Nothing to be done for teardown."; \ [2024-10-30T22:14:22.347Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17303264601530/TestTargetResult"; [2024-10-30T22:14:22.347Z] [2024-10-30T22:14:22.347Z] TEST SETUP: [2024-10-30T22:14:22.347Z] Nothing to be done for setup. [2024-10-30T22:14:22.347Z] [2024-10-30T22:14:22.347Z] TESTING: [2024-10-30T22:14:33.175Z] [2024-10-30T22:14:31.517+0000] com.twitter.finagle.http.HttpMuxer$ (com.twitter.finagle.http.HttpMuxer$ $anonfun$new$1) [2024-10-30T22:14:33.175Z] INFO: HttpMuxer[/admin/metrics.json] = com.twitter.finagle.stats.MetricsExporter(com.twitter.finagle.stats.MetricsExporter) [2024-10-30T22:14:33.175Z] [2024-10-30T22:14:31.596+0000] com.twitter.finagle.http.HttpMuxer$ (com.twitter.finagle.http.HttpMuxer$ $anonfun$new$1) [2024-10-30T22:14:33.175Z] INFO: HttpMuxer[/admin/per_host_metrics.json] = com.twitter.finagle.stats.HostMetricsExporter(com.twitter.finagle.stats.HostMetricsExporter) [2024-10-30T22:14:33.813Z] [2024-10-30T22:14:33.316+0000] com.twitter.finagle (com.twitter.finagle.Init$ $anonfun$once$1) [2024-10-30T22:14:33.813Z] INFO: Finagle version 21.12.0 (rev=95ec9bcb3d4db4553bdb7d90f07a034a6b6be5c0) built at 20211216-002149 [2024-10-30T22:14:34.768Z] finagle-http on :40091 spawning 4 client and default number of server workers. [2024-10-30T22:14:34.768Z] ====== finagle-http (web) [default], iteration 0 started ====== [2024-10-30T22:14:34.768Z] GC before operation: completed in 130.711 ms, heap usage 14.606 MB -> 9.890 MB. [2024-10-30T22:14:34.768Z] [2024-10-30T22:14:34.553+0000] com.twitter.finagle.BaseResolver (com.twitter.finagle.BaseResolver inetResolver$lzycompute) [2024-10-30T22:14:34.768Z] INFO: Using default inet resolver [2024-10-30T22:16:35.558Z] ====== finagle-http (web) [default], iteration 0 completed (115003.981 ms) ====== [2024-10-30T22:16:35.558Z] ====== finagle-http (web) [default], iteration 1 started ====== [2024-10-30T22:16:35.558Z] GC before operation: completed in 529.950 ms, heap usage 44.791 MB -> 13.594 MB. [2024-10-30T22:17:41.900Z] ====== finagle-http (web) [default], iteration 1 completed (64262.261 ms) ====== [2024-10-30T22:17:41.901Z] ====== finagle-http (web) [default], iteration 2 started ====== [2024-10-30T22:17:41.901Z] GC before operation: completed in 543.864 ms, heap usage 21.758 MB -> 13.846 MB. [2024-10-30T22:18:21.514Z] ====== finagle-http (web) [default], iteration 2 completed (43933.102 ms) ====== [2024-10-30T22:18:21.514Z] ====== finagle-http (web) [default], iteration 3 started ====== [2024-10-30T22:18:21.514Z] GC before operation: completed in 384.464 ms, heap usage 88.546 MB -> 13.852 MB. [2024-10-30T22:19:08.639Z] ====== finagle-http (web) [default], iteration 3 completed (44238.250 ms) ====== [2024-10-30T22:19:08.639Z] ====== finagle-http (web) [default], iteration 4 started ====== [2024-10-30T22:19:08.639Z] GC before operation: completed in 362.411 ms, heap usage 54.537 MB -> 13.832 MB. [2024-10-30T22:19:48.901Z] ====== finagle-http (web) [default], iteration 4 completed (42761.906 ms) ====== [2024-10-30T22:19:48.901Z] ====== finagle-http (web) [default], iteration 5 started ====== [2024-10-30T22:19:48.901Z] GC before operation: completed in 332.428 ms, heap usage 20.800 MB -> 13.809 MB. [2024-10-30T22:20:34.213Z] ====== finagle-http (web) [default], iteration 5 completed (41935.396 ms) ====== [2024-10-30T22:20:34.213Z] ====== finagle-http (web) [default], iteration 6 started ====== [2024-10-30T22:20:34.213Z] GC before operation: completed in 357.621 ms, heap usage 53.505 MB -> 13.887 MB. [2024-10-30T22:21:15.580Z] ====== finagle-http (web) [default], iteration 6 completed (42448.406 ms) ====== [2024-10-30T22:21:15.580Z] ====== finagle-http (web) [default], iteration 7 started ====== [2024-10-30T22:21:15.580Z] GC before operation: completed in 371.226 ms, heap usage 87.378 MB -> 13.818 MB. [2024-10-30T22:22:01.261Z] ====== finagle-http (web) [default], iteration 7 completed (43349.539 ms) ====== [2024-10-30T22:22:01.261Z] ====== finagle-http (web) [default], iteration 8 started ====== [2024-10-30T22:22:01.261Z] GC before operation: completed in 359.485 ms, heap usage 51.993 MB -> 13.828 MB. [2024-10-31T22:59:21.837Z] Cancelling nested steps due to timeout [2024-10-31T22:59:22.100Z] Sending interrupt signal to process [2024-10-31T22:59:27.373Z] Terminated [2024-10-31T22:59:27.373Z] make[4]: *** [autoGen.mk:99: renaissance-finagle-http_0] Error 143 [2024-10-31T22:59:27.373Z] make[4]: Leaving directory '/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/perf/renaissance' [2024-10-31T22:59:27.373Z] make[3]: *** [/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/settings.mk:361: extended.perf-renaissance] Error 2 [2024-10-31T22:59:27.373Z] make[3]: Leaving directory '/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/perf' [2024-10-31T22:59:27.373Z] make[2]: *** [/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/settings.mk:361: extended.perf-perf] Error 2 [2024-10-31T22:59:27.373Z] make[2]: Leaving directory '/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests' [2024-10-31T22:59:27.373Z] make[1]: *** [settings.mk:361: extended.perf-..] Error 2 [2024-10-31T22:59:27.373Z] make[1]: Leaving directory '/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG' [2024-10-31T22:59:27.373Z] make: *** [makefile:70: _extended.perf] Error 2 [2024-10-31T22:59:27.672Z] script returned exit code 2 [Pipeline] sh [2024-10-31T22:59:29.866Z] + uname [2024-10-31T22:59:29.866Z] + [ Linux = AIX ] [2024-10-31T22:59:29.866Z] + uname [2024-10-31T22:59:29.866Z] + [ Linux = SunOS ] [2024-10-31T22:59:29.866Z] + uname [2024-10-31T22:59:29.866Z] + [ Linux = *BSD ] [2024-10-31T22:59:29.866Z] + MAKE=make [2024-10-31T22:59:29.866Z] + make -f ./aqa-tests/TKG/testEnv.mk testEnvTeardown [2024-10-31T22:59:29.866Z] make: Nothing to be done for 'testEnvTeardown'. [Pipeline] } [2024-10-31T22:59:30.153Z] Xvfb stopping [Pipeline] // wrap [Pipeline] echo [2024-10-31T22:59:31.036Z] Could not find test result, set build result to FAILURE. [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Post) [Pipeline] step [2024-10-31T22:59:31.273Z] TAP Reports Processing: START [2024-10-31T22:59:31.274Z] Looking for TAP results report in workspace using pattern: aqa-tests/TKG/**/*.tap [2024-10-31T22:59:32.131Z] Did not find any matching files. Setting build result to FAILURE. [Pipeline] echo [2024-10-31T22:59:32.149Z] Saving aqa-tests/testenv/testenv.properties file on jenkins. [Pipeline] archiveArtifacts [2024-10-31T22:59:32.468Z] Archiving artifacts [2024-10-31T22:59:32.810Z] Recording fingerprints [Pipeline] echo [2024-10-31T22:59:33.028Z] Saving aqa-tests/TKG/**/*.tap file on jenkins. [Pipeline] archiveArtifacts [2024-10-31T22:59:33.125Z] Archiving artifacts [Pipeline] sh [2024-10-31T22:59:36.112Z] + tar -cf benchmark_test_output.tar.gz ./aqa-tests/TKG/output_17303264601530 [Pipeline] echo [2024-10-31T22:59:36.391Z] ARTIFACTORY_SERVER is not set. Saving artifacts on jenkins. [Pipeline] archiveArtifacts [2024-10-31T22:59:36.513Z] Archiving artifacts [2024-10-31T22:59:36.767Z] Recording fingerprints [Pipeline] findFiles [Pipeline] junit [2024-10-31T22:59:44.602Z] Recording test results [2024-10-31T22:59:53.216Z] [Checks API] No suitable checks publisher found. [Pipeline] } [Pipeline] // stage [Pipeline] echo [2024-10-31T22:59:53.256Z] PROCESSCATCH: Terminating any hung/left over test processes: [Pipeline] sh [2024-10-31T22:59:53.029Z] No test report files were found. Configuration error? [2024-10-31T22:59:55.293Z] + aqa-tests/terminateTestProcesses.sh jenkins [2024-10-31T22:59:55.293Z] Unix type machine.. [2024-10-31T22:59:55.293Z] Running on a Linux host [2024-10-31T22:59:55.293Z] Woohoo - no rogue processes detected! [Pipeline] cleanWs [2024-10-31T22:59:55.805Z] [WS-CLEANUP] Deleting project workspace... [2024-10-31T22:59:55.805Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2024-10-31T23:00:21.842Z] Body did not finish within grace period; terminating with extreme prejudice [Pipeline] echo [2024-10-31T23:00:21.852Z] Exception: org.jenkinsci.plugins.workflow.steps.FlowInterruptedException [Pipeline] sh [2024-10-31T23:00:23.161Z] [WS-CLEANUP] done [2024-10-31T23:00:23.754Z] + rm -rf /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG [Pipeline] cleanWs [2024-10-31T23:00:24.245Z] [WS-CLEANUP] Deleting project workspace... [2024-10-31T23:00:24.246Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2024-10-31T23:00:24.369Z] [WS-CLEANUP] done [Pipeline] sh [2024-10-31T23:00:26.176Z] + find /tmp -name *core* -print -exec rm -f {} ; [2024-10-31T23:00:26.176Z] + true [Pipeline] } [Pipeline] // timeout [Pipeline] timeout [2024-10-31T23:00:26.494Z] Timeout set to expire in 5 min 0 sec [Pipeline] { [Pipeline] echo [2024-10-31T23:00:26.514Z] Test_openjdk21_hs_extended.perf_riscv64_linux #56 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 /home/jenkins/.jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux@tmp/jfrog/56/.jfrog deleted Finished: FAILURE