0 Replies Latest reply on Feb 12, 2007 5:24 PM by Howard Greenblatt

    User Unable to Login after failure in  uil2.SocketManager

    Howard Greenblatt Newbie

      Hi,
      We seem to have this problem once a week, but in general it is not reproducible. The user gets the error in the log(below) and then is unable to login until JBoss is restarted. I hate putting the clip here.
      After the first clip is another showing the error the user has trying to log back in. We are unable to delete the subscription thru any manual method we have tried. We are not opposed to paying for support.
      ++++
      2007-02-07 23:54:18,908 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run
      2007-02-07 23:54:18,908 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run
      2007-02-07 23:54:18,908 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream
      2007-02-07 23:54:18,939 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream
      2007-02-07 23:54:19,111 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection
      2007-02-07 23:54:19,111 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up
      2007-02-07 23:54:19,439 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Checking durable subscription: DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton'], on topic: TOPIC.serverPublishTopic.DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton']
      2007-02-07 23:54:19,439 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] The subscription was not previously registered.
      2007-02-07 23:54:19,439 DEBUG [org.jboss.resource.adapter.jdbc.local.LocalManagedConnectionFactory] Using properties: {user=indigo, password=--hidden--}
      2007-02-07 23:54:19,455 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: TOPIC.serverPublishTopic.DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton']
      2007-02-07 23:54:19,455 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Saving config to: N:\Portware\bin\jboss\server\default\conf\jbossmq-state.xml
      2007-02-07 23:54:19,548 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Found a matching ClientID configuration section.
      2007-02-07 23:54:19,548 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Checking durable subscription: DurableSubscription[clientId=shoulton name=shoulton selector=null], on topic: null
      2007-02-07 23:54:19,548 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Found a matching ClientID configuration section.
      2007-02-07 23:54:19,548 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] The subscription was previously registered.
      2007-02-07 23:54:19,548 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Removing subscription.
      2007-02-07 23:54:19,548 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Saving config to: N:\Portware\bin\jboss\server\default\conf\jbossmq-state.xml
      2007-02-07 23:54:19,626 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Checking durable subscription: DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton'], on topic: TOPIC.serverPublishTopic.DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton']
      2007-02-07 23:54:19,626 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] The subscription was not previously registered.
      2007-02-07 23:54:19,626 DEBUG [org.jboss.mq.pm.jdbc2.PersistenceManager] Restored 0 message(s) to: TOPIC.serverPublishTopic.DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton']
      2007-02-07 23:54:19,626 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Saving config to: N:\Portware\bin\jboss\server\default\conf\jbossmq-state.xml
      2007-02-07 23:54:20,251 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
      2007-02-07 23:57:33,146 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Found a matching ClientID configuration section.
      2007-02-07 23:57:33,146 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Checking durable subscription: DurableSubscription[clientId=shoulton name=shoulton selector=null], on topic: null
      2007-02-07 23:57:33,146 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Found a matching ClientID configuration section.
      2007-02-07 23:57:33,146 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] The subscription was previously registered.
      2007-02-07 23:57:33,146 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Removing subscription.
      2007-02-07 23:57:33,146 ERROR [org.jboss.mq.il.uil2.SocketManager] Failed to handle: org.jboss.mq.il.uil2.msgs.DeleteSubscriptionMsg4798496[msgType: m_destroySubscription, msgID: 51, error: null]
      java.lang.NullPointerException
      at org.jboss.mq.server.MessageReference.redelivered(MessageReference.java:180)
      at org.jboss.mq.server.BasicQueue.nackMessage(BasicQueue.java:382)
      at org.jboss.mq.server.BasicQueue$RestoreMessageTask.run(BasicQueue.java:1115)
      at org.jboss.mq.pm.TxManager.addPostCommitTask(TxManager.java:103)
      at org.jboss.mq.server.BasicQueue.acknowledge(BasicQueue.java:558)
      at org.jboss.mq.server.BasicQueue.removeAllMessages(BasicQueue.java:651)
      at org.jboss.mq.server.JMSTopic.destroyDurableSubscription(JMSTopic.java:315)
      at org.jboss.mq.sm.AbstractStateManager.setDurableSubscription(AbstractStateManager.java:119)
      at org.jboss.mq.server.JMSDestinationManager.destroySubscription(JMSDestinationManager.java:620)
      at org.jboss.mq.server.JMSServerInterceptorSupport.destroySubscription(JMSServerInterceptorSupport.java:261)
      at org.jboss.mq.security.ServerSecurityInterceptor.destroySubscription(ServerSecurityInterceptor.java:197)
      at org.jboss.mq.server.TracingInterceptor.destroySubscription(TracingInterceptor.java:653)
      at org.jboss.mq.server.JMSServerInvoker.destroySubscription(JMSServerInvoker.java:261)
      at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:183)
      at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:369)
      at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:377)
      at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      at java.lang.Thread.run(Thread.java:534)
      2007-02-07 23:57:34,099 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run
      2007-02-07 23:57:34,099 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE
      java.net.SocketException: socket closed
      at java.net.SocketInputStream.socketRead0(Native Method)
      at java.net.SocketInputStream.read(SocketInputStream.java:129)
      at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)
      at java.io.BufferedInputStream.read(BufferedInputStream.java:201)
      at org.jboss.util.stream.NotifyingBufferedInputStream.read(NotifyingBufferedInputStream.java:67)
      at java.io.ObjectInputStream$PeekInputStream.peek(ObjectInputStream.java:2133)
      at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(ObjectInputStream.java:2313)
      at java.io.ObjectInputStream$BlockDataInputStream.refill(ObjectInputStream.java:2380)
      at java.io.ObjectInputStream$BlockDataInputStream.read(ObjectInputStream.java:2452)
      at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2601)
      at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)
      at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:290)
      at java.lang.Thread.run(Thread.java:534)
      2007-02-07 23:57:34,099 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run

      ++++ Now the user gets this when trying to login
      2007-02-08 07:50:02,826 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run
      2007-02-08 07:50:02,826 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run
      2007-02-08 07:50:02,826 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream
      2007-02-08 07:50:02,858 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream
      2007-02-08 07:50:03,030 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection
      2007-02-08 07:50:03,030 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up
      2007-02-08 07:50:03,373 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Checking durable subscription: DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton'], on topic: TOPIC.serverPublishTopic.DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton']
      2007-02-08 07:50:03,373 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Found a matching ClientID configuration section.
      2007-02-08 07:50:03,373 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] The subscription was previously registered.
      2007-02-08 07:50:03,373 ERROR [org.jboss.mq.il.uil2.SocketManager] Failed to handle: org.jboss.mq.il.uil2.msgs.SubscribeMsg26446166[msgType: m_subscribe, msgID: 5, error: null]
      java.lang.NullPointerException
      at org.jboss.mq.server.JMSTopic.addSubscriber(JMSTopic.java:104)
      at org.jboss.mq.server.ClientConsumer.addSubscription(ClientConsumer.java:133)
      at org.jboss.mq.server.JMSDestinationManager.subscribe(JMSDestinationManager.java:596)
      at org.jboss.mq.server.JMSServerInterceptorSupport.subscribe(JMSServerInterceptorSupport.java:297)
      at org.jboss.mq.security.ServerSecurityInterceptor.subscribe(ServerSecurityInterceptor.java:142)
      at org.jboss.mq.server.TracingInterceptor.subscribe(TracingInterceptor.java:769)
      at org.jboss.mq.server.JMSServerInvoker.subscribe(JMSServerInvoker.java:297)
      at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:166)
      at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:369)
      at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:377)
      at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      at java.lang.Thread.run(Thread.java:534)
      2007-02-08 07:50:07,780 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE
      java.net.SocketException: Connection reset
      at java.net.SocketInputStream.read(SocketInputStream.java:168)
      at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)
      at java.io.BufferedInputStream.read(BufferedInputStream.java:201)
      at org.jboss.util.stream.NotifyingBufferedInputStream.read(NotifyingBufferedInputStream.java:67)
      at java.io.ObjectInputStream$PeekInputStream.peek(ObjectInputStream.java:2133)
      at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(ObjectInputStream.java:2313)
      at java.io.ObjectInputStream$BlockDataInputStream.refill(ObjectInputStream.java:2380)
      at java.io.ObjectInputStream$BlockDataInputStream.read(ObjectInputStream.java:2452)
      at java.io.ObjectInputStream$BlockDataInputStream.readByte(ObjectInputStream.java:2601)
      at java.io.ObjectInputStream.readByte(ObjectInputStream.java:845)
      at org.jboss.mq.il.uil2.SocketManager$ReadTask.run(SocketManager.java:290)
      at java.lang.Thread.run(Thread.java:534)
      2007-02-08 07:50:07,780 DEBUG [org.jboss.mq.il.uil2.SocketManager] End ReadTask.run
      2007-02-08 07:50:07,780 DEBUG [org.jboss.mq.il.uil2.SocketManager] End WriteTask.run
      2007-02-08 07:50:52,687 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin ReadTask.run
      2007-02-08 07:50:52,687 DEBUG [org.jboss.mq.il.uil2.SocketManager] Begin WriteTask.run
      2007-02-08 07:50:52,687 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectOutputStream
      2007-02-08 07:50:52,718 DEBUG [org.jboss.mq.il.uil2.SocketManager] Created ObjectInputStream
      2007-02-08 07:50:52,890 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Setting up the UILClientIL Connection
      2007-02-08 07:50:52,890 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] The UILClientIL Connection is set up
      2007-02-08 07:50:53,218 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Checking durable subscription: DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton'], on topic: TOPIC.serverPublishTopic.DurableSubscription[clientId=shoulton name=shoulton selector=originator <> 'shoulton' OR destination = 'shoulton']
      2007-02-08 07:50:53,218 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] Found a matching ClientID configuration section.
      2007-02-08 07:50:53,218 DEBUG [org.jboss.mq.sm.file.DynamicStateManager] The subscription was previously registered.
      2007-02-08 07:50:53,218 ERROR [org.jboss.mq.il.uil2.SocketManager] Failed to handle: org.jboss.mq.il.uil2.msgs.SubscribeMsg33439185[msgType: m_subscribe, msgID: 5, error: null]
      java.lang.NullPointerException
      at org.jboss.mq.server.JMSTopic.addSubscriber(JMSTopic.java:104)
      at org.jboss.mq.server.ClientConsumer.addSubscription(ClientConsumer.java:133)
      at org.jboss.mq.server.JMSDestinationManager.subscribe(JMSDestinationManager.java:596)
      at org.jboss.mq.server.JMSServerInterceptorSupport.subscribe(JMSServerInterceptorSupport.java:297)
      at org.jboss.mq.security.ServerSecurityInterceptor.subscribe(ServerSecurityInterceptor.java:142)
      at org.jboss.mq.server.TracingInterceptor.subscribe(TracingInterceptor.java:769)
      at org.jboss.mq.server.JMSServerInvoker.subscribe(JMSServerInvoker.java:297)
      at org.jboss.mq.il.uil2.ServerSocketManagerHandler.handleMsg(ServerSocketManagerHandler.java:166)
      at org.jboss.mq.il.uil2.SocketManager$ReadTask.handleMsg(SocketManager.java:369)
      at org.jboss.mq.il.uil2.msgs.BaseMsg.run(BaseMsg.java:377)
      at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:748)
      at java.lang.Thread.run(Thread.java:534)
      2007-02-08 07:52:27,110 DEBUG [org.jboss.mq.il.uil2.ServerSocketManagerHandler] Exiting on IOE
      java.net.SocketException: Connection reset