jetty.project icon indicating copy to clipboard operation
jetty.project copied to clipboard

Published 20 hours ago • verified publisher icon jetty

Unconsumed content exception in the logs

Open iamsanjay opened this issue 1 year ago • 7 comments
trafficstars

jetty_logs.txt Jetty Version 10

Java Version 11

Question Why I am seeing Unconsumed content exception errors in the logs?

I am creating a POST request and sending a data to the Jetty server, and then receiving the response. From the logs, It seems that the server is able to process the stream without any issue. However, while parsing response it is throwing exception -- Unconsumed Content.

Attached the logs!

2854 DEBUG (httpShardExecutor-16-thread-2) [n: c: s: r: x: t:] o.e.j.h.p.Parser Parsed [HEADERS|64|4|1] frame header from java.nio.DirectByteBuffer[pos=9 lim=152 cap=16384]@926c80dd
2854 DEBUG (qtp1987626026-62) [n: c: s: r: x: t:] o.e.j.h.HTTP2Session Closed stream HTTP2Stream@6c9e1ce0#1@28ff38bd{sendWindow=8388538,recvWindow=524079,queue=0,demand=0,reset=false/false,CLOSED,age=345,attachment=ServerHttpChannelOverHTTP2@4f2412f6{s=HttpChannelState@379427b4{s=HANDLING rs=COMPLETING os=COMMITTED is=IDLE awp=false se=false i=false al=0},r=1,c=false/false,a=HANDLING,uri=http://127.0.0.1:58631/solr/admin/cores,age=343}#1} for HTTP2ServerSession@28ff38bd{local:/127.0.0.1:58631,remote:/127.0.0.1:58640,sendWindow=16777146,recvWindow=1048367,state=[streams=1,NOT_CLOSED,goAwayRecv=null,goAwaySent=null,failure=null]}
2854 DEBUG (qtp1987626026-62) [n: c: s: r: x: t:] o.e.j.h.HTTP2Session Destroyed stream #1 for HTTP2ServerSession@28ff38bd{local:/127.0.0.1:58631,remote:/127.0.0.1:58640,sendWindow=16777146,recvWindow=1048367,state=[streams=1,NOT_CLOSED,goAwayRecv=null,goAwaySent=null,failure=null]}
2854 DEBUG (httpShardExecutor-16-thread-2) [n: c: s: r: x: t:] o.e.j.h.p.HeadersBodyParser Parsed HEADERS frame hpack from java.nio.DirectByteBuffer[pos=73 lim=152 cap=16384]
2854 DEBUG (qtp1987626026-62) [n: c: s: r: x: t:] o.e.j.h.s.HttpTransportOverHTTP2 HTTP2 Response #1/28ff38bd commit success
2854 DEBUG (httpShardExecutor-16-thread-2) [n: c: s: r: x: t:] o.e.j.h.c.HTTP2ClientSession Received HeadersFrame@2081e226#1[end=false,{HTTP/2.0{s=200,h=4,cl=70}},priority=null]
2854 DEBUG (qtp1987626026-62) [n: c: s: r: x: t:] o.e.j.h.HTTP2Flusher Flushing HTTP2ServerSession@28ff38bd{local:/127.0.0.1:58631,remote:/127.0.0.1:58640,sendWindow=16777146,recvWindow=1048367,state=[streams=0,NOT_CLOSED,goAwayRecv=null,goAwaySent=null,failure=null]}
2854 DEBUG (httpShardExecutor-16-thread-2) [n: c: s: r: x: t:] o.e.j.h.HTTP2Stream Update close for HTTP2Stream@168a0731#1@55c13bd{sendWindow=524079,recvWindow=8388608,queue=0,demand=0,reset=false/false,LOCALLY_CLOSED,age=348,attachment=HttpReceiverOverHTTP2@274fe30c(rsp=IDLE,failure=null)} update=false event=RECEIVED
2854 DEBUG (qtp1987626026-62) [n: c: s: r: x: t:] o.e.j.h.HTTP2Flusher Flushed HTTP2ServerSession@28ff38bd{local:/127.0.0.1:58631,remote:/127.0.0.1:58640,sendWindow=16777146,recvWindow=1048367,state=[streams=0,NOT_CLOSED,goAwayRecv=null,goAwaySent=null,failure=null]}
2854 DEBUG (qtp1987626026-62) [n: c: s: r: x: t:] o.e.j.h.s.HttpChannelOverHTTP2 failing all content with org.eclipse.jetty.util.StaticException: Unconsumed content ServerHttpChannelOverHTTP2@4f2412f6{s=HttpChannelState@379427b4{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=1,c=true/true,a=IDLE,uri=http://127.0.0.1:58631/solr/admin/cores,age=343}#1
2855 DEBUG (httpShardExecutor-16-thread-2) [n: c: s: r: x: t:] o.e.j.h.HTTP2Stream Demand 1/1, stalling data processing for HTTP2Stream@168a0731#1@55c13bd{sendWindow=524079,recvWindow=8388608,queue=0,demand=1,reset=false/false,LOCALLY_CLOSED,age=349,attachment=HttpReceiverOverHTTP2@274fe30c(rsp=HEADERS,failure=null)}
2855 DEBUG (httpShardExecutor-16-thread-2) [n: c: s: r: x: t:] o.e.j.h.p.Parser Parsed HEADERS frame body from java.nio.DirectByteBuffer[pos=73 lim=152 cap=16384]@aa95dbed

Basically we are indexing one document and then finally shutdown the server as we shut down we also see this exception.

2913 DEBUG (qtp1987626026-58) [n: c: s: r: x: t:] o.e.j.h.HTTP2Connection Processing session failure on HTTP2ServerSession@51d68840{local:null,remote:null,sendWindow=16777125,recvWindow=1046903,state=[streams=0,CLOSED,goAwayRecv=GoAwayFrame@235a061a{0/no_error/stop},goAwaySent=GoAwayFrame@3b0b1f4d{3/no_error/close},failure=null]}
          => org.eclipse.jetty.io.EofException: Close no_error/ (stop)
	at org.eclipse.jetty.http2.server.HTTP2ServerConnectionFactory$HTTPServerSessionListener.onClose(HTTP2ServerConnectionFactory.java:127)
org.eclipse.jetty.io.EofException: Close no_error/ (stop)
	at org.eclipse.jetty.http2.server.HTTP2ServerConnectionFactory$HTTPServerSessionListener.onClose(HTTP2ServerConnectionFactory.java:127) ~[http2-server-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session.notifyClose(HTTP2Session.java:1197) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$StreamsState.terminate(HTTP2Session.java:2153) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$StreamsState.lambda$sendGoAwayAndTerminate$16(HTTP2Session.java:2059) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.Callback$6.completed(Callback.java:223) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.Callback$Nested.succeeded(Callback.java:396) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.Callback$Nested.succeeded(Callback.java:392) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$ControlEntry.succeeded(HTTP2Session.java:1377) ~[http2-common-10.0.22.jar:10.0.22]
	at java.base/java.util.ArrayList.forEach(ArrayList.java:1596) ~[?:?]
	at org.eclipse.jetty.http2.HTTP2Flusher.finish(HTTP2Flusher.java:323) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Flusher.succeeded(HTTP2Flusher.java:315) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:291) ~[jetty-io-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:254) ~[jetty-io-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.io.AbstractEndPoint.write(AbstractEndPoint.java:386) ~[jetty-io-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Flusher.process(HTTP2Flusher.java:292) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:243) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:224) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session.frame(HTTP2Session.java:821) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session.frames(HTTP2Session.java:793) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session.control(HTTP2Session.java:775) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$StreamsState.sendGoAway(HTTP2Session.java:2064) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$StreamsState.sendGoAwayAndTerminate(HTTP2Session.java:2059) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$StreamsState.lambda$onGoAway$4(HTTP2Session.java:1763) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$StreamsState.tryRunZeroStreamsAction(HTTP2Session.java:2135) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session$StreamsState.onGoAway(HTTP2Session.java:1847) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Session.onGoAway(HTTP2Session.java:498) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Connection.onGoAway(HTTP2Connection.java:284) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.parser.BodyParser.notifyGoAway(BodyParser.java:187) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.parser.GoAwayBodyParser.onGoAway(GoAwayBodyParser.java:167) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.parser.GoAwayBodyParser.parse(GoAwayBodyParser.java:137) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.parser.Parser.parseBody(Parser.java:240) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.parser.Parser.parse(Parser.java:167) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.parser.ServerParser.parse(ServerParser.java:126) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Connection$HTTP2Producer.produce(HTTP2Connection.java:350) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.produceTask(AdaptiveExecutionStrategy.java:455) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:248) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.produce(AdaptiveExecutionStrategy.java:193) ~[jetty-util-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Connection.produce(HTTP2Connection.java:208) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Connection.onFillable(HTTP2Connection.java:155) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.http2.HTTP2Connection$FillableCallback.succeeded(HTTP2Connection.java:450) ~[http2-common-10.0.22.jar:10.0.22]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[jetty-io-10.0.22.jar:10.0.22]

iamsanjay avatar Sep 23 '24 15:09 iamsanjay