BBB Server not responding

534 views

Skip to first unread message

Mustansar Mehmood

unread,

Aug 23, 2011, 1:01:19 PM8/23/11

to bigbluebu...@googlegroups.com, BigBlueButton-dev

Hi All,
We conducted a test session of big blue button with some staff and faculty members. With a total of 5 participants, at one point BBB server became non responsive and no user could get back to the meeting in progress.
We also tried creating a new meeting which went successful however no user could join the new meeting either. A restart of BBB button seemed to 'fix' the problem. We are using BBB through Sakai LMS. And trying to avoid
repetition of such incident with BBB server. I hope BBB developer community may have some pointers to help us diagnose the problem and perhaps share some preventative measures to avoid this when the semester is in full swing.
Regards,
Mustansar

-- 
Mustansar Mehmood
Educational System Developer& Integrator

Information Technology  
6100 Main St. MS 119
Houston Texas 77005

Phone:(713)348-2523
Fax  :(713)348 6099 
email:must...@rice.edu





I have yet to see any problem, however complicated, which, when,you  looked at it in the right way, did not become still more complicated. 
    -- Poul Anderson

Fred Dixon

unread,

Aug 23, 2011, 1:21:49 PM8/23/11

to bigblueb...@googlegroups.com

Hi Mustansar,

Sorry to hear about your problems.

As one of the BigBlueButton developers, we spend a lot of effort in
testing and making BigBlueButton as stable as possible. When a
server stops responding, we want to know why.

Unfortunately, there's not enough information in your post to
determine what caused BigBlueButton to stop accepting connections. To
help us diagnose the problem, one suggestion would be to do the
following:

sudo bbb-conf --clean

which does a restart of BigBlueButton and 'cleans' out all the log
files. Doing this enables you to run

sudo bbb-conf --debug

which greps through the various log files for BigBlueButton looking
for exceptions and errors. If you do a clean restart, try the session
with five people, and encounter the same problems, you can post the
output of debug to this mailing list. We'll try and determine what
causes the error.

You don't have to figure this all out alone. Another option is to
contact one of the companies listed at

http://bigbluebutton.org/support

for commercial support. In this manner, when there is a problem,
you'll have a company that is responsible for fixing it. For some of
the companies, the revenues they earn from support goes back into
improving BigBlueButton. This is the traditional open source business
model.

For preventative measures, make sure you give BigBlueButton plenty of
memory (at least 2G) and CPU (at least a dual-core, preferably a
quad-core).

Regards,... Fred
--
http://code.google.com/p/bigbluebutton/wiki/FAQ#BigBlueButton_Committer

> --
> You received this message because you are subscribed to the Google Groups
> "BigBlueButton-dev" group.
> To post to this group, send email to bigblueb...@googlegroups.com.
> To unsubscribe from this group, send email to
> bigbluebutton-...@googlegroups.com.
> For more options, visit this group at
> http://groups.google.com/group/bigbluebutton-dev?hl=en.
>

Mustansar Mehmood

unread,

Aug 26, 2011, 10:16:19 AM8/26/11

to bigblueb...@googlegroups.com

Here is the log file for the incident
----------------------- bigbluebutton.2011-08-23.log
------------------------------------
2011-08-23 09:36:19,472 [NioProcessor-1] ERROR
o.red5.server.net.rtmp.RTMPHandler - Error connecting {}
java.lang.IllegalStateException: Not connected to FreeSWITCH Event Socket
at
org.freeswitch.esl.client.inbound.Client.checkConnected(Client.java:569)
[na:na]
at
org.freeswitch.esl.client.inbound.Client.sendSyncApiCommand(Client.java:178)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchApplication.populateRoom(FreeswitchApplication.java:104)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchServiceProvider.populateRoom(FreeswitchServiceProvider.java:49)
[na:na]
at
org.bigbluebutton.webconference.voice.ConferenceService.createConference(ConferenceService.java:50)
[na:na]
at
org.bigbluebutton.conference.service.voice.VoiceHandler.roomConnect(VoiceHandler.java:94)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.roomConnect(MultiThreadedApplicationAdapter.java:653)
[red5.jar:na]
at
org.bigbluebutton.conference.BigBlueButtonApplication.roomConnect(BigBlueButtonApplication.java:127)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.connect(MultiThreadedApplicationAdapter.java:339)
[red5.jar:na]
at org.red5.server.Scope.connect(Scope.java:329) [red5.jar:na]
at org.red5.server.BaseConnection.connect(BaseConnection.java:303)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPConnection.connect(RTMPConnection.java:270)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaConnection.connect(RTMPMinaConnection.java:97)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPHandler.onInvoke(RTMPHandler.java:285)
[red5.jar:na]
at
org.red5.server.net.rtmp.BaseRTMPHandler.messageReceived(BaseRTMPHandler.java:138)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaIoHandler.messageReceived(RTMPMinaIoHandler.java:180)
[red5.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:713)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter$ProtocolDecoderOutputImpl.flush(ProtocolCodecFilter.java:375)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:229)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:119)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:426)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:638)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:598)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:587)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:61)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:969)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
[mina-core-2.0.0-RC1.jar:na]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[na:1.6.0_20]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[na:1.6.0_20]
at java.lang.Thread.run(Thread.java:636) [na:1.6.0_20]
2011-08-23 09:36:24,421 [Red5_Scheduler_Worker-3] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.53.15 : 51273 to 128.42.127.5 (in: 3660 out 3177 ), with id
1393675320 due to long handshake
2011-08-23 10:54:11,882 [Red5_Scheduler_Worker-2] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
200.13.111.200 : 50493 to 128.42.127.5 (in: 68095759 out 136139977 ),
with id 2058920782, due to too much inactivity (199727ms), last ping
sent 65437ms ago
2011-08-23 10:54:12,883 [Red5_Scheduler_Worker-2] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:12,883 [Red5_Scheduler_Worker-5] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
200.13.111.200 : 50562 to 128.42.127.5 (in: 4639899 out 4757097 ), with
id 50704776, due to too much inactivity (1599553ms), last ping sent
66438ms ago
2011-08-23 10:54:12,883 [Red5_Scheduler_Worker-11] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.94.194 : 49415 to 128.42.127.5 (in: 6228 out 6076 ), with id
1246683298, due to too much inactivity (87291ms), last ping sent 1992ms ago
2011-08-23 10:54:11,882 [Red5_Scheduler_Worker-6] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.94.194 : 49421 to 128.42.127.5 (in: 22254858 out 48788059 ), with
id 1906574939, due to too much inactivity (731466ms), last ping sent
66741ms ago
2011-08-23 10:54:12,883 [Red5_Scheduler_Worker-1] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.94.194 : 49415 to 128.42.127.5 (in: 6228 out 6076 ), with id
1246683298, due to too much inactivity (87291ms), last ping sent 1992ms ago
2011-08-23 10:54:12,228 [Red5_Scheduler_Worker-4] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.94.194 : 49420 to 128.42.127.5 (in: 8624 out 51687962 ), with id
1703961130, due to too much inactivity (78306ms), last ping sent 68398ms ago
2011-08-23 10:54:13,540 [Red5_Scheduler_Worker-5] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:14,193 [Red5_Scheduler_Worker-6] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:13,540 [Red5_Scheduler_Worker-11] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:14,193 [Red5_Scheduler_Worker-1] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:14,193 [Red5_Scheduler_Worker-7] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
200.13.111.200 : 50439 to 128.42.127.5 (in: 11414 out 11263 ), with id
1986022456, due to too much inactivity (74017ms), last ping sent 16576ms ago
2011-08-23 10:54:14,193 [Red5_Scheduler_Worker-8] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
200.13.111.250 : 58652 to 128.42.127.5 (in: 11542 out 11389 ), with id
1577592654, due to too much inactivity (73952ms), last ping sent 15316ms ago
2011-08-23 10:54:33,216 [Red5_Scheduler_Worker-7] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:15,498 [Red5_Scheduler_Worker-4] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:38,312 [Red5_Scheduler_Worker-8] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:33,866 [Red5_Scheduler_Worker-12] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.53.15 : 51423 to 128.42.127.5 (in: 9341 out 83832574 ), with id
1407461417, due to too much inactivity (84455ms), last ping sent 84272ms ago
2011-08-23 10:54:33,866 [Red5_Scheduler_Worker-9] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
200.13.111.250 : 58763 to 128.42.127.5 (in: 17261260 out 185915021 ),
with id 1024184893, due to too much inactivity (1573955ms), last ping
sent 83651ms ago
2011-08-23 10:54:54,285 [Red5_Scheduler_Worker-9] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:48,537 [Red5_Scheduler_Worker-12] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:43,353 [Red5_Scheduler_Worker-1] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
99.28.191.100 : 50238 to 128.42.127.5 (in: 10705 out 30148373 ), with id
1682302074, due to too much inactivity (108810ms), last ping sent
69298ms ago
2011-08-23 10:54:35,795 [Red5_Scheduler_Worker-11] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.53.15 : 51424 to 128.42.127.5 (in: 12939378 out 41015264 ), with
id 1546605602, due to too much inactivity (312137ms), last ping sent
54421ms ago
2011-08-23 10:54:33,866 [Red5_Scheduler_Worker-10] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
99.28.191.100 : 50243 to 128.42.127.5 (in: 3390508 out 3498949 ), with
id 591615258, due to too much inactivity (818931ms), last ping sent
73068ms ago
2011-08-23 10:54:54,935 [Red5_Scheduler_Worker-8] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
200.13.111.200 : 50491 to 128.42.127.5 (in: 11685 out 13776992 ), with
id 481359132, due to too much inactivity (101379ms), last ping sent
43394ms ago
2011-08-23 10:54:51,087 [Red5_Scheduler_Worker-3] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
10.74.53.15 : 51425 to 128.42.127.5 (in: 976475 out 1148327 ), with id
2117050972, due to too much inactivity (95012ms), last ping sent 39546ms ago
2011-08-23 10:55:01,314 [Red5_Scheduler_Worker-1] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:54:54,935 [Red5_Scheduler_Worker-7] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
200.13.111.250 : 58760 to 128.42.127.5 (in: 12008 out 16568066 ), with
id 946603593, due to too much inactivity (104192ms), last ping sent
43394ms ago
2011-08-23 10:55:02,644 [Red5_Scheduler_Worker-3] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:55:01,993 [Red5_Scheduler_Worker-10] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:55:41,193 [Red5_Scheduler_Worker-7] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:55:01,994 [Red5_Scheduler_Worker-8] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 10:55:01,993 [Red5_Scheduler_Worker-11] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 11:20:24,079 [main] WARN
o.s.b.GenericTypeAwarePropertyDescriptor - Invalid JavaBean property
'enableRmiAdapter' being accessed! Ambiguous write methods found next to
actually used [public void
org.red5.server.jmx.JMXAgent.setEnableRmiAdapter(java.lang.String)]:
[public void org.red5.server.jmx.JMXAgent.setEnableRmiAdapter(boolean)]
2011-08-23 11:20:24,082 [main] WARN
o.s.b.GenericTypeAwarePropertyDescriptor - Invalid JavaBean property
'enableSsl' being accessed! Ambiguous write methods found next to
actually used [public void
org.red5.server.jmx.JMXAgent.setEnableSsl(java.lang.String)]: [public
void org.red5.server.jmx.JMXAgent.setEnableSsl(boolean)]
2011-08-23 11:20:24,082 [main] WARN
o.s.b.GenericTypeAwarePropertyDescriptor - Invalid JavaBean property
'enableMinaMonitor' being accessed! Ambiguous write methods found next
to actually used [public void
org.red5.server.jmx.JMXAgent.setEnableMinaMonitor(java.lang.String)]:
[public void org.red5.server.jmx.JMXAgent.setEnableMinaMonitor(boolean)]
2011-08-23 11:20:24,661 [main] WARN o.s.b.f.c.CustomEditorConfigurer -
Passing PropertyEditor instances into CustomEditorConfigurer is
deprecated: use PropertyEditorRegistrars or PropertyEditor class names
instead. Offending key [java.net.SocketAddress; offending editor
instance: org.apache.mina.integration.beans.InetSocketAddressEditor@6482d603
2011-08-23 11:20:26,789 [main] WARN o.s.w.s.h.SimpleUrlHandlerMapping -
Neither 'urlMap' nor 'mappings' set on SimpleUrlHandlerMapping
2011-08-23 11:20:29,045 [Launcher:/bigbluebutton] WARN
o.f.esl.client.inbound.Client - Failed to connect to [127.0.0.1:8021]
2011-08-23 11:20:29,050 [Launcher:/bigbluebutton] WARN
o.f.esl.client.inbound.Client - * reason: {}
java.net.ConnectException: Connection refused
at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[na:1.6.0_20]
at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:592)
[na:1.6.0_20]
at
org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink$Boss.connect(NioClientSocketPipelineSink.java:374)
[netty-3.1.5.GA.jar:na]
at
org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink$Boss.processSelectedKeys(NioClientSocketPipelineSink.java:344)
[netty-3.1.5.GA.jar:na]
at
org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink$Boss.run(NioClientSocketPipelineSink.java:266)
[netty-3.1.5.GA.jar:na]
at
org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:108)
[netty-3.1.5.GA.jar:na]
at
org.jboss.netty.util.internal.IoWorkerRunnable.run(IoWorkerRunnable.java:46)
[netty-3.1.5.GA.jar:na]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[na:1.6.0_20]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[na:1.6.0_20]
at java.lang.Thread.run(Thread.java:636) [na:1.6.0_20]
2011-08-23 11:21:05,812 [NioProcessor-1] ERROR
o.red5.server.net.rtmp.RTMPHandler - Error connecting {}
java.lang.IllegalStateException: Not connected to FreeSWITCH Event Socket
at
org.freeswitch.esl.client.inbound.Client.checkConnected(Client.java:569)
[na:na]
at
org.freeswitch.esl.client.inbound.Client.sendSyncApiCommand(Client.java:178)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchApplication.populateRoom(FreeswitchApplication.java:104)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchServiceProvider.populateRoom(FreeswitchServiceProvider.java:49)
[na:na]
at
org.bigbluebutton.webconference.voice.ConferenceService.createConference(ConferenceService.java:50)
[na:na]
at
org.bigbluebutton.conference.service.voice.VoiceHandler.roomConnect(VoiceHandler.java:94)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.roomConnect(MultiThreadedApplicationAdapter.java:653)
[red5.jar:na]
at
org.bigbluebutton.conference.BigBlueButtonApplication.roomConnect(BigBlueButtonApplication.java:127)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.connect(MultiThreadedApplicationAdapter.java:339)
[red5.jar:na]
at org.red5.server.Scope.connect(Scope.java:329) [red5.jar:na]
at org.red5.server.BaseConnection.connect(BaseConnection.java:303)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPConnection.connect(RTMPConnection.java:270)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaConnection.connect(RTMPMinaConnection.java:97)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPHandler.onInvoke(RTMPHandler.java:285)
[red5.jar:na]
at
org.red5.server.net.rtmp.BaseRTMPHandler.messageReceived(BaseRTMPHandler.java:138)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaIoHandler.messageReceived(RTMPMinaIoHandler.java:180)
[red5.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:713)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter$ProtocolDecoderOutputImpl.flush(ProtocolCodecFilter.java:375)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:229)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:119)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:426)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:638)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:598)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:587)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:61)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:969)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
[mina-core-2.0.0-RC1.jar:na]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[na:1.6.0_20]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[na:1.6.0_20]
at java.lang.Thread.run(Thread.java:636) [na:1.6.0_20]
2011-08-23 11:21:10,575 [Red5_Scheduler_Worker-2] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
168.7.55.164 : 51696 to 128.42.127.5 (in: 3651 out 3177 ), with id
1098742785 due to long handshake
2011-08-23 11:21:36,896 [NioProcessor-1] ERROR
o.red5.server.net.rtmp.RTMPHandler - Error connecting {}
java.lang.IllegalStateException: Not connected to FreeSWITCH Event Socket
at
org.freeswitch.esl.client.inbound.Client.checkConnected(Client.java:569)
[na:na]
at
org.freeswitch.esl.client.inbound.Client.sendSyncApiCommand(Client.java:178)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchApplication.populateRoom(FreeswitchApplication.java:104)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchServiceProvider.populateRoom(FreeswitchServiceProvider.java:49)
[na:na]
at
org.bigbluebutton.webconference.voice.ConferenceService.createConference(ConferenceService.java:50)
[na:na]
at
org.bigbluebutton.conference.service.voice.VoiceHandler.roomConnect(VoiceHandler.java:94)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.roomConnect(MultiThreadedApplicationAdapter.java:653)
[red5.jar:na]
at
org.bigbluebutton.conference.BigBlueButtonApplication.roomConnect(BigBlueButtonApplication.java:127)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.connect(MultiThreadedApplicationAdapter.java:339)
[red5.jar:na]
at org.red5.server.Scope.connect(Scope.java:329) [red5.jar:na]
at org.red5.server.BaseConnection.connect(BaseConnection.java:303)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPConnection.connect(RTMPConnection.java:270)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaConnection.connect(RTMPMinaConnection.java:97)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPHandler.onInvoke(RTMPHandler.java:285)
[red5.jar:na]
at
org.red5.server.net.rtmp.BaseRTMPHandler.messageReceived(BaseRTMPHandler.java:138)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaIoHandler.messageReceived(RTMPMinaIoHandler.java:180)
[red5.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:713)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter$ProtocolDecoderOutputImpl.flush(ProtocolCodecFilter.java:375)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:229)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:119)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:426)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:638)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:598)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:587)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:61)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:969)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
[mina-core-2.0.0-RC1.jar:na]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[na:1.6.0_20]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[na:1.6.0_20]
at java.lang.Thread.run(Thread.java:636) [na:1.6.0_20]
2011-08-23 11:21:41,770 [Red5_Scheduler_Worker-9] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
168.7.55.164 : 51716 to 128.42.127.5 (in: 3654 out 3177 ), with id
1029878579 due to long handshake
2011-08-23 11:24:07,546 [NioProcessor-1] ERROR
o.red5.server.net.rtmp.RTMPHandler - Error connecting {}
java.lang.IllegalStateException: Not connected to FreeSWITCH Event Socket
at
org.freeswitch.esl.client.inbound.Client.checkConnected(Client.java:569)
[na:na]
at
org.freeswitch.esl.client.inbound.Client.sendSyncApiCommand(Client.java:178)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchApplication.populateRoom(FreeswitchApplication.java:104)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchServiceProvider.populateRoom(FreeswitchServiceProvider.java:49)
[na:na]
at
org.bigbluebutton.webconference.voice.ConferenceService.createConference(ConferenceService.java:50)
[na:na]
at
org.bigbluebutton.conference.service.voice.VoiceHandler.roomConnect(VoiceHandler.java:94)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.roomConnect(MultiThreadedApplicationAdapter.java:653)
[red5.jar:na]
at
org.bigbluebutton.conference.BigBlueButtonApplication.roomConnect(BigBlueButtonApplication.java:127)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.connect(MultiThreadedApplicationAdapter.java:339)
[red5.jar:na]
at org.red5.server.Scope.connect(Scope.java:329) [red5.jar:na]
at org.red5.server.BaseConnection.connect(BaseConnection.java:303)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPConnection.connect(RTMPConnection.java:270)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaConnection.connect(RTMPMinaConnection.java:97)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPHandler.onInvoke(RTMPHandler.java:285)
[red5.jar:na]
at
org.red5.server.net.rtmp.BaseRTMPHandler.messageReceived(BaseRTMPHandler.java:138)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaIoHandler.messageReceived(RTMPMinaIoHandler.java:180)
[red5.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:713)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter$ProtocolDecoderOutputImpl.flush(ProtocolCodecFilter.java:375)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:229)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:119)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:426)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:638)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:598)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:587)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:61)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:969)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
[mina-core-2.0.0-RC1.jar:na]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[na:1.6.0_20]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[na:1.6.0_20]
at java.lang.Thread.run(Thread.java:636) [na:1.6.0_20]
2011-08-23 11:24:12,343 [Red5_Scheduler_Worker-4] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
99.28.191.100 : 50366 to 128.42.127.5 (in: 3653 out 3177 ), with id
212493877 due to long handshake
2011-08-23 11:32:35,015 [task-scheduler-1] ERROR
o.s.i.handler.LoggingHandler -
org.springframework.integration.message.MessageHandlingException: Failed
to process Message.
at
org.springframework.integration.handler.MethodInvokingMessageProcessor.processMessage(MethodInvokingMessageProcessor.java:186)
at
org.springframework.integration.handler.ServiceActivatingHandler.handleRequestMessage(ServiceActivatingHandler.java:49)
at
org.springframework.integration.handler.AbstractReplyProducingMessageHandler.handleMessageInternal(AbstractReplyProducingMessageHandler.java:100)
at
org.springframework.integration.handler.AbstractMessageHandler.handleMessage(AbstractMessageHandler.java:64)
at
org.springframework.integration.dispatcher.UnicastingDispatcher.doDispatch(UnicastingDispatcher.java:103)
at
org.springframework.integration.dispatcher.UnicastingDispatcher.dispatch(UnicastingDispatcher.java:90)
at
org.springframework.integration.channel.AbstractSubscribableChannel.doSend(AbstractSubscribableChannel.java:43)
at
org.springframework.integration.channel.AbstractMessageChannel.send(AbstractMessageChannel.java:180)
at
org.springframework.integration.channel.AbstractMessageChannel.send(AbstractMessageChannel.java:153)
at
org.springframework.integration.channel.MessageChannelTemplate.doSend(MessageChannelTemplate.java:224)
at
org.springframework.integration.channel.MessageChannelTemplate.send(MessageChannelTemplate.java:181)
at
org.springframework.integration.endpoint.SourcePollingChannelAdapter.doPoll(SourcePollingChannelAdapter.java:78)
at
org.springframework.integration.endpoint.AbstractPollingEndpoint$Poller.innerPoll(AbstractPollingEndpoint.java:236)
at
org.springframework.integration.endpoint.AbstractPollingEndpoint$Poller.poll(AbstractPollingEndpoint.java:220)
at
org.springframework.integration.endpoint.AbstractPollingEndpoint$Poller.run(AbstractPollingEndpoint.java:213)
at
org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:51)
at
org.springframework.scheduling.concurrent.ReschedulingRunnable.run(ReschedulingRunnable.java:81)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:636)
Caused by: org.springframework.expression.spel.SpelEvaluationException:
EL1029E:(pos 8): A problem occurred when trying to execute method
'endMeetingRequest' on object of type
'org.bigbluebutton.conference.RoomsManager': 'Problem invoking method:
public void
org.bigbluebutton.conference.RoomsManager.endMeetingRequest(org.bigbluebutton.conference.Room)'
at
org.springframework.expression.spel.ast.MethodReference.getValueInternal(MethodReference.java:93)
at
org.springframework.expression.spel.ast.CompoundExpression.getValueInternal(CompoundExpression.java:57)
at
org.springframework.expression.spel.ast.SpelNodeImpl.getValue(SpelNodeImpl.java:93)
at
org.springframework.expression.spel.standard.SpelExpression.getValue(SpelExpression.java:93)
at
org.springframework.integration.handler.MethodInvokingMessageProcessor.processMessage(MethodInvokingMessageProcessor.java:167)
... 24 more
Caused by: org.springframework.expression.AccessException: Problem
invoking method: public void
org.bigbluebutton.conference.RoomsManager.endMeetingRequest(org.bigbluebutton.conference.Room)
at
org.springframework.integration.handler.FilteringReflectiveMethodResolver$ReflectiveMethodExecutor.execute(FilteringReflectiveMethodResolver.java:152)
at
org.springframework.expression.spel.ast.MethodReference.getValueInternal(MethodReference.java:90)
... 28 more
Caused by: java.lang.reflect.InvocationTargetException
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at
org.springframework.integration.handler.FilteringReflectiveMethodResolver$ReflectiveMethodExecutor.execute(FilteringReflectiveMethodResolver.java:150)
... 29 more
Caused by: java.lang.NullPointerException
at
org.bigbluebutton.conference.RoomsManager.endMeetingRequest(RoomsManager.java:87)
... 34 more

2011-08-23 13:19:56,402 [NioProcessor-1] ERROR
o.red5.server.net.rtmp.RTMPHandler - Error connecting {}
java.lang.IllegalStateException: Not connected to FreeSWITCH Event Socket
at
org.freeswitch.esl.client.inbound.Client.checkConnected(Client.java:569)
[na:na]
at
org.freeswitch.esl.client.inbound.Client.sendSyncApiCommand(Client.java:178)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchApplication.populateRoom(FreeswitchApplication.java:104)
[na:na]
at
org.bigbluebutton.webconference.voice.freeswitch.FreeswitchServiceProvider.populateRoom(FreeswitchServiceProvider.java:49)
[na:na]
at
org.bigbluebutton.webconference.voice.ConferenceService.createConference(ConferenceService.java:50)
[na:na]
at
org.bigbluebutton.conference.service.voice.VoiceHandler.roomConnect(VoiceHandler.java:94)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.roomConnect(MultiThreadedApplicationAdapter.java:653)
[red5.jar:na]
at
org.bigbluebutton.conference.BigBlueButtonApplication.roomConnect(BigBlueButtonApplication.java:127)
[na:na]
at
org.red5.server.adapter.MultiThreadedApplicationAdapter.connect(MultiThreadedApplicationAdapter.java:339)
[red5.jar:na]
at org.red5.server.Scope.connect(Scope.java:329) [red5.jar:na]
at org.red5.server.BaseConnection.connect(BaseConnection.java:303)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPConnection.connect(RTMPConnection.java:270)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaConnection.connect(RTMPMinaConnection.java:97)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPHandler.onInvoke(RTMPHandler.java:285)
[red5.jar:na]
at
org.red5.server.net.rtmp.BaseRTMPHandler.messageReceived(BaseRTMPHandler.java:138)
[red5.jar:na]
at
org.red5.server.net.rtmp.RTMPMinaIoHandler.messageReceived(RTMPMinaIoHandler.java:180)
[red5.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:713)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter$ProtocolDecoderOutputImpl.flush(ProtocolCodecFilter.java:375)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:229)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:119)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:426)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:638)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:598)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:587)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:61)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:969)
[mina-core-2.0.0-RC1.jar:na]
at
org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
[mina-core-2.0.0-RC1.jar:na]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[na:1.6.0_20]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[na:1.6.0_20]
at java.lang.Thread.run(Thread.java:636) [na:1.6.0_20]
2011-08-23 13:20:01,289 [Red5_Scheduler_Worker-9] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
98.201.110.173 : 51658 to 128.42.127.5 (in: 3660 out 3177 ), with id
1839285357 due to long handshake
2011-08-23 13:23:56,929 [Red5_Scheduler_Worker-2] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
98.201.110.173 : 51683 to 128.42.127.5 (in: 3632 out 3892 ), with id
30132531, due to too much inactivity (60902ms), last ping sent 1001ms ago
2011-08-23 13:23:56,929 [Red5_Scheduler_Worker-2] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 13:23:57,694 [Red5_Scheduler_Worker-5] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
98.201.110.173 : 51685 to 128.42.127.5 (in: 3805 out 4013 ), with id
1116576546, due to too much inactivity (60948ms), last ping sent 1000ms ago
2011-08-23 13:23:57,694 [Red5_Scheduler_Worker-5] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 13:23:57,973 [Red5_Scheduler_Worker-6] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
98.201.110.173 : 51686 to 128.42.127.5 (in: 3741 out 3929 ), with id
23386661, due to too much inactivity (60924ms), last ping sent 999ms ago
2011-08-23 13:23:57,974 [Red5_Scheduler_Worker-6] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 13:23:58,150 [Red5_Scheduler_Worker-7] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
98.201.110.173 : 51684 to 128.42.127.5 (in: 4553 out 5111 ), with id
638031455, due to too much inactivity (60978ms), last ping sent 1000ms ago
2011-08-23 13:23:58,151 [Red5_Scheduler_Worker-7] WARN
o.r.server.net.rtmp.RTMPConnection - This often happens if YOUR Red5
application generated an exception on start-up. Check earlier in the log
for that exception first!
2011-08-23 17:05:59,054 [Red5_Scheduler_Worker-11] WARN
o.r.server.net.rtmp.RTMPConnection - Closing RTMPMinaConnection from
108.94.177.210 : 60503 to null (in: 1537 out 3073 ), with id 27378165
due to long handshake

Richard Alam

unread,

Aug 26, 2011, 10:30:21 AM8/26/11

to bigblueb...@googlegroups.com

Hi,

On Fri, Aug 26, 2011 at 10:16 AM, Mustansar Mehmood <m...@rice.edu> wrote:
> Here is the log file for the incident
> ----------------------- bigbluebutton.2011-08-23.log
> ------------------------------------
> 2011-08-23 09:36:19,472 [NioProcessor-1] ERROR
> o.red5.server.net.rtmp.RTMPHandler - Error connecting {}
> java.lang.IllegalStateException: Not connected to FreeSWITCH Event Socket
> at
> org.freeswitch.esl.client.inbound.Client.checkConnected(Client.java:569)
> [na:na]

> 2011-08-23 11:20:29,045 [Launcher:/bigbluebutton] WARN

> o.f.esl.client.inbound.Client - Failed to connect to [127.0.0.1:8021]
> 2011-08-23 11:20:29,050 [Launcher:/bigbluebutton] WARN
> o.f.esl.client.inbound.Client - * reason: {}
> java.net.ConnectException: Connection refused

This error occurs when FreeSWITCH hasn't fully started and BBB tries
to connect to it. The start-up scripts has been modified to
prevent a race condition where Red5 starts up first before FreeSWITCH
but it seems to be not working 100% of the time.

I think this happens after a reboot of the machine. You can run
"bbb-conf --clean" or "sudo /etc/init.d/red5 restart" to fix the
issue.
We'll be looking at improving the startup sequence reliably.

Richard

--
---
BigBlueButton
http://www.bigbluebutton.org
http://code.google.com/p/bigbluebutton

Mustansar Mehmood

unread,

Aug 26, 2011, 10:35:09 AM8/26/11

to bigblueb...@googlegroups.com

I think last piece I could find was in freewswitch logs during the
session in question that may mean something is below
----------------------------------freeswitch.log-----------
2011-08-23 11:20:39.747379 [NOTICE] switch_loadable_module.c:273 Adding
API Function 'stop_local_stream'
2011-08-23 11:20:39.747418 [NOTICE] switch_loadable_module.c:273 Adding
API Function 'start_local_stream'
2011-08-23 11:20:39.747581 [NOTICE] switch_loadable_module.c:273 Adding
API Function 'show_local_stream'
2011-08-23 11:20:39.747641 [NOTICE] switch_loadable_module.c:297 Adding
File Format 'local_stream'
2011-08-23 11:20:39.748155 [NOTICE] switch_loadable_module.c:297 Adding
File Format 'tone_stream'
2011-08-23 11:20:39.748175 [NOTICE] switch_loadable_module.c:297 Adding
File Format 'silence_stream'
2011-08-23 11:20:39.748230 [CRIT] switch_loadable_module.c:926 Error
Loading module /opt/freeswitch/mod/mod_file_string.so
**/opt/freeswitch/mod/mod_file_string.so: cannot open shared object
file: No such file or directory**
2011-08-23 11:20:39.748266 [CRIT] switch_loadable_module.c:926 Error
Loading module /opt/freeswitch/mod/mod_spidermonkey.so
**/opt/freeswitch/mod/mod_spidermonkey.so: cannot open shared object
file: No such file or directory**
2011-08-23 11:20:39.748584 [NOTICE] switch_loadable_module.c:398 Adding
Say interface 'en'
2011-08-23 11:20:39.748651 [CRIT] switch_loadable_module.c:926 Error
Loading module /opt/freeswitch/mod/mod_say_ru.so
**/opt/freeswitch/mod/mod_say_ru.so: cannot open shared object file: No
such file or directory**
2011-08-23 11:20:39.748909 [DEBUG] mod_event_socket.c:2688 Socket up
listening on 127.0.0.1:8021
2011-08-23 11:20:39.748934 [NOTICE] switch_core.c:974 Created ip list
rfc1918.auto default (deny)
2011-08-23 11:20:39.748955 [NOTICE] switch_utils.c:195 Adding 10.0.0.0/8
(allow) [] to list rfc1918.auto
2011-08-23 11:20:39.748963 [NOTICE] switch_utils.c:195 Adding
172.16.0.0/12 (allow) [] to list rfc1918.auto
2011-08-23 11:20:39.748972 [NOTICE] switch_utils.c:195 Adding
192.168.0.0/16 (allow) [] to list rfc1918.auto
2011-08-23 11:20:39.748997 [NOTICE] switch_core.c:982 Created ip list
wan.auto default (allow)
2011-08-23 11:20:39.749001 [NOTICE] switch_utils.c:195 Adding 10.0.0.0/8
(deny) [] to list wan.auto
2011-08-23 11:20:39.749006 [NOTICE] switch_utils.c:195 Adding
172.16.0.0/12 (deny) [] to list wan.auto
2011-08-23 11:20:39.749013 [NOTICE] switch_utils.c:195 Adding
192.168.0.0/16 (deny) [] to list wan.auto
2011-08-23 11:20:39.749020 [NOTICE] switch_core.c:990 Created ip list
nat.auto default (deny)
2011-08-23 11:20:39.749028 [NOTICE] switch_core.c:992 Adding
128.42.127.5/255.255.255.0 (deny) to list nat.auto
2011-08-23 11:20:39.749041 [NOTICE] switch_utils.c:195 Adding 10.0.0.0/8
(allow) [] to list nat.auto
2011-08-23 11:20:39.749045 [NOTICE] switch_utils.c:195 Adding
172.16.0.0/12 (allow) [] to list nat.auto
2011-08-23 11:20:39.749051 [NOTICE] switch_utils.c:195 Adding
192.168.0.0/16 (allow) [] to list nat.auto
2011-08-23 11:20:39.749057 [NOTICE] switch_core.c:1001 Created ip list
loopback.auto default (deny)
2011-08-23 11:20:39.749062 [NOTICE] switch_utils.c:195 Adding
127.0.0.0/8 (allow) [] to list loopback.auto
2011-08-23 11:20:39.749100 [NOTICE] switch_core.c:1007 Created ip list
localnet.auto default (deny)
2011-08-23 11:20:39.749110 [NOTICE] switch_core.c:1010 Adding
128.42.127.5/255.255.255.0 (allow) to list localnet.auto
2011-08-23 11:20:39.749123 [ERR] switch_utils.c:175 Error Adding
127.0.0.1/255 (allow) [] to list localhost
2011-08-23 11:20:39.749138 [ERR] switch_core.c:1108 Error Adding
127.0.0.1/255 (allow) to list localhost
2011-08-23 11:20:39.749150 [NOTICE] switch_utils.c:195 Adding
192.168.42.0/24 (deny) [] to list lan
2011-08-23 11:20:39.749156 [NOTICE] switch_core.c:1106 Adding
192.168.42.0/24 (deny) to list lan
2011-08-23 11:20:39.749165 [NOTICE] switch_utils.c:195 Adding
192.168.42.42/32 (allow) [] to list lan
2011-08-23 11:20:39.749174 [NOTICE] switch_core.c:1106 Adding
192.168.42.42/32 (allow) to list lan

Mustansar Mehmood

unread,

Aug 26, 2011, 11:07:28 AM8/26/11

to bigblueb...@googlegroups.com, ffd...@gmail.com, ritz...@gmail.com

Dear All,
As a last resort I have rub bbb-conf -debug and found
this result
Alot of errors are about mysql connection, I think mysql is not even
being used by BBB(I would still want these errors to go away but not too
worried or should I be?).
However this error seems to be of concern
/usr/share/red5/log/error.log:2011-08-26 09:29:07,784 [RMI TCP
Connection(845)-128.42.127.5] ERROR o.a.c.loader.WebappClassLoader - A
web application appears to have started a thread named [pool-6-thread-2]
but has failed to stop it. This is very likely to create a memory leak.

I hope long time BBB users/developers have found a remedy for that and
wont mind sharing.

P.S Fred: Regarding out previous communication. I found out that there
is no easy way to create LMS account as well linux account for outside
users. However I can share log files at my own discretion.

Regards,
Mustansar

------------------------------------bbb.conf --debug
output-------------------

-- ERRORS found in /var/log/bigbluebutton/* --
/var/log/bigbluebutton/bbb-web.log:2011-08-26 09:29:12,345 ERROR
[StackTrace] - Sanitizing stacktrace:
/var/log/bigbluebutton/bbb-web.log:2011-08-26 09:29:12,347 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 13:56:24,160 ERROR
[StackTrace] - Sanitizing stacktrace:
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 13:56:24,162 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 14:25:21,249 ERROR
[StackTrace] - Sanitizing stacktrace:
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 14:25:21,252 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 14:41:51,705 ERROR
[StackTrace] - Sanitizing stacktrace:
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 14:41:51,707 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:2011-07-06 11:47:49,327 ERROR
[StackTrace] - Sanitizing stacktrace:
/var/log/bigbluebutton/bbb-web.log.1:2011-07-06 11:47:49,329 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure

-- ERRORS found in /var/log/bigbluebutton/* --
/var/log/bigbluebutton/bbb-web.log:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log:Caused by: java.net.ConnectException:
Connection refused
/var/log/bigbluebutton/bbb-web.log:2011-08-26 09:29:12,347 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log:Caused by: java.net.ConnectException:
Connection refused
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 13:56:24,162 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 14:25:21,252 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused
/var/log/bigbluebutton/bbb-web.log.1:2011-06-20 14:41:51,707 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused
/var/log/bigbluebutton/bbb-web.log.1:2011-07-06 11:47:49,329 ERROR
[org.codehaus.groovy.grails.web.context.GrailsContextLoader] - Error
occurred shutting down plug-in manager:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:org.codehaus.groovy.runtime.InvokerInvocationException:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications
link failure
/var/log/bigbluebutton/bbb-web.log.1: at
com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1074)
/var/log/bigbluebutton/bbb-web.log.1:Caused by:
java.net.ConnectException: Connection refused

-- ERRORS found in /usr/share/red5/log/* --
/usr/share/red5/log/bigbluebutton.2011-06-20.log:2011-06-20 13:58:54,028
[Launcher:/bigbluebutton] ERROR o.b.w.v.f.FreeswitchServiceProvider -
Connect to FreeSwitch ESL socket failed
/usr/share/red5/log/bigbluebutton.2011-06-20.log:2011-06-20 14:27:43,541
[Launcher:/bigbluebutton] ERROR o.b.w.v.f.FreeswitchServiceProvider -
Connect to FreeSwitch ESL socket failed
/usr/share/red5/log/bigbluebutton.2011-06-20.log:2011-06-20 14:44:14,103
[Launcher:/bigbluebutton] ERROR o.b.w.v.f.FreeswitchServiceProvider -
Connect to FreeSwitch ESL socket failed
/usr/share/red5/log/bigbluebutton.2011-07-06.log:2011-07-06 11:50:09,055
[Launcher:/bigbluebutton] ERROR o.b.w.v.f.FreeswitchServiceProvider -
Connect to FreeSwitch ESL socket failed
/usr/share/red5/log/bigbluebutton.2011-07-07.log:2011-07-07 16:03:50,875