JBossDeveloper External client hangs when looking up bean
msebi Mar 31, 2018 1:31 AMI'm running an external client that fails to lookup a bean and hangs. I'm using JBoss Developer Studio 11 (installed using jdk1.8.0_121 (x64) running on Win 10) and deployed on JBoss AS 6.x, JBoss AS 71 and Wildfly 11. All server runtimes have been downloaded
through Dev Studio. The code for the client is (the credentials are admin:admin for JBoss AS 6.0.0 (defaults) and admin:test123 for JBoss AS 7 and Wildfly 11):
StreamingClient.java
import java.util.Properties; import javax.naming.Context; import javax.naming.InitialContext; import javax.naming.NamingException; import src.StreamingEJB; public class StreamingClient { private Properties getRemoteProperties() { Properties jndiProperties = new Properties(); // jndiProperties.put(Context.INITIAL_CONTEXT_FACTORY, "org.wildfly.naming.client.WildFlyInitialContextFactory"); jndiProperties.put(Context.INITIAL_CONTEXT_FACTORY, "org.jboss.naming.remote.client.InitialContextFactory"); // jndiProperties.put(Context.PROVIDER_URL, "http-remoting://localhost:8080"); jndiProperties.put(Context.PROVIDER_URL, "remote+http://localhost:8080"); // username jndiProperties.put(Context.SECURITY_PRINCIPAL, "admin"); // password jndiProperties.put(Context.SECURITY_CREDENTIALS, "admin"); return jndiProperties; } //java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory //java.naming.factory.url.pkgs=org.jboss.naming.client //java.naming.factory.provider.url=localhost:8080 private Properties getLocalProperties() { Properties jndiProperties = new Properties(); // jndiProperties.put(Context.INITIAL_CONTEXT_FACTORY, "org.wildfly.naming.client.WildFlyInitialContextFactory"); // Taken from https://stackoverflow.com/questions/41574751/standalone-jboss-ejb-client-application-how-to-get-jndi-connection-distinct jndiProperties.put(Context.URL_PKG_PREFIXES, "org.jboss.ejb.client.naming"); jndiProperties.put(Context.PROVIDER_URL, "localhost:8080"); jndiProperties.put(Context.INITIAL_CONTEXT_FACTORY, "org.jnp.interfaces.NamingContextFactory"); return jndiProperties; } public static void main(String[] args) throws NamingException { System.out.println("Some line to breakpoint"); EJBClient ejbClient = new EJBClient(); InitialContext ctx = new InitialContext(ejbClient.getRemoteProperties()); System.out.println("Looking up bean StreamingEJB"); StreamingEJB= (StreamingEJB) ctx.lookup("StreamingBean"); callerInstance.startStreaming("REST Streaming API"); callerInstance.stopStreaming("REST Streaming API"); } }
The code for the bean is:
StreamingEJB.java
package src; import javax.ejb.LocalBean; import javax.ejb.Remote; import javax.ejb.Stateless; import org.jboss.logging.Logger; /** * Session Bean implementation class StreamingBean */ @Stateless(mappedName = "StreamingBean") @Remote(StreamingEJBRemote.class) public class StreamingEJB implements StreamingEJBRemote { /** * Default constructor. */ public FirstEJB() { // TODO Auto-generated constructor stub } private static final Logger LOGGER = Logger.getLogger(StreamingEJB.class); /** * Default constructor. */ @Override public String startStreaming(String channel) { LOGGER.info("In method startStreaming(String) in class " + this.getClass().getSimpleName()); System.out.println("Starting streaming on channel " + channel); System.out.println("Started streaming on channel " + channel); LOGGER.info("Starting streaming on channel " + channel); LOGGER.info("Started streaming on channel " + channel); return "Started Streaming!!!"; } @Override public String stopStreaming(String channel) { LOGGER.info("In method stopStreaming(String) in class " + this.getClass().getSimpleName()); System.out.println("Stopping streaming on channel " + channel); System.out.println("Stopped streaming on channel " + channel); LOGGER.info("Stopping streaming on channel " + channel); LOGGER.info("Stopped streaming on channel " + channel); return "Stopped Streaming!!!"; } }
StreamingEJBRemote.java
package src; import javax.ejb.Remote; @Remote public interface StreamingEJBRemote { public String startStreaming(String channel); public String stopStreaming(String channel); }
Runs were made using jdk 1.7. Deploying on JBoss AS 6.0.0 seems to run fine:
14:47:35,634 INFO [AbstractJBossASServerBase] Server Configuration: JBOSS_HOME URL: file:/D:/Tools/jboss-6.0.0.Final/ Bootstrap: $JBOSS_HOME\server/default/conf/bootstrap.xml Common Base: $JBOSS_HOME\common/ Common Library: $JBOSS_HOME\common/lib/ Server Name: default Server Base: $JBOSS_HOME\server/ Server Library: $JBOSS_HOME\server/default/lib/ Server Config: $JBOSS_HOME\server/default/conf/ Server Home: $JBOSS_HOME\server/default/ Server Data: $JBOSS_HOME\server/default/data/ Server Log: $JBOSS_HOME\server/default/log/ Server Temp: $JBOSS_HOME\server/default/tmp/ 14:47:35,636 INFO [AbstractServer] Starting: JBossAS [6.0.0.Final "Neo"] 14:47:36,926 INFO [ServerInfo] Java version: 1.7.0_11,Oracle Corporation 14:47:36,926 INFO [ServerInfo] Java Runtime: Java(TM) SE Runtime Environment (build 1.7.0_11-b21) 14:47:36,926 INFO [ServerInfo] Java VM: Java HotSpot(TM) 64-Bit Server VM 23.6-b04,Oracle Corporation 14:47:36,926 INFO [ServerInfo] OS-System: Windows 8 6.2,amd64 14:47:36,926 INFO [ServerInfo] VM arguments: -Dprogram.name=JBossTools: JBoss AS 6.x -Djava.awt.headless=true -Xms256m -Xmx768m -XX:MaxPermSize=256m -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000 -Djava.endorsed.dirs=D:\Tools\jboss-6.0.0.Final\lib\endorsed -Djava.library.path=D:/Tools/jboss-6.0.0.Final/bin/native -Dfile.encoding=Cp1252 14:47:36,956 INFO [JMXKernel] Legacy JMX core initialized 14:47:46,545 INFO [AbstractServerConfig] JBoss Web Services - Stack CXF Server 3.4.1.GA 14:47:46,944 INFO [JSFImplManagementDeployer] Initialized 3 JSF configurations: [Mojarra-1.2, MyFaces-2.0, Mojarra-2.0] 14:47:50,148 WARNING [FileConfigurationParser] AIO wasn't located on this platform, it will fall back to using pure Java NIO. If your platform is Linux, install LibAIO to enable the AIO journal 14:47:52,127 WARNING [FileConfigurationParser] AIO wasn't located on this platform, it will fall back to using pure Java NIO. If your platform is Linux, install LibAIO to enable the AIO journal 14:47:52,320 INFO [JMXConnector] starting JMXConnector on host localhost:1090 14:47:52,431 INFO [MailService] Mail Service bound to java:/Mail 14:47:53,169 INFO [HornetQServerImpl] live server is starting.. 14:47:53,228 INFO [JournalStorageManager] Using NIO Journal 14:47:53,249 WARNING [HornetQServerImpl] Security risk! It has been detected that the cluster admin user and password have not been changed from the installation default. Please see the HornetQ user guide, cluster chapter, for instructions on how to do this. 14:47:53,867 INFO [NettyAcceptor] Started Netty Acceptor version 3.2.1.Final-r2319 localhost:5455 for CORE protocol 14:47:53,868 INFO [NettyAcceptor] Started Netty Acceptor version 3.2.1.Final-r2319 localhost:5445 for CORE protocol 14:47:53,871 INFO [HornetQServerImpl] HornetQ Server version 2.1.2.Final (Colmeia, 120) started 14:47:53,908 INFO [WebService] Using RMI server codebase: http://localhost:8083/ 14:47:54,030 INFO [jbossatx] ARJUNA-32010 JBossTS Recovery Service (tag: JBOSSTS_4_14_0_Final) - JBoss Inc. 14:47:54,035 INFO [arjuna] ARJUNA-12324 Start RecoveryActivators 14:47:54,049 INFO [arjuna] ARJUNA-12296 ExpiredEntryMonitor running at Thu, 29 Mar 2018 14:47:54 14:47:54,100 INFO [arjuna] ARJUNA-12310 Recovery manager listening on endpoint 127.0.0.1:4712 14:47:54,101 INFO [arjuna] ARJUNA-12344 RecoveryManagerImple is ready on port 4712 14:47:54,101 INFO [jbossatx] ARJUNA-32013 Starting transaction recovery manager 14:47:54,113 INFO [arjuna] ARJUNA-12163 Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 4713 14:47:54,113 INFO [arjuna] ARJUNA-12337 TransactionStatusManagerItem host: 127.0.0.1 port: 4713 14:47:54,170 INFO [arjuna] ARJUNA-12170 TransactionStatusManager started on port 4713 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService 14:47:54,201 INFO [jbossatx] ARJUNA-32017 JBossTS Transaction Service (JTA version - tag: JBOSSTS_4_14_0_Final) - JBoss Inc. 14:47:54,225 INFO [arjuna] ARJUNA-12202 registering bean jboss.jta:type=ObjectStore. 14:47:54,410 INFO [AprLifecycleListener] The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: D:/Tools/jboss-6.0.0.Final/bin/native 14:47:54,510 INFO [TomcatDeployment] deploy, ctxPath=/invoker 14:47:54,737 INFO [ModClusterService] Initializing mod_cluster 1.1.0.Final 14:47:54,773 INFO [RARDeployment] Required license terms exist, view vfs:/D:/Tools/jboss-6.0.0.Final/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml 14:47:54,783 INFO [RARDeployment] Required license terms exist, view vfs:/D:/Tools/jboss-6.0.0.Final/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml 14:47:54,790 INFO [RARDeployment] Required license terms exist, view vfs:/D:/Tools/jboss-6.0.0.Final/server/default/deploy/jms-ra.rar/META-INF/ra.xml 14:47:54,828 INFO [HornetQResourceAdapter] HornetQ resource adaptor started 14:47:54,835 INFO [RARDeployment] Required license terms exist, view vfs:/D:/Tools/jboss-6.0.0.Final/server/default/deploy/mail-ra.rar/META-INF/ra.xml 14:47:54,847 INFO [RARDeployment] Required license terms exist, view vfs:/D:/Tools/jboss-6.0.0.Final/server/default/deploy/quartz-ra.rar/META-INF/ra.xml 14:47:54,952 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: Thread-2 14:47:54,984 INFO [SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 14:47:54,984 INFO [QuartzScheduler] Quartz Scheduler v.1.8.3 created. 14:47:54,987 INFO [RAMJobStore] RAMJobStore initialized. 14:47:54,989 INFO [QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v1.8.3) 'JBossQuartzScheduler' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads. Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered. 14:47:54,990 INFO [StdSchedulerFactory] Quartz scheduler 'JBossQuartzScheduler' initialized from an externally opened InputStream. 14:47:54,990 INFO [StdSchedulerFactory] Quartz scheduler version: 1.8.3 14:47:54,990 INFO [QuartzScheduler] Scheduler JBossQuartzScheduler_$_NON_CLUSTERED started. 14:47:55,286 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS' 14:47:55,446 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA' 14:47:55,568 INFO [xnio] XNIO Version 2.1.0.CR2 14:47:55,575 INFO [nio] XNIO NIO Implementation Version 2.1.0.CR2 14:47:55,745 INFO [remoting] JBoss Remoting version 3.1.0.Beta2 14:47:55,825 INFO [TomcatDeployment] deploy, ctxPath=/ 14:47:55,854 INFO [service] Removing bootstrap log handlers 14:47:55,906 INFO [org.apache.coyote.http11.Http11Protocol] Starting Coyote HTTP/1.1 on http-localhost%2F127.0.0.1-8080 14:47:55,911 INFO [org.apache.coyote.ajp.AjpProtocol] Starting Coyote AJP/1.3 on ajp-localhost%2F127.0.0.1-8009 14:47:55,912 INFO [org.jboss.bootstrap.impl.base.server.AbstractServer] JBossAS [6.0.0.Final "Neo"] Started in 20s:272ms 14:47:56,306 INFO [org.jboss.jpa.mcint.beans.metadata.plugins.PersistenceUnitValueMetaData] iDependOn persistence.unit:unitName=jboss-ejb3-timerservice-mk2.jar#timerdb 14:47:56,314 INFO [org.jboss.jpa.deployment.PersistenceUnitDeployment] Starting persistence unit persistence.unit:unitName=jboss-ejb3-timerservice-mk2.jar#timerdb 14:47:56,532 INFO [org.hibernate.annotations.common.Version] Hibernate Commons Annotations 3.2.0.Final 14:47:56,542 INFO [org.hibernate.cfg.Environment] Hibernate 3.6.0.Final 14:47:56,545 INFO [org.hibernate.cfg.Environment] hibernate.properties not found 14:47:56,549 INFO [org.hibernate.cfg.Environment] Bytecode provider name : javassist 14:47:56,554 INFO [org.hibernate.cfg.Environment] using JDK 1.4 java.sql.Timestamp handling 14:47:56,660 INFO [org.hibernate.ejb.Version] Hibernate EntityManager 3.6.0.Final 14:47:56,687 INFO [org.hibernate.ejb.Ejb3Configuration] Processing PersistenceUnitInfo [ name: timerdb ...] 14:47:56,706 WARN [org.hibernate.ejb.Ejb3Configuration] Persistence provider caller does not implement the EJB3 spec correctly.PersistenceUnitInfo.getNewTempClassLoader() is null. 14:47:56,799 INFO [org.hibernate.cfg.AnnotationBinder] Binding entity from annotated class: org.jboss.ejb3.timerservice.mk2.persistence.TimerEntity 14:47:56,873 INFO [org.hibernate.cfg.annotations.EntityBinder] Bind entity org.jboss.ejb3.timerservice.mk2.persistence.TimerEntity on table timer 14:47:56,950 INFO [org.hibernate.cfg.AnnotationBinder] Binding entity from annotated class: org.jboss.ejb3.timerservice.mk2.persistence.TimeoutMethod 14:47:56,957 INFO [org.hibernate.cfg.annotations.EntityBinder] Bind entity org.jboss.ejb3.timerservice.mk2.persistence.TimeoutMethod on table timeout_method 14:47:57,001 INFO [org.hibernate.cfg.AnnotationBinder] Binding entity from annotated class: org.jboss.ejb3.timerservice.mk2.persistence.CalendarTimerEntity 14:47:57,003 INFO [org.hibernate.cfg.annotations.EntityBinder] Bind entity org.jboss.ejb3.timerservice.mk2.persistence.CalendarTimerEntity on table calendar_timer 14:47:57,058 INFO [org.hibernate.validator.Version] Hibernate Validator 3.1.0.GA 14:47:57,109 INFO [org.hibernate.validator.util.Version] Hibernate Validator 4.1.0.Final 14:47:57,125 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 14:47:57,266 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 14:47:57,272 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 14:47:57,280 INFO [org.hibernate.cfg.search.HibernateSearchEventListenerRegister] Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. 14:47:57,294 INFO [org.hibernate.connection.ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider 14:47:57,303 INFO [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider] Using provided datasource 14:47:57,307 INFO [org.hibernate.cfg.SettingsFactory] Database -> name : HSQL Database Engine version : 1.8.0 major : 1 minor : 8 14:47:57,307 INFO [org.hibernate.cfg.SettingsFactory] Driver -> name : HSQL Database Engine Driver version : 1.8.0 major : 1 minor : 8 14:47:57,342 INFO [org.hibernate.dialect.Dialect] Using dialect: org.hibernate.dialect.HSQLDialect 14:47:57,365 INFO [org.hibernate.engine.jdbc.JdbcSupportLoader] Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 14:47:57,367 INFO [org.hibernate.transaction.TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory 14:47:57,371 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup 14:47:57,374 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiated TransactionManagerLookup 14:47:57,375 INFO [org.hibernate.cfg.SettingsFactory] Automatic flush during beforeCompletion(): disabled 14:47:57,375 INFO [org.hibernate.cfg.SettingsFactory] Automatic session close at end of transaction: disabled 14:47:57,375 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch size: 15 14:47:57,375 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch updates for versioned data: disabled 14:47:57,376 INFO [org.hibernate.cfg.SettingsFactory] Scrollable result sets: enabled 14:47:57,377 INFO [org.hibernate.cfg.SettingsFactory] JDBC3 getGeneratedKeys(): disabled 14:47:57,377 INFO [org.hibernate.cfg.SettingsFactory] Connection release mode: auto 14:47:57,378 INFO [org.hibernate.cfg.SettingsFactory] Default batch fetch size: 1 14:47:57,378 INFO [org.hibernate.cfg.SettingsFactory] Generate SQL with comments: disabled 14:47:57,378 INFO [org.hibernate.cfg.SettingsFactory] Order SQL updates by primary key: disabled 14:47:57,379 INFO [org.hibernate.cfg.SettingsFactory] Order SQL inserts for batching: disabled 14:47:57,379 INFO [org.hibernate.cfg.SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory 14:47:57,382 INFO [org.hibernate.hql.ast.ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory 14:47:57,382 INFO [org.hibernate.cfg.SettingsFactory] Query language substitutions: {} 14:47:57,382 INFO [org.hibernate.cfg.SettingsFactory] JPA-QL strict compliance: enabled 14:47:57,382 INFO [org.hibernate.cfg.SettingsFactory] Second-level cache: enabled 14:47:57,383 INFO [org.hibernate.cfg.SettingsFactory] Query cache: disabled 14:47:57,384 INFO [org.hibernate.cfg.SettingsFactory] Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge 14:47:57,392 INFO [org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge] Cache provider: org.hibernate.cache.HashtableCacheProvider 14:47:57,394 INFO [org.hibernate.cfg.SettingsFactory] Optimize cache for minimal puts: disabled 14:47:57,394 INFO [org.hibernate.cfg.SettingsFactory] Cache region prefix: persistence.unit:unitName=jboss-ejb3-timerservice-mk2.jar#timerdb 14:47:57,394 INFO [org.hibernate.cfg.SettingsFactory] Structured second-level cache entries: disabled 14:47:57,405 INFO [org.hibernate.cfg.SettingsFactory] Statistics: disabled 14:47:57,405 INFO [org.hibernate.cfg.SettingsFactory] Deleted entity synthetic identifier rollback: disabled 14:47:57,406 INFO [org.hibernate.cfg.SettingsFactory] Default entity-mode: pojo 14:47:57,406 INFO [org.hibernate.cfg.SettingsFactory] Named query checking : enabled 14:47:57,406 INFO [org.hibernate.cfg.SettingsFactory] Check Nullability in Core (should be disabled when Bean Validation is on): disabled 14:47:57,443 INFO [org.hibernate.impl.SessionFactoryImpl] building session factory 14:47:57,686 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Factory name: persistence.unit:unitName=jboss-ejb3-timerservice-mk2.jar#timerdb 14:47:57,688 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 14:47:57,691 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.unit:unitName=jboss-ejb3-timerservice-mk2.jar#timerdb 14:47:57,691 WARN [org.hibernate.impl.SessionFactoryObjectFactory] InitialContext did not implement EventContext 14:47:57,699 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] Running hbm2ddl schema update 14:47:57,700 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] fetching database metadata 14:47:57,701 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] updating schema 14:47:57,703 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 14:47:57,728 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] table found: PUBLIC.TIMEOUTMETHOD_METHODPARAMS 14:47:57,729 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] columns: [methodparams, timeoutmethod_id] 14:47:57,729 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] foreign keys: [fkf294c964b7de2d8a] 14:47:57,729 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] indexes: [sys_idx_55] 14:47:57,738 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] table found: PUBLIC.CALENDAR_TIMER 14:47:57,738 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] columns: [id, scheduleexprdayofmonth, scheduleexprenddate, scheduleexprtimezone, scheduleexprsecond, autotimer, scheduleexprmonth, scheduleexprstartdate, scheduleexprminute, scheduleexprdayofweek, scheduleexpryear, scheduleexprhour, timeoutmethod_id] 14:47:57,738 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] foreign keys: [fk2b697f04b7de2d8a, fk2b697f04e6e6ef93] 14:47:57,739 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] indexes: [sys_idx_57, sys_idx_49, sys_idx_59] 14:47:57,746 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] table found: PUBLIC.TIMEOUT_METHOD 14:47:57,746 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] columns: [id, methodname, declaringclass] 14:47:57,746 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] foreign keys: [] 14:47:57,746 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] indexes: [sys_idx_51] 14:47:57,759 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] table found: PUBLIC.TIMER 14:47:57,759 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] columns: [id, previousrun, initialdate, repeatinterval, timedobjectid, timerstate, nextdate, info] 14:47:57,759 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] foreign keys: [] 14:47:57,759 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] indexes: [sys_idx_53] 14:47:57,761 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] schema update complete 14:47:57,766 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 14:47:57,869 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1141435756{vfs:///C:/Users/admin/Desktop/Work/Skyvva/EJBWorkSpace2/.metadata/.plugins/org.jboss.ide.eclipse.as.core/JBoss_AS_6.x1522312172779/deploy/EJBProject.jar} 14:47:57,870 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@1141435756{vfs:///C:/Users/admin/Desktop/Work/Skyvva/EJBWorkSpace2/.metadata/.plugins/org.jboss.ide.eclipse.as.core/JBoss_AS_6.x1522312172779/deploy/EJBProject.jar} 14:47:57,890 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@58c58c2b into MC at org.jboss.ejb.bean.instantiator/EJBProject/EJBProject/StreamingEJB 14:47:57,899 WARN [org.jboss.ejb3.interceptor.InterceptorInfoRepository] EJBTHREE-1852: InterceptorInfoRepository is deprecated 14:47:58,204 INFO [org.jboss.ejb3.deployers.JBossASKernel] Created KernelDeployment for: EJBProject.jar 14:47:58,207 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:jar=EJBProject.jar,name=StreamingEJB,service=EJB3 14:47:58,207 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 14:47:58,207 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 14:47:58,207 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 14:47:58,207 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=EJBProject,module=EJBProject,name=StreamingEJB; Required: Create 14:47:58,208 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 14:47:58,208 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:src.StreamingEJBRemote 14:47:58,208 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:StreamingBean 14:47:58,208 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:StreamingEJB/remote-src.StreamingEJBRemote 14:47:58,212 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:jar=EJBProject.jar,name=StreamingEJB,service=EJB3) to KernelDeployment of: EJBProject.jar 14:47:58,259 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:jar=EJBProject.jar,name=StreamingEJB,service=EJB3 14:47:58,264 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: src.StreamingEJB ejbName: StreamingEJB 14:47:58,294 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: StreamingBean - EJB3.x Default Remote Business Interface StreamingEJB/remote-src.StreamingEJBRemote - EJB3.x Remote Business Interface 14:47:58,295 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers
When running the client, the context lookup hangs:
Some line to breakpoint Mar 29, 2018 2:54:12 PM org.jboss.naming.remote.client.InitialContextFactory INFO: WFNAM00025: org.jboss.naming.remote.client.InitialContextFactory is deprecated; new applications should use org.wildfly.naming.client.WildFlyInitialContextFactory instead Mar 29, 2018 2:54:13 PM org.wildfly.naming.client.Version INFO: WildFly Naming version 1.0.7.Final Mar 29, 2018 2:54:15 PM org.wildfly.security.Version INFO: ELY00001: WildFly Elytron version 1.1.6.Final Looking up bean CallerBean Mar 29, 2018 2:54:17 PM org.xnio.Xnio INFO: XNIO version 3.5.4.Final Mar 29, 2018 2:54:18 PM org.xnio.nio.NioXnio INFO: XNIO NIO Implementation Version 3.5.4.Final Mar 29, 2018 2:54:20 PM org.jboss.remoting3.EndpointImpl INFO: JBoss Remoting version 5.0.5.Final
This behavior persists when running using either jdk 1.7 and 1.8 and across all server instances. JBoss AS 7.1 and Wild 11.0 both have preconfigured management users and deploy successfully but the client fails to lookup the bean. Why?