2 Replies Latest reply on Feb 2, 2010 1:31 PM by Pedro Gontijo

    Remote JmsProvider - Server restarts, client stops

    Pedro Gontijo Newbie

      I dont know if this is a JBM or Remoting issue, but here it goes...

       

      The client is AS 4.2.3.GA with JBM 1.4.5.GA and remoting 2.2.3.SP1

      The server is a clustered AS 5.1.0.GA with JBM 1.4.3.GA and remoting 2.5.2.SP1

       

      In the client (4.2.3) there is a JMS provider which jndi properties is set to the server. And a connection manager using this provider so a could have XA transactions. (Works just fine)


      When the server is restarted, remoting logs the following:


      {noformat}

      2010-01-28 11:02:34,656 DEBUG [org.jboss.remoting.transport.socket.MicroSocketClientInvoker] SocketClientInvoker[191b73f, bisocket://172.20.37.19:4757] got java.net.ConnectException: Connection refused: connect: Connection refused: connect
      2010-01-28 11:02:34,656 DEBUG [org.jboss.remoting.LeasePinger] org.jboss.remoting.LeasePinger$LeaseTimerTask@1ac0080 failed to ping to server
      org.jboss.remoting.CannotConnectException: Can not get connection to server. Problem establishing socket connection for InvokerLocator [bisocket://172.20.37.19:4757/?JBM_clientMaxPoolSize=200&clientLeasePeriod=10000&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&connectionWait=10&dataType=jms&marshaller=org.jboss.jms.wireformat.JMSWireFormat&numberOfCallRetries=1&pingFrequency=214748364&pingWindowFactor=10&socket.check_connection=false&stopLeaseOnFailure=true&timeout=0&unmarshaller=org.jboss.jms.wireformat.JMSWireFormat&validatorPingPeriod=10000&validatorPingTimeout=5000]
          at org.jboss.remoting.transport.socket.MicroSocketClientInvoker.transport(MicroSocketClientInvoker.java:613)
          at org.jboss.remoting.transport.bisocket.BisocketClientInvoker.transport(BisocketClientInvoker.java:458)
          at org.jboss.remoting.MicroRemoteClientInvoker.invoke(MicroRemoteClientInvoker.java:141)
          at org.jboss.remoting.LeasePinger.sendClientPing(LeasePinger.java:375)
          at org.jboss.remoting.LeasePinger.access$000(LeasePinger.java:20)
          at org.jboss.remoting.LeasePinger$LeaseTimerTask.run(LeasePinger.java:403)
          at java.util.TimerThread.mainLoop(Timer.java:512)
          at java.util.TimerThread.run(Timer.java:462)
      Caused by: java.net.ConnectException: Connection refused: connect
          at java.net.PlainSocketImpl.socketConnect(Native Method)
          at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
          at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
          at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
          at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
          at java.net.Socket.connect(Socket.java:520)
          at org.jboss.remoting.transport.socket.SocketClientInvoker.createSocket(SocketClientInvoker.java:192)
          at org.jboss.remoting.transport.bisocket.BisocketClientInvoker.createSocket(BisocketClientInvoker.java:465)
          at org.jboss.remoting.transport.socket.MicroSocketClientInvoker.getConnection(MicroSocketClientInvoker.java:913)
          at org.jboss.remoting.transport.socket.MicroSocketClientInvoker.transport(MicroSocketClientInvoker.java:602)
          ... 7 more
      2010-01-28 11:02:34,656 WARN  [org.jboss.remoting.LeasePinger] org.jboss.remoting.LeasePinger$LeaseTimerTask@1ac0080 failed to ping to server: Can not get connection to server. Problem establishing socket connection for InvokerLocator [bisocket://172.20.37.19:4757/?JBM_clientMaxPoolSize=200&clientLeasePeriod=10000&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&connectionWait=10&dataType=jms&marshaller=org.jboss.jms.wireformat.JMSWireFormat&numberOfCallRetries=1&pingFrequency=214748364&pingWindowFactor=10&socket.check_connection=false&stopLeaseOnFailure=true&timeout=0&unmarshaller=org.jboss.jms.wireformat.JMSWireFormat&validatorPingPeriod=10000&validatorPingTimeout=5000]

      {noformat}

       

       

      Thats ok, but when the server is up again and the client sends a message, remoting (in debug) logs the following and freezes the thread! So I have to restart the client!

       

      {noformat}

      2010-01-28 11:08:48,484 DEBUG [org.jboss.remoting.Client] Client[10530691:4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2u].connect(ConsolidatedListener(UNINITIALIZED))
      2010-01-28 11:08:48,500 DEBUG [org.jboss.remoting.Client] Client[10530691:4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2u] connected to InvokerLocator [bisocket://172.20.37.19:4757/?JBM_clientMaxPoolSize=200&clientLeasePeriod=10000&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&connectionWait=10&dataType=jms&marshaller=org.jboss.jms.wireformat.JMSWireFormat&numberOfCallRetries=1&pingFrequency=214748364&pingWindowFactor=10&socket.check_connection=false&stopLeaseOnFailure=true&timeout=0&unmarshaller=org.jboss.jms.wireformat.JMSWireFormat&validatorPingPeriod=10000&validatorPingTimeout=5000]
      2010-01-28 11:08:48,500 DEBUG [org.jboss.remoting.Client] Client[22181209:4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2v].connect(null)
      2010-01-28 11:08:48,500 DEBUG [org.jboss.remoting.Client] Client[22181209:4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2v] connected to InvokerLocator [bisocket://172.20.37.19:4757/?JBM_clientMaxPoolSize=200&clientLeasePeriod=10000&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&connectionWait=10&dataType=jms&marshaller=org.jboss.jms.wireformat.JMSWireFormat&numberOfCallRetries=1&pingFrequency=214748364&pingWindowFactor=10&socket.check_connection=false&stopLeaseOnFailure=true&timeout=0&unmarshaller=org.jboss.jms.wireformat.JMSWireFormat&validatorPingPeriod=10000&validatorPingTimeout=5000]
      2010-01-28 11:08:48,500 DEBUG [org.jboss.remoting.Client] starting callback Connector: InvokerLocator [bisocket://172.20.37.46:1137718937/callback?serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper&isCallbackServer=true&callbackServerProtocol=bisocket&datatype=jms&guid=4sk111a-vja37y-g4y5zhk0-1-g4zk9ywk-2w&callbackServerHost=172.20.37.46&callbackServerPort=1137718937&clientMaxPoolSize=1&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper]
      2010-01-28 11:08:48,500 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingFrequency to 214748364
      2010-01-28 11:08:48,500 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] setting pingWindowFactor to 10
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] created server socket factory javax.net.DefaultServerSocketFactory@e34726
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.transport.Connector] org.jboss.remoting.transport.Connector@d5be9a started
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.Client] Client[7971413:4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2u].connect(null)
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.Client] Client[7971413:4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2u] connected to InvokerLocator [bisocket://172.20.37.46:1137718937/callback?callbackServerHost=172.20.37.46&callbackServerPort=1137718937&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=4sk111a-vja37y-g4y5zhk0-1-g4zk9ywk-2w&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper]
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.ServerInvoker] ServerInvoker (SocketServerInvoker[UNINITIALIZED]) added client callback handler CallbackManager[9651d2] with session id of 4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2u+4sk111a-vja37y-g4y5zhk0-1-g4zk9ywz-2x and callback handle object of null.
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.InvokerRegistry] removed org.jboss.remoting.transport.local.LocalClientInvoker@1908e2e from registry
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.Client] Client[7971413:4sk111a-vja37y-g4y5zhk0-1-g4zk9yw4-2u] is disconnected
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.transport.bisocket.BisocketClientInvoker] getting secondary locator
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.transport.bisocket.BisocketClientInvoker] secondary locator: InvokerLocator [null://172.20.37.19:30520/?]
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] creating control connection: InvokerLocator [null://172.20.37.19:30520/?]
      2010-01-28 11:08:48,515 DEBUG [org.jboss.remoting.transport.bisocket.BisocketServerInvoker] SocketServerInvoker[UNINITIALIZED] created control connection (4sk111a-vja37y-g4y5zhk0-1-g4zk9ywz-2x): Socket[addr=/172.20.37.19,port=30520,localport=4996]

      {noformat}

       

      After a few tries, my pool AvailableConnectionCount is 0 and the "No ManagedConnections available" is throw.

       

      Appreciate any help!

       

      P.S. I am using ServiceBindingManager in the client and already updated the bisocket config in sample-bindings.xml.