6 Replies Latest reply on Jan 3, 2009 12:12 AM by jaikiran

    EJBException: Failed to acquire the pool semaphore, strictTi

    pbaker01

      I have had numerous problems configuring JBoss Messaging and MDBs. One of the problems that I have is this error:

      10:40:07,914 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
      10:40:07,925 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[20162995751665686]:PERSISTENT, deliveryId=22
      javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000


      I'm using JBoss 5.0.0 GA with JBoss Messsaging
      Data Source is configured for MySQL.

      I am new to JBoss Messaging and JBoss config in general. If possible, please identify config files to be updated.

      MDB:

      @MessageDriven(mappedName = "jms/SSMListener", activationConfig = {
       @ActivationConfigProperty(propertyName="messagingType", propertyValue="javax.jms.MessageListener"),
       @ActivationConfigProperty(propertyName = "acknowledgeMode", propertyValue = "Auto-acknowledge"),
       @ActivationConfigProperty(propertyName = "destinationType", propertyValue = "javax.jms.Queue"),
       @ActivationConfigProperty(propertyName = "maxSession", propertyValue = "25"),
       @ActivationConfigProperty(propertyName="destination",propertyValue="queue/SSMQueue") })
      
      public class SSMListener implements MessageListener {



      jboss.xml
      <jboss xmlns:xs="http://www.jboss.org/j2ee/schema"
       xs:schemaLocation="http://www.jboss.org/j2ee/schema jboss_5_0.xsd"
       version="5.0">
       <security-domain></security-domain>
       <enterprise-beans>
       <message-driven>
       <ejb-name>SSMListener</ejb-name>
       <configuration-name>
       Standard Message Driven Bean
       </configuration-name>
       <destination-jndi-name>
       queue/SSMQueue
       </destination-jndi-name>
       </message-driven>
       </enterprise-beans>
      </jboss>



      ejb-jar.xml
      <?xml version="1.0"?>
      <ejb-jar
       xmlns="http://java.sun.com/xml/ns/javaee"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="http://java.sun.com/xml/ns/javaee
       http://java.sun.com/xml/ns/javaee/ejb-jar_3_0.xsd"
       version="3.0">
       <enterprise-beans>
       <message-driven>
       <ejb-name>SSMListener</ejb-name>
       <ejb-class>com.xxx.wab.mdb.ssim.SSMListener</ejb-class>
       <transaction-type>Container</transaction-type>
       </message-driven>


      Console Log
      10:29:42,895 INFO [ServerImpl] Starting JBoss (Microcontainer)...
      10:29:42,895 INFO [ServerImpl] Release ID: JBoss [Morpheus] 5.0.0.GA (build: SVNTag=JBoss_5_0_0_GA date=200812041714)
      10:29:42,895 INFO [ServerImpl] Bootstrap URL: null
      10:29:42,895 INFO [ServerImpl] Home Dir: C:\JBoss\jboss-5.0.0.GA
      10:29:42,895 INFO [ServerImpl] Home URL: file:/C:/JBoss/jboss-5.0.0.GA/
      10:29:42,895 INFO [ServerImpl] Library URL: file:/C:/JBoss/jboss-5.0.0.GA/lib/
      10:29:42,895 INFO [ServerImpl] Patch URL: null
      10:29:42,895 INFO [ServerImpl] Common Base URL: file:/C:/JBoss/jboss-5.0.0.GA/common/
      10:29:43,066 INFO [ServerImpl] Common Library URL: file:/C:/JBoss/jboss-5.0.0.GA/common/lib/
      10:29:43,066 INFO [ServerImpl] Server Name: default
      10:29:43,066 INFO [ServerImpl] Server Base Dir: C:\JBoss\jboss-5.0.0.GA\server
      10:29:43,066 INFO [ServerImpl] Server Base URL: file:/C:/JBoss/jboss-5.0.0.GA/server/
      10:29:43,066 INFO [ServerImpl] Server Config URL: file:/C:/JBoss/jboss-5.0.0.GA/server/default/conf/
      10:29:43,066 INFO [ServerImpl] Server Home Dir: C:\JBoss\jboss-5.0.0.GA\server\default
      10:29:43,066 INFO [ServerImpl] Server Home URL: file:/C:/JBoss/jboss-5.0.0.GA/server/default/
      10:29:43,082 INFO [ServerImpl] Server Data Dir: C:\JBoss\jboss-5.0.0.GA\server\default\data
      10:29:43,082 INFO [ServerImpl] Server Library URL: file:/C:/JBoss/jboss-5.0.0.GA/server/default/lib/
      10:29:43,082 INFO [ServerImpl] Server Log Dir: C:\JBoss\jboss-5.0.0.GA\server\default\log
      10:29:43,082 INFO [ServerImpl] Server Native Dir: C:\JBoss\jboss-5.0.0.GA\server\default\tmp\native
      10:29:43,082 INFO [ServerImpl] Server Temp Dir: C:\JBoss\jboss-5.0.0.GA\server\default\tmp
      10:29:43,082 INFO [ServerImpl] Server Temp Deploy Dir: C:\JBoss\jboss-5.0.0.GA\server\default\tmp\deploy
      10:29:43,550 INFO [ServerImpl] Starting Microcontainer, bootstrapURL=file:/C:/JBoss/jboss-5.0.0.GA/server/default/conf/bootstrap.xml
      10:29:43,956 INFO [VFSCacheFactory] Initializing VFSCache [org.jboss.virtual.plugins.cache.IterableTimedVFSCache]
      10:29:43,956 INFO [VFSCacheFactory] Using VFSCache [IterableTimedVFSCache{lifetime=1800, resolution=60}]
      10:29:44,206 INFO [CopyMechanism] VFS temp dir: C:\JBoss\jboss-5.0.0.GA\server\default\tmp
      10:29:44,206 INFO [ZipEntryContext] VFS force nested jars copy-mode is enabled.
      10:29:45,080 INFO [ServerInfo] Java version: 1.6.0_10,Sun Microsystems Inc.
      10:29:45,080 INFO [ServerInfo] Java VM: Java HotSpot(TM) 64-Bit Server VM 11.0-b15,Sun Microsystems Inc.
      10:29:45,080 INFO [ServerInfo] OS-System: Windows Vista 6.0,amd64
      10:29:45,095 INFO [JMXKernel] Legacy JMX core initialized
      10:29:46,812 INFO [ProfileServiceImpl] Loading profile: default from: org.jboss.system.server.profileservice.repository.SerializableDeploymentRepository@31eb494e(root=C:\JBoss\jboss-5.0.0.GA\server, key=org.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default])
      10:29:46,812 INFO [ProfileImpl] Using repository:org.jboss.system.server.profileservice.repository.SerializableDeploymentRepository@31eb494e(root=C:\JBoss\jboss-5.0.0.GA\server, key=org.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default])
      10:29:46,812 INFO [ProfileServiceImpl] Loaded profile: ProfileImpl@26ffd553{key=org.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default]}
      10:29:47,921 INFO [WebService] Using RMI server codebase: http://127.0.0.1:8083/
      10:29:52,961 INFO [NativeServerConfig] JBoss Web Services - Stack Native Core
      10:29:52,961 INFO [NativeServerConfig] 3.0.4.SP1
      10:30:00,730 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1727501758{vfsfile:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB_EJB.jar/}
      10:30:00,730 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1727501758{vfsfile:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB_EJB.jar/}
      10:30:00,730 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1727501758{vfsfile:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB_EJB.jar/}
      10:30:00,730 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1727501758{vfsfile:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB_EJB.jar/}
      10:30:03,555 INFO [JMXConnectorServerService] JMX Connector server: service:jmx:rmi://127.0.0.1/jndi/rmi://127.0.0.1:1090/jmxconnector
      10:30:03,774 INFO [MailService] Mail Service bound to java:/Mail
      10:30:05,631 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.
      10:30:05,632 WARN [AnnotationCreator] No ClassLoader provided, using TCCL: org.jboss.managed.api.annotation.ManagementComponent
      10:30:05,788 INFO [TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
      10:30:05,788 INFO [TransactionManagerService] Setting up property manager MBean and JMX layer
      10:30:06,147 INFO [TransactionManagerService] Initializing recovery manager
      10:30:06,334 INFO [TransactionManagerService] Recovery manager configured
      10:30:06,334 INFO [TransactionManagerService] Binding TransactionManager JNDI Reference
      10:30:06,365 INFO [TransactionManagerService] Starting transaction recovery manager
      10:30:06,973 INFO [Http11Protocol] Initializing Coyote HTTP/1.1 on http-127.0.0.1-8080
      10:30:06,973 INFO [AjpProtocol] Initializing Coyote AJP/1.3 on ajp-127.0.0.1-8009
      10:30:06,973 INFO [StandardService] Starting service jboss.web
      10:30:06,974 INFO [StandardEngine] Starting Servlet Engine: JBoss Web/2.1.1.GA
      10:30:07,037 INFO [Catalina] Server startup in 133 ms
      10:30:07,068 INFO [TomcatDeployment] deploy, ctxPath=/jbossws, vfsUrl=jbossws.sar/jbossws-management.war
      10:30:07,879 INFO [TomcatDeployment] deploy, ctxPath=/web-console, vfsUrl=management/console-mgr.sar/web-console.war
      10:30:08,207 INFO [TomcatDeployment] deploy, ctxPath=/invoker, vfsUrl=http-invoker.sar/invoker.war
      10:30:08,379 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml
      10:30:08,426 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml
      10:30:08,457 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/jms-ra.rar/META-INF/ra.xml
      10:30:08,489 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/mail-ra.rar/META-INF/ra.xml
      10:30:08,520 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/quartz-ra.rar/META-INF/ra.xml
      10:30:08,629 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
      10:30:08,660 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
      10:30:08,660 INFO [RAMJobStore] RAMJobStore initialized.
      10:30:08,660 INFO [StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
      10:30:08,676 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
      10:30:08,676 INFO [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
      10:30:08,879 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=WABDS' to JNDI name 'java:WABDS'
      10:30:08,941 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=ahm565DS' to JNDI name 'java:ahm565DS'
      10:30:09,175 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
      10:30:09,690 WARN [QuartzTimerServiceFactory] sql failed: CREATE TABLE QRTZ_JOB_DETAILS(JOB_NAME VARCHAR(80) NOT NULL, JOB_GROUP VARCHAR(80) NOT NULL, DESCRIPTION VARCHAR(120) NULL, JOB_CLASS_NAME VARCHAR(128) NOT NULL, IS_DURABLE VARCHAR(1) NOT NULL, IS_VOLATILE VARCHAR(1) NOT NULL, IS_STATEFUL VARCHAR(1) NOT NULL, REQUESTS_RECOVERY VARCHAR(1) NOT NULL, JOB_DATA BINARY NULL, PRIMARY KEY (JOB_NAME,JOB_GROUP))
      10:30:09,738 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
      10:30:09,738 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
      10:30:09,738 INFO [JobStoreCMT] Using db table-based data access locking (synchronization).
      10:30:09,816 INFO [JobStoreCMT] Removed 0 Volatile Trigger(s).
      10:30:09,816 INFO [JobStoreCMT] Removed 0 Volatile Job(s).
      10:30:09,816 INFO [JobStoreCMT] JobStoreCMT initialized.
      10:30:09,816 INFO [StdSchedulerFactory] Quartz scheduler 'JBossEJB3QuartzScheduler' initialized from an externally provided properties instance.
      10:30:09,816 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
      10:30:09,816 INFO [JobStoreCMT] Freed 0 triggers from 'acquired' / 'blocked' state.
      10:30:09,816 INFO [JobStoreCMT] Recovering 0 jobs that were in-progress at the time of the last shut-down.
      10:30:09,816 INFO [JobStoreCMT] Recovery complete.
      10:30:09,816 INFO [JobStoreCMT] Removed 0 'complete' triggers.
      10:30:09,816 INFO [JobStoreCMT] Removed 0 stale fired job entries.
      10:30:09,816 INFO [QuartzScheduler] Scheduler JBossEJB3QuartzScheduler_$_NON_CLUSTERED started.
      10:30:10,070 INFO [ServerPeer] JBoss Messaging 1.4.1.GA server [0] started
      10:30:10,217 INFO [QueueService] Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
      10:30:10,220 INFO [QueueService] Queue[/queue/SSMQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
      10:30:10,409 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
      10:30:10,409 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@6b731fdb started
      10:30:10,410 WARN [ConnectionFactoryJNDIMapper] supportsFailover attribute is true on connection factory: jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but post office is non clustered. So connection factory will *not* support failover
      10:30:10,410 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
      10:30:10,417 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
      10:30:10,417 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@4654c0da started
      10:30:10,420 INFO [QueueService] Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
      10:30:10,422 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
      10:30:10,422 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@1a47aa55 started
      10:30:10,433 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
      10:30:10,488 INFO [TomcatDeployment] deploy, ctxPath=/, vfsUrl=ROOT.war
      10:30:10,548 INFO [TomcatDeployment] deploy, ctxPath=/WAB, vfsUrl=WAB.war
      10:30:13,021 INFO [CacheManager] Selected [org.ajax4jsf.cache.LRUMapCacheFactory] cache factory
      10:30:13,023 INFO [LRUMapCacheFactory] Creating LRUMap cache instance using parameters: {facelets.DEVELOPMENT=true, enable-cache=false, com.sun.faces.duplicateJARPattern=^tmp\d+(\S*\.jar), javax.faces.CONFIG_FILES=/WEB-INF/faces-config.xml, org.richfaces.CONTROL_SKINNING_CLASSES=enable, org.richfaces.SKIN=blueSky, com.sun.faces.injectionProvider=org.jboss.web.jsf.integration.injection.JBossDelegatingInjectionProvider, com.sun.faces.validateXml=false, org.richfaces.LoadScriptStrategy=ALL, org.richfaces.LoadStyleStrategy=ALL, facelets.SKIP_COMMENTS=true, com.sun.faces.verifyObjects=false, javax.faces.DEFAULT_SUFFIX=.xhtml}
      10:30:13,024 INFO [LRUMapCacheFactory] Creating LRUMap cache instance of default capacity
      10:30:13,041 INFO [CacheManager] Selected [org.ajax4jsf.cache.LRUMapCacheFactory] cache factory
      10:30:13,042 INFO [LRUMapCacheFactory] Creating LRUMap cache instance using parameters: {facelets.DEVELOPMENT=true, enable-cache=false, com.sun.faces.duplicateJARPattern=^tmp\d+(\S*\.jar), javax.faces.CONFIG_FILES=/WEB-INF/faces-config.xml, org.richfaces.CONTROL_SKINNING_CLASSES=enable, org.richfaces.SKIN=blueSky, com.sun.faces.injectionProvider=org.jboss.web.jsf.integration.injection.JBossDelegatingInjectionProvider, com.sun.faces.validateXml=false, org.richfaces.LoadScriptStrategy=ALL, org.richfaces.LoadStyleStrategy=ALL, facelets.SKIP_COMMENTS=true, com.sun.faces.verifyObjects=false, javax.faces.DEFAULT_SUFFIX=.xhtml}
      10:30:13,042 INFO [LRUMapCacheFactory] Creating LRUMap cache instance of default capacity
      10:30:13,086 INFO [TomcatDeployment] deploy, ctxPath=/jmx-console, vfsUrl=jmx-console.war
      10:30:14,775 INFO [JBossASKernel] Created KernelDeployment for: WAB_EJB.jar
      10:30:14,781 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=WAB_EJB.jar,name=AircraftEjbBean,service=EJB3
      10:30:14,781 INFO [JBossASKernel] with dependencies:
      10:30:14,781 INFO [JBossASKernel] and demands:
      10:30:14,781 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
      10:30:14,781 INFO [JBossASKernel] persistence.unit:unitName=#WABPU
      10:30:14,781 INFO [JBossASKernel] and supplies:
      10:30:14,781 INFO [JBossASKernel] jndi:AircraftEjbBean/local-com.xxx.wab.ejb.aircraft.AircraftEjbLocal
      10:30:14,781 INFO [JBossASKernel] Class:com.xxx.wab.ejb.aircraft.AircraftEjbRemote
      10:30:14,781 INFO [JBossASKernel] jndi:AircraftEjbBean/local
      10:30:14,781 INFO [JBossASKernel] jndi:AircraftEjbBean/remote
      10:30:14,781 INFO [JBossASKernel] Class:com.xxx.wab.ejb.aircraft.AircraftEjbLocal
      10:30:14,781 INFO [JBossASKernel] jndi:AircraftEjbBean/remote-com.xxx.wab.ejb.aircraft.AircraftEjbRemote
      10:30:14,781 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=WAB_EJB.jar,name=AircraftEjbBean,service=EJB3) to KernelDeployment of: WAB_EJB.jar
      10:30:14,782 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=WAB_EJB.jar,name=AirlineEjbBean,service=EJB3
      10:30:14,782 INFO [JBossASKernel] with dependencies:
      10:30:14,782 INFO [JBossASKernel] and demands:
      10:30:14,782 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
      10:30:14,782 INFO [JBossASKernel] persistence.unit:unitName=#WABPU
      10:30:14,782 INFO [JBossASKernel] and supplies:
      10:30:14,782 INFO [JBossASKernel] jndi:AirlineEjbBean/remote
      10:30:14,782 INFO [JBossASKernel] jndi:AirlineEjbBean/local-com.xxx.wab.ejb.airline.AirlineEjbLocal
      10:30:14,782 INFO [JBossASKernel] Class:com.xxx.wab.ejb.airline.AirlineEjbRemote
      10:30:14,782 INFO [JBossASKernel] jndi:AirlineEjbBean/local
      10:30:14,782 INFO [JBossASKernel] jndi:AirlineEjbBean/remote-com.xxx.wab.ejb.airline.AirlineEjbRemote
      10:30:14,783 INFO [JBossASKernel] Class:com.xxx.wab.ejb.airline.AirlineEjbLocal
      10:30:14,783 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=WAB_EJB.jar,name=AirlineEjbBean,service=EJB3) to KernelDeployment of: WAB_EJB.jar
      10:30:14,783 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=WAB_EJB.jar,name=SsimEjbBean,service=EJB3
      10:30:14,783 INFO [JBossASKernel] with dependencies:
      10:30:14,783 INFO [JBossASKernel] and demands:
      10:30:14,783 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
      10:30:14,783 INFO [JBossASKernel] persistence.unit:unitName=#WABPU
      10:30:14,783 INFO [JBossASKernel] and supplies:
      10:30:14,783 INFO [JBossASKernel] Class:com.xxx.wab.ejb.ssim.SsimEjbLocal
      10:30:14,783 INFO [JBossASKernel] jndi:SsimEjbBean/local-com.xxx.wab.ejb.ssim.SsimEjbLocal
      10:30:14,783 INFO [JBossASKernel] jndi:SsimEjbBean/local
      10:30:14,784 INFO [JBossASKernel] jndi:SsimEjbBean/remote-com.xxx.wab.ejb.ssim.SsimEjbRemote
      10:30:14,784 INFO [JBossASKernel] Class:com.xxx.wab.ejb.ssim.SsimEjbRemote
      10:30:14,784 INFO [JBossASKernel] jndi:SsimEjbBean/remote
      10:30:14,784 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=WAB_EJB.jar,name=SsimEjbBean,service=EJB3) to KernelDeployment of: WAB_EJB.jar
      10:30:14,784 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=WAB_EJB.jar,name=SubscriberEjbBean,service=EJB3
      10:30:14,784 INFO [JBossASKernel] with dependencies:
      10:30:14,784 INFO [JBossASKernel] and demands:
      10:30:14,784 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
      10:30:14,784 INFO [JBossASKernel] persistence.unit:unitName=#WABPU
      10:30:14,784 INFO [JBossASKernel] and supplies:
      10:30:14,784 INFO [JBossASKernel] Class:com.xxx.wab.ejb.subscriber.SubscriberEjbLocal
      10:30:14,784 INFO [JBossASKernel] jndi:SubscriberEjbBean/remote-com.xxx.wab.ejb.subscriber.SubscriberEjbRemote
      10:30:14,784 INFO [JBossASKernel] Class:com.xxx.wab.ejb.subscriber.SubscriberEjbRemote
      10:30:14,785 INFO [JBossASKernel] jndi:SubscriberEjbBean/local
      10:30:14,785 INFO [JBossASKernel] jndi:SubscriberEjbBean/local-com.xxx.wab.ejb.subscriber.SubscriberEjbLocal
      10:30:14,785 INFO [JBossASKernel] jndi:SubscriberEjbBean/remote
      10:30:14,785 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=WAB_EJB.jar,name=SubscriberEjbBean,service=EJB3) to KernelDeployment of: WAB_EJB.jar
      10:30:14,785 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=WAB_EJB.jar,name=SSMListener,service=EJB3
      10:30:14,785 INFO [JBossASKernel] with dependencies:
      10:30:14,785 INFO [JBossASKernel] and demands:
      10:30:14,785 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
      10:30:14,785 INFO [JBossASKernel] persistence.unit:unitName=#WABPU
      10:30:14,785 INFO [JBossASKernel] and supplies:
      10:30:14,785 INFO [JBossASKernel] jndi:null
      10:30:14,785 INFO [JBossASKernel] Class:javax.jms.MessageListener
      10:30:14,786 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=WAB_EJB.jar,name=SSMListener,service=EJB3) to KernelDeployment of: WAB_EJB.jar
      10:30:14,871 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.unit:unitName=#WABPU
      10:30:14,997 INFO [Version] Hibernate Annotations 3.4.0.GA
      10:30:15,030 INFO [Environment] Hibernate 3.3.1.GA
      10:30:15,040 INFO [Environment] hibernate.properties not found
      10:30:15,047 INFO [Environment] Bytecode provider name : javassist
      10:30:15,057 INFO [Environment] using JDK 1.4 java.sql.Timestamp handling
      10:30:15,215 INFO [Version] Hibernate Commons Annotations 3.1.0.GA
      10:30:15,221 INFO [Version] Hibernate EntityManager 3.4.0.GA
      10:30:15,325 WARN [Ejb3Configuration] Persistence provider caller does not implement the EJB3 spec correctly. PersistenceUnitInfo.getNewTempClassLoader() is null.
      10:30:15,758 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineMessageDeliveryOptionImpl -> AirlineMessageDeliveryOption
      10:30:15,915 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.SubscriberImpl -> Subscriber
      10:30:15,923 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineLoadInformationCodeImpl -> AirlineLoadInformationCode
      10:30:15,924 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.masterflight.MasterFlightLegImpl -> MasterFlightLeg
      10:30:15,926 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineUnitsOfMeasureImpl -> AirlineUnitsOfMeasure
      10:30:15,929 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineContactImpl -> AirlineContact
      10:30:15,930 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.aircraft.AircraftUnitsOfMeasureImpl -> AircraftUnitsOfMeasure
      10:30:15,931 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.aircraft.AircraftTypeRevisionImpl -> AircraftTypeRevision
      10:30:15,932 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineImpl -> Airline
      10:30:15,940 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.masterflight.MasterFlightImpl -> MasterFlight
      10:30:15,945 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineOptionsImpl -> AirlineOptions
      10:30:15,946 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.aircraft.AircraftTypeImpl -> AircraftType
      10:30:15,947 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineBaggagePriorityImpl -> AirlineBaggagePriority
      10:30:15,947 INFO [HbmBinder] Mapping class: com.xxx.wab.entities.airline.AirlineFlightTypeImpl -> AirlineFlightType
      10:30:15,952 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.airline.SubscriberImpl.Airline -> Airline
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.airline.SubscriberImpl.MasterFlights -> MasterFlight
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.aircraft.AircraftTypeRevisionImpl.SharedAircraftTypeRevisions -> AircraftTypeRevision
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.airline.AirlineImpl.AirlineLoadInformationCodes -> AirlineLoadInformationCode
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.airline.AirlineImpl.AirlineFlightTypes -> AirlineFlightType
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.airline.AirlineImpl.AirlineMessageDeliveryOption -> AirlineMessageDeliveryOption
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.airline.AirlineImpl.AirlineBaggagePriority -> AirlineBaggagePriority
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.airline.AirlineImpl.AircraftTypes -> AircraftType
      10:30:15,953 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.masterflight.MasterFlightImpl.MasterFlightLegs -> MasterFlightLeg
      10:30:15,955 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.aircraft.AircraftTypeImpl.SharedAircraftTypes -> AircraftType
      10:30:15,956 INFO [HbmBinder] Mapping collection: com.xxx.wab.entities.aircraft.AircraftTypeImpl.AircraftTypeRevisions -> AircraftTypeRevision
      10:30:15,982 INFO [Version] Hibernate Validator 3.1.0.GA
      10:30:16,225 INFO [HibernateSearchEventListenerRegister] Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
      10:30:16,243 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
      10:30:16,253 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
      10:30:16,263 INFO [SettingsFactory] RDBMS: MySQL, version: 5.0.67-community-nt
      10:30:16,264 INFO [SettingsFactory] JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.0.7 ( $Date: 2007-03-09 22:13:57 +0100 (Fri, 09 Mar 2007) $, $Revision: 6341 $ )
      10:30:16,300 INFO [Dialect] Using dialect: org.hibernate.dialect.MySQLDialect
      10:30:16,307 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory
      10:30:16,313 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
      10:30:16,317 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
      10:30:16,317 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
      10:30:16,317 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
      10:30:16,317 INFO [SettingsFactory] JDBC batch size: 15
      10:30:16,317 INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
      10:30:16,319 INFO [SettingsFactory] Scrollable result sets: enabled
      10:30:16,319 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
      10:30:16,319 INFO [SettingsFactory] Connection release mode: auto
      10:30:16,321 INFO [SettingsFactory] Maximum outer join fetch depth: 2
      10:30:16,321 INFO [SettingsFactory] Default batch fetch size: 1
      10:30:16,321 INFO [SettingsFactory] Generate SQL with comments: disabled
      10:30:16,321 INFO [SettingsFactory] Order SQL updates by primary key: disabled
      10:30:16,321 INFO [SettingsFactory] Order SQL inserts for batching: disabled
      10:30:16,321 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
      10:30:16,328 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
      10:30:16,328 INFO [SettingsFactory] Query language substitutions: {}
      10:30:16,328 INFO [SettingsFactory] JPA-QL strict compliance: enabled
      10:30:16,328 INFO [SettingsFactory] Second-level cache: enabled
      10:30:16,328 INFO [SettingsFactory] Query cache: disabled
      10:30:16,346 INFO [SettingsFactory] Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge
      10:30:16,347 INFO [RegionFactoryCacheProviderBridge] Cache provider: org.hibernate.cache.HashtableCacheProvider
      10:30:16,351 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
      10:30:16,352 INFO [SettingsFactory] Cache region prefix: persistence.unit:unitName=#WABPU
      10:30:16,352 INFO [SettingsFactory] Structured second-level cache entries: disabled
      10:30:16,375 INFO [SettingsFactory] Statistics: disabled
      10:30:16,375 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
      10:30:16,376 INFO [SettingsFactory] Default entity-mode: pojo
      10:30:16,376 INFO [SettingsFactory] Named query checking : enabled
      10:30:16,624 INFO [SessionFactoryImpl] building session factory
      10:30:17,408 INFO [SessionFactoryObjectFactory] Factory name: persistence.unit:unitName=#WABPU
      10:30:17,413 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
      10:30:17,430 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.unit:unitName=#WABPU
      10:30:17,432 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
      10:30:17,433 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
      10:30:17,798 INFO [SessionSpecContainer] Starting jboss.j2ee:jar=WAB_EJB.jar,name=SubscriberEjbBean,service=EJB3
      10:30:17,881 INFO [EJBContainer] STARTED EJB: com.xxx.wab.ejb.subscriber.SubscriberEjbBean ejbName: SubscriberEjbBean
      10:30:18,000 INFO [SessionSpecContainer] Starting jboss.j2ee:jar=WAB_EJB.jar,name=AirlineEjbBean,service=EJB3
      10:30:18,002 INFO [EJBContainer] STARTED EJB: com.xxx.wab.ejb.airline.AirlineEjbBean ejbName: AirlineEjbBean
      10:30:18,020 INFO [SessionSpecContainer] Starting jboss.j2ee:jar=WAB_EJB.jar,name=SsimEjbBean,service=EJB3
      10:30:18,022 INFO [EJBContainer] STARTED EJB: com.xxx.wab.ejb.ssim.SsimEjbBean ejbName: SsimEjbBean
      10:30:18,070 INFO [SessionSpecContainer] Starting jboss.j2ee:jar=WAB_EJB.jar,name=AircraftEjbBean,service=EJB3
      10:30:18,072 INFO [EJBContainer] STARTED EJB: com.xxx.wab.ejb.aircraft.AircraftEjbBean ejbName: AircraftEjbBean
      10:30:18,119 INFO [EJBContainer] STARTED EJB: com.xxx.wab.mdb.ssim.SSMListener ejbName: SSMListener
      10:30:18,395 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:
      
       AircraftEjbBean/remote - EJB3.x Default Remote Business Interface
       AircraftEjbBean/remote-com.xxx.wab.ejb.aircraft.AircraftEjbRemote - EJB3.x Remote Business Interface
       AircraftEjbBean/local - EJB3.x Default Local Business Interface
       AircraftEjbBean/local-com.xxx.wab.ejb.aircraft.AircraftEjbLocal - EJB3.x Local Business Interface
      
      10:30:18,445 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:
      
       SsimEjbBean/remote - EJB3.x Default Remote Business Interface
       SsimEjbBean/remote-com.xxx.wab.ejb.ssim.SsimEjbRemote - EJB3.x Remote Business Interface
       SsimEjbBean/local - EJB3.x Default Local Business Interface
       SsimEjbBean/local-com.xxx.wab.ejb.ssim.SsimEjbLocal - EJB3.x Local Business Interface
      
      10:30:18,472 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:
      
       AirlineEjbBean/remote - EJB3.x Default Remote Business Interface
       AirlineEjbBean/remote-com.xxx.wab.ejb.airline.AirlineEjbRemote - EJB3.x Remote Business Interface
       AirlineEjbBean/local - EJB3.x Default Local Business Interface
       AirlineEjbBean/local-com.xxx.wab.ejb.airline.AirlineEjbLocal - EJB3.x Local Business Interface
      
      10:30:18,493 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:
      
       SubscriberEjbBean/remote - EJB3.x Default Remote Business Interface
       SubscriberEjbBean/remote-com.xxx.wab.ejb.subscriber.SubscriberEjbRemote - EJB3.x Remote Business Interface
       SubscriberEjbBean/local - EJB3.x Default Local Business Interface
       SubscriberEjbBean/local-com.xxx.wab.ejb.subscriber.SubscriberEjbLocal - EJB3.x Local Business Interface
      
      10:30:18,629 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-127.0.0.1-8080
      10:30:18,659 INFO [AjpProtocol] Starting Coyote AJP/1.3 on ajp-127.0.0.1-8009
      10:30:18,669 INFO [ServerImpl] JBoss (Microcontainer) [5.0.0.GA (build: SVNTag=JBoss_5_0_0_GA date=200812041714)] Started in 35s:587ms
      10:38:07,933 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/richfaces-ui-3.3.0-20081108.050927-49.jar/META-INF/richfaces.taglib.xml
      10:38:07,941 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/richfaces-ui-3.3.0-20081108.050927-49.jar/META-INF/rich.taglib.xml
      10:38:07,955 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/richfaces-ui-3.3.0-20081108.050927-49.jar/META-INF/ajax4jsf.taglib.xml
      10:38:07,959 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/richfaces-ui-3.3.0-20081108.050927-49.jar/META-INF/a4j.taglib.xml
      10:38:07,963 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/richfaces-ui-3.3.0-20081108.050927-49.jar/META-INF/jsp.taglib.xml
      10:38:07,985 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/jsf-facelets.jar/META-INF/jsf-core.taglib.xml
      10:38:07,992 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/jsf-facelets.jar/META-INF/jsf-html.taglib.xml
      10:38:08,004 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/jsf-facelets.jar/META-INF/jsf-ui.taglib.xml
      10:38:08,017 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/jsf-facelets.jar/META-INF/jstl-core.taglib.xml
      10:38:08,023 INFO [compiler] Added Library from: vfszip:/C:/JBoss/jboss-5.0.0.GA/server/default/deploy/WAB.war/WEB-INF/lib/jsf-facelets.jar/META-INF/jstl-fn.taglib.xml
      10:38:15,102 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:38:15,105 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:38:15,108 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.findSubscriberById(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.NotFoundException
      10:38:15,130 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.updateSubscriber(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.RecordExistsException,com.xxx.wab.exceptions.ConcurrentUpdateException
      10:38:15,146 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.createSubscriber(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.RecordExistsException
      10:38:15,166 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.findSubscriber(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.NotFoundException
      10:38:15,185 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.retrieveSubscribers(com.xxx.wab.dto.SubscriberReq)
      10:38:15,202 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.deleteSubscriber(com.xxx.wab.dto.SubscriberReq)
      10:38:15,225 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.findSubscriberById(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.NotFoundException
      10:38:15,246 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.updateSubscriber(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.RecordExistsException,com.xxx.wab.exceptions.ConcurrentUpdateException
      10:38:15,265 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.createSubscriber(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.RecordExistsException
      10:38:15,284 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.findSubscriber(com.xxx.wab.dto.SubscriberReq) throws com.xxx.wab.exceptions.NotFoundException
      10:38:15,305 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.retrieveSubscribers(com.xxx.wab.dto.SubscriberReq)
      10:38:15,324 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SubscriberRsp com.xxx.wab.ejb.subscriber.SubscriberEjbBean.deleteSubscriber(com.xxx.wab.dto.SubscriberReq)
      10:38:16,082 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:38:16,082 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:38:16,083 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.readAircraftType(com.xxx.wab.dto.AircraftReq) throws com.xxx.wab.exceptions.NotFoundException
      10:38:16,109 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.BaseEjb.setContext(com.xxx.wab.dto.RequestBase)
      10:38:16,129 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.updateAircraftType(com.xxx.wab.dto.AircraftReq) throws com.xxx.wab.exceptions.RecordExistsException,com.xxx.wab.exceptions.ConcurrentUpdateException
      10:38:16,149 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.BaseEjb.clearContext()
      10:38:16,169 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.retrieveAircraftTypes(com.xxx.wab.dto.AircraftReq)
      10:38:16,188 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.createAircraftType(com.xxx.wab.dto.AircraftReq) throws com.xxx.wab.exceptions.RecordExistsException
      10:38:16,208 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.readAircraftType(com.xxx.wab.dto.AircraftReq) throws com.xxx.wab.exceptions.NotFoundException
      10:38:16,227 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.BaseEjb.setContext(com.xxx.wab.dto.RequestBase)
      10:38:16,247 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.updateAircraftType(com.xxx.wab.dto.AircraftReq) throws com.xxx.wab.exceptions.RecordExistsException,com.xxx.wab.exceptions.ConcurrentUpdateException
      10:38:16,265 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.BaseEjb.clearContext()
      10:38:16,285 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.retrieveAircraftTypes(com.xxx.wab.dto.AircraftReq)
      10:38:16,306 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.AircraftRsp com.xxx.wab.ejb.aircraft.AircraftEjbBean.createAircraftType(com.xxx.wab.dto.AircraftReq) throws com.xxx.wab.exceptions.RecordExistsException
      10:38:44,690 WARN [AjaxContext] Target component for id activeAirline not found
      10:39:33,998 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:39:33,998 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:39:33,998 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SsimRsp com.xxx.wab.ejb.ssim.SsimEjbBean.startLoad(com.xxx.wab.dto.SsimReq)
      10:39:34,013 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.ssim.SsimEjbBean.buildFlights(java.util.ArrayList)
      10:39:34,027 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.ssim.SsimEjbBean.queueFlightPacket(java.util.ArrayList) throws com.xxx.wab.exceptions.ApplicationException
      10:39:34,043 WARN [InterceptorRegistry] applicable interceptors is non-existent for public com.xxx.wab.dto.SsimRsp com.xxx.wab.ejb.ssim.SsimEjbBean.startLoad(com.xxx.wab.dto.SsimReq)
      10:39:34,060 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.ssim.SsimEjbBean.buildFlights(java.util.ArrayList)
      10:39:34,076 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.ejb.ssim.SsimEjbBean.queueFlightPacket(java.util.ArrayList) throws com.xxx.wab.exceptions.ApplicationException
      10:39:34,788 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:39:34,788 WARN [InterceptorsFactory] EJBTHREE-1246: Do not use InterceptorsFactory with a ManagedObjectAdvisor, InterceptorRegistry should be used via the bean container
      10:39:34,789 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.mdb.ssim.SSMListener.onMessage(javax.jms.Message)
      10:39:35,644 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.xxx.wab.mdb.ssim.SSMListener.onMessage(javax.jms.Message)
      10:39:35,676 INFO [STDOUT] Starting message: ID:JBM-00fff919-1f40-4e0b-b88f-d88556e6556b
      10:39:36,466 INFO [STDOUT] Starting message: ID:JBM-3217b90f-0a4f-4b47-a9a1-321e6118c8be
      10:39:37,537 INFO [STDOUT] Starting message: ID:JBM-70a335c4-a1ae-4913-9514-87494f4491c0
      10:39:38,454 INFO [STDOUT] Starting message: ID:JBM-19e0b42e-eb29-4817-b78f-ea418ae2e9f6
      10:39:39,374 INFO [STDOUT] Starting message: ID:JBM-6f9a7562-f07c-4c34-9d5d-0233becaf261
      10:39:40,486 INFO [STDOUT] Starting message: ID:JBM-09ccd410-05c1-423a-ab45-b0902513f11f
      10:39:41,440 INFO [STDOUT] Starting message: ID:JBM-ca883464-7e58-48d6-b6b0-01494aa73195
      10:39:43,449 INFO [STDOUT] Starting message: ID:JBM-2b4fb301-03d2-450b-9624-219e094ccfb0
      10:39:44,196 INFO [STDOUT] Starting message: ID:JBM-c6d3ddc8-ea6e-412d-be78-2c1075572d35
      10:39:45,169 INFO [STDOUT] Starting message: ID:JBM-120989a8-58a3-4967-8be5-e2041dfd4ee5
      10:39:46,320 INFO [STDOUT] Starting message: ID:JBM-ef2ccf0a-6128-438d-b82b-3c44b6f34bbe
      10:39:47,259 INFO [STDOUT] Starting message: ID:JBM-7650ae2b-1d67-427d-8d21-7920d7212174
      10:39:47,381 INFO [STDOUT] Starting message: ID:JBM-929e9dc3-3806-4528-9b0e-76acd73963f3
      10:39:49,185 INFO [STDOUT] Starting message: ID:JBM-cf447247-c8c2-4831-b92d-c41044a061b0
      10:39:49,252 INFO [STDOUT] Starting message: ID:JBM-1d23f48b-eb1a-4ca2-b4f5-f4ea77a70eed
      10:39:51,287 INFO [STDOUT] Completed message: ID:JBM-6f9a7562-f07c-4c34-9d5d-0233becaf261
      10:39:51,309 INFO [STDOUT] Completed message: ID:JBM-ca883464-7e58-48d6-b6b0-01494aa73195
      10:39:51,355 INFO [STDOUT] Starting message: ID:JBM-d1498ceb-c635-4412-b629-72091bc2e42c
      10:39:51,356 INFO [STDOUT] Starting message: ID:JBM-2755797d-599c-4992-930c-65ae041bfe15
      10:39:56,175 INFO [STDOUT] Completed message: ID:JBM-19e0b42e-eb29-4817-b78f-ea418ae2e9f6
      10:39:56,179 INFO [STDOUT] Starting message: ID:JBM-a1cdc487-82f3-4f9f-8342-8cc90336e216
      10:39:57,134 INFO [STDOUT] Completed message: ID:JBM-2b4fb301-03d2-450b-9624-219e094ccfb0
      10:39:57,136 INFO [STDOUT] Starting message: ID:JBM-3bf24ab8-23b9-4302-9852-e7dc61b599e7
      10:39:58,174 INFO [STDOUT] Completed message: ID:JBM-09ccd410-05c1-423a-ab45-b0902513f11f
      10:39:58,179 INFO [STDOUT] Starting message: ID:JBM-dd13519e-8353-4dc1-88f1-ef6188c3e941
      10:40:03,954 INFO [STDOUT] Completed message: ID:JBM-ef2ccf0a-6128-438d-b82b-3c44b6f34bbe
      10:40:03,955 INFO [STDOUT] Starting message: ID:JBM-f3b246c0-e79e-4b9b-8bee-bd01e3fb4318
      10:40:05,151 INFO [STDOUT] Completed message: ID:JBM-7650ae2b-1d67-427d-8d21-7920d7212174
      10:40:05,152 INFO [STDOUT] Starting message: ID:JBM-c3680818-512b-43cc-89cf-0ab875eb52a9
      10:40:07,914 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
      10:40:07,925 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[20162995751665686]:PERSISTENT, deliveryId=22
      javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
       at org.jboss.ejb3.pool.StrictMaxPool.get(StrictMaxPool.java:126)
       at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:58)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)
       at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:194)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.security.RunAsSecurityInterceptorv2.invoke(RunAsSecurityInterceptorv2.java:94)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:75)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:65)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:262)
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:270)
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:140)
       at $Proxy130.onMessage(Unknown Source)
       at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
       at org.jboss.jms.client.container.ClientConsumer.callOnMessageStatic(ClientConsumer.java:160)
       at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:831)
       at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRun_1300817040.invoke(SessionAspect_z_handleRun_1300817040.java)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
       at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.jms.client.delegate.ClientSessionDelegate.run(ClientSessionDelegate.java)
       at org.jboss.jms.client.JBossSession.run(JBossSession.java:199)
       at org.jboss.resource.adapter.jms.inflow.JmsServerSession.run(JmsServerSession.java:234)
       at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
       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:619)
      10:40:07,995 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
      10:40:08,032 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[20162995767377943]:PERSISTENT, deliveryId=23
      javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
       at org.jboss.ejb3.pool.StrictMaxPool.get(StrictMaxPool.java:126)
       at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:58)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)
       at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:194)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.security.RunAsSecurityInterceptorv2.invoke(RunAsSecurityInterceptorv2.java:94)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:75)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:65)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:262)
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:270)
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:140)
       at $Proxy130.onMessage(Unknown Source)
       at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
       at org.jboss.jms.client.container.ClientConsumer.callOnMessageStatic(ClientConsumer.java:160)
       at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:831)
       at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRun_1300817040.invoke(SessionAspect_z_handleRun_1300817040.java)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
       at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.jms.client.delegate.ClientSessionDelegate.run(ClientSessionDelegate.java)
       at org.jboss.jms.client.JBossSession.run(JBossSession.java:199)
       at org.jboss.resource.adapter.jms.inflow.JmsServerSession.run(JmsServerSession.java:234)
       at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
       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:619)
      10:40:08,944 INFO [STDOUT] Completed message: ID:JBM-120989a8-58a3-4967-8be5-e2041dfd4ee5
      10:40:08,945 INFO [STDOUT] Starting message: ID:JBM-6df4383a-6951-4262-9b9c-6d7d1728cfa9
      10:40:10,090 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
      10:40:10,090 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[20162995798851609]:PERSISTENT, deliveryId=25
      javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
       at org.jboss.ejb3.pool.StrictMaxPool.get(StrictMaxPool.java:126)
       at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:58)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)
       at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:194)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.security.RunAsSecurityInterceptorv2.invoke(RunAsSecurityInterceptorv2.java:94)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:75)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:65)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:262)
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:270)
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:140)
       at $Proxy130.onMessage(Unknown Source)
       at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
       at org.jboss.jms.client.container.ClientConsumer.callOnMessageStatic(ClientConsumer.java:160)
       at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:831)
       at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRun_1300817040.invoke(SessionAspect_z_handleRun_1300817040.java)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
       at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
       at org.jboss.jms.client.delegate.ClientSessionDelegate.run(ClientSessionDelegate.java)
       at org.jboss.jms.client.JBossSession.run(JBossSession.java:199)
       at org.jboss.resource.adapter.jms.inflow.JmsServerSession.run(JmsServerSession.java:234)
       at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
       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:619)
      


        • 1. Re: EJBException: Failed to acquire the pool semaphore, stri
          pbaker01

          Any Ideas?
          Probem only occurs when running with max sessions.

          Frustrated in ATL.

          Happy New Year! PB

          • 2. Re: EJBException: Failed to acquire the pool semaphore, stri
            jaikiran

            After how many messages to the MDB does this exception occur? After 25 (=maxSessions)? Also, this looks incorrect to me:

            @ActivationConfigProperty(propertyName = "acknowledgeMode", propertyValue = "Auto-acknowledge")


            Should have been

            @ActivationConfigProperty(propertyName = "acknowledgeMode", propertyValue = "AUTO_ACKNOWLEDGE")


            • 3. Re: EJBException: Failed to acquire the pool semaphore, stri
              pbaker01

              That did not solve the problem :(

              The console log (below) outputs a line each time a message is received and processed. So 20 messages had been started and of those 5 had been completed.

              Thanks for replying on New Years day!!! Any other suggestions will be appreciated.

              Happy New Year! PB

              new MDB:

              package com.xxxx.wab.mdb.ssim;
              
              import java.util.ArrayList;
              
              import javax.ejb.ActivationConfigProperty;
              import javax.ejb.MessageDriven;
              import javax.jms.JMSException;
              import javax.jms.Message;
              import javax.jms.MessageListener;
              import javax.jms.ObjectMessage;
              import javax.persistence.EntityManager;
              import javax.persistence.PersistenceContext;
              
              import com.xxxx.wab.dao.MasterFlightDAO;
              import com.xxxx.wab.dao.SubscriberDAO;
              import com.xxxx.wab.dto.SsimRsp;
              import com.xxxx.wab.service.SsimService;
              import com.xxxx.wab.ssim.SsimFlight;
              
              @MessageDriven(mappedName = "jms/SSMListener", activationConfig = {
               @ActivationConfigProperty(propertyName="messagingType", propertyValue="javax.jms.MessageListener"),
               @ActivationConfigProperty(propertyName = "acknowledgeMode", propertyValue = "AUTO_ACKNOWLEDGE"),
               @ActivationConfigProperty(propertyName = "destinationType", propertyValue = "javax.jms.Queue"),
               @ActivationConfigProperty(propertyName = "maxSession", propertyValue = "25"),
               @ActivationConfigProperty(propertyName="destination",propertyValue="queue/SSMQueue") })
              
              public class SSMListener implements MessageListener {
              
               @PersistenceContext(unitName="WABPU")
               private EntityManager em;
               SsimService ssimService;
              
               private SsimService getSsimService() {
               if (ssimService == null)
               ssimService = new SsimService(em, null);
               MasterFlightDAO fltDAO = new MasterFlightDAO(em);
               SubscriberDAO subscriberDAO = new SubscriberDAO(em);
               ssimService.setFltDAO(fltDAO);
               ssimService.setSubscriberDAO(subscriberDAO);
              
               return ssimService;
               }
              
               public void onMessage(Message arg0) {
               // TODO Auto-generated method stub
               ObjectMessage om = (ObjectMessage) arg0;
               try {
               System.out.println("Starting message: " + om.getJMSMessageID());
               ArrayList<SsimFlight> flts = (ArrayList<SsimFlight>) om.getObject();
               getSsimService().buildFlights(flts);
               System.out.println("Completed message: " + om.getJMSMessageID());
               } catch (JMSException e) {
               // TODO Auto-generated catch block
               e.printStackTrace();
               } finally {
               }
               }
              }




              Console Log:
              09:13:55,386 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.sita.wab.mdb.ssim.SSMListener.onMessage(javax.jms.Message)
              09:13:56,333 WARN [InterceptorRegistry] applicable interceptors is non-existent for public void com.sita.wab.mdb.ssim.SSMListener.onMessage(javax.jms.Message)
              09:13:56,361 INFO [STDOUT] Starting message: ID:JBM-50492cac-9217-467a-9544-a1b1e77b523b
              09:13:57,310 INFO [STDOUT] Starting message: ID:JBM-3b47938a-0611-45d7-819e-982e682f5f07
              09:13:58,100 INFO [STDOUT] Starting message: ID:JBM-90be56ab-661a-47fc-94b0-97ddd20f263a
              09:13:58,955 INFO [STDOUT] Starting message: ID:JBM-40acff81-1248-45c4-9dfb-a17390c71b1d
              09:13:59,891 INFO [STDOUT] Starting message: ID:JBM-7471c02f-fb50-4d63-b01b-c7e15e694210
              09:14:00,884 INFO [STDOUT] Starting message: ID:JBM-f0d8822b-d29c-4938-8e42-fb1cf41ec12c
              09:14:01,671 INFO [STDOUT] Starting message: ID:JBM-fb4f42ff-06d4-4071-9fbb-4ad5b17f6dfb
              09:14:02,645 INFO [STDOUT] Starting message: ID:JBM-0c7fe953-0071-41d9-abda-dd9120ba564c
              09:14:03,648 INFO [STDOUT] Starting message: ID:JBM-a4d8b3f9-2de7-4938-a2b1-b9058618ece6
              09:14:05,327 INFO [STDOUT] Starting message: ID:JBM-23828e1a-a2f9-48b8-80aa-cc9e666f7c29
              09:14:05,451 INFO [STDOUT] Starting message: ID:JBM-074ae110-0e8b-4130-a7bb-08dc5e379d96
              09:14:07,240 INFO [STDOUT] Starting message: ID:JBM-54b1f520-fa1d-4e15-bbe2-3b1b8d81ab5e
              09:14:07,334 INFO [STDOUT] Starting message: ID:JBM-1c32374a-986a-430e-85dc-0cbe09acfe3d
              09:14:09,023 INFO [STDOUT] Starting message: ID:JBM-5703c26e-baf3-44c0-96d4-9e932805528c
              09:14:09,114 INFO [STDOUT] Starting message: ID:JBM-f01f269b-b8ab-4a73-ad76-81e6022037fe
              09:14:11,897 INFO [STDOUT] Completed message: ID:JBM-f0d8822b-d29c-4938-8e42-fb1cf41ec12c
              09:14:11,917 INFO [STDOUT] Starting message: ID:JBM-b9f677e6-7eae-463c-8e78-7b7de728434d
              09:14:11,926 INFO [STDOUT] Completed message: ID:JBM-0c7fe953-0071-41d9-abda-dd9120ba564c
              09:14:11,929 INFO [STDOUT] Starting message: ID:JBM-d730996a-fe0f-45ee-95ec-30390885a4f2
              09:14:14,889 INFO [STDOUT] Completed message: ID:JBM-a4d8b3f9-2de7-4938-a2b1-b9058618ece6
              09:14:14,890 INFO [STDOUT] Starting message: ID:JBM-82052933-8ccb-4fcb-b680-d31aba80ea48
              09:14:21,189 INFO [STDOUT] Completed message: ID:JBM-7471c02f-fb50-4d63-b01b-c7e15e694210
              09:14:21,190 INFO [STDOUT] Starting message: ID:JBM-f666def7-2b21-4fbc-b92d-6c7213d75539
              09:14:21,305 INFO [STDOUT] Completed message: ID:JBM-fb4f42ff-06d4-4071-9fbb-4ad5b17f6dfb
              09:14:21,307 INFO [STDOUT] Starting message: ID:JBM-620331ca-804c-43e8-9f74-4a6bec3db92d
              09:14:24,951 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
              09:14:24,959 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[20165742655045652]:PERSISTENT, deliveryId=20
              javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
               at org.jboss.ejb3.pool.StrictMaxPool.get(StrictMaxPool.java:126)
               at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:58)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)
               at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:194)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.security.RunAsSecurityInterceptorv2.invoke(RunAsSecurityInterceptorv2.java:94)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:75)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:65)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:262)
               at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:270)
               at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:140)
               at $Proxy129.onMessage(Unknown Source)
               at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
               at org.jboss.jms.client.container.ClientConsumer.callOnMessageStatic(ClientConsumer.java:160)
               at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:831)
               at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRun_1448464820.invoke(SessionAspect_z_handleRun_1448464820.java)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
               at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.jms.client.delegate.ClientSessionDelegate.run(ClientSessionDelegate.java)
               at org.jboss.jms.client.JBossSession.run(JBossSession.java:199)
               at org.jboss.resource.adapter.jms.inflow.JmsServerSession.run(JmsServerSession.java:234)
               at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
               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:619)
              09:14:25,925 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
              09:14:25,928 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[20165742671806485]:PERSISTENT, deliveryId=21
              javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
               at org.jboss.ejb3.pool.StrictMaxPool.get(StrictMaxPool.java:126)
               at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:58)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)
               at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:194)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.security.RunAsSecurityInterceptorv2.invoke(RunAsSecurityInterceptorv2.java:94)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:75)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:65)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:262)
               at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:270)
               at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:140)
               at $Proxy129.onMessage(Unknown Source)
               at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
               at org.jboss.jms.client.container.ClientConsumer.callOnMessageStatic(ClientConsumer.java:160)
               at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:831)
               at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect_z_handleRun_1448464820.invoke(SessionAspect_z_handleRun_1448464820.java)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
               at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:86)
               at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
               at org.jboss.jms.client.delegate.ClientSessionDelegate.run(ClientSessionDelegate.java)
               at org.jboss.jms.client.JBossSession.run(JBossSession.java:199)
               at org.jboss.resource.adapter.jms.inflow.JmsServerSession.run(JmsServerSession.java:234)
               at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
               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:619)
              09:14:26,875 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
              09:14:26,875 ERROR [TxPolicy] javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
              


              Additonal Info: (Service Methods)

              This methods queues the messages:

              /**
               * This method queues the flight schedule for processing
               * <p>
               *
               * @param pFlt
               * @throws ApplicationException
               */
               public void queueFlightPacket(ArrayList<SsimFlight> pFlightPacket)
               throws ApplicationException {
              
               Context jndiContext = null;
               ConnectionFactory connectionFactory = null;
               Connection connection = null;
               Session session = null;
               Destination destination = null;
               MessageProducer messageProducer = null;
               ObjectMessage message = null;
              
               try {
               jndiContext = new InitialContext();
               } catch (NamingException e) {
               System.out.println("Could not create JNDI " + "context: "
               + e.toString());
               }
              
               try {
               connectionFactory = (ConnectionFactory) jndiContext
               .lookup("ConnectionFactory");
               destination = (Queue) jndiContext.lookup("queue/SSMQueue");
               } catch (NamingException e) {
               System.out.println("JNDI lookup failed: " + e.toString());
               }
              
               try {
               connection = connectionFactory.createConnection();
               session = connection.createSession(false, Session.AUTO_ACKNOWLEDGE);
               messageProducer = session.createProducer(destination);
               message = session.createObjectMessage();
               message.setObject(pFlightPacket);
               messageProducer.send(message);
               connection.close();
               } catch (JMSException e) {
               System.out.println("Exception occurred: " + e.toString());
               throw new ApplicationException();
               } finally {
               }
               }
              


              • 4. Re: EJBException: Failed to acquire the pool semaphore, stri
                jaikiran

                 

                So 20 messages had been started and of those 5 had been completed


                If you look at the ejb3-interceptors-aop.xml in the deploy folder, you will notice that for the Message Driven Bean, there's a StrictMaxPool setting of max pool size = 15.

                Looking at your logs, it appears that each message processing in the onMessage takes around 10 or more minutes. And you have reached a stage where all the pool size of 15 has exhausted for that MDB. You can configure the pool size for your MDB by using a @PoolClass annotation. See this for details http://www.jboss.org/jbossejb3/docs/reference/build/reference/en/html/session-bean-config.html

                • 5. Re: EJBException: Failed to acquire the pool semaphore, stri
                  pbaker01

                  Hi jaikiran,

                  Problem solved. Thank-you.

                  I have added the annotation and that solved the current problem... I have a new problem and I'll start a new thread for that.

                  By the way, the documentation needs to be updated for JBoss 5.

                  The documenation refers to PoolClass and it appears that JBoss 5 uses "Pool" and not "PoolClass".

                  From referenced documentation:

                  @PoolClass (value=org.jboss.ejb3.StrictMaxPool.class, maxSize=5, timeout=10000)


                  Also the package name for StrickMaxPool has changed to:
                  org.jboss.ejb3.pool.StrictMaxPool

                  The annotation that I am using is:
                  @Pool (value="StrictMaxPool", maxSize=35, timeout=20000)


                  Thanks very much! PB


                  • 6. Re: EJBException: Failed to acquire the pool semaphore, stri
                    jaikiran

                     

                    "pbaker01" wrote:
                    By the way, the documentation needs to be updated for JBoss 5.

                    The documenation refers to PoolClass and it appears that JBoss 5 uses "Pool" and not "PoolClass".




                    That's correct. The documentation for JBossAS-5 will be updated soon.