Bug 912819 - Node HAProxy timeouts too small
Summary: Node HAProxy timeouts too small
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: OKD
Classification: Red Hat
Component: Containers
Version: 2.x
Hardware: Unspecified
OS: Unspecified
medium
medium
Target Milestone: ---
: ---
Assignee: Bill DeCoste
QA Contact: libra bugs
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2013-02-19 18:36 UTC by Bill DeCoste
Modified: 2015-05-14 23:05 UTC (History)
3 users (show)

Fixed In Version: https://github.com/openshift/origin-server/pull/1423
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2013-03-15 14:09:47 UTC
Target Upstream Version:
Embargoed:


Attachments (Terms of Use)
jboss_server_log (39.02 KB, text/plain)
2013-03-06 02:20 UTC, Meng Bo
no flags Details
jbossas_server_log (60.55 KB, text/plain)
2013-03-07 02:20 UTC, Meng Bo
no flags Details

Description Bill DeCoste 2013-02-19 18:36:31 UTC
Description of problem:
The "timeout client" and "timeout server" settings in the Node HAProxy (port-proxy.cfg) are currently 1m. These are too low for database pooling and ends up causing a lot of failed connections. The AS/EAP pools recover but it causes a lot of noise and inefficiency. I'd like to increase these timeouts to at least something like 10m. This has come up with a couple users recently. 


Version-Release number of selected component (if applicable):


How reproducible:
Frequently and easily

Steps to Reproduce:
1. Create a scaled AS/EAP instance with embedded postgresql
2. Deploy sfsbTest-1.0.war from https://github.com/bdecoste/sfsbTest.git
3. Hit http://whatever/sfsbTest-1.0/SfsbServlet
  
Actual results:
Will see:
2013/02/19 13:19:25,548 WARN  [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http-/127.0.250.129:8080-1) IJ000305: Connection error occured: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@d4295[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@acf575 connection handles=0 lastUse=1361297646747 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolByCri@19ab665 pool internal context=SemaphoreArrayListManagedConnectionPool@75dd33[pool=PostgreSQLDS] xaResource=LocalXAResourceImpl@8e56b0[connectionListener=d4295 connectionManager=1e83464 warned=false currentXid=null] txSync=null]: org.postgresql.util.PSQLException: An I/O error occured while sending to the backend.
	at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:283)
	at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:479)
	at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:353)
	at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:345)
	at org.jboss.jca.adapters.jdbc.CheckValidConnectionSQL.isValidConnection(CheckValidConnectionSQL.java:74)
	at org.jboss.jca.adapters.jdbc.BaseWrapperManagedConnectionFactory.isValidConnection(BaseWrapperManagedConnectionFactory.java:1097)
	at org.jboss.jca.adapters.jdbc.BaseWrapperManagedConnection.checkValid(BaseWrapperManagedConnection.java:501)
	at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.matchManagedConnections(LocalManagedConnectionFactory.java:464)
	at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:284)
	at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getTransactionNewConnection(AbstractPool.java:495)
	at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getConnection(AbstractPool.java:374)
	at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:329)
	at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:368)
	at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:464)
	at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:139)
	at org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider.getConnection(InjectedDataSourceConnectionProvider.java:70) [hibernate-entitymanager.jar:4.1.6.Final-redhat-3]
	at org.hibernate.internal.AbstractSessionImpl$NonContextualJdbcConnectionAccess.obtainConnection(AbstractSessionImpl.java:281) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.obtainConnection(LogicalConnectionImpl.java:297) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.getConnection(LogicalConnectionImpl.java:169) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler.extractPhysicalConnection(ConnectionProxyHandler.java:82) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler.continueInvocation(ConnectionProxyHandler.java:138) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.engine.jdbc.internal.proxy.AbstractProxyHandler.invoke(AbstractProxyHandler.java:81) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at sun.proxy.$Proxy44.prepareStatement(Unknown Source)
	at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$5.doPrepare(StatementPreparerImpl.java:147) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$StatementPreparationTemplate.prepareStatement(StatementPreparerImpl.java:166) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.engine.jdbc.internal.StatementPreparerImpl.prepareQueryStatement(StatementPreparerImpl.java:145) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.Loader.prepareQueryStatement(Loader.java:1720) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1697) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.Loader.doQuery(Loader.java:832) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:293) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:263) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.Loader.loadEntity(Loader.java:1977) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:82) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:72) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:3821) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.event.internal.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:458) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.event.internal.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:427) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.event.internal.DefaultLoadEventListener.load(DefaultLoadEventListener.java:204) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.event.internal.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:260) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.event.internal.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:148) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.internal.SessionImpl.fireLoad(SessionImpl.java:1079) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.internal.SessionImpl.access$2200(SessionImpl.java:172) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.internal.SessionImpl$IdentifierLoadAccessImpl.load(SessionImpl.java:2425) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.internal.SessionImpl.get(SessionImpl.java:975) [hibernate-core.jar:4.1.6.Final-redhat-3]
	at org.hibernate.ejb.AbstractEntityManagerImpl.find(AbstractEntityManagerImpl.java:807) [hibernate-entitymanager.jar:4.1.6.Final-redhat-3]
	at org.hibernate.ejb.AbstractEntityManagerImpl.find(AbstractEntityManagerImpl.java:781) [hibernate-entitymanager.jar:4.1.6.Final-redhat-3]
	at org.jboss.as.jpa.container.AbstractEntityManager.find(AbstractEntityManager.java:193) [jboss-as-jpa.jar:7.1.3.Final-redhat-4]
	at org.jboss.jndiTest.EntityTesterBean.findEntity(EntityTesterBean.java:53) [classes:]
	at sun.reflect.GeneratedMethodAccessor16.invoke(Unknown Source) [:1.7.0_09-icedtea]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_09-icedtea]
	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_09-icedtea]
	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.jpa.interceptor.SBInvocationInterceptor.processInvocation(SBInvocationInterceptor.java:47) [jboss-as-jpa.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.component.pool.PooledInstanceInterceptor.processInvocation(PooledInstanceInterceptor.java:51) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:226) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:302) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:188) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.remote.EJBRemoteTransactionPropagatingInterceptor.processInvocation(EJBRemoteTransactionPropagatingInterceptor.java:79) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:42) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:32) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-2]
	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.ejb3.remote.LocalEjbReceiver.processInvocation(LocalEjbReceiver.java:221) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
	at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:181) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at org.jboss.ejb.client.TransactionInterceptor.handleInvocation(TransactionInterceptor.java:42) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:183) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at org.jboss.ejb.client.ReceiverInterceptor.handleInvocation(ReceiverInterceptor.java:125) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:183) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at org.jboss.ejb.client.EJBInvocationHandler.doInvoke(EJBInvocationHandler.java:136) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at org.jboss.ejb.client.EJBInvocationHandler.doInvoke(EJBInvocationHandler.java:121) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at org.jboss.ejb.client.EJBInvocationHandler.invoke(EJBInvocationHandler.java:104) [jboss-ejb-client.jar:1.0.11.Final-redhat-1]
	at sun.proxy.$Proxy43.findEntity(Unknown Source)
	at org.jboss.sfsbTest.SfsbServlet.runTest(SfsbServlet.java:160) [classes:]
	at org.jboss.sfsbTest.SfsbServlet.callSfsb(SfsbServlet.java:87) [classes:]
	at org.jboss.sfsbTest.SfsbServlet.doGet(SfsbServlet.java:58) [classes:]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:734) [jboss-servlet-api_3.0_spec.jar:1.0.1.Final-redhat-2]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec.jar:1.0.1.Final-redhat-2]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329) [jbossweb.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248) [jbossweb.jar:]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275) [jbossweb.jar:]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161) [jbossweb.jar:]
	at org.jboss.as.web.session.ClusteredSessionValve.handleRequest(ClusteredSessionValve.java:134) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.session.ClusteredSessionValve.invoke(ClusteredSessionValve.java:99) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.session.JvmRouteValve.invoke(JvmRouteValve.java:92) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.session.LockingValve.invoke(LockingValve.java:64) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.jpa.interceptor.WebNonTxEmCloserValve.invoke(WebNonTxEmCloserValve.java:50) [jboss-as-jpa.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155) [jbossweb.jar:]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) [jbossweb.jar:]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) [jbossweb.jar:]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:372) [jbossweb.jar:]
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:877) [jbossweb.jar:]
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:679) [jbossweb.jar:]
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:931) [jbossweb.jar:]
	at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_09-icedtea]
Caused by: java.io.EOFException
	at org.postgresql.core.PGStream.ReceiveChar(PGStream.java:261)
	at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1620)
	at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:257)
	... 119 more

2013/02/19 13:19:25,554 WARN  [org.jboss.jca.core.connectionmanager.pool.strategy.PoolByCri] (http-/127.0.250.129:8080-1) IJ000612: Destroying connection that could not be successfully matched: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@d4295[state=DESTROYED managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@acf575 connection handles=0 lastUse=1361297646747 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolByCri@19ab665 pool internal context=SemaphoreArrayListManagedConnectionPool@75dd33[pool=PostgreSQLDS] xaResource=LocalXAResourceImpl@8e56b0[connectionListener=d4295 connectionManager=1e83464 warned=false currentXid=null] txSync=null]


Expected results:
Exception should be rare


Additional info:

Comment 2 Wei Sun 2013-03-01 07:20:14 UTC
Bug 912215 still got an SELinux issue, waiting for its fix to verify this bug.

Comment 3 Meng Bo 2013-03-05 05:02:22 UTC
Checked on devenv_2894, still met some error when hit the url

1.Create scalable jbosseap app with postgresql embedded.
2.Build a sfsbTest-1.0.war from the github
$mvn package
3.Git push
4.Visit the http://appurl/sfsbTest-1.0/SfsbServlet

Output from website:
type Exception report

message

description The server encountered an internal error () that prevented it from fulfilling this request.

exception

javax.servlet.ServletException: javax.naming.NameNotFoundException: RemoteConnectionFactory -- service jboss.naming.context.java.RemoteConnectionFactory
	org.jboss.sfsbTest.SfsbServlet.callSfsb(SfsbServlet.java:95)
	org.jboss.sfsbTest.SfsbServlet.doGet(SfsbServlet.java:58)
	javax.servlet.http.HttpServlet.service(HttpServlet.java:734)
	javax.servlet.http.HttpServlet.service(HttpServlet.java:847)

root cause

javax.naming.NameNotFoundException: RemoteConnectionFactory -- service jboss.naming.context.java.RemoteConnectionFactory
	org.jboss.as.naming.ServiceBasedNamingStore.lookup(ServiceBasedNamingStore.java:97)
	org.jboss.as.naming.NamingContext.lookup(NamingContext.java:179)
	org.jboss.as.naming.InitialContext.lookup(InitialContext.java:119)
	org.jboss.as.naming.NamingContext.lookup(NamingContext.java:215)
	javax.naming.InitialContext.lookup(InitialContext.java:411)
	org.jboss.sfsbTest.JmsClient.setupJMSConnection(JmsClient.java:104)
	org.jboss.sfsbTest.JmsClient.sendMessageOverJMS(JmsClient.java:61)
	org.jboss.sfsbTest.SfsbServlet.runTest(SfsbServlet.java:171)
	org.jboss.sfsbTest.SfsbServlet.callSfsb(SfsbServlet.java:87)
	org.jboss.sfsbTest.SfsbServlet.doGet(SfsbServlet.java:58)
	javax.servlet.http.HttpServlet.service(HttpServlet.java:734)
	javax.servlet.http.HttpServlet.service(HttpServlet.java:847)


jboss app log:
2013/03/04 23:55:41,135 INFO  [org.jboss.sfsbTest.SfsbServlet] (http-127.0.250.129/127.0.250.129:8080-2) ########################### callSfsb ########################
2013/03/04 23:55:41,135 INFO  [stdout] (http-127.0.250.129/127.0.250.129:8080-2) remotings null
2013/03/04 23:55:41,135 INFO  [stdout] (http-127.0.250.129/127.0.250.129:8080-2) -------------------------------
2013/03/04 23:55:41,137 INFO  [stdout] (http-127.0.250.129/127.0.250.129:8080-2) !!! jndi props {java.naming.provider.url=remote://localhost:5445, java.naming.factory.url.pkgs=org.jboss.ejb.client.naming}
2013/03/04 23:55:41,137 INFO  [stdout] (http-127.0.250.129/127.0.250.129:8080-2) !!!!!!!! remoting localhost:5445
2013/03/04 23:55:41,137 INFO  [stdout] (http-127.0.250.129/127.0.250.129:8080-2) !!!!!!!! props {java.naming.provider.url=remote://localhost:5445, java.naming.factory.url.pkgs=org.jboss.as.naming.interfaces:org.jboss.ejb.client.naming}
2013/03/04 23:55:41,137 INFO  [stdout] (http-127.0.250.129/127.0.250.129:8080-2) !!! RemoteConnectionFactory
2013/03/04 23:55:41,138 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) javax.naming.NameNotFoundException: RemoteConnectionFactory -- service jboss.naming.context.java.RemoteConnectionFactory
2013/03/04 23:55:41,149 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.naming.ServiceBasedNamingStore.lookup(ServiceBasedNamingStore.java:97)
2013/03/04 23:55:41,150 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.naming.NamingContext.lookup(NamingContext.java:179)
2013/03/04 23:55:41,153 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.naming.InitialContext.lookup(InitialContext.java:119)
2013/03/04 23:55:41,153 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.naming.NamingContext.lookup(NamingContext.java:215)
2013/03/04 23:55:41,153 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at javax.naming.InitialContext.lookup(InitialContext.java:411)
2013/03/04 23:55:41,154 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.sfsbTest.JmsClient.setupJMSConnection(JmsClient.java:104)
2013/03/04 23:55:41,155 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.sfsbTest.JmsClient.sendMessageOverJMS(JmsClient.java:61)
2013/03/04 23:55:41,157 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.sfsbTest.SfsbServlet.runTest(SfsbServlet.java:171)
2013/03/04 23:55:41,157 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.sfsbTest.SfsbServlet.callSfsb(SfsbServlet.java:87)
2013/03/04 23:55:41,157 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.sfsbTest.SfsbServlet.doGet(SfsbServlet.java:58)
2013/03/04 23:55:41,157 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at javax.servlet.http.HttpServlet.service(HttpServlet.java:734)
2013/03/04 23:55:41,161 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847)
2013/03/04 23:55:41,163 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
2013/03/04 23:55:41,163 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
2013/03/04 23:55:41,163 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
2013/03/04 23:55:41,163 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
2013/03/04 23:55:41,163 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.web.session.ClusteredSessionValve.handleRequest(ClusteredSessionValve.java:134)
2013/03/04 23:55:41,165 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.web.session.ClusteredSessionValve.invoke(ClusteredSessionValve.java:99)
2013/03/04 23:55:41,165 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.web.session.JvmRouteValve.invoke(JvmRouteValve.java:92)
2013/03/04 23:55:41,168 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.web.session.LockingValve.invoke(LockingValve.java:64)
2013/03/04 23:55:41,169 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.jpa.interceptor.WebNonTxEmCloserValve.invoke(WebNonTxEmCloserValve.java:50)
2013/03/04 23:55:41,178 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169)
2013/03/04 23:55:41,179 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
2013/03/04 23:55:41,179 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
2013/03/04 23:55:41,179 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
2013/03/04 23:55:41,179 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:372)
2013/03/04 23:55:41,179 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:877)
2013/03/04 23:55:41,179 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:679)
2013/03/04 23:55:41,179 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:931)
2013/03/04 23:55:41,180 ERROR [stderr] (http-127.0.250.129/127.0.250.129:8080-2) 	at java.lang.Thread.run(Thread.java:722)
2013/03/04 23:55:41,180 ERROR [org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/sfsbTest-1.0].[org.jboss.sfsbTest.SfsbServlet]] (http-127.0.250.129/127.0.250.129:8080-2) Servlet.service() for servlet org.jboss.sfsbTest.SfsbServlet threw exception: javax.naming.NameNotFoundException: RemoteConnectionFactory -- service jboss.naming.context.java.RemoteConnectionFactory
	at org.jboss.as.naming.ServiceBasedNamingStore.lookup(ServiceBasedNamingStore.java:97)
	at org.jboss.as.naming.NamingContext.lookup(NamingContext.java:179)
	at org.jboss.as.naming.InitialContext.lookup(InitialContext.java:119)
	at org.jboss.as.naming.NamingContext.lookup(NamingContext.java:215)
	at javax.naming.InitialContext.lookup(InitialContext.java:411) [rt.jar:1.7.0_09-icedtea]
	at org.jboss.sfsbTest.JmsClient.setupJMSConnection(JmsClient.java:104) [classes:]
	at org.jboss.sfsbTest.JmsClient.sendMessageOverJMS(JmsClient.java:61) [classes:]
	at org.jboss.sfsbTest.SfsbServlet.runTest(SfsbServlet.java:171) [classes:]
	at org.
jboss.sfsbTest.SfsbServlet.callSfsb(SfsbServlet.java:87) [classes:]
	at org.jboss.sfsbTest.SfsbServlet.doGet(SfsbServlet.java:58) [classes:]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:734) [jboss-servlet-api_3.0_spec.jar:1.0.1.Final-redhat-2]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec.jar:1.0.1.Final-redhat-2]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329) [jbossweb.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248) [jbossweb.jar:]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275) [jbossweb.jar:]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161) [jbossweb.jar:]
	at org.jboss.as.web.session.ClusteredSessionValve.handleRequest(ClusteredSessionValve.java:134) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.session.ClusteredSessionValve.invoke(ClusteredSessionValve.java:99) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.session.JvmRouteValve.invoke(JvmRouteValve.java:92) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.session.LockingValve.invoke(LockingValve.java:64) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.jpa.interceptor.WebNonTxEmCloserValve.invoke(WebNonTxEmCloserValve.java:50) [jboss-as-jpa.jar:7.1.3.Final-redhat-4]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web.jar:7.1.3.Final-redhat-4]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155) [jbossweb.jar:]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) [jbossweb.jar:]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) [jbossweb.jar:]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:372) [jbossweb.jar:]
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:877) [jbossweb.jar:]
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:679) [jbossweb.jar:]
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:931) [jbossweb.jar:]
	at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_09-icedtea]

Comment 4 Bill DeCoste 2013-03-05 19:16:03 UTC
Please attach your server.log. These errors are not related to the bug.

Comment 5 Meng Bo 2013-03-06 02:20:00 UTC
Created attachment 705757 [details]
jboss_server_log

Attached the jbosseap app server log

Comment 6 Bill DeCoste 2013-03-06 16:54:08 UTC
Run in AS not EAP. AS has test JMS Destinations deployed, EAP does not.

Comment 7 Meng Bo 2013-03-07 02:20:28 UTC
Created attachment 706315 [details]
jbossas_server_log

Checked on devenv_2904 with jbossas cartridge.

No such ERROR message after deploy sfsbTest-1.0.war.

Attached latest jbossas_server_log.

Move bug to VERIFIED.


Note You need to log in before you can comment on or make changes to this bug.