Ticket #179 (closed Bug: Fixed)

Opened 5 years ago

Last modified 5 years ago

Exception unregistering mbean

Reported by: electroteque Owned by: paul
Priority: Minor Component: App Server
Version: 0.8 RC2 Keywords:
Cc:

Description

Hi been getting these per request, are they OK to ignore, should I up the level of the logging for now ?

INFO | jvm 1 | 2007/05/04 09:44:47 | [ERROR] 31422 pool-3-thread-15:( org.red5.server.net.rtmp.RTMPMinaConnection.close ) Exception unregistering mbean
INFO | jvm 1 | 2007/05/04 09:44:47 | javax.management.InstanceNotFoundException: org.red5.server:type=RTMPMinaConnection,connectionType=persistent,host=serveriphere,port=1935,clientId=4
INFO | jvm 1 | 2007/05/04 09:44:47 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(Unknown Source)
INFO | jvm 1 | 2007/05/04 09:44:47 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/04 09:44:47 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/04 09:44:47 | at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.red5.server.net.rtmp.RTMPMinaConnection.close(RTMPMinaConnection.java:156)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.red5.server.net.rtmp.BaseRTMPHandler.connectionClosed(BaseRTMPHandler.java:212)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.red5.server.net.rtmp.RTMPMinaIoHandler.sessionClosed(RTMPMinaIoHandler.java:217)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.apache.mina.common.support.AbstractIoFilterChain$TailFilter.sessionClosed(AbstractIoFilterChain.java:677)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.apache.mina.common.support.AbstractIoFilterChain.callNextSessionClosed(AbstractIoFilterChain.java:321)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.apache.mina.common.support.AbstractIoFilterChain.access$800(AbstractIoFilterChain.java:54)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.sessionClosed(AbstractIoFilterChain.java:781)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java:267)
INFO | jvm 1 | 2007/05/04 09:44:47 | at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(ExecutorFilter.java:307)
INFO | jvm 1 | 2007/05/04 09:44:47 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2007/05/04 09:44:47 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2007/05/04 09:44:47 | at java.lang.Thread.run(Unknown Source)

Change History

Changed 5 years ago by joachim

Assigning to Paul.

Changed 5 years ago by paul

I believe this is fixed in r1969. I simplified unregistration and added a registration check prior to the unreg attempt.

Changed 5 years ago by electroteque

Hi, unfortunately after updating to 1716 i still get this

INFO | jvm 1 | 2007/05/07 05:07:07 | [ERROR] 196781 pool-3-thread-5:( org.red5.server.jmx.JMXFactory.unregisterMBean ) Exception unregistering mbean
INFO | jvm 1 | 2007/05/07 05:07:07 | javax.management.InstanceNotFoundException: org.red5.server:type=RTMPMinaConnection,connectionType=persistent,host=ourip,port=1935,clientId=34
INFO | jvm 1 | 2007/05/07 05:07:07 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(Unknown Source)
INFO | jvm 1 | 2007/05/07 05:07:07 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/07 05:07:07 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/07 05:07:07 | at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.red5.server.jmx.JMXFactory.unregisterMBean(JMXFactory.java:156)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.red5.server.net.rtmp.RTMPMinaConnection.close(RTMPMinaConnection.java:155)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.red5.server.net.rtmp.BaseRTMPHandler.connectionClosed(BaseRTMPHandler.java:212)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.red5.server.net.rtmp.RTMPMinaIoHandler.sessionClosed(RTMPMinaIoHandler.java:217)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.apache.mina.common.support.AbstractIoFilterChain$TailFilter.sessionClosed(AbstractIoFilterChain.java:677)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.apache.mina.common.support.AbstractIoFilterChain.callNextSessionClosed(AbstractIoFilterChain.java:321)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.apache.mina.common.support.AbstractIoFilterChain.access$800(AbstractIoFilterChain.java:54)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.sessionClosed(AbstractIoFilterChain.java:781)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java:267)
INFO | jvm 1 | 2007/05/07 05:07:07 | at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(ExecutorFilter.java:307)
INFO | jvm 1 | 2007/05/07 05:07:07 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2007/05/07 05:07:07 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2007/05/07 05:07:07 | at java.lang.Thread.run(Unknown Source)

Changed 5 years ago by electroteque

Still getting lots of these

INFO | jvm 1 | 2007/05/08 05:46:12 | [ERROR] 90066531 pool-3-thread-9:( org.red5.server.jmx.JMXFactory.unregisterMBean ) Exception unregistering mbean
INFO | jvm 1 | 2007/05/08 05:46:12 | javax.management.InstanceNotFoundException: org.red5.server:type=RTMPMinaConnection,connectionType=persistent,host=ourip,port=1935,clientId=20255
INFO | jvm 1 | 2007/05/08 05:46:12 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(Unknown Source)
INFO | jvm 1 | 2007/05/08 05:46:12 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/08 05:46:12 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/08 05:46:12 | at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.red5.server.jmx.JMXFactory.unregisterMBean(JMXFactory.java:156)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.red5.server.net.rtmp.RTMPMinaConnection.close(RTMPMinaConnection.java:155)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.red5.server.net.rtmp.BaseRTMPHandler.connectionClosed(BaseRTMPHandler.java:212)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.red5.server.net.rtmp.RTMPMinaIoHandler.sessionClosed(RTMPMinaIoHandler.java:217)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.apache.mina.common.support.AbstractIoFilterChain$TailFilter.sessionClosed(AbstractIoFilterChain.java:677)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.apache.mina.common.support.AbstractIoFilterChain.callNextSessionClosed(AbstractIoFilterChain.java:321)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.apache.mina.common.support.AbstractIoFilterChain.access$800(AbstractIoFilterChain.java:54)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.sessionClosed(AbstractIoFilterChain.java:781)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java:267)
INFO | jvm 1 | 2007/05/08 05:46:12 | at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(ExecutorFilter.java:307)
INFO | jvm 1 | 2007/05/08 05:46:12 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2007/05/08 05:46:12 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2007/05/08 05:46:12 | at java.lang.Thread.run(Unknown Source)

Changed 5 years ago by joachim

reopening as problem still seems to exist

Changed 5 years ago by paul

Ill whip up a junit test to see if i can reproduce it.

Changed 5 years ago by paul

Cant reproduce this issue. Junit test exists in test for verification

Changed 5 years ago by electroteque

Still getting them what do i do about it then ? I have jmx turned off for the moment because i cant even get local management working via the java service wrapper for windows let alone remotely via a secured connection.

INFO | jvm 1 | 2007/05/12 01:03:04 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:47 | [ERROR] 64966172 pool-3-thread-4:( org.red5.server.jmx.JMXAgent.unregisterMBean ) Exception unregistering mbean
INFO | jvm 1 | 2007/05/12 01:09:47 | javax.management.InstanceNotFoundException: org.red5.server:type=RTMPMinaConnection,connectionType=persistent,host=ourhost,port=1935,clientId=15591
INFO | jvm 1 | 2007/05/12 01:09:47 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:47 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:47 | at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:47 | at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.red5.server.jmx.JMXAgent.unregisterMBean(JMXAgent.java:135)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.red5.server.net.rtmp.RTMPMinaConnection.close(RTMPMinaConnection.java:60)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.red5.server.net.rtmp.BaseRTMPHandler.connectionClosed(BaseRTMPHandler.java:212)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.red5.server.net.rtmp.RTMPMinaIoHandler.sessionClosed(RTMPMinaIoHandler.java:223)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.apache.mina.common.support.AbstractIoFilterChain$TailFilter.sessionClosed(AbstractIoFilterChain.java:677)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.apache.mina.common.support.AbstractIoFilterChain.callNextSessionClosed(AbstractIoFilterChain.java:321)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.apache.mina.common.support.AbstractIoFilterChain.access$800(AbstractIoFilterChain.java:54)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.sessionClosed(AbstractIoFilterChain.java:781)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java:267)
INFO | jvm 1 | 2007/05/12 01:09:47 | at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(ExecutorFilter.java:307)
INFO | jvm 1 | 2007/05/12 01:09:47 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:47 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:47 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2007/05/12 01:09:51 | [ERROR] 64970875 pool-3-thread-11:( org.red5.server.jmx.JMXAgent.unregisterMBean ) Exception unregistering mbean

Changed 5 years ago by paul

Dan,
To assist in the reproduction of this issue I need to know the symptoms under which it occurs. I dont see the environment you are using nor any steps to reproduce? At a minimum I need the following:
- OS and version
- Java version
- Red5 version or SVN revision number
- War or standalone

In addition, I am curious as to how you are disabling JMX? I assume you mean the connectors.

Changed 5 years ago by paul

Downgraded to minor. This will not negatively impact the operation of the server outside of the JMX subsystem. The issue also not reproducible using the current unit test.

Changed 5 years ago by electroteque

Im not really sure how to reproduce because i dont know what the problem is to begin with.

For future references here are our details sorry

Latest SVN unfortunately
Java 6 update 1
Windows 2003 Server
Windows Service via java service wrapper
2GB Ram
3GB P4 with HT
Standalone Server

This same configuration is on 3 machines running behind a hardware load balancer.

I just dont enable the JMX remoting arguments on the java command if you know what i mean simply because it hasnt worked via the java service wrapper, ie i cant get it loading local management of red5 via jconsole its supposed to be running the service as administrator too, and remoting wont work for some reason now even unsecured but ive been trying to get it password protected with ssl with no luck.

Changed 5 years ago by dracoxul

I am getting these exceptions also .. it looks like two different starting places .. it seems to only happen when the connection was closed due to too much inactivity however it doesn't happen every time.

The ZLOG is some info I output for logging .. it is separated by semi-colons with these fields ..
Remote IP
Client ID
Date/Time
What happened
Info String
Last Ping Time
Written Bytes
Read Bytes
Free Memory
Total Memory
Max Memory

I'm running under a linux VPS ..
Red5 Version 0.6.2
Standalone Server

java version "1.6.0_02"
Java(TM) SE Runtime Environment (build 1.6.0_02-b05)
Java HotSpot(TM) Server VM (build 1.6.0_02-b05, mixed mode)
using -server option

Linux version 2.6.9-023stab044.4-enterprise (root@rhel4-32) (gcc version 3.4.5 20051201 (Red Hat 3.4.5-2)) #1 SMP Thu May 24 17:41:23 MSD 2007

[WARN] 30306753 DefaultQuartzScheduler_Worker-5:( org.red5.server.net.rtmp.RTMPConnection.execute ) Closing RTMPMinaConnection from 203.161.94.246:3411 to (in: 3720, out: 7292223) due to too much inactivity (1184376148420).
[INFO] 30306754 DefaultQuartzScheduler_Worker-5:( com.dragontech.streamserver.StreamServer.appDisconnect ) ZLOG=203.161.94.246;306;2007-07-13 20:22:33 -0500;disconnect;;-1;7292223;3720;115449632;144310272;151519232
[WARN] 30306758 DefaultQuartzScheduler_Worker-5:( org.red5.server.jmx.JMXAgent.unregisterMBean ) Exception unregistering mbean
javax.management.InstanceNotFoundException: org.red5.server:type=RTMPMinaConnection,connectionType=persistent,host=,port=1935,clientId=306

at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
at org.red5.server.jmx.JMXAgent.unregisterMBean(JMXAgent.java:192)
at org.red5.server.net.rtmp.RTMPMinaConnection.close(RTMPMinaConnection.java:62)
at org.red5.server.net.rtmp.RTMPMinaConnection.onInactive(RTMPMinaConnection.java:140)
at org.red5.server.net.rtmp.RTMPConnection$KeepAliveJob.execute(RTMPConnection.java:979)
at org.red5.server.scheduling.QuartzSchedulingServiceJob.execute(QuartzSchedulingServiceJob.java:59)
at org.quartz.core.JobRunShell.run(JobRunShell.java:202)
at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)

-- and --

[WARN] 33023161 DefaultQuartzScheduler_Worker-9:( org.red5.server.net.rtmp.RTMPConnection.execute ) Closing RTMPMinaConnection from 203.161.94.246:3500 to (in: 3994, out: 12714859) due to too much inactivity (89172).
[INFO] 33023162 DefaultQuartzScheduler_Worker-9:( com.dragontech.streamserver.StreamServer.appDisconnect ) ZLOG=203.161.94.246;379;2007-07-13 21:07:49 -0500;disconnect;;833;12714859;3994;132373216;145555456;151519232
[WARN] 33023163 pool-3-thread-16:( org.red5.server.jmx.JMXAgent.unregisterMBean ) Exception unregistering mbean
javax.management.InstanceNotFoundException: org.red5.server:type=RTMPMinaConnection,connectionType=persistent,host=,port=1935,clientId=379

at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
at org.red5.server.jmx.JMXAgent.unregisterMBean(JMXAgent.java:192)
at org.red5.server.net.rtmp.RTMPMinaConnection.close(RTMPMinaConnection.java:62)
at org.red5.server.net.rtmp.BaseRTMPHandler.connectionClosed(BaseRTMPHandler.java:229)
at org.red5.server.net.rtmp.RTMPMinaIoHandler.sessionClosed(RTMPMinaIoHandler.java:229)
at org.apache.mina.common.support.AbstractIoFilterChain$TailFilter.sessionClosed(AbstractIoFilterChain.java:677)
at org.apache.mina.common.support.AbstractIoFilterChain.callNextSessionClosed(AbstractIoFilterChain.java:321)
at org.apache.mina.common.support.AbstractIoFilterChain.access$800(AbstractIoFilterChain.java:54)
at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.sessionClosed(AbstractIoFilterChain.java:781)
at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java:267)
at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(ExecutorFilter.java:307)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
at java.lang.Thread.run(Thread.java:619)

I hope this helps .. let me know if you need something else

Changed 5 years ago by electroteque

please close this havent had it for a while

Changed 5 years ago by paul

All indications are that this is no longer an issue

Note: See TracTickets for help on using tickets.