Nutz
  1. 主页/
  2. 问答
NutzCN Logo
问答 疑问：日志显示了一个异常关闭的问题，搞不懂
发布于 2228天前 作者 文涛(wentao) 3169 次浏览 复制 上一个帖子 下一个帖子
标签: 无

日志显示java.nio.channels.ClosedChannelException，很费解，不知道怎么什么情况下会发生这种问题
日志如下：

2018-11-16 17:42:33 [USER_SERVICE]  [org.nutz.dao.impl.interceptor.DaoTimeInterceptor.filter(DaoTimeInterceptor.java:28)] - [DEBUG]  time=1ms, sql=select id from product where product_lc_id = '2574000301'
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:815)] - [DEBUG]  sendResponse info=null content=DirectByteBuffer@3cb6b668[p=0,l=49,c=32768,r=49]={<<<{"result":"0000",...\x96\xE6\xB6\x88\xE6\x88\x90\xE5\x8a\x9f\xEf\xBc\x81"}>>>"}\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=true callback=Blocker@233d231d{null}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannel.commit(HttpChannel.java:871)] - [DEBUG]  COMMIT for /user_sync on HttpChannelOverHttp@1c94033{r=1,c=true,a=DISPATCHED,uri=//192.168.1.12:8082/user_sync,age=18}
200 null HTTP/1.0
Date: Fri, 16 Nov 2018 09:42:33 GMT
X-Powered-By: nutz/1.r.67-SNAPSHOT <nutzam.com>
Content-Type: application/json;charset=utf-8
Cache-Control: no-cache


2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:735)] - [DEBUG]  org.eclipse.jetty.server.HttpConnection$SendCallback@56d9fd4b[PROCESSING][i=HTTP/1.0{s=200,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@7cdd476a] generate: NEED_HEADER (null,[p=0,l=49,c=32768,r=49],false)@START
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.http.HttpGenerator.generateHeaders(HttpGenerator.java:620)] - [DEBUG]  generateHeaders HTTP/1.0{s=200,h=4,cl=-1} last=false content=DirectByteBuffer@3cb6b668[p=0,l=49,c=32768,r=49]={<<<{"result":"0000",...\x96\xE6\xB6\x88\xE6\x88\x90\xE5\x8a\x9f\xEf\xBc\x81"}>>>"}\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.http.HttpGenerator.generateHeaders(HttpGenerator.java:621)] - [DEBUG]  Date: Fri, 16 Nov 2018 09:42:33 GMT
X-Powered-By: nutz/1.r.67-SNAPSHOT <nutzam.com>
Content-Type: application/json;charset=utf-8
Cache-Control: no-cache


2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.http.HttpGenerator.generateHeaders(HttpGenerator.java:792)] - [DEBUG]  EOF_CONTENT
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:735)] - [DEBUG]  org.eclipse.jetty.server.HttpConnection$SendCallback@56d9fd4b[PROCESSING][i=HTTP/1.0{s=200,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@7cdd476a] generate: FLUSH ([p=0,l=209,c=8192,r=209],[p=0,l=49,c=32768,r=49],false)@COMMITTED
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:270)] - [DEBUG]  write: WriteFlusher@50d1afcc{IDLE}->null [HeapByteBuffer@5bdd9649[p=0,l=209,c=8192,r=209]={<<<HTTP/1.1 200 OK\r\n....v20181111)\r\n\r\n>>>\nUser-Agent: Apac...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@3cb6b668[p=0,l=49,c=32768,r=49]={<<<{"result":"0000",...\x96\xE6\xB6\x88\xE6\x88\x90\xE5\x8a\x9f\xEf\xBc\x81"}>>>"}\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.WriteFlusher.updateState(WriteFlusher.java:112)] - [DEBUG]  update WriteFlusher@50d1afcc{WRITING}->null:IDLE-->WRITING
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:281)] - [DEBUG]  flushed 258 SocketChannelEndPoint@780dcbf3{/127.0.0.1:34873<->/127.0.0.1:8083,OPEN,fill=-,flush=W,to=27/300000}{io=0/0,kio=0,kro=1}->HttpConnection@26ad3fe5[p=HttpParser{s=END,203 of 203},g=HttpGenerator@30692a90{s=COMMITTED}]=>HttpChannelOverHttp@1c94033{r=1,c=true,a=DISPATCHED,uri=//192.168.1.12:8082/user_sync,age=22}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:398)] - [DEBUG]  Flushed=true written=258 remaining=0 WriteFlusher@50d1afcc{WRITING}->null
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.WriteFlusher.updateState(WriteFlusher.java:112)] - [DEBUG]  update WriteFlusher@50d1afcc{IDLE}->null:WRITING-->IDLE
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:735)] - [DEBUG]  org.eclipse.jetty.server.HttpConnection$SendCallback@56d9fd4b[PROCESSING][i=HTTP/1.0{s=200,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@7cdd476a] generate: DONE ([p=209,l=209,c=8192,r=0],[p=49,l=49,c=32768,r=0],false)@COMMITTED
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:815)] - [DEBUG]  sendResponse info=null content=HeapByteBuffer@9ba6230[p=0,l=0,c=0,r=0]={<<<>>>} complete=false committing=false callback=Blocker@233d231d{null}
2018-11-16 17:42:33 [USER_SERVICE]  [unicom.orderService.utils.LogTimeProcessor.process(LogTimeProcessor.java:42)] - [INFO]  [POST JSON] [clientIP: 119.39.118.165] [url:/user_sync] params:[{"key":"ad8c67e4c20f43caa0acb690ff59abd2","op_time":"20181116174626","op_type":"1","pay_type":"0","product_id":"2574000301","sign":"2f25818b18da0e1d16a99ba4b7efc3dab9684908","user_id":"073108792982A@tv"}] result:{"result":"0000","description":"处理成功"} time:19ms
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1567)] - [DEBUG]  FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler707161353==dftMaxIdleSec=1800
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.Server.handle(Server.java:505)] - [DEBUG]  handled=true async=false committed=true on HttpChannelOverHttp@1c94033{r=1,c=true,a=DISPATCHED,uri=//192.168.1.12:8082/user_sync,age=25}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannelState.unhandle(HttpChannelState.java:399)] - [DEBUG]  unhandle HttpChannelState@4ddec34e{s=DISPATCHED a=NOT_ASYNC i=true r=IDLE w=false}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:327)] - [DEBUG]  HttpChannelOverHttp@1c94033{r=1,c=true,a=COMPLETING,uri=//192.168.1.12:8082/user_sync,age=25} action COMPLETE
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:815)] - [DEBUG]  sendResponse info=null content=HeapByteBuffer@9ba6230[p=0,l=0,c=0,r=0]={<<<>>>} complete=true committing=false callback=Blocker@233d231d{null}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:735)] - [DEBUG]  org.eclipse.jetty.server.HttpConnection$SendCallback@56d9fd4b[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@314cea9] generate: CONTINUE (null,[p=0,l=0,c=0,r=0],true)@COMPLETING
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:735)] - [DEBUG]  org.eclipse.jetty.server.HttpConnection$SendCallback@56d9fd4b[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@314cea9] generate: SHUTDOWN_OUT (null,[p=0,l=0,c=0,r=0],true)@END
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:735)] - [DEBUG]  org.eclipse.jetty.server.HttpConnection$SendCallback@56d9fd4b[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@314cea9] generate: DONE (null,[p=0,l=0,c=0,r=0],true)@END
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.AbstractEndPoint.shutdownOutput(AbstractEndPoint.java:120)] - [DEBUG]  shutdownOutput SocketChannelEndPoint@780dcbf3{/127.0.0.1:34873<->/127.0.0.1:8083,OPEN,fill=-,flush=-,to=4/300000}{io=0/0,kio=0,kro=1}->HttpConnection@26ad3fe5[p=HttpParser{s=END,203 of 203},g=HttpGenerator@30692a90{s=END}]=>HttpChannelOverHttp@1c94033{r=1,c=true,a=COMPLETING,uri=//192.168.1.12:8082/user_sync,age=26}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannelState.onComplete(HttpChannelState.java:860)] - [DEBUG]  onComplete HttpChannelState@4ddec34e{s=COMPLETING a=NOT_ASYNC i=false r=IDLE w=false}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:737)] - [DEBUG]  COMPLETE for /user_sync written=49
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannelState.onReadEof(HttpChannelState.java:1294)] - [DEBUG]  onEof HttpChannelState@4ddec34e{s=COMPLETED a=NOT_ASYNC i=false r=IDLE w=false}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannelState.recycle(HttpChannelState.java:917)] - [DEBUG]  recycle HttpChannelState@4ddec34e{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.http.HttpParser.close(HttpParser.java:1832)] - [DEBUG]  close HttpParser{s=END,203 of 203}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.http.HttpParser.setState(HttpParser.java:1862)] - [DEBUG]  END --> CLOSE
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:538)] - [DEBUG]  HttpChannelOverHttp@1c94033{r=1,c=false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.AbstractEndPoint.shutdownInput(AbstractEndPoint.java:66)] - [DEBUG]  shutdownInput SocketChannelEndPoint@780dcbf3{/127.0.0.1:34873<->/127.0.0.1:8083,OSHUT,fill=-,flush=-,to=6/300000}{io=0/0,kio=0,kro=1}->HttpConnection@26ad3fe5[p=HttpParser{s=CLOSE,203 of 203},g=HttpGenerator@30692a90{s=START}]=>HttpChannelOverHttp@1c94033{r=1,c=false,a=IDLE,uri=null,age=0}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.ChannelEndPoint.doClose(ChannelEndPoint.java:196)] - [DEBUG]  doClose SocketChannelEndPoint@780dcbf3{/127.0.0.1:34873<->/127.0.0.1:8083,CLOSED,fill=-,flush=-,to=6/300000}{io=0/0,kio=0,kro=1}->HttpConnection@26ad3fe5[p=HttpParser{s=CLOSE,203 of 203},g=HttpGenerator@30692a90{s=START}]=>HttpChannelOverHttp@1c94033{r=1,c=false,a=IDLE,uri=null,age=0}
2018-11-16 17:42:33 [USER_SERVICE]  [org.eclipse.jetty.io.WriteFlusher.onFail(WriteFlusher.java:471)] - [DEBUG]  ignored: WriteFlusher@50d1afcc{IDLE}->null
java.nio.channels.ClosedChannelException
        at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:502)
        at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:353)
        at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:216)
        at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225)
        at org.eclipse.jetty.io.AbstractEndPoint.shutdownInput(AbstractEndPoint.java:107)
        at org.eclipse.jetty.io.ChannelEndPoint.fill(ChannelEndPoint.java:239)
        at org.eclipse.jetty.server.HttpConnection.fillRequestBuffer(HttpConnection.java:331)
        at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:243)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
        at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
        at java.lang.Thread.run(Thread.java:748)
1 回复

浏览器提前断开了请求而已吧

添加回复
请先登陆
回到顶部