qpid-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "ASF subversion and git services (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (QPID-6257) [Java Broker] Excessive ERROR log entry is added into a Broker log file on unexpected socket close by the client
Date Fri, 05 Dec 2014 09:34:12 GMT

    [ https://issues.apache.org/jira/browse/QPID-6257?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14235310#comment-14235310
] 

ASF subversion and git services commented on QPID-6257:
-------------------------------------------------------

Commit 1643208 from orudyy@apache.org in branch 'qpid/trunk'
[ https://svn.apache.org/r1643208 ]

QPID-6257: Introduce operational log for connection being dropped by the clients or due to
network issue and change the log level for SenderExceptions into INFO/DEBUG

> [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-unsubscribe@qpid.apache.org
For additional commands, e-mail: dev-help@qpid.apache.org


Mime
View raw message