Cross posting to the dev_at_genericjmsra mailing list as well.
-------- Original Message --------
Subject: Some errors appearing connecting Sun AS9 (Beta) to Sun JMQ
Date: Tue, 05 Jun 2007 18:37:25 +0100
From: Donald Forbes <donald.forbes_at_sun.com>
To: issues_at_genericjmsra.dev.java.net
Hi Experts,
Not too sure where the fault may lie as I am using quite a few beta
projects here. Namely AS 9.1 (Beta1), JMQ 4.1 and GenericJMSRA 2.0.a.
However I am seeing the errors reported in the log files below occur
with a simple test application that consumes from the queue and produces
messages as an XA transaction into a database.
At the moment I suspect there may be an issue with the GenericJMSRA as I
have tried the same configuration out using AS8.1, MQ 3.6 and switching
the G-JMSRA between the 1.5.1 version and the 2.0 version and I see
these same errors reported as soon as I go to the G-JMSRA 2.0 version.
Anyone else seen anything like this or have any ideas what might be
going wrong?
Many thanks
Donald
*Server.log (Snippet)*
[#|2007-06-05T16:23:31.212+0100|FINEST|sun-appserver9.1|com.service.messaging|_ThreadID=53;_ThreadName=p:
jms_thread_pool; w:
32;ClassName=com.service.messaging.ejb.DatabaseServiceBean;MethodName=insertMsgIntoDB;_RequestID=3a474413-8e10-4491-b80e-b1b6d218c21c;|MethodEntry
- DatabaseServiceBean.insertMsgIntoDB started|#]
[#|2007-06-05T16:23:31.213+0100|FINEST|sun-appserver9.1|com.service.messaging|_ThreadID=80;_ThreadName=p:
jms_thread_pool; w:
60;ClassName=com.service.messaging.ejb.DatabaseServiceBean;MethodName=insertMsgHeaderIntoDB;_RequestID=3d963ac3-15bc-4f33-bfc8-faa8d93dddab;|Method
Entry - DatabaseServiceBean.insertMsgHeaderIntoDB|#]
[#|2007-06-05T16:23:31.183+0100|FINEST|sun-appserver9.1|com.service.messaging|_ThreadID=88;_ThreadName=p:
jms_thread_pool; w:
68;ClassName=com.service.messaging.ejb.DatabaseServiceBean;MethodName=insertMsgHeaderIntoDB;_RequestID=835f0093-92b1-4249-9a1b-bc0312940b5a;|Method
Entry - DatabaseServiceBean.insertMsgHeaderIntoDB|#]
[#|2007-06-05T16:23:31.217+0100|SEVERE|sun-appserver9.1|com.sun.genericjmsra|_ThreadID=72;_ThreadName=p:
jms_thread_pool; w:
50;_RequestID=327d65f3-bd2d-4ab5-82c9-a48146cfc3a9;|FAILED : sending
message to DMD|#]
[#|2007-06-05T16:23:31.218+0100|WARNING|sun-appserver9.1|javax.enterprise.system.stream.err|_ThreadID=73;_ThreadName=p:
jms_thread_pool; w: 49;_RequestID=90489e45-0107-4bbc-a31f-e59ec1556a06;|
java.lang.RuntimeException: [ACKNOWLEDGE_REPLY(25)] [C4036]: A broker
error occurred. :[412] Unexpected Broker Exception: [Transaction
[consumer:7372131990475376384, type=NONE]: is not started, ignoring]
user=admin, broker=tannoch-bge0:37676(33872)
at
com.sun.messaging.jmq.jmsclient.ServerSessionRunner.run(ServerSessionRunner.java:131)
at
com.sun.messaging.jmq.jmsclient.SessionImpl.run(SessionImpl.java:2987)
at com.sun.genericra.inbound.WorkImpl.run(WorkImpl.java:52)
at
com.sun.enterprise.connectors.work.OneWork.doWork(OneWork.java:63)
at
com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:509)
|#]
[#|2007-06-05T16:23:31.220+0100|SEVERE|sun-appserver9.1|com.sun.genericjmsra|_ThreadID=73;_ThreadName=p:
jms_thread_pool; w:
49;_RequestID=90489e45-0107-4bbc-a31f-e59ec1556a06;|FAILED : sending
message to DMD|#]
[#|2007-06-05T16:23:31.221+0100|SEVERE|sun-appserver9.1|com.sun.genericjmsra|_ThreadID=26;_ThreadName=p:
jms_thread_pool; w:
2;_RequestID=c1a6a6d5-fedd-4b34-9ba2-a98d739b4b2c;|FAILED : sending
message to DMD|#]
[#|2007-06-05T16:23:31.221+0100|WARNING|sun-appserver9.1|javax.enterprise.system.stream.err|_ThreadID=58;_ThreadName=p:
jms_thread_pool; w: 37;_RequestID=b60c5db2-6444-41c1-8cc6-ece78c2d06e8;|
java.lang.RuntimeException: [ACKNOWLEDGE_REPLY(25)] [C4036]: A broker
error occurred. :[412] Unexpected Broker Exception: [Transaction
[consumer:7372131990475376384, type=NONE]: is not started, ignoring]
user=admin, broker=tannoch-bge0:37676(33872)
at
com.sun.messaging.jmq.jmsclient.ServerSessionRunner.run(ServerSessionRunner.java:131)
at
com.sun.messaging.jmq.jmsclient.SessionImpl.run(SessionImpl.java:2987)
at com.sun.genericra.inbound.WorkImpl.run(WorkImpl.java:52)
at
com.sun.enterprise.connectors.work.OneWork.doWork(OneWork.java:63)
at
com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:509)
|#]
[#|2007-06-05T16:23:31.222+0100|SEVERE|sun-appserver9.1|com.sun.genericjmsra|_ThreadID=58;_ThreadName=p:
jms_thread_pool; w:
37;_RequestID=b60c5db2-6444-41c1-8cc6-ece78c2d06e8;|FAILED : sending
message to DMD|#]
[#|2007-06-05T16:23:31.183+0100|FINEST|sun-appserver9.1|com.service.messaging|_ThreadID=61;_ThreadName=p:
jms_thread_pool; w:
41;ClassName=com.service.messaging.ejb.DatabaseServiceBean;MethodName=insertMsgHeaderIntoDB;_RequestID=a49ed87a-a009-46e5-acc4-9c80b81d2e00;|Method
Entry - DatabaseServiceBean.insertMsgHeaderIntoDB|#]
[#|2007-06-05T16:23:31.182+0100|FINEST|sun-appserver9.1|com.service.messaging|_ThreadID=59;_ThreadName=p:
jms_thread_pool; w:
39;ClassName=com.service.messaging.ejb.DatabaseServiceBean;MethodName=insertMsgHeaderIntoDB;_RequestID=ece5dbea-ba00-4840-803f-a47bd13e72cc;|Method
Entry - DatabaseServiceBean.insertMsgHeaderIntoDB|#]
[#|2007-06-05T16:23:31.233+0100|FINEST|sun-appserver9.1|com.service.messaging|_ThreadID=65;_ThreadName=p:
jms_thread_pool; w:
57;ClassName=com.service.messaging.ejb.DatabaseServiceBean;MethodName=insertMsgHeaderIntoDB;_RequestID=80ccb04e-194c-4a7c-91dc-c902944cba7e;|Method
Entry - DatabaseServiceBean.insertMsgHeaderIntoDB|#]
[#|2007-06-05T16:23:31.234+0100|SEVERE|sun-appserver9.1|com.sun.genericjmsra|_ThreadID=66;_ThreadName=p:
jms_thread_pool; w:
56;_RequestID=2894a777-da76-4b8b-98c6-05e0c6c2e9e0;|FAILED : sending
message to DMD|#]
[#|2007-06-05T16:23:31.182+0100|FINEST|sun-appserver9.1|com.service.messaging|_ThreadID=86;_ThreadName=p:
jms_thread_pool; w:
66;ClassName=com.service.messaging.ejb.DatabaseServiceBean;MethodName=insertMsgHeaderIntoDB;_RequestID=15515438-fbe4-4c58-a8b0-d4ebfdc4bad9;|Method
Entry - DatabaseServiceBean.insertMsgHeaderIntoDB|#]
*Broker log file.*
Connections: 14 JVM Heap: 46727168 bytes (35645008 free) Threads: 28
(14-1010)
In: 896 msgs (1285597 bytes) 10872 pkts (3394711 bytes)
Out: 896 msgs (1320444 bytes) 11589 pkts (2695593 bytes)
Rate In: 12 msgs/sec (18512 bytes/sec) 149 pkts/sec (47881 bytes/sec)
Rate Out: 12 msgs/sec (18561 bytes/sec) 161 pkts/sec (37329 bytes/sec)
[05/Jun/2007:16:23:29 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:29 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:30 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] Internal Exception processing trans
acknowledge
[7372131990646522881,11053-127.0.0.1(fd:15:5d:b9:89:f0)-34279-1181057008886,[consumer:7372131990475376384,
type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker
Exception: [Transaction [consumer:7372131990475376384, type=NONE]: is
not started, ignoring]
[05/Jun/2007:16:23:31 BST] Internal Exception processing trans
acknowledge
[7372131990646523392,11046-127.0.0.1(b5:ab:ad:a8:d5:f5)-34297-1181057008873,[consumer:7372131990475376384,
type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker
Exception: [Transaction [consumer:7372131990475376384, type=NONE]: is
not started, ignoring]
[05/Jun/2007:16:23:31 BST] Internal Exception processing trans
acknowledge
[7372131990646541825,11039-127.0.0.1(d0:d:a9:44:8b:f9)-34295-1181057008859,[consumer:7372131990475376384,
type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker
Exception: [Transaction [consumer:7372131990475376384, type=NONE]: is
not started, ignoring]
[05/Jun/2007:16:23:31 BST] Internal Exception processing trans
acknowledge
[7372131990646541827,11032-127.0.0.1(b2:d0:7b:99:7f:7e)-34293-1181057008846,[consumer:7372131990475376384,
type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker
Exception: [Transaction [consumer:7372131990475376384, type=NONE]: is
not started, ignoring]
[05/Jun/2007:16:23:31 BST] Internal Exception processing trans
acknowledge
[7372131990646542081,11025-127.0.0.1(ad:9e:f0:b3:35:5b)-34291-1181057008832,[consumer:7372131990475376384,
type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker
Exception: [Transaction [consumer:7372131990475376384, type=NONE]: is
not started, ignoring]
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:31 BST] WARNING ROLLBACK_TRANSACTION(48): Ignoring
unknown XID=NULL_XID broker will notify the client
[05/Jun/2007:16:23:58 BST]
Connections: 14 JVM Heap: 46530560 bytes (35170440 free) Threads: 28
(14-1010)
In: 1021 msgs (1462315 bytes) 12425 pkts (3876231 bytes)
Out: 1021 msgs (1497868 bytes) 13254 pkts (3066508 bytes)
Rate In: 4 msgs/sec (5888 bytes/sec) 51 pkts/sec (16045 bytes/sec)
Rate Out: 4 msgs/sec (5912 bytes/sec) 55 pkts/sec (12359 bytes/sec)