VoIP Mailing List Archives
Mailing list archives for the VoIP community |
|
View previous topic :: View next topic |
Author |
Message |
Thomas.Sluschny at sie... Guest
|
Posted: Wed Aug 27, 2008 8:46 am Post subject: [Freeswitch-users] 100% cpu load on incoming call, only duri |
|
|
Hi,
i want to use the new FreeSwitch version 1.0 (Rev8701) and yes, i tested also the new one today (Rev9371):
if an incoming call received, cpu load goes to 100%. And if i answer this incoming call, cpu load goes down.
Every other thing works great. So i ask you: has no one ever had this problem?
I use WinXP 3.0GHz Pentium, and also mod_PortAudio.
In dialplan-directory in public.xml i added:
<extension name="99901">
<condition field="destination_number" expression="^99901$">
<action application="ring_ready"/>
<action application="bridge" data="portaudio"/>
</condition>
</extension>
In console everything seems to be ok (with debug):
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [e79a8e3c-98f2-7f4f-a625-13cfa855b7a8]
2008-08-27 15:02:13 [DEBUG] sofia.c:3653 sofia_handle_sip_i_invite() Setting NAT mode based on rfc1918
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [received]
2008-08-27 15:02:13 [DEBUG] sofia.c:2161 sofia_handle_sip_i_state() Remote SDP:
v=0
o=10221 1698418623 1698418623 IN IP4 192.168.102.51
s=SIPa Session
c=IN IP4 192.168.102.51
t=0 0
m=audio 23450 RTP/AVP 9 0 41 111 8 127 126 2 125
a=rtpmap:9 g722/8000/1
a=rtpmap:0 pcmu/8000/1
a=rtpmap:41 pcma16/16000/1
a=rtpmap:111 pcma32/32000/1
a=rtpmap:8 pcma/8000/1
a=rtpmap:127 g726-16/8000/1
a=rtpmap:126 g726-24/8000/1
a=rtpmap:2 g726-32/8000/1
a=rtpmap:125 g726-40/8000/1
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMA:8:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[GSM:3:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:1547 sofia_glue_tech_set_codec() Set Codec sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) PCMU/8000 20 ms 160 samples
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2298 sofia_handle_sip_i_state() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() Standard ROUTING sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [INFO] mod_dialplan_xml.c:228 dialplan_hunt() Processing 10221->99901@public
2008-08-27 15:02:13 [DEBUG] mod_dialplan_xml.c:113 parse_exten() test conditions destination_number(99901) =~ /^99901$/
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:97 switch_core_standard_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_ROUTING -> CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:129 switch_core_standard_on_execute() Standard EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute ring_ready()
2008-08-27 15:02:13 [NOTICE] mod_sofia.c:1071 sofia_receive_message() Ring-Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [NOTICE] mod_dptools.c:373 ring_ready_function() Ring Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [early]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute bridge(portaudio)
2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel PortAudio/10221 [8af6d26a-4dc4-c841-8dec-5271424b23d4]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:693 channel_outgoing_channel() PortAudio/10221 State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:194 channel_on_init() PortAudio/10221 CHANNEL INIT 1 0
Pa_StartStream: waveInStart returned = 0x0.
2008-08-27 15:02:13 [NOTICE] mod_PortAudio.c:235 channel_on_init() Ring-Ready PortAudio/10221!
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
2008-08-27 15:02:18 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
pa answer
------------------------------------------------------------------------------------------- and now cpu load goes down again ---------------------------------------------------------------------------------------------
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:289 channel_on_init() PortAudio/10221 State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_channel.c:1547 switch_channel_perform_mark_answered() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] mod_PortAudio.c:1371 answer_call() Channel [PortAudio/10221] has been answered
API CALL [pa(answer)] output:
Answered 1 channels.
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:296 channel_on_routing() PortAudio/10221 CHANNEL ROUTING
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() PortAudio/10221 State Change CS_ROUTING -> CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] sofia_glue.c:1756 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/10221@192.168.102.51] 192.168.101.60 port 29652 -> 192.168.102.51 port 23450 codec: 0 ms: 20
2008-08-27 15:02:22 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
2008-08-27 15:02:22 [DEBUG] mod_sofia.c:439 sofia_answer_channel() Local SDP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]):
v=0
o=FreeSWITCH 1219812490 1219812491 IN IP4 192.168.101.60
s=FreeSWITCH
c=IN IP4 192.168.101.60
t=0 0
a=sendrecv
m=audio 29652 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] switch_ivr_originate.c:1327 switch_ivr_originate() Channel [sofia/internal/10221@192.168.102.51] has been answered
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:1365 switch_ivr_originate() Originate Resulted in Success: [PortAudio/10221]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_ivr_bridge.c:786 switch_ivr_multi_threaded_bridge() PortAudio/10221 State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:457 channel_on_exchange_media() CHANNEL LOOPBACK
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [completed]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [ready]
2008-08-27 15:02:23 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:23 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
pa hangup
2008-08-27 15:02:30 [NOTICE] mod_PortAudio.c:1332 hangup_call() Hangup PortAudio/10221 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill PortAudio/10221 [KILL]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
API CALL [pa(hangup)] output:
OK
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [PortAudio/10221]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:394 remove_pvt() No more channels, deactivating audio
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [sofia/internal/10221@192.168.102.51]
2008-08-27 15:02:30 [NOTICE] switch_core_state_machine.c:157 switch_core_standard_on_execute() Hangup sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_EXECUTE] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [KILL]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) hanging up, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:321 sofia_on_hangup() Sending BYE to sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]), cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_HANGUP]
WinMME StopStream: waiting for background thread.
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:425 channel_on_hangup() PortAudio/10221 CHANNEL HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP PortAudio/10221, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 10 (PortAudio/10221) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 10 (PortAudio/10221) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel PortAudio/10221 [CS_HANGUP]
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
Thanks in advance,
Thomas |
|
Back to top |
|
|
mike at jerris.com Guest
|
Posted: Wed Aug 27, 2008 10:45 am Post subject: [Freeswitch-users] 100% cpu load on incoming call, only duri |
|
|
please file a bug on http://jira.freeswitch.org for this.
Mike
On Aug 27, 2008, at 9:44 AM, Sluschny, Thomas wrote:
Quote: | Hi,
i want to use the new FreeSwitch version 1.0 (Rev8701) and yes, i tested also the new one today (Rev9371):
if an incoming call received, cpu load goes to 100%. And if i answer this incoming call, cpu load goes down.
Every other thing works great. So i ask you: has no one ever had this problem?
I use WinXP 3.0GHz Pentium, and also mod_PortAudio.
In dialplan-directory in public.xml i added:
<extension name="99901">
<condition field="destination_number" expression="^99901$">
<action application="ring_ready"/>
<action application="bridge" data="portaudio"/>
</condition>
</extension>
In console everything seems to be ok (with debug):
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [e79a8e3c-98f2-7f4f-a625-13cfa855b7a8]
2008-08-27 15:02:13 [DEBUG] sofia.c:3653 sofia_handle_sip_i_invite() Setting NAT mode based on rfc1918
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [received]
2008-08-27 15:02:13 [DEBUG] sofia.c:2161 sofia_handle_sip_i_state() Remote SDP:
v=0
o=10221 1698418623 1698418623 IN IP4 192.168.102.51
s=SIPa Session
c=IN IP4 192.168.102.51
t=0 0
m=audio 23450 RTP/AVP 9 0 41 111 8 127 126 2 125
a=rtpmap:9 g722/8000/1
a=rtpmap:0 pcmu/8000/1
a=rtpmap:41 pcma16/16000/1
a=rtpmap:111 pcma32/32000/1
a=rtpmap:8 pcma/8000/1
a=rtpmap:127 g726-16/8000/1
a=rtpmap:126 g726-24/8000/1
a=rtpmap:2 g726-32/8000/1
a=rtpmap:125 g726-40/8000/1
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMA:8:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[GSM:3:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:1547 sofia_glue_tech_set_codec() Set Codec sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) PCMU/8000 20 ms 160 samples
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2298 sofia_handle_sip_i_state() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() Standard ROUTING sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [INFO] mod_dialplan_xml.c:228 dialplan_hunt() Processing 10221->99901@public
2008-08-27 15:02:13 [DEBUG] mod_dialplan_xml.c:113 parse_exten() test conditions destination_number(99901) =~ /^99901$/
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:97 switch_core_standard_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_ROUTING -> CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:129 switch_core_standard_on_execute() Standard EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute ring_ready()
2008-08-27 15:02:13 [NOTICE] mod_sofia.c:1071 sofia_receive_message() Ring-Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [NOTICE] mod_dptools.c:373 ring_ready_function() Ring Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [early]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute bridge(portaudio)
2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel PortAudio/10221 [8af6d26a-4dc4-c841-8dec-5271424b23d4]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:693 channel_outgoing_channel() PortAudio/10221 State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:194 channel_on_init() PortAudio/10221 CHANNEL INIT 1 0
Pa_StartStream: waveInStart returned = 0x0.
2008-08-27 15:02:13 [NOTICE] mod_PortAudio.c:235 channel_on_init() Ring-Ready PortAudio/10221!
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
2008-08-27 15:02:18 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
pa answer
------------------------------------------------------------------------------------------- and now cpu load goes down again ---------------------------------------------------------------------------------------------
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:289 channel_on_init() PortAudio/10221 State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_channel.c:1547 switch_channel_perform_mark_answered() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] mod_PortAudio.c:1371 answer_call() Channel [PortAudio/10221] has been answered
API CALL [pa(answer)] output:
Answered 1 channels.
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:296 channel_on_routing() PortAudio/10221 CHANNEL ROUTING
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() PortAudio/10221 State Change CS_ROUTING -> CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] sofia_glue.c:1756 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])] 192.168.101.60 port 29652 -> 192.168.102.51 port 23450 codec: 0 ms: 20
2008-08-27 15:02:22 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
2008-08-27 15:02:22 [DEBUG] mod_sofia.c:439 sofia_answer_channel() Local SDP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]):
v=0
o=FreeSWITCH 1219812490 1219812491 IN IP4 192.168.101.60
s=FreeSWITCH
c=IN IP4 192.168.101.60
t=0 0
a=sendrecv
m=audio 29652 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] switch_ivr_originate.c:1327 switch_ivr_originate() Channel [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])] has been answered
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:1365 switch_ivr_originate() Originate Resulted in Success: [PortAudio/10221]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_ivr_bridge.c:786 switch_ivr_multi_threaded_bridge() PortAudio/10221 State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:457 channel_on_exchange_media() CHANNEL LOOPBACK
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [completed]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [ready]
2008-08-27 15:02:23 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:23 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
pa hangup
2008-08-27 15:02:30 [NOTICE] mod_PortAudio.c:1332 hangup_call() Hangup PortAudio/10221 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill PortAudio/10221 [KILL]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
API CALL [pa(hangup)] output:
OK
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [PortAudio/10221]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:394 remove_pvt() No more channels, deactivating audio
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])]
2008-08-27 15:02:30 [NOTICE] switch_core_state_machine.c:157 switch_core_standard_on_execute() Hangup sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_EXECUTE] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [KILL]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) hanging up, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:321 sofia_on_hangup() Sending BYE to sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]), cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_HANGUP]
WinMME StopStream: waiting for background thread.
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:425 channel_on_hangup() PortAudio/10221 CHANNEL HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP PortAudio/10221, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 10 (PortAudio/10221) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 10 (PortAudio/10221) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel PortAudio/10221 [CS_HANGUP]
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
Thanks in advance,
Thomas
_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (Freeswitch-users@lists.freeswitch.org)
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
|
|
|
Back to top |
|
|
anthony.minessale at g... Guest
|
Posted: Wed Aug 27, 2008 11:04 am Post subject: [Freeswitch-users] 100% cpu load on incoming call, only duri |
|
|
nevermind,
I fixed it already in rev 9372.
The issue only occurs when there is no ring file set. In latest trunk the issue is completely eliminated.
On Wed, Aug 27, 2008 at 10:34 AM, Michael Jerris <mike@jerris.com (mike@jerris.com)> wrote:
Quote: | please file a bug on http://jira.freeswitch.org for this.
Mike
On Aug 27, 2008, at 9:44 AM, Sluschny, Thomas wrote:
Quote: |
Hi,
i want to use the new FreeSwitch version 1.0 (Rev8701) and yes, i tested also the new one today (Rev9371):
if an incoming call received, cpu load goes to 100%. And if i answer this incoming call, cpu load goes down.
Every other thing works great. So i ask you: has no one ever had this problem?
I use WinXP 3.0GHz Pentium, and also mod_PortAudio.
In dialplan-directory in public.xml i added:
<extension name="99901">
<condition field="destination_number" expression="^99901$">
<action application="ring_ready"/>
<action application="bridge" data="portaudio"/>
</condition>
</extension>
In console everything seems to be ok (with debug):
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [e79a8e3c-98f2-7f4f-a625-13cfa855b7a8]
2008-08-27 15:02:13 [DEBUG] sofia.c:3653 sofia_handle_sip_i_invite() Setting NAT mode based on rfc1918
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [received]
2008-08-27 15:02:13 [DEBUG] sofia.c:2161 sofia_handle_sip_i_state() Remote SDP:
v=0
o=10221 1698418623 1698418623 IN IP4 192.168.102.51
s=SIPa Session
c=IN IP4 192.168.102.51
t=0 0
m=audio 23450 RTP/AVP 9 0 41 111 8 127 126 2 125
a=rtpmap:9 g722/8000/1
a=rtpmap:0 pcmu/8000/1
a=rtpmap:41 pcma16/16000/1
a=rtpmap:111 pcma32/32000/1
a=rtpmap:8 pcma/8000/1
a=rtpmap:127 g726-16/8000/1
a=rtpmap:126 g726-24/8000/1
a=rtpmap:2 g726-32/8000/1
a=rtpmap:125 g726-40/8000/1
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMA:8:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[GSM:3:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:1547 sofia_glue_tech_set_codec() Set Codec sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) PCMU/8000 20 ms 160 samples
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2298 sofia_handle_sip_i_state() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() Standard ROUTING sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [INFO] mod_dialplan_xml.c:228 dialplan_hunt() Processing 10221->99901@public
2008-08-27 15:02:13 [DEBUG] mod_dialplan_xml.c:113 parse_exten() test conditions destination_number(99901) =~ /^99901$/
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:97 switch_core_standard_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_ROUTING -> CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:129 switch_core_standard_on_execute() Standard EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute ring_ready()
2008-08-27 15:02:13 [NOTICE] mod_sofia.c:1071 sofia_receive_message() Ring-Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [NOTICE] mod_dptools.c:373 ring_ready_function() Ring Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [early]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute bridge(portaudio)
2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel PortAudio/10221 [8af6d26a-4dc4-c841-8dec-5271424b23d4]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:693 channel_outgoing_channel() PortAudio/10221 State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:194 channel_on_init() PortAudio/10221 CHANNEL INIT 1 0
Pa_StartStream: waveInStart returned = 0x0.
2008-08-27 15:02:13 [NOTICE] mod_PortAudio.c:235 channel_on_init() Ring-Ready PortAudio/10221!
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
2008-08-27 15:02:18 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
pa answer
------------------------------------------------------------------------------------------- and now cpu load goes down again ---------------------------------------------------------------------------------------------
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:289 channel_on_init() PortAudio/10221 State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_channel.c:1547 switch_channel_perform_mark_answered() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] mod_PortAudio.c:1371 answer_call() Channel [PortAudio/10221] has been answered
API CALL [pa(answer)] output:
Answered 1 channels.
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:296 channel_on_routing() PortAudio/10221 CHANNEL ROUTING
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() PortAudio/10221 State Change CS_ROUTING -> CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] sofia_glue.c:1756 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])] 192.168.101.60 port 29652 -> 192.168.102.51 port 23450 codec: 0 ms: 20
2008-08-27 15:02:22 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
2008-08-27 15:02:22 [DEBUG] mod_sofia.c:439 sofia_answer_channel() Local SDP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]):
v=0
o=FreeSWITCH 1219812490 1219812491 IN IP4 192.168.101.60
s=FreeSWITCH
c=IN IP4 192.168.101.60
t=0 0
a=sendrecv
m=audio 29652 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] switch_ivr_originate.c:1327 switch_ivr_originate() Channel [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])] has been answered
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:1365 switch_ivr_originate() Originate Resulted in Success: [PortAudio/10221]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_ivr_bridge.c:786 switch_ivr_multi_threaded_bridge() PortAudio/10221 State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:457 channel_on_exchange_media() CHANNEL LOOPBACK
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [completed]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [ready]
2008-08-27 15:02:23 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:23 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
pa hangup
2008-08-27 15:02:30 [NOTICE] mod_PortAudio.c:1332 hangup_call() Hangup PortAudio/10221 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill PortAudio/10221 [KILL]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
API CALL [pa(hangup)] output:
OK
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [PortAudio/10221]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:394 remove_pvt() No more channels, deactivating audio
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])]
2008-08-27 15:02:30 [NOTICE] switch_core_state_machine.c:157 switch_core_standard_on_execute() Hangup sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_EXECUTE] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [KILL]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) hanging up, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:321 sofia_on_hangup() Sending BYE to sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]), cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_HANGUP]
WinMME StopStream: waiting for background thread.
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:425 channel_on_hangup() PortAudio/10221 CHANNEL HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP PortAudio/10221, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 10 (PortAudio/10221) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 10 (PortAudio/10221) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel PortAudio/10221 [CS_HANGUP]
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
Thanks in advance,
Thomas
_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (Freeswitch-users@lists.freeswitch.org)
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
|
_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (Freeswitch-users@lists.freeswitch.org)
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
|
--
Anthony Minessale II
FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
AIM: anthm
MSN:anthony_minessale@hotmail.com ([email]MSN%3Aanthony_minessale@hotmail.com[/email])
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com ([email]PAYPAL%3Aanthony.minessale@gmail.com[/email])
IRC: irc.freenode.net #freeswitch
FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org ([email]sip%3A888@conference.freeswitch.org[/email])
iax:guest@conference.freeswitch.org/888
googletalk:conf+888@conference.freeswitch.org ([email]googletalk%3Aconf%2B888@conference.freeswitch.org[/email])
pstn:213-799-1400 |
|
Back to top |
|
|
Thomas.Sluschny at sie... Guest
|
Posted: Thu Aug 28, 2008 3:48 am Post subject: [Freeswitch-users] 100% cpu load on incoming call, only duri |
|
|
wow, that was fast
everyting works fine now,
thank you very much, this is a really nice project with a really nice community
greetings,
thomas
Von: freeswitch-users-bounces@lists.freeswitch.org [mailto:freeswitch-users-bounces@lists.freeswitch.org] Im Auftrag von Anthony Minessale
Gesendet: Mittwoch, 27. August 2008 17:50
An: freeswitch-users@lists.freeswitch.org
Betreff: Re: [Freeswitch-users] 100% cpu load on incoming call,only during ringing!
nevermind,
I fixed it already in rev 9372.
The issue only occurs when there is no ring file set. In latest trunk the issue is completely eliminated.
On Wed, Aug 27, 2008 at 10:34 AM, Michael Jerris <mike@jerris.com (mike@jerris.com)> wrote:
Quote: | please file a bug on http://jira.freeswitch.org for this.
Mike
On Aug 27, 2008, at 9:44 AM, Sluschny, Thomas wrote:
Quote: |
Hi,
i want to use the new FreeSwitch version 1.0 (Rev8701) and yes, i tested also the new one today (Rev9371):
if an incoming call received, cpu load goes to 100%. And if i answer this incoming call, cpu load goes down.
Every other thing works great. So i ask you: has no one ever had this problem?
I use WinXP 3.0GHz Pentium, and also mod_PortAudio.
In dialplan-directory in public.xml i added:
<extension name="99901">
<condition field="destination_number" expression="^99901$">
<action application="ring_ready"/>
<action application="bridge" data="portaudio"/>
</condition>
</extension>
In console everything seems to be ok (with debug):
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [e79a8e3c-98f2-7f4f-a625-13cfa855b7a8]
2008-08-27 15:02:13 [DEBUG] sofia.c:3653 sofia_handle_sip_i_invite() Setting NAT mode based on rfc1918
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [received]
2008-08-27 15:02:13 [DEBUG] sofia.c:2161 sofia_handle_sip_i_state() Remote SDP:
v=0
o=10221 1698418623 1698418623 IN IP4 192.168.102.51
s=SIPa Session
c=IN IP4 192.168.102.51
t=0 0
m=audio 23450 RTP/AVP 9 0 41 111 8 127 126 2 125
a=rtpmap:9 g722/8000/1
a=rtpmap:0 pcmu/8000/1
a=rtpmap:41 pcma16/16000/1
a=rtpmap:111 pcma32/32000/1
a=rtpmap:8 pcma/8000/1
a=rtpmap:127 g726-16/8000/1
a=rtpmap:126 g726-24/8000/1
a=rtpmap:2 g726-32/8000/1
a=rtpmap:125 g726-40/8000/1
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[PCMA:8:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [g722:9:8000]/[GSM:3:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:2298 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[PCMU:0:8000]
2008-08-27 15:02:13 [DEBUG] sofia_glue.c:1547 sofia_glue_tech_set_codec() Set Codec sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) PCMU/8000 20 ms 160 samples
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State NEW
2008-08-27 15:02:13 [DEBUG] sofia.c:2298 sofia_handle_sip_i_state() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA INIT
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State INIT going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA ROUTING
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() Standard ROUTING sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [INFO] mod_dialplan_xml.c:228 dialplan_hunt() Processing 10221->99901@public
2008-08-27 15:02:13 [DEBUG] mod_dialplan_xml.c:113 parse_exten() test conditions destination_number(99901) =~ /^99901$/
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:97 switch_core_standard_on_routing() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) State Change CS_ROUTING -> CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State ROUTING going to sleep
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE
2008-08-27 15:02:13 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) SOFIA EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:129 switch_core_standard_on_execute() Standard EXECUTE
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute ring_ready()
2008-08-27 15:02:13 [NOTICE] mod_sofia.c:1071 sofia_receive_message() Ring-Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:13 [NOTICE] mod_dptools.c:373 ring_ready_function() Ring Ready sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])!
2008-08-27 15:02:13 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:13 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [early]
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Execute bridge(portaudio)
2008-08-27 15:02:13 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel PortAudio/10221 [8af6d26a-4dc4-c841-8dec-5271424b23d4]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:693 channel_outgoing_channel() PortAudio/10221 State Change CS_NEW -> CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_INIT
2008-08-27 15:02:13 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:194 channel_on_init() PortAudio/10221 CHANNEL INIT 1 0
Pa_StartStream: waveInStart returned = 0x0.
2008-08-27 15:02:13 [NOTICE] mod_PortAudio.c:235 channel_on_init() Ring-Ready PortAudio/10221!
2008-08-27 15:02:13 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
2008-08-27 15:02:18 [DEBUG] mod_PortAudio.c:252 channel_on_init() BRRRRING! BRRRRING! call 5
------------------------------------------------------------------------------------------- here we have 100% cpu load ---------------------------------------------------------------------------------------------
pa answer
------------------------------------------------------------------------------------------- and now cpu load goes down again ---------------------------------------------------------------------------------------------
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:289 channel_on_init() PortAudio/10221 State Change CS_INIT -> CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (PortAudio/10221) State INIT going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_ROUTING
2008-08-27 15:02:22 [DEBUG] switch_channel.c:1547 switch_channel_perform_mark_answered() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] mod_PortAudio.c:1371 answer_call() Channel [PortAudio/10221] has been answered
API CALL [pa(answer)] output:
Answered 1 channels.
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:296 channel_on_routing() PortAudio/10221 CHANNEL ROUTING
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() PortAudio/10221 State Change CS_ROUTING -> CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (PortAudio/10221) State ROUTING going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA
2008-08-27 15:02:22 [DEBUG] sofia_glue.c:1756 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])] 192.168.101.60 port 29652 -> 192.168.102.51 port 23450 codec: 0 ms: 20
2008-08-27 15:02:22 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
2008-08-27 15:02:22 [DEBUG] mod_sofia.c:439 sofia_answer_channel() Local SDP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]):
v=0
o=FreeSWITCH 1219812490 1219812491 IN IP4 192.168.101.60
s=FreeSWITCH
c=IN IP4 192.168.101.60
t=0 0
a=sendrecv
m=audio 29652 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [NOTICE] switch_ivr_originate.c:1327 switch_ivr_originate() Channel [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])] has been answered
2008-08-27 15:02:22 [DEBUG] switch_ivr_originate.c:1365 switch_ivr_originate() Originate Resulted in Success: [PortAudio/10221]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_ivr_bridge.c:786 switch_ivr_multi_threaded_bridge() PortAudio/10221 State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (PortAudio/10221) State CONSUME_MEDIA going to sleep
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA
2008-08-27 15:02:22 [DEBUG] mod_PortAudio.c:457 channel_on_exchange_media() CHANNEL LOOPBACK
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [completed]
2008-08-27 15:02:22 [DEBUG] sofia.c:2157 sofia_handle_sip_i_state() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) entering state [ready]
2008-08-27 15:02:23 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:23 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
pa hangup
2008-08-27 15:02:30 [NOTICE] mod_PortAudio.c:1332 hangup_call() Hangup PortAudio/10221 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill PortAudio/10221 [KILL]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:435 switch_core_session_receive_message() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
API CALL [pa(hangup)] output:
OK
freeswitch@hb2p113c ([email]freeswitch@hb2p113c[/email])> 2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:352 audio_bridge_thread() Kill PortAudio/10221 [BREAK]
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:444 channel_kill_channel() PortAudio/10221 CHANNEL KILL
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [PortAudio/10221]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (PortAudio/10221) State EXCHANGE_MEDIA going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() PortAudio/10221 Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:394 remove_pvt() No more channels, deactivating audio
2008-08-27 15:02:30 [DEBUG] switch_ivr_bridge.c:355 audio_bridge_thread() BRIDGE THREAD DONE [sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])]
2008-08-27 15:02:30 [NOTICE] switch_core_state_machine.c:157 switch_core_standard_on_execute() Hangup sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_EXECUTE] [NORMAL_CLEARING]
2008-08-27 15:02:30 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [KILL]
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [BREAK]
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State EXECUTE going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) Running State Change CS_HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) hanging up, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] mod_sofia.c:321 sofia_on_hangup() Sending BYE to sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]), cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 9 (sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email])) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/internal/10221@192.168.102.51 ([email]sofia/internal/10221@192.168.102.51[/email]) [CS_HANGUP]
WinMME StopStream: waiting for background thread.
2008-08-27 15:02:30 [DEBUG] mod_PortAudio.c:425 channel_on_hangup() PortAudio/10221 CHANNEL HANGUP
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP PortAudio/10221, cause: NORMAL_CLEARING
2008-08-27 15:02:30 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (PortAudio/10221) State HANGUP going to sleep
2008-08-27 15:02:30 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 10 (PortAudio/10221) Locked, Waiting on external entities
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 10 (PortAudio/10221) Ended
2008-08-27 15:02:30 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel PortAudio/10221 [CS_HANGUP]
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
2008-08-27 15:02:31 [DEBUG] sofia.c:200 sofia_event_callback() event [nua_r_options] status [405][Method Not Allowed] session: n/a
Thanks in advance,
Thomas
_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (Freeswitch-users@lists.freeswitch.org)
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
|
_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (Freeswitch-users@lists.freeswitch.org)
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
|
--
Anthony Minessale II
FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
AIM: anthm
MSN:anthony_minessale@hotmail.com ([email]MSN%3Aanthony_minessale@hotmail.com[/email])
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com ([email]PAYPAL%3Aanthony.minessale@gmail.com[/email])
IRC: irc.freenode.net #freeswitch
FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org ([email]sip%3A888@conference.freeswitch.org[/email])
iax:guest@conference.freeswitch.org/888
googletalk:conf+888@conference.freeswitch.org ([email]googletalk%3Aconf%2B888@conference.freeswitch.org[/email])
pstn:213-799-1400 |
|
Back to top |
|
|
|
|
|
You cannot post new topics in this forum You cannot reply to topics in this forum You cannot edit your posts in this forum You cannot delete your posts in this forum You cannot vote in polls in this forum
|
Powered by phpBB © 2001, 2005 phpBB Group
|