0 Replies Latest reply on Mar 17, 2012 5:09 PM by arturo_corona

    Failure in activation of DLQ in JBOSS 5

    arturo_corona

      I am getting an error regarding a DLQ.

       

      I added a DLQ reference to the destinations-service.xml but it is not working.  I copied that XML from the examples-destinations-service.xml.

       

      Can someone please let me know if there is a different way do do this ? 


      Below I have listed the latest destinations-service.xml and the complete server log that shows te DLQ error message.

       

      <?xml version="1.0" encoding="UTF-8"?>

       

      <server>

       

      <mbean code="org.jboss.jms.server.destination.QueueService"
            name="jboss.messaging.destination:service=Queue,name=OrderBillingQueue"
            xmbean-dd="xmdesc/Queue-xmbean.xml">
            <depends optional-attribute-name="ServerPeer">jboss.messaging:service=ServerPeer</depends>
            <depends>jboss.messaging:service=PostOffice</depends>
         </mbean>

       

      <mbean code="org.jboss.jms.server.destination.QueueService"
            name="jboss.messaging.destination:service=Queue,name=ShippingRequestQueue"
            xmbean-dd="xmdesc/Queue-xmbean.xml">
            <depends optional-attribute-name="ServerPeer">jboss.messaging:service=ServerPeer</depends>
            <depends>jboss.messaging:service=PostOffice</depends>
         </mbean>
        
        
         <mbean code="org.jboss.jms.server.destination.QueueService"
            name="jboss.messaging.destination:service=Queue,name=DLQ"
            xmbean-dd="xmdesc/Queue-xmbean.xml">
            <annotation>@org.jboss.system.deployers.managed.ManagementObjectClass(code=org.jboss.jms.server.destination.QueueServiceMO)</annotation>     
            <depends optional-attribute-name="ServerPeer">jboss.messaging:service=ServerPeer</depends>
            <depends>jboss.messaging:service=PostOffice</depends>
         </mbean>
        
      </server>

       

       

      The complete server log :

       

      Microsoft Windows [Version 6.1.7601]
      Copyright (c) 2009 Microsoft Corporation.  All rights reserved.

      C:\Windows\System32>cd \jboss-5.0.1.ga\bin

      C:\jboss-5.0.1.GA\bin>run
      ===============================================================================

        JBoss Bootstrap Environment

        JBOSS_HOME: c:\jboss-5.0.1.GA

        JAVA: c:\jdk1.6.0_30\bin\java

        JAVA_OPTS: -Dprogram.name=run.bat -server -Xms128m -Xmx512m -XX:MaxPermSize=256m -Dorg.jboss.resolver.warning=true -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000

        CLASSPATH: c:\jboss-5.0.1.GA\bin\run.jar

      ===============================================================================

      13:54:02,619 INFO  [ServerImpl] Starting JBoss (Microcontainer)...
      13:54:02,619 INFO  [ServerImpl] Release ID: JBoss [Morpheus] 5.0.1.GA (build: SVNTag=JBoss_5_0_1_GA date=200902231221)
      13:54:02,619 INFO  [ServerImpl] Bootstrap URL: null
      13:54:02,619 INFO  [ServerImpl] Home Dir: C:\jboss-5.0.1.GA
      13:54:02,619 INFO  [ServerImpl] Home URL: file:/C:/jboss-5.0.1.GA/
      13:54:02,619 INFO  [ServerImpl] Library URL: file:/C:/jboss-5.0.1.GA/lib/
      13:54:02,635 INFO  [ServerImpl] Patch URL: null
      13:54:02,635 INFO  [ServerImpl] Common Base URL: file:/C:/jboss-5.0.1.GA/common/
      13:54:02,635 INFO  [ServerImpl] Common Library URL: file:/C:/jboss-5.0.1.GA/common/lib/
      13:54:02,635 INFO  [ServerImpl] Server Name: default
      13:54:02,635 INFO  [ServerImpl] Server Base Dir: C:\jboss-5.0.1.GA\server
      13:54:02,635 INFO  [ServerImpl] Server Base URL: file:/C:/jboss-5.0.1.GA/server/
      13:54:02,635 INFO  [ServerImpl] Server Config URL: file:/C:/jboss-5.0.1.GA/server/default/conf/
      13:54:02,635 INFO  [ServerImpl] Server Home Dir: C:\jboss-5.0.1.GA\server\default
      13:54:02,635 INFO  [ServerImpl] Server Home URL: file:/C:/jboss-5.0.1.GA/server/default/
      13:54:02,635 INFO  [ServerImpl] Server Data Dir: C:\jboss-5.0.1.GA\server\default\data
      13:54:02,635 INFO  [ServerImpl] Server Library URL: file:/C:/jboss-5.0.1.GA/server/default/lib/
      13:54:02,635 INFO  [ServerImpl] Server Log Dir: C:\jboss-5.0.1.GA\server\default\log
      13:54:02,635 INFO  [ServerImpl] Server Native Dir: C:\jboss-5.0.1.GA\server\default\tmp\native
      13:54:02,650 INFO  [ServerImpl] Server Temp Dir: C:\jboss-5.0.1.GA\server\default\tmp
      13:54:02,650 INFO  [ServerImpl] Server Temp Deploy Dir: C:\jboss-5.0.1.GA\server\default\tmp\deploy
      13:54:03,961 INFO  [ServerImpl] Starting Microcontainer, bootstrapURL=file:/C:/jboss-5.0.1.GA/server/default/conf/bootstrap.xml
      13:54:05,131 INFO  [VFSCacheFactory] Initializing VFSCache [org.jboss.virtual.plugins.cache.CombinedVFSCache]
      13:54:05,146 INFO  [VFSCacheFactory] Using VFSCache [CombinedVFSCache[real-cache: null]]
      13:54:05,817 INFO  [CopyMechanism] VFS temp dir: C:\jboss-5.0.1.GA\server\default\tmp
      13:54:05,817 INFO  [ZipEntryContext] VFS force nested jars copy-mode is enabled.
      13:54:08,485 INFO  [ServerInfo] Java version: 1.6.0_30,Sun Microsystems Inc.
      13:54:08,485 INFO  [ServerInfo] Java Runtime: Java(TM) SE Runtime Environment (build 1.6.0_30-b12)
      13:54:08,500 INFO  [ServerInfo] Java VM: Java HotSpot(TM) 64-Bit Server VM 20.5-b03,Sun Microsystems Inc.
      13:54:08,500 INFO  [ServerInfo] OS-System: Windows 7 6.1,amd64
      13:54:08,594 INFO  [JMXKernel] Legacy JMX core initialized
      13:54:14,164 INFO  [ProfileServiceImpl] Loading profile: default from: org.jboss.system.server.profileservice.repository.SerializableDeploymentRepository@4f5264db(root=C:\jboss-5.0.1.GA\server, key=or
      g.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default])
      13:54:14,164 INFO  [ProfileImpl] Using repository:org.jboss.system.server.profileservice.repository.SerializableDeploymentRepository@4f5264db(root=C:\jboss-5.0.1.GA\server, key=org.jboss.profileservic
      e.spi.ProfileKey@143b82c3[domain=default,server=default,name=default])
      13:54:14,180 INFO  [ProfileServiceImpl] Loaded profile: ProfileImpl@717757ad{key=org.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default]}
      13:54:17,752 INFO  [WebService] Using RMI server codebase: http://127.0.0.1:8083/
      13:54:29,249 INFO  [NativeServerConfig] JBoss Web Services - Stack Native Core
      13:54:29,249 INFO  [NativeServerConfig] 3.0.5.GA
      13:54:42,712 INFO  [ContainerDependencyMetaData] addJndiDependency, JndiDependencyMetaData@55ace3f9{jms/OrderBillingQueue}
      13:54:42,790 INFO  [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1653157061{vfszip:/C:/jboss-5.0.1.GA/server/default/deploy/chapter2.ear/chapter2-ejb.jar/}
      13:54:42,806 INFO  [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1653157061{vfszip:/C:/jboss-5.0.1.GA/server/default/deploy/chapter2.ear/chapter2-ejb.jar/}
      13:54:47,439 INFO  [JMXConnectorServerService] JMX Connector server: service:jmx:rmi://127.0.0.1/jndi/rmi://127.0.0.1:1090/jmxconnector
      13:54:47,767 INFO  [MailService] Mail Service bound to java:/Mail
      13:54:51,027 WARN  [JBossASSecurityMetadataStore] WARNING! POTENTIAL SECURITY RISK. It has been detected that the MessageSucker component which sucks messages from one node to another has not had its
      password changed from the installation default. Please see the JBoss Messaging user guide for instructions on how to do this.
      13:54:51,074 WARN  [AnnotationCreator] No ClassLoader provided, using TCCL: org.jboss.managed.api.annotation.ManagementComponent
      13:54:51,402 INFO  [TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
      13:54:51,402 INFO  [TransactionManagerService] Setting up property manager MBean and JMX layer
      13:54:51,916 INFO  [TransactionManagerService] Initializing recovery manager
      13:54:52,244 INFO  [TransactionManagerService] Recovery manager configured
      13:54:52,244 INFO  [TransactionManagerService] Binding TransactionManager JNDI Reference
      13:54:52,306 INFO  [TransactionManagerService] Starting transaction recovery manager
      13:54:53,476 INFO  [Http11Protocol] Initializing Coyote HTTP/1.1 on http-127.0.0.1-8080
      13:54:53,476 INFO  [AjpProtocol] Initializing Coyote AJP/1.3 on ajp-127.0.0.1-8009
      13:54:53,508 INFO  [StandardService] Starting service jboss.web
      13:54:53,523 INFO  [StandardEngine] Starting Servlet Engine: JBoss Web/2.1.2.GA
      13:54:53,679 INFO  [Catalina] Server startup in 372 ms
      13:54:53,726 INFO  [TomcatDeployment] deploy, ctxPath=/invoker
      13:54:55,114 INFO  [TomcatDeployment] deploy, ctxPath=/jbossws
      13:54:55,239 INFO  [TomcatDeployment] deploy, ctxPath=/web-console
      13:54:56,050 INFO  [RARDeployment] Required license terms exist, view vfszip:/C:/jboss-5.0.1.GA/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml
      13:54:56,097 INFO  [RARDeployment] Required license terms exist, view vfszip:/C:/jboss-5.0.1.GA/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml
      13:54:56,175 INFO  [RARDeployment] Required license terms exist, view vfszip:/C:/jboss-5.0.1.GA/server/default/deploy/jms-ra.rar/META-INF/ra.xml
      13:54:56,222 INFO  [RARDeployment] Required license terms exist, view vfszip:/C:/jboss-5.0.1.GA/server/default/deploy/mail-ra.rar/META-INF/ra.xml
      13:54:56,284 INFO  [RARDeployment] Required license terms exist, view vfszip:/C:/jboss-5.0.1.GA/server/default/deploy/quartz-ra.rar/META-INF/ra.xml
      13:54:56,472 INFO  [SimpleThreadPool] Job execution threads will use class loader of thread: main
      13:54:56,534 INFO  [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
      13:54:56,550 INFO  [RAMJobStore] RAMJobStore initialized.
      13:54:56,550 INFO  [StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
      13:54:56,550 INFO  [StdSchedulerFactory] Quartz scheduler version: 1.5.2
      13:54:56,550 INFO  [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
      13:54:56,986 INFO  [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
      13:54:57,891 WARN  [JDBCPersistenceManager]

      JBoss Messaging Warning: DataSource connection transaction isolation should be READ_COMMITTED, but it is currently REPEATABLE_READ.
                               Using an isolation level less strict than READ_COMMITTED may lead to data consistency problems.
                               Using an isolation level more strict than READ_COMMITTED may lead to deadlock.

      13:54:58,718 INFO  [ServerPeer] JBoss Messaging 1.4.1.GA server [0] started
      13:54:58,983 WARN  [ConnectionFactoryJNDIMapper] supportsFailover attribute is true on connection factory: jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but post office is non c
      lustered. So connection factory will *not* support failover
      13:54:58,999 WARN  [ConnectionFactoryJNDIMapper] supportsLoadBalancing attribute is true on connection factory: jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but post office is
      non clustered. So connection factory will *not* support load balancing
      13:54:59,170 INFO  [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
      13:54:59,186 INFO  [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@275a83d started
      13:54:59,280 INFO  [QueueService] Queue[/queue/ShippingRequestQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
      13:54:59,295 INFO  [QueueService] Queue[/queue/OrderBillingQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
      13:54:59,311 INFO  [QueueService] Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
      13:54:59,311 INFO  [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
      13:54:59,326 INFO  [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@3fecf56a started
      13:54:59,326 INFO  [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
      13:54:59,342 INFO  [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@27c3aecb started
      13:54:59,342 INFO  [QueueService] Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
      13:54:59,358 INFO  [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
      13:54:59,514 INFO  [TomcatDeployment] deploy, ctxPath=/
      13:54:59,685 INFO  [TomcatDeployment] deploy, ctxPath=/jmx-console
      13:55:01,308 INFO  [JBossASKernel] Created KernelDeployment for: chapter2-ejb.jar
      13:55:01,323 INFO  [JBossASKernel] installing bean: jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=OrderBillingMDB,service=EJB3
      13:55:01,323 INFO  [JBossASKernel]   with dependencies:
      13:55:01,323 INFO  [JBossASKernel]   and demands:
      13:55:01,339 INFO  [JBossASKernel]      jboss.ejb:service=EJBTimerService
      13:55:01,339 INFO  [JBossASKernel]      persistence.unit:unitName=chapter2.ear/chapter2-ejb.jar#actionBazaar
      13:55:01,339 INFO  [JBossASKernel]   and supplies:
      13:55:01,339 INFO  [JBossASKernel]      jndi:null
      13:55:01,354 INFO  [JBossASKernel]      Class:javax.jms.MessageListener
      13:55:01,354 INFO  [JBossASKernel] Added bean(jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=OrderBillingMDB,service=EJB3) to KernelDeployment of: chapter2-ejb.jar
      13:55:01,354 INFO  [JBossASKernel] installing bean: jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=PlaceBid,service=EJB3
      13:55:01,354 INFO  [JBossASKernel]   with dependencies:
      13:55:01,370 INFO  [JBossASKernel]   and demands:
      13:55:01,370 INFO  [JBossASKernel]      jboss.ejb:service=EJBTimerService
      13:55:01,370 INFO  [JBossASKernel]      persistence.unit:unitName=chapter2.ear/chapter2-ejb.jar#actionBazaar
      13:55:01,370 INFO  [JBossASKernel]   and supplies:
      13:55:01,370 INFO  [JBossASKernel]      jndi:chapter2/PlaceBid/remote-com.ejb3inaction.actionbazaar.buslogic.PlaceBid
      13:55:01,386 INFO  [JBossASKernel]      jndi:chapter2/PlaceBid/remote
      13:55:01,386 INFO  [JBossASKernel]      Class:com.ejb3inaction.actionbazaar.buslogic.PlaceBid
      13:55:01,386 INFO  [JBossASKernel] Added bean(jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=PlaceBid,service=EJB3) to KernelDeployment of: chapter2-ejb.jar
      13:55:01,386 INFO  [JBossASKernel] installing bean: jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=PlaceOrderBean,service=EJB3
      13:55:01,401 INFO  [JBossASKernel]   with dependencies:
      13:55:01,401 INFO  [JBossASKernel]   and demands:
      13:55:01,401 INFO  [JBossASKernel]      jboss.ejb:service=EJBTimerService
      13:55:01,401 INFO  [JBossASKernel]      persistence.unit:unitName=chapter2.ear/chapter2-ejb.jar#actionBazaar
      13:55:01,417 INFO  [JBossASKernel]   and supplies:
      13:55:01,417 INFO  [JBossASKernel]      jndi:chapter2/PlaceOrderBean/remote
      13:55:01,417 INFO  [JBossASKernel]      jndi:chapter2/PlaceOrderBean/remote-com.ejb3inaction.actionbazaar.buslogic.PlaceOrder
      13:55:01,417 INFO  [JBossASKernel]      Class:com.ejb3inaction.actionbazaar.buslogic.PlaceOrder
      13:55:01,432 INFO  [JBossASKernel] Added bean(jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=PlaceOrderBean,service=EJB3) to KernelDeployment of: chapter2-ejb.jar
      13:55:01,573 INFO  [PersistenceUnitDeployment] Starting persistence unit persistence.unit:unitName=chapter2.ear/chapter2-ejb.jar#actionBazaar
      13:55:01,713 INFO  [Version] Hibernate Annotations 3.4.0.GA
      13:55:01,776 INFO  [Environment] Hibernate 3.3.1.GA
      13:55:01,791 INFO  [Environment] hibernate.properties not found
      13:55:01,807 INFO  [Environment] Bytecode provider name : javassist
      13:55:01,822 INFO  [Environment] using JDK 1.4 java.sql.Timestamp handling
      13:55:02,072 INFO  [Version] Hibernate Commons Annotations 3.1.0.GA
      13:55:02,088 INFO  [Version] Hibernate EntityManager 3.4.0.GA
      13:55:02,244 WARN  [Ejb3Configuration] Persistence provider caller does not implement the EJB3 spec correctly. PersistenceUnitInfo.getNewTempClassLoader() is null.
      13:55:02,493 INFO  [AnnotationBinder] Binding entity from annotated class: com.ejb3inaction.actionbazaar.persistence.Bid
      13:55:02,649 INFO  [EntityBinder] Bind entity com.ejb3inaction.actionbazaar.persistence.Bid on table BIDS
      13:55:02,836 INFO  [AnnotationBinder] Binding entity from annotated class: com.ejb3inaction.actionbazaar.persistence.Order
      13:55:02,836 INFO  [EntityBinder] Bind entity com.ejb3inaction.actionbazaar.persistence.Order on table ORDERS
      13:55:02,914 INFO  [Version] Hibernate Validator 3.1.0.GA
      13:55:03,055 INFO  [HibernateSearchEventListenerRegister] Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
      13:55:03,538 INFO  [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
      13:55:03,554 INFO  [InjectedDataSourceConnectionProvider] Using provided datasource
      13:55:03,554 INFO  [SettingsFactory] RDBMS: MySQL, version: 5.5.19
      13:55:03,554 INFO  [SettingsFactory] JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.1.18 ( Revision: tonci.grgin@oracle.com-20110930151701-jfj14ddfq48ifkfq )
      13:55:03,616 INFO  [Dialect] Using dialect: org.hibernate.dialect.MySQLDialect
      13:55:03,648 INFO  [TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory
      13:55:03,648 INFO  [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
      13:55:03,663 INFO  [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
      13:55:03,663 INFO  [SettingsFactory] Automatic flush during beforeCompletion(): disabled
      13:55:03,679 INFO  [SettingsFactory] Automatic session close at end of transaction: disabled
      13:55:03,679 INFO  [SettingsFactory] JDBC batch size: 15
      13:55:03,679 INFO  [SettingsFactory] JDBC batch updates for versioned data: disabled
      13:55:03,694 INFO  [SettingsFactory] Scrollable result sets: enabled
      13:55:03,694 INFO  [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
      13:55:03,694 INFO  [SettingsFactory] Connection release mode: auto
      13:55:03,710 INFO  [SettingsFactory] Maximum outer join fetch depth: 2
      13:55:03,710 INFO  [SettingsFactory] Default batch fetch size: 1
      13:55:03,710 INFO  [SettingsFactory] Generate SQL with comments: disabled
      13:55:03,710 INFO  [SettingsFactory] Order SQL updates by primary key: disabled
      13:55:03,726 INFO  [SettingsFactory] Order SQL inserts for batching: disabled
      13:55:03,726 INFO  [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
      13:55:03,741 INFO  [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
      13:55:03,741 INFO  [SettingsFactory] Query language substitutions: {}
      13:55:03,741 INFO  [SettingsFactory] JPA-QL strict compliance: enabled
      13:55:03,741 INFO  [SettingsFactory] Second-level cache: enabled
      13:55:03,757 INFO  [SettingsFactory] Query cache: disabled
      13:55:03,772 INFO  [SettingsFactory] Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge
      13:55:03,788 INFO  [RegionFactoryCacheProviderBridge] Cache provider: org.hibernate.cache.HashtableCacheProvider
      13:55:03,788 INFO  [SettingsFactory] Optimize cache for minimal puts: disabled
      13:55:03,804 INFO  [SettingsFactory] Cache region prefix: persistence.unit:unitName=chapter2.ear/chapter2-ejb.jar#actionBazaar
      13:55:03,804 INFO  [SettingsFactory] Structured second-level cache entries: disabled
      13:55:03,835 INFO  [SettingsFactory] Statistics: disabled
      13:55:03,835 INFO  [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
      13:55:03,835 INFO  [SettingsFactory] Default entity-mode: pojo
      13:55:03,850 INFO  [SettingsFactory] Named query checking : enabled
      13:55:03,991 INFO  [SessionFactoryImpl] building session factory
      13:55:04,506 INFO  [SessionFactoryObjectFactory] Factory name: persistence.unit:unitName=chapter2.ear/chapter2-ejb.jar#actionBazaar
      13:55:04,521 INFO  [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}

      13:55:04,552 INFO  [NamingHelper] Creating subcontext: persistence.unit:unitName=chapter2.ear
      13:55:04,568 INFO  [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.unit:unitName=chapter2.ear/chapter2-ejb.jar#actionBazaar
      13:55:04,568 WARN  [SessionFactoryObjectFactory] InitialContext did not implement EventContext
      13:55:04,599 INFO  [SchemaUpdate] Running hbm2ddl schema update
      13:55:04,599 INFO  [SchemaUpdate] fetching database metadata
      13:55:04,615 INFO  [SchemaUpdate] updating schema
      13:55:04,771 INFO  [TableMetadata] table found: localDB.bids
      13:55:04,771 INFO  [TableMetadata] columns: [bid_id, bidder_id, bid_price, item_id, bid_date]
      13:55:04,786 INFO  [TableMetadata] foreign keys: []
      13:55:04,786 INFO  [TableMetadata] indexes: [primary]
      13:55:04,849 INFO  [TableMetadata] table found: localDB.orders
      13:55:04,849 INFO  [TableMetadata] columns: [bidder_id, account_number, status, street, state, expiry_date, order_id, city, credit_card_type]
      13:55:04,864 INFO  [TableMetadata] foreign keys: []
      13:55:04,864 INFO  [TableMetadata] indexes: [primary]
      13:55:04,864 INFO  [SchemaUpdate] schema update complete
      13:55:04,880 INFO  [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}

      13:55:05,332 INFO  [SessionSpecContainer] Starting jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=PlaceBid,service=EJB3
      13:55:05,379 INFO  [EJBContainer] STARTED EJB: com.ejb3inaction.actionbazaar.buslogic.PlaceBidBean ejbName: PlaceBid
      13:55:05,473 INFO  [EJBContainer] STARTED EJB: com.ejb3inaction.actionbazaar.buslogic.OrderBillingMDB ejbName: OrderBillingMDB
      13:55:05,644 INFO  [SessionSpecContainer] Starting jboss.j2ee:ear=chapter2.ear,jar=chapter2-ejb.jar,name=PlaceOrderBean,service=EJB3
      13:55:05,660 INFO  [EJBContainer] STARTED EJB: com.ejb3inaction.actionbazaar.buslogic.PlaceOrderBean ejbName: PlaceOrderBean
      13:55:05,785 INFO  [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:

              chapter2/PlaceOrderBean/remote - EJB3.x Default Remote Business Interface
              chapter2/PlaceOrderBean/remote-com.ejb3inaction.actionbazaar.buslogic.PlaceOrder - EJB3.x Remote Business Interface

      13:55:05,941 INFO  [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:

              chapter2/PlaceBid/remote - EJB3.x Default Remote Business Interface
              chapter2/PlaceBid/remote-com.ejb3inaction.actionbazaar.buslogic.PlaceBid - EJB3.x Remote Business Interface

      13:55:06,362 INFO  [Http11Protocol] Starting Coyote HTTP/1.1 on http-127.0.0.1-8080
      13:55:06,471 INFO  [AjpProtocol] Starting Coyote AJP/1.3 on ajp-127.0.0.1-8009
      13:55:06,518 INFO  [ServerImpl] JBoss (Microcontainer) [5.0.1.GA (build: SVNTag=JBoss_5_0_1_GA date=200902231221)] Started in 1m:3s:868ms
      13:55:07,766 WARN  [JmsActivation] Failure in jms activation org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@51986e63(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@61d44c3 destination=j
      ms/OrderBillingQueue destinationType=javax.jms.Queue tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true
      DLQHandler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
      javax.naming.NameNotFoundException: jms not bound
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:771)
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:779)
              at org.jnp.server.NamingServer.getObject(NamingServer.java:785)
              at org.jnp.server.NamingServer.lookup(NamingServer.java:396)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:722)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:682)
              at javax.naming.InitialContext.lookup(InitialContext.java:392)
              at org.jboss.util.naming.Util.lookup(Util.java:222)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setupDestination(JmsActivation.java:464)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setup(JmsActivation.java:352)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation$SetupActivation.run(JmsActivation.java:729)
              at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:213)
              at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:260)
              at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
              at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
              at java.lang.Thread.run(Thread.java:662)
      13:55:17,890 INFO  [JmsActivation] Attempting to reconnect org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@51986e63(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@61d44c3 destination=jms
      /OrderBillingQueue destinationType=javax.jms.Queue tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true D
      LQHandler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
      13:55:17,906 ERROR [JmsActivation] Unable to reconnect org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@51986e63(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@61d44c3 destination=jms/Ord
      erBillingQueue destinationType=javax.jms.Queue tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true DLQHa
      ndler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
      javax.naming.NameNotFoundException: jms not bound
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:771)
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:779)
              at org.jnp.server.NamingServer.getObject(NamingServer.java:785)
              at org.jnp.server.NamingServer.lookup(NamingServer.java:396)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:722)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:682)
              at javax.naming.InitialContext.lookup(InitialContext.java:392)
              at org.jboss.util.naming.Util.lookup(Util.java:222)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setupDestination(JmsActivation.java:464)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setup(JmsActivation.java:352)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.handleFailure(JmsActivation.java:292)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation$SetupActivation.run(JmsActivation.java:733)
              at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:213)
              at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:260)
              at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
              at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
              at java.lang.Thread.run(Thread.java:662)
      13:55:27,984 INFO  [JmsActivation] Attempting to reconnect org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@51986e63(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@61d44c3 destination=jms
      /OrderBillingQueue destinationType=javax.jms.Queue tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true D
      LQHandler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
      13:55:27,999 ERROR [JmsActivation] Unable to reconnect org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@51986e63(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@61d44c3 destination=jms/Ord
      erBillingQueue destinationType=javax.jms.Queue tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true DLQHa
      ndler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
      javax.naming.NameNotFoundException: jms not bound
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:771)
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:779)
              at org.jnp.server.NamingServer.getObject(NamingServer.java:785)
              at org.jnp.server.NamingServer.lookup(NamingServer.java:396)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:722)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:682)
              at javax.naming.InitialContext.lookup(InitialContext.java:392)
              at org.jboss.util.naming.Util.lookup(Util.java:222)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setupDestination(JmsActivation.java:464)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setup(JmsActivation.java:352)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.handleFailure(JmsActivation.java:292)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation$SetupActivation.run(JmsActivation.java:733)
              at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:213)
              at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:260)
              at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
              at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
              at java.lang.Thread.run(Thread.java:662)
      13:55:38,077 INFO  [JmsActivation] Attempting to reconnect org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@51986e63(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@61d44c3 destination=jms
      /OrderBillingQueue destinationType=javax.jms.Queue tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true D
      LQHandler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
      13:55:38,108 ERROR [JmsActivation] Unable to reconnect org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@51986e63(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@61d44c3 destination=jms/Ord
      erBillingQueue destinationType=javax.jms.Queue tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true DLQHa
      ndler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
      javax.naming.NameNotFoundException: jms not bound
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:771)
              at org.jnp.server.NamingServer.getBinding(NamingServer.java:779)
              at org.jnp.server.NamingServer.getObject(NamingServer.java:785)
              at org.jnp.server.NamingServer.lookup(NamingServer.java:396)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:722)
              at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:682)
              at javax.naming.InitialContext.lookup(InitialContext.java:392)
              at org.jboss.util.naming.Util.lookup(Util.java:222)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setupDestination(JmsActivation.java:464)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.setup(JmsActivation.java:352)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation.handleFailure(JmsActivation.java:292)
              at org.jboss.resource.adapter.jms.inflow.JmsActivation$SetupActivation.run(JmsActivation.java:733)
              at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:213)
              at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:260)
              at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
              at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
              at java.lang.Thread.run(Thread.java:662)