[Freeswitch-users] FreeSwitch Utilizes 100% of CPU Sometimes When User Quits Conference

Erwin Merioles erwin at merioles.net
Thu Aug 25 13:14:41 MSD 2011 

Thanks for the reply!

I think I've found the problem. When our "host" ends the meeting, it sends a
disconnect event to all participants. The SWITCH_POLLOUT event happens when
the participants tries to end the meeting simultaneously with the host.

I think it is the server's way of doing a "cleanup" and tries to send any
remaining data to whoever is left in the conference -- which in our case is
none. This results to an infinite loop of tries.

The problem was fixed on our end by delaying the disconnection of the
participants a bit.

Regards,

Erwin D. Merioles

-----Original Message-----
From: freeswitch-users-bounces at lists.freeswitch.org
[mailto:freeswitch-users-bounces at lists.freeswitch.org] On Behalf Of Anthony
Minessale
Sent: Thursday, August 25, 2011 12:01 AM
To: FreeSWITCH Users Help
Subject: Re: [Freeswitch-users] FreeSwitch Utilizes 100% of CPU Sometimes
When User Quits Conference

Really you should be reporting bugs to http://jira.freeswitch.org Are you
only having this problem with mod_rtmp (its only 2 months old) Most likely
you have created a new condition that the author has not taken into account.
Ideally you should file it on jira under mod_rtmp and attach a back trace
from a core dump produced by gcore.



On Wed, Aug 24, 2011 at 7:31 AM, Erwin Merioles <erwin at merioles.net> wrote:
> Hey guys,
>
>
>
> I’ve been having trouble with FreeSwitch for quite some time. We’re 
> trying to use mod_rtmp to add sound to one of our applications,
www.321meet.com.
> Unfortunately, FreeSwitch’s CPU usage spikes when the host ( the first 
> one to join the conference ), quits or closes the browser window. I’ve 
> checked and this always happen when the following line is called –
>
>
>
> 2011-08-24 12:24:28.057200 [NOTICE] rtmp_tcp.c:73 Pollout: true
>
>
>
> FS Console Log follows :
>
>
>
> 2011-08-24 12:22:59.177294 [NOTICE] mod_rtmp.c:743 New RTMP session 
> [4a97320c-e50d-4ed3-a59c-4aef799d379d]
>
> 2011-08-24 12:22:59.477278 [NOTICE] rtmp_sig.c:121 Sent connect reply
>
> 2011-08-24 12:23:15.597196 [INFO] rtmp_sig.c:136 Replied to 
> createStream (1)
>
> 2011-08-24 12:23:16.237201 [INFO] rtmp_sig.c:274 Got publish on stream 1.
>
> 2011-08-24 12:23:17.177293 [INFO] rtmp_sig.c:136 Replied to 
> createStream (2)
>
> 2011-08-24 12:23:17.177293 [WARNING] rtmp.c:99 [amfnumber=2] Unhandled 
> control packet (type=0x3)
>
> 2011-08-24 12:23:17.457195 [WARNING] sofia.c:4403 Ping succeeded 
> voip9.telsome.com with code 404 - count -1/1/1, state UP
>
> 2011-08-24 12:23:17.457195 [INFO] rtmp_sig.c:136 Replied to 
> createStream (3)
>
> 2011-08-24 12:23:17.457195 [NOTICE] switch_channel.c:897 New Channel
> rtmp/default/3213533 [8e7ccce8-172c-4b91-9183-1ad9d2f0e6dd]
>
> 2011-08-24 12:23:17.457195 [ERR] rtmp_sig.c:305 Couldn't create call.
>
> 2011-08-24 12:23:17.457195 [WARNING] sofia.c:4403 Ping succeeded 
> testin with code 404 - count -1/1/1, state UP
>
> 2011-08-24 12:23:17.497199 [INFO] mod_dialplan_xml.c:336 Processing
> <0000000000>->3213533 in context default
>
> 2011-08-24 12:23:17.497199 [NOTICE] mod_rtmp.c:497 Channel 
> [rtmp/default/3213533] has been answered
>
> 2011-08-24 12:23:17.497199 [INFO] mod_conference.c:6644 using channel 
> sound
> prefix: /usr/local/freeswitch/sounds/en/us/callie
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '0' to 'mute'
>
> 2011-08-24 12:23:17.597289 [INFO] switch_ivr_async.c:164 Digit parser
> mod_conference: Setting realm to conf
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '*' to 'deaf mute'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '9' to 'energy up'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '8' to 'energy equ'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '7' to 'energy dn'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '3' to 'vol talk up'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '2' to 'vol talk zero'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '1' to 'vol talk dn'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '6' to 'vol listen up'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '5' to 'vol listen zero'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '4' to 'vol listen dn'
>
> 2011-08-24 12:23:17.597289 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '#' to 'hangup'
>
> 2011-08-24 12:23:17.797195 [INFO] rtmp_sig.c:159 Sending audio
>
> 2011-08-24 12:23:17.797195 [WARNING] rtmp.c:99 [amfnumber=2] Unhandled 
> control packet (type=0x3)
>
> 2011-08-24 12:23:18.117290 [INFO] rtmp_sig.c:274 Got publish on stream 3.
>
> 2011-08-24 12:23:36.697195 [ERR] rtmp.c:678 Read error
>
> 2011-08-24 12:23:36.697195 [NOTICE] mod_rtmp.c:788 RTMP session ended 
> [4a97320c-e50d-4ed3-a59c-4aef799d379d]
>
> 2011-08-24 12:23:36.697195 [NOTICE] mod_rtmp.c:803 Hangup
> rtmp/default/3213533 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]
>
> 2011-08-24 12:23:36.777294 [NOTICE] switch_core_session.c:1347 Session 
> 1
> (rtmp/default/3213533) Ended
>
> 2011-08-24 12:23:36.777294 [NOTICE] switch_core_session.c:1349 Close 
> Channel
> rtmp/default/3213533 [CS_DESTROY]
>
> 2011-08-24 12:23:48.237202 [NOTICE] mod_rtmp.c:743 New RTMP session 
> [3ff4786f-7cae-4653-879a-ae95a9d50742]
>
> 2011-08-24 12:23:48.557204 [NOTICE] rtmp_sig.c:121 Sent connect reply
>
> 2011-08-24 12:23:55.357293 [NOTICE] mod_rtmp.c:743 New RTMP session 
> [af763930-9054-4c76-a0eb-9c351e75949d]
>
> 2011-08-24 12:23:55.757290 [NOTICE] rtmp_sig.c:121 Sent connect reply
>
> 2011-08-24 12:23:58.957195 [INFO] rtmp_sig.c:136 Replied to 
> createStream (1)
>
> 2011-08-24 12:23:59.517204 [INFO] rtmp_sig.c:274 Got publish on stream 1.
>
> 2011-08-24 12:24:00.557201 [INFO] rtmp_sig.c:136 Replied to 
> createStream (2)
>
> 2011-08-24 12:24:00.557201 [WARNING] rtmp.c:99 [amfnumber=2] Unhandled 
> control packet (type=0x3)
>
> 2011-08-24 12:24:00.557201 [INFO] rtmp_sig.c:136 Replied to 
> createStream (3)
>
> 2011-08-24 12:24:00.857222 [NOTICE] switch_channel.c:897 New Channel
> rtmp/default/3213533 [5de55413-5cb9-4c18-8b65-9c438833449f]
>
> 2011-08-24 12:24:00.857222 [ERR] rtmp_sig.c:305 Couldn't create call.
>
> 2011-08-24 12:24:00.857222 [INFO] mod_dialplan_xml.c:336 Processing
> <0000000000>->3213533 in context default
>
> 2011-08-24 12:24:00.857222 [NOTICE] mod_rtmp.c:497 Channel 
> [rtmp/default/3213533] has been answered
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:6644 using channel 
> sound
> prefix: /usr/local/freeswitch/sounds/en/us/callie
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '0' to 'mute'
>
> 2011-08-24 12:24:00.857222 [INFO] switch_ivr_async.c:164 Digit parser
> mod_conference: Setting realm to conf
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '*' to 'deaf mute'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '9' to 'energy up'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '8' to 'energy equ'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '7' to 'energy dn'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '3' to 'vol talk up'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '2' to 'vol talk zero'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '1' to 'vol talk dn'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '6' to 'vol listen up'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '5' to 'vol listen zero'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '4' to 'vol listen dn'
>
> 2011-08-24 12:24:00.857222 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '#' to 'hangup'
>
> 2011-08-24 12:24:01.197247 [WARNING] rtmp.c:99 [amfnumber=2] Unhandled 
> control packet (type=0x3)
>
> 2011-08-24 12:24:01.197247 [INFO] rtmp_sig.c:274 Got publish on stream 3.
>
> 2011-08-24 12:24:01.197247 [INFO] rtmp_sig.c:159 Sending audio
>
> 2011-08-24 12:24:13.637291 [INFO] rtmp_sig.c:136 Replied to 
> createStream (1)
>
> 2011-08-24 12:24:14.157289 [INFO] rtmp_sig.c:274 Got publish on stream 1.
>
> 2011-08-24 12:24:17.057195 [INFO] rtmp_sig.c:136 Replied to 
> createStream (2)
>
> 2011-08-24 12:24:17.057195 [WARNING] rtmp.c:99 [amfnumber=2] Unhandled 
> control packet (type=0x3)
>
> 2011-08-24 12:24:17.057195 [INFO] rtmp_sig.c:136 Replied to 
> createStream (3)
>
> 2011-08-24 12:24:17.057195 [NOTICE] switch_channel.c:897 New Channel
> rtmp/default/3213533 [4e6c35ce-ec56-4ef1-816a-b94704efbfbd]
>
> 2011-08-24 12:24:17.057195 [ERR] rtmp_sig.c:305 Couldn't create call.
>
> 2011-08-24 12:24:17.057195 [INFO] mod_dialplan_xml.c:336 Processing
> <0000000000>->3213533 in context default
>
> 2011-08-24 12:24:17.057195 [NOTICE] mod_rtmp.c:497 Channel 
> [rtmp/default/3213533] has been answered
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '0' to 'mute'
>
> 2011-08-24 12:24:17.057195 [INFO] switch_ivr_async.c:164 Digit parser
> mod_conference: Setting realm to conf
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '*' to 'deaf mute'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '9' to 'energy up'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '8' to 'energy equ'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '7' to 'energy dn'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '3' to 'vol talk up'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '2' to 'vol talk zero'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '1' to 'vol talk dn'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '6' to 'vol listen up'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '5' to 'vol listen zero'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '4' to 'vol listen dn'
>
> 2011-08-24 12:24:17.057195 [INFO] mod_conference.c:7007 
> rtmp/default/3213533 binding '#' to 'hangup'
>
> 2011-08-24 12:24:17.497196 [WARNING] rtmp.c:99 [amfnumber=2] Unhandled 
> control packet (type=0x3)
>
> 2011-08-24 12:24:17.497196 [INFO] rtmp_sig.c:274 Got publish on stream 3.
>
> 2011-08-24 12:24:17.497196 [INFO] rtmp_sig.c:159 Sending audio
>
> 2011-08-24 12:24:27.657196 [ERR] rtmp.c:678 Read error
>
> 2011-08-24 12:24:27.657196 [NOTICE] mod_rtmp.c:788 RTMP session ended 
> [3ff4786f-7cae-4653-879a-ae95a9d50742]
>
> 2011-08-24 12:24:27.657196 [NOTICE] mod_rtmp.c:803 Hangup
> rtmp/default/3213533 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]
>
> 2011-08-24 12:24:27.677201 [NOTICE] switch_core_session.c:1347 Session 
> 2
> (rtmp/default/3213533) Ended
>
> 2011-08-24 12:24:27.677201 [NOTICE] switch_core_session.c:1349 Close 
> Channel
> rtmp/default/3213533 [CS_DESTROY]
>
> 2011-08-24 12:24:28.057200 [NOTICE] rtmp_tcp.c:73 Pollout: true
>
>
>
> Which results to :
>
>
>
>   PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM    TIME+  COMMAND
>
> 1608 root      -2 -10  191m  20m 6588 R 94.3  3.5   5:39.50 fs
>
>
>
> Any ideas? Help is VERY much appreciated. TIA!
>
>
>
> Regards,
>
>
>
> Erwin D. Merioles
>
>
>
> merioles.net
>
> +63 922 837 9466 | +63 917 501 1010 | +1 760 670 3241
>
> aY!M : erwin_merioles | Skype : erwin.merioles
>
> This message (including any attachments) contains information that may 
> be confidential. Unless you are the intended recipient (or is 
> authorized to receive for the intended recipient), you may not read, 
> print, retain, use, copy, distribute, or disclose to anyone, any 
> information contained here. If you have received this in error, please 
> advise the sender by reply e-mail, and delete all copies of the original
message (including attachments).
>
>
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-use
> rs
> http://www.freeswitch.org
>
>



--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
Twitter: http://twitter.com/FreeSWITCH_wire

AIM: anthm
MSN:anthony_minessale at hotmail.com
GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com
IRC: irc.freenode.net #freeswitch

FreeSWITCH Developer Conference
sip:888 at conference.freeswitch.org
googletalk:conf+888 at conference.freeswitch.org
pstn:+19193869900


FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users mailing list