User Unable to Login after failure in uil2.SocketManager
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