Hello,
I've got a previous error on one message that later yields an
"acknowledgment missed". There seems to be a deadlock occurring:
2010-05-03 15:03:16 [-Processor17] <INFO > <cecid.ebms.spa> <Incoming
ebxml message received:
20100503-1...@10.47.18.94>
2010-05-03 15:03:16 [-Processor17] <INFO > <cecid.ebms.spa> <Inbound
agreement checking for interop>
2010-05-03 15:03:16 [-Processor17] <INFO > <cecid.ebms.spa> <It is an
order message:
20100503-1...@10.47.18.94>
2010-05-03 15:03:16 [-Processor17] <INFO > <cecid.ebms.spa> <Store the
incoming message:
20100503-1...@10.47.18.94>
2010-05-03 15:03:16 [-Processor17] <INFO > <cecid.ebms.spa> <Sending
async reply message:
20100503-1...@153.110.160.149>
2010-05-03 15:03:17 [Thread-2028 ] <INFO > <cecid.ebms.spa> <Ebxml
Message (
20100503-1...@10.47.18.94) is stored in inbox with
order number: 7773>
2010-05-03 15:03:17 [-Processor17] <ERROR> <cecid.ebms.spa> <Cannot
generate acknowledgement for message:
20100503-1...@10.47.18.94>
2010-05-03 15:03:17 [-Processor17] <DEBUG> <cecid.ebms.spa> <Error in
processing incoming message>
hk.hku.cecid.ebms.spa.handler.MessageServiceHandlerException: Cannot
generate acknowledgement for message:
20100503-1...@10.47.18.94
by hk.hku.cecid.ebms.spa.handler.MessageServiceHandlerException:
Error in processing outgoing message
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
hk.hku.cecid.ebms.spa.dao.MessageServerDataSourceDAO::Process@1e46f7b
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
hk.hku.cecid.ebms.spa.dao.OutboxDataSourceDAO::Process@f63c1c
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
update: INSERT INTO outbox (message_id, retried) VALUES (?,?)
by hk.hku.cecid.piazza.commons.dao.DAOException: Error occurred when
executing update at row 0 of the specified parameter array
by com.mysql.jdbc.exceptions.MySQLTransactionRollbackException:
Deadlock found when trying to get lock; try restarting transaction
at
hk.hku.cecid.ebms.spa.handler.InboundMessageProcessor.processOrderMessage(InboundMessageProcessor.java:
930)
at
hk.hku.cecid.ebms.spa.handler.InboundMessageProcessor.processIncomingMessage(InboundMessageProcessor.java:
271)
at
hk.hku.cecid.ebms.spa.handler.MessageServiceHandler.processInboundMessage(MessageServiceHandler.java:
276)
at
hk.hku.cecid.ebms.spa.listener.EbmsInboundListener.processRequest(EbmsInboundListener.java:
59)
at
hk.hku.cecid.ebms.spa.listener.EbmsAdaptor.processRequest(EbmsAdaptor.java:
42)
at
hk.hku.cecid.piazza.commons.soap.SOAPHttpAdaptor.processRequest(SOAPHttpAdaptor.java:
132)
at
hk.hku.cecid.piazza.commons.servlet.http.HttpDispatcher.processRequest(HttpDispatcher.java:
126)
at
hk.hku.cecid.piazza.commons.servlet.http.HttpDispatcher.doPost(HttpDispatcher.java:
277)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:647)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:
269)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
188)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:
213)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:
172)
at
org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:
433)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:
127)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:
117)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
108)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
174)
at
org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:
875)
at org.apache.coyote.http11.Http11BaseProtocol
$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:
665)
at
org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:
528)
at
org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:
81)
at org.apache.tomcat.util.threads.ThreadPool
$ControlRunnable.run(ThreadPool.java:689)
at java.lang.Thread.run(Unknown Source)
Caused by:
hk.hku.cecid.ebms.spa.handler.MessageServiceHandlerException: Error in
processing outgoing message
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
hk.hku.cecid.ebms.spa.dao.MessageServerDataSourceDAO::Process@1e46f7b
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
hk.hku.cecid.ebms.spa.dao.OutboxDataSourceDAO::Process@f63c1c
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
update: INSERT INTO outbox (message_id, retried) VALUES (?,?)
by hk.hku.cecid.piazza.commons.dao.DAOException: Error occurred when
executing update at row 0 of the specified parameter array
by com.mysql.jdbc.exceptions.MySQLTransactionRollbackException:
Deadlock found when trying to get lock; try restarting transaction
at
hk.hku.cecid.ebms.spa.handler.OutboundMessageProcessor.processOutgoingMessage(OutboundMessageProcessor.java:
129)
at
hk.hku.cecid.ebms.spa.handler.MessageServiceHandler.processOutboundMessage(MessageServiceHandler.java:
283)
at
hk.hku.cecid.ebms.spa.handler.InboundMessageProcessor.sendAsyncMessage(InboundMessageProcessor.java:
1644)
at
hk.hku.cecid.ebms.spa.handler.InboundMessageProcessor.processOrderMessage(InboundMessageProcessor.java:
924)
... 24 more
Caused by: hk.hku.cecid.piazza.commons.dao.DAOException: Error in
executing
hk.hku.cecid.ebms.spa.dao.MessageServerDataSourceDAO::Process@1e46f7b
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
hk.hku.cecid.ebms.spa.dao.OutboxDataSourceDAO::Process@f63c1c
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
update: INSERT INTO outbox (message_id, retried) VALUES (?,?)
by hk.hku.cecid.piazza.commons.dao.DAOException: Error occurred when
executing update at row 0 of the specified parameter array
by com.mysql.jdbc.exceptions.MySQLTransactionRollbackException:
Deadlock found when trying to get lock; try restarting transaction
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceProcess.start(DataSourceProcess.java:
97)
at
hk.hku.cecid.ebms.spa.dao.MessageServerDataSourceDAO.storeOutboxMessage(MessageServerDataSourceDAO.java:
93)
at
hk.hku.cecid.ebms.spa.handler.OutboundMessageProcessor.storeOutgoingMessage(OutboundMessageProcessor.java:
287)
at
hk.hku.cecid.ebms.spa.handler.OutboundMessageProcessor.processOutgoingMessage(OutboundMessageProcessor.java:
121)
... 27 more
Caused by: hk.hku.cecid.piazza.commons.dao.DAOException: Error in
executing
hk.hku.cecid.ebms.spa.dao.OutboxDataSourceDAO::Process@f63c1c
by hk.hku.cecid.piazza.commons.dao.DAOException: Error in executing
update: INSERT INTO outbox (message_id, retried) VALUES (?,?)
by hk.hku.cecid.piazza.commons.dao.DAOException: Error occurred when
executing update at row 0 of the specified parameter array
by com.mysql.jdbc.exceptions.MySQLTransactionRollbackException:
Deadlock found when trying to get lock; try restarting transaction
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceProcess.start(DataSourceProcess.java:
97)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceDAO.executeUpdate(DataSourceDAO.java:
588)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceDAO.executeUpdate(DataSourceDAO.java:
548)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceDAO.executeUpdate(DataSourceDAO.java:
528)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceDAO.create(DataSourceDAO.java:
626)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceDAO.create(DataSourceDAO.java:
610)
at
hk.hku.cecid.ebms.spa.dao.OutboxDataSourceDAO.addOutbox(OutboxDataSourceDAO.java:
39)
at hk.hku.cecid.ebms.spa.dao.MessageServerDataSourceDAO
$2.doTransaction(MessageServerDataSourceDAO.java:89)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceProcess.start(DataSourceProcess.java:
89)
... 30 more
Caused by: hk.hku.cecid.piazza.commons.dao.DAOException: Error in
executing update: INSERT INTO outbox (message_id, retried) VALUES
(?,?)
by hk.hku.cecid.piazza.commons.dao.DAOException: Error occurred when
executing update at row 0 of the specified parameter array
by com.mysql.jdbc.exceptions.MySQLTransactionRollbackException:
Deadlock found when trying to get lock; try restarting transaction
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceUpdate.doTransaction(DataSourceUpdate.java:
110)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceProcess.start(DataSourceProcess.java:
89)
... 38 more
Caused by: hk.hku.cecid.piazza.commons.dao.DAOException: Error
occurred when executing update at row 0 of the specified parameter
array
by com.mysql.jdbc.exceptions.MySQLTransactionRollbackException:
Deadlock found when trying to get lock; try restarting transaction
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceUpdate.doTransaction(DataSourceUpdate.java:
96)
... 39 more
Caused by:
com.mysql.jdbc.exceptions.MySQLTransactionRollbackException: Deadlock
found when trying to get lock; try restarting transaction
at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:941)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2985)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1631)
at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1723)
at com.mysql.jdbc.Connection.execSQL(Connection.java:3283)
at
com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:
1332)
at
com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:
1604)
at
com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:
1519)
at
com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:
1504)
at
hk.hku.cecid.piazza.commons.dao.ds.DataSourceUpdate.doTransaction(DataSourceUpdate.java:
92)
... 39 more
Regards,
Dag
On Apr 29, 3:55 am, Franz Wong <
franzw...@gmail.com> wrote:
> From the ebms.log, can you notice any other errors?
>
> There is a record in the database for the acknowledgement generated.
> Can you find that?
>
> If you now send the message again, can the acknowledgement be
> generated and sent successfully?
>
> I need to gather more information as this is not a common case.
>
> Regards,
> Franz