9 Replies Latest reply on Apr 27, 2007 3:19 AM by ron_sigal

Remoting is throwing unrecognized listener ID: blah blah

timfox Apr 25, 2007 6:02 AM

Remoting is frequently throwing the following warnings:

14:26:40,328 WARN [BisocketServerInvoker] unrecognized listener ID: a00h-citaru-f0we4a0g-1-f0we6jfw
-1s
14:28:39,609 WARN [BisocketServerInvoker] unrecognized listener ID: a00h-citaru-f0we4a0g-1-f0we940c
-1y
14:30:39,046 WARN [BisocketServerInvoker] unrecognized listener ID: a00h-citaru-f0we4a0g-1-f0webol9

I remember Ron explaining there was some reason for this some time ago.

Ron - can you advise?

1. Re: Remoting is throwing unrecognized listener ID: blah blah

timfox Apr 25, 2007 6:03 AM (in response to timfox)

If these are "to be expected" I guess they should be logged as debug rather than warn so users can filter them out with their log4j settings.
Actions
2. Re: Remoting is throwing unrecognized listener ID: blah blah

ron_sigal Apr 25, 2007 5:13 PM (in response to timfox)

When Client.addListener() is called, bisocket stores some information in a static map, keyed on the listener ID, and when Client.removeListener() is called, that information is purged. The "unrecognized listener ID" message comes up when the purge method doesn't find the listener ID among the keys. So it represents an event I didn't expect.

However, I see one way the situation could arise. The client side runs a thread that monitors the health of the control connection, and if the control connection fails, the monitor thread will recreate the control connection. However, it could be that the failed control connection was to a server that died and was restarted (with new connections), in which case there's no point in restarting the old control connection. So the monitor thread keeps a count of the number of times the control connection has been restarted, and when the count maxes out, the thread gives up and purges the static map. If Client.addListener() is called after that, the message will come up.

So I'm thinking

1. I'd like to leave the warning message in, but
2. I could leave a dummy entry in the map (instead of a Thread), just so it's clear that all is well.

But, I'd like to know if this scenario corresponds to what you're seeing, or if there's something else going on that I need to fix.

-Ron
Actions
3. Re: Remoting is throwing unrecognized listener ID: blah blah

timfox Apr 25, 2007 5:36 PM (in response to timfox)

http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4040193#4040193
Actions
4. Re: Remoting is throwing unrecognized listener ID: blah blah

anil.saldhana Apr 25, 2007 6:02 PM (in response to timfox)

Ron, at the least change the log level to debug or trace for that. Trace the better. These WARN messages for listener ID are quite annoying.
Actions
5. Re: Remoting is throwing unrecognized listener ID: blah blah

timfox Apr 26, 2007 4:04 AM (in response to timfox)

Ron-

If this is expected behaviour the exception should be handled and maybe logged at DEBUG or TRACE.

If not, it represents a bug and should be fixed.

According to the link I posted previously this seems to be happening on startup/shutdown of server and every time an MDB receives a message - so I suspect this is a bug.
Actions
6. Re: Remoting is throwing unrecognized listener ID: blah blah

ron_sigal Apr 26, 2007 4:16 AM (in response to timfox)

every time an MDB receives a message

Ah, that's what "each time it fires" means. Yeah, I think it's a bug. I'll look into it.

-Ron
Actions
7. Re: Remoting is throwing unrecognized listener ID: blah blah

timfox Apr 26, 2007 4:27 AM (in response to timfox)

"ron.sigal@jboss.com" wrote:

Ah, that's what "each time it fires" means.
-Ron

Well, that's my interpretation of the user's posting - I could have it wrong.

Either way we should dig deeper and try and reproduce this.
Actions
8. Re: Remoting is throwing unrecognized listener ID: blah blah

ron_sigal Apr 26, 2007 4:31 AM (in response to timfox)

I'm going to run the remote Messaging tests over night to see if the problem shows up. Maybe that'll shed some light.
Actions
9. Re: Remoting is throwing unrecognized listener ID: blah blah

ron_sigal Apr 27, 2007 3:19 AM (in response to timfox)

Ok, with help from Andrew on the related thread

http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4041276#4041276

I see why this message is appearing. When a callback handler is registered, the client side creates a control connection, and when the handler is unregistered, the control connection is destroyed. However, the creation and destruction is somewhat asymmetric: the connection is created when BisocketClientInvoker.transport() sees an ADDLISTENER message go by, and it is destroyed when the callback BisocketServerInvoker.handleInternalInvocation() gets a REMOVECLIENTLISTENER message. Originally, the treatment was symmetric, but in the case of "quick shutdown" with no network i/o, BisocketClientInvoker is bypassed, which caused a leak.

Now, the problem occurs when the Messaging connection is created in a single JVM. Even though the transport is specified as "bisocket", a LocalClientInvoker is used, which makes direct method calls on the server invoker, and so, appropriately, the bisocket control connection isn't created. But the REMOVECLIENTLISTENER message still goes to the callback BisocketServerInvoker, which tries to destroy the non-existent control connection.

I'll reduce the message's log level in Remoting 2.2.0.SP3, which I'll create in time for the Messaging 1_2_0_SP2 release.
Actions

Go to original post