[JBoss Messaging Users] - Cannot insert duplicate key JBM_MSG_REF - jboss-user

Friday, 20 November 2009

I have a process as part of my application which generates large number of messages.
During testing we encountered the exception below. From a clean start of JBoss we started
the process which generated several thousand messages. Two minutes into the process we got
this error. Previously we only encountered this problem when using 8 clustered JBoss
instances clustered and then we addressed it by using non-consecutive Peer Ids. However in
this instance there was only a single JBoss instance involved.

A quick look online only brought us to a similar, yet different issue
https://jira.jboss.org/jira/browse/JBMESSAGING-1062 which has already been fixed. Does
anybody know if this is a know problem and fixed in the latest GA release.

We are using JBoss 4.2.3 GA with JBoss Messaging 1.4.2.GA-SP1
Exception

  | 10:26:01,467 INFO  [Http11Protocol] Starting Coyote HTTP/1.1 on http-0.0.0.0-8080
  | 10:26:01,514 INFO  [AjpProtocol] Starting Coyote AJP/1.3 on ajp-0.0.0.0-8009
  | 10:26:01,514 INFO  [Server] JBoss (MX MicroKernel) [4.2.3.GA (build:
SVNTag=JBoss_4_2_3_GA date=200807181439)] Started in 3m:31s:994ms
  | 10:40:52,036 WARN  [SpoolProcessorBean] spool cannot be deleted when status=CLOSED
  | 10:40:52,036 WARN  [SpoolProcessorBean] spool cannot be deleted when status=CLOSED
  | 10:40:52,036 WARN  [SpoolProcessorBean] spool cannot be deleted when status=CLOSED
  | 10:40:52,036 WARN  [SpoolProcessorBean] spool cannot be deleted when status=CLOSED
  | 10:40:52,051 WARN  [SpoolProcessorBean] spool cannot be deleted when status=CLOSED
  | 10:40:52,051 WARN  [SpoolProcessorBean] spool cannot be deleted when status=CLOSED
  | 10:40:52,051 WARN  [SpoolProcessorBean] spool cannot be deleted when status=CLOSED
  | 10:42:29,138 WARN  [JDBCSupport] SQLException caught, SQLState 23000 code:2627-
assuming deadlock detected, try:1
  | java.sql.SQLException: Violation of PRIMARY KEY constraint
'PK__JBM_MSG_REF__2057CCD0'. Cannot insert duplicate key in object
'dbo.JBM_MSG_REF'.
  |         at
net.sourceforge.jtds.jdbc.SQLDiagnostic.addDiagnostic(SQLDiagnostic.java:365)
  |         at net.sourceforge.jtds.jdbc.TdsCore.tdsErrorToken(TdsCore.java:2781)
  |         at net.sourceforge.jtds.jdbc.TdsCore.nextToken(TdsCore.java:2224)
  |         at net.sourceforge.jtds.jdbc.TdsCore.getMoreResults(TdsCore.java:628)
  |         at
net.sourceforge.jtds.jdbc.JtdsStatement.processResults(JtdsStatement.java:525)
  |         at net.sourceforge.jtds.jdbc.JtdsStatement.executeSQL(JtdsStatement.java:487)
  |         at
net.sourceforge.jtds.jdbc.JtdsPreparedStatement.executeUpdate(JtdsPreparedStatement.java:421)
  |         at
org.jboss.resource.adapter.jdbc.CachedPreparedStatement.executeUpdate(CachedPreparedStatement.java:95)
  |         at
org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:365)
  |         at
org.jboss.messaging.core.impl.JDBCPersistenceManager$1HandleBeforePrepareRunner.doTransaction(JDBCPersistenceManager.java:1972)
  |         at
org.jboss.messaging.core.impl.JDBCSupport$JDBCTxRunner2.execute(JDBCSupport.java:474)
  |         at
org.jboss.messaging.core.impl.JDBCSupport$JDBCTxRunner2.executeWithRetry(JDBCSupport.java:512)
  |         at
org.jboss.messaging.core.impl.JDBCPersistenceManager.handleBeforePrepare(JDBCPersistenceManager.java:2063)
  |         at
org.jboss.messaging.core.impl.JDBCPersistenceManager$TransactionCallback.beforePrepare(JDBCPersistenceManager.java:2984)
  |         at org.jboss.messaging.core.impl.tx.Transaction.prepare(Transaction.java:265)
  |         at
org.jboss.jms.server.endpoint.ServerConnectionEndpoint.sendTransaction(ServerConnectionEndpoint.java:498)
  |         at
org.jboss.jms.server.endpoint.advised.ConnectionAdvised.org$jboss$jms$server$endpoint$advised$ConnectionAdvised$sendTransaction$aop(ConnectionAdvised.java:101)
  |         at
org.jboss.jms.server.endpoint.advised.ConnectionAdvised$sendTransaction_N3268650789275322226.invokeNext(ConnectionAdvised$sendTransaction_N3268650789275322226.java)
  |         at
org.jboss.jms.server.container.SecurityAspect.handleSendTransaction(SecurityAspect.java:195)
  |         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  |         at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
  |         at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
  |         at java.lang.reflect.Method.invoke(Method.java:597)
  |         at org.jboss.aop.advice.PerInstanceAdvice.invoke(PerInstanceAdvice.java:121)
  |         at
org.jboss.jms.server.endpoint.advised.ConnectionAdvised$sendTransaction_N3268650789275322226.invokeNext(ConnectionAdvised$sendTransaction_N3268650789275322226.java)
  |         at
org.jboss.jms.server.container.ServerLogInterceptor.invoke(ServerLogInterceptor.java:105)
  |         at
org.jboss.jms.server.endpoint.advised.ConnectionAdvised$sendTransaction_N3268650789275322226.invokeNext(ConnectionAdvised$sendTransaction_N3268650789275322226.java)
  |         at
org.jboss.jms.server.endpoint.advised.ConnectionAdvised.sendTransaction(ConnectionAdvised.java)
  |         at
org.jboss.jms.wireformat.ConnectionSendTransactionRequest.serverInvoke(ConnectionSendTransactionRequest.java:82)
  |         at
org.jboss.jms.server.remoting.JMSServerInvocationHandler.invoke(JMSServerInvocationHandler.java:143)
  |         at org.jboss.remoting.ServerInvoker.invoke(ServerInvoker.java:809)
  |         at
org.jboss.remoting.transport.local.LocalClientInvoker.invoke(LocalClientInvoker.java:101)
  |         at org.jboss.remoting.Client.invoke(Client.java:1634)
  |         at org.jboss.remoting.Client.invoke(Client.java:548)
  |         at org.jboss.remoting.Client.invoke(Client.java:536)
  |         at
org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:189)
  |         at
org.jboss.jms.client.delegate.DelegateSupport.doInvoke(DelegateSupport.java:160)
  |         at
org.jboss.jms.client.delegate.ClientConnectionDelegate.org$jboss$jms$client$delegate$ClientConnectionDelegate$sendTransaction$aop(ClientConnectionDelegate.java:221)
  |         at
org.jboss.jms.client.delegate.ClientConnectionDelegate$sendTransaction_N3268650789275322226.invokeNext(ClientConnectionDelegate$sendTransaction_N3268650789275322226.java)
  |         at
org.jboss.jms.client.container.FailoverValveInterceptor.invoke(FailoverValveInterceptor.java:92)
  |         at
org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
  |         at
org.jboss.jms.client.delegate.ClientConnectionDelegate$sendTransaction_N3268650789275322226.invokeNext(ClientConnectionDelegate$sendTransaction_N3268650789275322226.java)
  |         at
org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
  |         at
org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
  |         at
org.jboss.jms.client.delegate.ClientConnectionDelegate$sendTransaction_N3268650789275322226.invokeNext(ClientConnectionDelegate$sendTransaction_N3268650789275322226.java)
  |         at
org.jboss.jms.client.delegate.ClientConnectionDelegate.sendTransaction(ClientConnectionDelegate.java)
  |         at
org.jboss.jms.tx.ResourceManager.sendTransactionXA(ResourceManager.java:667)
  |         at org.jboss.jms.tx.ResourceManager.prepare(ResourceManager.java:337)
  |         at org.jboss.jms.tx.MessagingXAResource.prepare(MessagingXAResource.java:241)
  |         at
org.jboss.resource.adapter.jms.JmsXAResource.prepare(JmsXAResource.java:85)
  |         at
org.jboss.resource.connectionmanager.xa.JcaXAResourceWrapper.prepare(JcaXAResourceWrapper.java:93)
  |         at
com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelPrepare(XAResourceRecord.java:264)
  |         at
com.arjuna.ats.arjuna.coordinator.BasicAction.doPrepare(BasicAction.java:2871)
  |         at
com.arjuna.ats.arjuna.coordinator.BasicAction.doPrepare(BasicAction.java:2828)
  |         at
com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(BasicAction.java:2382)
  |         at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1783)
  |         at
com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:88)
  |         at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:177)
  |         at
com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1389)
  |         at
com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:135)
  |         at
com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:87)
  |         at org.jboss.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:175)
  |         at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:87)
  |         at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:191)
  |         at
org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
  |         at
org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:95)
  |         at
org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
  |         at
org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:62)
  |         at
org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
  |         at
org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77)
  |         at
org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:110)
  |         at
org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
  |         at
org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)
  |         at
org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
  |         at
org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)
  |         at
org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
  |         at
org.jboss.ejb3.stateless.StatelessContainer.dynamicInvoke(StatelessContainer.java:304)
  |         at
org.jboss.ejb3.remoting.IsLocalInterceptor.invokeLocal(IsLocalInterceptor.java:81)
  |         at
org.jboss.ejb3.remoting.IsLocalInterceptor.invoke(IsLocalInterceptor.java:72)
  |         at
org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
  |         at
org.jboss.ejb3.stateless.StatelessRemoteProxy.invoke(StatelessRemoteProxy.java:107)
  |         at $Proxy131.purgeJobsByDate(Unknown Source)
  |         at
com.thunderhead.web.PurgeFormController.onSubmit(PurgeFormController.java:63)
  |         at
org.springframework.web.servlet.mvc.SimpleFormController.processFormSubmission(SimpleFormController.java:267)
  |         at
org.springframework.web.servlet.mvc.AbstractFormController.handleRequestInternal(AbstractFormController.java:265)
  |         at
org.springframework.web.servlet.mvc.AbstractController.handleRequest(AbstractController.java:153)
  |         at
org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
  |         at
org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:858)
  |         at
org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:792)
  |         at
org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:476)
  |         at
org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:441)
  |         at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
  |         at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
  |         at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
  |         at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
  |         at
org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:96)
  |         at
org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:75)
  |         at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
  |         at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
  |         at
org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
  |         at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
  |         at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
  |         at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230)
  |         at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
  |         at
org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:182)
  |         at
org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:524)
  |         at
org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:84)
  |         at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
  |         at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
  |         at
org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:157)
  |         at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
  |         at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:262)
  |         at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
  |         at
org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
  |         at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:446)
  |         at java.lang.Thread.run(Thread.java:619)
  | 10:42:29,481 WARN  [JDBCSupport] Trying again after a pause
  | 10:43:08,185 WARN  [JDBCSupport] SQLException caught, SQLState 23000 code:2627-
assuming deadlock detected, try:2
  | java.sql.SQLException: Violation of PRIMARY KEY constraint
'PK__JBM_MSG_REF__2057CCD0'. Cannot insert duplicate key in object
'dbo.JBM_MSG_REF'.
  |         at
net.sourceforge.jtds.jdbc.SQLDiagnostic.addDiagnostic(SQLDiagnostic.java:365)
  |         at net.sourceforge.jtds.jdbc.TdsCore.tdsErrorToken(TdsCore.java:2781)
  |         at net.sourceforge.jtds.jdbc.TdsCore.nextToken(TdsCore.java:2224)
  |         at net.sourceforge.jtds.jdbc.TdsCore.getMoreResults(TdsCore.java:628)
  |         at
net.sourceforge.jtds.jdbc.JtdsStatement.processResults(JtdsStatement.java:525)
  |         at net.sourceforge.jtds.jdbc.JtdsStatement.executeSQL(JtdsStatement.java:487)
  |         at
net.sourceforge.jtds.jdbc.JtdsPreparedStatement.executeUpdate(JtdsPreparedStatement.java:421)
  |         at
org.jboss.resource.adapter.jdbc.CachedPreparedStatement.executeUpdate(CachedPreparedStatement.java:95)
  |         at
org.jboss.resource.adapter.jdbc.WrappedPreparedStatement.executeUpdate(WrappedPreparedStatement.java:365)
  |         at
org.jboss.messaging.core.impl.JDBCPersistenceManager$1HandleBeforePrepareRunner.doTransaction(JDBCPersistenceManager.java:1972)
  |         at
org.jboss.messaging.core.impl.JDBCSupport$JDBCTxRunner2.execute(JDBCSupport.java:474)
  |         at
org.jboss.messaging.core.impl.JDBCSupport$JDBCTxRunner2.executeWithRetry(JDBCSupport.java:512)
  |         at
org.jboss.messaging.core.impl.JDBCPersistenceManager.handleBeforePrepare(JDBCPersistenceManager.java:2063)
  | 

View the original post :
http://www.jboss.org/index.html?module=bb&op=viewtopic&p=4266721#...

Reply to the post :
http://www.jboss.org/index.html?module=bb&op=posting&mode=reply&a...

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

[JBoss Messaging Users] - Cannot insert duplicate key JBM_MSG_REF