Unexpected Broker Exception: [received acknowledgement with Unknown TxID]
After running OpenMessageQueue 4.0 for about nearly 2 months now without too much of a problem, I am happy.
However, today, I got a few of these exceptions on the broker.
In Summary:
1) Unknown TransactionID -1
2) Unknown Transaction xxxxxxxx
3) Transaction yyyyyy is already resolved
Here are the stack traces from the broker ( the client stack traces will follow next ):
[31/Aug/2006:14:01:21 EST] Internal Exception processing trans acknowledge [-1,11609754-10.0.21.17(9e:3c:3f:68:a2:d9)-44999-1156996820654,[consu
mer:1344620632658584576, type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction ID -1: ign
oring message]
[31/Aug/2006:14:01:21 EST] ERROR [B3100]: Unexpected Broker Internal Error : [-Internal Error: Invalid
Acknowledge Packet processing
notifying client
Packet: ACKNOWLEDGE(24):11616462-10.0.21.17(e9:c2:ea:ad:cb:c5)-45005-1156996881490
Magic/Version: 469754818/301 Size: 128Type: ACKNOWLEDGE(24)
Expiration: 0 Timestamp: 1156996881490
Source IP: 10.0.21.17(e9:c2:ea:ad:cb:c5) Port: 45005Sequence: 11616462
Property Offset: 88 Property Size: 0
Encryption: 0Priority: 5
Flags: A consumerID: 17764
TransactionID: -1
MessageID: 11616462-10.0.21.17(e9:c2:ea:ad:cb:c5)-45005-1156996881490
Properties:null
Message Body: 40 bytes [1344620632658584576:11609754-10.0.21.17(9e:3c:3f:68:a2:d9)-44999-1156996820654]
Internal Buffers (useDirect=false):
Fixed Header Buffer:java.nio.HeapByteBuffer[pos=0 lim=72 cap=72]
--] :
com.sun.messaging.jmq.jmsserver.util.BrokerException: Internal Error: Unable to complete processing acknowledgements in a tranaction: com.sun.m
essaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction ID -1: ignoring mes
sage]
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handleTransaction(AckHandler.java:404)
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handle(AckHandler.java:225)
at com.sun.messaging.jmq.jmsserver.data.PacketRouter.handleMessage(PacketRouter.java:164)
at com.sun.messaging.jmq.jmsserver.service.imq.IMQConnection.readData(IMQConnection.java:1886)
at com.sun.messaging.jmq.jmsserver.service.imq.IMQConnection.process(IMQConnection.java:840)
at com.sun.messaging.jmq.jmsserver.service.imq.OperationRunnable.process(OperationRunnable.java:159)
at com.sun.messaging.jmq.jmsserver.util.pool.BasicRunnable.run(BasicRunnable.java:477)
at java.lang.Thread.run(Thread.java:595)
Caused by: com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction
ID -1: ignoring message]
at com.sun.messaging.jmq.jmsserver.data.TransactionList.addAcknowledgement(TransactionList.java:549)
at com.sun.messaging.jmq.jmsserver.data.TransactionList.addAcknowledgement(TransactionList.java:538)
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handleTransaction(AckHandler.java:397)
... 7 more
[31/Aug/2006:14:01:54 EST] Internal Exception processing trans acknowledge [1344620679964220928,11611493-10.0.21.17(f9:b6:69:b7:6d:72)-45001-115
6996835639,[consumer:1344620632658568960, type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction ID 1344620
679964220928: ignoring message]
[31/Aug/2006:14:01:54 EST] Internal Exception processing trans acknowledge [1344620679964306688,11611319-10.0.21.17(f9:b6:69:b7:6d:72)-45001-115
6996834641,[consumer:1344620632658492160, type=NONE]]:
com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction ID 1344620
679964306688: ignoring message]
[31/Aug/2006:14:01:54 EST] ERROR [B3100]: Unexpected Broker Internal Error : [-Internal Error: Invalid
Acknowledge Packet processing
notifying client
Packet: ACKNOWLEDGE(24):11620037-10.0.21.17(c5:cd:9b:d7:de:a9)-45003-1156996913862
Magic/Version: 469754818/301 Size: 128Type: ACKNOWLEDGE(24)
Expiration: 0 Timestamp: 1156996913862
Source IP: 10.0.21.17(c5:cd:9b:d7:de:a9) Port: 45003Sequence: 11620037
Property Offset: 88 Property Size: 0
Encryption: 0Priority: 5
Flags: A consumerID: 17763
TransactionID: 1344620679964220928
MessageID: 11620037-10.0.21.17(c5:cd:9b:d7:de:a9)-45003-1156996913862
Properties:null
Message Body: 40 bytes [1344620632658568960:11611493-10.0.21.17(f9:b6:69:b7:6d:72)-45001-1156996835639]
Internal Buffers (useDirect=false):
Fixed Header Buffer:java.nio.HeapByteBuffer[pos=0 lim=72 cap=72]
--] :
com.sun.messaging.jmq.jmsserver.util.BrokerException: Internal Error: Unable to complete processing acknowledgements in a tranaction: com.sun.m
essaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction ID 1344620679964220
928: ignoring message]
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handleTransaction(AckHandler.java:404)
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handle(AckHandler.java:225)
at com.sun.messaging.jmq.jmsserver.data.PacketRouter.handleMessage(PacketRouter.java:164)
at com.sun.messaging.jmq.jmsserver.service.imq.IMQConnection.readData(IMQConnection.java:1886)
at com.sun.messaging.jmq.jmsserver.service.imq.IMQConnection.process(IMQConnection.java:840)
at com.sun.messaging.jmq.jmsserver.service.imq.OperationRunnable.process(OperationRunnable.java:159)
at com.sun.messaging.jmq.jmsserver.util.pool.BasicRunnable.run(BasicRunnable.java:477)
at java.lang.Thread.run(Thread.java:595)
Caused by: com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction
ID 1344620679964220928: ignoring message]
at com.sun.messaging.jmq.jmsserver.data.TransactionList.addAcknowledgement(TransactionList.java:549)
at com.sun.messaging.jmq.jmsserver.data.TransactionList.addAcknowledgement(TransactionList.java:538)
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handleTransaction(AckHandler.java:397)
... 7 more
[31/Aug/2006:14:01:54 EST] ERROR [B3100]: Unexpected Broker Internal Error : [-Internal Error: Invalid
Acknowledge Packet processing
notifying client
Packet: ACKNOWLEDGE(24):11620036-10.0.21.17(d1:8d:1f:9e:81:78)-44993-1156996913843
Magic/Version: 469754818/301 Size: 128Type: ACKNOWLEDGE(24)
Expiration: 0 Timestamp: 1156996913843
Source IP: 10.0.21.17(d1:8d:1f:9e:81:78) Port: 44993Sequence: 11620036
Property Offset: 88 Property Size: 0
Encryption: 0Priority: 5
Flags: A consumerID: 17375
TransactionID: 1344620679964306688
MessageID: 11620036-10.0.21.17(d1:8d:1f:9e:81:78)-44993-1156996913843
Properties:null
Message Body: 40 bytes [1344620632658492160:11611319-10.0.21.17(f9:b6:69:b7:6d:72)-45001-1156996834641]
Internal Buffers (useDirect=false):
Fixed Header Buffer:java.nio.HeapByteBuffer[pos=0 lim=72 cap=72]
--] :
com.sun.messaging.jmq.jmsserver.util.BrokerException: Internal Error: Unable to complete processing acknowledgements in a tranaction: com.sun.m
essaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction ID 1344620679964306
688: ignoring message]
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handleTransaction(AckHandler.java:404)
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handle(AckHandler.java:225)
at com.sun.messaging.jmq.jmsserver.data.PacketRouter.handleMessage(PacketRouter.java:164)
at com.sun.messaging.jmq.jmsserver.service.imq.IMQConnection.readData(IMQConnection.java:1886)
at com.sun.messaging.jmq.jmsserver.service.imq.IMQConnection.process(IMQConnection.java:840)
at com.sun.messaging.jmq.jmsserver.service.imq.OperationRunnable.process(OperationRunnable.java:159)
at com.sun.messaging.jmq.jmsserver.util.pool.BasicRunnable.run(BasicRunnable.java:477)
at java.lang.Thread.run(Thread.java:595)
Caused by: com.sun.messaging.jmq.jmsserver.util.BrokerException: Unexpected Broker Exception: [received acknowledgement with Unknown Transaction
ID 1344620679964306688: ignoring message]
at com.sun.messaging.jmq.jmsserver.data.TransactionList.addAcknowledgement(TransactionList.java:549)
at com.sun.messaging.jmq.jmsserver.data.TransactionList.addAcknowledgement(TransactionList.java:538)
at com.sun.messaging.jmq.jmsserver.data.handlers.AckHandler.handleTransaction(AckHandler.java:397)
... 7 more
[31/Aug/2006:14:02:01 EST] ERROR Transaction ID 1344620679964306688 has already been resolved. Ignoring request: ROLLBACK_TRANSACTION(48). Last
state ofthis transaction: guest@null:COMMITED(6) broker will not notify the client
Here are the stack traces from the client:
2006-08-31 14:03:31,045 [imqConsumerReader-1-1344620610360452864-1] ERROR - Unable to commit JMS transaction.
com.sun.messaging.jms.JMSException: [C4000]: Packet acknowledge failed. user=guest, broker=10.0.21.94:7676(37111)
at com.sun.messaging.jmq.jmsclient.ProtocolHandler.writePacketWithAck(ProtocolHandler.java:694)
at com.sun.messaging.jmq.jmsclient.ProtocolHandler.writePacketWithAck(ProtocolHandler.java:555)
at com.sun.messaging.jmq.jmsclient.ProtocolHandler.writePacketWithReply(ProtocolHandler.java:410)
at com.sun.messaging.jmq.jmsclient.ProtocolHandler.startTransaction(ProtocolHandler.java:2599)
at com.sun.messaging.jmq.jmsclient.ProtocolHandler.startTransaction(ProtocolHandler.java:2538)
at com.sun.messaging.jmq.jmsclient.Transaction.startTransaction(Transaction.java:521)
at com.sun.messaging.jmq.jmsclient.Transaction.startNewLocalTransaction(Transaction.java:503)
at com.sun.messaging.jmq.jmsclient.Transaction.commit(Transaction.java:138)
at com.sun.messaging.jmq.jmsclient.SessionImpl.commit(SessionImpl.java:1879)
at softgame.gateway.connectors.http.AbstractMessageNotificationListener.onMessage(AbstractMessageNotificationListener.java:155)
at com.sun.messaging.jmq.jmsclient.MessageConsumerImpl.deliverAndAcknowledge(MessageConsumerImpl.java:300)
at com.sun.messaging.jmq.jmsclient.MessageConsumerImpl.onMessage(MessageConsumerImpl.java:263)
at com.sun.messaging.jmq.jmsclient.SessionReader.deliver(SessionReader.java:94)
at com.sun.messaging.jmq.jmsclient.ConsumerReader.run(ConsumerReader.java:174)
at java.lang.Thread.run(Thread.java:595)
2006-08-31 14:03:38,019 [imqConsumerReader-3-1344620610360476928-3] ERROR - Unable to process notification
com.sun.messaging.jms.JMSException: [SEND_REPLY(9)] [C4036]: A server error occurred. :[500] transaction failed: Unexpected Broker Exception: [r
eceived message with Unknown Transaction ID -1: ignoring message] user=guest, broker=10.0.21.94:7676(37111)
at com.sun.messaging.jmq.jmsclient.ProtocolHandler.throwServerErrorException(ProtocolHandler.java:3764)
at com.sun.messaging.jmq.jmsclient.ProtocolHandler.writeJMSMessage(ProtocolHandler.java:1900)
at com.sun.messaging.jmq.jmsclient.WriteChannel.sendWithFlowControl(WriteChannel.java:141)
at com.sun.messaging.jmq.jmsclient.WriteChannel.writeJMSMessage(WriteChannel.java:94)
at com.sun.messaging.jmq.jmsclient.Transaction.send(Transaction.java:431)
at com.sun.messaging.jmq.jmsclient.SessionImpl.writeJMSMessage(SessionImpl.java:747)
at com.sun.messaging.jmq.jmsclient.MessageProducerImpl.writeJMSMessage(MessageProducerImpl.java:191)
at com.sun.messaging.jmq.jmsclient.MessageProducerImpl.writeJMSMessage(MessageProducerImpl.java:180)
at com.sun.messaging.jmq.jmsclient.MessageProducerImpl.send(MessageProducerImpl.java:561)
at com.sun.messaging.jmq.jmsclient.QueueSenderImpl.send(QueueSenderImpl.java:90)
at softgame.gateway.connectors.http.MessageNotificationListener.processNotification(MessageNotificationListener.java:194)
at softgame.gateway.connectors.http.AbstractMessageNotificationListener.onMessage(AbstractMessageNotificationListener.java:111)
at com.sun.messaging.jmq.jmsclient.MessageConsumerImpl.deliverAndAcknowledge(MessageConsumerImpl.java:300)
at com.sun.messaging.jmq.jmsclient.MessageConsumerImpl.onMessage(MessageConsumerImpl.java:263)
at com.sun.messaging.jmq.jmsclient.SessionReader.deliver(SessionReader.java:94)
at com.sun.messaging.jmq.jmsclient.ConsumerReader.run(ConsumerReader.java:174)
at java.lang.Thread.run(Thread.java:595)
