Tony Fan created JBMESSAGING-1961: ------------------------------------- Summary: message didn't put into the queue Key: JBMESSAGING-1961 URL: https://issues.jboss.org/browse/JBMESSAGING-1961 Project: JBoss Messaging Issue Type: Feature Request Components: Messaging Core Affects Versions: 1.4.8.SP9 Environment: Jboss 5.1 JBOSS remoting 2.5.4.SP5 Jboss JMS 1.4.8.SP9 Reporter: Tony Fan We have a one central server is using Jboss 5.1 with JBossQueue[dmStatusQueue], two instruments send status message to central server every 20-30 seconds, after system running for a while we had discovered that one of our instrument message got to put into the queue, the other didn't, I turned debug on and find out message never put into the queue. here is log from jboss successfully : 2017-04-12 12:12:01,730 TRACE [org.jboss.jms.server.remoting.ServerSocketWrapper] (WorkerThread#1441[192.168.207.136:45506]) acknowledge read byte WorkerThread#1441[192.168.207.136:45506] 2017-04-12 12:12:01,730 TRACE [org.jboss.remoting.transport.socket.TimedOutputStream] (WorkerThread#1441[192.168.207.136:45506]) org.jboss.remoting.transport.socket.TimedOutputStream@1aea853 scheduled org.jboss.remoting.transport.socket.TimedOutputStream$OutputTimerTask@55b8ad: 30000 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#1441[192.168.207.136:45506]) preparing to process next invocation 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#1441[192.168.207.136:45506]) WorkerThread#1441[192.168.207.136:45506] blocking to read version from input stream 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#1441[192.168.207.136:45506]) WorkerThread#1441[192.168.207.136:45506] read version 22 from input stream 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#1441[192.168.207.136:45506]) blocking to read invocation from unmarshaller 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#1441[192.168.207.136:45506]) Reading 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#1441[192.168.207.136:45506]) Stream is already DataInputStream :) 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#1441[192.168.207.136:45506]) Created packet org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#1441[192.168.207.136:45506]) Reading packet 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#1441[192.168.207.136:45506]) Read packet 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#1441[192.168.207.136:45506]) Returning payload: InvocationRequest[c768f1, JMS, OnewayInvocation[org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e]] 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#1441[192.168.207.136:45506]) read InvocationRequest[c768f1, JMS, OnewayInvocation[org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e]] from unmarshaller 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#1441[192.168.207.136:45506]) about to call SocketServerInvoker[192.168.207.199:4459].invoke() 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#1441[192.168.207.136:45506]) SocketServerInvoker[192.168.207.199:4459] received OnewayInvocation[org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e] 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#1441[192.168.207.136:45506]) reusing oneway thread pool 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#1441[192.168.207.136:45506]) SocketServerInvoker[192.168.207.199:4459] placing InvocationRequest[c768f1, JMS, org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e] in onewayThreadPool 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#1441[192.168.207.136:45506]) SocketServerInvoker[192.168.207.199:4459] received org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e 2017-04-12 12:12:01,731 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#1441[192.168.207.136:45506]) SocketServerInvoker[192.168.207.199:4459] dispatching InvocationRequest[c768f1, JMS, org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e] from client null to subsystem 'JMS' 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.server.remoting.JMSServerInvocationHandler] (WorkerThread#1441[192.168.207.136:45506]) invoking InvocationRequest[c768f1, JMS, org.jboss.jms.wireformat.SessionSendRequest@1cf6c2e] 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.server.container.SecurityAspect] (WorkerThread#1441[192.168.207.136:45506]) checking access permissions to JBossQueue[dmStatusQueue] 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore] (WorkerThread#1441[192.168.207.136:45506]) authenticating user null 2017-04-12 12:12:01,731 TRACE [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore] (WorkerThread#1441[192.168.207.136:45506]) authorizing user null for role(s) [guest, publisher] 2017-04-12 12:12:01,732 TRACE [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore] (WorkerThread#1441[192.168.207.136:45506]) user null is authorized 2017-04-12 12:12:01,732 TRACE [org.jboss.jms.server.endpoint.ServerConnectionEndpoint] (WorkerThread#1441[192.168.207.136:45506]) ConnectionEndpoint[0t24-tvhtld1j-1-8yfjj21j-m4r0qw-j5r5o4c5] sending message JBossMessage[6111287385027365]:NON-PERSISTENT non-transactionally ......... Here is one unsuccessfully: 2017-04-18 15:56:53,593 TRACE [org.jboss.jms.server.remoting.ServerSocketWrapper] (WorkerThread#21[192.168.207.136:41466]) acknowledge read byte WorkerThread#21[192.168.207.136:41466] 2017-04-18 15:56:53,593 TRACE [org.jboss.remoting.transport.socket.TimedOutputStream] (WorkerThread#21[192.168.207.136:41466]) org.jboss.remoting.transport.socket.TimedOutputStream@12ba248 scheduled org.jboss.remoting.transport.socket.TimedOutputStream$OutputTimerTask@cabb1d: 30000 2017-04-18 15:56:53,593 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) preparing to process next invocation 2017-04-18 15:56:53,593 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) WorkerThread#21[192.168.207.136:41466] blocking to read version from input stream 2017-04-18 15:56:53,595 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) WorkerThread#21[192.168.207.136:41466] read version 22 from input stream 2017-04-18 15:56:53,595 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) blocking to read invocation from unmarshaller 2017-04-18 15:56:53,596 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#21[192.168.207.136:41466]) Reading 2017-04-18 15:56:53,596 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#21[192.168.207.136:41466]) Stream is already DataInputStream :) 2017-04-18 15:56:53,596 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#21[192.168.207.136:41466]) Created packet org.jboss.jms.wireformat.SessionSendRequest@1c28b12 2017-04-18 15:56:53,596 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#21[192.168.207.136:41466]) Reading packet 2017-04-18 15:56:53,596 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#21[192.168.207.136:41466]) Read packet 2017-04-18 15:56:53,596 TRACE [org.jboss.jms.wireformat.JMSWireFormat] (WorkerThread#21[192.168.207.136:41466]) Returning payload: InvocationRequest[13875b4, JMS, OnewayInvocation[org.jboss.jms.wireformat.SessionSendRequest@1c28b12]] 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) read InvocationRequest[13875b4, JMS, OnewayInvocation[org.jboss.jms.wireformat.SessionSendRequest@1c28b12]] from unmarshaller 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) about to call SocketServerInvoker[192.168.207.199:4459].invoke() 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#21[192.168.207.136:41466]) SocketServerInvoker[192.168.207.199:4459] received OnewayInvocation[org.jboss.jms.wireformat.SessionSendRequest@1c28b12] 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#21[192.168.207.136:41466]) reusing oneway thread pool 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#21[192.168.207.136:41466]) SocketServerInvoker[192.168.207.199:4459] placing InvocationRequest[13875b4, JMS, org.jboss.jms.wireformat.SessionSendRequest@1c28b12] in onewayThreadPool 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#21[192.168.207.136:41466]) SocketServerInvoker[192.168.207.199:4459] received org.jboss.jms.wireformat.SessionSendRequest@1c28b12 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#21[192.168.207.136:41466]) SocketServerInvoker[192.168.207.199:4459] dispatching InvocationRequest[13875b4, JMS, org.jboss.jms.wireformat.SessionSendRequest@1c28b12] from client null to subsystem 'JMS' 2017-04-18 15:56:53,596 TRACE [org.jboss.jms.server.remoting.JMSServerInvocationHandler] (WorkerThread#21[192.168.207.136:41466]) invoking InvocationRequest[13875b4, JMS, org.jboss.jms.wireformat.SessionSendRequest@1c28b12] 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.ServerInvoker] (WorkerThread#21[192.168.207.136:41466]) SocketServerInvoker[192.168.207.199:4459] successfully dispatched invocation, returning null from subsystem 'JMS' to client null 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) SocketServerInvoker[192.168.207.199:4459].invoke() returned null 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) oneway request, writing no reply on the wire 2017-04-18 15:56:53,596 TRACE [org.jboss.remoting.transport.socket.ServerThread] (WorkerThread#21[192.168.207.136:41466]) checking connection ------------------------------------------------------------------------------------------------------- it skipped the part "TRACE [org.jboss.jms.server.container.SecurityAspect] (WorkerThread#1441[192.168.207.136:45506]) checking access permissions to JBossQueue[dmStatusQueue]" can someone help me to do more debug on this issue? Thanks -- This message was sent by Atlassian JIRA (v7.2.3#72005)