I can reproduce with 8.5.x head and the following JVMs:
Zulu 1.8.0_312-b07:
OpenJDK Runtime Environment (Zulu 8.58.0.13-CA-solaris) (build
1.8.0_312-b07)
OpenJDK 64-Bit Server VM (Zulu 8.58.0.13-CA-solaris) (build 25.312-b07,
mixed mode)
Adopt: 1.8.0_282-b08
openjdk version "1.8.0_282"
OpenJDK Runtime Environment (AdoptOpenJDK)(build 1.8.0_282-b08)
OpenJDK 64-Bit Server VM (AdoptOpenJDK)(build 25.282-b08, mixed mode)
OpenJDK: 1.8.0_212-b10
java version "1.8.0_212"
Java(TM) SE Runtime Environment (build 1.8.0_212-b10)
Java HotSpot(TM) 64-Bit Server VM (build 25.212-b10, mixed mode)
I only needed to run the test
test.name=org/apache/coyote/http2/TestCancelledUpload.java
in a loop. Sometimes it needs 10 retries, sometimes only 3, so not
strictly reproducible, but one doesn't need to wait long.
I tried to reproduce on RHEL 7 Linux but couldn't (stopped after 150
attempts). The Solaris system is much slower though, so race conditions
are more likely there.
I can add more debug logging if that helps.
I am attaching a debug test output for the OK and the deadlock case and
the jstack output for the deadlock.
Thanks and regards,
Rainer
Am 01.01.2022 um 20:07 schrieb Rainer Jung:
Hi hi,
I am running the unit tests for TC 8.5.73 plus few post-release patches
on Solaris 10 Sparc with various Java 8 JVMs. I noticed one deadlock
when running on Zulu 8.58.0.13-CA-solaris (build 1.8.0_312-b07). Maybe
it is a sporadic deadlock and could also happen on the 1.8.0 variations,
but I could not yet check that. I did not notice such a deadlock on 5
Linux distributions on which I also ran all unit tests with a variety of
JVMs, including the Zulu one.
Due to the logs the deadlock happens in
org.apache.coyote.http2.TestCancelledUpload, but
org.apache.coyote.http2.TestFlowControl runs concurrently at the same
time (zwo test threads). Test methods are testCancelledRequest rwsp.
testNotFound.
The stacks are:
Found one Java-level deadlock:
=============================
"http-nio-127.0.0.1-auto-1-exec-7":
waiting to lock monitor 0x0000000100f99508 (object
0xffffffff41a99b40, a org.apache.coyote.http2.StreamStateMachine),
which is held by "http-nio-127.0.0.1-auto-1-exec-5"
"http-nio-127.0.0.1-auto-1-exec-5":
waiting to lock monitor 0x00000001002da838 (object
0xffffffff42015548, a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper),
which is held by "http-nio-127.0.0.1-auto-1-exec-7"
Java stack information for the threads listed above:
===================================================
"http-nio-127.0.0.1-auto-1-exec-7":
at
org.apache.coyote.http2.StreamStateMachine.checkFrameType(StreamStateMachine.java:125)
- waiting to lock <0xffffffff41a99b40> (a
org.apache.coyote.http2.StreamStateMachine)
at
org.apache.coyote.http2.AbstractNonZeroStream.checkState(AbstractNonZeroStream.java:144)
at
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame(Http2UpgradeHandler.java:1641)
at
org.apache.coyote.http2.Http2Parser.readDataFrame(Http2Parser.java:168)
at
org.apache.coyote.http2.Http2Parser.readFrame(Http2Parser.java:95)
at
org.apache.coyote.http2.Http2Parser.readFrame(Http2Parser.java:69)
at
org.apache.coyote.http2.Http2UpgradeHandler.upgradeDispatch(Http2UpgradeHandler.java:340)
at
org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60)
at
org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59)
at
org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:849)
at
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1677)
at
org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
- locked <0xffffffff42015548> (a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-5":
at
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset(Http2UpgradeHandler.java:558)
- waiting to lock <0xffffffff42015548> (a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper)
at org.apache.coyote.http2.Stream.close(Stream.java:623)
- locked <0xffffffff41a99b40> (a
org.apache.coyote.http2.StreamStateMachine)
at
org.apache.coyote.http2.StreamProcessor.process(StreamProcessor.java:85)
- locked <0xffffffff41ac4888> (a
org.apache.coyote.http2.StreamProcessor)
at
org.apache.coyote.http2.StreamRunnable.run(StreamRunnable.java:35)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
I am attaching the detailed log from the start of the test cases until
the last line that was logged for either of the two deadlocked threads.
Note that unit testing proceeds for test thread 1 until the remaining
tests are done. Only testing on thread 2 stopps due to the deadlock.
I will kill the process now and I will see, whether it is reproducible.
The three added patches - I guess they are not responsible, but
mentioning them for the sake of completeness - are:
-
ThreadPoolExecutor_prestartAllCoreThreads-23c78507b5d3dc4c0bd36d263e4f99aa8221205c.patch
-
revert_previous_fix-BZ65714-07747b8ca36ffd29350af24d1c9fd05a174ba25d.patch
- improved_fix-BZ65714-4795df9bf89f84decafa276805d0c265f93eb368.patch
Thanks and regards,
Rainer
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@tomcat.apache.org
For additional commands, e-mail: dev-h...@tomcat.apache.org
--
kippdata
informationstechnologie GmbH Tel: 0228 98549 -0
Bornheimer Str. 33a Fax: 0228 98549 -50
53111 Bonn www.kippdata.de
HRB 8018 Amtsgericht Bonn / USt.-IdNr. DE 196 457 417
Geschäftsführer: Dr. Thomas Höfer, Rainer Jung, Sven Maurmann
Buildfile: /shared/build/dev/tomcat/git/8.5.x/build.xml
download-jacoco:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/jacoco-0.8.7/lib/jacocoant.jar
setproxy:
downloadzip:
setup-jacoco:
download-compile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/commons-daemon-1.2.4/commons-daemon-1.2.4.jar
downloadgz-2:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/ecj-4.6.3/ecj-4.6.3.jar
downloadfile-2:
build-prepare:
[delete] Deleting directory
/shared/build/dev/tomcat/git/8.5.x/output/build/temp
[mkdir] Created dir: /shared/build/dev/tomcat/git/8.5.x/output/build/temp
compile-prepare:
download-validate:
validate:
compile:
download-test-compile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/junit-4.13.2/junit-4.13.2.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/hamcrest-2.2/hamcrest-2.2.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/easymock-3.6/easymock-3.6.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/cglib-3.3.0/cglib-nodep-3.3.0.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/objenesis-2.6/objenesis-2.6.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/unboundid-6.0.3/unboundid-ldapsdk-6.0.3.jar
setproxy:
downloadfile:
compile-webapp-examples:
test-compile:
build-manifests:
package:
build-docs:
[xmlvalidate] 1 file(s) have been successfully validated.
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/appdev
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/config
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/architecture
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/tribes
build-tomcat-jdbc:
[echo] Building Tomcat JDBC pool libraries
prepare:
download:
build-src:
build:
deploy:
examples-sources:
test-openssl-exists:
test-nio:
[junit] Running org.apache.coyote.http2.TestCancelledUpload in thread 1
[junit] TIMESTAMP INFO [main]
org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case
[testCancelledRequest[0]]
[junit] TIMESTAMP INFO [main]
org.apache.coyote.http11.AbstractHttp11Protocol.configureUpgradeProtocol The
["http-nio-127.0.0.1-auto-1"] connector has been configured to support HTTP
upgrade to [h2c]
[junit] TIMESTAMP INFO [main] org.apache.coyote.AbstractProtocol.init
Initializing ProtocolHandler ["http-nio-127.0.0.1-auto-1"]
[junit] TIMESTAMP INFO [main]
org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared
selector for servlet write/read
[junit] TIMESTAMP INFO [main]
org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
[junit] TIMESTAMP INFO [main]
org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine:
[Apache Tomcat/8.5.72-dev]
[junit] TIMESTAMP INFO [main] org.apache.coyote.AbstractProtocol.start
Starting ProtocolHandler ["http-nio-127.0.0.1-auto-1-57566"]
[junit] TIMESTAMP FINE [main] org.apache.coyote.http2.HpackEncoder.encode
Encoding header [:method] with value [POST]
[junit] TIMESTAMP FINE [main] org.apache.coyote.http2.HpackEncoder.encode
Encoding header [:scheme] with value [http]
[junit] TIMESTAMP FINE [main] org.apache.coyote.http2.HpackEncoder.encode
Encoding header [:path] with value [/simple]
[junit] TIMESTAMP FINE [main] org.apache.coyote.http2.HpackEncoder.encode
Encoding header [:authority] with value [localhost:57566]
[junit] TIMESTAMP FINE [main] org.apache.coyote.http2.HpackEncoder.encode
Encoding header [x-trailertest] with value [test]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.AbstractProcessorLight.process Socket:
[org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@3c91bba6:org.apache.tomcat.util.net.NioChannel@5d8856c4:java.nio.channels.SocketChannel[connected
local=/127.0.0.1:57566 remote=/127.0.0.1:57567]], Status in: [OPEN_READ],
State out: [CLOSED]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream.recycle Connection [0], Stream [1] has been
recycled
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.upgradeDispatch Entry, Connection
[0], SocketStatus [OPEN_READ]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.init Connection [0], State
[CONNECTED]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [HEADERS], Flags [4], Payload size [22]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.StreamStateMachine.stateChange Connection [0], Stream
[3], State changed from [null] to [IDLE]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.StreamStateMachine.stateChange Connection [0], Stream
[3], State changed from [IDLE] to [OPEN]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readHeaderPayload Connection [0], Stream
[3], Processing headers payload of size [22]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.addStaticTableEntry Using header from
index [3] of static table
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:method] and value [POST]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:method], Value [POST]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.addStaticTableEntry Using header from
index [6] of static table
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:scheme] and value [http]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:scheme], Value [http]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:path] and value [/simple]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:path], Value [/simple]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.addEntryToHeaderTable Adding header to
index [0] of dynamic table with name [:path] and value [/simple]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:authority] and value [localhost:57566]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:authority], Value [localhost:57566]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackDecoder.addEntryToHeaderTable Adding header to
index [1] of dynamic table with name [:authority] and value [localhost:57566]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [0] bytes
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [HEADERS] resulted in new overhead count of [-130]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-150]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=65535 cap=65535]]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-170]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=8192 lim=65535 cap=65535]]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-190]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=16384 lim=65535 cap=65535]]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-210]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=24576 lim=65535 cap=65535]]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-230]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=32768 lim=65535 cap=65535]]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-250]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=40960 lim=65535 cap=65535]]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-270]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=49152 lim=65535 cap=65535]]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.upgradeDispatch Exit, Connection
[0], SocketState [UPGRADED]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamInputBuffer.doRead Copying [57344] bytes
from inBuffer to outBuffer
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [57,344] bytes
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Stream [0], Sent window update to client increasing window by [57,344] bytes
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.doWriteHeaders Connection [0],
Stream [3], Writing the headers, EndOfStream [false]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [:status] with
value [403]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [content-length]
with value [0]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [date] with value
[Sun, 02 Jan 2022 18:05:39 GMT]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.doWriteHeaders 37 bytes
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream$StreamOutputBuffer.flush Connection [0], Stream
[3], flushing output with buffer at position [0], writeInProgress [false] and
closed [true]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.writeBody Connection [0], Stream
[3], Data length [0], EndOfStream [true]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-290]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.StreamStateMachine.stateChange Connection [0], Stream
[3], State changed from [OPEN] to [HALF_CLOSED_LOCAL]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.AbstractProcessorLight.process Socket:
[org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@3c91bba6:org.apache.tomcat.util.net.NioChannel@5d8856c4:java.nio.channels.SocketChannel[connected
local=/127.0.0.1:57566 remote=/127.0.0.1:57567]], Status in: [OPEN_READ],
State out: [CLOSED]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Stream.close Connection [0], Stream [3], Reset sent due
to [CANCEL]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.upgradeDispatch Entry, Connection
[0], SocketStatus [OPEN_READ]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.init Connection [0], State
[CONNECTED]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-310]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.StreamStateMachine.stateChange Connection [0], Stream
[3], State changed from [HALF_CLOSED_LOCAL] to [CLOSED_RST_TX]
[junit] TIMESTAMP FINE [http-nio-127.0.0.1-auto-1-exec-NN]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [CANCEL], Message [Connection [0], Stream [3], The remaining
request body is not required.], RST (closing stream)
[junit] Running org.apache.coyote.http2.TestCancelledUpload in thread 1
[junit] Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0
sec, Thread: 1, Class: org.apache.coyote.http2.TestCancelledUpload
test-nio2:
test-apr-exists:
test-apr:
coverage-report:
test-status:
[concat] Testsuites with skipped tests:
[concat] Testsuites with failed tests:
[concat] TEST-org.apache.coyote.http2.TestCancelledUpload.NIO.txt
2022-01-02 19:06:46
Full thread dump Java HotSpot(TM) 64-Bit Server VM (25.212-b10 mixed mode):
"Attach Listener" #29 daemon prio=9 os_prio=64 tid=0x0000000100c28800 nid=0x20
waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE
"http-nio-127.0.0.1-auto-1-57566-AsyncTimeout" #28 daemon prio=5 os_prio=64
tid=0x0000000100716800 nid=0x1f waiting on condition [0xfffffffe9e0ff000]
java.lang.Thread.State: TIMED_WAITING (sleeping)
at java.lang.Thread.sleep(Native Method)
at
org.apache.coyote.AbstractProtocol$AsyncTimeout.run(AbstractProtocol.java:1213)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-Acceptor-0" #27 daemon prio=5 os_prio=64
tid=0x0000000100715800 nid=0x1e runnable [0xfffffffe9e2ff000]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method)
at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:422)
at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:250)
- locked <0xffffffff43441540> (a java.lang.Object)
at
org.apache.tomcat.util.net.NioEndpoint$Acceptor.run(NioEndpoint.java:512)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-ClientPoller-1" #26 daemon prio=5 os_prio=64
tid=0x000000010107d800 nid=0x1d runnable [0xfffffffe9e4fe000]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.DevPollArrayWrapper.poll0(Native Method)
at sun.nio.ch.DevPollArrayWrapper.poll(DevPollArrayWrapper.java:223)
at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:98)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
- locked <0xffffffff4154c310> (a sun.nio.ch.Util$3)
- locked <0xffffffff4154c300> (a java.util.Collections$UnmodifiableSet)
- locked <0xffffffff4154c1e8> (a sun.nio.ch.DevPollSelectorImpl)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at
org.apache.tomcat.util.net.NioEndpoint$Poller.run(NioEndpoint.java:875)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-ClientPoller-0" #25 daemon prio=5 os_prio=64
tid=0x000000010107c800 nid=0x1c runnable [0xfffffffe9e6fe000]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.DevPollArrayWrapper.poll0(Native Method)
at sun.nio.ch.DevPollArrayWrapper.poll(DevPollArrayWrapper.java:223)
at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:98)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
- locked <0xffffffff4154b870> (a sun.nio.ch.Util$3)
- locked <0xffffffff4154b860> (a java.util.Collections$UnmodifiableSet)
- locked <0xffffffff4154b748> (a sun.nio.ch.DevPollSelectorImpl)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at
org.apache.tomcat.util.net.NioEndpoint$Poller.run(NioEndpoint.java:875)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-10" #24 daemon prio=5 os_prio=64
tid=0x0000000100a28800 nid=0x1b waiting on condition [0xfffffffe9e8fe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-9" #23 daemon prio=5 os_prio=64
tid=0x0000000100a37800 nid=0x1a waiting on condition [0xfffffffe9eafe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-8" #22 daemon prio=5 os_prio=64
tid=0x0000000101079000 nid=0x19 waiting for monitor entry [0xfffffffe9ecfe000]
java.lang.Thread.State: BLOCKED (on object monitor)
at
org.apache.coyote.http2.StreamStateMachine.checkFrameType(StreamStateMachine.java:125)
- waiting to lock <0xffffffff41bd12e0> (a
org.apache.coyote.http2.StreamStateMachine)
at
org.apache.coyote.http2.AbstractNonZeroStream.checkState(AbstractNonZeroStream.java:144)
at
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame(Http2UpgradeHandler.java:1641)
at
org.apache.coyote.http2.Http2Parser.readDataFrame(Http2Parser.java:168)
at org.apache.coyote.http2.Http2Parser.readFrame(Http2Parser.java:95)
at org.apache.coyote.http2.Http2Parser.readFrame(Http2Parser.java:69)
at
org.apache.coyote.http2.Http2UpgradeHandler.upgradeDispatch(Http2UpgradeHandler.java:340)
at
org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60)
at
org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59)
at
org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:849)
at
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1677)
at
org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
- locked <0xffffffff41f873d0> (a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-7" #21 daemon prio=5 os_prio=64
tid=0x0000000100a43800 nid=0x18 waiting on condition [0xfffffffe9eefe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-6" #20 daemon prio=5 os_prio=64
tid=0x0000000100a42000 nid=0x17 waiting on condition [0xfffffffe9f0fe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-5" #19 daemon prio=5 os_prio=64
tid=0x0000000100a40800 nid=0x16 waiting on condition [0xfffffffe9f2fe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-4" #18 daemon prio=5 os_prio=64
tid=0x0000000100dd0800 nid=0x15 waiting for monitor entry [0xfffffffe9f4fe000]
java.lang.Thread.State: BLOCKED (on object monitor)
at
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset(Http2UpgradeHandler.java:558)
- waiting to lock <0xffffffff41f873d0> (a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper)
at org.apache.coyote.http2.Stream.close(Stream.java:623)
- locked <0xffffffff41bd12e0> (a
org.apache.coyote.http2.StreamStateMachine)
at
org.apache.coyote.http2.StreamProcessor.process(StreamProcessor.java:85)
- locked <0xffffffff41bfddc0> (a
org.apache.coyote.http2.StreamProcessor)
at org.apache.coyote.http2.StreamRunnable.run(StreamRunnable.java:35)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-3" #17 daemon prio=5 os_prio=64
tid=0x0000000100dcf000 nid=0x14 waiting on condition [0xfffffffe9f6fe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-2" #16 daemon prio=5 os_prio=64
tid=0x0000000100db1800 nid=0x13 waiting on condition [0xfffffffe9f8fe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-1" #15 daemon prio=5 os_prio=64
tid=0x0000000100a04000 nid=0x12 waiting on condition [0xfffffffe9fafe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff414edb28> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:146)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:33)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1114)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"ContainerBackgroundProcessor[StandardEngine[Tomcat]]" #14 daemon prio=5
os_prio=64 tid=0x0000000100db0800 nid=0x11 waiting on condition
[0xfffffffe9fcff000]
java.lang.Thread.State: TIMED_WAITING (sleeping)
at java.lang.Thread.sleep(Native Method)
at
org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1363)
at java.lang.Thread.run(Thread.java:748)
"NioBlockingSelector.BlockPoller-0" #11 daemon prio=5 os_prio=64
tid=0x0000000100cfb000 nid=0xe runnable [0xfffffffea05fe000]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.DevPollArrayWrapper.poll0(Native Method)
at sun.nio.ch.DevPollArrayWrapper.poll(DevPollArrayWrapper.java:223)
at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:98)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
- locked <0xffffffff43441228> (a sun.nio.ch.Util$3)
- locked <0xffffffff43441218> (a java.util.Collections$UnmodifiableSet)
- locked <0xffffffff434410f0> (a sun.nio.ch.DevPollSelectorImpl)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at
org.apache.tomcat.util.net.NioBlockingSelector$BlockPoller.run(NioBlockingSelector.java:343)
"AsyncFileHandlerWriter-127618319" #10 daemon prio=5 os_prio=64
tid=0x00000001008cb000 nid=0xd waiting on condition [0xfffffffea10fe000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff432008b8> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingDeque.takeFirst(LinkedBlockingDeque.java:492)
at
java.util.concurrent.LinkedBlockingDeque.take(LinkedBlockingDeque.java:680)
at
org.apache.juli.AsyncFileHandler$LoggerThread.run(AsyncFileHandler.java:204)
"Service Thread" #7 daemon prio=9 os_prio=64 tid=0x0000000100310000 nid=0xb
runnable [0x0000000000000000]
java.lang.Thread.State: RUNNABLE
"C1 CompilerThread1" #6 daemon prio=9 os_prio=64 tid=0x000000010030b800 nid=0xa
waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE
"C2 CompilerThread0" #5 daemon prio=9 os_prio=64 tid=0x0000000100308800 nid=0x9
waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE
"Signal Dispatcher" #4 daemon prio=9 os_prio=64 tid=0x0000000100307800 nid=0x8
runnable [0x0000000000000000]
java.lang.Thread.State: RUNNABLE
"Finalizer" #3 daemon prio=8 os_prio=64 tid=0x00000001002d1800 nid=0x7 in
Object.wait() [0xfffffffea40ff000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xffffffff4320a250> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:144)
- locked <0xffffffff4320a250> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:165)
at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:216)
"Reference Handler" #2 daemon prio=10 os_prio=64 tid=0x00000001002c9800 nid=0x6
in Object.wait() [0xfffffffea42fe000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xffffffff43200aa0> (a java.lang.ref.Reference$Lock)
at java.lang.Object.wait(Object.java:502)
at java.lang.ref.Reference.tryHandlePending(Reference.java:191)
- locked <0xffffffff43200aa0> (a java.lang.ref.Reference$Lock)
at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)
"main" #1 prio=5 os_prio=64 tid=0x0000000100111000 nid=0x2 runnable
[0xffffffff7bcfb000]
java.lang.Thread.State: RUNNABLE
at java.net.SocketOutputStream.socketWrite0(Native Method)
at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111)
at java.net.SocketOutputStream.write(SocketOutputStream.java:143)
at
org.apache.coyote.http2.Http2TestBase.writeFrame(Http2TestBase.java:448)
at
org.apache.coyote.http2.Http2TestBase.writeFrame(Http2TestBase.java:442)
at
org.apache.coyote.http2.Http2TestBase.writeFrame(Http2TestBase.java:436)
at
org.apache.coyote.http2.TestCancelledUpload.testCancelledRequest(TestCancelledUpload.java:63)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at
org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:61)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at
org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:27)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:50)
at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:536)
at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:1178)
at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:1032)
"VM Thread" os_prio=64 tid=0x00000001002bf000 nid=0x5 runnable
"GC task thread#0 (ParallelGC)" os_prio=64 tid=0x000000010011e000 nid=0x3
runnable
"GC task thread#1 (ParallelGC)" os_prio=64 tid=0x0000000100126800 nid=0x4
runnable
"VM Periodic Task Thread" os_prio=64 tid=0x0000000100315800 nid=0xc waiting on
condition
JNI global references: 343
Found one Java-level deadlock:
=============================
"http-nio-127.0.0.1-auto-1-exec-8":
waiting to lock monitor 0x00000001002ce0a8 (object 0xffffffff41bd12e0, a
org.apache.coyote.http2.StreamStateMachine),
which is held by "http-nio-127.0.0.1-auto-1-exec-4"
"http-nio-127.0.0.1-auto-1-exec-4":
waiting to lock monitor 0x00000001002cf6a8 (object 0xffffffff41f873d0, a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper),
which is held by "http-nio-127.0.0.1-auto-1-exec-8"
Java stack information for the threads listed above:
===================================================
"http-nio-127.0.0.1-auto-1-exec-8":
at
org.apache.coyote.http2.StreamStateMachine.checkFrameType(StreamStateMachine.java:125)
- waiting to lock <0xffffffff41bd12e0> (a
org.apache.coyote.http2.StreamStateMachine)
at
org.apache.coyote.http2.AbstractNonZeroStream.checkState(AbstractNonZeroStream.java:144)
at
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame(Http2UpgradeHandler.java:1641)
at
org.apache.coyote.http2.Http2Parser.readDataFrame(Http2Parser.java:168)
at org.apache.coyote.http2.Http2Parser.readFrame(Http2Parser.java:95)
at org.apache.coyote.http2.Http2Parser.readFrame(Http2Parser.java:69)
at
org.apache.coyote.http2.Http2UpgradeHandler.upgradeDispatch(Http2UpgradeHandler.java:340)
at
org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60)
at
org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59)
at
org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:849)
at
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1677)
at
org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
- locked <0xffffffff41f873d0> (a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
"http-nio-127.0.0.1-auto-1-exec-4":
at
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset(Http2UpgradeHandler.java:558)
- waiting to lock <0xffffffff41f873d0> (a
org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper)
at org.apache.coyote.http2.Stream.close(Stream.java:623)
- locked <0xffffffff41bd12e0> (a
org.apache.coyote.http2.StreamStateMachine)
at
org.apache.coyote.http2.StreamProcessor.process(StreamProcessor.java:85)
- locked <0xffffffff41bfddc0> (a
org.apache.coyote.http2.StreamProcessor)
at org.apache.coyote.http2.StreamRunnable.run(StreamRunnable.java:35)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
Found 1 deadlock.
Buildfile: /shared/build/dev/tomcat/git/8.5.x/build.xml
download-jacoco:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/jacoco-0.8.7/lib/jacocoant.jar
setproxy:
downloadzip:
setup-jacoco:
download-compile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/commons-daemon-1.2.4/commons-daemon-1.2.4.jar
downloadgz-2:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/ecj-4.6.3/ecj-4.6.3.jar
downloadfile-2:
build-prepare:
[delete] Deleting directory
/shared/build/dev/tomcat/git/8.5.x/output/build/temp
[mkdir] Created dir: /shared/build/dev/tomcat/git/8.5.x/output/build/temp
compile-prepare:
download-validate:
validate:
compile:
download-test-compile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/junit-4.13.2/junit-4.13.2.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/hamcrest-2.2/hamcrest-2.2.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/easymock-3.6/easymock-3.6.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/cglib-3.3.0/cglib-nodep-3.3.0.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/objenesis-2.6/objenesis-2.6.jar
setproxy:
downloadfile:
testexist:
[echo] Testing for
/shared/build/dev/tomcat/git/tomcat-build-libs-85/unboundid-6.0.3/unboundid-ldapsdk-6.0.3.jar
setproxy:
downloadfile:
compile-webapp-examples:
test-compile:
build-manifests:
package:
build-docs:
[xmlvalidate] 1 file(s) have been successfully validated.
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/appdev
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/config
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/architecture
[xslt] Transforming into
/shared/build/dev/tomcat/git/8.5.x/output/build/webapps/docs/tribes
build-tomcat-jdbc:
[echo] Building Tomcat JDBC pool libraries
prepare:
download:
build-src:
build:
deploy:
examples-sources:
test-openssl-exists:
test-nio:
[junit] Running org.apache.coyote.http2.TestCancelledUpload in thread 1
[junit] 02-Jan-2022 19:05:09.653 INFO [main]
org.apache.catalina.startup.LoggingBaseTest.setUp Starting test case
[testCancelledRequest[0]]
[junit] 02-Jan-2022 19:05:12.158 INFO [main]
org.apache.coyote.http11.AbstractHttp11Protocol.configureUpgradeProtocol The
["http-nio-127.0.0.1-auto-1"] connector has been configured to support HTTP
upgrade to [h2c]
[junit] 02-Jan-2022 19:05:12.159 INFO [main]
org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler
["http-nio-127.0.0.1-auto-1"]
[junit] 02-Jan-2022 19:05:12.276 INFO [main]
org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared
selector for servlet write/read
[junit] 02-Jan-2022 19:05:12.462 INFO [main]
org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat]
[junit] 02-Jan-2022 19:05:12.464 INFO [main]
org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine:
[Apache Tomcat/8.5.72-dev]
[junit] 02-Jan-2022 19:05:13.122 INFO [main]
org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler
["http-nio-127.0.0.1-auto-1-57560"]
[junit] 02-Jan-2022 19:05:14.067 FINE [main]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [:method] with
value [POST]
[junit] 02-Jan-2022 19:05:14.068 FINE [main]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [:scheme] with
value [http]
[junit] 02-Jan-2022 19:05:14.069 FINE [main]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [:path] with value
[/simple]
[junit] 02-Jan-2022 19:05:14.069 FINE [main]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [:authority] with
value [localhost:57560]
[junit] 02-Jan-2022 19:05:14.080 FINE [main]
org.apache.coyote.http2.HpackEncoder.encode Encoding header [x-trailertest]
with value [test]
[junit] 02-Jan-2022 19:05:14.084 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.upgradeDispatch Entry, Connection
[0], SocketStatus [OPEN_READ]
[junit] 02-Jan-2022 19:05:14.086 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.init Connection [0], State
[CONNECTED]
[junit] 02-Jan-2022 19:05:14.088 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [HEADERS], Flags [4], Payload size [22]
[junit] 02-Jan-2022 19:05:14.090 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.StreamStateMachine.stateChange Connection [0], Stream
[3], State changed from [null] to [IDLE]
[junit] 02-Jan-2022 19:05:14.097 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.StreamStateMachine.stateChange Connection [0], Stream
[3], State changed from [IDLE] to [OPEN]
[junit] 02-Jan-2022 19:05:14.104 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readHeaderPayload Connection [0], Stream
[3], Processing headers payload of size [22]
[junit] 02-Jan-2022 19:05:14.105 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.addStaticTableEntry Using header from
index [3] of static table
[junit] 02-Jan-2022 19:05:14.107 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:method] and value [POST]
[junit] 02-Jan-2022 19:05:14.107 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:method], Value [POST]
[junit] 02-Jan-2022 19:05:14.109 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.addStaticTableEntry Using header from
index [6] of static table
[junit] 02-Jan-2022 19:05:14.109 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:scheme] and value [http]
[junit] 02-Jan-2022 19:05:14.110 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:scheme], Value [http]
[junit] 02-Jan-2022 19:05:14.111 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:path] and value [/simple]
[junit] 02-Jan-2022 19:05:14.111 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:path], Value [/simple]
[junit] 02-Jan-2022 19:05:14.112 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.addEntryToHeaderTable Adding header to
index [0] of dynamic table with name [:path] and value [/simple]
[junit] 02-Jan-2022 19:05:14.113 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.emitHeader Emitting header with name
[:authority] and value [localhost:57560]
[junit] 02-Jan-2022 19:05:14.113 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream.emitHeader Connection [0], Stream [3], HTTP
header [:authority], Value [localhost:57560]
[junit] 02-Jan-2022 19:05:14.114 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.HpackDecoder.addEntryToHeaderTable Adding header to
index [1] of dynamic table with name [:authority] and value [localhost:57560]
[junit] 02-Jan-2022 19:05:14.115 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [0] bytes
[junit] 02-Jan-2022 19:05:14.116 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [HEADERS] resulted in new overhead count of [-130]
[junit] 02-Jan-2022 19:05:14.120 FINE [http-nio-127.0.0.1-auto-1-exec-10]
org.apache.coyote.http2.Stream$StreamInputBuffer.doRead The Stream input buffer
is empty. Waiting for more data
[junit] 02-Jan-2022 19:05:14.122 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.123 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.124 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-150]
[junit] 02-Jan-2022 19:05:14.125 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.142 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.143 FINE [http-nio-127.0.0.1-auto-1-exec-10]
org.apache.coyote.http2.Stream$StreamInputBuffer.doRead Copying [8192] bytes
from inBuffer to outBuffer
[junit] 02-Jan-2022 19:05:14.144 FINE [http-nio-127.0.0.1-auto-1-exec-10]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.145 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.145 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.146 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-170]
[junit] 02-Jan-2022 19:05:14.147 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.148 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.149 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.149 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.150 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-190]
[junit] 02-Jan-2022 19:05:14.151 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=8192 lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.152 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.152 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.153 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.170 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-210]
[junit] 02-Jan-2022 19:05:14.171 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=16384 lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.172 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.173 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.173 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.174 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-230]
[junit] 02-Jan-2022 19:05:14.175 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=24576 lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.176 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.176 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.177 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.177 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-250]
[junit] 02-Jan-2022 19:05:14.178 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=32768 lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.179 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.180 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.180 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.181 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-270]
[junit] 02-Jan-2022 19:05:14.181 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=40960 lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.182 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.205 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.219 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.220 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-290]
[junit] 02-Jan-2022 19:05:14.221 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=49152 lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.222 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.onDataAvailable Data added to
inBuffer when read thread is waiting. Signalling that thread to continue
[junit] 02-Jan-2022 19:05:14.224 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.226 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.226 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-310]
[junit] 02-Jan-2022 19:05:14.227 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned
[java.nio.HeapByteBuffer[pos=57344 lim=65535 cap=65535]]
[junit] 02-Jan-2022 19:05:14.228 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.230 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.231 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Stream [0], Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.238 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream.close Connection [0], Stream [3], Reset sent due
to [FLOW_CONTROL_ERROR]
[junit] 02-Jan-2022 19:05:14.239 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.StreamStateMachine.stateChange Connection [0], Stream
[3], State changed from [OPEN] to [CLOSED_RST_TX]
[junit] 02-Jan-2022 19:05:14.241 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.246 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.WindowAllocationManager.notify Connection [0], Stream
[3], Waiting type [0], Notify type [3]
[junit] 02-Jan-2022 19:05:14.255 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream$StreamInputBuffer.swallowUnread Swallowing
[57,344] bytes previously read into input stream buffer
[junit] 02-Jan-2022 19:05:14.256 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [57,344] bytes
[junit] 02-Jan-2022 19:05:14.256 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Stream.recycle Connection [0], Stream [3] has been
recycled
[junit] 02-Jan-2022 19:05:14.258 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.259 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.259 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-330]
[junit] 02-Jan-2022 19:05:14.260 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.260 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.261 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.262 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.263 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.264 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.264 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-350]
[junit] 02-Jan-2022 19:05:14.265 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.265 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.266 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.267 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.268 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.268 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.269 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-370]
[junit] 02-Jan-2022 19:05:14.270 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.270 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.271 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.272 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.272 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.273 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.274 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-390]
[junit] 02-Jan-2022 19:05:14.283 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.284 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.285 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.286 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.286 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.287 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.288 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-410]
[junit] 02-Jan-2022 19:05:14.288 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.289 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.290 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.290 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.291 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.299 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.300 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-430]
[junit] 02-Jan-2022 19:05:14.322 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.323 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.325 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.326 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.327 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.327 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.327 FINE [main]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [-1], Stream [0],
Frame type [WINDOW_UPDATE], Flags [0], Payload size [4]
[junit] 02-Jan-2022 19:05:14.328 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-450]
[junit] 02-Jan-2022 19:05:14.328 FINE [main]
org.apache.coyote.http2.Http2Parser.readWindowUpdateFrame Connection [-1],
Stream [0], Window size increment [8192]
[junit] 02-Jan-2022 19:05:14.328 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.329 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.329 FINE [main]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [-1], Stream [3],
Frame type [WINDOW_UPDATE], Flags [0], Payload size [4]
[junit] 02-Jan-2022 19:05:14.330 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.330 FINE [main]
org.apache.coyote.http2.Http2Parser.readWindowUpdateFrame Connection [-1],
Stream [3], Window size increment [8192]
[junit] 02-Jan-2022 19:05:14.331 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.331 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.331 FINE [main]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [-1], Stream [3],
Frame type [RST], Flags [0], Payload size [4]
[junit] 02-Jan-2022 19:05:14.332 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.333 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.updateOverheadCount Connection [0],
Stream [0], Frame type [DATA] resulted in new overhead count of [-470]
[junit] 02-Jan-2022 19:05:14.333 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.startRequestBodyFrame Connection
[0], Stream [3] startRequestBodyFrame returned [java.nio.HeapByteBuffer[pos=0
lim=0 cap=0]]
[junit] 02-Jan-2022 19:05:14.334 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.swallowPayload Connection [0], Stream [3],
Swallowed [8192] bytes
[junit] 02-Jan-2022 19:05:14.334 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.writeWindowUpdate Connection [0],
Sent window update to client increasing window by [8,192] bytes
[junit] 02-Jan-2022 19:05:14.335 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2UpgradeHandler.sendStreamReset Connection [0],
Stream [3], Error [FLOW_CONTROL_ERROR], Message [Connection [0], Client sent
more data than stream window allowed], RST (closing stream)
[junit] 02-Jan-2022 19:05:14.336 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.validateFrame Connection [0], Stream [3],
Frame type [DATA], Flags [0], Payload size [8192]
[junit] 02-Jan-2022 19:05:14.336 FINE [http-nio-127.0.0.1-auto-1-exec-8]
org.apache.coyote.http2.Http2Parser.readDataFrame Connection [0], Stream [3],
Data length [8192], Padding length [none]
[junit] 02-Jan-2022 19:05:14.339 INFO [main]
org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler
["http-nio-127.0.0.1-auto-1-57560"]
[junit] 02-Jan-2022 19:05:14.381 INFO [main]
org.apache.catalina.core.StandardService.stopInternal Stopping service [Tomcat]
[junit] 02-Jan-2022 19:05:14.442 INFO [main]
org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler
["http-nio-127.0.0.1-auto-1-57560"]
[junit] 02-Jan-2022 19:05:14.483 INFO [main]
org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler
["http-nio-127.0.0.1-auto-1-57560"]
[junit] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed:
5.271 sec, Thread: 1, Class: org.apache.coyote.http2.TestCancelledUpload
test-nio2:
test-apr-exists:
test-apr:
coverage-report:
test-status:
[concat] Testsuites with skipped tests:
[concat] Testsuites with failed tests:
test:
BUILD SUCCESSFUL
Total time: 23 seconds
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@tomcat.apache.org
For additional commands, e-mail: dev-h...@tomcat.apache.org
