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

Erwin Merioles erwin at merioles.net
Wed Aug 24 16:31:13 MSD 2011


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).

 

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20110824/61b9877f/attachment-0001.html 


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