"adrian@jboss.org" wrote:
You haven't posted the log all the way through to conclusion.
You should see
RETURN: addMessage
when the add message has finished.
In the second log, you have the problem that memory is tight so JBossMQ is
pushing messages onto disk.
2004-04-01 12:17:17,125 TRACE [org.jboss.mq.server.MessageCache] Memory usage serverity=1.0
What are your memory and message cache settings?
Regards,
Adrian
Thanks for the fast response!
The second log is of a message that doesn't give any problem. I posted it for the comparison. But the memory in the machine is 2GB, min is 1024 en max 1536. If I inspect the memory there is always plenty of free memory.
The first log more complete:
2004-04-01 12:26:22,578 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : org.jboss.mq.SpyObjectMessage {
Header {
jmsDestination : TOPIC.testTopic
jmsDeliveryMode : 2
jmsExpiration : 1080818792578
jmsPriority : 1
jmsMessageID : ID:258-108081878257864
jmsTimeStamp : 1080818782578
jmsCorrelationID: null
jmsReplyTo : null
jmsType : null
jmsRedelivered : false
jmsProperties : {provider=SIM}
jmsPropertiesReadWrite:true
msgReadOnly : false
producerClientId: ID:258
}
}
2004-04-01 12:26:22,578 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : addMessage
2004-04-01 12:26:22,578 TRACE [org.jboss.mq.server.MessageReference] getMessage lock aquire 6324 msg=1761 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=2355778
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
Principal: guest
Principal: Roles(members:guest)
;principal=null;roles=Roles(members:guest) for rolePrincipals [durpublisher, publisher, guest]
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire message 6324 msg=1761 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=2355778 wasHard=true
2004-04-01 12:26:22,578 TRACE [org.jboss.mq.server.MessageCache] add locks release6329 msg=2282 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=17987846
2004-04-01 12:26:22,578 TRACE [org.jboss.mq.server.MessageCache] remove lock release message 6299 msg=1755 hard soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=17704026 clear= false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : org.jboss.mq.SpyObjectMessage {
Header {
jmsDestination : TOPIC.testTopic
jmsDeliveryMode : 2
jmsExpiration : 1080818792578
jmsPriority : 1
jmsMessageID : ID:130-108081878257897
jmsTimeStamp : 1080818782578
jmsCorrelationID: null
jmsReplyTo : null
jmsType : null
jmsRedelivered : false
jmsProperties : {provider=SIM}
jmsPropertiesReadWrite:true
msgReadOnly : false
producerClientId: ID:130
}
}
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpyXAResource] Start xid=XidImpl [FormatId=257, GlobalId=mls-dev//176625, BranchQual=], flags=0
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire lrucache 6324 msg=1761 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=2355778 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] run lock aquire, validateSoftReferenceDepth
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock aquire message 6291 msg=1750 soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=7684703 clear=true reset=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] makeSoft lock released 6299 msg=1755 soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=17704026
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
Principal: guest
Principal: Roles(members:guest)
;principal=null;roles=Roles(members:guest) for rolePrincipals [durpublisher, publisher, guest]
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpyXAResourceManager] Converting tx anonXid=-9223372036854774738, xid=XidImpl [FormatId=257, GlobalId=mls-dev//176625, BranchQual=]
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent locks released 6324 msg=1761 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=2355778 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock aquire lrucache 6291 msg=1750 soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=7684703 clear= true reset=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] run lock aquire, validateSoftReferenceDepth
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpySession] Setting current tx org.jboss.mq.SpyTopicSession@a0a36 xid=XidImpl [FormatId=257, GlobalId=mls-dev//176625, BranchQual=] previous: -9223372036854774738
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] Memory usage serverity=1.0
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock released 6324 msg=1761 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=2355778
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.Connection] Starting connection, ClientID=ID:136
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] Using optimized 1p commit to control TX.
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] Need to soften 38 messages
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : receive
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : setEnabled
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] run lock release, validateSoftReferenceDepth
2004-04-01 12:26:22,593 TRACE [org.jboss.ejb.plugins.jms.JMSContainerInvoker] processing message: org.jboss.mq.SpyObjectMessage {
Header {
jmsDestination : TOPIC.testTopic
jmsDeliveryMode : 2
jmsExpiration : 1080818782765
jmsPriority : 1
jmsMessageID : ID:132-108081877276574
jmsTimeStamp : 1080818772765
jmsCorrelationID: null
jmsReplyTo : null
jmsType : null
jmsRedelivered : false
jmsProperties : {provider=SIM}
jmsPropertiesReadWrite:false
msgReadOnly : true
producerClientId: ID:132
}
}
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSessionPool] getting a server session
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] soften lock acquire 6304 msg=2272 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock release lrucache 6291 msg=1750 soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=7684703 clear= true reset=true
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSessionPool] using server session: org.jboss.jms.asf.StdServerSession@216b59
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.ClientConsumer] ClientConsumer:ID:136->setEnabled(enabled=true)
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] makeSoft lock aquire 6304 msg=2272 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] clear lock aquire 6291 msg=1750 soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=7684703
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : setEnabled
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] Memory usage serverity=1.0
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpyConnectionConsumer] SpyConnectionConsumer:QUEUE.testQueue Starting the ServerSession.
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] Saving message to storage 6304 msg=2272 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] clear lock relased 6291 msg=1750 soft REMOVED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=7684703
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : addMessage
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] Need to soften 38 messages
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] run lock release, validateSoftReferenceDepth
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] soften lock acquire 6304 msg=2272 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : org.jboss.mq.SpyObjectMessage {
Header {
jmsDestination : TOPIC.testTopic
jmsDeliveryMode : 2
jmsExpiration : 1080818792593
jmsPriority : 1
jmsMessageID : ID:136-108081878259387
jmsTimeStamp : 1080818782593
jmsCorrelationID: null
jmsReplyTo : null
jmsType : null
jmsRedelivered : false
jmsProperties : {provider=SIM}
jmsPropertiesReadWrite:true
msgReadOnly : false
producerClientId: ID:136
}
}
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock release message NOT IN CACHE hashCode=7684703 clear= true reset=true
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] starting invokes on server session
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : transact
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
Principal: guest
Principal: Roles(members:guest)
;principal=null;roles=Roles(members:guest) for rolePrincipals [durpublisher, publisher, guest]
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] running...
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : receive
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] onMessage done
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpyXAResourceManager] Starting tx with new xid=-9223372036854774047
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : -2147483648
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] recycling...
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : -1
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpySession] Setting current tx org.jboss.mq.SpyTopicSession@1635484 xid=-9223372036854774047 previous: null
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] Saved message to storage 6304 msg=2272 hard STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSessionPool] recycled server session: org.jboss.jms.asf.StdServerSession@91d7c
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Checking receive authorize on SpyDistributedConnection:ID:3/b1f54387dcb822beb90286e64542a365 subId=-2147483648
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpyXAResourceManager] Ack'ing message xid=-9223372036854774047
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] finished run
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock aquire message 6304 msg=2272 hard soft STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051 clear=false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
Principal: guest
Principal: Roles(members:guest)
;principal=null;roles=Roles(members:guest) for rolePrincipals [publisher, guest]
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] onMessage running (pool, session, xaSession, useLocalTX): , org.jboss.mq.SpyTopicSession@1635484, org.jboss.mq.SpyTopicSession@1635484, true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock aquire lrucache 6304 msg=2272 hard soft STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051 clear= false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock aquire 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpyXAResource] Start xid=XidImpl [FormatId=257, GlobalId=mls-dev//176635, BranchQual=], flags=0
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock release lrucache 6304 msg=2272 hard soft STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051 clear= false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire message 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpyXAResourceManager] Converting tx anonXid=-9223372036854774047, xid=XidImpl [FormatId=257, GlobalId=mls-dev//176635, BranchQual=]
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock release message 6304 msg=2272 hard soft STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051 clear= false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire lrucache 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.SpySession] Setting current tx org.jboss.mq.SpyTopicSession@1635484 xid=XidImpl [FormatId=257, GlobalId=mls-dev//176635, BranchQual=] previous: -9223372036854774047
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] makeSoft lock released 6304 msg=2272 soft STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent locks released 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.jms.asf.StdServerSession] Using optimized 1p commit to control TX.
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock aquire 6329 msg=2282 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=17987846
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] makeSoft lock aquire 6304 msg=2272 soft STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=27689051
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock released 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939
2004-04-01 12:26:22,593 TRACE [org.jboss.ejb.plugins.jms.JMSContainerInvoker] processing message: org.jboss.mq.SpyObjectMessage {
Header {
jmsDestination : QUEUE.testQueue
jmsDeliveryMode : 2
jmsExpiration : 1080818902328
jmsPriority : 1
jmsMessageID : ID:1130-10808187823281
jmsTimeStamp : 1080818782328
jmsCorrelationID: null
jmsReplyTo : null
jmsType : null
jmsRedelivered : false
jmsProperties : {}
jmsPropertiesReadWrite:false
msgReadOnly : true
producerClientId: ID:1130
}
}
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire message 6329 msg=2282 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=17987846 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] run lock aquire, validateSoftReferenceDepth
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock aquire 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire lrucache 6329 msg=2282 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=17987846 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] Memory usage serverity=1.0
Header {
jmsDestination : QUEUE.testQueue
jmsDeliveryMode : 2
jmsExpiration : 1080818902328
jmsPriority : 1
jmsMessageID : ID:1130-10808187823281
jmsTimeStamp : 1080818782328
jmsCorrelationID: null
jmsReplyTo : null
jmsType : null
jmsRedelivered : false
jmsProperties : {}
jmsPropertiesReadWrite:false
msgReadOnly : true
producerClientId: ID:1130
}
}
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire message 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] Need to soften 37 messages
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] run lock release, validateSoftReferenceDepth
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] soften lock acquire 6301 msg=1756 hard STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=15703456
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire lrucache 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent locks released 6329 msg=2282 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=17987846 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] makeSoft lock aquire 6301 msg=1756 hard STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=15703456
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent locks released 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939 wasHard=true
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock released 6329 msg=2282 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=17987846
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock aquire message 6301 msg=1756 hard soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=15703456 clear=false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock aquire lrucache 6301 msg=1756 hard soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=15703456 clear= false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock release lrucache 6301 msg=1756 hard soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=15703456 clear= false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock released 6325 msg=1762 hard STORED PERSISTENT queue=QUEUE.testQueue priority=1 hashCode=32893939
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageReference] getMessage lock aquire 6216 msg=2243 soft STORED PERSISTENT queue=TOPIC.testTopic.ID:1.-2147483648 priority=1 hashCode=12680240
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] add lock aquire message 6330 msg=2283 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:4.-2147483648 priority=1 hashCode=1257809
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] remove lock release message 6301 msg=1756 hard soft STORED PERSISTENT queue=QUEUE.testQueue priority=4 hashCode=15703456 clear= false reset=false
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.MessageCache] add lock aquire lruCache 6330 msg=2283 hard NOT_STORED PERSISTENT queue=TOPIC.testTopic.ID:4.-2147483648 priority=1 hashCode=1257809
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : receive
2004-04-01 12:26:22,593 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : addMessage