You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@qpid.apache.org by "Alex Rudyy (JIRA)" <ji...@apache.org> on 2014/12/03 13:19:13 UTC
[jira] [Updated] (QPID-6257) [Java Broker] Excessive ERROR log
entry is added into a Broker log file on unexpected socket close by the
client
[ https://issues.apache.org/jira/browse/QPID-6257?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Alex Rudyy updated QPID-6257:
-----------------------------
Attachment: Introduce_operational_log_for_unexpected_connection_close_and_reduce_logging_l.patch
Attached a patch containing changes for 0.9 path from a very old branch
> [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: 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-unsubscribe@qpid.apache.org
For additional commands, e-mail: dev-help@qpid.apache.org