[ https://issues.apache.org/jira/browse/QPID-6257?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Rob Godfrey resolved QPID-6257. ------------------------------- Resolution: Fixed > [Java Broker] Excessive ERROR log entry is added into a Broker log file on > unexpected socket close by the client > ---------------------------------------------------------------------------------------------------------------- > > Key: QPID-6257 > URL: https://issues.apache.org/jira/browse/QPID-6257 > Project: Qpid > Issue Type: Bug > Components: Java Broker > Affects Versions: 0.30 > Reporter: Alex Rudyy > Assignee: Alex Rudyy > Fix For: 0.31 > > Attachments: > 0001-QPID-6257-Introduce-operational-log-for-connection-b.patch, > Introduce_operational_log_for_unexpected_connection_close_and_reduce_logging_l.patch > > > The org.apache.qpid.transport.SenderClosedException is logged using ERROR log > level. The log severity should be reduced (to INFO/DEBUG ???) > {noformat} > 2014-11-12 11:30:48,632 DEBUG [pool-1-thread-9] (queue.QueueRunner) - Problem > during asynchronous delivery by > org.apache.qpid.server.queue.QueueRunner$1@7c2e85e5 > org.apache.qpid.transport.SenderClosedException: sender for socket > /169.124.113.161:34264 is closed > at > org.apache.qpid.transport.network.io.IoSender.checkNotAlreadyClosed(IoSender.java:361) > at > org.apache.qpid.transport.network.io.IoSender.send(IoSender.java:146) > at > org.apache.qpid.transport.network.io.IoSender.send(IoSender.java:37) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.writeFrame(AMQProtocolEngine.java:724) > at > org.apache.qpid.server.protocol.v0_8.output.ProtocolOutputConverterImpl.writeFrame(ProtocolOutputConverterImpl.java:430) > at > org.apache.qpid.server.protocol.v0_8.output.ProtocolOutputConverterImpl.writeMessageDeliveryUnchanged(ProtocolOutputConverterImpl.java:215) > at > org.apache.qpid.server.protocol.v0_8.output.ProtocolOutputConverterImpl.writeMessageDelivery(ProtocolOutputConverterImpl.java:141) > at > org.apache.qpid.server.protocol.v0_8.output.ProtocolOutputConverterImpl.writeMessageDelivery(ProtocolOutputConverterImpl.java:101) > at > org.apache.qpid.server.protocol.v0_8.output.ProtocolOutputConverterImpl.writeDeliver(ProtocolOutputConverterImpl.java:78) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine$WriteDeliverMethod.deliverToClient(AMQProtocolEngine.java:1662) > at > org.apache.qpid.server.protocol.v0_8.ConsumerTarget_0_8.sendToClient(ConsumerTarget_0_8.java:513) > at > org.apache.qpid.server.protocol.v0_8.ConsumerTarget_0_8$AckConsumer.send(ConsumerTarget_0_8.java:317) > at > org.apache.qpid.server.queue.QueueConsumerImpl.send(QueueConsumerImpl.java:475) > at > org.apache.qpid.server.queue.AbstractQueue.deliverMessage(AbstractQueue.java:1239) > at > org.apache.qpid.server.queue.AbstractQueue.attemptDelivery(AbstractQueue.java:2001) > at > org.apache.qpid.server.queue.AbstractQueue.processQueue(AbstractQueue.java:2172) > at org.apache.qpid.server.queue.QueueRunner$1.run(QueueRunner.java:77) > at java.security.AccessController.doPrivileged(Native Method) > at javax.security.auth.Subject.doAs(Subject.java:356) > at org.apache.qpid.server.queue.QueueRunner.run(QueueRunner.java:68) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) > at java.lang.Thread.run(Thread.java:745) > Caused by: java.net.SocketException: Connection reset > at > java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:118) > at java.net.SocketOutputStream.write(SocketOutputStream.java:159) > at > org.apache.qpid.transport.network.io.IoSender.run(IoSender.java:299) > ... 1 more > 2014-11-12 11:30:48,632 DEBUG [IoReceiver - /169.124.113.161:34264] > (v0_8.AMQProtocolEngine) - SEND: Frame channelId: 1, bodyFrame: > [ChannelCloseOkBodyImpl: ] > 2014-11-12 11:30:48,635 ERROR [IoReceiver - /169.124.113.161:34264] > (v0_8.AMQProtocolEngine) - Unexpected exception while processing frame. > Closing connection. > org.apache.qpid.transport.SenderClosedException: sender for socket > /169.124.113.161:34264 is closed > at > org.apache.qpid.transport.network.io.IoSender.checkNotAlreadyClosed(IoSender.java:361) > at > org.apache.qpid.transport.network.io.IoSender.send(IoSender.java:111) > at > org.apache.qpid.transport.network.io.IoSender.send(IoSender.java:37) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.writeFrame(AMQProtocolEngine.java:724) > at > org.apache.qpid.server.protocol.v0_8.handler.ChannelCloseHandler.methodReceived(ChannelCloseHandler.java:74) > at > org.apache.qpid.server.protocol.v0_8.handler.ServerMethodDispatcherImpl.dispatchChannelClose(ServerMethodDispatcherImpl.java:228) > at > org.apache.qpid.framing.amqp_0_9.ChannelCloseBodyImpl.execute(ChannelCloseBodyImpl.java:127) > at > org.apache.qpid.server.protocol.v0_8.state.AMQStateManager$1.run(AMQStateManager.java:122) > at > org.apache.qpid.server.protocol.v0_8.state.AMQStateManager$1.run(AMQStateManager.java:118) > at java.security.AccessController.doPrivileged(Native Method) > at javax.security.auth.Subject.doAs(Subject.java:415) > at > org.apache.qpid.server.protocol.v0_8.state.AMQStateManager.methodReceived(AMQStateManager.java:117) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.methodFrameReceived(AMQProtocolEngine.java:626) > at > org.apache.qpid.framing.AMQMethodBodyImpl.handle(AMQMethodBodyImpl.java:99) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.frameReceived(AMQProtocolEngine.java:477) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.dataBlockReceived(AMQProtocolEngine.java:412) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.access$1100(AMQProtocolEngine.java:89) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine$2.run(AMQProtocolEngine.java:311) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine$2.run(AMQProtocolEngine.java:283) > at java.security.AccessController.doPrivileged(Native Method) > at javax.security.auth.Subject.doAs(Subject.java:356) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.received(AMQProtocolEngine.java:282) > at > org.apache.qpid.server.protocol.v0_8.AMQProtocolEngine.received(AMQProtocolEngine.java:89) > at > org.apache.qpid.server.protocol.MultiVersionProtocolEngine.received(MultiVersionProtocolEngine.java:134) > at > org.apache.qpid.server.protocol.MultiVersionProtocolEngine.received(MultiVersionProtocolEngine.java:50) > at > org.apache.qpid.transport.network.io.IoReceiver.run(IoReceiver.java:161) > at java.lang.Thread.run(Thread.java:745) > Caused by: java.net.SocketException: Connection reset > at > java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:118) > at java.net.SocketOutputStream.write(SocketOutputStream.java:159) > at > org.apache.qpid.transport.network.io.IoSender.run(IoSender.java:299) > ... 1 more > 2014-11-12 11:30:48,635 DEBUG [IoReceiver - /169.124.113.161:34264] > (state.AMQStateManager) - State changing to CONNECTION_CLOSED from old state > CONNECTION_OPEN > 2014-11-12 11:30:48,636 DEBUG [IoReceiver - /169.124.113.161:34264] > (v0_8.AMQProtocolEngine) - Frame handled in 4109 ms. Frame: Frame channelId: > 1, bodyFrame: [ChannelCloseBodyImpl: replyCode=200, replyText=JMS client > closing channel, classId=0, methodId=0] > 2014-11-12 11:30:48,636 DEBUG [IoReceiver - /169.124.113.161:34264] > (v0_8.AMQChannel) - sync() called on channel 1(42262038) > 2014-11-12 11:30:48,636 INFO [IoReceiver - /169.124.113.161:34264] > (connection.close) - [con:17(guest@/169.124.113.161:34264/default)] > [con:17(guest@/169.124.113.161:34264/default)] CON-1002 : Close > 2014-11-12 11:30:48,636 DEBUG [IoReceiver - /169.124.113.161:34264] > (state.AMQStateManager) - State changing to CONNECTION_CLOSED > {noformat} -- This message was sent by Atlassian JIRA (v6.3.4#6332) --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@qpid.apache.org For additional commands, e-mail: dev-h...@qpid.apache.org