[jboss-jira] [JBoss JIRA] (JBMESSAGING-1961) message didn't put into the queue

Tony Fan (JIRA) issues at jboss.org
Tue Apr 18 17:27:00 EDT 2017


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 at 1aea853 scheduled org.jboss.remoting.transport.socket.TimedOutputStream$OutputTimerTask at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 12ba248 scheduled org.jboss.remoting.transport.socket.TimedOutputStream$OutputTimerTask at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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)


More information about the jboss-jira mailing list