Hello friends,
I follow the soa sample of JBoss Tool to implement HelloWorld in Eclipse. I deployed the HelloWorld ESB to the server, then start server. Then I run the HelloWorld_TestClient. Everything works fine except the message I expect from MyListenerAction class is not printed out. I attach the server log here so that you can help me to figure out the issue. Thanks for any help.
09:31:15,455 INFO [ServerImpl] Starting JBoss (Microcontainer)...
09:31:15,456 INFO [ServerImpl] Release ID: JBoss [The Oracle] 5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)
09:31:15,456 INFO [ServerImpl] Bootstrap URL: null
09:31:15,456 INFO [ServerImpl] Home Dir: D:\software\jboss-5.1.0.GA
09:31:15,456 INFO [ServerImpl] Home URL: file:/D:/software/jboss-5.1.0.GA/
09:31:15,456 INFO [ServerImpl] Library URL: file:/D:/software/jboss-5.1.0.GA/lib/
09:31:15,457 INFO [ServerImpl] Patch URL: null
09:31:15,457 INFO [ServerImpl] Common Base URL: file:/D:/software/jboss-5.1.0.GA/common/
09:31:15,457 INFO [ServerImpl] Common Library URL: file:/D:/software/jboss-5.1.0.GA/common/lib/
09:31:15,457 INFO [ServerImpl] Server Name: default
09:31:15,457 INFO [ServerImpl] Server Base Dir: D:\software\jboss-5.1.0.GA\server
09:31:15,457 INFO [ServerImpl] Server Base URL: file:/D:/software/jboss-5.1.0.GA/server/
09:31:15,457 INFO [ServerImpl] Server Config URL: file:/D:/software/jboss-5.1.0.GA/server/default/conf/
09:31:15,457 INFO [ServerImpl] Server Home Dir: D:\software\jboss-5.1.0.GA\server\default
09:31:15,457 INFO [ServerImpl] Server Home URL: file:/D:/software/jboss-5.1.0.GA/server/default/
09:31:15,457 INFO [ServerImpl] Server Data Dir: D:\software\jboss-5.1.0.GA\server\default\data
09:31:15,457 INFO [ServerImpl] Server Library URL: file:/D:/software/jboss-5.1.0.GA/server/default/lib/
09:31:15,458 INFO [ServerImpl] Server Log Dir: D:\software\jboss-5.1.0.GA\server\default\log
09:31:15,458 INFO [ServerImpl] Server Native Dir: D:\software\jboss-5.1.0.GA\server\default\tmp\native
09:31:15,458 INFO [ServerImpl] Server Temp Dir: D:\software\jboss-5.1.0.GA\server\default\tmp
09:31:15,458 INFO [ServerImpl] Server Temp Deploy Dir: D:\software\jboss-5.1.0.GA\server\default\tmp\deploy
09:31:15,786 INFO [ServerImpl] Starting Microcontainer, bootstrapURL=file:/D:/software/jboss-5.1.0.GA/server/default/conf/bootstrap.xml
09:31:16,082 INFO [VFSCacheFactory] Initializing VFSCache [org.jboss.virtual.plugins.cache.CombinedVFSCache]
09:31:16,084 INFO [VFSCacheFactory] Using VFSCache [CombinedVFSCache[real-cache: null]]
09:31:16,226 INFO [CopyMechanism] VFS temp dir: D:\software\jboss-5.1.0.GA\server\default\tmp
09:31:16,226 INFO [ZipEntryContext] VFS force nested jars copy-mode is enabled.
09:31:16,781 INFO [ServerInfo] Java version: 1.7.0_45,Oracle Corporation
09:31:16,781 INFO [ServerInfo] Java Runtime: Java(TM) SE Runtime Environment (build 1.7.0_45-b18)
09:31:16,781 INFO [ServerInfo] Java VM: Java HotSpot(TM) 64-Bit Server VM 24.45-b08,Oracle Corporation
09:31:16,781 INFO [ServerInfo] OS-System: Windows 7 6.1,amd64
09:31:16,782 INFO [ServerInfo] VM arguments: -Dprogram.name=JBossTools: JBoss 5.1 Runtime Server -Xms256m -Xmx768m -XX:MaxPermSize=256m -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000 -Djava.endorsed.dirs=D:\software\jboss-5.1.0.GA\lib\endorsed -Dfile.encoding=Cp1252
09:31:16,799 INFO [JMXKernel] Legacy JMX core initialized
09:31:17,690 INFO [ProfileServiceBootstrap] Loading profile: ProfileKey@57c468d7[domain=default, server=default, name=default]
09:31:18,400 INFO [WebService] Using RMI server codebase: http://localhost:8083/
09:31:20,655 INFO [NativeServerConfig] JBoss Web Services - Stack Native Core
09:31:20,655 INFO [NativeServerConfig] 3.1.2.GA
09:31:20,826 INFO [AttributeCallbackItem] Owner callback not implemented.
09:31:21,358 INFO [LogNotificationListener] Adding notification listener for logging mbean "jboss.system:service=Logging,type=Log4jService" to server org.jboss.mx.server.MBeanServerImpl@6165c64[ defaultDomain='jboss' ]
09:31:25,066 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1693803282{vfsfile:/D:/software/jboss-5.1.0.GA/server/default/deploy/profileservice-secured.jar/}
09:31:25,067 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1693803282{vfsfile:/D:/software/jboss-5.1.0.GA/server/default/deploy/profileservice-secured.jar/}
09:31:25,067 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1693803282{vfsfile:/D:/software/jboss-5.1.0.GA/server/default/deploy/profileservice-secured.jar/}
09:31:25,067 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1693803282{vfsfile:/D:/software/jboss-5.1.0.GA/server/default/deploy/profileservice-secured.jar/}
09:31:26,687 INFO [JMXConnectorServerService] JMX Connector server: service:jmx:rmi://localhost/jndi/rmi://localhost:1090/jmxconnector
09:31:26,744 INFO [MailService] Mail Service bound to java:/Mail
09:31:28,149 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.
09:31:28,157 WARN [AnnotationCreator] No ClassLoader provided, using TCCL: org.jboss.managed.api.annotation.ManagementComponent
09:31:28,188 WARN [AnnotationCreator] No ClassLoader provided, using TCCL: org.jboss.managed.api.annotation.ManagementComponent
09:31:28,211 INFO [TransactionManagerService] JBossTS Transaction Service (JTA version - tag:JBOSSTS_4_6_1_GA) - JBoss Inc.
09:31:28,211 INFO [TransactionManagerService] Setting up property manager MBean and JMX layer
09:31:28,358 INFO [TransactionManagerService] Initializing recovery manager
09:31:28,410 INFO [TransactionManagerService] Recovery manager configured
09:31:28,410 INFO [TransactionManagerService] Binding TransactionManager JNDI Reference
09:31:28,422 INFO [TransactionManagerService] Starting transaction recovery manager
09:31:28,613 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\jre7\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;native;C:\tibco\tibrv\8.4\bin;C:\Program Files (x86)\Intel\iCLS Client\;C:\Program Files\Intel\iCLS Client\;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\Intel\Intel(R) Management Engine Components\DAL;C:\Program Files\Intel\Intel(R) Management Engine Components\IPT;C:\Program Files (x86)\Intel\Intel(R) Management Engine Components\DAL;C:\Program Files (x86)\Intel\Intel(R) Management Engine Components\IPT;C:\Program Files (x86)\Intel\OpenCL SDK\2.0\bin\x86;C:\Program Files (x86)\Intel\OpenCL SDK\2.0\bin\x64;C:\Program Files\Java\jdk1.7.0_45\bin;C:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;C:\Program Files\Microsoft SQL Server\100\Tools\Binn\;C:\Program Files\Microsoft SQL Server\100\DTS\Binn\;D:\software\apache-ant-1.9.2\bin;.
09:31:28,644 INFO [Http11Protocol] Initializing Coyote HTTP/1.1 on http-localhost%2F127.0.0.1-8081
09:31:28,645 INFO [AjpProtocol] Initializing Coyote AJP/1.3 on ajp-localhost%2F127.0.0.1-8010
09:31:28,655 INFO [StandardService] Starting service jboss.web
09:31:28,657 INFO [StandardEngine] Starting Servlet Engine: JBoss Web/2.1.3.GA
09:31:28,682 INFO [Catalina] Server startup in 37 ms
09:31:28,692 INFO [TomcatDeployment] deploy, ctxPath=/invoker
09:31:29,404 INFO [TomcatDeployment] deploy, ctxPath=/jbossws
09:31:29,425 INFO [TomcatDeployment] deploy, ctxPath=/web-console
09:31:29,591 INFO [RARDeployment] Required license terms exist, view vfszip:/D:/software/jboss-5.1.0.GA/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml
09:31:29,600 INFO [RARDeployment] Required license terms exist, view vfszip:/D:/software/jboss-5.1.0.GA/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml
09:31:29,611 INFO [RARDeployment] Required license terms exist, view vfszip:/D:/software/jboss-5.1.0.GA/server/default/deploy/jms-ra.rar/META-INF/ra.xml
09:31:29,619 INFO [RARDeployment] Required license terms exist, view vfszip:/D:/software/jboss-5.1.0.GA/server/default/deploy/mail-ra.rar/META-INF/ra.xml
09:31:29,630 INFO [RARDeployment] Required license terms exist, view vfszip:/D:/software/jboss-5.1.0.GA/server/default/deploy/quartz-ra.rar/META-INF/ra.xml
09:31:29,664 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
09:31:29,676 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
09:31:29,677 INFO [RAMJobStore] RAMJobStore initialized.
09:31:29,678 INFO [StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
09:31:29,678 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
09:31:29,678 INFO [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
09:31:29,873 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
09:31:30,072 INFO [ServerPeer] JBoss Messaging 1.4.3.GA server [0] started
09:31:30,108 INFO [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
09:31:30,108 INFO [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
09:31:30,131 INFO [ConnectionFactory] Connector bisocket://localhost:4457 has leasing enabled, lease period 10000 milliseconds
09:31:30,131 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@75168398 started
09:31:30,132 INFO [ConnectionFactory] Connector bisocket://localhost:4457 has leasing enabled, lease period 10000 milliseconds
09:31:30,132 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@4a5587dc started
09:31:30,139 INFO [QueueService] Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
09:31:30,140 INFO [ConnectionFactory] Connector bisocket://localhost:4457 has leasing enabled, lease period 10000 milliseconds
09:31:30,140 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@2927fec started
09:31:30,141 INFO [QueueService] Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
09:31:30,180 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
09:31:30,353 INFO [JBossASKernel] Created KernelDeployment for: profileservice-secured.jar
09:31:30,356 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3
09:31:30,356 INFO [JBossASKernel] with dependencies:
09:31:30,356 INFO [JBossASKernel] and demands:
09:31:30,356 INFO [JBossASKernel] jndi:SecureManagementView/remote-org.jboss.deployers.spi.management.ManagementView
09:31:30,356 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
09:31:30,356 INFO [JBossASKernel] and supplies:
09:31:30,356 INFO [JBossASKernel] Class:org.jboss.profileservice.spi.ProfileService
09:31:30,356 INFO [JBossASKernel] jndi:SecureProfileService/remote
09:31:30,356 INFO [JBossASKernel] jndi:SecureProfileService/remote-org.jboss.profileservice.spi.ProfileService
09:31:30,357 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3) to KernelDeployment of: profileservice-secured.jar
09:31:30,357 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3
09:31:30,357 INFO [JBossASKernel] with dependencies:
09:31:30,357 INFO [JBossASKernel] and demands:
09:31:30,357 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
09:31:30,357 INFO [JBossASKernel] and supplies:
09:31:30,357 INFO [JBossASKernel] jndi:SecureDeploymentManager/remote-org.jboss.deployers.spi.management.deploy.DeploymentManager
09:31:30,357 INFO [JBossASKernel] Class:org.jboss.deployers.spi.management.deploy.DeploymentManager
09:31:30,357 INFO [JBossASKernel] jndi:SecureDeploymentManager/remote
09:31:30,357 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3) to KernelDeployment of: profileservice-secured.jar
09:31:30,358 INFO [JBossASKernel] installing bean: jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3
09:31:30,358 INFO [JBossASKernel] with dependencies:
09:31:30,358 INFO [JBossASKernel] and demands:
09:31:30,358 INFO [JBossASKernel] jboss.ejb:service=EJBTimerService
09:31:30,358 INFO [JBossASKernel] and supplies:
09:31:30,358 INFO [JBossASKernel] jndi:SecureManagementView/remote-org.jboss.deployers.spi.management.ManagementView
09:31:30,358 INFO [JBossASKernel] Class:org.jboss.deployers.spi.management.ManagementView
09:31:30,358 INFO [JBossASKernel] jndi:SecureManagementView/remote
09:31:30,358 INFO [JBossASKernel] Added bean(jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3) to KernelDeployment of: profileservice-secured.jar
09:31:30,361 INFO [EJB3EndpointDeployer] Deploy AbstractBeanMetaData@78b66c31{name=jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3_endpoint bean=org.jboss.ejb3.endpoint.deployers.impl.EndpointImpl properties=[container] constructor=null autowireCandidate=true}
09:31:30,361 INFO [EJB3EndpointDeployer] Deploy AbstractBeanMetaData@915ca3a{name=jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3_endpoint bean=org.jboss.ejb3.endpoint.deployers.impl.EndpointImpl properties=[container] constructor=null autowireCandidate=true}
09:31:30,361 INFO [EJB3EndpointDeployer] Deploy AbstractBeanMetaData@2b02727e{name=jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3_endpoint bean=org.jboss.ejb3.endpoint.deployers.impl.EndpointImpl properties=[container] constructor=null autowireCandidate=true}
09:31:30,487 INFO [SessionSpecContainer] Starting jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3
09:31:30,493 INFO [EJBContainer] STARTED EJB: org.jboss.profileservice.ejb.SecureDeploymentManager ejbName: SecureDeploymentManager
09:31:30,534 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:
SecureDeploymentManager/remote - EJB3.x Default Remote Business Interface
SecureDeploymentManager/remote-org.jboss.deployers.spi.management.deploy.DeploymentManager - EJB3.x Remote Business Interface
09:31:30,620 INFO [SessionSpecContainer] Starting jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3
09:31:30,621 INFO [EJBContainer] STARTED EJB: org.jboss.profileservice.ejb.SecureManagementView ejbName: SecureManagementView
09:31:30,633 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:
SecureManagementView/remote - EJB3.x Default Remote Business Interface
SecureManagementView/remote-org.jboss.deployers.spi.management.ManagementView - EJB3.x Remote Business Interface
09:31:30,660 INFO [SessionSpecContainer] Starting jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3
09:31:30,661 INFO [EJBContainer] STARTED EJB: org.jboss.profileservice.ejb.SecureProfileServiceBean ejbName: SecureProfileService
09:31:30,665 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:
SecureProfileService/remote - EJB3.x Default Remote Business Interface
SecureProfileService/remote-org.jboss.profileservice.spi.ProfileService - EJB3.x Remote Business Interface
09:31:30,738 INFO [TomcatDeployment] deploy, ctxPath=/admin-console
09:31:30,777 INFO [config] Initializing Mojarra (1.2_12-b01-FCS) for context '/admin-console'
09:31:31,735 INFO [TomcatDeployment] deploy, ctxPath=/
09:31:31,761 INFO [TomcatDeployment] deploy, ctxPath=/jmx-console
09:31:31,791 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-localhost%2F127.0.0.1-8081
09:31:31,806 INFO [AjpProtocol] Starting Coyote AJP/1.3 on ajp-localhost%2F127.0.0.1-8010
09:31:31,810 INFO [ServerImpl] JBoss (Microcontainer) [5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)] Started in 16s:351ms
09:31:37,311 INFO [QueueService] Queue[/queue/quickstart_helloworld_Request_esb] started, fullSize=200000, pageSize=2000, downCacheSize=2000
09:31:37,343 INFO [QueueService] Queue[/queue/quickstart_helloworld_Request_gw] started, fullSize=200000, pageSize=2000, downCacheSize=2000