Re: Audio lags / user got disconnected

Fri, 11 Dec 2015 00:54:48 -0800 

Hi Maxim,

thank you for your response.
I will try to figure out, what went wrong. Use a different (test) system might work, but at least I will have problems to produce a kind of traffic.
Unfortunately I don't know how to reproduce these error... Might be traffic, might be configuration... it seems we are the only one with that problem... :-( I think you already had a look at your logs...
First I would like to know, do I have to worry about the "INFO o.r.server.net.rtmp.RTMPConnection - Rejected message:"-entries? Rejected doesn't sound that good for me.. 

Greetings Peter

Am 10.12.2015 um 16:52 schrieb Maxim Solodovnik:

I never saw errors like this :(
Can only suggest to
1) try 3.1.0 SNAPSHOT should work better with audio/video
2) try to update red5 to latest RC might work better
3) ask red5 mailing list
try all above :)

is the issue easily reproducible?

On Thu, Dec 10, 2015 at 8:25 PM, Peter Dähn <[email protected]> wrote:


Hi there,

we encounter a problem using om 3.0.7 on ubuntu-server with 4 cpus and 6
gb ram. Network is 1 gb-ethernet.

A teacher reported, that a session was stopped due to several disconnects
and a bad audio quality. Audio had glitches and user got disconnected very
often. The situation got worst by using screen-recording.

I found alot of warnings and disconnects in the log that time.

Here an example:

2015-12-09 18:51:23,504 [NioProcessor-4] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=16, timer=1768266], [message timestamp=1768266]] on
CG2CSHDX5LFVD
2015-12-09 18:51:23,736 [NioProcessor-4] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=34, timer=1388395], [message timestamp=1388395]] on
0XS9VHWF75UMX
2015-12-09 18:51:23,750 [NioProcessor-4] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=16, timer=1768498], [message timestamp=1768498]] on
CG2CSHDX5LFVD
2015-12-09 18:51:23,750 [NioProcessor-1] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=58, timer=504480], [message timestamp=504480]] on
V3GNK2XVSN2VO
2015-12-09 18:51:23,765 [NioProcessor-13] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=4, timer=665318], [message timestamp=665318]] on
3Y2OVUT1W0KWG
2015-12-09 18:51:23,766 [NioProcessor-4] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=16, timer=1768544], [message timestamp=1768544]] on
CG2CSHDX5LFVD
2015-12-09 18:51:23,776 [NioProcessor-9] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=34, timer=1510203], [message timestamp=1510203]] on
O6M69P8EUCWJX
2015-12-09 18:51:23,787 [NioProcessor-15] INFO
o.r.server.net.rtmp.RTMPConnection - Rejected message: Packet [[header data
type=8, channel=58, timer=1448863], [message timestamp=1448863]] on
TIUYYRLR80D32
2015-12-09 18:51:24,126 [DeadlockGuardScheduler-17] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
K1EFB4AJLRGNK
2015-12-09 18:51:26,109 [DeadlockGuardScheduler-21] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
K1EFB4AJLRGNK
2015-12-09 18:51:26,393 [RTMPConnectionScheduler-12] WARN
o.r.server.net.rtmp.RTMPConnection - Closing 1MGWAR97JFXFW, due to long
handshake. State: handshake
2015-12-09 18:51:27,661 [DeadlockGuardScheduler-31] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
K1EFB4AJLRGNK
2015-12-09 18:51:28,283 [http-nio-0.0.0.0-5080-exec-20] WARN
o.r.server.net.rtmpt.RTMPTServlet - Null connection for session id:
1MGWAR97JFXFW
2015-12-09 18:51:29,428 [DeadlockGuardScheduler-23] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
K1EFB4AJLRGNK
2015-12-09 18:51:31,447 [DeadlockGuardScheduler-13] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
YZO09I3B96EYW
2015-12-09 18:51:31,896 [RTMPConnectionExecutor-134] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:51:33,027 [RTMPConnectionExecutor-157] INFO
o.a.o.r.r.ScopeApplicationAdapter - W3C x-category:session x-event:connect
c-ip:xxx.xxx.xxx.xxx c-client-id:HUDY62CJY8UI0
2015-12-09 18:51:34,321 [http-nio-0.0.0.0-5080-exec-35] ERROR
o.r.s.n.r.codec.RTMPProtocolDecoder - Last header null not new, headerSize:
1, channelId 37
2015-12-09 18:51:34,436 [http-nio-0.0.0.0-5080-exec-35] WARN
o.r.s.n.r.codec.RTMPProtocolDecoder - Closing connection because decoding
failed: RTMPTConnection from xxx.xxx.xxx.xxx (in: 3914 out: 3900) session:
HUDY62CJY8UI0 state: connected
2015-12-09 18:51:34,724 [http-nio-0.0.0.0-5080-exec-35] INFO
o.a.o.r.r.ScopeApplicationAdapter - W3C x-category:session
x-event:disconnect c-ip:xxx.xxx.xxx.xxx c-client-id:HUDY62CJY8UI0
2015-12-09 18:51:34,991 [http-nio-0.0.0.0-5080-exec-34] WARN
o.r.server.net.rtmpt.RTMPTServlet - Null connection for session id:
HUDY62CJY8UI0
2015-12-09 18:51:41,518 [DeadlockGuardScheduler-30] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
5SYB0WBTBVCMZ
2015-12-09 18:51:41,819 [RTMPConnectionExecutor-154] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:52:01,167 [DeadlockGuardScheduler-21] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
5SYB0WBTBVCMZ
2015-12-09 18:52:01,404 [RTMPConnectionExecutor-133] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:52:10,091 [RTMPConnectionExecutor-138] INFO
o.a.o.r.r.ScopeApplicationAdapter - W3C x-category:session x-event:connect
c-ip:xxx.xxx.xxx.xxx c-client-id:567
2015-12-09 18:52:29,221 [DeadlockGuardScheduler-17] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
QI81ELGF3OZSJ
2015-12-09 18:52:29,369 [DeadlockGuardScheduler-10] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
QI81ELGF3OZSJ
2015-12-09 18:52:29,483 [RTMPConnectionExecutor-145] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:52:29,617 [RTMPConnectionExecutor-138] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:52:44,665 [DeadlockGuardScheduler-24] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
GARSOFM7QNIBF
2015-12-09 18:52:45,080 [DeadlockGuardScheduler-10] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
YZO09I3B96EYW
2015-12-09 18:52:45,199 [RTMPConnectionExecutor-154] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:52:48,382 [DeadlockGuardScheduler-28] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
XYPAYVIJODN57
2015-12-09 18:53:06,901 [DeadlockGuardScheduler-30] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
5SYB0WBTBVCMZ
2015-12-09 18:53:07,011 [RTMPConnectionExecutor-155] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:53:12,984 [DeadlockGuardScheduler-18] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
5SYB0WBTBVCMZ
2015-12-09 18:53:13,052 [RTMPConnectionExecutor-159] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:53:19,812 [DeadlockGuardScheduler-13] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
XYPAYVIJODN57
2015-12-09 18:53:19,816 [DeadlockGuardScheduler-32] WARN
o.r.s.net.rtmp.ReceivedMessageTask - Interrupting unfinished active task on
XYPAYVIJODN57
2015-12-09 18:53:19,818 [RTMPConnectionExecutor-157] WARN
o.r.s.net.rtmp.RTMPMinaConnection - Interrupted while waiting for write
lock. State: connected
2015-12-09 18:53:22,358 [NioProcessor-5] INFO
o.a.o.r.r.ScopeApplicationAdapter - W3C x-category:session
x-event:disconnect c-ip:xxx.xxx.xxx.xxx c-client-id:559

Does anyone see something like this? Where do I have to start to
investigate, what happens?

BR Peter

Reply via email to