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
