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: 3.203 sec
------------- Standard Output ---------------
Sent Hello message, waiting for data
Received Hello, now sending data
------------- ---------------- ---------------
------------- Standard Error -----------------
26-Oct-2021 10:17:33.184 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToDigestEndpoint]
26-Oct-2021 10:17:33.331 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.32-dev] using APR version [1.7.1-dev].
26-Oct-2021 10:17:33.331 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [{4}].
26-Oct-2021 10:17:33.331 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true]
26-Oct-2021 10:17:33.333 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1m-dev  xx XXX xxxx]
26-Oct-2021 10:17:33.591 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-apr-127.0.0.1-auto-1"]
26-Oct-2021 10:17:33.606 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
26-Oct-2021 10:17:33.607 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
26-Oct-2021 10:17:33.714 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-apr-127.0.0.1-auto-1-44291"]
26-Oct-2021 10:17:33.863 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-apr-127.0.0.1-auto-1-44291"]
26-Oct-2021 10:17:33.871 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
26-Oct-2021 10:17:33.880 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-apr-127.0.0.1-auto-1-44291"]
26-Oct-2021 10:17:33.933 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-apr-127.0.0.1-auto-1-44291"]
26-Oct-2021 10:17:33.940 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToServerEndpoint]
26-Oct-2021 10:17:33.941 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.32-dev] using APR version [1.7.1-dev].
26-Oct-2021 10:17:33.941 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [{4}].
26-Oct-2021 10:17:33.941 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true]
26-Oct-2021 10:17:33.941 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1m-dev  xx XXX xxxx]
26-Oct-2021 10:17:33.942 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-apr-127.0.0.1-auto-2"]
26-Oct-2021 10:17:33.943 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
26-Oct-2021 10:17:33.943 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
26-Oct-2021 10:17:33.956 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-apr-127.0.0.1-auto-2-34125"]
26-Oct-2021 10:17:35.525 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-apr-127.0.0.1-auto-2-34125"]
26-Oct-2021 10:17:35.527 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
26-Oct-2021 10:17:35.531 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-apr-127.0.0.1-auto-2-34125"]
26-Oct-2021 10:17:35.582 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-apr-127.0.0.1-auto-2-34125"]
26-Oct-2021 10:17:35.585 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToBasicEndpoint]
26-Oct-2021 10:17:35.586 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.32-dev] using APR version [1.7.1-dev].
26-Oct-2021 10:17:35.587 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [{4}].
26-Oct-2021 10:17:35.587 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true]
26-Oct-2021 10:17:35.587 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1m-dev  xx XXX xxxx]
26-Oct-2021 10:17:35.589 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-apr-127.0.0.1-auto-3"]
26-Oct-2021 10:17:35.589 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
26-Oct-2021 10:17:35.589 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
26-Oct-2021 10:17:35.627 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-apr-127.0.0.1-auto-3-36367"]
26-Oct-2021 10:17:35.759 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-apr-127.0.0.1-auto-3-36367"]
26-Oct-2021 10:17:35.762 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
26-Oct-2021 10:17:35.778 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-apr-127.0.0.1-auto-3-36367"]
26-Oct-2021 10:17:35.832 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-apr-127.0.0.1-auto-3-36367"]
26-Oct-2021 10:17:35.835 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testConnectToRootEndpoint]
26-Oct-2021 10:17:35.836 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.32-dev] using APR version [1.7.1-dev].
26-Oct-2021 10:17:35.836 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [{4}].
26-Oct-2021 10:17:35.836 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true]
26-Oct-2021 10:17:35.836 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1m-dev  xx XXX xxxx]
26-Oct-2021 10:17:35.837 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-apr-127.0.0.1-auto-4"]
26-Oct-2021 10:17:35.837 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
26-Oct-2021 10:17:35.838 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.72-dev]
26-Oct-2021 10:17:35.880 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-apr-127.0.0.1-auto-4-44173"]
26-Oct-2021 10:17:35.889 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:44173]
26-Oct-2021 10:17:35.891 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:57018]
26-Oct-2021 10:17:35.891 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint$Acceptor.run APR socket [139,638,178,064,400] opened with remote port [57,018]
26-Oct-2021 10:17:35.892 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint.processSocketWithOptions socket [139,638,178,064,400]
26-Oct-2021 10:17:35.905 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,064,400] with status [OPEN_READ]
26-Oct-2021 10:17:35.906 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [139,638,178,064,400]
26-Oct-2021 10:17:35.906 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [null] from cache
26-Oct-2021 10:17:35.907 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.register Register [org.apache.coyote.http11.Http11Processor@574b1c8d] as [Tomcat:type=RequestProcessor,worker="http-apr-127.0.0.1-auto-4-44173",name=HttpRequest1]
26-Oct-2021 10:17:35.907 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created new processor [org.apache.coyote.http11.Http11Processor@574b1c8d]
26-Oct-2021 10:17:35.907 FINE [http-apr-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]
26-Oct-2021 10:17:35.908 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read from buffer: [0]
26-Oct-2021 10:17:35.908 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read into buffer: [154]
26-Oct-2021 10:17:35.909 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read from buffer: [154]
26-Oct-2021 10:17:35.909 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET / HTTP/1.1
Sec-WebSocket-Key: hvmbllrHkRpFj/LnIY7qvQ==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:44173
Upgrade: websocket

]
26-Oct-2021 10:17:35.912 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [6]
26-Oct-2021 10:17:35.912 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Status in: [OPEN_READ], State out: [UPGRADING]
26-Oct-2021 10:17:35.920 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@574b1c8d]
26-Oct-2021 10:17:35.920 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@5db377fd] for socket wrapper [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400]
26-Oct-2021 10:17:35.921 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read from buffer: [0]
26-Oct-2021 10:17:35.921 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read into buffer: [11]
26-Oct-2021 10:17:35.921 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read from buffer: [11]
26-Oct-2021 10:17:35.921 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read from buffer: [0]
26-Oct-2021 10:17:35.921 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read into buffer: [0]
26-Oct-2021 10:17:35.921 FINE [WebSocketClient-AsyncIO-3] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
26-Oct-2021 10:17:35.921 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Status in: [OPEN_READ], State out: [UPGRADED]
26-Oct-2021 10:17:35.922 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400]
26-Oct-2021 10:17:35.922 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [6]
26-Oct-2021 10:17:35.923 FINE [http-apr-127.0.0.1-auto-4-exec-1] org.apache.coyote.AbstractProtocol.addWaitingProcessor Added processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@5db377fd] to waiting processors
26-Oct-2021 10:17:35.923 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:44173/]
26-Oct-2021 10:17:35.924 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Add to poller socket [139,638,178,064,400]
26-Oct-2021 10:17:35.924 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,064,400] from poller
26-Oct-2021 10:17:35.924 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Processing socket [139,638,178,064,400] for event(s) [1]
26-Oct-2021 10:17:35.928 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,064,400] with status [OPEN_READ]
26-Oct-2021 10:17:35.928 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@5db377fd] for socket [139,638,178,064,400]
26-Oct-2021 10:17:35.928 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@5db377fd] from waiting processors
26-Oct-2021 10:17:35.928 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read from buffer: [0]
26-Oct-2021 10:17:35.928 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read into buffer: [8]
26-Oct-2021 10:17:35.928 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Read from buffer: [8]
26-Oct-2021 10:17:35.928 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:57020]
26-Oct-2021 10:17:35.928 FINE [WebSocketClient-AsyncIO-2] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
26-Oct-2021 10:17:35.929 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint$Acceptor.run APR socket [139,638,178,126,656] opened with remote port [57,020]
26-Oct-2021 10:17:35.929 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint.processSocketWithOptions socket [139,638,178,126,656]
26-Oct-2021 10:17:35.930 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@5db377fd] from waiting processors
26-Oct-2021 10:17:35.930 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@598ce814:139638178064400], Status in: [OPEN_READ], State out: [CLOSED]
26-Oct-2021 10:17:35.930 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,064,400] from poller
26-Oct-2021 10:17:35.941 FINE [http-apr-127.0.0.1-auto-4-exec-2] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@5db377fd] from waiting processors
26-Oct-2021 10:17:35.942 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,126,656] with status [OPEN_READ]
26-Oct-2021 10:17:35.942 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [139,638,178,126,656]
26-Oct-2021 10:17:35.944 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@574b1c8d] from cache
26-Oct-2021 10:17:35.944 FINE [http-apr-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: [154]
26-Oct-2021 10:17:35.944 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read from buffer: [0]
26-Oct-2021 10:17:35.944 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read into buffer: [154]
26-Oct-2021 10:17:35.944 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read from buffer: [154]
26-Oct-2021 10:17:35.944 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET / HTTP/1.1
Sec-WebSocket-Key: w78p7hcsf88vlW5K6FsSuQ==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:44173
Upgrade: websocket

]
26-Oct-2021 10:17:35.946 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [8]
26-Oct-2021 10:17:35.950 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Status in: [OPEN_READ], State out: [UPGRADING]

Continuation...

26-Oct-2021 10:17:35.950 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@574b1c8d]
26-Oct-2021 10:17:35.950 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@6ee62edf] for socket wrapper [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656]
26-Oct-2021 10:17:35.950 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read from buffer: [0]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read into buffer: [11]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read from buffer: [11]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read from buffer: [0]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read into buffer: [0]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Status in: [OPEN_READ], State out: [UPGRADED]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-exec-3] org.apache.coyote.AbstractProtocol.addWaitingProcessor Added processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@6ee62edf] to waiting processors
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Add to poller socket [139,638,178,126,656]
26-Oct-2021 10:17:35.951 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,126,656] from poller
26-Oct-2021 10:17:35.952 FINE [WebSocketClient-AsyncIO-1] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
26-Oct-2021 10:17:35.952 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [8]
26-Oct-2021 10:17:35.952 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Processing socket [139,638,178,126,656] for event(s) [1]
26-Oct-2021 10:17:35.953 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:44173/foo]
26-Oct-2021 10:17:35.963 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:57024]
26-Oct-2021 10:17:35.963 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint$Acceptor.run APR socket [139,638,178,064,400] opened with remote port [57,024]
26-Oct-2021 10:17:35.963 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint.processSocketWithOptions socket [139,638,178,064,400]
26-Oct-2021 10:17:35.966 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,126,656] with status [OPEN_READ]
26-Oct-2021 10:17:35.967 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@6ee62edf] for socket [139,638,178,126,656]
26-Oct-2021 10:17:35.968 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@6ee62edf] from waiting processors
26-Oct-2021 10:17:35.968 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read from buffer: [0]
26-Oct-2021 10:17:35.968 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read into buffer: [8]
26-Oct-2021 10:17:35.971 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Read from buffer: [8]
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@6ee62edf] from waiting processors
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,064,400] with status [OPEN_READ]
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,126,656] from poller
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [139,638,178,064,400]
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@574b1c8d] from cache
26-Oct-2021 10:17:35.975 FINE [http-apr-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]
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Read from buffer: [0]
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Read into buffer: [157]
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Read from buffer: [157]
26-Oct-2021 10:17:35.975 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET /foo HTTP/1.1
Sec-WebSocket-Key: cwjW6TygZKYcuTI0xsRoew==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:44173
Upgrade: websocket

]
26-Oct-2021 10:17:35.976 FINE [http-apr-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: [157]
26-Oct-2021 10:17:35.976 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Read from buffer: [0]
26-Oct-2021 10:17:35.976 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Read into buffer: [0]
26-Oct-2021 10:17:35.976 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.coyote.http11.Http11InputBuffer.fill Received []
26-Oct-2021 10:17:35.976 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Status in: [OPEN_READ], State out: [OPEN]
26-Oct-2021 10:17:35.976 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@574b1c8d]
26-Oct-2021 10:17:35.976 FINE [http-apr-127.0.0.1-auto-4-exec-5] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400]
26-Oct-2021 10:17:35.976 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@6021ccab:139638178126656], Status in: [OPEN_READ], State out: [CLOSED]
26-Oct-2021 10:17:35.977 FINE [http-apr-127.0.0.1-auto-4-exec-4] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@6ee62edf] from waiting processors
26-Oct-2021 10:17:35.977 FINE [WebSocketClient-AsyncIO-7] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
26-Oct-2021 10:17:35.977 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Add to poller socket [139,638,178,064,400]
26-Oct-2021 10:17:35.977 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:44173/foo/]
26-Oct-2021 10:17:35.977 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,064,400] from poller
26-Oct-2021 10:17:35.978 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:57026]
26-Oct-2021 10:17:35.978 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint$Acceptor.run APR socket [139,638,178,208,368] opened with remote port [57,026]
26-Oct-2021 10:17:35.978 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint.processSocketWithOptions socket [139,638,178,208,368]
26-Oct-2021 10:17:35.986 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,208,368] with status [OPEN_READ]
26-Oct-2021 10:17:35.986 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [139,638,178,208,368]
26-Oct-2021 10:17:35.986 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@574b1c8d] from cache
26-Oct-2021 10:17:35.986 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.http11.Http11InputBuffer.fill Before fill(): parsingHeader: [true], parsingRequestLine: [true], parsingRequestLinePhase: [0], parsingRequestLineStart: [0], byteBuffer.position(): [0], byteBuffer.limit(): [0], end: [157]
26-Oct-2021 10:17:35.986 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read from buffer: [0]
26-Oct-2021 10:17:35.986 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read into buffer: [158]
26-Oct-2021 10:17:35.986 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read from buffer: [158]
26-Oct-2021 10:17:35.987 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET /foo/ HTTP/1.1
Sec-WebSocket-Key: QZHIP2COg7aJ7J1ALeYXuQ==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:44173
Upgrade: websocket

]
26-Oct-2021 10:17:35.993 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [a]
26-Oct-2021 10:17:35.996 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Status in: [OPEN_READ], State out: [UPGRADING]
26-Oct-2021 10:17:35.996 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Processing socket [139,638,178,064,400] for event(s) [1]
26-Oct-2021 10:17:35.996 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@574b1c8d]
26-Oct-2021 10:17:35.996 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@79b0ccda] for socket wrapper [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368]
26-Oct-2021 10:17:35.997 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read from buffer: [0]
26-Oct-2021 10:17:35.997 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read into buffer: [11]
26-Oct-2021 10:17:35.997 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read from buffer: [11]
26-Oct-2021 10:17:35.997 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read from buffer: [0]
26-Oct-2021 10:17:35.997 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read into buffer: [0]
26-Oct-2021 10:17:35.997 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,064,400] with status [OPEN_READ]
26-Oct-2021 10:17:35.997 FINE [WebSocketClient-AsyncIO-1] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
26-Oct-2021 10:17:35.998 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [a]
26-Oct-2021 10:17:35.999 FINE [main] org.apache.tomcat.websocket.WsWebSocketContainer.connectToServerRecursive Connecting endpoint instance of type [org.apache.tomcat.websocket.TesterMessageCountClient$TesterProgrammaticEndpoint] to [ws://localhost:44173/foo/]
26-Oct-2021 10:17:35.997 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Status in: [OPEN_READ], State out: [UPGRADED]
26-Oct-2021 10:17:35.999 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.registerReadInterest Registered read interest for [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368]
26-Oct-2021 10:17:35.998 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [139,638,178,064,400]
26-Oct-2021 10:17:36.001 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@574b1c8d] from cache
26-Oct-2021 10:17:36.001 FINE [http-apr-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: [158]
26-Oct-2021 10:17:36.001 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Read from buffer: [0]
26-Oct-2021 10:17:36.001 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Read into buffer: [-1]
26-Oct-2021 10:17:36.001 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.http11.Http11InputBuffer.fill Received []
26-Oct-2021 10:17:36.001 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.http11.Http11Processor.service Error parsing HTTP request header
	java.io.EOFException: Unexpected EOF read on the socket
		at org.apache.coyote.http11.Http11InputBuffer.fill(Http11InputBuffer.java:824)
		at org.apache.coyote.http11.Http11InputBuffer.parseRequestLine(Http11InputBuffer.java:375)
		at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:511)
		at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
		at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:831)
		at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.doRun(AprEndpoint.java:2091)
		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)
26-Oct-2021 10:17:36.001 FINE [http-apr-127.0.0.1-auto-4-exec-6] org.apache.coyote.AbstractProtocol.addWaitingProcessor Added processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@79b0ccda] to waiting processors
26-Oct-2021 10:17:36.001 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Add to poller socket [139,638,178,208,368]
26-Oct-2021 10:17:35.999 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:57028]
26-Oct-2021 10:17:36.002 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,208,368] from poller

Continuation...

26-Oct-2021 10:17:36.002 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.run Processing socket [139,638,178,208,368] for event(s) [1]
26-Oct-2021 10:17:36.003 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint$Acceptor.run APR socket [139,638,178,234,592] opened with remote port [57,028]
26-Oct-2021 10:17:36.003 FINE [http-apr-127.0.0.1-auto-4-Acceptor-0] org.apache.tomcat.util.net.AprEndpoint.processSocketWithOptions socket [139,638,178,234,592]
26-Oct-2021 10:17:36.003 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProcessor.setErrorState Error state [CLOSE_CONNECTION_NOW] reported while processing request
	java.io.EOFException: Unexpected EOF read on the socket
		at org.apache.coyote.http11.Http11InputBuffer.fill(Http11InputBuffer.java:824)
		at org.apache.coyote.http11.Http11InputBuffer.parseRequestLine(Http11InputBuffer.java:375)
		at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:511)
		at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
		at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:831)
		at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.doRun(AprEndpoint.java:2091)
		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)
26-Oct-2021 10:17:36.003 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@49106a70:139638178064400], Status in: [OPEN_READ], State out: [CLOSED]
26-Oct-2021 10:17:36.003 FINE [http-apr-127.0.0.1-auto-4-exec-7] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@574b1c8d]
26-Oct-2021 10:17:36.011 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,064,400] from poller
26-Oct-2021 10:17:36.011 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,208,368] with status [OPEN_READ]
26-Oct-2021 10:17:36.011 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@79b0ccda] for socket [139,638,178,208,368]
26-Oct-2021 10:17:36.011 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@79b0ccda] from waiting processors
26-Oct-2021 10:17:36.011 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read from buffer: [0]
26-Oct-2021 10:17:36.012 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read into buffer: [8]
26-Oct-2021 10:17:36.012 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Read from buffer: [8]
26-Oct-2021 10:17:36.012 FINE [WebSocketClient-AsyncIO-7] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
26-Oct-2021 10:17:36.013 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@79b0ccda] from waiting processors
26-Oct-2021 10:17:36.013 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@77772c44:139638178208368], Status in: [OPEN_READ], State out: [CLOSED]
26-Oct-2021 10:17:36.013 FINE [http-apr-127.0.0.1-auto-4-Poller] org.apache.tomcat.util.net.AprEndpoint$Poller.removeFromPoller Attempting to remove [139,638,178,208,368] from poller
26-Oct-2021 10:17:36.014 FINE [http-apr-127.0.0.1-auto-4-exec-8] org.apache.coyote.AbstractProtocol.removeWaitingProcessor Removed processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@79b0ccda] from waiting processors
26-Oct-2021 10:17:36.021 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket [139,638,178,234,592] with status [OPEN_READ]
26-Oct-2021 10:17:36.021 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor [null] for socket [139,638,178,234,592]
26-Oct-2021 10:17:36.022 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor [org.apache.coyote.http11.Http11Processor@574b1c8d] from cache
26-Oct-2021 10:17:36.022 FINE [http-apr-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: [158]
26-Oct-2021 10:17:36.022 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read from buffer: [0]
26-Oct-2021 10:17:36.022 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read into buffer: [158]
26-Oct-2021 10:17:36.022 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read from buffer: [158]
26-Oct-2021 10:17:36.022 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.http11.Http11InputBuffer.fill Received [GET /foo/ HTTP/1.1
Sec-WebSocket-Key: t9Hm8hX/H0tuIDtPHHrAfA==
Connection: upgrade
Sec-WebSocket-Version: 13
Host: localhost:44173
Upgrade: websocket

]
26-Oct-2021 10:17:36.165 FINE [main] org.apache.tomcat.websocket.WsSession.<init> Created WebSocket session [c]
26-Oct-2021 10:17:36.166 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Status in: [OPEN_READ], State out: [UPGRADING]
26-Oct-2021 10:17:36.166 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor [org.apache.coyote.http11.Http11Processor@574b1c8d]
26-Oct-2021 10:17:36.166 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProtocol$ConnectionHandler.process Created upgrade processor [org.apache.coyote.http11.upgrade.UpgradeProcessorInternal@2ddeadae] for socket wrapper [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592]
26-Oct-2021 10:17:36.167 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read from buffer: [0]
26-Oct-2021 10:17:36.167 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read into buffer: [11]
26-Oct-2021 10:17:36.167 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read from buffer: [11]
26-Oct-2021 10:17:36.167 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read from buffer: [0]
26-Oct-2021 10:17:36.167 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper.read Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Read into buffer: [0]
26-Oct-2021 10:17:36.167 FINE [WebSocketClient-AsyncIO-3] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [1], payload length [5]
26-Oct-2021 10:17:36.167 FINE [http-apr-127.0.0.1-auto-4-exec-9] org.apache.coyote.AbstractProcessorLight.process Socket: [org.apache.tomcat.util.net.AprEndpoint$AprSocketWrapper@231957bd:139638178234592], Status in: [OPEN_READ], State out: [UPGRADED]
26-Oct-2021 10:17:36.167 FINE [main] org.apache.tomcat.websocket.WsSession.doClose Closing WebSocket session [c]
26-Oct-2021 10:17:36.168 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-apr-127.0.0.1-auto-4-44173"]
26-Oct-2021 10:17:36.170 FINE [WebSocketClient-AsyncIO-1] org.apache.tomcat.websocket.WsFrameBase.processInitialHeader WebSocket frame received. fin [true], rsv [0], OpCode [8], payload length [2]
26-Oct-2021 10:17:36.173 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
26-Oct-2021 10:17:36.178 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-apr-127.0.0.1-auto-4-44173"]
26-Oct-2021 10:17:36.231 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-apr-127.0.0.1-auto-4-44173"]
------------- ---------------- ---------------

Testcase: testConnectToDigestEndpoint took 0.879 sec
Testcase: testConnectToServerEndpoint took 1.645 sec
Testcase: testConnectToBasicEndpoint took 0.251 sec
Testcase: testConnectToRootEndpoint took 0.399 sec

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

Last Updated: Tue, 26 Oct 2021 06:00:05 ().Python Logo