Hi Jhalliday,
Thank you for taking the time to look at this. The system does not "hang" but it degregates to the point where, for all practical purposes, it is hung up. The console messages in the original post were the same as the ones that I was getting so I thought there may be a connection.
I have tried a number of combinations of sessions verse the work packet size. The work packet size represents the number of entries in each queue message. A work pack size of 1 will generate approximately 1 query and up to 30 x 2 inserts (two tables are updated on each insert).
The message producer and message consumer (MDB) both work at the same time.
JBoss: version 4.2.3
JBoss Messaing 1.4
MySQL is used as the datasource
JPA/Hibernate used for message consumer
Taking your advise, I reduced the work pack size to 5 and increased the number of sessions to 25.
With this configuration, the processing starts up and runs well for the first few minutes but then quickly degenerates. See below for new console output.
Any tuning advise will be greating appreciated. If you do find anything to change, will you please identify the configuration file along with the setting?
Thanks so much! PB
Console Log:
06:47:42,908 INFO [Server] Starting JBoss (MX MicroKernel)...
06:47:42,909 INFO [Server] Release ID: JBoss [Trinity] 4.2.3.GA (build: SVNTag=JBoss_4_2_3_GA date=200807181417)
06:47:42,910 INFO [Server] Home Dir: C:\JBoss\jboss-4.2.3.GA
06:47:42,910 INFO [Server] Home URL: file:/C:/JBoss/jboss-4.2.3.GA/
06:47:42,910 INFO [Server] Patch URL: null
06:47:42,911 INFO [Server] Server Name: messaging
06:47:42,911 INFO [Server] Server Home Dir: C:\JBoss\jboss-4.2.3.GA\server\messaging
06:47:42,911 INFO [Server] Server Home URL: file:/C:/JBoss/jboss-4.2.3.GA/server/messaging/
06:47:42,911 INFO [Server] Server Log Dir: C:\JBoss\jboss-4.2.3.GA\server\messaging\log
06:47:42,911 INFO [Server] Server Temp Dir: C:\JBoss\jboss-4.2.3.GA\server\messaging\tmp
06:47:42,912 INFO [Server] Root Deployment Filename: jboss-service.xml
06:47:43,140 INFO [ServerInfo] Java version: 1.6.0_10,Sun Microsystems Inc.
06:47:43,140 INFO [ServerInfo] Java VM: Java HotSpot(TM) 64-Bit Server VM 11.0-b15,Sun Microsystems Inc.
06:47:43,141 INFO [ServerInfo] OS-System: Windows Vista 6.0,amd64
06:47:43,433 INFO [Server] Core system initialized
06:47:44,968 INFO [WebService] Using RMI server codebase: http://127.0.0.1:8083/
06:47:44,970 INFO [Log4jService$URLWatchTimerTask] Configuring from URL: resource:jboss-log4j.xml
06:47:45,712 INFO [TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
06:47:45,712 INFO [TransactionManagerService] Setting up property manager MBean and JMX layer
06:47:45,931 INFO [TransactionManagerService] Starting recovery manager
06:47:46,038 INFO [TransactionManagerService] Recovery manager started
06:47:46,038 INFO [TransactionManagerService] Binding TransactionManager JNDI Reference
06:47:48,137 INFO [EJB3Deployer] Starting java:comp multiplexer
06:47:48,943 INFO [ServerPeer] ServerPeer[0] creating server peer with ID 0
06:47:50,782 INFO [NativeServerConfig] JBoss Web Services - Native
06:47:50,782 INFO [NativeServerConfig] jbossws-3.0.1-native-2.0.4.GA (build=200803312044)
06:47:51,503 INFO [Embedded] Catalina naming disabled
06:47:51,629 INFO [AprLifecycleListener] The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: C:\Program Files\Java\jdk1.6.0_10\bin
06:47:51,678 INFO [Http11Protocol] Initializing Coyote HTTP/1.1 on http-127.0.0.1-8080
06:47:51,684 INFO [AjpProtocol] Initializing Coyote AJP/1.3 on ajp-127.0.0.1-8009
06:47:51,684 INFO [Catalina] Initialization processed in 181 ms
06:47:51,684 INFO [StandardService] Starting service jboss.web
06:47:51,686 INFO [StandardEngine] Starting Servlet Engine: JBossWeb/2.0.1.GA
06:47:51,723 INFO [Catalina] Server startup in 39 ms
06:47:51,806 INFO [TomcatDeployer] deploy, ctxPath=/, warUrl=.../deploy/jboss-web.deployer/ROOT.war/
06:47:52,507 INFO [TomcatDeployer] deploy, ctxPath=/invoker, warUrl=.../deploy/http-invoker.sar/invoker.war/
06:47:52,644 INFO [TomcatDeployer] deploy, ctxPath=/jbossws, warUrl=.../deploy/jbossws.sar/jbossws-context.war/
06:47:52,946 INFO [TomcatDeployer] deploy, ctxPath=/web-console, warUrl=.../deploy/management/console-mgr.sar/web-console.war/
06:47:53,471 INFO [MailService] Mail Service bound to java:/Mail
06:47:53,627 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-ha-local-jdbc.rar
06:47:53,668 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-ha-xa-jdbc.rar
06:47:53,707 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-local-jdbc.rar
06:47:53,745 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-xa-jdbc.rar
06:47:53,830 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jms-ra.rar
06:47:53,866 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/mail-ra.rar
06:47:53,916 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/quartz-ra.rar
06:47:53,927 INFO [QuartzResourceAdapter] start quartz!!!
06:47:54,003 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
06:47:54,042 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
06:47:54,045 INFO [RAMJobStore] RAMJobStore initialized.
06:47:54,045 INFO [StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
06:47:54,045 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
06:47:54,046 INFO [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
06:47:54,266 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=WABDS' to JNDI name 'java:WABDS'
06:47:54,455 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
06:47:54,568 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
06:47:56,541 INFO [ServerPeer] JBoss Messaging 1.3.0.GA server [0] started
06:47:56,873 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
06:47:56,873 INFO [ConnectionFactory] [/ConnectionFactory, /XAConnectionFactory, java:/ConnectionFactory, java:/XAConnectionFactory] started
06:47:56,874 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
06:47:56,874 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
06:47:56,876 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
06:47:56,876 INFO [ConnectionFactory] [/ClusteredConnectionFactory, /ClusteredXAConnectionFactory, java:/ClusteredConnectionFactory, java:/ClusteredXAConnectionFactory] started
06:47:57,029 INFO [QueueService] Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,049 INFO [QueueService] Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,051 INFO [TopicService] Topic[/topic/testTopic] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,052 INFO [TopicService] Topic[/topic/securedTopic] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,053 INFO [TopicService] Topic[/topic/testDurableTopic] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,074 INFO [QueueService] Queue[/queue/testQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,107 INFO [QueueService] Queue[/queue/A] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,132 INFO [QueueService] Queue[/queue/B] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,157 INFO [QueueService] Queue[/queue/C] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,182 INFO [QueueService] Queue[/queue/D] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,207 INFO [QueueService] Queue[/queue/ex] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,268 INFO [QueueService] Queue[/queue/PrivateDLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,298 INFO [QueueService] Queue[/queue/PrivateExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,332 INFO [QueueService] Queue[/queue/QueueWithOwnDLQAndExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,333 INFO [TopicService] Topic[/topic/TopicWithOwnDLQAndExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,348 INFO [QueueService] Queue[/queue/QueueWithOwnRedeliveryDelay] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,349 INFO [TopicService] Topic[/topic/TopicWithOwnRedeliveryDelay] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,374 INFO [QueueService] Queue[/queue/testDistributedQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,375 INFO [TopicService] Topic[/topic/testDistributedTopic] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,399 INFO [QueueService] Queue[/queue/SSMQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
06:47:57,914 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.entity.PersistenceUnitDeployment
06:47:57,919 INFO [JmxKernelAbstraction] installing MBean: persistence.units:jar=WAB_EJB.jar,unitName=WABPU with dependencies:
06:47:57,919 INFO [JmxKernelAbstraction] jboss.jca:name=WABDS,service=DataSourceBinding
06:47:57,921 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.units:jar=WAB_EJB.jar,unitName=WABPU
06:47:57,949 INFO [Version] Hibernate EntityManager 3.2.1.GA
06:47:57,972 INFO [Version] Hibernate Annotations 3.2.1.GA
06:47:57,982 INFO [Environment] Hibernate 3.2.4.sp1
06:47:57,987 INFO [Environment] hibernate.properties not found
06:47:57,990 INFO [Environment] Bytecode provider name : javassist
06:47:57,997 INFO [Environment] using JDK 1.4 java.sql.Timestamp handling
06:47:58,514 INFO [Configuration] Reading mappings from resource : META-INF/orm.xml
06:47:58,516 INFO [Ejb3Configuration] [PersistenceUnit: WABPU] no META-INF/orm.xml found
06:47:58,942 INFO [HbmBinder] Mapping class: com.sita.wab.entities.aircraft.AircraftType -> AircraftType
06:47:59,085 INFO [HbmBinder] Mapping class: com.sita.wab.entities.aircraft.AircraftTypeRevision -> AircraftTypeRevision
06:47:59,087 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineImpl -> Airline
06:47:59,096 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineBaggagePriorityImpl -> AirlineBaggagePriority
06:47:59,097 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineContactImpl -> AirlineContact
06:47:59,099 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineFlightTypeImpl -> AirlineFlightType
06:47:59,100 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineLoadInformationCodeImpl -> AirlineLoadInformationCode
06:47:59,101 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineMessageDeliveryOptionImpl -> AirlineMessageDeliveryOption
06:47:59,102 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineOptionsImpl -> AirlineOptions
06:47:59,109 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.AirlineUnitsOfMeasureImpl -> AirlineUnitsOfMeasure
06:47:59,110 INFO [HbmBinder] Mapping class: com.sita.wab.entities.airline.SubscriberImpl -> Subscriber
06:47:59,111 INFO [HbmBinder] Mapping class: com.sita.wab.entities.masterflight.MasterFlightImpl -> MasterFlight
06:47:59,116 INFO [HbmBinder] Mapping class: com.sita.wab.entities.masterflight.MasterFlightLegImpl -> MasterFlightLeg
06:47:59,123 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.aircraft.AircraftType.SharedAircraftTypes -> AircraftType
06:47:59,124 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.aircraft.AircraftType.AircraftTypeRevisions -> AircraftTypeRevision
06:47:59,124 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.aircraft.AircraftTypeRevision.SharedAircraftTypeRevisions -> AircraftTypeRevision
06:47:59,124 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.airline.AirlineImpl.AirlineLoadInformationCodes -> AirlineLoadInformationCode
06:47:59,124 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.airline.AirlineImpl.AirlineFlightTypes -> AirlineFlightType
06:47:59,124 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.airline.AirlineImpl.AirlineMessageDeliveryOption -> AirlineMessageDeliveryOption
06:47:59,125 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.airline.AirlineImpl.AirlineBaggagePriority -> AirlineBaggagePriority
06:47:59,125 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.airline.AirlineImpl.AircraftTypes -> AircraftType
06:47:59,125 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.airline.SubscriberImpl.Airline -> Airline
06:47:59,125 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.airline.SubscriberImpl.MasterFlights -> MasterFlight
06:47:59,125 INFO [HbmBinder] Mapping collection: com.sita.wab.entities.masterflight.MasterFlightImpl.MasterFlightLegs -> MasterFlightLeg
06:47:59,301 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
06:47:59,307 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
06:47:59,320 INFO [SettingsFactory] RDBMS: MySQL, version: 5.0.67-community-nt
06:47:59,320 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 $ )
06:47:59,352 INFO [Dialect] Using dialect: org.hibernate.dialect.MySQLDialect
06:47:59,360 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory
06:47:59,364 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
06:47:59,367 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
06:47:59,367 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
06:47:59,367 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
06:47:59,368 INFO [SettingsFactory] JDBC batch size: 15
06:47:59,368 INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
06:47:59,369 INFO [SettingsFactory] Scrollable result sets: enabled
06:47:59,369 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
06:47:59,369 INFO [SettingsFactory] Connection release mode: auto
06:47:59,371 INFO [SettingsFactory] Maximum outer join fetch depth: 2
06:47:59,372 INFO [SettingsFactory] Default batch fetch size: 1
06:47:59,372 INFO [SettingsFactory] Generate SQL with comments: disabled
06:47:59,372 INFO [SettingsFactory] Order SQL updates by primary key: disabled
06:47:59,372 INFO [SettingsFactory] Order SQL inserts for batching: disabled
06:47:59,372 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
06:47:59,379 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
06:47:59,379 INFO [SettingsFactory] Query language substitutions: {}
06:47:59,379 INFO [SettingsFactory] JPA-QL strict compliance: enabled
06:47:59,379 INFO [SettingsFactory] Second-level cache: enabled
06:47:59,379 INFO [SettingsFactory] Query cache: disabled
06:47:59,379 INFO [SettingsFactory] Cache provider: org.hibernate.cache.HashtableCacheProvider
06:47:59,383 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
06:47:59,383 INFO [SettingsFactory] Cache region prefix: WAB_EJB_jar,WABPU
06:47:59,383 INFO [SettingsFactory] Structured second-level cache entries: disabled
06:47:59,397 INFO [SettingsFactory] Statistics: disabled
06:47:59,397 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
06:47:59,397 INFO [SettingsFactory] Default entity-mode: pojo
06:47:59,397 INFO [SettingsFactory] Named query checking : enabled
06:47:59,452 INFO [SessionFactoryImpl] building session factory
06:47:59,838 INFO [SessionFactoryObjectFactory] Factory name: persistence.units:jar=WAB_EJB.jar,unitName=WABPU
06:47:59,840 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
06:47:59,847 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.units:jar=WAB_EJB.jar,unitName=WABPU
06:47:59,847 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
06:47:59,848 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
06:48:00,119 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.stateless.StatelessContainer
06:48:00,127 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:jar=WAB_EJB.jar,name=AirlineEjbBean,service=EJB3 with dependencies:
06:48:00,127 INFO [JmxKernelAbstraction] persistence.units:jar=WAB_EJB.jar,unitName=WABPU
06:48:00,195 INFO [EJBContainer] STARTED EJB: com.sita.wab.ejb.airline.AirlineEjbBean ejbName: AirlineEjbBean
06:48:00,287 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.stateless.StatelessContainer
06:48:00,288 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:jar=WAB_EJB.jar,name=SsimEjbBean,service=EJB3 with dependencies:
06:48:00,288 INFO [JmxKernelAbstraction] persistence.units:jar=WAB_EJB.jar,unitName=WABPU
06:48:00,295 INFO [EJBContainer] STARTED EJB: com.sita.wab.ejb.ssim.SsimEjbBean ejbName: SsimEjbBean
06:48:00,305 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.stateless.StatelessContainer
06:48:00,305 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:jar=WAB_EJB.jar,name=SubscriberEjbBean,service=EJB3 with dependencies:
06:48:00,305 INFO [JmxKernelAbstraction] persistence.units:jar=WAB_EJB.jar,unitName=WABPU
06:48:00,312 INFO [EJBContainer] STARTED EJB: com.sita.wab.ejb.subscriber.SubscriberEjbBean ejbName: SubscriberEjbBean
06:48:00,322 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.mdb.MDB
06:48:00,326 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:jar=WAB_EJB.jar,name=SSMListener,service=EJB3 with dependencies:
06:48:00,326 INFO [JmxKernelAbstraction] persistence.units:jar=WAB_EJB.jar,unitName=WABPU
06:48:00,333 INFO [EJBContainer] STARTED EJB: com.sita.wab.mdb.ssim.SSMListener ejbName: SSMListener
06:48:00,371 INFO [EJB3Deployer] Deployed: file:/C:/JBoss/jboss-4.2.3.GA/server/messaging/deploy/WAB_EJB.jar/
06:48:00,411 INFO [TomcatDeployer] deploy, ctxPath=/WAB, warUrl=.../deploy/WAB.war/
06:48:00,621 WARN [config] JSF1059: WARNING! The com.sun.faces.verifyObjects feature is to aid developers not using tools. It shouldn''t be enabled if using an IDE, or if this application is being deployed for production as it will impact application start times.
06:48:04,473 INFO [CacheManager] Selected [org.ajax4jsf.cache.LRUMapCacheFactory] cache factory
06:48:04,482 INFO [LRUMapCacheFactory] Creating LRUMap cache instance using parameters: {facelets.DEVELOPMENT=true, enable-cache=false, 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.JBossInjectionProvider, com.sun.faces.validateXml=true, org.richfaces.LoadScriptStrategy=ALL, org.richfaces.LoadStyleStrategy=ALL, facelets.SKIP_COMMENTS=true, com.sun.faces.verifyObjects=true, javax.faces.DEFAULT_SUFFIX=.xhtml}
06:48:04,482 INFO [LRUMapCacheFactory] Creating LRUMap cache instance of default capacity
06:48:04,508 INFO [CacheManager] Selected [org.ajax4jsf.cache.LRUMapCacheFactory] cache factory
06:48:04,509 INFO [LRUMapCacheFactory] Creating LRUMap cache instance using parameters: {facelets.DEVELOPMENT=true, enable-cache=false, 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.JBossInjectionProvider, com.sun.faces.validateXml=true, org.richfaces.LoadScriptStrategy=ALL, org.richfaces.LoadStyleStrategy=ALL, facelets.SKIP_COMMENTS=true, com.sun.faces.verifyObjects=true, javax.faces.DEFAULT_SUFFIX=.xhtml}
06:48:04,509 INFO [LRUMapCacheFactory] Creating LRUMap cache instance of default capacity
06:48:04,546 INFO [TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=.../deploy/jmx-console.war/
06:48:04,718 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-127.0.0.1-8080
06:48:04,743 INFO [AjpProtocol] Starting Coyote AJP/1.3 on ajp-127.0.0.1-8009
06:48:04,757 INFO [Server] JBoss (MX MicroKernel) [4.2.3.GA (build: SVNTag=JBoss_4_2_3_GA date=200807181417)] Started in 21s:844ms
06:48:17,572 INFO [STDOUT] Starting message: ID:JBM-0
06:48:18,230 INFO [STDOUT] Starting message: ID:JBM-1
06:48:18,650 INFO [STDOUT] Starting message: ID:JBM-2
06:48:18,724 INFO [STDOUT] Starting message: ID:JBM-3
06:48:19,412 INFO [STDOUT] Starting message: ID:JBM-4
06:48:19,506 INFO [STDOUT] Starting message: ID:JBM-5
06:48:20,279 INFO [STDOUT] Starting message: ID:JBM-6
06:48:20,628 INFO [STDOUT] Starting message: ID:JBM-7
06:48:20,729 INFO [STDOUT] Starting message: ID:JBM-8
06:48:21,135 INFO [STDOUT] Starting message: ID:JBM-9
06:48:21,857 INFO [STDOUT] Starting message: ID:JBM-10
06:48:21,985 INFO [STDOUT] Starting message: ID:JBM-11
06:48:22,628 INFO [STDOUT] Starting message: ID:JBM-12
06:48:22,796 INFO [STDOUT] Starting message: ID:JBM-13
06:48:23,182 INFO [STDOUT] Starting message: ID:JBM-14
06:48:23,928 INFO [STDOUT] Completed message: ID:JBM-7
06:48:23,940 INFO [STDOUT] Starting message: ID:JBM-15
06:48:24,049 INFO [STDOUT] Completed message: ID:JBM-3
06:48:24,088 INFO [STDOUT] Starting message: ID:JBM-16
06:48:25,018 INFO [STDOUT] Completed message: ID:JBM-5
06:48:25,019 INFO [STDOUT] Starting message: ID:JBM-17
06:48:25,029 INFO [STDOUT] Completed message: ID:JBM-12
06:48:25,113 INFO [STDOUT] Starting message: ID:JBM-18
06:48:25,447 INFO [STDOUT] Completed message: ID:JBM-8
06:48:25,647 INFO [STDOUT] Completed message: ID:JBM-6
06:48:25,769 INFO [STDOUT] Completed message: ID:JBM-11
06:48:26,266 INFO [STDOUT] Starting message: ID:JBM-19
06:48:26,871 INFO [STDOUT] Starting message: ID:JBM-20
06:48:27,235 INFO [STDOUT] Completed message: ID:JBM-17
06:48:27,302 INFO [STDOUT] Starting message: ID:JBM-21
06:48:27,705 INFO [STDOUT] Starting message: ID:JBM-22
06:48:29,806 INFO [STDOUT] Completed message: ID:JBM-4
06:48:29,820 INFO [STDOUT] Starting message: ID:JBM-23
06:48:30,296 INFO [STDOUT] Completed message: ID:JBM-9
06:48:30,297 INFO [STDOUT] Starting message: ID:JBM-24
06:48:30,591 INFO [STDOUT] Completed message: ID:JBM-13
06:48:30,592 INFO [STDOUT] Starting message: ID:JBM-25
06:48:31,892 INFO [STDOUT] Completed message: ID:JBM-21
06:48:31,893 INFO [STDOUT] Starting message: ID:JBM-26
06:48:32,008 INFO [STDOUT] Completed message: ID:JBM-22
06:48:32,008 INFO [STDOUT] Starting message: ID:JBM-27
06:48:32,608 INFO [STDOUT] Completed message: ID:JBM-2
06:48:32,608 INFO [STDOUT] Starting message: ID:JBM-28
06:48:34,510 INFO [STDOUT] Completed message: ID:JBM-14
06:48:34,512 INFO [STDOUT] Starting message: ID:JBM-29
06:48:36,428 INFO [STDOUT] Completed message: ID:JBM-26
06:48:36,428 INFO [STDOUT] Starting message: ID:JBM-30
06:48:38,340 INFO [STDOUT] Completed message: ID:JBM-27
06:48:38,341 INFO [STDOUT] Starting message: ID:JBM-31
06:48:41,862 INFO [STDOUT] Completed message: ID:JBM-23
06:48:41,862 INFO [STDOUT] Starting message: ID:JBM-32
06:48:42,741 INFO [STDOUT] Completed message: ID:JBM-24
06:48:42,743 INFO [STDOUT] Starting message: ID:JBM-33
06:48:42,785 INFO [STDOUT] Completed message: ID:JBM-10
06:48:42,786 INFO [STDOUT] Starting message: ID:JBM-34
06:48:42,887 INFO [STDOUT] Completed message: ID:JBM-30
06:48:42,889 INFO [STDOUT] Starting message: ID:JBM-35
06:48:43,374 INFO [STDOUT] Completed message: ID:JBM-19
06:48:43,380 INFO [STDOUT] Starting message: ID:JBM-36
06:48:43,551 INFO [STDOUT] Completed message: ID:JBM-15
06:48:43,552 INFO [STDOUT] Starting message: ID:JBM-37
06:48:43,950 INFO [STDOUT] Completed message: ID:JBM-29
06:48:43,950 INFO [STDOUT] Starting message: ID:JBM-38
06:48:43,950 INFO [STDOUT] Completed message: ID:JBM-32
06:48:43,952 INFO [STDOUT] Starting message: ID:JBM-39
06:48:44,660 INFO [STDOUT] Completed message: ID:JBM-35
06:48:44,667 INFO [STDOUT] Starting message: ID:JBM-40
06:48:44,836 INFO [STDOUT] Completed message: ID:JBM-34
06:48:44,839 INFO [STDOUT] Starting message: ID:JBM-41
06:48:46,515 INFO [STDOUT] Completed message: ID:JBM-33
06:48:46,524 INFO [STDOUT] Starting message: ID:JBM-42
06:48:51,955 INFO [STDOUT] Completed message: ID:JBM-16
06:48:51,956 INFO [STDOUT] Starting message: ID:JBM-43
06:48:54,164 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[46]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:48:54,164 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[44]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:48:54,164 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[45]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:48:54,680 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[47]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:48:54,686 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[48]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:48:54,839 INFO [STDOUT] Completed message: ID:JBM-18
06:48:54,840 INFO [STDOUT] Starting message: ID:JBM-49
06:48:55,318 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[50]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:48:56,392 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[51]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:48:57,557 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[52]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:49:03,212 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[53]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:49:03,773 INFO [STDOUT] Completed message: ID:JBM-43
06:49:03,774 INFO [STDOUT] Starting message: ID:JBM-54
06:49:04,302 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[45]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:49:04,300 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[46]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:49:04,938 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[48]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:49:04,938 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[44]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:49:05,502 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[47]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)
06:49:06,595 ERROR [JmsServerSession] Unexpected error delivering message delegator->JBossMessage[50]:PERSISTENT
javax.ejb.EJBException: Failed to acquire the pool semaphore, strictTimeout=10000
at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:122)
at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy84.onMessage(Unknown Source)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.onMessage(JmsServerSession.java:178)
at org.jboss.jms.client.remoting.MessageCallbackHandler.callOnMessage(MessageCallbackHandler.java:153)
at org.jboss.jms.client.container.SessionAspect.handleRun(SessionAspect.java:804)
at org.jboss.aop.advice.org.jboss.jms.client.container.SessionAspect14.invoke(SessionAspect14.java)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
at org.jboss.jms.client.container.ClosedInterceptor.invoke(ClosedInterceptor.java:170)
at org.jboss.aop.advice.PerInstanceInterceptor.invoke(PerInstanceInterceptor.java:105)
at org.jboss.jms.client.delegate.ClientSessionDelegate$run_N8003352271541955702.invokeNext(ClientSessionDelegate$run_N8003352271541955702.java)
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:237)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:619)