-
Bug
-
Resolution: Unresolved
-
P4
-
23
-
x86_64
-
windows
The test appears to complete OK
Server: close outbound: /foo/x48
Server: close inbound: /foo/x48
Server: close outbound: /foo/x50
Server: close inbound: /foo/x50
Server: close outbound: /foo/x46
Server: close inbound: /foo/x46
159ms: Result (48) from /foo/x50
159ms: Result (49) from /foo/x48
159ms: Result (50) from /foo/x46
OK
But "under the hood" the test gets itself in a "knot" with HttpClient/HttpServer SSL exchange failing and appears to continuously fail thereafter until jtreg timeout activates.
This may be dues to the termination/shutdown sequences
log extract ... there are a multitude of http server exchange failures
----------messages:(9/541)----------
command: main -Djdk.tracePinnedThreads=full -Djdk.httpclient.HttpClient.log=ssl,channel ManyRequests
reason: User specified action: run main/othervm/timeout=40 -Djdk.tracePinnedThreads=full -Djdk.httpclient.HttpClient.log=ssl,channel ManyRequests
started: Tue Mar 12 08:43:33 UTC 2024
Mode: othervm [/othervm specified]
Additional options from @modules: --add-modules java.net.http,java.logging,jdk.httpserver
Timeout information:
--- Timeout information end.
finished: Tue Mar 12 08:46:41 UTC 2024
elapsed time (seconds): 187.803
----------configuration:(3/72)----------
Boot Layer
add modules: java.net.http java.logging jdk.httpserver
----------System.out:(513/19768)----------
Seed from RandomFactory = 8893814701183628058L
Running on: Windows Server 2016
Sending 50 requests; delay=false, chunks=0, XFixed=false
Client: sendAsync: https://localhost:55489/foo/x1
Client: sendAsync: https://localhost:55489/foo/x2
Client: sendAsync: https://localhost:55489/foo/x3
Client: sendAsync: https://localhost:55489/foo/x4
Client: sendAsync: https://localhost:55489/foo/x5
Client: sendAsync: https://localhost:55489/foo/x6
Client: sendAsync: https://localhost:55489/foo/x7
Client: sendAsync: https://localhost:55489/foo/x8
Client: sendAsync: https://localhost:55489/foo/x9
Client: sendAsync: https://localhost:55489/foo/x10
Client: sendAsync: https://localhost:55489/foo/x11
Client: sendAsync: https://localhost:55489/foo/x12
Client: sendAsync: https://localhost:55489/foo/x13
Client: sendAsync: https://localhost:55489/foo/x14
Client: sendAsync: https://localhost:55489/foo/x15
Client: sendAsync: https://localhost:55489/foo/x16
Client: sendAsync: https://localhost:55489/foo/x17
Client: sendAsync: https://localhost:55489/foo/x18
Client: sendAsync: https://localhost:55489/foo/x19
Client: sendAsync: https://localhost:55489/foo/x20
Client: sendAsync: https://localhost:55489/foo/x21
Client: sendAsync: https://localhost:55489/foo/x22
Client: sendAsync: https://localhost:55489/foo/x23
. . .
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x27 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x6 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x12 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x36 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x30 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x34 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x32 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x2 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55507 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:45:42 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.doHandshake(SSLStreams.java:481)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:419)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:45:37 AM jdk.internal.net.http.Http2Connection lambda$checkSSLConfig$4
INFO: SSL: No ALPN negotiated
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x25 POST
. . .
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: An established connection was aborted by the software in your host machine
at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method)
at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:54)
at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58)
at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566)
at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSendX(SSLStreams.java:299)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSend(SSLStreams.java:273)
at jdk.httpserver/sun.net.httpserver.SSLStreams.sendData(SSLStreams.java:380)
at jdk.httpserver/sun.net.httpserver.SSLStreams$OutputStream.write(SSLStreams.java:642)
at java.base/java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:125)
at java.base/java.io.BufferedOutputStream.implFlush(BufferedOutputStream.java:252)
at java.base/java.io.BufferedOutputStream.flush(BufferedOutputStream.java:240)
at jdk.httpserver/sun.net.httpserver.ExchangeImpl.sendResponseHeaders(ExchangeImpl.java:281)
at jdk.httpserver/sun.net.httpserver.HttpsExchangeImpl.sendResponseHeaders(HttpsExchangeImpl.java:85)
at EchoHandler.handle(EchoHandler.java:53)
at ManyRequests$TestEchoHandler.handle(ManyRequests.java:147)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:101)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:871)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:845)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:08 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55514]
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x15 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x16 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x1 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x25 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: An established connection was aborted by the software in your host machine
at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method)
at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:54)
at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58)
at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566)
at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSendX(SSLStreams.java:299)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSend(SSLStreams.java:273)
at jdk.httpserver/sun.net.httpserver.SSLStreams.sendData(SSLStreams.java:380)
at jdk.httpserver/sun.net.httpserver.SSLStreams$OutputStream.write(SSLStreams.java:642)
at java.base/java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:125)
at java.base/java.io.BufferedOutputStream.implFlush(BufferedOutputStream.java:252)
at java.base/java.io.BufferedOutputStream.flush(BufferedOutputStream.java:240)
at jdk.httpserver/sun.net.httpserver.ExchangeImpl.sendResponseHeaders(ExchangeImpl.java:281)
at jdk.httpserver/sun.net.httpserver.HttpsExchangeImpl.sendResponseHeaders(HttpsExchangeImpl.java:85)
at EchoHandler.handle(EchoHandler.java:53)
at ManyRequests$TestEchoHandler.handle(ManyRequests.java:147)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:101)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:871)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:845)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:08 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55516]
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x7 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x4 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x12 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x2 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x19 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x5 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x35 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: An established connection was aborted by the software in your host machine
at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method)
at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:54)
at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58)
at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566)
at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSendX(SSLStreams.java:299)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSend(SSLStreams.java:273)
at jdk.httpserver/sun.net.httpserver.SSLStreams.sendData(SSLStreams.java:380)
at jdk.httpserver/sun.net.httpserver.SSLStreams$OutputStream.write(SSLStreams.java:642)
at java.base/java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:125)
at java.base/java.io.BufferedOutputStream.implFlush(BufferedOutputStream.java:252)
at java.base/java.io.BufferedOutputStream.flush(BufferedOutputStream.java:240)
at jdk.httpserver/sun.net.httpserver.ExchangeImpl.sendResponseHeaders(ExchangeImpl.java:281)
at jdk.httpserver/sun.net.httpserver.HttpsExchangeImpl.sendResponseHeaders(HttpsExchangeImpl.java:85)
at EchoHandler.handle(EchoHandler.java:53)
at ManyRequests$TestEchoHandler.handle(ManyRequests.java:147)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:101)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:871)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:845)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:08 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55517]
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
. . .
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x48 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x50 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x46 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Dispatcher handleEvent
FINER: Write Finished
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Dispatcher handleEvent
FINER: Write Finished
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Dispatcher handleEvent
FINER: Write Finished
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.HttpClientImpl$SelectorManager run
INFO: CHANNEL: HttpClient-1-SelectorManager: stopping
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55531 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55590 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55531]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55540 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55620 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55620]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55540]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55590]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
Server: close outbound: /foo/x48
Server: close inbound: /foo/x48
Server: close outbound: /foo/x50
Server: close inbound: /foo/x50
Server: close outbound: /foo/x46
Server: close inbound: /foo/x46
159ms: Result (48) from /foo/x50
159ms: Result (49) from /foo/x48
159ms: Result (50) from /foo/x46
OK
But "under the hood" the test gets itself in a "knot" with HttpClient/HttpServer SSL exchange failing and appears to continuously fail thereafter until jtreg timeout activates.
This may be dues to the termination/shutdown sequences
log extract ... there are a multitude of http server exchange failures
----------messages:(9/541)----------
command: main -Djdk.tracePinnedThreads=full -Djdk.httpclient.HttpClient.log=ssl,channel ManyRequests
reason: User specified action: run main/othervm/timeout=40 -Djdk.tracePinnedThreads=full -Djdk.httpclient.HttpClient.log=ssl,channel ManyRequests
started: Tue Mar 12 08:43:33 UTC 2024
Mode: othervm [/othervm specified]
Additional options from @modules: --add-modules java.net.http,java.logging,jdk.httpserver
Timeout information:
--- Timeout information end.
finished: Tue Mar 12 08:46:41 UTC 2024
elapsed time (seconds): 187.803
----------configuration:(3/72)----------
Boot Layer
add modules: java.net.http java.logging jdk.httpserver
----------System.out:(513/19768)----------
Seed from RandomFactory = 8893814701183628058L
Running on: Windows Server 2016
Sending 50 requests; delay=false, chunks=0, XFixed=false
Client: sendAsync: https://localhost:55489/foo/x1
Client: sendAsync: https://localhost:55489/foo/x2
Client: sendAsync: https://localhost:55489/foo/x3
Client: sendAsync: https://localhost:55489/foo/x4
Client: sendAsync: https://localhost:55489/foo/x5
Client: sendAsync: https://localhost:55489/foo/x6
Client: sendAsync: https://localhost:55489/foo/x7
Client: sendAsync: https://localhost:55489/foo/x8
Client: sendAsync: https://localhost:55489/foo/x9
Client: sendAsync: https://localhost:55489/foo/x10
Client: sendAsync: https://localhost:55489/foo/x11
Client: sendAsync: https://localhost:55489/foo/x12
Client: sendAsync: https://localhost:55489/foo/x13
Client: sendAsync: https://localhost:55489/foo/x14
Client: sendAsync: https://localhost:55489/foo/x15
Client: sendAsync: https://localhost:55489/foo/x16
Client: sendAsync: https://localhost:55489/foo/x17
Client: sendAsync: https://localhost:55489/foo/x18
Client: sendAsync: https://localhost:55489/foo/x19
Client: sendAsync: https://localhost:55489/foo/x20
Client: sendAsync: https://localhost:55489/foo/x21
Client: sendAsync: https://localhost:55489/foo/x22
Client: sendAsync: https://localhost:55489/foo/x23
. . .
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x27 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x6 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x12 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x36 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x30 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x34 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x32 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x2 POST
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55507 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:45:42 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.doHandshake(SSLStreams.java:481)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:419)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:45:37 AM jdk.internal.net.http.Http2Connection lambda$checkSSLConfig$4
INFO: SSL: No ALPN negotiated
Mar 12, 2024 8:45:42 AM jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber onSubscribe
INFO: CHANNEL: HTTP/1 read subscriber got subscription from https://localhost:55489/foo/x25 POST
. . .
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: An established connection was aborted by the software in your host machine
at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method)
at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:54)
at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58)
at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566)
at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSendX(SSLStreams.java:299)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSend(SSLStreams.java:273)
at jdk.httpserver/sun.net.httpserver.SSLStreams.sendData(SSLStreams.java:380)
at jdk.httpserver/sun.net.httpserver.SSLStreams$OutputStream.write(SSLStreams.java:642)
at java.base/java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:125)
at java.base/java.io.BufferedOutputStream.implFlush(BufferedOutputStream.java:252)
at java.base/java.io.BufferedOutputStream.flush(BufferedOutputStream.java:240)
at jdk.httpserver/sun.net.httpserver.ExchangeImpl.sendResponseHeaders(ExchangeImpl.java:281)
at jdk.httpserver/sun.net.httpserver.HttpsExchangeImpl.sendResponseHeaders(HttpsExchangeImpl.java:85)
at EchoHandler.handle(EchoHandler.java:53)
at ManyRequests$TestEchoHandler.handle(ManyRequests.java:147)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:101)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:871)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:845)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:08 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55514]
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x15 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x16 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x1 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x25 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: An established connection was aborted by the software in your host machine
at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method)
at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:54)
at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58)
at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566)
at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSendX(SSLStreams.java:299)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSend(SSLStreams.java:273)
at jdk.httpserver/sun.net.httpserver.SSLStreams.sendData(SSLStreams.java:380)
at jdk.httpserver/sun.net.httpserver.SSLStreams$OutputStream.write(SSLStreams.java:642)
at java.base/java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:125)
at java.base/java.io.BufferedOutputStream.implFlush(BufferedOutputStream.java:252)
at java.base/java.io.BufferedOutputStream.flush(BufferedOutputStream.java:240)
at jdk.httpserver/sun.net.httpserver.ExchangeImpl.sendResponseHeaders(ExchangeImpl.java:281)
at jdk.httpserver/sun.net.httpserver.HttpsExchangeImpl.sendResponseHeaders(HttpsExchangeImpl.java:85)
at EchoHandler.handle(EchoHandler.java:53)
at ManyRequests$TestEchoHandler.handle(ManyRequests.java:147)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:101)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:871)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:845)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:08 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55516]
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x7 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x4 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x12 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x2 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x19 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x5 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
FINER: Sent headers: noContentToSend=false
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x35 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: An established connection was aborted by the software in your host machine
at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method)
at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:54)
at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102)
at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58)
at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566)
at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSendX(SSLStreams.java:299)
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.wrapAndSend(SSLStreams.java:273)
at jdk.httpserver/sun.net.httpserver.SSLStreams.sendData(SSLStreams.java:380)
at jdk.httpserver/sun.net.httpserver.SSLStreams$OutputStream.write(SSLStreams.java:642)
at java.base/java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:125)
at java.base/java.io.BufferedOutputStream.implFlush(BufferedOutputStream.java:252)
at java.base/java.io.BufferedOutputStream.flush(BufferedOutputStream.java:240)
at jdk.httpserver/sun.net.httpserver.ExchangeImpl.sendResponseHeaders(ExchangeImpl.java:281)
at jdk.httpserver/sun.net.httpserver.HttpsExchangeImpl.sendResponseHeaders(HttpsExchangeImpl.java:85)
at EchoHandler.handle(EchoHandler.java:53)
at ManyRequests$TestEchoHandler.handle(ManyRequests.java:147)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:101)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:871)
at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:98)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:845)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:08 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55517]
Mar 12, 2024 8:46:08 AM sun.net.httpserver.ExchangeImpl sendResponseHeaders
. . .
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x48 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x50 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl logReply
FINE: POST /foo/x46 HTTP/1.1 [200 OK] ()
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Dispatcher handleEvent
FINER: Write Finished
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Dispatcher handleEvent
FINER: Write Finished
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Dispatcher handleEvent
FINER: Write Finished
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.HttpClientImpl$SelectorManager run
INFO: CHANNEL: HttpClient-1-SelectorManager: stopping
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55531 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55590 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55531]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55540 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
INFO: CHANNEL: Closing channel: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55620 remote=localhost/127.0.0.1:55489]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube signalClosed
INFO: CHANNEL: Connection close signalled: connection closed locally (java.nio.channels.SocketChannel[closed])
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: exchange started
Mar 12, 2024 8:46:15 AM sun.net.httpserver.ServerImpl$Exchange run
FINER: ServerImpl.Exchange
java.io.IOException: connection closed for reading
at jdk.httpserver/sun.net.httpserver.SSLStreams$EngineWrapper.recvAndUnwrap(SSLStreams.java:335)
at jdk.httpserver/sun.net.httpserver.SSLStreams.recvData(SSLStreams.java:407)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:528)
at jdk.httpserver/sun.net.httpserver.SSLStreams$InputStream.read(SSLStreams.java:600)
at jdk.httpserver/sun.net.httpserver.Request.readLine(Request.java:79)
at jdk.httpserver/sun.net.httpserver.Request.<init>(Request.java:52)
at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:692)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
at java.base/java.lang.Thread.run(Thread.java:1575)
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55620]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55540]
Mar 12, 2024 8:46:15 AM sun.net.httpserver.HttpConnection close
FINER: Closing connection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:55489 remote=127.0.0.1/127.0.0.1:55590]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription signalError
INFO: CHANNEL: Read error signalled on channel java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Raising error with subscriber for java.nio.channels.SocketChannel[closed]: java.io.IOException: connection closed locally
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription read
INFO: CHANNEL: Stopped reading from channel java.nio.channels.SocketChannel[closed]
Mar 12, 2024 8:46:15 AM jdk.internal.net.http.PlainHttpConnection close
- relates to
-
JDK-8256459 java/net/httpclient/ManyRequests.java and java/net/httpclient/LineBodyHandlerTest.java fail infrequently with java.net.ConnectException: Connection timed out: no further information
- Resolved