[JBoss Messaging] - Re: Serious idle problem with Bridges on AS 5.1.0.Beta
by rnicholson10
Sorry about the delay in getting back to you.
Here's the log from the box1, after the idle time.
It seems that the remoting session etc. is found. Spot anything interesting?
The second log extract is from box2 when I try to redeploy one of the bridges.
| 2009-06-19 09:43:40,373 DEBUG [org.jboss.remoting.Client] (http-0.0.0.0-8080-1) starting callback Connector: InvokerLocator [bisocket://172.16.113.11:871825415/callback?callbackServerHost=172.16.113.11&callbackServerPort=871825415&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jq&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper]
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.ServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.ServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] created server socket factory javax.net.DefaultServerSocketFactory@87bf5
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] setting pingFrequency to 214748364
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] setting pingWindowFactor to 10
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.transport.Connector] (http-0.0.0.0-8080-1) org.jboss.remoting.transport.Connector@1117568 started
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.ServerInvoker] (http-0.0.0.0-8080-1) ServerInvoker (SocketServerInvoker[172.16.113.11:871825415]) added client callback handler CallbackManager[12d3d44] with session id of 4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo+4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr and callback handle object of null.
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.InvokerRegistry] (http-0.0.0.0-8080-1) removed org.jboss.remoting.transport.local.LocalClientInvoker@1e3ba17 from registry
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.callback.ServerInvokerCallbackHandler] (http-0.0.0.0-8080-1) Session id for callback handler is 4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo+4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.callback.ServerInvokerCallbackHandler] (http-0.0.0.0-8080-1) ServerInvokerCallbackHandler[4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo+4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr] using callbackTimeout value 10000
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.callback.DefaultCallbackErrorHandler] (http-0.0.0.0-8080-1) DefaultCallbackErrorHandler[UNITIALIZED] setting server invoker to SocketServerInvoker[jbdevapp1:4457]
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.remoting.callback.DefaultCallbackErrorHandler] (http-0.0.0.0-8080-1) DefaultCallbackErrorHandler[SocketServerInvoker[jbdevapp1:4457]] setting callback handler to ServerInvokerCallbackHandler[4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo+4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr]
| 2009-06-19 09:43:40,385 DEBUG [org.jboss.jms.server.remoting.JMSServerInvocationHandler] (http-0.0.0.0-8080-1) adding callback handler ServerInvokerCallbackHandler[4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo+4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr]
| 2009-06-19 09:43:40,386 DEBUG [org.jboss.jms.server.remoting.JMSServerInvocationHandler] (http-0.0.0.0-8080-1) found calllback handler for remoting session ...-fw4nm1g4-jo UID=4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo
| 2009-06-19 09:43:40,417 ERROR [org.jboss.messaging.util.ExceptionUtil] (http-0.0.0.0-8080-1) ConnectionFactoryEndpoint[jboss.messaging.connectionfactory:service=ConnectionFactory] createFailoverConnectionDelegate [uj-dh1mn4wf-1-ylp9t3wf-hb44uw-b53gs4]
| javax.jms.JMSSecurityException: User null is NOT authenticated
| at org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore.authenticate(JBossASSecurityMetadataStore.java:223)
| at sun.reflect.GeneratedMethodAccessor316.invoke(Unknown Source)
| at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
| at java.lang.reflect.Method.invoke(Unknown Source)
| at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(Unknown Source)
| at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(Unknown Source)
| at com.sun.jmx.mbeanserver.MBeanIntrospector.invokeM(Unknown Source)
| at com.sun.jmx.mbeanserver.PerInterface.invoke(Unknown Source)
| at com.sun.jmx.mbeanserver.MBeanSupport.invoke(Unknown Source)
| at javax.management.StandardMBean.invoke(Unknown Source)
| at org.jboss.mx.server.RawDynamicInvoker.invoke(RawDynamicInvoker.java:164)
| at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:668)
| at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:210)
| at $Proxy106.authenticate(Unknown Source)
| at org.jboss.jms.server.endpoint.ServerConnectionFactoryEndpoint.createConnectionDelegateInternal(ServerConnectionFactoryEndpoint.java:233)
| at org.jboss.jms.server.endpoint.ServerConnectionFactoryEndpoint.createConnectionDelegate(ServerConnectionFactoryEndpoint.java:171)
| at org.jboss.jms.server.endpoint.advised.ConnectionFactoryAdvised.org$jboss$jms$server$endpoint$advised$ConnectionFactoryAdvised$createConnectionDelegate$aop(ConnectionFactoryAdvised.java:108)
| at org.jboss.jms.server.endpoint.advised.ConnectionFactoryAdvised.createConnectionDelegate(ConnectionFactoryAdvised.java)
| at org.jboss.jms.wireformat.ConnectionFactoryCreateConnectionDelegateRequest.serverInvoke(ConnectionFactoryCreateConnectionDelegateRequest.java:91)
| at org.jboss.jms.server.remoting.JMSServerInvocationHandler.invoke(JMSServerInvocationHandler.java:143)
| at org.jboss.remoting.ServerInvoker.invoke(ServerInvoker.java:908)
| at org.jboss.remoting.transport.local.LocalClientInvoker.invoke(LocalClientInvoker.java:106)
| at org.jboss.remoting.Client.invoke(Client.java:1708)
| at org.jboss.remoting.Client.invoke(Client.java:612)
| at org.jboss.jms.client.delegate.ClientConnectionFactoryDelegate.org$jboss$jms$client$delegate$ClientConnectionFactoryDelegate$createConnectionDelegate$aop(ClientConnectionFactoryDelegate.java:171)
| at org.jboss.jms.client.delegate.ClientConnectionFactoryDelegate$createConnectionDelegate_N3019492359065420858.invokeTarget(ClientConnectionFactoryDelegate$createConnectionDelegate_N3019492359065420858.java)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:111)
| at org.jboss.jms.client.container.StateCreationAspect.handleCreateConnectionDelegate(StateCreationAspect.java:81)
| at org.jboss.aop.advice.org.jboss.jms.client.container.StateCreationAspect_z_handleCreateConnectionDelegate_26294606.invoke(StateCreationAspect_z_handleCreateConnectionDelegate_26294606.java)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
| at org.jboss.jms.client.delegate.ClientConnectionFactoryDelegate.createConnectionDelegate(ClientConnectionFactoryDelegate.java)
| at org.jboss.jms.client.JBossConnectionFactory.createConnectionInternal(JBossConnectionFactory.java:205)
| at org.jboss.jms.client.JBossConnectionFactory.createConnection(JBossConnectionFactory.java:87)
| at org.jboss.jms.client.JBossConnectionFactory.createConnection(JBossConnectionFactory.java:82)
| at com.paddypower.phase.api.InputMessageSender.sendMessage(InputMessageSender.java:60)
| at com.paddypower.phase.test.servlet.TestMessageOneServlet.doGet(TestMessageOneServlet.java:99)
| at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
| at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
| at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
| at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
| at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
| at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
| at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
| at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:235)
| at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
| at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:190)
| at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:92)
| at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.process(SecurityContextEstablishmentValve.java:126)
| at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.invoke(SecurityContextEstablishmentValve.java:70)
| at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
| at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
| at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:158)
| at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
| at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:330)
| at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:829)
| at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:601)
| at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
| at java.lang.Thread.run(Unknown Source)
| 2009-06-19 09:43:40,419 DEBUG [org.jboss.jms.server.remoting.JMSServerInvocationHandler] (http-0.0.0.0-8080-1) removing callback handler ServerInvokerCallbackHandler[4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo+4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr]
| 2009-06-19 09:43:40,419 DEBUG [org.jboss.remoting.InvokerRegistry] (http-0.0.0.0-8080-1) removed org.jboss.remoting.transport.local.LocalClientInvoker@a5853c from registry
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.ServerInvoker] (http-0.0.0.0-8080-1) ServerInvoker (SocketServerInvoker[172.16.113.11:871825415]) removing client callback handler with session id of 4sg35b-wu44bh-fw3t9ply-1-fw4nm1g4-jo+4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr.
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (http-0.0.0.0-8080-1) unrecognized listener ID: 4sg35b-wu44bh-fw3t9ply-1-fw4nm1gh-jr
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.InvokerRegistry] (http-0.0.0.0-8080-1) removed org.jboss.remoting.transport.local.LocalClientInvoker@ce2ca0 from registry
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] stopped threads in clientpool
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] stopped threads in threadpool
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] exiting
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.ServerInvoker] (http-0.0.0.0-8080-1) SocketServerInvoker[172.16.113.11:871825415] stopped
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.InvokerRegistry] (http-0.0.0.0-8080-1) decremented org.jboss.remoting.transport.local.LocalClientInvoker@1e98793's count, current count 8
| 2009-06-19 09:43:40,420 DEBUG [org.jboss.remoting.InvokerRegistry] (http-0.0.0.0-8080-1) decremented org.jboss.remoting.transport.local.LocalClientInvoker@c3cb07's count, current count 8
|
| 2009-06-19 09:44:15,554 FINE [sun.rmi.transport.tcp] (HDScanner) HDScanner: reuse connection
| 2009-06-19 09:44:15,556 FINE [sun.rmi.loader] (HDScanner) HDScanner: name = "org.jnp.interfaces.MarshalledValuePair", codebase = "http://jbdevapp1:8083/", defaultLoader = BaseClassLoader@186a6af{vfsfile:/opt/jboss-5.1.0.Beta1-jdk6/server/default/deploy/messaging/bridge-input-to-engine-service.xml}
| 2009-06-19 09:44:15,557 FINE [sun.rmi.loader] (HDScanner) HDScanner: name = "java.rmi.MarshalledObject", codebase = "http://jbdevapp1:8083/", defaultLoader = BaseClassLoader@186a6af{vfsfile:/opt/jboss-5.1.0.Beta1-jdk6/server/default/deploy/messaging/bridge-input-to-engine-service.xml}
| 2009-06-19 09:44:15,557 FINE [sun.rmi.loader] (HDScanner) HDScanner: name = "[B", codebase = "", defaultLoader = BaseClassLoader@186a6af{vfsfile:/opt/jboss-5.1.0.Beta1-jdk6/server/default/deploy/messaging/bridge-input-to-engine-service.xml}
| 2009-06-19 09:44:15,558 FINE [sun.rmi.client.ref] (HDScanner) HDScanner: free connection (reuse = true)
| 2009-06-19 09:44:15,558 FINE [sun.rmi.transport.tcp] (HDScanner) HDScanner: reuse connection
| 2009-06-19 09:44:15,558 FINE [sun.rmi.loader] (HDScanner) HDScanner: name = "org.jboss.jms.client.JBossConnectionFactory", codebase = "http://jbdevapp1:8083/", defaultLoader = BaseClassLoader@186a6af{vfsfile:/opt/jboss-5.1.0.Beta1-jdk6/server/default/deploy/messaging/bridge-input-to-engine-service.xml}
| 2009-06-19 09:44:15,561 FINE [sun.rmi.loader] (HDScanner) HDScanner: name = "org.jboss.jms.client.delegate.ClientConnectionFactoryDelegate", codebase = "http://jbdevapp1:8083/", defaultLoader = BaseClassLoader@186a6af{vfsfile:/opt/jboss-5.1.0.Beta1-jdk6/server/default/deploy/messaging/bridge-input-to-engine-service.xml}
| 2009-06-19 09:44:15,562 FINE [sun.rmi.loader] (HDScanner) HDScanner: name = "org.jboss.jms.client.delegate.DelegateSupport", codebase = "http://jbdevapp1:8083/", defaultLoader = BaseClassLoader@186a6af{vfsfile:/opt/jboss-5.1.0.Beta1-jdk6/server/default/deploy/messaging/bridge-input-to-engine-service.xml}
| 2009-06-19 09:44:15,565 FINE [sun.rmi.loader] (HDScanner) HDScanner: name = "org.jboss.messaging.util.Version", codebase = "http://jbdevapp1:8083/", defaultLoader = BaseClassLoader@186a6af{vfsfile:/opt/jboss-5.1.0.Beta1-jdk6/server/default/deploy/messaging/bridge-input-to-engine-service.xml}
| 2009-06-19 09:44:15,571 DEBUG [org.jboss.remoting.MicroRemoteClientInvoker] (HDScanner) SocketClientInvoker[44a5e1, bisocket://jbdevapp1:4457] added client with session ID 4sg35m-ly74q9-fw3tcotx-1-fw4nmslr-nu to the lease pinger
| 2009-06-19 09:44:15,572 DEBUG [org.jboss.remoting.Client] (HDScanner) starting callback Connector: InvokerLocator [bisocket://172.16.113.22:396696380/callback?callbackServerHost=172.16.113.22&callbackServerPort=396696380&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=4sg35m-ly74q9-fw3tcotx-1-fw4nmslw-nw&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper]
| 2009-06-19 09:44:15,581 DEBUG [org.jboss.remoting.ServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
| 2009-06-19 09:44:15,582 DEBUG [org.jboss.remoting.ServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] created server socket factory javax.net.DefaultServerSocketFactory@d6f833
| 2009-06-19 09:44:15,582 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] setting pingFrequency to 214748364
| 2009-06-19 09:44:15,582 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] setting pingWindowFactor to 10
| 2009-06-19 09:44:15,582 DEBUG [org.jboss.remoting.transport.Connector] (HDScanner) org.jboss.remoting.transport.Connector@ce6ecd started
| 2009-06-19 09:44:15,582 DEBUG [org.jboss.remoting.ServerInvoker] (HDScanner) ServerInvoker (SocketServerInvoker[172.16.113.22:396696380]) added client callback handler CallbackManager[13d4257] with session id of 4sg35m-ly74q9-fw3tcotx-1-fw4nmslr-nu+4sg35m-ly74q9-fw3tcotx-1-fw4nmsm6-nx and callback handle object of null.
| 2009-06-19 09:44:15,582 DEBUG [org.jboss.remoting.InvokerRegistry] (HDScanner) removed org.jboss.remoting.transport.local.LocalClientInvoker@1ab182a from registry
| 2009-06-19 09:44:15,582 DEBUG [org.jboss.remoting.transport.bisocket.BisocketClientInvoker] (HDScanner) getting secondary locator
| 2009-06-19 09:44:15,601 DEBUG [org.jboss.remoting.transport.bisocket.BisocketClientInvoker] (HDScanner) secondary locator: InvokerLocator [null://jbdevapp1:12746/?]
| 2009-06-19 09:44:15,601 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (HDScanner) creating control connection: InvokerLocator [null://jbdevapp1:12746/?]
| 2009-06-19 09:44:15,602 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] created control connection (4sg35m-ly74q9-fw3tcotx-1-fw4nmsm6-nx): Socket[addr=jbdevapp1/172.16.113.11,port=12746,localport=48393]
| 2009-06-19 09:44:15,718 DEBUG [org.jboss.remoting.ServerInvoker] (HDScanner) ServerInvoker (SocketServerInvoker[172.16.113.22:396696380]) removing client callback handler with session id of 4sg35m-ly74q9-fw3tcotx-1-fw4nmslr-nu+4sg35m-ly74q9-fw3tcotx-1-fw4nmsm6-nx.
| 2009-06-19 09:44:15,721 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] shutting down control connection: 4sg35m-ly74q9-fw3tcotx-1-fw4nmsm6-nx
| 2009-06-19 09:44:15,721 DEBUG [org.jboss.remoting.InvokerRegistry] (HDScanner) removed org.jboss.remoting.transport.local.LocalClientInvoker@1bd982 from registry
| 2009-06-19 09:44:15,721 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] stopped threads in clientpool
| 2009-06-19 09:44:15,722 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] stopped threads in threadpool
| 2009-06-19 09:44:15,722 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] exiting
| 2009-06-19 09:44:15,722 DEBUG [org.jboss.remoting.ServerInvoker] (HDScanner) SocketServerInvoker[172.16.113.22:396696380] stopped
| 2009-06-19 09:44:15,722 DEBUG [org.jboss.remoting.LeasePinger] (HDScanner) LeasePinger[SocketClientInvoker[44a5e1, bisocket://jbdevapp1:4457](4sg35m-ly74q9-fw3tcotx-1-fw3te7td-a1)] setting disconnect timeout to: -1
| 2009-06-19 09:44:15,737 DEBUG [org.jboss.remoting.InvokerRegistry] (HDScanner) decremented SocketClientInvoker[44a5e1, bisocket://jbdevapp1:4457]'s count, current count 3
| 2009-06-19 09:44:15,737 DEBUG [org.jboss.remoting.InvokerRegistry] (HDScanner) decremented SocketClientInvoker[1876083, bisocket://jbdevapp1:4457]'s count, current count 3
| 2009-06-19 09:44:15,737 WARN [org.jboss.jms.server.bridge.Bridge] (HDScanner) jboss.messaging:name=InputToEngineBridge,service=Bridge Failed to set up connections
| javax.jms.JMSSecurityException: User null is NOT authenticated
| at org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore.authenticate(JBossASSecurityMetadataStore.java:223)
| at sun.reflect.GeneratedMethodAccessor316.invoke(Unknown Source)
| at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
| at java.lang.reflect.Method.invoke(Unknown Source)
| at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(Unknown Source)
| at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(Unknown Source)
| at com.sun.jmx.mbeanserver.MBeanIntrospector.invokeM(Unknown Source)
| at com.sun.jmx.mbeanserver.PerInterface.invoke(Unknown Source)
| at com.sun.jmx.mbeanserver.MBeanSupport.invoke(Unknown Source)
| at javax.management.StandardMBean.invoke(Unknown Source)
| at org.jboss.mx.server.RawDynamicInvoker.invoke(RawDynamicInvoker.java:164)
| at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:668)
| at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:210)
| at $Proxy106.authenticate(Unknown Source)
| at org.jboss.jms.server.endpoint.ServerConnectionFactoryEndpoint.createConnectionDelegateInternal(ServerConnectionFactoryEndpoint.java:233)
| at org.jboss.jms.server.endpoint.ServerConnectionFactoryEndpoint.createConnectionDelegate(ServerConnectionFactoryEndpoint.java:171)
| at org.jboss.jms.server.endpoint.advised.ConnectionFactoryAdvised.org$jboss$jms$server$endpoint$advised$ConnectionFactoryAdvised$createConnectionDelegate$aop(ConnectionFactoryAdvised.java:108)
| at org.jboss.jms.server.endpoint.advised.ConnectionFactoryAdvised.createConnectionDelegate(ConnectionFactoryAdvised.java)
| at org.jboss.jms.wireformat.ConnectionFactoryCreateConnectionDelegateRequest.serverInvoke(ConnectionFactoryCreateConnectionDelegateRequest.java:91)
| at org.jboss.jms.server.remoting.JMSServerInvocationHandler.invoke(JMSServerInvocationHandler.java:143)
| at org.jboss.remoting.ServerInvoker.invoke(ServerInvoker.java:908)
| at org.jboss.remoting.transport.socket.ServerThread.completeInvocation(ServerThread.java:742)
| at org.jboss.remoting.transport.socket.ServerThread.processInvocation(ServerThread.java:695)
| at org.jboss.remoting.transport.socket.ServerThread.dorun(ServerThread.java:549)
| at org.jboss.remoting.transport.socket.ServerThread.run(ServerThread.java:230)
| at org.jboss.remoting.MicroRemoteClientInvoker.invoke(MicroRemoteClientInvoker.java:206)
| at org.jboss.remoting.Client.invoke(Client.java:1708)
| at org.jboss.remoting.Client.invoke(Client.java:612)
| at org.jboss.jms.client.delegate.ClientConnectionFactoryDelegate.org$jboss$jms$client$delegate$ClientConnectionFactoryDelegate$createConnectionDelegate$aop(ClientConnectionFactoryDelegate.java:171)
| at org.jboss.jms.client.delegate.ClientConnectionFactoryDelegate$createConnectionDelegate_N3019492359065420858.invokeTarget(ClientConnectionFactoryDelegate$createConnectionDelegate_N3019492359065420858.java)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:111)
| at org.jboss.jms.client.container.StateCreationAspect.handleCreateConnectionDelegate(StateCreationAspect.java:81)
| at org.jboss.aop.advice.org.jboss.jms.client.container.StateCreationAspect_z_handleCreateConnectionDelegate_11160700.invoke(StateCreationAspect_z_handleCreateConnectionDelegate_11160700.java)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
| at org.jboss.jms.client.delegate.ClientConnectionFactoryDelegate.createConnectionDelegate(ClientConnectionFactoryDelegate.java)
| at org.jboss.jms.client.JBossConnectionFactory.createConnectionInternal(JBossConnectionFactory.java:205)
| at org.jboss.jms.client.JBossConnectionFactory.createConnection(JBossConnectionFactory.java:87)
| at org.jboss.jms.client.JBossConnectionFactory.createConnection(JBossConnectionFactory.java:82)
| at org.jboss.jms.server.bridge.Bridge.createConnection(Bridge.java:893)
| at org.jboss.jms.server.bridge.Bridge.setupJMSObjects(Bridge.java:1011)
| at org.jboss.jms.server.bridge.Bridge.start(Bridge.java:320)
| at org.jboss.jms.server.bridge.BridgeService.startService(BridgeService.java:336)
| at org.jboss.system.ServiceMBeanSupport.jbossInternalStart(ServiceMBeanSupport.java:376)
| at org.jboss.system.ServiceMBeanSupport.start(ServiceMBeanSupport.java:269)
| at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
| at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
| at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
| at java.lang.reflect.Method.invoke(Unknown Source)
| at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:157)
| at org.jboss.mx.server.Invocation.dispatch(Invocation.java:96)
| at org.jboss.mx.interceptor.AbstractInterceptor.invoke(AbstractInterceptor.java:138)
| at org.jboss.mx.server.Invocation.invoke(Invocation.java:90)
| at org.jboss.mx.interceptor.ModelMBeanOperationInterceptor.invoke(ModelMBeanOperationInterceptor.java:140)
| at org.jboss.mx.server.Invocation.invoke(Invocation.java:90)
| at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:264)
| at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:668)
| at org.jboss.system.microcontainer.ServiceProxy.invoke(ServiceProxy.java:206)
| at $Proxy36.start(Unknown Source)
| at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:42)
| at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:37)
| at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleInstallAction(SimpleControllerContextAction.java:62)
| at org.jboss.dependency.plugins.action.AccessControllerContextAction.install(AccessControllerContextAction.java:71)
| at org.jboss.dependency.plugins.AbstractControllerContextActions.install(AbstractControllerContextActions.java:51)
| at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
| at org.jboss.system.microcontainer.ServiceControllerContext.install(ServiceControllerContext.java:286)
| at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1598)
| at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:934)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:1062)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:984)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:822)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:553)
| at org.jboss.system.ServiceController.doChange(ServiceController.java:688)
| at org.jboss.system.ServiceController.start(ServiceController.java:460)
| at org.jboss.system.deployers.ServiceDeployer.start(ServiceDeployer.java:163)
| at org.jboss.system.deployers.ServiceDeployer.deploy(ServiceDeployer.java:99)
| at org.jboss.system.deployers.ServiceDeployer.deploy(ServiceDeployer.java:46)
| at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalDeploy(AbstractSimpleRealDeployer.java:62)
| at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.deploy(AbstractRealDeployer.java:50)
| at org.jboss.deployers.plugins.deployers.DeployerWrapper.deploy(DeployerWrapper.java:171)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.doDeploy(DeployersImpl.java:1439)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:1157)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:1178)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.install(DeployersImpl.java:1098)
| at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
| at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1598)
| at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:934)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:1062)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:984)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:822)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:553)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:781)
| at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:698)
| at org.jboss.system.server.profileservice.repository.MainDeployerAdapter.process(MainDeployerAdapter.java:117)
| at org.jboss.system.server.profileservice.hotdeploy.HDScanner.scan(HDScanner.java:334)
| at org.jboss.system.server.profileservice.hotdeploy.HDScanner.run(HDScanner.java:229)
| at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
| at java.util.concurrent.FutureTask$Sync.innerRunAndReset(Unknown Source)
| at java.util.concurrent.FutureTask.runAndReset(Unknown Source)
| at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(Unknown Source)
| at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(Unknown Source)
| at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
| at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
| at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
| at java.lang.Thread.run(Unknown Source)
|
View the original post : http://www.jboss.org/index.html?module=bb&op=viewtopic&p=4238743#4238743
Reply to the post : http://www.jboss.org/index.html?module=bb&op=posting&mode=reply&p=4238743
16 years, 10 months
[JBoss Messaging] - Re: Ordering Group and Queue-FullSize reload problem
by mclu
Howard!
Here more analysis!
I can see that the order is scambled.
But this has nothing to do with the ordered load of the messages. It has to do with updating the Page_ORD.
The above error can be reproduced if you kill the server before all Page_Ord`s are updated.
So before UpdatePageOrderRunner().executeWithRetry(); finishes.
Then I see in my database this!
| ORD numbers not real numbers
|
| ORD PAGE_ORD
| 1 NULL
| 2 NULL
| ....
| 24 NULL
| 25 25
| 26 26
| ...
| 84 84
| 85 NULL
| 86 NULL
| ...
| 115 NULL
|
As you can see after Ord 85 the page_ord was not yet set!
Then I restart the server and while starting the QueueService the load() method is called. This results in calling loadFromStart() at the JDBC PersistenceManager.
I don't know why but after the first call it loads 50 unpaged messages to the Queue(pageOrd=NULL) and updated one (the last one) to 85. ?!?
So the ord message 115 has now page_ord 85.??
Then I stopped debugging.
So it seams that setting the right page_ord numbers is not handled well if the system has not set the numbers for the newest messages!
Hope this helps to reproduce the Bug
View the original post : http://www.jboss.org/index.html?module=bb&op=viewtopic&p=4238734#4238734
Reply to the post : http://www.jboss.org/index.html?module=bb&op=posting&mode=reply&p=4238734
16 years, 10 months