Run| Workspace| Log| Issues| Fixes| Pre-reqs| Stats| XRef| Maven Repository Proxy Log Gump Logo

Details

File Contents

Testsuite: org.apache.tomcat.websocket.TestWebSocketFrameClient
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.785 sec
------------- Standard Output ---------------
Sent Hello message, waiting for data
Received Hello, now sending data
------------- ---------------- ---------------
------------- Standard Error -----------------
22-Oct-2021 04:26:17.880 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToDigestEndpoint]
22-Oct-2021 04:26:18.292 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio2-127.0.0.1-auto-1"]
22-Oct-2021 04:26:18.316 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
22-Oct-2021 04:26:18.317 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
22-Oct-2021 04:26:18.426 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio2-127.0.0.1-auto-1-46809"]
22-Oct-2021 04:26:18.774 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio2-127.0.0.1-auto-1-46809"]
22-Oct-2021 04:26:18.782 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
22-Oct-2021 04:26:18.792 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio2-127.0.0.1-auto-1-46809"]
22-Oct-2021 04:26:18.793 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio2-127.0.0.1-auto-1-46809"]
22-Oct-2021 04:26:18.801 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToServerEndpoint]
22-Oct-2021 04:26:18.803 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio2-127.0.0.1-auto-2"]
22-Oct-2021 04:26:18.812 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
22-Oct-2021 04:26:18.812 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
22-Oct-2021 04:26:18.821 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio2-127.0.0.1-auto-2-36367"]
22-Oct-2021 04:26:19.982 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio2-127.0.0.1-auto-2-36367"]
22-Oct-2021 04:26:19.990 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
22-Oct-2021 04:26:20.000 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio2-127.0.0.1-auto-2-36367"]
22-Oct-2021 04:26:20.001 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio2-127.0.0.1-auto-2-36367"]
22-Oct-2021 04:26:20.005 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToBasicEndpoint]
22-Oct-2021 04:26:20.007 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio2-127.0.0.1-auto-3"]
22-Oct-2021 04:26:20.011 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
22-Oct-2021 04:26:20.011 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
22-Oct-2021 04:26:20.057 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio2-127.0.0.1-auto-3-34897"]
22-Oct-2021 04:26:20.165 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio2-127.0.0.1-auto-3-34897"]
22-Oct-2021 04:26:20.175 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
22-Oct-2021 04:26:20.181 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio2-127.0.0.1-auto-3-34897"]
22-Oct-2021 04:26:20.181 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio2-127.0.0.1-auto-3-34897"]
22-Oct-2021 04:26:20.298 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToRootEndpoint]
22-Oct-2021 04:26:20.300 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio2-127.0.0.1-auto-4"]
22-Oct-2021 04:26:20.301 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
22-Oct-2021 04:26:20.301 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
22-Oct-2021 04:26:20.322 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio2-127.0.0.1-auto-4-37393"]
22-Oct-2021 04:26:20.325 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:37393]
22-Oct-2021 04:26:20.331 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Writing the HTTP upgrade request from buffer starting at [0] with a limit of [154] from local address [/127.0.0.1:54492]
22-Oct-2021 04:26:20.336 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]] with status [OPEN_READ]
22-Oct-2021 04:26:20.337 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]]
22-Oct-2021 04:26:20.338 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [null] from cache
22-Oct-2021 04:26:20.338 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.register Register [org.apache.coyote.http11.Http11Processor@15c3528] as [Tomcat:type=RequestProcessor,worker="http-nio2-127.0.0.1-auto-4-37393",name=HttpRequest1]
22-Oct-2021 04:26:20.338 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created new processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.338 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [0]
22-Oct-2021 04:26:20.339 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read from buffer: [0]
22-Oct-2021 04:26:20.340 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read into buffer: [0]
22-Oct-2021 04:26:20.340 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Interest: [false]
22-Oct-2021 04:26:20.340 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.http11.Http11InputBuffer.fill Received []
22-Oct-2021 04:26:20.340 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Status in: [OPEN_READ], State out: [OPEN]
22-Oct-2021 04:26:20.345 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.347 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]] with status [OPEN_READ]
22-Oct-2021 04:26:20.347 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]]
22-Oct-2021 04:26:20.347 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.351 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [0]
22-Oct-2021 04:26:20.351 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read from buffer: [154]
22-Oct-2021 04:26:20.351 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET / HTTP/1.1
Sec-WebSocket-Key: mN2yO+96pB+J192kNR3q1g==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:37393
Upgrade: websocket

]
22-Oct-2021 04:26:20.355 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [6]
22-Oct-2021 04:26:20.355 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Status in: [OPEN_READ], State out: [UPGRADING]
22-Oct-2021 04:26:20.357 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.357 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@702a23f6] for socket wrapper [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]]
22-Oct-2021 04:26:20.358 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read from buffer: [0]
22-Oct-2021 04:26:20.358 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Interest: [false]
22-Oct-2021 04:26:20.358 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read into buffer: [11]
22-Oct-2021 04:26:20.358 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read from buffer: [11]
22-Oct-2021 04:26:20.358 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read from buffer: [0]
22-Oct-2021 04:26:20.358 FINE [WebSocketClient-AsyncIO-5] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
22-Oct-2021 04:26:20.362 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [6]
22-Oct-2021 04:26:20.363 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:37393/]
22-Oct-2021 04:26:20.364 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Writing the HTTP upgrade request from buffer starting at [0] with a limit of [154] from local address [/127.0.0.1:54494]
22-Oct-2021 04:26:20.365 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read into buffer: [0]
22-Oct-2021 04:26:20.365 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Status in: [OPEN_READ], State out: [UPGRADED]
22-Oct-2021 04:26:20.372 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Interest: [true]
22-Oct-2021 04:26:20.372 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]] with status [OPEN_READ]
22-Oct-2021 04:26:20.372 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]]
22-Oct-2021 04:26:20.372 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.372 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [154]
22-Oct-2021 04:26:20.372 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read from buffer: [0]
22-Oct-2021 04:26:20.373 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read into buffer: [0]
22-Oct-2021 04:26:20.373 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.coyote.http11.Http11InputBuffer.fill Received []
22-Oct-2021 04:26:20.373 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Status in: [OPEN_READ], State out: [OPEN]
22-Oct-2021 04:26:20.373 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.373 FINE [http-nio2-127.0.0.1-auto-4-exec-4] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]]
22-Oct-2021 04:26:20.377 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Interest: [true]
22-Oct-2021 04:26:20.380 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]]
22-Oct-2021 04:26:20.380 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol.addWaitingProcessor Added processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@702a23f6] to waiting processors
22-Oct-2021 04:26:20.380 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]] with status [OPEN_READ]

Continuation...

22-Oct-2021 04:26:20.381 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@702a23f6] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]]
22-Oct-2021 04:26:20.381 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@702a23f6] from waiting processors
22-Oct-2021 04:26:20.381 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]], Read from buffer: [8]
22-Oct-2021 04:26:20.381 FINE [WebSocketClient-AsyncIO-4] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
22-Oct-2021 04:26:20.391 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close Calling [org.apache.tomcat.util.net.Nio2Endpoint@13b6f892].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54492]])
	java.lang.Exception
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close(Nio2Endpoint.java:895)
		at org.apache.tomcat.websocket.server.WsRemoteEndpointImplServer.doClose(WsRemoteEndpointImplServer.java:173)
		at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.close(WsRemoteEndpointImplBase.java:755)
		at org.apache.tomcat.websocket.WsSession.onClose(WsSession.java:745)
		at org.apache.tomcat.websocket.WsFrameBase.processDataControl(WsFrameBase.java:367)
		at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:296)
		at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133)
		at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85)
		at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:183)
		at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:162)
		at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:157)
		at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60)
		at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59)
		at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:831)
		at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1638)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1125)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:587)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:565)
		at java.base/sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:127)
		at java.base/sun.nio.ch.Invoker$2.run(Invoker.java:219)
		at java.base/sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:829)
22-Oct-2021 04:26:20.392 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@702a23f6] from waiting processors
22-Oct-2021 04:26:20.393 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed]], Status in: [OPEN_READ], State out: [CLOSED]
22-Oct-2021 04:26:20.393 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@702a23f6] from waiting processors
22-Oct-2021 04:26:20.393 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close Calling [org.apache.tomcat.util.net.Nio2Endpoint@13b6f892].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@563b1db:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed]])
	java.lang.Exception
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close(Nio2Endpoint.java:895)
		at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1642)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1125)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:587)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:565)
		at java.base/sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:127)
		at java.base/sun.nio.ch.Invoker$2.run(Invoker.java:219)
		at java.base/sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:829)
22-Oct-2021 04:26:20.395 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]] with status [OPEN_READ]
22-Oct-2021 04:26:20.395 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]]
22-Oct-2021 04:26:20.395 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.395 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [154]
22-Oct-2021 04:26:20.396 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read from buffer: [154]
22-Oct-2021 04:26:20.396 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET / HTTP/1.1
Sec-WebSocket-Key: w3Ud6BhSWpq9zVEyH3AOIA==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:37393
Upgrade: websocket

]
22-Oct-2021 04:26:20.410 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [8]
22-Oct-2021 04:26:20.415 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Status in: [OPEN_READ], State out: [UPGRADING]
22-Oct-2021 04:26:20.416 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.416 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@7f4ee8b] for socket wrapper [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]]
22-Oct-2021 04:26:20.416 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read from buffer: [0]
22-Oct-2021 04:26:20.416 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Interest: [false]
22-Oct-2021 04:26:20.416 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read into buffer: [11]
22-Oct-2021 04:26:20.416 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read from buffer: [11]
22-Oct-2021 04:26:20.417 FINE [WebSocketClient-AsyncIO-2] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
22-Oct-2021 04:26:20.418 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read from buffer: [0]
22-Oct-2021 04:26:20.418 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read into buffer: [0]
22-Oct-2021 04:26:20.418 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Status in: [OPEN_READ], State out: [UPGRADED]
22-Oct-2021 04:26:20.418 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]]
22-Oct-2021 04:26:20.418 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol.addWaitingProcessor Added processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@7f4ee8b] to waiting processors
22-Oct-2021 04:26:20.418 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [8]
22-Oct-2021 04:26:20.419 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:37393/foo]
22-Oct-2021 04:26:20.420 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Writing the HTTP upgrade request from buffer starting at [0] with a limit of [157] from local address [/127.0.0.1:54496]
22-Oct-2021 04:26:20.424 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Interest: [true]
22-Oct-2021 04:26:20.425 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]] with status [OPEN_READ]
22-Oct-2021 04:26:20.425 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@7f4ee8b] for socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]]
22-Oct-2021 04:26:20.425 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@7f4ee8b] from waiting processors
22-Oct-2021 04:26:20.425 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]], Read from buffer: [8]
22-Oct-2021 04:26:20.425 FINE [WebSocketClient-AsyncIO-5] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
22-Oct-2021 04:26:20.429 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close Calling [org.apache.tomcat.util.net.Nio2Endpoint@13b6f892].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54494]])
	java.lang.Exception
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close(Nio2Endpoint.java:895)
		at org.apache.tomcat.websocket.server.WsRemoteEndpointImplServer.doClose(WsRemoteEndpointImplServer.java:173)
		at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.close(WsRemoteEndpointImplBase.java:755)
		at org.apache.tomcat.websocket.WsSession.onClose(WsSession.java:745)
		at org.apache.tomcat.websocket.WsFrameBase.processDataControl(WsFrameBase.java:367)
		at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:296)
		at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133)
		at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85)
		at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:183)
		at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:162)
		at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:157)
		at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60)
		at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59)
		at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:831)
		at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1638)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1125)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:587)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:565)
		at java.base/sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:127)
		at java.base/sun.nio.ch.Invoker$2.run(Invoker.java:219)
		at java.base/sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:829)
22-Oct-2021 04:26:20.430 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@7f4ee8b] from waiting processors
22-Oct-2021 04:26:20.430 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed]], Status in: [OPEN_READ], State out: [CLOSED]
22-Oct-2021 04:26:20.430 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@7f4ee8b] from waiting processors
22-Oct-2021 04:26:20.431 FINE [http-nio2-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close Calling [org.apache.tomcat.util.net.Nio2Endpoint@13b6f892].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@271aa07c:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed]])
	java.lang.Exception
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close(Nio2Endpoint.java:895)
		at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1642)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1125)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:587)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:565)
		at java.base/sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:127)
		at java.base/sun.nio.ch.Invoker$2.run(Invoker.java:219)
		at java.base/sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)

Continuation...

		at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:829)
22-Oct-2021 04:26:20.431 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]] with status [OPEN_READ]
22-Oct-2021 04:26:20.431 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]]
22-Oct-2021 04:26:20.432 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.432 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [154]
22-Oct-2021 04:26:20.432 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Read from buffer: [0]
22-Oct-2021 04:26:20.437 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Interest: [false]
22-Oct-2021 04:26:20.437 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Read into buffer: [0]
22-Oct-2021 04:26:20.437 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.coyote.http11.Http11InputBuffer.fill Received []
22-Oct-2021 04:26:20.437 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Status in: [OPEN_READ], State out: [OPEN]
22-Oct-2021 04:26:20.437 FINE [http-nio2-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.437 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]] with status [OPEN_READ]
22-Oct-2021 04:26:20.438 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]]
22-Oct-2021 04:26:20.438 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.438 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [154]
22-Oct-2021 04:26:20.438 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Read from buffer: [157]
22-Oct-2021 04:26:20.438 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET /foo HTTP/1.1
Sec-WebSocket-Key: k4WpHNSgQxonfj7Dg2AMhw==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:37393
Upgrade: websocket

]
22-Oct-2021 04:26:20.441 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [157]
22-Oct-2021 04:26:20.442 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Read from buffer: [0]
22-Oct-2021 04:26:20.442 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:37393/foo/]
22-Oct-2021 04:26:20.442 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Read into buffer: [0]
22-Oct-2021 04:26:20.442 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.http11.Http11InputBuffer.fill Received []
22-Oct-2021 04:26:20.442 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Status in: [OPEN_READ], State out: [OPEN]
22-Oct-2021 04:26:20.442 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.442 FINE [http-nio2-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]]
22-Oct-2021 04:26:20.443 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Writing the HTTP upgrade request from buffer starting at [0] with a limit of [158] from local address [/127.0.0.1:54498]
22-Oct-2021 04:26:20.445 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]] with status [OPEN_READ]
22-Oct-2021 04:26:20.446 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]]
22-Oct-2021 04:26:20.446 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.446 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [157]
22-Oct-2021 04:26:20.446 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read from buffer: [0]
22-Oct-2021 04:26:20.449 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read into buffer: [0]
22-Oct-2021 04:26:20.449 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.coyote.http11.Http11InputBuffer.fill Received []
22-Oct-2021 04:26:20.449 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Status in: [OPEN_READ], State out: [OPEN]
22-Oct-2021 04:26:20.449 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.449 FINE [http-nio2-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]]
22-Oct-2021 04:26:20.449 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Interest: [true]
22-Oct-2021 04:26:20.450 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]] with status [OPEN_READ]
22-Oct-2021 04:26:20.450 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]]
22-Oct-2021 04:26:20.450 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.450 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [157]
22-Oct-2021 04:26:20.450 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read from buffer: [158]
22-Oct-2021 04:26:20.450 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET /foo/ HTTP/1.1
Sec-WebSocket-Key: yfqoqYPtVjNwdnNQn0qGrQ==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:37393
Upgrade: websocket

]
22-Oct-2021 04:26:20.456 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [a]
22-Oct-2021 04:26:20.456 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Status in: [OPEN_READ], State out: [UPGRADING]
22-Oct-2021 04:26:20.457 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.457 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@41fcadf8] for socket wrapper [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]]
22-Oct-2021 04:26:20.460 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]], Interest: [true]
22-Oct-2021 04:26:20.460 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]] with status [ERROR]
22-Oct-2021 04:26:20.460 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]]
22-Oct-2021 04:26:20.460 FINE [http-nio2-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close Calling [org.apache.tomcat.util.net.Nio2Endpoint@13b6f892].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3e20df29:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54496]])
	java.lang.Exception
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close(Nio2Endpoint.java:895)
		at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1642)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:829)
22-Oct-2021 04:26:20.461 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read from buffer: [0]
22-Oct-2021 04:26:20.461 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Interest: [false]
22-Oct-2021 04:26:20.461 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read into buffer: [11]
22-Oct-2021 04:26:20.461 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read from buffer: [11]
22-Oct-2021 04:26:20.461 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read from buffer: [0]
22-Oct-2021 04:26:20.462 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read into buffer: [0]
22-Oct-2021 04:26:20.462 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Status in: [OPEN_READ], State out: [UPGRADED]
22-Oct-2021 04:26:20.462 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]]
22-Oct-2021 04:26:20.462 FINE [http-nio2-127.0.0.1-auto-4-exec-10] org.apache.coyote.AbstractProtocol.addWaitingProcessor Added processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@41fcadf8] to waiting processors
22-Oct-2021 04:26:20.465 FINE [WebSocketClient-AsyncIO-1] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
22-Oct-2021 04:26:20.465 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [a]

Continuation...

22-Oct-2021 04:26:20.466 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:37393/foo/]
22-Oct-2021 04:26:20.467 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Interest: [true]
22-Oct-2021 04:26:20.467 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]] with status [OPEN_READ]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]] with status [OPEN_READ]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@41fcadf8] for socket [org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@41fcadf8] from waiting processors
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [158]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]], Read from buffer: [8]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read from buffer: [0]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read into buffer: [0]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.http11.Http11InputBuffer.fill Received []
22-Oct-2021 04:26:20.468 FINE [WebSocketClient-AsyncIO-4] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Status in: [OPEN_READ], State out: [OPEN]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]]
22-Oct-2021 04:26:20.468 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close Calling [org.apache.tomcat.util.net.Nio2Endpoint@13b6f892].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54498]])
	java.lang.Exception
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close(Nio2Endpoint.java:895)
		at org.apache.tomcat.websocket.server.WsRemoteEndpointImplServer.doClose(WsRemoteEndpointImplServer.java:173)
		at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.close(WsRemoteEndpointImplBase.java:755)
		at org.apache.tomcat.websocket.WsSession.onClose(WsSession.java:745)
		at org.apache.tomcat.websocket.WsFrameBase.processDataControl(WsFrameBase.java:367)
		at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:296)
		at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133)
		at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85)
		at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:183)
		at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:162)
		at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:157)
		at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60)
		at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59)
		at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:831)
		at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1638)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1125)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:587)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:565)
		at java.base/sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:127)
		at java.base/sun.nio.ch.Invoker$2.run(Invoker.java:219)
		at java.base/sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:829)
22-Oct-2021 04:26:20.469 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@41fcadf8] from waiting processors
22-Oct-2021 04:26:20.469 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed]], Status in: [OPEN_READ], State out: [CLOSED]
22-Oct-2021 04:26:20.469 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Writing the HTTP upgrade request from buffer starting at [0] with a limit of [158] from local address [/127.0.0.1:54500]
22-Oct-2021 04:26:20.469 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@41fcadf8] from waiting processors
22-Oct-2021 04:26:20.469 FINE [http-nio2-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close Calling [org.apache.tomcat.util.net.Nio2Endpoint@13b6f892].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@3f12fb4f:org.apache.tomcat.util.net.Nio2Channel@29dd4b37:sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed]])
	java.lang.Exception
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.close(Nio2Endpoint.java:895)
		at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1642)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1125)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:587)
		at org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed(Nio2Endpoint.java:565)
		at java.base/sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:127)
		at java.base/sun.nio.ch.Invoker$2.run(Invoker.java:219)
		at java.base/sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
		at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:829)
22-Oct-2021 04:26:20.470 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Interest: [true]
22-Oct-2021 04:26:20.470 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]] with status [OPEN_READ]
22-Oct-2021 04:26:20.470 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]]
22-Oct-2021 04:26:20.470 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@15c3528] from cache
22-Oct-2021 04:26:20.470 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [158]
22-Oct-2021 04:26:20.470 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read from buffer: [158]
22-Oct-2021 04:26:20.470 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET /foo/ HTTP/1.1
Sec-WebSocket-Key: wSldVh7qRyx0aisYc3NBtw==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:37393
Upgrade: websocket

]
22-Oct-2021 04:26:20.474 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Status in: [OPEN_READ], State out: [UPGRADING]
22-Oct-2021 04:26:20.474 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@15c3528]
22-Oct-2021 04:26:20.474 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [c]
22-Oct-2021 04:26:20.474 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@682e3502] for socket wrapper [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]]
22-Oct-2021 04:26:20.480 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read from buffer: [0]
22-Oct-2021 04:26:20.480 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper$2.completed Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Interest: [false]
22-Oct-2021 04:26:20.481 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read into buffer: [11]
22-Oct-2021 04:26:20.481 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read from buffer: [11]
22-Oct-2021 04:26:20.481 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read from buffer: [0]
22-Oct-2021 04:26:20.481 FINE [WebSocketClient-AsyncIO-2] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
22-Oct-2021 04:26:20.481 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Read into buffer: [0]
22-Oct-2021 04:26:20.481 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]], Status in: [OPEN_READ], State out: [UPGRADED]
22-Oct-2021 04:26:20.481 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@e62e35:org.apache.tomcat.util.net.Nio2Channel@f9bdaab:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:37393 remote=/127.0.0.1:54500]]
22-Oct-2021 04:26:20.481 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [c]
22-Oct-2021 04:26:20.481 FINE [http-nio2-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol.addWaitingProcessor Added processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@682e3502] to waiting processors
22-Oct-2021 04:26:20.481 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio2-127.0.0.1-auto-4-37393"]
22-Oct-2021 04:26:20.484 FINE [WebSocketClient-AsyncIO-1] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
22-Oct-2021 04:26:20.487 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
22-Oct-2021 04:26:20.496 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio2-127.0.0.1-auto-4-37393"]
22-Oct-2021 04:26:20.497 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio2-127.0.0.1-auto-4-37393"]
------------- ---------------- ---------------

Testcase: testConnectToDigestEndpoint took 1.052 sec
Testcase: testConnectToServerEndpoint took 1.203 sec
Testcase: testConnectToBasicEndpoint took 0.293 sec
Testcase: testConnectToRootEndpoint took 0.208 sec

Apache Gump, Gump, Apache, the Apache feather logo, and the Apache Gump project logos are trademarks of The Apache Software Foundation.

Last Updated: Fri, 22 Oct 2021 00:00:06 ().Python Logo