8 Replies Latest reply on Jun 2, 2004 6:21 AM by Martin Husted Hartvig

    Cannot invoke MDB when message is sent.

    Tim Carey Newbie

      I am having difficulty invoking an MDB when a message is sent to a queue. I have searched the forum and the Wiki but I cant seem to find a solution. At the end of the client request, the message is still in the queue, but is not consumed by the MDB.

      I have enabled tracing per Faq, any help would be appreciated.

      -----------Initial Startup Trace ------------
      2004-05-01 11:57:06,160 INFO [org.jboss.ejb.EjbModule] Deploying MemberService
      2004-05-01 11:57:07,211 INFO [org.jboss.ejb.EjbModule] Deploying CodeInterface
      2004-05-01 11:57:07,542 TRACE [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Constructing
      2004-05-01 11:57:07,973 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Creating
      2004-05-01 11:57:07,983 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Created
      2004-05-01 11:57:08,343 INFO [org.jboss.ejb.plugins.StatelessSessionInstancePool] Started jboss.j2ee:jndiName=ejb/MemberServiceHomeRemote,plugin=pool,service=EJB
      2004-05-01 11:57:08,343 INFO [org.jboss.ejb.StatelessSessionContainer] Started jboss.j2ee:jndiName=ejb/MemberServiceHomeRemote,service=EJB
      2004-05-01 11:57:08,363 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Starting
      2004-05-01 11:57:08,363 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Initializing
      2004-05-01 11:57:08,363 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Looking up provider adapter: java:/DefaultJMSProvider
      2004-05-01 11:57:08,373 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Provider adapter: org.jboss.jms.jndi.JBossMQProvider@d8fd1a
      2004-05-01 11:57:08,383 TRACE [org.jboss.ejb.plugins.jms.DLQHandler] Constructing
      2004-05-01 11:57:08,383 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Creating
      2004-05-01 11:57:08,383 DEBUG [org.jboss.jms.jndi.JBossMQProvider] no provider url; connecting to local JNDI
      2004-05-01 11:57:08,393 DEBUG [org.jboss.jms.jndi.JBossMQProvider] created context: javax.naming.InitialContext@129dcc0
      2004-05-01 11:57:08,393 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Using factory: org.jboss.mq.SpyXAConnectionFactory@15be8d3
      2004-05-01 11:57:08,463 DEBUG [org.jboss.mq.Connection] Setting the clockDaemon's thread factory
      2004-05-01 11:57:08,473 TRACE [org.jboss.mq.Connection] Connection Initializing
      2004-05-01 11:57:08,473 TRACE [org.jboss.mq.Connection] Getting the serverIL
      2004-05-01 11:57:08,473 TRACE [org.jboss.mq.Connection] serverIL=org.jboss.mq.il.jvm.JVMServerIL@7946a2
      2004-05-01 11:57:08,473 TRACE [org.jboss.mq.Connection] Authenticating
      2004-05-01 11:57:08,473 TRACE [org.jboss.mq.Connection] Authenticating user null
      2004-05-01 11:57:08,473 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : authenticate
      2004-05-01 11:57:08,473 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Autenticating user null/null
      2004-05-01 11:57:08,553 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] initialize
      2004-05-01 11:57:08,553 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] Saw unauthenticatedIdentity=guest
      2004-05-01 11:57:08,553 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] login
      2004-05-01 11:57:08,563 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] Authenticating as unauthenticatedIdentity=guest
      2004-05-01 11:57:08,563 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] User 'guest' authenticated, loginOk=true
      2004-05-01 11:57:08,563 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] commit, loginOk=true
      2004-05-01 11:57:08,603 TRACE [org.jboss.mq.security.SecurityManager] Username: null is authenticated
      2004-05-01 11:57:08,624 TRACE [org.jboss.mq.security.SecurityManager] Adding group : class org.jboss.security.NestableGroup Roles(members:guest)
      2004-05-01 11:57:08,624 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : authenticate
      2004-05-01 11:57:08,624 TRACE [org.jboss.mq.Connection] Starting the clientIL service
      2004-05-01 11:57:08,654 DEBUG [org.jboss.mq.GenericConnectionFactory] Handing out ClientIL: org.jboss.mq.il.jvm.JVMClientILService
      2004-05-01 11:57:08,664 TRACE [org.jboss.mq.Connection] Connection establishment successful
      2004-05-01 11:57:08,664 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Created connection: org.jboss.mq.SpyConnection@14f79cb
      2004-05-01 11:57:08,674 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] SpyDestinationObjectFactory->getObjectInstance()
      2004-05-01 11:57:08,674 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Using Queue: QUEUE.DLQ
      2004-05-01 11:57:08,674 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Created
      2004-05-01 11:57:08,674 DEBUG [org.jboss.jms.jndi.JBossMQProvider] no provider url; connecting to local JNDI
      2004-05-01 11:57:08,674 DEBUG [org.jboss.jms.jndi.JBossMQProvider] created context: javax.naming.InitialContext@178743b
      2004-05-01 11:57:08,674 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] context: javax.naming.InitialContext@178743b
      2004-05-01 11:57:08,674 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] jndiSuffix: ServerCodeInterfaceQueue
      2004-05-01 11:57:08,674 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Initialized with config org.jboss.ejb.plugins.jms.JMSContainerInvoker@116e5d6{ maxMessagesNr=1, maxPoolSize=15, reconnectInterval=10000, providerAdapterJNDI=java:/DefaultJMSProvider, serverSessionPoolFactoryJNDI=java:/StdJMSPool, acknowledgeMode=1, isContainerManagedTx=false, isNotSupportedTx=false, useDLQ=true, dlqHandler=org.jboss.ejb.plugins.jms.DLQHandler@4b4b50{ destinationJNDI=queue/DLQ, maxResent=10, timeToLive=0 } }
      2004-05-01 11:57:08,674 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Starting
      2004-05-01 11:57:08,674 INFO [org.jboss.ejb.plugins.jms.DLQHandler] Started null
      2004-05-01 11:57:08,684 INFO [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Started jboss.j2ee:binding=message-driven-bean,jndiName=local/CodeInterface,plugin=invoker,service=EJB
      2004-05-01 11:57:08,684 INFO [org.jboss.ejb.plugins.MessageDrivenInstancePool] Started jboss.j2ee:jndiName=local/CodeInterface,plugin=pool,service=EJB
      2004-05-01 11:57:08,684 INFO [org.jboss.ejb.MessageDrivenContainer] Started jboss.j2ee:jndiName=local/CodeInterface,service=EJB
      2004-05-01 11:57:08,684 INFO [org.jboss.ejb.EjbModule] Started jboss.j2ee:module=Member.jar,service=EjbModule
      2004-05-01 11:57:08,684 INFO [org.jboss.ejb.EJBDeployer] Deployed: file:/C:/Java/jboss-3.2.3/server/default/deploy/Member.jar/
      2004-05-01 11:57:08,894 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/C:/Java/jboss-3.2.3/server/default/deploy/Member.jar/


      ------------------Send a message (Message is in the ServerCodeInterfaceQueue, but the MDB CodeInterface was not invoked--------------------------------------

      2004-05-01 12:00:10,435 TRACE [org.jboss.mq.il.oil.OILServerILService] Setting TcpNoDelay Option to:true
      2004-05-01 12:00:10,445 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : authenticate
      2004-05-01 12:00:10,445 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Autenticating user null/null
      2004-05-01 12:00:10,445 TRACE [org.jboss.mq.security.SecurityManager] Username: null is authenticated
      2004-05-01 12:00:10,445 TRACE [org.jboss.mq.security.SecurityManager] Adding group : class org.jboss.security.NestableGroup Roles(members:guest)
      2004-05-01 12:00:10,445 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : authenticate
      2004-05-01 12:00:10,575 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getID
      2004-05-01 12:00:10,575 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getID
      2004-05-01 12:00:10,575 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : setEnabled
      2004-05-01 12:00:10,575 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : true
      2004-05-01 12:00:10,605 TRACE [org.jboss.mq.server.ClientConsumer] ClientConsumer:ID:1->setEnabled(enabled=true)
      2004-05-01 12:00:10,605 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : setEnabled
      2004-05-01 12:00:10,615 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
      2004-05-01 12:00:10,615 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1083430810615
      2004-05-01 12:00:10,615 DEBUG [org.jboss.mq.il.oil.OILClientIL] ConnectionReceiverOILClient is connecting to: 192.168.0.100:1435
      2004-05-01 12:00:10,655 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
      2004-05-01 12:00:10,785 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : addMessage
      2004-05-01 12:00:10,785 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : org.jboss.mq.SpyTextMessage {
      Header {
      jmsDestination : QUEUE.ServerCodeInterfaceQueue
      jmsDeliveryMode : 2
      jmsExpiration : 0
      jmsPriority : 4
      jmsMessageID : ID:1-10834308107551
      jmsTimeStamp : 1083430810755
      jmsCorrelationID: null
      jmsReplyTo : null
      jmsType : null
      jmsRedelivered : false
      jmsProperties : {}
      jmsPropertiesReadWrite:true
      msgReadOnly : false
      producerClientId: ID:1
      }
      Body {
      text :Just checking
      }
      }
      2004-05-01 12:00:10,785 DEBUG [org.jboss.mq.security.SecurityManager] No SecurityMetadadata was available for ServerCodeInterfaceQueue using default security config
      2004-05-01 12:00:10,785 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=guest;read=true;write=true;create=true}
      2004-05-01 12:00:10,785 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
      Principal: guest
      Principal: Roles(members:guest)
      ;principal=null;roles=Roles(members:guest) for rolePrincipals [guest]
      2004-05-01 12:00:10,795 TRACE [org.jboss.mq.server.MessageCache] add lock aquire message 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,795 TRACE [org.jboss.mq.server.MessageCache] add lock aquire lruCache 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,795 TRACE [org.jboss.mq.server.MessageCache] add locks release1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,795 TRACE [org.jboss.mq.server.MessageCache] run lock aquire, validateSoftReferenceDepth
      2004-05-01 12:00:10,795 TRACE [org.jboss.mq.server.MessageReference] getMessage lock aquire 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,795 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire message 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 wasHard=true
      2004-05-01 12:00:10,795 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire lrucache 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 wasHard=true
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent locks released 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 wasHard=true
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageReference] getMessage lock released 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageReference] invalidate lock aquire 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageReference] invalidate lock relased 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] About to add message 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 transaction=null
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageReference] getMessage lock aquire 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire message 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 wasHard=true
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent lock aquire lrucache 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 wasHard=true
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageCache] messageReferenceUsedEvent locks released 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 wasHard=true
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.server.MessageReference] getMessage lock released 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760
      2004-05-01 12:00:10,805 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] Inserting message 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 transaction=null
      2004-05-01 12:00:10,866 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] Added message 1 msg=0 hard STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 hashCode=8921760 transaction=null
      2004-05-01 12:00:10,876 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : addMessage
      2004-05-01 12:00:10,886 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : connectionClosing
      2004-05-01 12:00:10,886 TRACE [org.jboss.mq.server.ClientConsumer] ClientConsumer:ID:1->close()
      2004-05-01 12:00:10,896 TRACE [org.jboss.mq.il.oil.OILClientIL] Closing OILClientIL
      2004-05-01 12:00:10,896 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : connectionClosing


      ---ejb-ear.xml-----(P.S. I have tried it with containter transaction as well)--------
      <message-driven>
      CodeInterface EJB
      <ejb-name>CodeInterface</ejb-name>
      <ejb-class>org.cdmhope.member.services.CodeInterface</ejb-class>
      <transaction-type>Bean</transaction-type>
      <message-selector></message-selector>
      <acknowledge-mode>Auto-acknowledge</acknowledge-mode>
      <message-driven-destination>
      <destination-type>javax.jms.queue</destination-type>
      <subscription-durability>NonDurable</subscription-durability>
      </message-driven-destination>
      <resource-ref>
      <res-ref-name>jms/CodeInterfaceFactory</res-ref-name>
      <res-type>javax.jms.QueueConnectionFactory</res-type>
      <res-auth>Container</res-auth>
      </resource-ref>
      </message-driven>


      ----jboss.xml-----------(P.S. I have tried it with UIL2ConnectionFactory as well)------

      <message-driven>
      <ejb-name>CodeInterface</ejb-name>
      <destination-jndi-name>queue/ServerCodeInterfaceQueue</destination-jndi-name>
      <resource-ref>
      <res-ref-name>jms/CodeInterfaceFactory</res-ref-name>
      <jndi-name>ConnectionFactory</jndi-name>
      </resource-ref>
      </message-driven>


      ---jboss-mqdestinations-service--------------

      <depends optional-attribute-name="DestinationManager">jboss.mq:service=DestinationManager


        • 1. Re: Cannot invoke MDB when message is sent.
          Adrian Brock Master

          I would expect to see something like:
          2004-05-01 12:00:10,785 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : receive
          when you deploy your MDB, do you see this, is it after the deployment phase
          you already posted?

          If you look on the jmx-console you can see the receivers waiting for a message
          on the queue in the jboss.mq.destinations domain. You should have at least one
          receiver waiting.

          You haven't posted which version of JBoss you are running.
          There was a race condition in the mdb before 3.2.3 where
          connection.start() and mdb.receive() were processed at the same time, but
          incorrectly, this was typically seen at random on multi-processor machines.

          Finally, there is a bug in concurrent.jar that affects mdb delivery.
          If you download jboss-3.2.4RC1 you can get a fixed version of concurrent.jar
          that can be used with earlier versions of jboss.
          Also 3.2.4RC1 contains more useful jbossmq trace logging so it would be easier
          for me to see what is going on.

          Regards,
          Adrian

          • 2. Re: Cannot invoke MDB when message is sent.
            Tim Carey Newbie

            Adrian,

            Thanks for the tip.

            The version I was using is 3.2.3; I did not see a receive in the log when deployed and the console does not show a receiver.

            I'll download 3.2.4RC1 and see if that fixes the problem.

            Thanks,
            Tim

            • 3. Re: Cannot invoke MDB when message is sent.
              Tim Carey Newbie

              Adrian,

              I downloaded 3.2.4RC1 and used the concurrent JAR with 3.2.3 -- This did not work.
              I then switched over to 3.2.4RC1 and still was not able to invoke the MDB.

              The message stays in the queue and no receiver is listed.

              Also the local/CodeInterface MBEAN counters indicate that the bean was never created or received a message.

              The trace never states that the CodeInterface is a receiver of the queue

              In reviewing the traces I do see 3 exceptions.
              During startup --> SecurityManager failed to lookup the jbossmq domain.
              During the message send ---> Socket manager (java.lang.exception)
              During connection close ---> java.io.EOFException in the exception manager

              Any ideas?


              Trace information to follow:
              -----------Initial Startup-----------------------------------------------------
              ----------------Setup of MQ and Queues------------------------------------------------
              2004-05-02 16:05:09,953 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Created securityMgr=org.jboss.security.plugins.JaasSecurityManager@1dea382
              2004-05-02 16:05:09,983 INFO [org.jboss.security.plugins.JaasSecurityManagerService] setCachePolicy, c=org.jboss.util.TimedCachePolicy@1e2bda7
              2004-05-02 16:05:09,983 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Added JmsXARealm, org.jboss.security.plugins.SecurityDomainContext@1115a61 to map
              2004-05-02 16:05:10,073 INFO [org.jboss.resource.adapter.jms.JmsManagedConnectionFactory.JmsXA] Bound connection factory for resource adapter for ConnectionManager 'jboss.jca:service=TxCM,name=JmsXA to JNDI name 'java:/JmsXA'
              2004-05-02 16:05:10,073 INFO [org.jboss.resource.connectionmanager.TxConnectionManager] Started jboss.jca:service=TxCM,name=JmsXA
              2004-05-02 16:05:10,183 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Created securityMgr=org.jboss.security.plugins.JaasSecurityManager@d134d0
              2004-05-02 16:05:10,183 INFO [org.jboss.security.plugins.JaasSecurityManagerService] setCachePolicy, c=org.jboss.util.TimedCachePolicy@e265f5
              2004-05-02 16:05:10,183 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Added HsqlDbRealm, org.jboss.security.plugins.SecurityDomainContext@193d2f2 to map
              2004-05-02 16:05:10,203 INFO [org.jboss.resource.adapter.jdbc.local.LocalManagedConnectionFactory.DefaultDS] Bound connection factory for resource adapter for ConnectionManager 'jboss.jca:service=LocalTxCM,name=DefaultDS to JNDI name 'java:/DefaultDS'
              2004-05-02 16:05:10,203 INFO [org.jboss.resource.connectionmanager.TxConnectionManager] Started jboss.jca:service=LocalTxCM,name=DefaultDS
              2004-05-02 16:05:10,203 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Starting
              2004-05-02 16:05:10,213 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Resolving uncommited TXS
              2004-05-02 16:05:10,674 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Could not create table with SQL: CREATE CACHED TABLE JMS_MESSAGES ( MESSAGEID INTEGER NOT NULL, DESTINATION VARCHAR(255) NOT NULL, TXID INTEGER, TXOP CHAR(1), MESSAGEBLOB OBJECT, PRIMARY KEY (MESSAGEID, DESTINATION) ), got : java.sql.SQLException: Table already exists: JMS_MESSAGES in statement [CREATE CACHED TABLE JMS_MESSAGES ( MESSAGEID INTEGER NOT NULL, DESTINATION VARCHAR(255) NOT NULL, TXID INTEGER, TXOP CHAR(1), MESSAGEBLOB OBJECT, PRIMARY KEY (MESSAGEID, DESTINATION) )]
              2004-05-02 16:05:10,684 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Could not create table with SQL: CREATE CACHED TABLE JMS_TRANSACTIONS ( TXID INTEGER, PRIMARY KEY (TXID) ), got : java.sql.SQLException: Table already exists: JMS_TRANSACTIONS in statement [CREATE CACHED TABLE JMS_TRANSACTIONS ( TXID INTEGER, PRIMARY KEY (TXID) )]
              2004-05-02 16:05:10,754 INFO [org.jboss.mq.pm.jdbc2.PersistenceManager] Started jboss.mq:service=PersistenceManager
              2004-05-02 16:05:10,754 DEBUG [org.jboss.mq.server.jmx.DestinationManager] Starting
              2004-05-02 16:05:10,874 INFO [org.jboss.mq.server.jmx.DestinationManager] Started jboss.mq:service=DestinationManager
              2004-05-02 16:05:10,884 DEBUG [org.jboss.mq.security.SecurityManager] Starting
              2004-05-02 16:05:10,904 DEBUG [org.jboss.mq.security.SecurityManager] Failed to lookup securityDomain=jbossmq
              javax.naming.NameNotFoundException: jbossmq not bound
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:495)
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:503)
              at org.jnp.server.NamingServer.getObject(NamingServer.java:509)
              at org.jnp.server.NamingServer.lookup(NamingServer.java:282)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:528)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:507)
              at javax.naming.InitialContext.lookup(InitialContext.java:347)
              at org.jboss.mq.security.SecurityManager.startService(SecurityManager.java:162)
              at org.jboss.system.ServiceMBeanSupport.start(ServiceMBeanSupport.java:192)
              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:324)
              at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:72)
              at org.jboss.mx.server.Invocation.dispatch(Invocation.java:45)
              at org.jboss.mx.server.Invocation.invoke(Invocation.java:70)
              at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:187)
              at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:480)
              at org.jboss.system.ServiceController$ServiceProxy.invoke(ServiceController.java:825)
              at $Proxy16.start(Unknown Source)
              at org.jboss.system.ServiceController.start(ServiceController.java:360)
              at org.jboss.system.ServiceController.start(ServiceController.java:382)
              at org.jboss.system.ServiceController.start(ServiceController.java:382)
              at org.jboss.system.ServiceController.start(ServiceController.java:382)
              at org.jboss.system.ServiceController.start(ServiceController.java:382)
              at sun.reflect.GeneratedMethodAccessor6.invoke(Unknown Source)
              at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
              at java.lang.reflect.Method.invoke(Method.java:324)
              at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:72)
              at org.jboss.mx.server.Invocation.dispatch(Invocation.java:45)
              at org.jboss.mx.server.Invocation.invoke(Invocation.java:70)
              at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:187)
              at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:480)
              at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:174)
              at $Proxy4.start(Unknown Source)
              at org.jboss.deployment.SARDeployer.start(SARDeployer.java:250)
              at org.jboss.deployment.MainDeployer.start(MainDeployer.java:833)
              at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:643)
              at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:606)
              at sun.reflect.GeneratedMethodAccessor16.invoke(Unknown Source)
              at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
              at java.lang.reflect.Method.invoke(Method.java:324)
              at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:72)
              at org.jboss.mx.server.Invocation.dispatch(Invocation.java:45)
              at org.jboss.mx.server.Invocation.invoke(Invocation.java:70)
              at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:187)
              at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:480)
              at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:174)
              at $Proxy7.deploy(Unknown Source)
              at org.jboss.deployment.scanner.URLDeploymentScanner.deploy(URLDeploymentScanner.java:302)
              at org.jboss.deployment.scanner.URLDeploymentScanner.scan(URLDeploymentScanner.java:476)
              at org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread.doScan(AbstractDeploymentScanner.java:201)
              at org.jboss.deployment.scanner.AbstractDeploymentScanner.startService(AbstractDeploymentScanner.java:274)
              at org.jboss.system.ServiceMBeanSupport.start(ServiceMBeanSupport.java:192)
              at sun.reflect.GeneratedMethodAccessor5.invoke(Unknown Source)
              at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
              at java.lang.reflect.Method.invoke(Method.java:324)
              at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:72)
              at org.jboss.mx.server.Invocation.dispatch(Invocation.java:45)
              at org.jboss.mx.server.Invocation.invoke(Invocation.java:70)
              at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:187)
              at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:480)
              at org.jboss.system.ServiceController$ServiceProxy.invoke(ServiceController.java:825)
              at $Proxy0.start(Unknown Source)
              at org.jboss.system.ServiceController.start(ServiceController.java:360)
              at sun.reflect.GeneratedMethodAccessor6.invoke(Unknown Source)
              at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
              at java.lang.reflect.Method.invoke(Method.java:324)
              at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:72)
              at org.jboss.mx.server.Invocation.dispatch(Invocation.java:45)
              at org.jboss.mx.server.Invocation.invoke(Invocation.java:70)
              at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:187)
              at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:480)
              at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:174)
              at $Proxy4.start(Unknown Source)
              at org.jboss.deployment.SARDeployer.start(SARDeployer.java:250)
              at org.jboss.deployment.MainDeployer.start(MainDeployer.java:833)
              at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:643)
              at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:606)
              at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:590)
              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:324)
              at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:72)
              at org.jboss.mx.server.Invocation.dispatch(Invocation.java:45)
              at org.jboss.mx.server.Invocation.invoke(Invocation.java:70)
              at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:187)
              at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:480)
              at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:174)
              at $Proxy5.deploy(Unknown Source)
              at org.jboss.system.server.ServerImpl.doStart(ServerImpl.java:404)
              at org.jboss.system.server.ServerImpl.start(ServerImpl.java:311)
              at org.jboss.Main.boot(Main.java:144)
              at org.jboss.Main$1.run(Main.java:389)
              at java.lang.Thread.run(Thread.java:534)
              2004-05-02 16:05:10,974 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Created securityMgr=org.jboss.security.plugins.JaasSecurityManager@1292d12
              2004-05-02 16:05:10,984 INFO [org.jboss.security.plugins.JaasSecurityManagerService] setCachePolicy, c=org.jboss.util.TimedCachePolicy@4e32c8
              2004-05-02 16:05:10,984 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Added jbossmq, org.jboss.security.plugins.SecurityDomainContext@1e80c87 to map
              2004-05-02 16:05:11,025 INFO [org.jboss.mq.security.SecurityManager] Started jboss.mq:service=SecurityManager
              2004-05-02 16:05:11,025 DEBUG [org.jboss.mq.server.jmx.InterceptorLoader] Starting
              2004-05-02 16:05:11,025 INFO [org.jboss.mq.server.jmx.InterceptorLoader] Started jboss.mq:service=TracingInterceptor
              2004-05-02 16:05:11,025 DEBUG [org.jboss.mq.server.jmx.Invoker] Starting
              2004-05-02 16:05:11,035 INFO [org.jboss.mq.server.jmx.Invoker] Started jboss.mq:service=Invoker
              2004-05-02 16:05:11,035 DEBUG [org.jboss.mq.il.http.HTTPServerILService] Starting
              2004-05-02 16:05:11,105 TRACE [org.jboss.mq.il.http.HTTPClient] created(String http://192.168.0.100:8080/jbossmq-httpil/HTTPServerILServlet)
              2004-05-02 16:05:12,026 INFO [org.jboss.mq.il.http.HTTPServerILService] Started jboss.mq:service=InvocationLayer,type=HTTP
              2004-05-02 16:05:12,026 DEBUG [org.jboss.mq.il.uil2.UILServerILService] Starting
              2004-05-02 16:05:12,136 INFO [org.jboss.mq.il.uil2.UILServerILService] JBossMQ UIL service available at : /0.0.0.0:8093
              2004-05-02 16:05:12,136 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getThreadGroup
              2004-05-02 16:05:12,136 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getThreadGroup
              2004-05-02 16:05:12,657 INFO [org.jboss.mq.il.uil2.UILServerILService] Started jboss.mq:service=InvocationLayer,type=UIL2
              2004-05-02 16:05:12,657 DEBUG [org.jboss.mq.il.jvm.JVMServerILService] Starting
              2004-05-02 16:05:12,677 INFO [org.jboss.mq.il.jvm.JVMServerILService] Started jboss.mq:service=InvocationLayer,type=JVM
              2004-05-02 16:05:12,677 DEBUG [org.jboss.mq.server.jmx.Queue.DLQ] Starting
              2004-05-02 16:05:12,797 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.DLQ
              2004-05-02 16:05:12,807 INFO [org.jboss.mq.server.jmx.Queue.DLQ] Bound to JNDI name: queue/DLQ
              2004-05-02 16:05:12,807 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=guest;read=true;write=true;create=true}
              2004-05-02 16:05:12,817 INFO [org.jboss.mq.server.jmx.Queue.DLQ] Started jboss.mq.destination:service=Queue,name=DLQ
              2004-05-02 16:05:12,817 DEBUG [org.jboss.mq.server.jmx.Topic.testTopic] Starting
              2004-05-02 16:05:12,887 INFO [org.jboss.mq.server.jmx.Topic.testTopic] Bound to JNDI name: topic/testTopic
              2004-05-02 16:05:12,887 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=guest;read=true;write=true;create=false}
              2004-05-02 16:05:12,887 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=publisher;read=true;write=true;create=false}
              2004-05-02 16:05:12,887 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=durpublisher;read=true;write=true;create=true}
              2004-05-02 16:05:12,887 INFO [org.jboss.mq.server.jmx.Topic.testTopic] Started jboss.mq.destination:service=Topic,name=testTopic
              2004-05-02 16:05:12,897 DEBUG [org.jboss.mq.server.jmx.Topic.securedTopic] Starting
              2004-05-02 16:05:12,897 INFO [org.jboss.mq.server.jmx.Topic.securedTopic] Bound to JNDI name: topic/securedTopic
              2004-05-02 16:05:12,897 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=publisher;read=true;write=true;create=false}
              2004-05-02 16:05:12,897 INFO [org.jboss.mq.server.jmx.Topic.securedTopic] Started jboss.mq.destination:service=Topic,name=securedTopic
              2004-05-02 16:05:12,897 DEBUG [org.jboss.mq.server.jmx.Topic.testDurableTopic] Starting
              2004-05-02 16:05:12,897 INFO [org.jboss.mq.server.jmx.Topic.testDurableTopic] Bound to JNDI name: topic/testDurableTopic
              2004-05-02 16:05:12,897 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=guest;read=true;write=true;create=false}
              2004-05-02 16:05:12,907 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=publisher;read=true;write=true;create=false}
              2004-05-02 16:05:12,907 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=durpublisher;read=true;write=true;create=true}
              2004-05-02 16:05:12,907 INFO [org.jboss.mq.server.jmx.Topic.testDurableTopic] Started jboss.mq.destination:service=Topic,name=testDurableTopic
              2004-05-02 16:05:12,907 DEBUG [org.jboss.mq.server.jmx.Queue.testQueue] Starting
              2004-05-02 16:05:12,907 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.testQueue
              2004-05-02 16:05:12,907 INFO [org.jboss.mq.server.jmx.Queue.testQueue] Bound to JNDI name: queue/testQueue
              2004-05-02 16:05:12,917 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=guest;read=true;write=true;create=false}
              2004-05-02 16:05:12,917 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=publisher;read=true;write=true;create=false}
              2004-05-02 16:05:12,917 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=noacc;read=false;write=false;create=false}
              2004-05-02 16:05:12,917 INFO [org.jboss.mq.server.jmx.Queue.testQueue] Started jboss.mq.destination:service=Queue,name=testQueue
              2004-05-02 16:05:12,917 DEBUG [org.jboss.mq.server.jmx.Queue.A] Starting
              2004-05-02 16:05:12,927 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.A
              2004-05-02 16:05:12,927 INFO [org.jboss.mq.server.jmx.Queue.A] Bound to JNDI name: queue/A
              2004-05-02 16:05:12,927 INFO [org.jboss.mq.server.jmx.Queue.A] Started jboss.mq.destination:service=Queue,name=A
              2004-05-02 16:05:12,927 DEBUG [org.jboss.mq.server.jmx.Queue.B] Starting
              2004-05-02 16:05:12,937 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.B
              2004-05-02 16:05:12,937 INFO [org.jboss.mq.server.jmx.Queue.B] Bound to JNDI name: queue/B
              2004-05-02 16:05:12,937 INFO [org.jboss.mq.server.jmx.Queue.B] Started jboss.mq.destination:service=Queue,name=B
              2004-05-02 16:05:12,937 DEBUG [org.jboss.mq.server.jmx.Queue.C] Starting
              2004-05-02 16:05:12,947 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.C
              2004-05-02 16:05:12,947 INFO [org.jboss.mq.server.jmx.Queue.C] Bound to JNDI name: queue/C
              2004-05-02 16:05:12,947 INFO [org.jboss.mq.server.jmx.Queue.C] Started jboss.mq.destination:service=Queue,name=C
              2004-05-02 16:05:12,947 DEBUG [org.jboss.mq.server.jmx.Queue.D] Starting
              2004-05-02 16:05:12,987 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.D
              2004-05-02 16:05:12,987 INFO [org.jboss.mq.server.jmx.Queue.D] Bound to JNDI name: queue/D
              2004-05-02 16:05:12,987 INFO [org.jboss.mq.server.jmx.Queue.D] Started jboss.mq.destination:service=Queue,name=D
              2004-05-02 16:05:12,987 DEBUG [org.jboss.mq.server.jmx.Queue.ex] Starting
              2004-05-02 16:05:12,997 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.ex
              2004-05-02 16:05:12,997 INFO [org.jboss.mq.server.jmx.Queue.ex] Bound to JNDI name: queue/ex
              2004-05-02 16:05:12,997 INFO [org.jboss.mq.server.jmx.Queue.ex] Started jboss.mq.destination:service=Queue,name=ex
              2004-05-02 16:05:12,997 DEBUG [org.jboss.mq.server.jmx.Queue.ServerCodeInterfaceQueue] Starting
              2004-05-02 16:05:13,007 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.ServerCodeInterfaceQueue
              2004-05-02 16:05:13,007 INFO [org.jboss.mq.server.jmx.Queue.ServerCodeInterfaceQueue] Bound to JNDI name: queue/ServerCodeInterfaceQueue
              2004-05-02 16:05:13,007 INFO [org.jboss.mq.server.jmx.Queue.ServerCodeInterfaceQueue] Started jboss.mq.destination:service=Queue,name=ServerCodeInterfaceQueue
              2004-05-02 16:05:13,007 DEBUG [org.jboss.mq.server.jmx.Queue.ClientCodeInterfaceQueue] Starting
              2004-05-02 16:05:13,017 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: QUEUE.ClientCodeInterfaceQueue
              2004-05-02 16:05:13,017 INFO [org.jboss.mq.server.jmx.Queue.ClientCodeInterfaceQueue] Bound to JNDI name: queue/ClientCodeInterfaceQueue
              2004-05-02 16:05:13,017 INFO [org.jboss.mq.server.jmx.Queue.ClientCodeInterfaceQueue] Started jboss.mq.destination:service=Queue,name=ClientCodeInterfaceQueue











              -----------------Deployment of application services_______________________________
              2004-05-02 16:05:18,836 INFO [org.jboss.ejb.EjbModule] Deploying MemberService
              2004-05-02 16:05:19,206 TRACE [org.jboss.ejb.plugins.StatelessSessionInstancePool] Constructing
              2004-05-02 16:05:19,206 INFO [org.jboss.ejb.EjbModule] Deploying CodeInterface
              2004-05-02 16:05:19,387 TRACE [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Constructing
              2004-05-02 16:05:19,397 TRACE [org.jboss.ejb.plugins.MessageDrivenInstancePool] Constructing
              2004-05-02 16:05:19,457 DEBUG [org.jboss.ejb.plugins.StatelessSessionInstancePool] Creating
              2004-05-02 16:05:19,457 DEBUG [org.jboss.ejb.plugins.StatelessSessionInstancePool] Created
              2004-05-02 16:05:19,597 DEBUG [org.jboss.ejb.plugins.MessageDrivenInstancePool] Creating
              2004-05-02 16:05:19,597 DEBUG [org.jboss.ejb.plugins.MessageDrivenInstancePool] Created
              2004-05-02 16:05:19,617 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Creating
              2004-05-02 16:05:19,627 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Created
              2004-05-02 16:05:19,717 DEBUG [org.jboss.ejb.plugins.local.BaseLocalProxyFactory] MemberService cannot be Bound, doesn't have local and local home interfaces
              2004-05-02 16:05:19,927 DEBUG [org.jboss.ejb.plugins.StatelessSessionInstancePool] Starting
              2004-05-02 16:05:19,927 INFO [org.jboss.ejb.plugins.StatelessSessionInstancePool] Started jboss.j2ee:jndiName=ejb/MemberServiceHomeRemote,plugin=pool,service=EJB
              2004-05-02 16:05:19,927 INFO [org.jboss.ejb.StatelessSessionContainer] Started jboss.j2ee:jndiName=ejb/MemberServiceHomeRemote,service=EJB
              2004-05-02 16:05:19,937 DEBUG [org.jboss.ejb.plugins.local.BaseLocalProxyFactory] CodeInterface cannot be Bound, doesn't have local and local home interfaces
              2004-05-02 16:05:19,937 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Starting
              2004-05-02 16:05:19,937 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Initializing
              2004-05-02 16:05:19,937 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Looking up provider adapter: java:/DefaultJMSProvider
              2004-05-02 16:05:19,937 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Provider adapter: org.jboss.jms.jndi.JBossMQProvider@e9a7c2
              2004-05-02 16:05:19,947 TRACE [org.jboss.ejb.plugins.jms.DLQHandler] Constructing
              2004-05-02 16:05:19,947 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Creating
              2004-05-02 16:05:19,947 DEBUG [org.jboss.jms.jndi.JBossMQProvider] no provider url; connecting to local JNDI
              2004-05-02 16:05:19,947 DEBUG [org.jboss.jms.jndi.JBossMQProvider] created context: javax.naming.InitialContext@c9e870
              2004-05-02 16:05:19,947 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Using factory: org.jboss.mq.SpyXAConnectionFactory@1bb3e9a
              2004-05-02 16:05:20,068 DEBUG [org.jboss.mq.Connection] Setting the clockDaemon's thread factory
              2004-05-02 16:05:20,078 TRACE [org.jboss.mq.Connection] Connection Initializing userName=null Connection@21683767[clientID=null rcvstate=STOPPED]
              2004-05-02 16:05:20,078 TRACE [org.jboss.mq.Connection] Getting the serverIL Connection@21683767[clientID=null rcvstate=STOPPED]
              2004-05-02 16:05:20,078 TRACE [org.jboss.mq.Connection] serverIL=org.jboss.mq.il.jvm.JVMServerIL@431693 Connection@21683767[clientID=null rcvstate=STOPPED]
              2004-05-02 16:05:20,078 TRACE [org.jboss.mq.Connection] Authenticating user null Connection@21683767[clientID=null rcvstate=STOPPED]
              2004-05-02 16:05:20,078 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : authenticate
              2004-05-02 16:05:20,078 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Autenticating user null/null
              2004-05-02 16:05:20,108 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] initialize
              2004-05-02 16:05:20,108 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] Saw unauthenticatedIdentity=guest
              2004-05-02 16:05:20,108 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] login
              2004-05-02 16:05:20,108 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] Authenticating as unauthenticatedIdentity=guest
              2004-05-02 16:05:20,108 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] User 'guest' authenticated, loginOk=true
              2004-05-02 16:05:20,108 TRACE [org.jboss.mq.sm.file.DynamicLoginModule] commit, loginOk=true
              2004-05-02 16:05:20,128 TRACE [org.jboss.mq.security.SecurityManager] Username: null is authenticated
              2004-05-02 16:05:20,168 TRACE [org.jboss.mq.security.SecurityManager] Adding group : class org.jboss.security.NestableGroup Roles(members:guest)
              2004-05-02 16:05:20,168 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : authenticate
              2004-05-02 16:05:20,168 TRACE [org.jboss.mq.Connection] Starting the client il Connection@21683767[clientID=null rcvstate=STOPPED]
              2004-05-02 16:05:20,198 TRACE [org.jboss.mq.GenericConnectionFactory] Handing out ClientIL: org.jboss.mq.il.jvm.JVMClientILService
              2004-05-02 16:05:20,198 TRACE [org.jboss.mq.Connection] Using client id org.jboss.mq.il.jvm.JVMClientILService@1117681 Connection@21683767[clientID=null rcvstate=STOPPED]
              2004-05-02 16:05:20,198 TRACE [org.jboss.mq.Connection] Creating XAResourceManager Connection@21683767[token=ConnectionToken:null/a38c8f8222b9e4a55db42a31245468ae rcvstate=STOPPED]
              2004-05-02 16:05:20,218 TRACE [org.jboss.mq.Connection] Starting the ping thread Connection@21683767[token=ConnectionToken:null/a38c8f8222b9e4a55db42a31245468ae rcvstate=STOPPED]
              2004-05-02 16:05:20,218 TRACE [org.jboss.mq.Connection] Connection establishment successful Connection@21683767[token=ConnectionToken:null/a38c8f8222b9e4a55db42a31245468ae rcvstate=STOPPED]
              2004-05-02 16:05:20,218 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Created connection: Connection@21683767[token=ConnectionToken:null/a38c8f8222b9e4a55db42a31245468ae rcvstate=STOPPED]
              2004-05-02 16:05:20,228 DEBUG [org.jboss.mq.referenceable.SpyDestinationObjectFactory] SpyDestinationObjectFactory->getObjectInstance()
              2004-05-02 16:05:20,228 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Using Queue: QUEUE.DLQ
              2004-05-02 16:05:20,228 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Created
              2004-05-02 16:05:20,238 DEBUG [org.jboss.jms.jndi.JBossMQProvider] no provider url; connecting to local JNDI
              2004-05-02 16:05:20,238 DEBUG [org.jboss.jms.jndi.JBossMQProvider] created context: javax.naming.InitialContext@111985e
              2004-05-02 16:05:20,238 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] context: javax.naming.InitialContext@111985e
              2004-05-02 16:05:20,238 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] jndiSuffix: ServerCodeInterfaceQueue
              2004-05-02 16:05:20,238 DEBUG [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Initialized with config org.jboss.ejb.plugins.jms.JMSContainerInvoker@2a730e{ maxMessagesNr=1, maxPoolSize=15, reconnectInterval=10000, providerAdapterJNDI=java:/DefaultJMSProvider, serverSessionPoolFactoryJNDI=java:/StdJMSPool, acknowledgeMode=1, isContainerManagedTx=false, isNotSupportedTx=false, useDLQ=true, dlqHandler=org.jboss.ejb.plugins.jms.DLQHandler@e7b3cf{ destinationJNDI=queue/DLQ, maxResent=10, timeToLive=0 } }
              2004-05-02 16:05:20,238 DEBUG [org.jboss.ejb.plugins.jms.DLQHandler] Starting
              2004-05-02 16:05:20,238 INFO [org.jboss.ejb.plugins.jms.DLQHandler] Started null
              2004-05-02 16:05:20,238 INFO [org.jboss.ejb.plugins.jms.JMSContainerInvoker] Started jboss.j2ee:binding=message-driven-bean,jndiName=local/CodeInterface,plugin=invoker,service=EJB
              2004-05-02 16:05:20,238 DEBUG [org.jboss.ejb.plugins.MessageDrivenInstancePool] Starting
              2004-05-02 16:05:20,238 INFO [org.jboss.ejb.plugins.MessageDrivenInstancePool] Started jboss.j2ee:jndiName=local/CodeInterface,plugin=pool,service=EJB
              2004-05-02 16:05:20,238 INFO [org.jboss.ejb.MessageDrivenContainer] Started jboss.j2ee:jndiName=local/CodeInterface,service=EJB
              2004-05-02 16:05:20,238 INFO [org.jboss.ejb.EjbModule] Started jboss.j2ee:module=Member.jar,service=EjbModule
              2004-05-02 16:05:20,238 INFO [org.jboss.ejb.EJBDeployer] Deployed: file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/deploy/Member.jar/
              2004-05-02 16:05:20,568 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/deploy/Member.jar/
              2004-05-02 16:05:20,568 INFO [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/deploy/jmx-console.war/
              2004-05-02 16:05:20,729 INFO [org.jboss.web.tomcat.tc5.TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/deploy/jmx-console.war/
              2004-05-02 16:05:20,909 DEBUG [tomcat.localhost./jmx-console.Context] Starting tomcat.localhost./jmx-console.Context
              2004-05-02 16:05:20,919 DEBUG [tomcat.localhost./jmx-console.Context] Configuring default Resources
              2004-05-02 16:05:20,939 DEBUG [tomcat.localhost./jmx-console.Context] Processing standard container startup
              2004-05-02 16:05:21,049 DEBUG [tomcat.localhost./jmx-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
              2004-05-02 16:05:21,219 DEBUG [tomcat.localhost./jmx-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
              2004-05-02 16:05:21,309 DEBUG [tomcat.localhost./jmx-console.Context] Posting standard context attributes
              2004-05-02 16:05:21,309 DEBUG [tomcat.localhost./jmx-console.Context] Configuring application event listeners
              2004-05-02 16:05:21,309 DEBUG [tomcat.localhost./jmx-console.Context] Sending application start events
              2004-05-02 16:05:21,309 DEBUG [tomcat.localhost./jmx-console.Context] Starting filters
              2004-05-02 16:05:21,309 DEBUG [tomcat.localhost./jmx-console.Context] Starting completed
              2004-05-02 16:05:21,309 DEBUG [tomcat.localhost./jmx-console.Context] Checking for jboss.web:j2eeType=WebModule,name=//localhost/jmx-console,J2EEApplication=none,J2EEServer=none
              2004-05-02 16:05:21,590 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/deploy/jmx-console.war/
              2004-05-02 16:05:21,600 INFO [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/deploy/management/web-console.war
              2004-05-02 16:05:23,192 INFO [org.jboss.web.tomcat.tc5.TomcatDeployer] deploy, ctxPath=/web-console, warUrl=file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/tmp/deploy/tmp30328web-console.war/
              2004-05-02 16:05:23,272 DEBUG [tomcat.localhost./web-console.Context] Starting tomcat.localhost./web-console.Context
              2004-05-02 16:05:23,292 DEBUG [tomcat.localhost./web-console.Context] Configuring default Resources
              2004-05-02 16:05:23,322 DEBUG [tomcat.localhost./web-console.Context] Processing standard container startup
              2004-05-02 16:05:23,382 DEBUG [tomcat.localhost./web-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
              2004-05-02 16:05:23,472 DEBUG [tomcat.localhost./web-console.Context] Setting deployment descriptor public ID to '-//Sun Microsystems, Inc.//DTD Web Application 2.3//EN'
              2004-05-02 16:05:23,593 DEBUG [tomcat.localhost./web-console.Context] Posting standard context attributes
              2004-05-02 16:05:23,593 DEBUG [tomcat.localhost./web-console.Context] Configuring application event listeners
              2004-05-02 16:05:23,593 DEBUG [tomcat.localhost./web-console.Context] Sending application start events
              2004-05-02 16:05:23,593 DEBUG [tomcat.localhost./web-console.Context] Starting filters
              2004-05-02 16:05:24,955 DEBUG [tomcat.localhost./web-console.Context] Starting completed
              2004-05-02 16:05:24,955 DEBUG [tomcat.localhost./web-console.Context] Checking for jboss.web:j2eeType=WebModule,name=//localhost/web-console,J2EEApplication=none,J2EEServer=none
              2004-05-02 16:05:25,555 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/deploy/management/web-console.war
              2004-05-02 16:05:25,565 INFO [org.jboss.deployment.scanner.URLDeploymentScanner] Started jboss.deployment:type=DeploymentScanner,flavor=URL
              2004-05-02 16:05:26,367 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/C:/Java/jboss-3.2.4/jboss-3.2.4RC1/server/default/conf/jboss-service.xml
              2004-05-02 16:05:26,367 INFO [org.jboss.system.server.Server] JBoss (MX MicroKernel) [3.2.4RC1 (build: CVSTag=JBoss_3_2_4_RC1 date=200403122128)] Started in 58s:154ms



              ------------------Send a message (Message is in the ServerCodeInterfaceQueue, but the MDB CodeInterface was not invoked--------------------------------------
              2004-05-02 16:17:18,551 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getThreadGroup
              2004-05-02 16:17:18,551 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getThreadGroup
              2004-05-02 16:17:18,551 TRACE [org.jboss.mq.il.uil2.SocketManager] start called
              java.lang.Exception: Start stack trace
              at org.jboss.mq.il.uil2.SocketManager.start(SocketManager.java:91)
              at org.jboss.mq.il.uil2.UILServerILService.run(UILServerILService.java:126)
              at java.lang.Thread.run(Thread.java:534)
              2004-05-02 16:17:18,621 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run
              2004-05-02 16:17:18,621 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run
              2004-05-02 16:17:18,621 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream
              2004-05-02 16:17:18,691 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream
              2004-05-02 16:17:18,691 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_authenticate, msgID: 1
              2004-05-02 16:17:18,691 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg21153343[msgType: m_authenticate, msgID: 1, error: null]
              2004-05-02 16:17:18,691 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 22
              2004-05-02 16:17:18,691 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : authenticate
              2004-05-02 16:17:18,691 TRACE [org.jboss.mq.security.ServerSecurityInterceptor] Autenticating user null/null
              2004-05-02 16:17:18,691 TRACE [org.jboss.mq.security.SecurityManager] Username: null is authenticated
              2004-05-02 16:17:18,701 TRACE [org.jboss.mq.security.SecurityManager] Adding group : class org.jboss.security.NestableGroup Roles(members:guest)
              2004-05-02 16:17:18,701 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : authenticate
              2004-05-02 16:17:18,701 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg21153343[msgType: m_authenticate, msgID: 1, error: null]
              2004-05-02 16:17:18,701 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.CheckUserMsg21153343[msgType: m_authenticate, msgID: 1, error: null]
              2004-05-02 16:17:18,701 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.CheckUserMsg21153343[msgType: m_authenticate, msgID: 1, error: null]
              2004-05-02 16:17:18,701 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 22
              2004-05-02 16:17:18,741 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_setSpyDistributedConnection, msgID: 2
              2004-05-02 16:17:18,761 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg9240774[msgType: m_setSpyDistributedConnection, msgID: 2, error: null]
              2004-05-02 16:17:18,761 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 15
              2004-05-02 16:17:18,761 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection
              2004-05-02 16:17:18,761 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg9240774[msgType: m_setSpyDistributedConnection, msgID: 2, error: null]
              2004-05-02 16:17:18,761 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg9240774[msgType: m_setSpyDistributedConnection, msgID: 2, error: null]
              2004-05-02 16:17:18,771 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.ConnectionTokenMsg9240774[msgType: m_setSpyDistributedConnection, msgID: 2, error: null]
              2004-05-02 16:17:18,771 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up
              2004-05-02 16:17:18,771 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 15
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_getID, msgID: 3
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.GetIDMsg19778444[msgType: m_getID, msgID: 3, error: null]
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 9
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : getID
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : getID
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.GetIDMsg19778444[msgType: m_getID, msgID: 3, error: null]
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.GetIDMsg19778444[msgType: m_getID, msgID: 3, error: null]
              2004-05-02 16:17:18,791 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 9
              2004-05-02 16:17:18,801 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_ping, msgID: 4
              2004-05-02 16:17:18,801 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.PingMsg26117676[msgType: m_ping, msgID: 4, error: null]
              2004-05-02 16:17:18,801 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 21
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : ping
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : 1083532638801
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.PingMsg701837[msgType: m_pong, msgID: -2147483647, error: null]
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.PingMsg701837[msgType: m_pong, msgID: -2147483647, error: null]
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : ping
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 21
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.GetIDMsg19778444[msgType: m_getID, msgID: 3, error: null]
              2004-05-02 16:17:18,811 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.PingMsg701837[msgType: m_pong, msgID: -2147483647, error: null]
              2004-05-02 16:17:18,821 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_setEnabled, msgID: 5
              2004-05-02 16:17:18,821 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg27011377[msgType: m_setEnabled, msgID: 5, error: null]
              2004-05-02 16:17:18,831 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 14
              2004-05-02 16:17:18,831 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : setEnabled
              2004-05-02 16:17:18,831 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : true
              2004-05-02 16:17:18,861 TRACE [org.jboss.mq.server.ClientConsumer] ClientConsumer:ID:1->setEnabled(enabled=true)
              2004-05-02 16:17:18,861 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : setEnabled
              2004-05-02 16:17:18,861 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg27011377[msgType: m_setEnabled, msgID: 5, error: null]
              2004-05-02 16:17:18,871 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg27011377[msgType: m_setEnabled, msgID: 5, error: null]
              2004-05-02 16:17:18,891 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg27011377[msgType: m_setEnabled, msgID: 5, error: null]
              2004-05-02 16:17:18,891 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 14
              2004-05-02 16:17:19,021 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_addMessage, msgID: 6
              2004-05-02 16:17:19,031 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.AddMsg32379995[msgType: m_addMessage, msgID: 6, error: null]
              2004-05-02 16:17:19,031 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 2
              2004-05-02 16:17:19,031 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : addMessage
              2004-05-02 16:17:19,041 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : SpyTextMessage {
              Header {
              jmsDestination : QUEUE.ServerCodeInterfaceQueue
              jmsDeliveryMode : 2
              jmsExpiration : 0
              jmsPriority : 4
              jmsMessageID : ID:1-10835326390111
              jmsTimeStamp : 1083532639011
              jmsCorrelationID: null
              jmsReplyTo : null
              jmsType : null
              jmsRedelivered : false
              jmsProperties : {}
              jmsPropReadWrite: true
              msgReadOnly : false
              producerClientId: ID:1
              }
              Body {
              text :Just checking
              }
              }
              2004-05-02 16:17:19,041 DEBUG [org.jboss.mq.security.SecurityManager] No SecurityMetadadata was available for ServerCodeInterfaceQueue using default security config
              2004-05-02 16:17:19,041 TRACE [org.jboss.mq.security.SecurityMetadata] Adding role: Role {name=guest;read=true;write=true;create=true}
              2004-05-02 16:17:19,041 TRACE [org.jboss.mq.security.SecurityManager] Checking authorize on subjectInfo: SubjectInfo {subject=Subject:
              Principal: guest
              Principal: Roles(members:guest)
              ;principal=null;roles=Roles(members:guest) for rolePrincipals [guest]
              2004-05-02 16:17:19,051 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] About to add message 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 lateClone=false hashCode=9245412 transaction=null
              2004-05-02 16:17:19,051 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] Inserting message 1 msg=0 hard NOT_STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 lateClone=false hashCode=9245412 transaction=null
              2004-05-02 16:17:19,071 TRACE [org.jboss.mq.pm.jdbc2.PersistenceManager] Added message 1 msg=0 hard STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 lateClone=false hashCode=9245412 transaction=null
              2004-05-02 16:17:19,071 TRACE [org.jboss.mq.server.BasicQueue] addMessage 1 msg=0 hard STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 lateClone=false hashCode=9245412 null org.jboss.mq.server.PersistentQueue@1186cf9{QUEUE.ServerCodeInterfaceQueue}
              2004-05-02 16:17:19,081 TRACE [org.jboss.mq.server.BasicQueue] internalAddMessage 1 msg=0 hard STORED PERSISTENT queue=QUEUE.ServerCodeInterfaceQueue priority=4 lateClone=false hashCode=9245412 org.jboss.mq.server.PersistentQueue@1186cf9{QUEUE.ServerCodeInterfaceQueue}
              2004-05-02 16:17:19,081 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : addMessage
              2004-05-02 16:17:19,081 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.AddMsg32379995[msgType: m_addMessage, msgID: 6, error: null]
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.AddMsg32379995[msgType: m_addMessage, msgID: 6, error: null]
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_setEnabled, msgID: 7
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg6673800[msgType: m_setEnabled, msgID: 7, error: null]
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.AddMsg32379995[msgType: m_addMessage, msgID: 6, error: null]
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 2
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 14
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : setEnabled
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.server.TracingInterceptor] ARG : false
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.server.ClientConsumer] ClientConsumer:ID:1->setEnabled(enabled=false)
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : setEnabled
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg6673800[msgType: m_setEnabled, msgID: 7, error: null]
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.EnableConnectionMsg6673800[msgType: m_setEnabled, msgID: 7, error: null]
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.EnableConnectionMsg6673800[msgType: m_setEnabled, msgID: 7, error: null]
              2004-05-02 16:17:19,091 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 14
              2004-05-02 16:17:19,101 TRACE [org.jboss.mq.il.uil2.SocketManager] Read msgType: m_connectionClosing, msgID: 8
              2004-05-02 16:17:19,101 TRACE [org.jboss.mq.il.uil2.SocketManager] Read new msg: org.jboss.mq.il.uil2.msgs.CloseMsg20104469[msgType: m_connectionClosing, msgID: 8, error: null]
              2004-05-02 16:17:19,101 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Begin handleMsg, msgType: 5
              2004-05-02 16:17:19,101 TRACE [org.jboss.mq.server.TracingInterceptor] CALLED : connectionClosing
              2004-05-02 16:17:19,101 TRACE [org.jboss.mq.server.ClientConsumer] ClientConsumer:ID:1->close()
              2004-05-02 16:17:19,122 TRACE [org.jboss.mq.server.TracingInterceptor] RETURN : connectionClosing
              2004-05-02 16:17:19,122 TRACE [org.jboss.mq.il.uil2.SocketManager] Begin internalSendMessage, one-way msg=org.jboss.mq.il.uil2.msgs.CloseMsg20104469[msgType: m_connectionClosing, msgID: 8, error: null]
              2004-05-02 16:17:19,122 TRACE [org.jboss.mq.il.uil2.SocketManager] Write msg: org.jboss.mq.il.uil2.msgs.CloseMsg20104469[msgType: m_connectionClosing, msgID: 8, error: null]
              2004-05-02 16:17:19,122 TRACE [org.jboss.mq.il.uil2.SocketManager] Exiting on IOE
              java.io.EOFException
              at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2603)
              at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)
              at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:273)
              at java.lang.Thread.run(Thread.java:534)
              2004-05-02 16:17:19,132 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE
              java.io.EOFException
              at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2603)
              at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)
              at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:273)
              at java.lang.Thread.run(Thread.java:534)
              2004-05-02 16:17:19,132 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run
              2004-05-02 16:17:19,142 TRACE [org.jboss.mq.il.uil2.SocketManager] WriteTask was interrupted
              java.lang.InterruptedException
              at java.lang.Object.wait(Native Method)
              at java.lang.Object.wait(Object.java:429)
              at EDU.oswego.cs.dl.util.concurrent.LinkedQueue.take(LinkedQueue.java:122)
              at org.jboss.mq.il.uil2.SocketManager$WriteTask.run(SocketManager.java:468)
              at java.lang.Thread.run(Thread.java:534)
              2004-05-02 16:17:19,142 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run
              2004-05-02 16:17:19,182 TRACE [org.jboss.mq.il.uil2.SocketManager] End internalSendMessage, msg=org.jboss.mq.il.uil2.msgs.CloseMsg20104469[msgType: m_connectionClosing, msgID: 8, error: null]
              2004-05-02 16:17:19,182 TRACE [org.jboss.mq.il.uil2.ServerSocketManagerHandler] End handleMsg, msgType: 5



              ---ejb-ear.xml-----(P.S. I have tried it with containter transaction as well)--------
              <message-driven>
              CodeInterface EJB
              <ejb-name>CodeInterface</ejb-name>
              <ejb-class>org.cdmhope.member.services.CodeInterface</ejb-class>
              <transaction-type>Bean</transaction-type>
              <message-selector></message-selector>
              <acknowledge-mode>Auto-acknowledge</acknowledge-mode>
              <message-driven-destination>
              <destination-type>javax.jms.queue</destination-type>
              <subscription-durability>NonDurable</subscription-durability>
              </message-driven-destination>
              <resource-ref>
              <res-ref-name>jms/CodeInterfaceFactory</res-ref-name>
              <res-type>javax.jms.QueueConnectionFactory</res-type>
              <res-auth>Container</res-auth>
              </resource-ref>
              </message-driven>


              ----jboss.xml-----------(P.S. I have tried it with UIL2ConnectionFactory as well)------

              <message-driven>
              <ejb-name>CodeInterface</ejb-name>
              <destination-jndi-name>queue/ServerCodeInterfaceQueue</destination-jndi-name>
              <resource-ref>
              <res-ref-name>jms/CodeInterfaceFactory</res-ref-name>
              <jndi-name>ConnectionFactory</jndi-name>
              </resource-ref>
              </message-driven>


              ---jboss-mqdestinations-service--------------

              <depends optional-attribute-name="DestinationManager">jboss.mq:service=DestinationManager




              -----------------JNDI Name Spaces__________________________________________________________
              java:comp namespace of the CodeInterface bean:

              +- UserTransaction (class: javax.transaction.UserTransaction)
              +- env (class: org.jnp.interfaces.NamingContext)
              | +- jms (class: org.jnp.interfaces.NamingContext)
              | | +- CodeInterfaceFactory[link -> ConnectionFactory] (class: javax.naming.LinkRef)


              Global JNDI Namespace

              +- XAConnectionFactory (class: org.jboss.mq.SpyXAConnectionFactory)
              +- UIL2ConnectionFactory[link -> ConnectionFactory] (class: javax.naming.LinkRef)
              +- UserTransactionSessionFactory (proxy: $Proxy12 implements interface org.jboss.tm.usertx.interfaces.UserTransactionSessionFactory)
              +- HTTPConnectionFactory (class: org.jboss.mq.SpyConnectionFactory)
              +- UIL2XAConnectionFactory[link -> XAConnectionFactory] (class: javax.naming.LinkRef)
              +- console (class: org.jnp.interfaces.NamingContext)
              | +- PluginManager (proxy: $Proxy20 implements interface org.jboss.console.manager.PluginManagerMBean)
              +- UUIDKeyGeneratorFactory (class: org.jboss.ejb.plugins.keygenerator.uuid.UUIDKeyGeneratorFactory)
              +- topic (class: org.jnp.interfaces.NamingContext)
              | +- testDurableTopic (class: org.jboss.mq.SpyTopic)
              | +- testTopic (class: org.jboss.mq.SpyTopic)
              | +- securedTopic (class: org.jboss.mq.SpyTopic)
              +- HTTPXAConnectionFactory (class: org.jboss.mq.SpyXAConnectionFactory)
              +- queue (class: org.jnp.interfaces.NamingContext)
              | +- D (class: org.jboss.mq.SpyQueue)
              | +- C (class: org.jboss.mq.SpyQueue)
              | +- B (class: org.jboss.mq.SpyQueue)
              | +- ServerCodeInterfaceQueue (class: org.jboss.mq.SpyQueue)
              | +- A (class: org.jboss.mq.SpyQueue)
              | +- testQueue (class: org.jboss.mq.SpyQueue)
              | +- ex (class: org.jboss.mq.SpyQueue)
              | +- DLQ (class: org.jboss.mq.SpyQueue)
              | +- ClientCodeInterfaceQueue (class: org.jboss.mq.SpyQueue)
              +- ConnectionFactory (class: org.jboss.mq.SpyConnectionFactory)
              +- UserTransaction (class: org.jboss.tm.usertx.client.ClientUserTransaction)
              +- ejb (class: org.jnp.interfaces.NamingContext)
              | +- MemberServiceHomeRemote (proxy: $Proxy37 implements interface org.cdmhope.member.interfaces.MemberServiceHomeRemote,interface javax.ejb.Handle)
              +- invokers (class: org.jnp.interfaces.NamingContext)
              | +- 0.0.0.0 (class: org.jnp.interfaces.NamingContext)
              | | +- pooled (class: org.jboss.invocation.pooled.interfaces.PooledInvokerProxy)
              | +- rrcarey (class: org.jnp.interfaces.NamingContext)
              | | +- jrmp (class: org.jboss.invocation.jrmp.interfaces.JRMPInvokerProxy)
              | | +- http (class: org.jboss.invocation.http.interfaces.HttpInvokerProxy)
              +- jmx (class: org.jnp.interfaces.NamingContext)
              | +- invoker (class: org.jnp.interfaces.NamingContext)
              | | +- RMIAdaptor (proxy: $Proxy19 implements interface org.jboss.jmx.adaptor.rmi.RMIAdaptor,interface org.jboss.jmx.adaptor.rmi.RMIAdaptorExt)
              | +- rmi (class: org.jnp.interfaces.NamingContext)
              | | +- RMIAdaptor[link -> jmx/invoker/RMIAdaptor] (class: javax.naming.LinkRef)
              +- UILXAConnectionFactory[link -> XAConnectionFactory] (class: javax.naming.LinkRef)
              +- UILConnectionFactory[link -> ConnectionFactory] (class: javax.naming.LinkRef)


              • 4. Re: Cannot invoke MDB when message is sent.
                Adrian Brock Master

                Your problem is in ejb-jar.xml, note: Queue not queue.

                 <destination-type>javax.jms.Queue</destination-type>
                


                I'll add an error message for this as the problem isn't currently being trapped.


                • 5. Re: Cannot invoke MDB when message is sent.
                  Martin Husted Hartvig Newbie

                  What do you mean with "Your problem is in ejb-jar.xml, note: Queue not queue."? We are having problems where the mdb just stops receiving, it could sound like this, but looking in the "JBoss Administration and DevelopmentThird Edition (3.2.x Series)" page 321 "<destination-type>javax.jms.Queue</destination-type>" should be fine to use (we have see this problem from 2.4.4-2.4.10 and now also in 3.2.3). We have just noticed with twiddle that the ReceiversCount on a get jboss.mq.destination:name=ourQueue,service=Queue ReceiversCount returns 0. We don't know what we should do to fix it, next thing will be making a cron job that calls twiddle and grep'ing on 0, if it gives a result then we make a start invoke on the mdb. But this doesn't solve it, it's only a little help.

                  Regards,
                  Martin Husted Hartvig

                  • 6. Re: Cannot invoke MDB when message is sent.
                    Adrian Brock Master

                    That isn't what you originally reported, from your original post:

                    <destination-type>javax.jms.queue</destination-type>
                    


                    Anyway, the bug still exists in concurrent.jar (even the latest version).
                    http://cvs.sourceforge.net/viewcvs.py/jboss/jboss/src/main/org/jboss/jms/asf/ServerSessionPoolFactory.java?only_with_tag=JBoss_3_2_4_RC2

                    • 7. Re: Cannot invoke MDB when message is sent.
                      Adrian Brock Master

                      Hagar which part of "DO NOT HIJACK" DO YOU NOT UNDERSTAND

                      • 8. Re: Cannot invoke MDB when message is sent.
                        Martin Husted Hartvig Newbie

                        Sorry about that, but this have been/is a major problem for us and now we just found some hints in this topic. I btw. found the right topic:
                        http://www.jboss.org/index.html?module=bb&op=viewtopic&t=43951
                        I move my questions/attension to that topic.

                        Regards,
                        Martin