You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@activemq.apache.org by Pep <pe...@yahoo.es> on 2006/08/01 10:54:53 UTC
ActiveMQ Hangs on OC4J
Hi,
we deployed ActiveMQ as an embedded broker in OC4J 10.1.3. Everything seemed
to work fine but we are experiencing some hangs in ActiveMQ after delivering
some messages (sometimes a lot of messages, sometimes just a few ones). When
ActiveMQ is hang, we try to connect with Hermes, but we are unable to browse
the Queue's contents, Hermes just stays trying to connect.
Furthermore, we are still able to navigate to other parts of the application
which do not interact with ActiveMQ. This indicates that OC4J is still
alive. But as soon we call a feature that interacts with ActiveMQ by
producing messages, then we get the whole system hanged or not working
properly.
Unfortuatelly, we do not receive any exception or message that may help
identify where the hang is produced (we set log4j traces to DEBUG level). We
are using VM transport protocol inside our application and tcp protocol from
Hermes.
Did anyone experience a similar problem? I looked in the Jira repository of
ActiveMQ but I could not find any issue similar to this one. Is there any
way to get detailed info of what is being hang?
Thanks in advance,
Pep.
--
View this message in context: http://www.nabble.com/ActiveMQ-Hangs-on-OC4J-tf2032286.html#a5590730
Sent from the ActiveMQ - User forum at Nabble.com.
Re: ActiveMQ Hangs on OC4J
Posted by Pep <pe...@yahoo.es>.
Problem found! It was a problem with the version on the Oracle jdbc driver.
At the end, nothing related to ActiveMQ :))
Thanks for the help.
Pep.
--
View this message in context: http://www.nabble.com/ActiveMQ-Hangs-on-OC4J-tf2032286.html#a5599593
Sent from the ActiveMQ - User forum at Nabble.com.
Re: ActiveMQ Hangs on OC4J
Posted by Pep <pe...@yahoo.es>.
Quite a lot, about 100 KB.
Pep.
--
View this message in context: http://www.nabble.com/ActiveMQ-Hangs-on-OC4J-tf2032286.html#a5596478
Sent from the ActiveMQ - User forum at Nabble.com.
Re: ActiveMQ Hangs on OC4J
Posted by James Strachan <ja...@gmail.com>.
If the messages don't make it to the journal then you have not yet
completed committing your transaction.
How big are the messages?
On 8/1/06, Pep <pe...@yahoo.es> wrote:
>
> We are using ActiveMQ 4.0.1.
>
> I increased the memory limit to 25 MB and also made sure that the messages
> were published with DeliveryMode.PERSISTENT flag. I still got activemq
> hanged. This is the debug traces I got. As you can see, there are 9 messages
> published, 7 to QUEUE.CONTROL_DELETED queue and 2 QUEUE.PATIENT_DELETED
> queue. Only 8 of them got commited to the Journal Store. Does this mean that
> the transaction is not fully commited in the ActiveMQ side? Furthermore,
> notice that one ControlDeletedMessage and one PatientDeleted message are
> consumed (you can identify it because there is the trace JAMES_EVENTS_INFO
> for every consumption.
>
> These are the DEBUG traces.
>
> 2006-08-01 14:52:18,512 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
> 2006-08-01 14:52:18,512 DEBUG [org.apache.activemq.TransactionContext]
> Started XA transaction:
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
> 2006-08-01 14:52:18,528 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:1:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:1:1, destination =
> queue://QUEUE.CONTROL_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436738512, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@64804,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:18,528 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:1:1:1, at:
> 0:67882
> 2006-08-01 14:52:18,637 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:2:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:2:1, destination =
> queue://QUEUE.CONTROL_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436738622, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@1f43791,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:18,637 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:2:1:1, at:
> 0:82928
> 2006-08-01 14:52:18,840 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:3:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:3:1, destination =
> queue://QUEUE.PATIENT_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436738825, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@79de1d,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:18,840 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:3:1:1, at:
> 0:98339
> 2006-08-01 14:52:19,184 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:4:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:4:1, destination =
> queue://QUEUE.CONTROL_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436739184, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@186cfde,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:19,184 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:4:1:1, at:
> 0:101214
> 2006-08-01 14:52:19,309 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:5:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:5:1, destination =
> queue://QUEUE.CONTROL_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436739309, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@a808d0,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:19,309 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:5:1:1, at:
> 0:116043
> 2006-08-01 14:52:19,434 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:6:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:6:1, destination =
> queue://QUEUE.CONTROL_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436739434, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@893391,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:19,434 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:6:1:1, at:
> 0:130872
> 2006-08-01 14:52:19,559 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:7:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:7:1, destination =
> queue://QUEUE.CONTROL_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436739559, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@b53830,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:19,559 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:7:1:1, at:
> 0:145701
> 2006-08-01 14:52:19,747 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:8:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:8:1, destination =
> queue://QUEUE.CONTROL_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436739747, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@200ff6,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:19,747 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:8:1:1, at:
> 0:160711
> 2006-08-01 14:52:19,965 DEBUG [org.apache.activemq.ActiveMQSession] Sending
> message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
> messageId = ID:nte70-4068-1154436638525-3:7:9:1:1, originalDestination =
> null, originalTransactionId = null, producerId =
> ID:nte70-4068-1154436638525-3:7:9:1, destination =
> queue://QUEUE.PATIENT_DELETED, transactionId =
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
> expiration = 0, timestamp = 1154436739950, arrival = 0, correlationId =
> null, replyTo = null, persistent = true, type = null, priority = 4, groupID
> = null, groupSequence = 0, targetConsumerId = null, compressed = false,
> userID = null, content = org.apache.activeio.packet.ByteSequence@17dfc02,
> marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
> size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
> 2006-08-01 14:52:19,965 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message add for: ID:nte70-4068-1154436638525-3:7:9:1:1, at:
> 0:175721
> 2006-08-01 14:52:20,356 DEBUG [org.apache.activemq.TransactionContext] End:
> Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
> 2006-08-01 14:52:20,356 DEBUG [org.apache.activemq.TransactionContext] Ended
> XA transaction:
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
> 2006-08-01 14:52:20,356 DEBUG [org.apache.activemq.TransactionContext]
> Prepare: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
> 2006-08-01 14:52:20,356 DEBUG
> [org.apache.activemq.transaction.XATransaction] XA Transaction prepare:
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
> 2006-08-01 14:52:20,403 DEBUG [org.apache.activemq.TransactionContext]
> Commit: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
> 2006-08-01 14:52:20,403 DEBUG
> [org.apache.activemq.transaction.XATransaction] XA Transaction commit:
> XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
> 2006-08-01 14:52:20,418 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:1:1:1, at: 0:67882
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Using idle session: ServerSessionImpl:3
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
> Starting run.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
> Work accepted:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@13d7910]
> 2006-08-01 14:52:20,418 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:2:1:1, at: 0:82928
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Using idle session: ServerSessionImpl:2
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
> Starting run.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
> Work accepted:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@d71e97]
> 2006-08-01 14:52:20,418 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:3:1:1, at: 0:98339
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Using idle session: ServerSessionImpl:4
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> Starting run.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> Work accepted:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@1194cf5]
> 2006-08-01 14:52:20,418 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:4:1:1, at: 0:101214
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Using idle session: ServerSessionImpl:1
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
> Starting run.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
> Work accepted:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ba6ef]
> 2006-08-01 14:52:20,418 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:5:1:1, at: 0:116043
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Using idle session: ServerSessionImpl:0
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
> Starting run.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
> Work accepted:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@fccc53]
> 2006-08-01 14:52:20,418 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:6:1:1, at: 0:130872
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Created a new session: ServerSessionImpl:5
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
> Starting run.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
> Work accepted:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ca9718]
> 2006-08-01 14:52:20,418 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:7:1:1, at: 0:145701
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Created a new session: ServerSessionImpl:6
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
> Starting run.
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
> Work accepted:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@85dc5a]
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
> Work started:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@13d7910]
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
> Running
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
> run loop start
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1d.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.TransactionContext]
> Started XA transaction:
> XID:4660:0a000046f62200001d000000000000000000000000000000:3e56f9a7000000000000000000000000
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
> Work started:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@d71e97]
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> Work started:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@1194cf5]
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
> Work started:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ba6ef]
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
> Work started:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@fccc53]
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
> Work started:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ca9718]
> 2006-08-01 14:52:20,434 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> add commit for: ID:nte70-4068-1154436638525-3:7:8:1:1, at: 0:160711
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
> Work started:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@85dc5a]
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
> Running
> 2006-08-01 14:52:20,434 INFO [JAMES_EVENTS_INFO] Control
> Deleted[User[Id=1,Username=was,Locale=en_US,Role=11,Facility=null,Patient=null,Digital
> Signature=nullFullName [name=websphere, surname=do not touch,
> middlename=null, second surname=null]],
> com.nte.anthema.datamodel.calendar.DetailedWithAppointment@2da883]
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
> Running
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> Running
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
> Running
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> ServerSession requested.
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
> Running
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
> run loop start
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
> run loop start
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> run loop start
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
> run loop start
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
> run loop start
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
> Running
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1e.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1f.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.20.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
> run loop start
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.22.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Started XA transaction:
> XID:4660:0a000046f62200001f000000000000000000000000000000:3e56f9a7000000000000000000000000
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.23.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,434 INFO [JAMES_EVENTS_INFO] Patient
> Deleted[User[Id=1,Username=was,Locale=en_US,Role=11,Facility=null,Patient=null,Digital
> Signature=nullFullName [name=websphere, surname=do not touch,
> middlename=null, second surname=null]], Patient[Facility ID=81, custom
> code=null, language code=es, Status=1, New Contact=Contact[[Full
> Name=[name=Josep, surname=Viladomat, middlename=null, second
> surname=Queral], PostalAddress=[address=C\ Aragn, city=Barcelona,
> countryCode=ES, province=Barcelona, zipCode=08015], Nationality=ES,
> phone1=934010101, phone2=null, mobile1=null, mobile2=null, fax=null,
> faxLocation=null, email1=null, email2=null, Office Email=null,
> DOB=1975-09-11, Sex=Unknown, Regional ID=3839634, Fax Location=null,
> ID=922], Patient Care Giver ID=null, Patient Care Giver Relation=null,
> Doctor Note=null, Host Id=1415, Exitus date=null, Consent Signed = true,
> Consent Limit date = null, Created From Host = false]]
> 2006-08-01 14:52:20,434 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Journalled
> transacted message remove for: ID:nte70-4068-1154436638525-3:7:3:1:1, at:
> 0:178756
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext] End:
> Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1f.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext] Ended
> XA transaction:
> XID:4660:0a000046f62200001f000000000000000000000000000000:3e56f9a7000000000000000000000000
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Commit: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.1f.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,434 DEBUG
> [org.apache.activemq.transaction.XATransaction] XA Transaction commit:
> XID:4660:0a000046f62200001f000000000000000000000000000000:3e56f9a7000000000000000000000000
> 2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
> Start: Xid( Global Id
> 0a.00.00.46.f6.22.00.00.21.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
> Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
> 2006-08-01 14:52:20,450 DEBUG
> [org.apache.activemq.store.journal.JournalMessageStore] Transacted message
> remove commit for: ID:nte70-4068-1154436638525-3:7:3:1:1, at: 0:178756
> 2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> run loop end
> 2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
> Session returned to pool: ServerSessionImpl:4
> 2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> Run finished
> 2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
> Work completed:
> javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@1194cf5]
> 2006-08-01 14:57:11,863 DEBUG
> [org.apache.activemq.store.journal.JournalPersistenceAdapter] Checkpoint
> started.
> 2006-08-01 14:57:12,113 DEBUG
> [org.apache.activemq.store.journal.JournalPersistenceAdapter] Marking
> journal at: 0:175721
> 2006-08-01 14:57:12,129 DEBUG
> [org.apache.activemq.store.jdbc.JDBCPersistenceAdapter] Cleaning up old
> messages.
> 2006-08-01 14:57:12,129 DEBUG
> [org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter] Executing SQL:
> DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <= (
> SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE
> ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
> 2006-08-01 14:57:12,145 DEBUG
> [org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter] Deleted 0 old
> message(s).
> 2006-08-01 14:57:12,145 DEBUG
> [org.apache.activemq.store.jdbc.JDBCPersistenceAdapter] Cleanup done.
> 2006-08-01 14:57:12,145 DEBUG
> [org.apache.activemq.store.journal.JournalPersistenceAdapter] Checkpoint
> done.
>
>
> Pep.
>
>
> --
> View this message in context: http://www.nabble.com/ActiveMQ-Hangs-on-OC4J-tf2032286.html#a5596260
> Sent from the ActiveMQ - User forum at Nabble.com.
>
>
--
James
-------
http://radio.weblogs.com/0112098/
Re: ActiveMQ Hangs on OC4J
Posted by Pep <pe...@yahoo.es>.
We are using ActiveMQ 4.0.1.
I increased the memory limit to 25 MB and also made sure that the messages
were published with DeliveryMode.PERSISTENT flag. I still got activemq
hanged. This is the debug traces I got. As you can see, there are 9 messages
published, 7 to QUEUE.CONTROL_DELETED queue and 2 QUEUE.PATIENT_DELETED
queue. Only 8 of them got commited to the Journal Store. Does this mean that
the transaction is not fully commited in the ActiveMQ side? Furthermore,
notice that one ControlDeletedMessage and one PatientDeleted message are
consumed (you can identify it because there is the trace JAMES_EVENTS_INFO
for every consumption.
These are the DEBUG traces.
2006-08-01 14:52:18,512 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
2006-08-01 14:52:18,512 DEBUG [org.apache.activemq.TransactionContext]
Started XA transaction:
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
2006-08-01 14:52:18,528 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:1:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:1:1, destination =
queue://QUEUE.CONTROL_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436738512, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@64804,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:18,528 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:1:1:1, at:
0:67882
2006-08-01 14:52:18,637 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:2:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:2:1, destination =
queue://QUEUE.CONTROL_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436738622, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@1f43791,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:18,637 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:2:1:1, at:
0:82928
2006-08-01 14:52:18,840 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:3:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:3:1, destination =
queue://QUEUE.PATIENT_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436738825, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@79de1d,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:18,840 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:3:1:1, at:
0:98339
2006-08-01 14:52:19,184 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:4:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:4:1, destination =
queue://QUEUE.CONTROL_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436739184, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@186cfde,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:19,184 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:4:1:1, at:
0:101214
2006-08-01 14:52:19,309 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:5:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:5:1, destination =
queue://QUEUE.CONTROL_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436739309, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@a808d0,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:19,309 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:5:1:1, at:
0:116043
2006-08-01 14:52:19,434 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:6:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:6:1, destination =
queue://QUEUE.CONTROL_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436739434, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@893391,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:19,434 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:6:1:1, at:
0:130872
2006-08-01 14:52:19,559 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:7:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:7:1, destination =
queue://QUEUE.CONTROL_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436739559, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@b53830,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:19,559 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:7:1:1, at:
0:145701
2006-08-01 14:52:19,747 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:8:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:8:1, destination =
queue://QUEUE.CONTROL_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436739747, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@200ff6,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:19,747 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:8:1:1, at:
0:160711
2006-08-01 14:52:19,965 DEBUG [org.apache.activemq.ActiveMQSession] Sending
message: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:nte70-4068-1154436638525-3:7:9:1:1, originalDestination =
null, originalTransactionId = null, producerId =
ID:nte70-4068-1154436638525-3:7:9:1, destination =
queue://QUEUE.PATIENT_DELETED, transactionId =
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001,
expiration = 0, timestamp = 1154436739950, arrival = 0, correlationId =
null, replyTo = null, persistent = true, type = null, priority = 4, groupID
= null, groupSequence = 0, targetConsumerId = null, compressed = false,
userID = null, content = org.apache.activeio.packet.ByteSequence@17dfc02,
marshalledProperties = null, dataStructure = null, redeliveryCounter = 0,
size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true}
2006-08-01 14:52:19,965 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message add for: ID:nte70-4068-1154436638525-3:7:9:1:1, at:
0:175721
2006-08-01 14:52:20,356 DEBUG [org.apache.activemq.TransactionContext] End:
Xid( Global Id
0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
2006-08-01 14:52:20,356 DEBUG [org.apache.activemq.TransactionContext] Ended
XA transaction:
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
2006-08-01 14:52:20,356 DEBUG [org.apache.activemq.TransactionContext]
Prepare: Xid( Global Id
0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
2006-08-01 14:52:20,356 DEBUG
[org.apache.activemq.transaction.XATransaction] XA Transaction prepare:
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
2006-08-01 14:52:20,403 DEBUG [org.apache.activemq.TransactionContext]
Commit: Xid( Global Id
0a.00.00.46.f6.22.00.00.1c.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.01)
2006-08-01 14:52:20,403 DEBUG
[org.apache.activemq.transaction.XATransaction] XA Transaction commit:
XID:4660:0a000046f62200001c000000000000000000000000000000:3e56f9a7000000000000000000000001
2006-08-01 14:52:20,418 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:1:1:1, at: 0:67882
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Using idle session: ServerSessionImpl:3
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
Starting run.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
Work accepted:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@13d7910]
2006-08-01 14:52:20,418 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:2:1:1, at: 0:82928
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Using idle session: ServerSessionImpl:2
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
Starting run.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
Work accepted:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@d71e97]
2006-08-01 14:52:20,418 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:3:1:1, at: 0:98339
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Using idle session: ServerSessionImpl:4
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
Starting run.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
Work accepted:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@1194cf5]
2006-08-01 14:52:20,418 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:4:1:1, at: 0:101214
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Using idle session: ServerSessionImpl:1
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
Starting run.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
Work accepted:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ba6ef]
2006-08-01 14:52:20,418 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:5:1:1, at: 0:116043
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Using idle session: ServerSessionImpl:0
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
Starting run.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
Work accepted:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@fccc53]
2006-08-01 14:52:20,418 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:6:1:1, at: 0:130872
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Created a new session: ServerSessionImpl:5
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
Starting run.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
Work accepted:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ca9718]
2006-08-01 14:52:20,418 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:7:1:1, at: 0:145701
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Created a new session: ServerSessionImpl:6
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
Starting run.
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
Work accepted:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@85dc5a]
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
Work started:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@13d7910]
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
Running
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.ra.ServerSessionImpl:3]
run loop start
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.1d.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,418 DEBUG [org.apache.activemq.TransactionContext]
Started XA transaction:
XID:4660:0a000046f62200001d000000000000000000000000000000:3e56f9a7000000000000000000000000
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
Work started:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@d71e97]
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
Work started:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@1194cf5]
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
Work started:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ba6ef]
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
Work started:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@fccc53]
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
Work started:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@ca9718]
2006-08-01 14:52:20,434 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
add commit for: ID:nte70-4068-1154436638525-3:7:8:1:1, at: 0:160711
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
Work started:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@85dc5a]
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
Running
2006-08-01 14:52:20,434 INFO [JAMES_EVENTS_INFO] Control
Deleted[User[Id=1,Username=was,Locale=en_US,Role=11,Facility=null,Patient=null,Digital
Signature=nullFullName [name=websphere, surname=do not touch,
middlename=null, second surname=null]],
com.nte.anthema.datamodel.calendar.DetailedWithAppointment@2da883]
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
Running
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
Running
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
Running
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
ServerSession requested.
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
Running
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:2]
run loop start
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:1]
run loop start
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
run loop start
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:5]
run loop start
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:6]
run loop start
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
Running
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.1e.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.1f.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.20.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.ra.ServerSessionImpl:0]
run loop start
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.22.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Started XA transaction:
XID:4660:0a000046f62200001f000000000000000000000000000000:3e56f9a7000000000000000000000000
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.23.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,434 INFO [JAMES_EVENTS_INFO] Patient
Deleted[User[Id=1,Username=was,Locale=en_US,Role=11,Facility=null,Patient=null,Digital
Signature=nullFullName [name=websphere, surname=do not touch,
middlename=null, second surname=null]], Patient[Facility ID=81, custom
code=null, language code=es, Status=1, New Contact=Contact[[Full
Name=[name=Josep, surname=Viladomat, middlename=null, second
surname=Queral], PostalAddress=[address=C\ Aragn, city=Barcelona,
countryCode=ES, province=Barcelona, zipCode=08015], Nationality=ES,
phone1=934010101, phone2=null, mobile1=null, mobile2=null, fax=null,
faxLocation=null, email1=null, email2=null, Office Email=null,
DOB=1975-09-11, Sex=Unknown, Regional ID=3839634, Fax Location=null,
ID=922], Patient Care Giver ID=null, Patient Care Giver Relation=null,
Doctor Note=null, Host Id=1415, Exitus date=null, Consent Signed = true,
Consent Limit date = null, Created From Host = false]]
2006-08-01 14:52:20,434 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Journalled
transacted message remove for: ID:nte70-4068-1154436638525-3:7:3:1:1, at:
0:178756
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext] End:
Xid( Global Id
0a.00.00.46.f6.22.00.00.1f.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext] Ended
XA transaction:
XID:4660:0a000046f62200001f000000000000000000000000000000:3e56f9a7000000000000000000000000
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Commit: Xid( Global Id
0a.00.00.46.f6.22.00.00.1f.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,434 DEBUG
[org.apache.activemq.transaction.XATransaction] XA Transaction commit:
XID:4660:0a000046f62200001f000000000000000000000000000000:3e56f9a7000000000000000000000000
2006-08-01 14:52:20,434 DEBUG [org.apache.activemq.TransactionContext]
Start: Xid( Global Id
0a.00.00.46.f6.22.00.00.21.00.00.00.00.00.00.00.00.00.00.00.00.00.00.00,
Format Id 4660, Branch Id 3e.56.f9.a7.00.00.00.00.00.00.00.00.00.00.00.00)
2006-08-01 14:52:20,450 DEBUG
[org.apache.activemq.store.journal.JournalMessageStore] Transacted message
remove commit for: ID:nte70-4068-1154436638525-3:7:3:1:1, at: 0:178756
2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
run loop end
2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl]
Session returned to pool: ServerSessionImpl:4
2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
Run finished
2006-08-01 14:52:20,465 DEBUG [org.apache.activemq.ra.ServerSessionImpl:4]
Work completed:
javax.resource.spi.work.WorkEvent[source=oracle.j2ee.connector.work.WorkWrapper@1194cf5]
2006-08-01 14:57:11,863 DEBUG
[org.apache.activemq.store.journal.JournalPersistenceAdapter] Checkpoint
started.
2006-08-01 14:57:12,113 DEBUG
[org.apache.activemq.store.journal.JournalPersistenceAdapter] Marking
journal at: 0:175721
2006-08-01 14:57:12,129 DEBUG
[org.apache.activemq.store.jdbc.JDBCPersistenceAdapter] Cleaning up old
messages.
2006-08-01 14:57:12,129 DEBUG
[org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter] Executing SQL:
DELETE FROM ACTIVEMQ_MSGS WHERE ( EXPIRATION<>0 AND EXPIRATION<?) OR ID <= (
SELECT min(ACTIVEMQ_ACKS.LAST_ACKED_ID) FROM ACTIVEMQ_ACKS WHERE
ACTIVEMQ_ACKS.CONTAINER=ACTIVEMQ_MSGS.CONTAINER)
2006-08-01 14:57:12,145 DEBUG
[org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter] Deleted 0 old
message(s).
2006-08-01 14:57:12,145 DEBUG
[org.apache.activemq.store.jdbc.JDBCPersistenceAdapter] Cleanup done.
2006-08-01 14:57:12,145 DEBUG
[org.apache.activemq.store.journal.JournalPersistenceAdapter] Checkpoint
done.
Pep.
--
View this message in context: http://www.nabble.com/ActiveMQ-Hangs-on-OC4J-tf2032286.html#a5596260
Sent from the ActiveMQ - User forum at Nabble.com.
Re: ActiveMQ Hangs on OC4J
Posted by James Strachan <ja...@gmail.com>.
Which version are you using?
The most likely cause is you are using non-persistent messaging and
you have ran out of RAM - try increase your <usageManager/>'s limit
which is pretty small out of the box.
http://incubator.apache.org/activemq/xml-configuration.html
On 8/1/06, Pep <pe...@yahoo.es> wrote:
>
> Hi,
>
> we deployed ActiveMQ as an embedded broker in OC4J 10.1.3. Everything seemed
> to work fine but we are experiencing some hangs in ActiveMQ after delivering
> some messages (sometimes a lot of messages, sometimes just a few ones). When
> ActiveMQ is hang, we try to connect with Hermes, but we are unable to browse
> the Queue's contents, Hermes just stays trying to connect.
>
> Furthermore, we are still able to navigate to other parts of the application
> which do not interact with ActiveMQ. This indicates that OC4J is still
> alive. But as soon we call a feature that interacts with ActiveMQ by
> producing messages, then we get the whole system hanged or not working
> properly.
>
> Unfortuatelly, we do not receive any exception or message that may help
> identify where the hang is produced (we set log4j traces to DEBUG level). We
> are using VM transport protocol inside our application and tcp protocol from
> Hermes.
>
> Did anyone experience a similar problem? I looked in the Jira repository of
> ActiveMQ but I could not find any issue similar to this one. Is there any
> way to get detailed info of what is being hang?
>
> Thanks in advance,
>
> Pep.
> --
> View this message in context: http://www.nabble.com/ActiveMQ-Hangs-on-OC4J-tf2032286.html#a5590730
> Sent from the ActiveMQ - User forum at Nabble.com.
>
>
--
James
-------
http://radio.weblogs.com/0112098/