8 Replies Latest reply on Mar 2, 2006 9:35 PM by Elias Ross

    Message Driven Bean does not catch the message with onMessag

    arabin Novice

      I am using 3.2.2RC3
      I used the following to define my message driven bean in standardjboss.xml:
      ----------------------------------------------------
      <container-configuration>
      <container-name>Singleton Message Driven Bean</container-name>
      <call-logging>false</call-logging>
      <container-invoker>org.jboss.ejb.plugins.jms.JMSContainerInvoker</container-invoker>
      <container-interceptors>
      org.jboss.ejb.plugins.LogInterceptor
      org.jboss.ejb.plugins.RunAsSecurityInterceptor
      <!-- CMT -->

      org.jboss.ejb.plugins.TxInterceptorCMT
      org.jboss.ejb.plugins.MetricsInterceptor
      org.jboss.ejb.plugins.MessageDrivenInstanceInterceptor
      <!-- BMT -->

      org.jboss.ejb.plugins.MessageDrivenInstanceInterceptor
      org.jboss.ejb.plugins.MessageDrivenTxInterceptorBMT
      org.jboss.ejb.plugins.MetricsInterceptor
      org.jboss.resource.connectionmanager.CachedConnectionInterceptor
      </container-interceptors>
      <client-interceptors>

      org.jboss.proxy.ejb.HomeInterceptor
      org.jboss.proxy.SecurityInterceptor
      org.jboss.proxy.TransactionInterceptor
      org.jboss.invocation.InvokerInterceptor


      new org.jboss.proxy.ejb.StatelessSessionInterceptor
      org.jboss.proxy.SecurityInterceptor
      org.jboss.proxy.TransactionInterceptor
      org.jboss.invocation.InvokerInterceptor

      </client-interceptors>
      <instance-pool>org.jboss.ejb.plugins.MessageDrivenInstancePool</instance-pool>
      <instance-cache/>
      <persistence-manager/>
      <transaction-manager>org.jboss.tm.TxManager</transaction-manager>
      <container-invoker-conf>
      DefaultJMSProvider
      StdJMSPool
      15
      1

      10

      queue/DLQ
      10
      0


      </container-invoker-conf>
      <container-pool-conf>
      1
      </container-pool-conf>
      </container-configuration>
      ----------------------------------------------------

      My ejb-jar.xml contains the following lines:
      ----------------------------------------------------
      <message-driven >
      <![CDATA[This is a Message Driven Bean based on a Topic.]]>

      <ejb-name>jms/RetranslateMessage</ejb-name>

      <ejb-class>com.teamworks.mdb.RetranslatorMessageBean</ejb-class>

      <transaction-type>Bean</transaction-type>
      <acknowledge-mode>Auto-acknowledge</acknowledge-mode>
      <message-driven-destination>
      <destination-type>javax.jms.Queue</destination-type>
      <subscription-durability>NonDurable</subscription-durability>
      </message-driven-destination>

      </message-driven>
      ----------------------------------------------------

      I have a queue with jndi name queue/DLQ

      I am sending messages to the queue/DLQ from a client attached to JBoss.

      My message driven bean looks like this:
      ---------------------------------------------------
      package com.teamworks.mdb;

      import javax.ejb.MessageDrivenBean;
      import javax.ejb.MessageDrivenContext;
      import javax.ejb.EJBException;
      import java.util.Collection;
      import java.util.Iterator;
      import java.rmi.RemoteException;
      import javax.jms.JMSException;

      import javax.naming.*;
      import javax.jms.*;

      /**
      * This is a Message Driven Bean based on a Topic.
      *
      *
      * @ejb.bean name="jms/RetranslateMessage"
      * transaction-type="Bean"
      * destination-type="javax.jms.Queue"
      * subscription-durability="NonDurable"
      * acknowledge-mode="Auto-acknowledge"
      *
      *
      * @jboss.destination-jndi-name "queue/DLQ"
      *
      *
      *
      * @jboss.container-configuration name="Singleton Message Driven Bean"
      *** for message ordering
      */

      public class RetranslatorMessageBean implements MessageDrivenBean, MessageListener
      {
      private MessageDrivenContext ctx = null;

      public void setMessageDrivenContext (MessageDrivenContext ctx)
      {
      this.ctx = ctx;
      }

      /**
      * @ejb.create-method
      */
      public void ejbCreate()
      {
      }

      public void ejbRemove()
      {
      ctx = null;
      }

      /**
      *
      */

      public void onMessage ( Message message )
      {
      try
      {
      ctx.getUserTransaction().begin();

      // process message
      TextMessage tm = (TextMessage) message;
      try
      {
      System.out.println("onMessage, MDB recv text="
      + tm.getText());
      }
      catch(Throwable t)
      {
      t.printStackTrace();
      }

      ctx.getUserTransaction().commit ();
      }
      catch (Exception e)
      {
      try
      {
      ctx.getUserTransaction().rollback();
      }
      catch (javax.transaction.SystemException se) {}

      // it will not acknowledgw the message forcing jms provider to redeliver
      throw new EJBException (e);
      }
      }
      }
      ---------------------------------------------------

      The method onMessage() never gets the message.

      My server.log file is enclosed. I am not really sure my message driven bean is deployed. But there are no error messages or exceptions.

      What is wrong with my message driven bean?

      My server console output file is the following:
      ---------------------------------------------------------------
      2003-09-14 08:22:48,749 DEBUG [org.jboss.logging.Log4jService] Installed System.out adapter
      2003-09-14 08:22:48,749 DEBUG [org.jboss.logging.Log4jService] Installed System.err adapter
      2003-09-14 08:22:48,759 DEBUG [org.jboss.logging.Log4jService] Added ThrowableListener: org.jboss.logging.Log4jService$ThrowableListenerLoggingAdapter@14e8cee
      2003-09-14 08:22:48,759 INFO [org.jboss.logging.Log4jService] Created
      2003-09-14 08:22:48,759 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=5,timeStamp=1063545768759,message=null,userData=jboss.system:type=Log4jService,service=Logging]
      2003-09-14 08:22:48,759 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=5,timeStamp=1063545768759,message=null,userData=jboss.system:type=Log4jService,service=Logging]
      2003-09-14 08:22:48,759 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.system:type=Log4jService,service=Logging dependents are: []
      2003-09-14 08:22:48,759 DEBUG [org.jboss.system.ServiceController] Creating service jboss.rmi:type=RMIClassLoader
      2003-09-14 08:22:48,769 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=6,timeStamp=1063545768769,message=null,userData=jboss.rmi:type=RMIClassLoader]
      2003-09-14 08:22:48,769 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=6,timeStamp=1063545768769,message=null,userData=jboss.rmi:type=RMIClassLoader]
      2003-09-14 08:22:48,769 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.rmi:type=RMIClassLoader dependents are: []
      2003-09-14 08:22:48,769 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=WebService
      2003-09-14 08:22:48,769 INFO [org.jboss.web.WebService] Creating
      2003-09-14 08:22:48,779 INFO [org.jboss.web.WebService] Created
      2003-09-14 08:22:48,779 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=7,timeStamp=1063545768779,message=null,userData=jboss:service=WebService]
      2003-09-14 08:22:48,779 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=7,timeStamp=1063545768779,message=null,userData=jboss:service=WebService]
      2003-09-14 08:22:48,779 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=WebService dependents are: [ObjectName: jboss.ejb:service=EJBDeployer
      state: CONFIGURED
      I Depend On: jboss:service=TransactionManager
      jboss:service=WebService

      Depends On Me: ]
      2003-09-14 08:22:48,779 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:48,779 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb:service=EJBDeployer waiting on jboss:service=TransactionManager
      2003-09-14 08:22:48,779 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=Naming
      2003-09-14 08:22:48,779 INFO [org.jboss.naming.NamingService] Creating
      2003-09-14 08:22:48,779 INFO [org.jboss.naming.NamingService] Created
      2003-09-14 08:22:48,779 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=8,timeStamp=1063545768779,message=null,userData=jboss:service=Naming]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.JNDIResource] Service name: jboss:service=Naming
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.JNDIResource] postRegister(), parent: jboss.management.local:j2eeType=J2EEServer,name=Local
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.JNDIResource] Created JSR-77 JNDIResource: LocalJNDI
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=Naming dependents are: []
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=JNDIView
      2003-09-14 08:22:48,799 INFO [org.jboss.naming.JNDIView] Creating
      2003-09-14 08:22:48,799 INFO [org.jboss.naming.JNDIView] Created
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=9,timeStamp=1063545768799,message=null,userData=jboss:service=JNDIView]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=9,timeStamp=1063545768799,message=null,userData=jboss:service=JNDIView]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=JNDIView dependents are: []
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=SecurityConfig
      2003-09-14 08:22:48,799 INFO [org.jboss.security.plugins.SecurityConfig] Creating
      2003-09-14 08:22:48,799 INFO [org.jboss.security.plugins.SecurityConfig] Created
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=10,timeStamp=1063545768799,message=null,userData=jboss.security:service=SecurityConfig]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=10,timeStamp=1063545768799,message=null,userData=jboss.security:service=SecurityConfig]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=SecurityConfig dependents are: []
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=XMLLoginConfig
      2003-09-14 08:22:48,799 INFO [org.jboss.security.auth.login.XMLLoginConfig] Creating
      2003-09-14 08:22:48,799 INFO [org.jboss.security.auth.login.XMLLoginConfig] Created
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=11,timeStamp=1063545768799,message=null,userData=jboss.security:service=XMLLoginConfig]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=11,timeStamp=1063545768799,message=null,userData=jboss.security:service=XMLLoginConfig]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=XMLLoginConfig dependents are: []
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=JaasSecurityManager
      2003-09-14 08:22:48,799 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Creating
      2003-09-14 08:22:48,799 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Created
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=12,timeStamp=1063545768799,message=null,userData=jboss.security:service=JaasSecurityManager]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=12,timeStamp=1063545768799,message=null,userData=jboss.security:service=JaasSecurityManager]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=JaasSecurityManager dependents are: []
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=XidFactory
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=13,timeStamp=1063545768799,message=null,userData=jboss:service=XidFactory]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=13,timeStamp=1063545768799,message=null,userData=jboss:service=XidFactory]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=XidFactory dependents are: [ObjectName: jboss:service=TransactionManager
      state: CONFIGURED
      I Depend On: jboss:service=XidFactory

      Depends On Me: jboss.ejb:service=EJBDeployer
      jboss:service=invoker,type=jrmp
      jboss:service=invoker,type=local
      jboss:service=invoker,type=pooled
      ]
      2003-09-14 08:22:48,799 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=TransactionManager
      2003-09-14 08:22:48,799 INFO [org.jboss.tm.TransactionManagerService] Creating
      2003-09-14 08:22:48,809 INFO [org.jboss.tm.TransactionManagerService] Created
      2003-09-14 08:22:48,809 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=14,timeStamp=1063545768809,message=null,userData=jboss:service=TransactionManager]
      2003-09-14 08:22:48,809 DEBUG [org.jboss.management.j2ee.JTAResource] Service name: jboss:service=TransactionManager
      2003-09-14 08:22:48,809 DEBUG [org.jboss.management.j2ee.JTAResource] postRegister(), parent: jboss.management.local:j2eeType=J2EEServer,name=Local
      2003-09-14 08:22:48,809 DEBUG [org.jboss.management.j2ee.JTAResource] Created JSR-77 JTAResource: TransactionManager
      2003-09-14 08:22:48,809 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=TransactionManager dependents are: [ObjectName: jboss.ejb:service=EJBDeployer
      state: CONFIGURED
      I Depend On: jboss:service=TransactionManager
      jboss:service=WebService

      Depends On Me: , ObjectName: jboss:service=invoker,type=jrmp
      state: CONFIGURED
      I Depend On: jboss:service=TransactionManager

      Depends On Me: , ObjectName: jboss:service=invoker,type=local
      state: CONFIGURED
      I Depend On: jboss:service=TransactionManager

      Depends On Me: , ObjectName: jboss:service=invoker,type=pooled
      state: CONFIGURED
      I Depend On: jboss:service=TransactionManager

      Depends On Me: ]
      2003-09-14 08:22:48,809 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:48,809 INFO [org.jboss.ejb.EJBDeployer] Creating
      2003-09-14 08:22:48,829 INFO [org.jboss.ejb.EJBDeployer] Created
      2003-09-14 08:22:48,829 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=15,timeStamp=1063545768829,message=null,userData=jboss.ejb:service=EJBDeployer]
      2003-09-14 08:22:48,829 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.ejb:service=EJBDeployer dependents are: []
      2003-09-14 08:22:48,829 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=jrmp
      2003-09-14 08:22:48,829 INFO [org.jboss.invocation.jrmp.server.JRMPInvoker] Creating
      2003-09-14 08:22:48,829 INFO [org.jboss.invocation.jrmp.server.JRMPInvoker] Created
      2003-09-14 08:22:48,829 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=16,timeStamp=1063545768829,message=null,userData=jboss:service=invoker,type=jrmp]
      2003-09-14 08:22:48,829 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=16,timeStamp=1063545768829,message=null,userData=jboss:service=invoker,type=jrmp]
      2003-09-14 08:22:48,829 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=jrmp dependents are: []
      2003-09-14 08:22:48,829 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=local
      2003-09-14 08:22:48,829 INFO [org.jboss.invocation.local.LocalInvoker] Creating
      2003-09-14 08:22:48,839 INFO [org.jboss.invocation.local.LocalInvoker] Created
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=17,timeStamp=1063545768839,message=null,userData=jboss:service=invoker,type=local]
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=17,timeStamp=1063545768839,message=null,userData=jboss:service=invoker,type=local]
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=local dependents are: []
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=pooled
      2003-09-14 08:22:48,839 INFO [org.jboss.invocation.pooled.server.PooledInvoker] Creating
      2003-09-14 08:22:48,839 INFO [org.jboss.invocation.pooled.server.PooledInvoker] Created
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=18,timeStamp=1063545768839,message=null,userData=jboss:service=invoker,type=pooled]
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=18,timeStamp=1063545768839,message=null,userData=jboss:service=invoker,type=pooled]
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=pooled dependents are: []
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=TransactionManager
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=TransactionManager
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=ClientUserTransaction
      2003-09-14 08:22:48,839 INFO [org.jboss.tm.usertx.server.ClientUserTransactionService] Creating
      2003-09-14 08:22:48,839 INFO [org.jboss.tm.usertx.server.ClientUserTransactionService] Created
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=19,timeStamp=1063545768839,message=null,userData=jboss:service=ClientUserTransaction]
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.JTAResource] Service name: jboss:service=ClientUserTransaction
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.JTAResource] postRegister(), parent: jboss.management.local:j2eeType=J2EEServer,name=Local
      2003-09-14 08:22:48,839 DEBUG [org.jboss.management.j2ee.JTAResource] Created JSR-77 JTAResource: ClientUserTransaction
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=ClientUserTransaction dependents are: []
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:48,839 DEBUG [org.jboss.system.ServiceController] Creating service jboss.j2ee:service=EARDeployer
      2003-09-14 08:22:48,839 INFO [org.jboss.deployment.EARDeployer] Creating
      2003-09-14 08:22:48,859 INFO [org.jboss.deployment.EARDeployer] Created
      2003-09-14 08:22:48,859 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=20,timeStamp=1063545768859,message=null,userData=jboss.j2ee:service=EARDeployer]
      2003-09-14 08:22:48,859 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.j2ee:service=EARDeployer dependents are: []
      2003-09-14 08:22:48,859 DEBUG [org.jboss.system.ServiceController] Creating service jboss.scripts:service=BSHDeployer
      2003-09-14 08:22:48,859 INFO [org.jboss.varia.deployment.BeanShellSubDeployer] Creating
      2003-09-14 08:22:48,869 INFO [org.jboss.varia.deployment.BeanShellSubDeployer] Created
      2003-09-14 08:22:48,869 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=21,timeStamp=1063545768869,message=null,userData=jboss.scripts:service=BSHDeployer]
      2003-09-14 08:22:48,869 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=21,timeStamp=1063545768869,message=null,userData=jboss.scripts:service=BSHDeployer]
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.scripts:service=BSHDeployer dependents are: []
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=jrmp
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=jrmp
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=local
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=local
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=pooled
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=pooled
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Creating service jboss.deployment:type=DeploymentScanner,flavor=URL
      2003-09-14 08:22:48,869 INFO [org.jboss.deployment.scanner.URLDeploymentScanner] Creating
      2003-09-14 08:22:48,869 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Scanner thread started
      2003-09-14 08:22:48,869 INFO [org.jboss.deployment.scanner.URLDeploymentScanner] Created
      2003-09-14 08:22:48,869 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=22,timeStamp=1063545768869,message=null,userData=jboss.deployment:type=DeploymentScanner,flavor=URL]
      2003-09-14 08:22:48,869 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=22,timeStamp=1063545768869,message=null,userData=jboss.deployment:type=DeploymentScanner,flavor=URL]
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.deployment:type=DeploymentScanner,flavor=URL dependents are: []
      2003-09-14 08:22:48,869 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceDeployer,type=org.jboss.deployment.SubDeployer.create,sequenceNumber=4,timeStamp=1063545768869,message=null,userData=org.jboss.deployment.DeploymentInfo@d5899ee9 { url=file:/C:/jboss-3.2.2RC3/server/default/conf/jboss-service.xml }
      deployer: org.jboss.deployment.SARDeployer@134a7d8
      status: null
      state: CREATE_DEPLOYER
      watch: file:/C:/jboss-3.2.2RC3/server/default/conf/jboss-service.xml
      lastDeployed: 1063545767066
      lastModified: 1063545766696
      mbeans:
      jboss.management.local:j2eeType=J2EEDomain,name=Manager (state not available)
      jboss.system:type=Log4jService,service=Logging state: Created
      jboss.rmi:type=RMIClassLoader (state not available)
      jboss:service=WebService state: Created
      jboss:service=Naming state: Created
      jboss:service=JNDIView state: Created
      jboss.security:service=SecurityConfig state: Created
      jboss.security:service=XMLLoginConfig state: Created
      jboss.security:service=JaasSecurityManager state: Created
      jboss:service=XidFactory (state not available)
      jboss:service=TransactionManager state: Created
      jboss:service=ClientUserTransaction state: Created
      jboss.ejb:service=EJBDeployer state: Created
      jboss.j2ee:service=EARDeployer state: Created
      jboss.scripts:service=BSHDeployer state: Created
      jboss:service=invoker,type=jrmp state: Created
      jboss:service=invoker,type=local state: Created
      jboss:service=invoker,type=pooled state: Created
      jboss.deployment:type=DeploymentScanner,flavor=URL state: Created
      ]
      2003-09-14 08:22:48,869 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying jboss-service.xml
      2003-09-14 08:22:48,869 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/C:/jboss-3.2.2RC3/server/default/conf/jboss-service.xml
      2003-09-14 08:22:48,869 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, start step: url file:/C:/jboss-3.2.2RC3/server/default/conf/jboss-service.xml
      2003-09-14 08:22:48,869 DEBUG [org.jboss.system.ServiceController] starting service jboss.management.local:j2eeType=J2EEDomain,name=Manager
      2003-09-14 08:22:48,869 INFO [org.jboss.management.j2ee.LocalJBossServerDomain] Starting
      2003-09-14 08:22:48,879 INFO [org.jboss.management.j2ee.LocalJBossServerDomain] Started
      2003-09-14 08:22:48,879 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.management.local:j2eeType=J2EEDomain,name=Manager dependent components: []
      2003-09-14 08:22:48,879 DEBUG [org.jboss.system.ServiceController] starting service jboss.system:type=Log4jService,service=Logging
      2003-09-14 08:22:48,879 INFO [org.jboss.logging.Log4jService] Starting
      2003-09-14 08:22:48,879 INFO [org.jboss.logging.Log4jService] Started
      2003-09-14 08:22:48,879 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.system:type=Log4jService,service=Logging dependent components: []
      2003-09-14 08:22:48,879 DEBUG [org.jboss.system.ServiceController] starting service jboss.rmi:type=RMIClassLoader
      2003-09-14 08:22:48,879 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.rmi:type=RMIClassLoader dependent components: []
      2003-09-14 08:22:48,879 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=WebService
      2003-09-14 08:22:48,879 INFO [org.jboss.web.WebService] Starting
      2003-09-14 08:22:48,879 INFO [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Running
      2003-09-14 08:22:48,879 DEBUG [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Disabled, waiting for notification
      2003-09-14 08:22:48,889 DEBUG [org.jboss.web.WebServer] Started server: ServerSocket[addr=AL-9NYHWKOJ2Y/192.168.0.104,port=0,localport=8083]
      2003-09-14 08:22:48,889 INFO [org.jboss.web.WebService] Started WebServer with address: 192.168.0.104:8083
      2003-09-14 08:22:48,889 INFO [org.jboss.web.WebService] Using RMI server codebase: http://AL-9NYHWKOJ2Y:8083/
      2003-09-14 08:22:48,889 INFO [org.jboss.web.WebService] Started
      2003-09-14 08:22:48,889 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=WebService dependent components: [ObjectName: jboss.ejb:service=EJBDeployer
      state: CREATED
      I Depend On: jboss:service=TransactionManager
      jboss:service=WebService

      Depends On Me: ]
      2003-09-14 08:22:48,889 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:48,889 DEBUG [org.jboss.system.ServiceController] waiting in start jboss.ejb:service=EJBDeployer on jboss:service=TransactionManager
      2003-09-14 08:22:48,889 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=Naming
      2003-09-14 08:22:48,889 INFO [org.jboss.naming.NamingService] Starting
      2003-09-14 08:22:48,889 DEBUG [org.jboss.naming.NamingService] System.setProperty, key=java.naming.factory.initial, value=org.jnp.interfaces.NamingContextFactory
      2003-09-14 08:22:48,889 DEBUG [org.jboss.naming.NamingService] System.setProperty, key=java.naming.factory.url.pkgs, value=org.jboss.naming:org.jnp.interfaces
      2003-09-14 08:22:48,889 INFO [org.jboss.naming.NamingService] Starting jnp server
      2003-09-14 08:22:48,939 DEBUG [org.jboss.naming.NamingService] NamingServer stub: org.jnp.server.NamingServer_Stub[RemoteStub [ref: [endpoint:[192.168.0.104:1547](local),objID:[0]]]]
      2003-09-14 08:22:48,959 INFO [org.jboss.naming.NamingService] Started jnpPort=1099, rmiPort=0, backlog=50, bindAddress=null, Client SocketFactory=null, Server SocketFactory=null
      2003-09-14 08:22:48,969 DEBUG [org.jboss.naming.NamingService] InitialContext Environment:
      2003-09-14 08:22:48,969 DEBUG [org.jboss.naming.NamingService] key=java.naming.factory.initial, value=org.jnp.interfaces.NamingContextFactory
      2003-09-14 08:22:48,969 DEBUG [org.jboss.naming.NamingService] key=java.naming.factory.url.pkgs, value=org.jboss.naming:org.jnp.interfaces:org.jboss.naming:org.jnp.interfaces
      2003-09-14 08:22:48,979 INFO [org.jboss.naming.NamingService] Listening on port 1099
      2003-09-14 08:22:48,989 INFO [org.jboss.naming.NamingService] Started
      2003-09-14 08:22:48,989 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=Naming dependent components: []
      2003-09-14 08:22:48,989 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=JNDIView
      2003-09-14 08:22:48,989 INFO [org.jboss.naming.JNDIView] Starting
      2003-09-14 08:22:48,989 INFO [org.jboss.naming.JNDIView] Started
      2003-09-14 08:22:48,989 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=JNDIView dependent components: []
      2003-09-14 08:22:48,989 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=SecurityConfig
      2003-09-14 08:22:48,989 INFO [org.jboss.security.plugins.SecurityConfig] Starting
      2003-09-14 08:22:48,999 DEBUG [org.jboss.security.plugins.SecurityConfig] Installed JAAS Configuration service=jboss.security:service=XMLLoginConfig, config=org.jboss.security.auth.login.XMLLoginConfigImpl@4ee70b
      2003-09-14 08:22:48,999 INFO [org.jboss.security.plugins.SecurityConfig] Started
      2003-09-14 08:22:48,999 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=SecurityConfig dependent components: []
      2003-09-14 08:22:48,999 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=XMLLoginConfig
      2003-09-14 08:22:48,999 INFO [org.jboss.security.auth.login.XMLLoginConfig] Starting
      2003-09-14 08:22:48,999 DEBUG [org.jboss.security.auth.login.XMLLoginConfigImpl] Try loading config as XML, url=file:/C:/jboss-3.2.2RC3/server/default/conf/login-config.xml
      2003-09-14 08:22:49,069 INFO [org.jboss.security.auth.login.XMLLoginConfig] Started
      2003-09-14 08:22:49,069 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=XMLLoginConfig dependent components: []
      2003-09-14 08:22:49,069 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=JaasSecurityManager
      2003-09-14 08:22:49,069 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Starting
      2003-09-14 08:22:49,069 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] securityMgrCtxPath=java:/jaas
      2003-09-14 08:22:49,069 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] cachePolicyCtxPath=java:/timedCacheFactory
      2003-09-14 08:22:49,079 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] SecurityProxyFactory=org.jboss.security.SubjectSecurityProxyFactory@14835fb
      2003-09-14 08:22:49,079 INFO [org.jboss.security.plugins.JaasSecurityManagerService] Started
      2003-09-14 08:22:49,079 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=JaasSecurityManager dependent components: []
      2003-09-14 08:22:49,079 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=XidFactory
      2003-09-14 08:22:49,079 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=XidFactory dependent components: [ObjectName: jboss:service=TransactionManager
      state: CREATED
      I Depend On: jboss:service=XidFactory

      Depends On Me: jboss.ejb:service=EJBDeployer
      jboss:service=invoker,type=jrmp
      jboss:service=invoker,type=local
      jboss:service=invoker,type=pooled
      ]
      2003-09-14 08:22:49,079 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=TransactionManager
      2003-09-14 08:22:49,079 INFO [org.jboss.tm.TransactionManagerService] Starting
      2003-09-14 08:22:49,099 INFO [org.jboss.tm.TransactionManagerService] Started
      2003-09-14 08:22:49,109 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=TransactionManager dependent components: [ObjectName: jboss.ejb:service=EJBDeployer
      state: CREATED
      I Depend On: jboss:service=TransactionManager
      jboss:service=WebService

      Depends On Me: , ObjectName: jboss:service=invoker,type=jrmp
      state: CREATED
      I Depend On: jboss:service=TransactionManager

      Depends On Me: , ObjectName: jboss:service=invoker,type=local
      state: CREATED
      I Depend On: jboss:service=TransactionManager

      Depends On Me: , ObjectName: jboss:service=invoker,type=pooled
      state: CREATED
      I Depend On: jboss:service=TransactionManager

      Depends On Me: ]
      2003-09-14 08:22:49,109 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:49,109 INFO [org.jboss.ejb.EJBDeployer] Starting
      2003-09-14 08:22:49,119 INFO [org.jboss.deployment.MainDeployer] Adding deployer: org.jboss.ejb.EJBDeployer@c623af
      2003-09-14 08:22:49,119 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=MainDeployer,type=org.jboss.deployment.MainDeployer.addDeployer,sequenceNumber=5,timeStamp=1063545769119,message=null,userData=jboss.ejb:service=EJBDeployer]
      2003-09-14 08:22:49,119 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] Registering as listener of deployer: jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:49,119 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.AttributeChangeNotification: source=jboss.ejb:service=EJBDeployer seq-no=2 time=1063545769119 message=EJBDeployer started attributeName=State attributeType=java.lang.Integer oldValue=2 newValue=3 notificationType=jmx.attribute.change userData=null
      2003-09-14 08:22:49,119 INFO [org.jboss.ejb.EJBDeployer] Started
      2003-09-14 08:22:49,119 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.ejb:service=EJBDeployer dependent components: []
      2003-09-14 08:22:49,119 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=jrmp
      2003-09-14 08:22:49,119 INFO [org.jboss.invocation.jrmp.server.JRMPInvoker] Starting
      2003-09-14 08:22:49,119 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] RMI Port='Anonymous'
      2003-09-14 08:22:49,119 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Client SocketFactory='Default'
      2003-09-14 08:22:49,119 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Server SocketFactory='Default'
      2003-09-14 08:22:49,119 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Server SocketAddr='Default'
      2003-09-14 08:22:49,119 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] SecurityDomain='Default'
      2003-09-14 08:22:49,149 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Bound JRMP invoker for JMX node
      2003-09-14 08:22:49,149 INFO [org.jboss.invocation.jrmp.server.JRMPInvoker] Started
      2003-09-14 08:22:49,149 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=jrmp dependent components: []
      2003-09-14 08:22:49,149 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=local
      2003-09-14 08:22:49,149 INFO [org.jboss.invocation.local.LocalInvoker] Starting
      2003-09-14 08:22:49,159 DEBUG [org.jboss.invocation.local.LocalInvoker] Local invoker for JMX node started
      2003-09-14 08:22:49,159 INFO [org.jboss.invocation.local.LocalInvoker] Started
      2003-09-14 08:22:49,159 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=local dependent components: []
      2003-09-14 08:22:49,159 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=pooled
      2003-09-14 08:22:49,159 INFO [org.jboss.invocation.pooled.server.PooledInvoker] Starting
      2003-09-14 08:22:49,179 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Bound invoker for JMX node
      2003-09-14 08:22:49,179 INFO [org.jboss.invocation.pooled.server.PooledInvoker] Started
      2003-09-14 08:22:49,179 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=pooled dependent components: []
      2003-09-14 08:22:49,179 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=TransactionManager
      2003-09-14 08:22:49,179 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=TransactionManager
      2003-09-14 08:22:49,179 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=ClientUserTransaction
      2003-09-14 08:22:49,179 INFO [org.jboss.tm.usertx.server.ClientUserTransactionService] Starting
      2003-09-14 08:22:49,199 INFO [org.jboss.tm.usertx.server.ClientUserTransactionService] Started
      2003-09-14 08:22:49,199 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=ClientUserTransaction dependent components: []
      2003-09-14 08:22:49,199 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:49,199 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss.ejb:service=EJBDeployer
      2003-09-14 08:22:49,199 DEBUG [org.jboss.system.ServiceController] starting service jboss.j2ee:service=EARDeployer
      2003-09-14 08:22:49,199 INFO [org.jboss.deployment.EARDeployer] Starting
      2003-09-14 08:22:49,199 INFO [org.jboss.deployment.MainDeployer] Adding deployer: org.jboss.deployment.EARDeployer@d56b37
      2003-09-14 08:22:49,209 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=MainDeployer,type=org.jboss.deployment.MainDeployer.addDeployer,sequenceNumber=6,timeStamp=1063545769209,message=null,userData=jboss.j2ee:service=EARDeployer]
      2003-09-14 08:22:49,209 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] Registering as listener of deployer: jboss.j2ee:service=EARDeployer
      2003-09-14 08:22:49,209 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.AttributeChangeNotification: source=jboss.j2ee:service=EARDeployer seq-no=2 time=1063545769209 message=EARDeployer started attributeName=State attributeType=java.lang.Integer oldValue=2 newValue=3 notificationType=jmx.attribute.change userData=null
      2003-09-14 08:22:49,209 INFO [org.jboss.deployment.EARDeployer] Started
      2003-09-14 08:22:49,209 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.j2ee:service=EARDeployer dependent components: []
      2003-09-14 08:22:49,209 DEBUG [org.jboss.system.ServiceController] starting service jboss.scripts:service=BSHDeployer
      2003-09-14 08:22:49,209 INFO [org.jboss.varia.deployment.BeanShellSubDeployer] Starting
      2003-09-14 08:22:49,219 INFO [org.jboss.deployment.MainDeployer] Adding deployer: org.jboss.varia.deployment.BeanShellSubDeployer@1947496
      2003-09-14 08:22:49,219 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=MainDeployer,type=org.jboss.deployment.MainDeployer.addDeployer,sequenceNumber=7,timeStamp=1063545769219,message=null,userData=jboss.scripts:service=BSHDeployer]
      2003-09-14 08:22:49,219 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] Registering as listener of deployer: jboss.scripts:service=BSHDeployer
      2003-09-14 08:22:49,219 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.AttributeChangeNotification: source=jboss.scripts:service=BSHDeployer seq-no=2 time=1063545769219 message=BeanShellSubDeployer started attributeName=State attributeType=java.lang.Integer oldValue=2 newValue=3 notificationType=jmx.attribute.change userData=null
      2003-09-14 08:22:49,219 INFO [org.jboss.varia.deployment.BeanShellSubDeployer] Started
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.scripts:service=BSHDeployer dependent components: []
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=jrmp
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=jrmp
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=local
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=local
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=pooled
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=pooled
      2003-09-14 08:22:49,219 DEBUG [org.jboss.system.ServiceController] starting service jboss.deployment:type=DeploymentScanner,flavor=URL
      2003-09-14 08:22:49,219 INFO [org.jboss.deployment.scanner.URLDeploymentScanner] Starting
      2003-09-14 08:22:49,229 INFO [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,239 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,239 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.SARDeployer@134a7d8
      2003-09-14 08:22:49,249 DEBUG [org.jboss.deployment.SARDeployer] about to copy 0 local directories
      2003-09-14 08:22:49,249 DEBUG [org.jboss.deployment.SARDeployer] looking for nested deployments in : file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,249 INFO [org.jboss.deployment.SARDeployer] nested deployment: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/invoker.war/
      2003-09-14 08:22:49,249 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceDeployer,type=org.jboss.deployment.SubDeployer.init,sequenceNumber=5,timeStamp=1063545769249,message=null,userData=org.jboss.deployment.DeploymentInfo@aa66a8 { url=file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/ }
      deployer: org.jboss.deployment.SARDeployer@134a7d8
      status: null
      state: INIT_DEPLOYER
      watch: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/META-INF/jboss-service.xml
      lastDeployed: 0
      lastModified: 0
      mbeans:
      ]
      2003-09-14 08:22:49,249 DEBUG [org.jboss.deployment.DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
      2003-09-14 08:22:49,249 DEBUG [org.jboss.mx.loading.UnifiedClassLoader] New jmx UCL with url file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.mx.loading.UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@1b4a74b{ url=file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/ ,addedOrder=0}
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] found 1 subpackages of file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/invoker.war/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] No deployer found for url: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/invoker.war/
      2003-09-14 08:22:49,259 INFO [org.jboss.deployment.MainDeployer] deployment waiting for deployer: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/invoker.war/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/invoker.war/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/invoker.war/
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.MainDeployer] Still no deployer for package in create step: invoker.war
      2003-09-14 08:22:49,259 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, create step: url file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,269 DEBUG [org.jboss.deployment.SARDeployer] Registering service UCL=jmx.loading:UCL=1b4a74b
      2003-09-14 08:22:49,269 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss:service=invoker,type=http with code: org.jboss.invocation.http.server.HttpInvoker
      2003-09-14 08:22:49,269 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss:service=invoker,type=http
      2003-09-14 08:22:49,269 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLPrefix set to http:// in jboss:service=invoker,type=http
      2003-09-14 08:22:49,279 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLSuffix set to :8080/invoker/EJBInvokerServlet in jboss:service=invoker,type=http
      2003-09-14 08:22:49,279 DEBUG [org.jboss.system.ServiceConfigurator] UseHostName set to true in jboss:service=invoker,type=http
      2003-09-14 08:22:49,279 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss:service=invoker,type=httpHA with code: org.jboss.invocation.http.server.HttpInvokerHA
      2003-09-14 08:22:49,289 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss:service=invoker,type=httpHA
      2003-09-14 08:22:49,289 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLPrefix set to http:// in jboss:service=invoker,type=httpHA
      2003-09-14 08:22:49,299 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLSuffix set to :8080/invoker/EJBInvokerHAServlet in jboss:service=invoker,type=httpHA
      2003-09-14 08:22:49,299 DEBUG [org.jboss.system.ServiceConfigurator] UseHostName set to true in jboss:service=invoker,type=httpHA
      2003-09-14 08:22:49,299 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss:service=invoker,type=http,target=Naming with code: org.jboss.invocation.http.server.HttpProxyFactory
      2003-09-14 08:22:49,299 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,309 DEBUG [org.jboss.system.ServiceConfigurator] InvokerName set to jboss:service=Naming in jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,309 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLPrefix set to http:// in jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,309 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLSuffix set to :8080/invoker/JMXInvokerServlet in jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,309 DEBUG [org.jboss.system.ServiceConfigurator] UseHostName set to true in jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,319 DEBUG [org.jboss.system.ServiceConfigurator] ExportedInterface set to interface org.jnp.interfaces.Naming in jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,319 DEBUG [org.jboss.system.ServiceConfigurator] ClientInterceptors set to [interceptors: null] in jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,319 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss:service=invoker,type=http,target=Naming,readonly=true with code: org.jboss.invocation.http.server.HttpProxyFactory
      2003-09-14 08:22:49,329 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,329 DEBUG [org.jboss.system.ServiceConfigurator] InvokerName set to jboss:service=Naming in jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,329 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLPrefix set to http:// in jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,339 DEBUG [org.jboss.system.ServiceConfigurator] InvokerURLSuffix set to :8080/invoker/readonly/JMXInvokerServlet in jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,339 DEBUG [org.jboss.system.ServiceConfigurator] UseHostName set to true in jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,339 DEBUG [org.jboss.system.ServiceConfigurator] ExportedInterface set to interface org.jnp.interfaces.Naming in jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,339 DEBUG [org.jboss.system.ServiceConfigurator] ClientInterceptors set to [interceptors: null] in jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,339 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=http
      2003-09-14 08:22:49,349 INFO [org.jboss.invocation.http.server.HttpInvoker] Creating
      2003-09-14 08:22:49,349 INFO [org.jboss.invocation.http.server.HttpInvoker] Created
      2003-09-14 08:22:49,349 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=23,timeStamp=1063545769349,message=null,userData=jboss:service=invoker,type=http]
      2003-09-14 08:22:49,349 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=23,timeStamp=1063545769349,message=null,userData=jboss:service=invoker,type=http]
      2003-09-14 08:22:49,349 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=http dependents are: []
      2003-09-14 08:22:49,349 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=httpHA
      2003-09-14 08:22:49,359 INFO [org.jboss.invocation.http.server.HttpInvokerHA] Creating
      2003-09-14 08:22:49,359 INFO [org.jboss.invocation.http.server.HttpInvokerHA] Created
      2003-09-14 08:22:49,359 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=24,timeStamp=1063545769359,message=null,userData=jboss:service=invoker,type=httpHA]
      2003-09-14 08:22:49,359 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=24,timeStamp=1063545769359,message=null,userData=jboss:service=invoker,type=httpHA]
      2003-09-14 08:22:49,359 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=httpHA dependents are: []
      2003-09-14 08:22:49,359 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,359 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Creating
      2003-09-14 08:22:49,359 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Created
      2003-09-14 08:22:49,359 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=25,timeStamp=1063545769359,message=null,userData=jboss:service=invoker,type=http,target=Naming]
      2003-09-14 08:22:49,359 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=25,timeStamp=1063545769359,message=null,userData=jboss:service=invoker,type=http,target=Naming]
      2003-09-14 08:22:49,359 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=http,target=Naming dependents are: []
      2003-09-14 08:22:49,359 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,359 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Creating
      2003-09-14 08:22:49,359 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Created
      2003-09-14 08:22:49,359 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=26,timeStamp=1063545769359,message=null,userData=jboss:service=invoker,type=http,target=Naming,readonly=true]
      2003-09-14 08:22:49,359 DEBUG [org.jboss.management.j2ee.factory.DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=26,timeStamp=1063545769359,message=null,userData=jboss:service=invoker,type=http,target=Naming,readonly=true]
      2003-09-14 08:22:49,359 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=http,target=Naming,readonly=true dependents are: []
      2003-09-14 08:22:49,359 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceDeployer,type=org.jboss.deployment.SubDeployer.create,sequenceNumber=6,timeStamp=1063545769359,message=null,userData=org.jboss.deployment.DeploymentInfo@aa66a8 { url=file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/ }
      deployer: org.jboss.deployment.SARDeployer@134a7d8
      status: null
      state: CREATE_DEPLOYER
      watch: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/META-INF/jboss-service.xml
      lastDeployed: 1063545769259
      lastModified: 1062903814902
      mbeans:
      jboss:service=invoker,type=http state: Created
      jboss:service=invoker,type=httpHA state: Created
      jboss:service=invoker,type=http,target=Naming (state not available)
      jboss:service=invoker,type=http,target=Naming,readonly=true (state not available)
      ]
      2003-09-14 08:22:49,359 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying http-invoker.sar
      2003-09-14 08:22:49,359 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,359 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/invoker.war/
      2003-09-14 08:22:49,359 DEBUG [org.jboss.deployment.MainDeployer] Still no deployer for package in start step: invoker.war
      2003-09-14 08:22:49,359 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, start step: url file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/
      2003-09-14 08:22:49,359 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=http
      2003-09-14 08:22:49,359 INFO [org.jboss.invocation.http.server.HttpInvoker] Starting
      2003-09-14 08:22:49,370 DEBUG [org.jboss.invocation.http.server.HttpInvoker] Set invokerURL to http://AL-9NYHWKOJ2Y:8080/invoker/EJBInvokerServlet
      2003-09-14 08:22:49,380 DEBUG [org.jboss.invocation.http.server.HttpInvoker] Bound Http invoker for JMX node
      2003-09-14 08:22:49,380 INFO [org.jboss.invocation.http.server.HttpInvoker] Started
      2003-09-14 08:22:49,380 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=http dependent components: []
      2003-09-14 08:22:49,380 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=httpHA
      2003-09-14 08:22:49,380 INFO [org.jboss.invocation.http.server.HttpInvokerHA] Starting
      2003-09-14 08:22:49,380 DEBUG [org.jboss.invocation.http.server.HttpInvokerHA] Set invokerURL to http://AL-9NYHWKOJ2Y:8080/invoker/EJBInvokerHAServlet
      2003-09-14 08:22:49,390 DEBUG [org.jboss.invocation.http.server.HttpInvokerHA] Bound HttpHA invoker for JMX node
      2003-09-14 08:22:49,390 INFO [org.jboss.invocation.http.server.HttpInvokerHA] Started
      2003-09-14 08:22:49,390 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=httpHA dependent components: []
      2003-09-14 08:22:49,390 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=http,target=Naming
      2003-09-14 08:22:49,390 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Starting
      2003-09-14 08:22:49,390 DEBUG [org.jboss.invocation.http.server.HttpProxyFactory] Set invokerURL to http://AL-9NYHWKOJ2Y:8080/invoker/JMXInvokerServlet
      2003-09-14 08:22:49,390 DEBUG [org.jboss.invocation.http.server.HttpProxyFactory] Bound delegate: org.jboss.invocation.http.interfaces.HttpInvokerProxy@d17ec3(externalURL:null) for invoker=jboss:service=Naming
      2003-09-14 08:22:49,420 DEBUG [org.jboss.invocation.http.server.HttpProxyFactory] Created HttpInvokerProxy for invoker=jboss:service=Naming, nameHash=349114835
      2003-09-14 08:22:49,420 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Started
      2003-09-14 08:22:49,420 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=http,target=Naming dependent components: []
      2003-09-14 08:22:49,420 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=http,target=Naming,readonly=true
      2003-09-14 08:22:49,420 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Starting
      2003-09-14 08:22:49,430 DEBUG [org.jboss.invocation.http.server.HttpProxyFactory] Set invokerURL to http://AL-9NYHWKOJ2Y:8080/invoker/readonly/JMXInvokerServlet
      2003-09-14 08:22:49,430 DEBUG [org.jboss.invocation.http.server.HttpProxyFactory] Bound delegate: org.jboss.invocation.http.interfaces.HttpInvokerProxy@747fa2(externalURL:null) for invoker=jboss:service=Naming
      2003-09-14 08:22:49,430 DEBUG [org.jboss.invocation.http.server.HttpProxyFactory] Created HttpInvokerProxy for invoker=jboss:service=Naming, nameHash=349114835
      2003-09-14 08:22:49,430 INFO [org.jboss.invocation.http.server.HttpProxyFactory] Started
      2003-09-14 08:22:49,430 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=http,target=Naming,readonly=true dependent components: []
      2003-09-14 08:22:49,430 DEBUG [org.jboss.management.j2ee.LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceDeployer,type=org.jboss.deployment.SubDeployer.start,sequenceNumber=7,timeStamp=1063545769430,message=null,userData=org.jboss.deployment.DeploymentInfo@aa66a8 { url=file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/ }
      deployer: org.jboss.deployment.SARDeployer@134a7d8
      status: Starting
      state: START_DEPLOYER
      watch: file:/C:/jboss-3.2.2RC3/server/default/deploy/http-invoker.sar/META-INF/jboss-service.xml
      lastDeployed: 1063545769259
      l