Testsuite: org.apache.catalina.nonblocking.TestNonBlockingAPI Tests run: 43, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 74.861 sec ------------- Standard Output --------------- Read [16], buffer [This is 16 bytes], total read [16], isReady [false] debug onError-async complete Read [16], buffer [This is 16 bytes], total read [16], isReady [false] debug onError-async complete ------------- ---------------- --------------- ------------- Standard Error ----------------- 07-Jan-2025 08:07:24.755 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterTrailerCrlf] 07-Jan-2025 08:07:27.227 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-1"] 07-Jan-2025 08:07:27.332 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:27.332 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:27.657 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-1-46649"] 07-Jan-2025 08:07:27.967 INFO [Thread-3] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:27.977 INFO [Thread-3] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:27.981 INFO [Thread-3] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:27.987 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-1-46649"] 07-Jan-2025 08:07:27.998 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:28.042 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-1-46649"] 07-Jan-2025 08:07:28.056 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-1-46649"] 07-Jan-2025 08:07:28.067 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterTrailerName] 07-Jan-2025 08:07:28.076 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-2"] 07-Jan-2025 08:07:28.079 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:28.080 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:28.095 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-2-46229"] 07-Jan-2025 08:07:28.333 INFO [Thread-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:28.334 INFO [Thread-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:28.336 INFO [Thread-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:28.338 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-2-46229"] 07-Jan-2025 08:07:28.344 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:28.362 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-2-46229"] 07-Jan-2025 08:07:28.374 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-2-46229"] 07-Jan-2025 08:07:28.383 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInTrailerValue] 07-Jan-2025 08:07:28.390 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-3"] 07-Jan-2025 08:07:28.395 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:28.399 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:28.423 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-3-38827"] 07-Jan-2025 08:07:28.665 INFO [Thread-9] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:28.666 INFO [Thread-9] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:28.666 INFO [Thread-9] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:28.667 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-3-38827"] 07-Jan-2025 08:07:28.670 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:28.678 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-3-38827"] 07-Jan-2025 08:07:28.685 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-3-38827"] 07-Jan-2025 08:07:28.697 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitMaximum] 07-Jan-2025 08:07:28.711 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-4"] 07-Jan-2025 08:07:28.725 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:28.726 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:28.742 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-4-40163"] 07-Jan-2025 08:07:37.843 INFO [Thread-55] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:37.843 INFO [Thread-55] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=0 notReadyCount=45 containerThreadCount=46 nonContainerThreadCount=0 07-Jan-2025 08:07:37.843 INFO [Thread-55] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:37.845 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-4-40163"] 07-Jan-2025 08:07:37.847 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:37.858 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-4-40163"] 07-Jan-2025 08:07:37.874 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-4-40163"] 07-Jan-2025 08:07:37.879 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInHeaderCrlf] 07-Jan-2025 08:07:37.885 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-5"] 07-Jan-2025 08:07:37.886 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:37.895 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:37.914 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-5-35419"] 07-Jan-2025 08:07:38.155 INFO [Thread-58] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:38.156 INFO [Thread-58] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:38.156 INFO [Thread-58] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:38.157 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-5-35419"] 07-Jan-2025 08:07:38.158 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:38.167 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-5-35419"] 07-Jan-2025 08:07:38.186 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-5-35419"] 07-Jan-2025 08:07:38.192 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testCanceledPostNoChunking] 07-Jan-2025 08:07:38.203 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-6"] 07-Jan-2025 08:07:38.206 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:38.206 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:38.228 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-6-36867"] org.apache.catalina.connector.ClientAbortException: java.io.EOFException at org.apache.catalina.connector.InputBuffer.realReadBytes(InputBuffer.java:312) at org.apache.catalina.connector.InputBuffer.checkByteBufferEof(InputBuffer.java:615) at org.apache.catalina.connector.InputBuffer.read(InputBuffer.java:358) at org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:132) at org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:111) at org.apache.catalina.nonblocking.TestNonBlockingAPI$CanceledPostReadListener.onDataAvailable(TestNonBlockingAPI.java:1934) at org.apache.coyote.Request.onDataAvailable(Request.java:256) at org.apache.catalina.connector.CoyoteAdapter.asyncDispatch(CoyoteAdapter.java:206) at org.apache.coyote.AbstractProcessor.dispatch(AbstractProcessor.java:242) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:57) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1793) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190) at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63) at java.base/java.lang.Thread.run(Thread.java:1575) Caused by: java.io.EOFException at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.fillReadBuffer(NioEndpoint.java:1345) at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.read(NioEndpoint.java:1233) at org.apache.coyote.http11.Http11InputBuffer.fill(Http11InputBuffer.java:796) at org.apache.coyote.http11.Http11InputBuffer.access$400(Http11InputBuffer.java:41) at org.apache.coyote.http11.Http11InputBuffer$SocketInputBuffer.doRead(Http11InputBuffer.java:1202) at org.apache.coyote.http11.filters.IdentityInputFilter.doRead(IdentityInputFilter.java:96) at org.apache.coyote.http11.Http11InputBuffer.doRead(Http11InputBuffer.java:244) at org.apache.coyote.Request.doRead(Request.java:626) at org.apache.catalina.connector.InputBuffer.realReadBytes(InputBuffer.java:303) ... 16 more 07-Jan-2025 08:07:38.265 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-6-36867"] 07-Jan-2025 08:07:38.269 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:38.277 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-6-36867"] 07-Jan-2025 08:07:38.302 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-6-36867"] 07-Jan-2025 08:07:38.310 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInChunkHeader] 07-Jan-2025 08:07:38.314 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-7"] 07-Jan-2025 08:07:38.323 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:38.323 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:38.354 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-7-45225"] 07-Jan-2025 08:07:38.607 INFO [Thread-61] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:38.608 INFO [Thread-61] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:38.608 INFO [Thread-61] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:38.608 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-7-45225"] 07-Jan-2025 08:07:38.609 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:38.620 WARNING [main] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] is still processing a request that has yet to finish. This is very likely to create a memory leak. You can control the time allowed for requests to finish by using the unloadDelay attribute of the standard Context implementation. Stack trace of request processing thread:[ java.base/sun.nio.ch.SocketDispatcher.write0(Native Method) java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:62) java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137) java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102) java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58) java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566) java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618) org.apache.tomcat.util.net.NioChannel.write(NioChannel.java:140) org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.doWrite(NioEndpoint.java:1430) org.apache.tomcat.util.net.SocketWrapperBase.doWrite(SocketWrapperBase.java:775) org.apache.tomcat.util.net.SocketWrapperBase.flushBlocking(SocketWrapperBase.java:739) org.apache.tomcat.util.net.SocketWrapperBase.flush(SocketWrapperBase.java:723) org.apache.coyote.http11.Http11OutputBuffer$SocketOutputBuffer.end(Http11OutputBuffer.java:569) org.apache.coyote.http11.filters.IdentityOutputFilter.end(IdentityOutputFilter.java:123) org.apache.coyote.http11.Http11OutputBuffer.end(Http11OutputBuffer.java:230) org.apache.coyote.http11.Http11Processor.finishResponse(Http11Processor.java:1233) org.apache.coyote.AbstractProcessor.action(AbstractProcessor.java:386) org.apache.coyote.Response.action(Response.java:207) org.apache.catalina.connector.OutputBuffer.close(OutputBuffer.java:259) org.apache.catalina.connector.Response.finishResponse(Response.java:445) org.apache.catalina.connector.CoyoteAdapter.asyncDispatch(CoyoteAdapter.java:250) org.apache.coyote.AbstractProcessor.dispatch(AbstractProcessor.java:242) org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:57) org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937) org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1793) org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1335) org.apache.tomcat.util.net.SocketWrapperBase.processSocket(SocketWrapperBase.java:794) org.apache.coyote.AbstractProcessor.executeDispatches(AbstractProcessor.java:950) org.apache.coyote.AbstractProcessor.action(AbstractProcessor.java:609) org.apache.coyote.Request.action(Request.java:505) org.apache.coyote.http11.filters.ChunkedInputFilter.parseTrailerFields(ChunkedInputFilter.java:498) org.apache.coyote.http11.filters.ChunkedInputFilter.doRead(ChunkedInputFilter.java:148) org.apache.coyote.http11.Http11InputBuffer.doRead(Http11InputBuffer.java:244) org.apache.coyote.Request.doRead(Request.java:626) org.apache.catalina.connector.InputBuffer.realReadBytes(InputBuffer.java:303) org.apache.catalina.connector.InputBuffer.checkByteBufferEof(InputBuffer.java:615) org.apache.catalina.connector.InputBuffer.read(InputBuffer.java:358) org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:132) org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:111) org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener$1.run(TestNonBlockingAPI.java:1198)] 07-Jan-2025 08:07:38.621 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-7-45225"] 07-Jan-2025 08:07:38.640 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-7-45225"] 07-Jan-2025 08:07:38.642 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterExtension] 07-Jan-2025 08:07:38.649 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-8"] 07-Jan-2025 08:07:38.653 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:38.659 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:38.675 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-8-41247"] 07-Jan-2025 08:07:38.926 INFO [Thread-64] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:38.926 INFO [Thread-64] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:38.926 INFO [Thread-64] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:38.930 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-8-41247"] 07-Jan-2025 08:07:38.937 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:38.949 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-8-41247"] 07-Jan-2025 08:07:38.965 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-8-41247"] 07-Jan-2025 08:07:38.970 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testBug55438NonBlockingReadWriteEmptyRead] 07-Jan-2025 08:07:38.983 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-9"] 07-Jan-2025 08:07:38.986 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:38.988 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:39.016 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-9-41079"] 07-Jan-2025 08:07:39.063 INFO [http-nio-127.0.0.1-auto-9-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadWriteListener.onAllDataRead onAllDataRead 07-Jan-2025 08:07:39.066 INFO [http-nio-127.0.0.1-auto-9-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadWriteListener$1.onWritePossible Writing [] 07-Jan-2025 08:07:39.085 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-9-41079"] 07-Jan-2025 08:07:39.086 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:39.096 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-9-41079"] 07-Jan-2025 08:07:39.103 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-9-41079"] 07-Jan-2025 08:07:39.113 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingWriteError02NoSwallow] 07-Jan-2025 08:07:39.119 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-10"] 07-Jan-2025 08:07:39.120 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:39.120 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:39.134 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-10-46221"] 07-Jan-2025 08:07:39.178 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-10-46221"] 07-Jan-2025 08:07:39.183 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:39.192 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-10-46221"] 07-Jan-2025 08:07:39.208 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-10-46221"] 07-Jan-2025 08:07:39.210 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInChunkBody] 07-Jan-2025 08:07:39.219 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-11"] 07-Jan-2025 08:07:39.229 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:39.229 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:39.252 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-11-46509"] 07-Jan-2025 08:07:39.491 INFO [Thread-67] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:39.492 INFO [Thread-67] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:39.492 INFO [Thread-67] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:39.493 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-11-46509"] 07-Jan-2025 08:07:39.495 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:39.498 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-11-46509"] 07-Jan-2025 08:07:39.509 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-11-46509"] 07-Jan-2025 08:07:39.518 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingWriteError02Swallow] 07-Jan-2025 08:07:39.524 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-12"] 07-Jan-2025 08:07:39.524 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:39.524 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:39.537 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-12-39487"] 07-Jan-2025 08:07:39.553 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-12-39487"] 07-Jan-2025 08:07:39.555 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:39.559 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-12-39487"] 07-Jan-2025 08:07:39.562 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-12-39487"] 07-Jan-2025 08:07:39.564 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testDelayedNBWrite] 07-Jan-2025 08:07:39.569 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-13"] 07-Jan-2025 08:07:39.570 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:39.571 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:39.584 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-13-33237"] 07-Jan-2025 08:07:39.624 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-13-33237"] 07-Jan-2025 08:07:39.632 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:39.635 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-13-33237"] 07-Jan-2025 08:07:39.642 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-13-33237"] 07-Jan-2025 08:07:39.647 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testDelayedNBReadWrite] 07-Jan-2025 08:07:39.654 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-14"] 07-Jan-2025 08:07:39.654 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:39.654 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:39.667 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-14-35445"] 07-Jan-2025 08:07:39.698 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-14-35445"] 07-Jan-2025 08:07:39.703 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:39.712 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-14-35445"] 07-Jan-2025 08:07:39.727 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-14-35445"] 07-Jan-2025 08:07:39.728 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitBeforeChunkBodyCrlf] 07-Jan-2025 08:07:39.736 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-15"] 07-Jan-2025 08:07:39.736 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:39.736 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:39.759 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-15-34131"] 07-Jan-2025 08:07:39.984 INFO [Thread-73] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:39.984 INFO [Thread-73] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=0 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=0 07-Jan-2025 08:07:39.984 INFO [Thread-73] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:39.987 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-15-34131"] 07-Jan-2025 08:07:39.988 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:39.996 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-15-34131"] 07-Jan-2025 08:07:40.001 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-15-34131"] 07-Jan-2025 08:07:40.011 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterExtensionDelimter] 07-Jan-2025 08:07:40.013 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-16"] 07-Jan-2025 08:07:40.014 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:40.021 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:40.047 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-16-39837"] 07-Jan-2025 08:07:40.274 INFO [Thread-76] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:40.274 INFO [Thread-76] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:40.274 INFO [Thread-76] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:40.275 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-16-39837"] 07-Jan-2025 08:07:40.281 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:40.286 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-16-39837"] 07-Jan-2025 08:07:40.303 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-16-39837"] 07-Jan-2025 08:07:40.313 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInTrailerCrlf] 07-Jan-2025 08:07:40.324 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-17"] 07-Jan-2025 08:07:40.325 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:40.325 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:40.346 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-17-38971"] 07-Jan-2025 08:07:40.574 INFO [Thread-79] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:40.579 INFO [Thread-79] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:40.579 INFO [Thread-79] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:40.581 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-17-38971"] 07-Jan-2025 08:07:40.589 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:40.592 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-17-38971"] 07-Jan-2025 08:07:40.611 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-17-38971"] 07-Jan-2025 08:07:40.613 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInTrailerName] 07-Jan-2025 08:07:40.618 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-18"] 07-Jan-2025 08:07:40.618 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:40.619 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:40.628 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-18-41301"] 07-Jan-2025 08:07:40.865 INFO [Thread-82] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:40.865 INFO [Thread-82] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:40.866 INFO [Thread-82] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:40.867 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-18-41301"] 07-Jan-2025 08:07:40.877 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:40.883 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-18-41301"] 07-Jan-2025 08:07:40.889 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-18-41301"] 07-Jan-2025 08:07:40.892 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingRead] 07-Jan-2025 08:07:40.897 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-19"] 07-Jan-2025 08:07:40.897 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:40.897 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:40.910 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-19-46019"] 07-Jan-2025 08:07:40.931 INFO [http-nio-127.0.0.1-auto-19-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onDataAvailable WANTMORE 07-Jan-2025 08:07:41.430 INFO [http-nio-127.0.0.1-auto-19-exec-2] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onDataAvailable WANTMORE 07-Jan-2025 08:07:41.931 INFO [http-nio-127.0.0.1-auto-19-exec-3] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onDataAvailable WANTMORE 07-Jan-2025 08:07:42.434 INFO [http-nio-127.0.0.1-auto-19-exec-4] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onDataAvailable WANTMORE 07-Jan-2025 08:07:42.934 INFO [http-nio-127.0.0.1-auto-19-exec-5] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onDataAvailable FINISHED 07-Jan-2025 08:07:42.934 INFO [http-nio-127.0.0.1-auto-19-exec-5] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=40 07-Jan-2025 08:07:42.934 INFO [http-nio-127.0.0.1-auto-19-exec-5] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:42.941 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-19-46019"] 07-Jan-2025 08:07:42.945 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:42.951 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-19-46019"] 07-Jan-2025 08:07:42.959 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-19-46019"] 07-Jan-2025 08:07:42.961 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterEndChunkCrlf] 07-Jan-2025 08:07:42.965 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-20"] 07-Jan-2025 08:07:42.966 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:42.966 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:42.979 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-20-40029"] 07-Jan-2025 08:07:43.218 INFO [Thread-85] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:43.218 INFO [Thread-85] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:43.218 INFO [Thread-85] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:43.225 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-20-40029"] 07-Jan-2025 08:07:43.227 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:43.232 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-20-40029"] 07-Jan-2025 08:07:43.239 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-20-40029"] 07-Jan-2025 08:07:43.241 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingWriteWithKeepAlive] 07-Jan-2025 08:07:43.251 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-21"] 07-Jan-2025 08:07:43.252 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:43.252 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:43.276 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-21-37679"] 07-Jan-2025 08:07:43.321 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:43.326 INFO [http-nio-127.0.0.1-auto-21-exec-3] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 7 ms. Bytes before=0 after=1048576 07-Jan-2025 08:07:43.330 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:43.345 INFO [http-nio-127.0.0.1-auto-21-exec-4] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 11 ms. Bytes before=1048576 after=2097152 07-Jan-2025 08:07:43.407 INFO [http-nio-127.0.0.1-auto-21-exec-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 2 ms. Bytes before=2097152 after=3145728 07-Jan-2025 08:07:43.493 INFO [http-nio-127.0.0.1-auto-21-exec-8] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 1 ms. Bytes before=3145728 after=4194304 07-Jan-2025 08:07:43.608 INFO [http-nio-127.0.0.1-auto-21-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 1 ms. Bytes before=4194304 after=5242880 07-Jan-2025 08:07:43.831 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:43.839 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:43.848 INFO [http-nio-127.0.0.1-auto-21-exec-4] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 6 ms. Bytes before=5242880 after=6291456 07-Jan-2025 08:07:44.345 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:44.357 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:44.405 INFO [http-nio-127.0.0.1-auto-21-exec-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 3 ms. Bytes before=6291456 after=7340032 07-Jan-2025 08:07:44.860 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:44.863 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:45.367 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:45.380 INFO [http-nio-127.0.0.1-auto-21-exec-8] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 3 ms. Bytes before=7340032 after=8388608 07-Jan-2025 08:07:45.400 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:45.900 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:45.906 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:45.952 INFO [http-nio-127.0.0.1-auto-21-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 5 ms. Bytes before=8388608 after=9437184 07-Jan-2025 08:07:46.407 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:46.409 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:46.910 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:46.913 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:46.958 INFO [http-nio-127.0.0.1-auto-21-exec-5] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 1 ms. Bytes before=9437184 after=10485760 07-Jan-2025 08:07:47.416 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:47.417 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:47.918 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:47.921 INFO [http-nio-127.0.0.1-auto-21-exec-8] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 0 ms. Bytes before=10485760 after=10485760 07-Jan-2025 08:07:47.921 INFO [http-nio-127.0.0.1-auto-21-exec-8] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBWriteServlet$1.onComplete onComplete 07-Jan-2025 08:07:47.934 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:48.436 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:48.437 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:48.945 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:48.946 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:49.447 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:49.455 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:49.956 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:49.956 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:50.458 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:50.459 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:07:50.960 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:07:50.999 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Client read 10486333 bytes 07-Jan-2025 08:07:50.999 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.000 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 108, End: 204909 07-Jan-2025 08:07:51.000 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqponml0zyxwvutsrqponml1 07-Jan-2025 08:07:51.000 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.000 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 204917, End: 409718 07-Jan-2025 08:07:51.001 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpo3200zyxwvutsrqpo3201 07-Jan-2025 08:07:51.001 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.001 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 409726, End: 614527 07-Jan-2025 08:07:51.001 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpo6400zyxwvutsrqpo6401 07-Jan-2025 08:07:51.001 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.001 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 614535, End: 819336 07-Jan-2025 08:07:51.002 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpo9600zyxwvutsrqpo9601 07-Jan-2025 08:07:51.002 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.002 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 819344, End: 1024145 07-Jan-2025 08:07:51.002 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpoC800zyxwvutsrqpoC801 07-Jan-2025 08:07:51.003 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.003 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1024153, End: 1228954 07-Jan-2025 08:07:51.003 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpoFA00zyxwvutsrqpoFA01 07-Jan-2025 08:07:51.003 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.005 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1228962, End: 1433763 07-Jan-2025 08:07:51.005 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp12C00zyxwvutsrqp12C01 07-Jan-2025 08:07:51.005 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.007 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1433771, End: 1638572 07-Jan-2025 08:07:51.007 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp15E00zyxwvutsrqp15E01 07-Jan-2025 08:07:51.007 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.008 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1638580, End: 1843381 07-Jan-2025 08:07:51.008 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp19000zyxwvutsrqp19001 07-Jan-2025 08:07:51.008 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.008 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1843389, End: 2048190 07-Jan-2025 08:07:51.009 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp1C200zyxwvutsrqp1C201 07-Jan-2025 08:07:51.010 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.010 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2048198, End: 2252999 07-Jan-2025 08:07:51.010 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp1F400zyxwvutsrqp1F401 07-Jan-2025 08:07:51.010 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2253007, End: 2457808 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp22600zyxwvutsrqp22601 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2457816, End: 2662617 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp25800zyxwvutsrqp25801 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2662625, End: 2867426 07-Jan-2025 08:07:51.011 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp28A00zyxwvutsrqp28A01 07-Jan-2025 08:07:51.012 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.012 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2867434, End: 3072235 07-Jan-2025 08:07:51.012 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp2BC00zyxwvutsrqp2BC01 07-Jan-2025 08:07:51.012 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.016 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3072243, End: 3277044 07-Jan-2025 08:07:51.016 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp2EE00zyxwvutsrqp2EE01 07-Jan-2025 08:07:51.016 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.016 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3277052, End: 3481853 07-Jan-2025 08:07:51.016 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp32000zyxwvutsrqp32001 07-Jan-2025 08:07:51.016 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3481861, End: 3686662 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp35200zyxwvutsrqp35201 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3686670, End: 3891471 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp38400zyxwvutsrqp38401 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3891479, End: 4096280 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp3B600zyxwvutsrqp3B601 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4096288, End: 4301089 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp3E800zyxwvutsrqp3E801 07-Jan-2025 08:07:51.017 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.018 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4301097, End: 4505898 07-Jan-2025 08:07:51.019 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp41A00zyxwvutsrqp41A01 07-Jan-2025 08:07:51.019 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.019 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4505906, End: 4710707 07-Jan-2025 08:07:51.020 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp44C00zyxwvutsrqp44C01 07-Jan-2025 08:07:51.020 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.020 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4710715, End: 4915516 07-Jan-2025 08:07:51.021 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp47E00zyxwvutsrqp47E01 07-Jan-2025 08:07:51.021 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.021 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4915524, End: 5120325 07-Jan-2025 08:07:51.021 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp4B000zyxwvutsrqp4B001 07-Jan-2025 08:07:51.021 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.021 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5120333, End: 5325134 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp4E200zyxwvutsrqp4E201 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5325142, End: 5529943 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp51400zyxwvutsrqp51401 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5529951, End: 5734752 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp54600zyxwvutsrqp54601 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.022 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5734760, End: 5939561 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp57800zyxwvutsrqp57801 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5939569, End: 6144370 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp5AA00zyxwvutsrqp5AA01 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6144378, End: 6349179 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp5DC00zyxwvutsrqp5DC01 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6349187, End: 6553988 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp60E00zyxwvutsrqp60E01 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6553996, End: 6758797 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp64000zyxwvutsrqp64001 07-Jan-2025 08:07:51.023 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.024 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6758805, End: 6963606 07-Jan-2025 08:07:51.024 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp67200zyxwvutsrqp67201 07-Jan-2025 08:07:51.024 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.025 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6963614, End: 7168415 07-Jan-2025 08:07:51.025 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp6A400zyxwvutsrqp6A401 07-Jan-2025 08:07:51.025 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.027 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7168423, End: 7373224 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp6D600zyxwvutsrqp6D601 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7373232, End: 7578033 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp70800zyxwvutsrqp70801 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7578041, End: 7782842 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp73A00zyxwvutsrqp73A01 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7782850, End: 7987651 07-Jan-2025 08:07:51.028 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp76C00zyxwvutsrqp76C01 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7987659, End: 8192460 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp79E00zyxwvutsrqp79E01 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8192468, End: 8397269 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp7D000zyxwvutsrqp7D001 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8397277, End: 8602078 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp80200zyxwvutsrqp80201 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8602086, End: 8806887 07-Jan-2025 08:07:51.029 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp83400zyxwvutsrqp83401 07-Jan-2025 08:07:51.030 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.030 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8806895, End: 9011696 07-Jan-2025 08:07:51.030 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp86600zyxwvutsrqp86601 07-Jan-2025 08:07:51.030 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.030 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9011704, End: 9216505 07-Jan-2025 08:07:51.033 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp89800zyxwvutsrqp89801 07-Jan-2025 08:07:51.035 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.035 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9216513, End: 9421314 07-Jan-2025 08:07:51.036 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp8CA00zyxwvutsrqp8CA01 07-Jan-2025 08:07:51.036 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.036 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9421322, End: 9626123 07-Jan-2025 08:07:51.036 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp8FC00zyxwvutsrqp8FC01 07-Jan-2025 08:07:51.036 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.039 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9626131, End: 9830932 07-Jan-2025 08:07:51.039 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp92E00zyxwvutsrqp92E01 07-Jan-2025 08:07:51.039 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9830940, End: 10035741 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp96000zyxwvutsrqp96001 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10035749, End: 10240550 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp99200zyxwvutsrqp99201 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10240558, End: 10445359 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp9C400zyxwvutsrqp9C401 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [a000] 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10445366, End: 10486327 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp9F600zyxwvutsrqp9F601 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [0] 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10486331, End: 10486332 07-Jan-2025 08:07:51.040 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal 07-Jan-2025 08:07:51.040 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-21-37679"] 07-Jan-2025 08:07:51.043 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:51.052 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-21-37679"] 07-Jan-2025 08:07:51.068 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-21-37679"] 07-Jan-2025 08:07:51.070 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterChunkBodyCrlf] 07-Jan-2025 08:07:51.075 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-22"] 07-Jan-2025 08:07:51.075 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:51.075 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:51.080 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-22-41825"] 07-Jan-2025 08:07:51.315 INFO [Thread-87] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:51.316 INFO [Thread-87] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=0 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=0 07-Jan-2025 08:07:51.316 INFO [Thread-87] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:51.317 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-22-41825"] 07-Jan-2025 08:07:51.319 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:51.324 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-22-41825"] 07-Jan-2025 08:07:51.327 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-22-41825"] 07-Jan-2025 08:07:51.329 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterHeaderCrlf] 07-Jan-2025 08:07:51.336 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-23"] 07-Jan-2025 08:07:51.337 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:51.337 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:51.364 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-23-43223"] 07-Jan-2025 08:07:51.604 INFO [Thread-90] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:51.604 INFO [Thread-90] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:51.604 INFO [Thread-90] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:51.608 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-23-43223"] 07-Jan-2025 08:07:51.613 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:51.620 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-23-43223"] 07-Jan-2025 08:07:51.629 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-23-43223"] 07-Jan-2025 08:07:51.630 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testCanceledPostChunked] 07-Jan-2025 08:07:51.638 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-24"] 07-Jan-2025 08:07:51.638 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:51.638 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:51.655 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-24-34135"] org.apache.catalina.connector.ClientAbortException: java.io.EOFException at org.apache.catalina.connector.InputBuffer.realReadBytes(InputBuffer.java:312) at org.apache.catalina.connector.InputBuffer.checkByteBufferEof(InputBuffer.java:615) at org.apache.catalina.connector.InputBuffer.read(InputBuffer.java:358) at org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:132) at org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:111) at org.apache.catalina.nonblocking.TestNonBlockingAPI$CanceledPostReadListener.onDataAvailable(TestNonBlockingAPI.java:1934) at org.apache.coyote.Request.onDataAvailable(Request.java:256) at org.apache.catalina.connector.CoyoteAdapter.asyncDispatch(CoyoteAdapter.java:206) at org.apache.coyote.AbstractProcessor.dispatch(AbstractProcessor.java:242) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:57) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1793) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190) at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63) at java.base/java.lang.Thread.run(Thread.java:1575) Caused by: java.io.EOFException at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.fillReadBuffer(NioEndpoint.java:1345) at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.read(NioEndpoint.java:1233) at org.apache.coyote.http11.Http11InputBuffer.fill(Http11InputBuffer.java:796) at org.apache.coyote.http11.Http11InputBuffer.access$400(Http11InputBuffer.java:41) at org.apache.coyote.http11.Http11InputBuffer$SocketInputBuffer.doRead(Http11InputBuffer.java:1202) at org.apache.coyote.http11.filters.ChunkedInputFilter.readBytes(ChunkedInputFilter.java:265) at org.apache.coyote.http11.filters.ChunkedInputFilter.fill(ChunkedInputFilter.java:306) at org.apache.coyote.http11.filters.ChunkedInputFilter.parseChunkHeader(ChunkedInputFilter.java:336) at org.apache.coyote.http11.filters.ChunkedInputFilter.doRead(ChunkedInputFilter.java:135) at org.apache.coyote.http11.Http11InputBuffer.doRead(Http11InputBuffer.java:244) at org.apache.coyote.Request.doRead(Request.java:626) at org.apache.catalina.connector.InputBuffer.realReadBytes(InputBuffer.java:303) ... 16 more 07-Jan-2025 08:07:51.681 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-24-34135"] 07-Jan-2025 08:07:51.682 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:51.691 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-24-34135"] 07-Jan-2025 08:07:51.702 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-24-34135"] 07-Jan-2025 08:07:51.706 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitBeforeTrailer] 07-Jan-2025 08:07:51.710 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-25"] 07-Jan-2025 08:07:51.711 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:51.711 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:51.722 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-25-38687"] 07-Jan-2025 08:07:51.938 INFO [Thread-93] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:51.939 INFO [Thread-93] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:51.940 INFO [Thread-93] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:51.940 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-25-38687"] 07-Jan-2025 08:07:51.944 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:51.950 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-25-38687"] 07-Jan-2025 08:07:51.959 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-25-38687"] 07-Jan-2025 08:07:51.963 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedNoSplits] 07-Jan-2025 08:07:51.968 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-26"] 07-Jan-2025 08:07:51.969 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:51.969 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:51.988 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-26-32899"] 07-Jan-2025 08:07:52.006 INFO [Thread-95] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:52.007 INFO [Thread-95] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=0 containerThreadCount=1 nonContainerThreadCount=1 07-Jan-2025 08:07:52.007 INFO [Thread-95] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:52.008 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-26-32899"] 07-Jan-2025 08:07:52.010 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:52.015 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-26-32899"] 07-Jan-2025 08:07:52.018 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-26-32899"] 07-Jan-2025 08:07:52.020 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitBeforeChunkHeader] 07-Jan-2025 08:07:52.025 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-27"] 07-Jan-2025 08:07:52.029 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:52.030 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:52.040 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-27-39083"] 07-Jan-2025 08:07:52.272 INFO [Thread-97] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:52.275 INFO [Thread-97] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=0 containerThreadCount=1 nonContainerThreadCount=1 07-Jan-2025 08:07:52.275 INFO [Thread-97] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:52.276 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-27-39083"] 07-Jan-2025 08:07:52.286 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:52.289 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-27-39083"] 07-Jan-2025 08:07:52.316 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-27-39083"] 07-Jan-2025 08:07:52.318 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitBeforeExtensionDelimter] 07-Jan-2025 08:07:52.328 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-28"] 07-Jan-2025 08:07:52.333 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:52.334 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:52.353 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-28-44631"] 07-Jan-2025 08:07:52.578 INFO [Thread-100] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:52.578 INFO [Thread-100] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:52.578 INFO [Thread-100] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:52.579 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-28-44631"] 07-Jan-2025 08:07:52.584 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:52.589 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-28-44631"] 07-Jan-2025 08:07:52.593 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-28-44631"] 07-Jan-2025 08:07:52.596 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInExtension] 07-Jan-2025 08:07:52.603 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-29"] 07-Jan-2025 08:07:52.603 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:52.603 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:52.613 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-29-45925"] 07-Jan-2025 08:07:52.831 INFO [Thread-103] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:52.831 INFO [Thread-103] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:52.832 INFO [Thread-103] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:52.834 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-29-45925"] 07-Jan-2025 08:07:52.837 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:52.839 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-29-45925"] 07-Jan-2025 08:07:52.842 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-29-45925"] 07-Jan-2025 08:07:52.847 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadWithDispatch] 07-Jan-2025 08:07:52.850 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-30"] 07-Jan-2025 08:07:52.850 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:52.850 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:52.857 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-30-36085"] 07-Jan-2025 08:07:54.898 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-30-36085"] 07-Jan-2025 08:07:54.900 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:54.907 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-30-36085"] 07-Jan-2025 08:07:54.911 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-30-36085"] 07-Jan-2025 08:07:54.913 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingWriteError01NoListenerComplete] 07-Jan-2025 08:07:54.917 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-31"] 07-Jan-2025 08:07:54.918 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:54.918 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:54.941 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-31-44319"] 07-Jan-2025 08:07:54.953 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [3] ms 07-Jan-2025 08:07:54.953 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.953 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.953 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.954 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:54.956 INFO [http-nio-127.0.0.1-auto-31-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 5 ms. Bytes before=0 after=1048576 07-Jan-2025 08:07:55.459 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.460 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.460 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.460 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.461 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.462 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.464 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [1] ms 07-Jan-2025 08:07:55.464 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.464 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.464 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.464 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [3392] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.465 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.466 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read 331072 bytes 07-Jan-2025 08:07:55.467 INFO [http-nio-127.0.0.1-auto-31-exec-2] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onError WriteListener.onError java.io.IOException: Broken pipe at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method) at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:62) at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137) at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102) at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58) at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566) at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618) at org.apache.tomcat.util.net.NioChannel.write(NioChannel.java:140) at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.doWrite(NioEndpoint.java:1460) at org.apache.tomcat.util.net.SocketWrapperBase.doWrite(SocketWrapperBase.java:775) at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.flushNonBlocking(NioEndpoint.java:1358) at org.apache.tomcat.util.net.SocketWrapperBase.flush(SocketWrapperBase.java:725) at org.apache.coyote.http11.Http11OutputBuffer.flushBuffer(Http11OutputBuffer.java:492) at org.apache.coyote.http11.Http11Processor.flushBufferedWrite(Http11Processor.java:1144) at org.apache.coyote.AbstractProcessor.dispatch(AbstractProcessor.java:206) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:57) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1793) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190) at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63) at java.base/java.lang.Thread.run(Thread.java:1575) 07-Jan-2025 08:07:55.468 INFO [http-nio-127.0.0.1-auto-31-exec-2] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBWriteServlet$1.onError AsyncListener.onError 07-Jan-2025 08:07:55.468 INFO [http-nio-127.0.0.1-auto-31-exec-2] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBWriteServlet$1.onComplete onComplete 07-Jan-2025 08:07:55.576 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-31-44319"] 07-Jan-2025 08:07:55.582 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:55.585 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-31-44319"] 07-Jan-2025 08:07:55.593 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-31-44319"] 07-Jan-2025 08:07:55.597 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingWriteError01ListenerComplete] 07-Jan-2025 08:07:55.604 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-32"] 07-Jan-2025 08:07:55.607 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:55.607 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:55.662 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-32-33057"] 07-Jan-2025 08:07:55.692 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [3] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.693 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.694 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.694 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.694 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.694 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.694 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.694 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.694 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:55.696 INFO [http-nio-127.0.0.1-auto-32-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 5 ms. Bytes before=0 after=1048576 07-Jan-2025 08:07:56.195 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.196 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.196 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.196 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.196 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.196 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.196 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.196 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.198 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [2] ms 07-Jan-2025 08:07:56.198 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.198 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.198 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.198 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.198 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.198 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.201 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [3392] bytes in [3] ms 07-Jan-2025 08:07:56.201 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.201 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.201 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.202 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read [8192] bytes in [0] ms 07-Jan-2025 08:07:56.204 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.doTestNonBlockingWriteError01NoListenerComplete Client read 331072 bytes 07-Jan-2025 08:07:56.205 INFO [http-nio-127.0.0.1-auto-32-exec-4] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onError WriteListener.onError java.io.IOException: Broken pipe at java.base/sun.nio.ch.SocketDispatcher.write0(Native Method) at java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:62) at java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:137) at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:102) at java.base/sun.nio.ch.IOUtil.write(IOUtil.java:58) at java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:566) at java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:618) at org.apache.tomcat.util.net.NioChannel.write(NioChannel.java:140) at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.doWrite(NioEndpoint.java:1460) at org.apache.tomcat.util.net.SocketWrapperBase.doWrite(SocketWrapperBase.java:775) at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.flushNonBlocking(NioEndpoint.java:1358) at org.apache.tomcat.util.net.SocketWrapperBase.flush(SocketWrapperBase.java:725) at org.apache.coyote.http11.Http11OutputBuffer.flushBuffer(Http11OutputBuffer.java:492) at org.apache.coyote.http11.Http11Processor.flushBufferedWrite(Http11Processor.java:1144) at org.apache.coyote.AbstractProcessor.dispatch(AbstractProcessor.java:206) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:57) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1793) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190) at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63) at java.base/java.lang.Thread.run(Thread.java:1575) 07-Jan-2025 08:07:56.205 INFO [http-nio-127.0.0.1-auto-32-exec-4] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBWriteServlet$1.onError AsyncListener.onError 07-Jan-2025 08:07:56.205 INFO [http-nio-127.0.0.1-auto-32-exec-4] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBWriteServlet$1.onComplete onComplete 07-Jan-2025 08:07:56.308 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-32-33057"] 07-Jan-2025 08:07:56.314 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:56.316 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-32-33057"] 07-Jan-2025 08:07:56.336 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-32-33057"] 07-Jan-2025 08:07:56.342 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitBeforeEndChunkCrlf] 07-Jan-2025 08:07:56.345 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-33"] 07-Jan-2025 08:07:56.345 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:56.345 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:56.352 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-33-37243"] 07-Jan-2025 08:07:56.567 INFO [Thread-107] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:56.567 INFO [Thread-107] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:56.567 INFO [Thread-107] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:56.568 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-33-37243"] 07-Jan-2025 08:07:56.574 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:56.580 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-33-37243"] 07-Jan-2025 08:07:56.593 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-33-37243"] 07-Jan-2025 08:07:56.597 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterTrailerValue] 07-Jan-2025 08:07:56.599 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-34"] 07-Jan-2025 08:07:56.601 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:56.601 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:56.619 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-34-34009"] 07-Jan-2025 08:07:56.843 INFO [Thread-110] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:56.843 INFO [Thread-110] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:56.843 INFO [Thread-110] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:56.847 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-34-34009"] 07-Jan-2025 08:07:56.848 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:56.853 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-34-34009"] 07-Jan-2025 08:07:56.856 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-34-34009"] 07-Jan-2025 08:07:56.860 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInEndChunkCrlf] 07-Jan-2025 08:07:56.865 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-35"] 07-Jan-2025 08:07:56.868 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:56.868 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:56.880 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-35-43183"] 07-Jan-2025 08:07:57.094 INFO [Thread-113] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:57.095 INFO [Thread-113] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:57.095 INFO [Thread-113] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:57.103 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-35-43183"] 07-Jan-2025 08:07:57.117 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:57.119 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-35-43183"] 07-Jan-2025 08:07:57.138 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-35-43183"] 07-Jan-2025 08:07:57.142 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterChunkHeader] 07-Jan-2025 08:07:57.145 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-36"] 07-Jan-2025 08:07:57.150 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:57.150 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:57.172 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-36-39719"] 07-Jan-2025 08:07:57.389 INFO [Thread-116] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:57.391 INFO [Thread-116] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:57.391 INFO [Thread-116] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:57.391 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-36-39719"] 07-Jan-2025 08:07:57.395 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:57.397 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-36-39719"] 07-Jan-2025 08:07:57.404 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-36-39719"] 07-Jan-2025 08:07:57.408 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadIgnoreIsReady] 07-Jan-2025 08:07:57.411 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-37"] 07-Jan-2025 08:07:57.412 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:57.412 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:57.426 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-37-44099"] 07-Jan-2025 08:07:57.442 INFO [http-nio-127.0.0.1-auto-37-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onError ReadListener.onError totalData=0 java.lang.IllegalStateException: In non-blocking mode you may not read from the ServletInputStream until the previous read has completed and isReady() returns true at org.apache.catalina.connector.CoyoteInputStream.checkNonBlockingRead(CoyoteInputStream.java:215) at org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:117) at org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:111) at org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onDataAvailable(TestNonBlockingAPI.java:1121) at org.apache.coyote.Request.onDataAvailable(Request.java:256) at org.apache.catalina.connector.CoyoteAdapter.asyncDispatch(CoyoteAdapter.java:206) at org.apache.coyote.AbstractProcessor.dispatch(AbstractProcessor.java:242) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:50) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1793) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190) at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63) at java.base/java.lang.Thread.run(Thread.java:1575) 07-Jan-2025 08:07:57.443 INFO [http-nio-127.0.0.1-auto-37-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onError AsyncListener.onError 07-Jan-2025 08:07:57.443 INFO [http-nio-127.0.0.1-auto-37-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:58.948 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-37-44099"] 07-Jan-2025 08:07:58.949 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:58.957 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-37-44099"] 07-Jan-2025 08:07:58.960 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-37-44099"] 07-Jan-2025 08:07:58.963 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInFinalCrlf] 07-Jan-2025 08:07:58.966 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-38"] 07-Jan-2025 08:07:58.967 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:58.967 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:58.971 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-38-43699"] 07-Jan-2025 08:07:59.181 INFO [Thread-119] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:07:59.182 INFO [Thread-119] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:07:59.182 INFO [Thread-119] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:07:59.182 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-38-43699"] 07-Jan-2025 08:07:59.186 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:07:59.188 WARNING [main] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] is still processing a request that has yet to finish. This is very likely to create a memory leak. You can control the time allowed for requests to finish by using the unloadDelay attribute of the standard Context implementation. Stack trace of request processing thread:[ java.base/java.nio.charset.CharsetDecoder.charset(CharsetDecoder.java:247) java.base/sun.nio.cs.ThreadLocalCoders$1.hasName(ThreadLocalCoders.java:91) java.base/sun.nio.cs.ThreadLocalCoders$Cache.forName(ThreadLocalCoders.java:71) java.base/sun.nio.cs.ThreadLocalCoders.decoderFor(ThreadLocalCoders.java:107) java.base/java.nio.charset.Charset.decode(Charset.java:885) org.apache.tomcat.util.buf.ByteChunk.toStringInternal(ByteChunk.java:622) org.apache.tomcat.util.buf.StringCache.toString(StringCache.java:231) org.apache.tomcat.util.buf.ByteChunk.toString(ByteChunk.java:580) org.apache.tomcat.util.buf.ByteChunk.toString(ByteChunk.java:565) org.apache.tomcat.util.buf.MessageBytes.toStringType(MessageBytes.java:196) org.apache.catalina.connector.Request.getRequestURI(Request.java:2208) org.apache.catalina.valves.AbstractAccessLogValve$RequestElement.addElement(AbstractAccessLogValve.java:1158) org.apache.catalina.valves.AbstractAccessLogValve.log(AbstractAccessLogValve.java:686) org.apache.catalina.core.AccessLogAdapter.log(AccessLogAdapter.java:48) org.apache.catalina.core.ContainerBase.logAccess(ContainerBase.java:884) org.apache.catalina.core.ContainerBase.logAccess(ContainerBase.java:891) org.apache.catalina.connector.CoyoteAdapter.asyncDispatch(CoyoteAdapter.java:285) org.apache.coyote.AbstractProcessor.dispatch(AbstractProcessor.java:242) org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:57) org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937) org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1793) org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) org.apache.tomcat.util.net.AbstractEndpoint.processSocket(AbstractEndpoint.java:1335) org.apache.tomcat.util.net.SocketWrapperBase.processSocket(SocketWrapperBase.java:794) org.apache.coyote.AbstractProcessor.executeDispatches(AbstractProcessor.java:950) org.apache.coyote.AbstractProcessor.action(AbstractProcessor.java:609) org.apache.coyote.Request.action(Request.java:505) org.apache.coyote.http11.filters.ChunkedInputFilter.parseTrailerFields(ChunkedInputFilter.java:498) org.apache.coyote.http11.filters.ChunkedInputFilter.doRead(ChunkedInputFilter.java:148) org.apache.coyote.http11.Http11InputBuffer.doRead(Http11InputBuffer.java:244) org.apache.coyote.Request.doRead(Request.java:626) org.apache.catalina.connector.InputBuffer.realReadBytes(InputBuffer.java:303) org.apache.catalina.connector.InputBuffer.checkByteBufferEof(InputBuffer.java:615) org.apache.catalina.connector.InputBuffer.read(InputBuffer.java:358) org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:132) org.apache.catalina.connector.CoyoteInputStream.read(CoyoteInputStream.java:111) org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener$1.run(TestNonBlockingAPI.java:1198)] 07-Jan-2025 08:07:59.189 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-38-43699"] 07-Jan-2025 08:07:59.198 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-38-43699"] 07-Jan-2025 08:07:59.207 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadAsync] 07-Jan-2025 08:07:59.208 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-39"] 07-Jan-2025 08:07:59.209 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:07:59.209 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:07:59.224 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-39-42627"] 07-Jan-2025 08:08:29.345 INFO [Thread-22423] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=16000000 07-Jan-2025 08:08:29.351 INFO [Thread-22423] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=21384 notReadyCount=919 containerThreadCount=920 nonContainerThreadCount=21384 07-Jan-2025 08:08:29.351 INFO [Thread-22423] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:08:29.357 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-39-42627"] 07-Jan-2025 08:08:29.360 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:08:29.362 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-39-42627"] 07-Jan-2025 08:08:29.369 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-39-42627"] 07-Jan-2025 08:08:29.371 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitAfterTrailerDelimter] 07-Jan-2025 08:08:29.372 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-40"] 07-Jan-2025 08:08:29.373 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:08:29.373 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:08:29.376 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-40-36979"] 07-Jan-2025 08:08:29.585 INFO [Thread-22426] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:08:29.586 INFO [Thread-22426] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:08:29.586 INFO [Thread-22426] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:08:29.587 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-40-36979"] 07-Jan-2025 08:08:29.589 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:08:29.594 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-40-36979"] 07-Jan-2025 08:08:29.597 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-40-36979"] 07-Jan-2025 08:08:29.600 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingWrite] 07-Jan-2025 08:08:29.604 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-41"] 07-Jan-2025 08:08:29.605 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:08:29.605 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:08:29.610 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-41-40355"] 07-Jan-2025 08:08:29.618 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:29.623 INFO [http-nio-127.0.0.1-auto-41-exec-1] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 6 ms. Bytes before=0 after=1048576 07-Jan-2025 08:08:29.624 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:29.629 INFO [http-nio-127.0.0.1-auto-41-exec-2] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 5 ms. Bytes before=1048576 after=2097152 07-Jan-2025 08:08:29.639 INFO [http-nio-127.0.0.1-auto-41-exec-4] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 1 ms. Bytes before=2097152 after=3145728 07-Jan-2025 08:08:29.723 INFO [http-nio-127.0.0.1-auto-41-exec-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 2 ms. Bytes before=3145728 after=4194304 07-Jan-2025 08:08:30.126 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:30.129 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:30.130 INFO [http-nio-127.0.0.1-auto-41-exec-9] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 2 ms. Bytes before=4194304 after=5242880 07-Jan-2025 08:08:30.630 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:30.635 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:30.638 INFO [http-nio-127.0.0.1-auto-41-exec-2] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 1 ms. Bytes before=5242880 after=6291456 07-Jan-2025 08:08:30.758 INFO [http-nio-127.0.0.1-auto-41-exec-5] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 1 ms. Bytes before=6291456 after=7340032 07-Jan-2025 08:08:30.840 INFO [http-nio-127.0.0.1-auto-41-exec-7] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 6 ms. Bytes before=7340032 after=8388608 07-Jan-2025 08:08:31.136 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:31.137 INFO [http-nio-127.0.0.1-auto-41-exec-10] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 0 ms. Bytes before=8388608 after=9437184 07-Jan-2025 08:08:31.138 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:31.638 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:31.646 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:32.146 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:32.148 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:32.153 INFO [http-nio-127.0.0.1-auto-41-exec-3] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 1 ms. Bytes before=9437184 after=10485760 07-Jan-2025 08:08:32.650 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:32.651 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:32.693 INFO [http-nio-127.0.0.1-auto-41-exec-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestWriteListener.onWritePossible Write took: 0 ms. Bytes before=10485760 after=10485760 07-Jan-2025 08:08:32.693 INFO [http-nio-127.0.0.1-auto-41-exec-6] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBWriteServlet$1.onComplete onComplete 07-Jan-2025 08:08:33.152 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:33.153 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:33.657 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:33.659 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:34.160 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:34.175 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:34.676 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:34.677 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:35.179 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:35.180 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:35.681 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:35.682 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:36.182 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:36.183 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:36.686 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:36.687 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Read 663552 bytes, pause 500ms 07-Jan-2025 08:08:37.190 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Reading data 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Client read 10486333 bytes 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 108, End: 204909 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqponml0zyxwvutsrqponml1 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 204917, End: 409718 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpo3200zyxwvutsrqpo3201 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 409726, End: 614527 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpo6400zyxwvutsrqpo6401 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.245 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 614535, End: 819336 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpo9600zyxwvutsrqpo9601 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 819344, End: 1024145 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpoC800zyxwvutsrqpoC801 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1024153, End: 1228954 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqpoFA00zyxwvutsrqpoFA01 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.247 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1228962, End: 1433763 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp12C00zyxwvutsrqp12C01 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1433771, End: 1638572 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp15E00zyxwvutsrqp15E01 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1638580, End: 1843381 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp19000zyxwvutsrqp19001 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 1843389, End: 2048190 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp1C200zyxwvutsrqp1C201 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2048198, End: 2252999 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp1F400zyxwvutsrqp1F401 07-Jan-2025 08:08:37.248 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2253007, End: 2457808 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp22600zyxwvutsrqp22601 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2457816, End: 2662617 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp25800zyxwvutsrqp25801 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2662625, End: 2867426 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp28A00zyxwvutsrqp28A01 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.249 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 2867434, End: 3072235 07-Jan-2025 08:08:37.250 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp2BC00zyxwvutsrqp2BC01 07-Jan-2025 08:08:37.250 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.250 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3072243, End: 3277044 07-Jan-2025 08:08:37.250 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp2EE00zyxwvutsrqp2EE01 07-Jan-2025 08:08:37.250 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.250 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3277052, End: 3481853 07-Jan-2025 08:08:37.251 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp32000zyxwvutsrqp32001 07-Jan-2025 08:08:37.251 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.251 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3481861, End: 3686662 07-Jan-2025 08:08:37.251 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp35200zyxwvutsrqp35201 07-Jan-2025 08:08:37.251 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.251 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3686670, End: 3891471 07-Jan-2025 08:08:37.252 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp38400zyxwvutsrqp38401 07-Jan-2025 08:08:37.252 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.252 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 3891479, End: 4096280 07-Jan-2025 08:08:37.252 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp3B600zyxwvutsrqp3B601 07-Jan-2025 08:08:37.252 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.253 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4096288, End: 4301089 07-Jan-2025 08:08:37.253 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp3E800zyxwvutsrqp3E801 07-Jan-2025 08:08:37.253 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.253 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4301097, End: 4505898 07-Jan-2025 08:08:37.253 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp41A00zyxwvutsrqp41A01 07-Jan-2025 08:08:37.256 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.256 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4505906, End: 4710707 07-Jan-2025 08:08:37.256 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp44C00zyxwvutsrqp44C01 07-Jan-2025 08:08:37.256 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.257 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4710715, End: 4915516 07-Jan-2025 08:08:37.257 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp47E00zyxwvutsrqp47E01 07-Jan-2025 08:08:37.257 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.258 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 4915524, End: 5120325 07-Jan-2025 08:08:37.258 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp4B000zyxwvutsrqp4B001 07-Jan-2025 08:08:37.258 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.258 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5120333, End: 5325134 07-Jan-2025 08:08:37.258 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp4E200zyxwvutsrqp4E201 07-Jan-2025 08:08:37.259 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.259 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5325142, End: 5529943 07-Jan-2025 08:08:37.259 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp51400zyxwvutsrqp51401 07-Jan-2025 08:08:37.259 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.259 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5529951, End: 5734752 07-Jan-2025 08:08:37.259 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp54600zyxwvutsrqp54601 07-Jan-2025 08:08:37.260 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.260 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5734760, End: 5939561 07-Jan-2025 08:08:37.260 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp57800zyxwvutsrqp57801 07-Jan-2025 08:08:37.260 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.260 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 5939569, End: 6144370 07-Jan-2025 08:08:37.260 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp5AA00zyxwvutsrqp5AA01 07-Jan-2025 08:08:37.260 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6144378, End: 6349179 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp5DC00zyxwvutsrqp5DC01 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6349187, End: 6553988 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp60E00zyxwvutsrqp60E01 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6553996, End: 6758797 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp64000zyxwvutsrqp64001 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6758805, End: 6963606 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp67200zyxwvutsrqp67201 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 6963614, End: 7168415 07-Jan-2025 08:08:37.261 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp6A400zyxwvutsrqp6A401 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7168423, End: 7373224 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp6D600zyxwvutsrqp6D601 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7373232, End: 7578033 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp70800zyxwvutsrqp70801 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7578041, End: 7782842 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp73A00zyxwvutsrqp73A01 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7782850, End: 7987651 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp76C00zyxwvutsrqp76C01 07-Jan-2025 08:08:37.262 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 7987659, End: 8192460 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp79E00zyxwvutsrqp79E01 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8192468, End: 8397269 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp7D000zyxwvutsrqp7D001 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8397277, End: 8602078 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp80200zyxwvutsrqp80201 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8602086, End: 8806887 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp83400zyxwvutsrqp83401 07-Jan-2025 08:08:37.263 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 8806895, End: 9011696 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp86600zyxwvutsrqp86601 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9011704, End: 9216505 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp89800zyxwvutsrqp89801 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9216513, End: 9421314 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp8CA00zyxwvutsrqp8CA01 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9421322, End: 9626123 07-Jan-2025 08:08:37.264 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp8FC00zyxwvutsrqp8FC01 07-Jan-2025 08:08:37.265 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.265 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9626131, End: 9830932 07-Jan-2025 08:08:37.265 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp92E00zyxwvutsrqp92E01 07-Jan-2025 08:08:37.265 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.265 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 9830940, End: 10035741 07-Jan-2025 08:08:37.266 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp96000zyxwvutsrqp96001 07-Jan-2025 08:08:37.266 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.266 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10035749, End: 10240550 07-Jan-2025 08:08:37.266 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp99200zyxwvutsrqp99201 07-Jan-2025 08:08:37.266 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [32000] 07-Jan-2025 08:08:37.266 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10240558, End: 10445359 07-Jan-2025 08:08:37.266 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp9C400zyxwvutsrqp9C401 07-Jan-2025 08:08:37.267 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [a000] 07-Jan-2025 08:08:37.267 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10445366, End: 10486327 07-Jan-2025 08:08:37.267 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal zyxwvutsrqp9F600zyxwvutsrqp9F601 07-Jan-2025 08:08:37.267 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal [0] 07-Jan-2025 08:08:37.267 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal Start : 10486331, End: 10486332 07-Jan-2025 08:08:37.267 INFO [main] org.apache.catalina.nonblocking.TestNonBlockingAPI.testNonBlockingWriteInternal 07-Jan-2025 08:08:37.269 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-41-40355"] 07-Jan-2025 08:08:37.270 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:08:37.275 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-41-40355"] 07-Jan-2025 08:08:37.281 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-41-40355"] 07-Jan-2025 08:08:37.283 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitBeforeTrailerValue] 07-Jan-2025 08:08:37.292 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-42"] 07-Jan-2025 08:08:37.293 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:08:37.293 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:08:37.304 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-42-34943"] 07-Jan-2025 08:08:37.526 INFO [Thread-22429] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:08:37.527 INFO [Thread-22429] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=1 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=1 07-Jan-2025 08:08:37.527 INFO [Thread-22429] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:08:37.529 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-42-34943"] 07-Jan-2025 08:08:37.531 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:08:37.534 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-42-34943"] 07-Jan-2025 08:08:37.538 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-42-34943"] 07-Jan-2025 08:08:37.540 INFO [main] org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case [testNonBlockingReadChunkedSplitInChunkBodyCrlf] 07-Jan-2025 08:08:37.544 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-43"] 07-Jan-2025 08:08:37.545 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat] 07-Jan-2025 08:08:37.545 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.99-dev] 07-Jan-2025 08:08:37.556 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-127.0.0.1-auto-43-38611"] 07-Jan-2025 08:08:37.770 INFO [Thread-22431] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestReadListener.onAllDataRead onAllDataRead totalData=20 07-Jan-2025 08:08:37.770 INFO [Thread-22431] org.apache.catalina.nonblocking.TestNonBlockingAPI$TestAsyncReadListener.onAllDataRead isReadyCount=0 notReadyCount=1 containerThreadCount=2 nonContainerThreadCount=0 07-Jan-2025 08:08:37.771 INFO [Thread-22431] org.apache.catalina.nonblocking.TestNonBlockingAPI$NBReadServlet$1.onComplete onComplete 07-Jan-2025 08:08:37.772 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-127.0.0.1-auto-43-38611"] 07-Jan-2025 08:08:37.776 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat] 07-Jan-2025 08:08:37.781 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-127.0.0.1-auto-43-38611"] 07-Jan-2025 08:08:37.786 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-127.0.0.1-auto-43-38611"] ------------- ---------------- --------------- Testcase: testNonBlockingReadChunkedSplitAfterTrailerCrlf took 4.929 sec Testcase: testNonBlockingReadChunkedSplitAfterTrailerName took 0.312 sec Testcase: testNonBlockingReadChunkedSplitInTrailerValue took 0.31 sec Testcase: testNonBlockingReadChunkedSplitMaximum took 9.184 sec Testcase: testNonBlockingReadChunkedSplitInHeaderCrlf took 0.313 sec Testcase: testCanceledPostNoChunking took 0.115 sec Testcase: testNonBlockingReadChunkedSplitInChunkHeader took 0.335 sec Testcase: testNonBlockingReadChunkedSplitAfterExtension took 0.327 sec Testcase: testBug55438NonBlockingReadWriteEmptyRead took 0.137 sec Testcase: testNonBlockingWriteError02NoSwallow took 0.099 sec Testcase: testNonBlockingReadChunkedSplitInChunkBody took 0.307 sec Testcase: testNonBlockingWriteError02Swallow took 0.045 sec Testcase: testDelayedNBWrite took 0.081 sec Testcase: testDelayedNBReadWrite took 0.083 sec Testcase: testNonBlockingReadChunkedSplitBeforeChunkBodyCrlf took 0.275 sec Testcase: testNonBlockingReadChunkedSplitAfterExtensionDelimter took 0.309 sec Testcase: testNonBlockingReadChunkedSplitInTrailerCrlf took 0.3 sec Testcase: testNonBlockingReadChunkedSplitInTrailerName took 0.278 sec Testcase: testNonBlockingRead took 2.07 sec Testcase: testNonBlockingReadChunkedSplitAfterEndChunkCrlf took 0.28 sec Testcase: testNonBlockingWriteWithKeepAlive took 7.828 sec Testcase: testNonBlockingReadChunkedSplitAfterChunkBodyCrlf took 0.259 sec Testcase: testNonBlockingReadChunkedSplitAfterHeaderCrlf took 0.301 sec Testcase: testCanceledPostChunked took 0.073 sec Testcase: testNonBlockingReadChunkedSplitBeforeTrailer took 0.255 sec Testcase: testNonBlockingReadChunkedNoSplits took 0.059 sec Testcase: testNonBlockingReadChunkedSplitBeforeChunkHeader took 0.298 sec Testcase: testNonBlockingReadChunkedSplitBeforeExtensionDelimter took 0.278 sec Testcase: testNonBlockingReadChunkedSplitInExtension took 0.25 sec Testcase: testNonBlockingReadWithDispatch took 2.066 sec Testcase: testNonBlockingWriteError01NoListenerComplete took 0.683 sec Testcase: testNonBlockingWriteError01ListenerComplete took 0.74 sec Testcase: testNonBlockingReadChunkedSplitBeforeEndChunkCrlf took 0.257 sec Testcase: testNonBlockingReadChunkedSplitAfterTrailerValue took 0.26 sec Testcase: testNonBlockingReadChunkedSplitInEndChunkCrlf took 0.282 sec Testcase: testNonBlockingReadChunkedSplitAfterChunkHeader took 0.267 sec Testcase: testNonBlockingReadIgnoreIsReady took 1.553 sec Testcase: testNonBlockingReadChunkedSplitInFinalCrlf took 0.24 sec Testcase: testNonBlockingReadAsync took 30.168 sec Testcase: testNonBlockingReadChunkedSplitAfterTrailerDelimter took 0.229 sec Testcase: testNonBlockingWrite took 7.684 sec Testcase: testNonBlockingReadChunkedSplitBeforeTrailerValue took 0.256 sec Testcase: testNonBlockingReadChunkedSplitInChunkBodyCrlf took 0.247 sec