[Freeswitch-dev] tone_detect

Michael Jerris mike at jerris.com
Mon Sep 8 16:24:51 EDT 2008 

What svn revision is this on?  Does it work with 9467?

Mike

On Sep 8, 2008, at 4:15 PM, Cesar Cepeda wrote:

> Hi Brian,
>
>> Have you looked at these examples:
>> http://wiki.freeswitch.org/wiki/Misc._Dialplan_Tools_tone_detect
>
> Actually I based my dialplan on that specific example.
>
>> Can you provide a console log with debug output would help see what  
>> is
>> going on.
>
> Here is an example, it happens the same no matter if I record or not  
> the
> session.  I know that the audio is interrupted because I'm tapping the
> analog channel of the fax machine and hearing what happens.
>
> The interruption of the audio comes almost as the same time as the  
> line:
> 2008-09-08 14:27:35 [DEBUG] switch_core_io.c:365
> switch_core_session_read_frame() Engaging Read Buffer at 320 bytes  
> vs 1
>
> -------------------------
>
> 2008-09-08 14:27:24 [DEBUG] switch_ivr_originate.c:664
> switch_ivr_originate() variable string 0 = [ignore_early_media=true]
> 2008-09-08 14:27:24 [NOTICE] switch_channel.c:538  
> switch_channel_set_name()
> New Channel sofia/auronix/116 at 192.168.1.16
> [e19ba58a-95ba-fd49-972a-30824b45daf6]
> 2008-09-08 14:27:24 [DEBUG] mod_sofia.c:2020 sofia_outgoing_channel()
> sofia/auronix/116 at 192.168.1.16 State Change CS_NEW -> CS_INIT
> 2008-09-08 14:27:24 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill
> sofia/auronix/116 at 192.168.1.16 [BREAK]
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/auronix/116 at 192.168.1.16 Running State
> Change CS_INIT
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:415
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State INIT
> 2008-09-08 14:27:24 [DEBUG] mod_sofia.c:80 sofia_on_init()
> sofia/auronix/116 at 192.168.1.16 SOFIA INIT
> 2008-09-08 14:27:24 [DEBUG] mod_sofia.c:107 sofia_on_init()
> sofia/auronix/116 at 192.168.1.16 State Change CS_INIT -> CS_ROUTING
> 2008-09-08 14:27:24 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill
> sofia/auronix/116 at 192.168.1.16 [BREAK]
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:415
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> INIT going
> to sleep
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/auronix/116 at 192.168.1.16 Running State
> Change CS_ROUTING
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> ROUTING
> 2008-09-08 14:27:24 [DEBUG] mod_sofia.c:119 sofia_on_routing()
> sofia/auronix/116 at 192.168.1.16 SOFIA ROUTING
> 2008-09-08 14:27:24 [DEBUG] switch_ivr_originate.c:57  
> originate_on_routing()
> sofia/auronix/116 at 192.168.1.16 State Change CS_ROUTING ->  
> CS_CONSUME_MEDIA
> 2008-09-08 14:27:24 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill
> sofia/auronix/116 at 192.168.1.16 [BREAK]
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> ROUTING
> going to sleep
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/auronix/116 at 192.168.1.16 Running State
> Change CS_CONSUME_MEDIA
> 2008-09-08 14:27:24 [DEBUG] switch_core_state_machine.c:442
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State
> CONSUME_MEDIA
> 2008-09-08 14:27:24 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_i_state] status [0][INVITE sent] session:
> sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:27:24 [DEBUG] sofia.c:2159 sofia_handle_sip_i_state()  
> Channel
> sofia/auronix/116 at 192.168.1.16 entering state [calling]
> 2008-09-08 14:27:24 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_r_invite] status [183][Session Progress] session:
> sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:27:24 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_i_state] status [183][Session Progress] session:
> sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:27:24 [DEBUG] sofia.c:2159 sofia_handle_sip_i_state()  
> Channel
> sofia/auronix/116 at 192.168.1.16 entering state [proceeding]
> 2008-09-08 14:27:24 [DEBUG] sofia.c:2163 sofia_handle_sip_i_state()  
> Remote
> SDP:
> v=0
> o=AudiocodesGW 679202607 679202491 IN IP4 192.168.1.16
> s=Phone-Call
> c=IN IP4 192.168.1.16
> t=0 0
> m=audio 6000 RTP/AVP 0 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=ptime:20
>
> 2008-09-08 14:27:24 [DEBUG] sofia_glue.c:2303  
> sofia_glue_negotiate_sdp()
> Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000]
> 2008-09-08 14:27:24 [DEBUG] sofia_glue.c:1548  
> sofia_glue_tech_set_codec()
> Set Codec sofia/auronix/116 at 192.168.1.16 PCMU/8000 20 ms 160 samples
> 2008-09-08 14:27:24 [DEBUG] sofia_glue.c:2266  
> sofia_glue_negotiate_sdp() Set
> 2833 dtmf payload to 101
> 2008-09-08 14:27:24 [DEBUG] sofia_glue.c:1757  
> sofia_glue_activate_rtp()
> AUDIO RTP [sofia/auronix/116 at 192.168.1.16] 192.168.1.1 port 25268 ->
> 192.168.1.16 port 6000 codec: 0 ms: 20
> 2008-09-08 14:27:24 [DEBUG] switch_rtp.c:818 switch_rtp_create()  
> Starting
> timer [soft] 160 bytes per 20000ms
> 2008-09-08 14:27:24 [NOTICE] switch_channel.c:1413
> switch_channel_perform_mark_pre_answered() Ring-Ready
> sofia/auronix/116 at 192.168.1.16!
> 2008-09-08 14:27:24 [NOTICE] sofia_glue.c:2032 sofia_glue_tech_media()
> Pre-Answer sofia/auronix/116 at 192.168.1.16!
> 2008-09-08 14:27:24 [DEBUG] sofia.c:332 sofia_event_callback()  
> nua_r_prack:
> unknown event 39: 200 OK
> 2008-09-08 14:27:27 [DEBUG] sofia.c:2159 sofia_handle_sip_i_state()  
> Channel
> sofia/auronix/116 at 192.168.1.16 entering state [ready]
> 2008-09-08 14:27:27 [DEBUG] switch_ivr_originate.c:1380
> switch_ivr_originate() Originate Resulted in Success:
> [sofia/auronix/116 at 192.168.1.16]
> 2008-09-08 14:27:27 [NOTICE] sofia.c:2474 sofia_handle_sip_i_state()  
> Channel
> [sofia/auronix/116 at 192.168.1.16] has been answered
> 2008-09-08 14:27:27 [DEBUG] switch_ivr.c:1092  
> switch_ivr_session_transfer()
> sofia/auronix/116 at 192.168.1.16 State Change CS_CONSUME_MEDIA ->  
> CS_ROUTING
> 2008-09-08 14:27:27 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill
> sofia/auronix/116 at 192.168.1.16 [BREAK]
> 2008-09-08 14:27:27 [DEBUG] switch_core_session.c:435
> switch_core_session_receive_message() Kill sofia/auronix/116 at 192.168.1.16
> [BREAK]
> 2008-09-08 14:27:27 [NOTICE] switch_ivr.c:1098  
> switch_ivr_session_transfer()
> Transfer sofia/auronix/116 at 192.168.1.16 to XML[detecta_fax at default]
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:442
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State
> CONSUME_MEDIA going to sleep
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/auronix/116 at 192.168.1.16 Running State
> Change CS_ROUTING
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> ROUTING
> 2008-09-08 14:27:27 [DEBUG] mod_sofia.c:119 sofia_on_routing()
> sofia/auronix/116 at 192.168.1.16 SOFIA ROUTING
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:64
> switch_core_standard_on_routing() Standard ROUTING
> sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:27:27 [INFO] mod_dialplan_xml.c:228 dialplan_hunt()  
> Processing
> FreeSWITCH->detecta_fax in context default
> 2008-09-08 14:27:27 [DEBUG] mod_dialplan_xml.c:113 parse_exten() test
> conditions destination_number(detecta_fax) =~ /detecta_fax/
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:97
> switch_core_standard_on_routing() sofia/auronix/116 at 192.168.1.16 State
> Change CS_ROUTING -> CS_EXECUTE
> 2008-09-08 14:27:27 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill
> sofia/auronix/116 at 192.168.1.16 [BREAK]
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> ROUTING
> going to sleep
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/auronix/116 at 192.168.1.16 Running State
> Change CS_EXECUTE
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:430
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> EXECUTE
> 2008-09-08 14:27:27 [DEBUG] mod_sofia.c:156 sofia_on_execute()
> sofia/auronix/116 at 192.168.1.16 SOFIA EXECUTE
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:129
> switch_core_standard_on_execute() Standard EXECUTE
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:140
> switch_core_standard_on_execute() sofia/auronix/116 at 192.168.1.16  
> Execute
> record_session(C:\\borrame\\sesion.wav)
> 2008-09-08 14:27:27 [DEBUG] switch_core_media_bug.c:227
> switch_core_media_bug_add() Attaching BUG to sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:140
> switch_core_standard_on_execute() sofia/auronix/116 at 192.168.1.16  
> Execute
> tone_detect(fax 1100 r +5000 transfer fax_detectado XML default)
> 2008-09-08 14:27:27 [NOTICE] mod_dptools.c:1228
> tone_detect_session_function() Enabling tone detection 'fax' '1100'
> 2008-09-08 14:27:27 [DEBUG] switch_ivr_async.c:1271
> switch_ivr_tone_detect_session() Adding tone spec 1100 index 0
> 2008-09-08 14:27:27 [DEBUG] switch_core_media_bug.c:227
> switch_core_media_bug_add() Attaching BUG to sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:27:27 [DEBUG] switch_core_state_machine.c:140
> switch_core_standard_on_execute() sofia/auronix/116 at 192.168.1.16  
> Execute
> playback(C:\\Auronix\\OpenSource\\Freeswitch\\audios\ 
> \_retencionAgente.WAV)
> 2008-09-08 14:27:27 [DEBUG] switch_ivr_play_say.c:917  
> switch_ivr_play_file()
> Codec Activated L16 at 8000hz 1 channels 20ms
> 2008-09-08 14:27:27 [DEBUG] switch_core_session.c:435
> switch_core_session_receive_message() Kill sofia/auronix/116 at 192.168.1.16
> [BREAK]
> 2008-09-08 14:27:35 [DEBUG] switch_core_io.c:365
> switch_core_session_read_frame() Engaging Read Buffer at 320 bytes  
> vs 1
> 2008-09-08 14:28:12 [DEBUG] sofia.c:2159 sofia_handle_sip_i_state()  
> Channel
> sofia/auronix/116 at 192.168.1.16 entering state [received]
> 2008-09-08 14:28:12 [DEBUG] sofia.c:2163 sofia_handle_sip_i_state()  
> Remote
> SDP:
> v=0
> o=AudiocodesGW 679202607 679202491 IN IP4 192.168.1.16
> s=Phone-Call
> c=IN IP4 192.168.1.16
> t=0 0
> m=audio 6000 RTP/AVP 0 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=ptime:20
>
> 2008-09-08 14:28:12 [DEBUG] sofia_glue.c:2240  
> sofia_glue_negotiate_sdp() Our
> existing sdp is still good [PCMU 192.168.1.16:6000], let's keep it.
> 2008-09-08 14:28:12 [DEBUG] sofia_glue.c:2266  
> sofia_glue_negotiate_sdp() Set
> 2833 dtmf payload to 101
> 2008-09-08 14:28:12 [DEBUG] sofia_glue.c:1751  
> sofia_glue_activate_rtp()
> Audio params are unchanged.
> 2008-09-08 14:28:12 [DEBUG] sofia.c:2433 sofia_handle_sip_i_state()
> Processing Reinvite
> 2008-09-08 14:28:12 [DEBUG] sofia.c:2159 sofia_handle_sip_i_state()  
> Channel
> sofia/auronix/116 at 192.168.1.16 entering state [completed]
> 2008-09-08 14:28:13 [DEBUG] sofia.c:2159 sofia_handle_sip_i_state()  
> Channel
> sofia/auronix/116 at 192.168.1.16 entering state [ready]
> 2008-09-08 14:28:56 [DEBUG] switch_ivr_play_say.c:1207
> switch_ivr_play_file() done playing file
> 2008-09-08 14:28:56 [DEBUG] switch_core_state_machine.c:140
> switch_core_standard_on_execute() sofia/auronix/116 at 192.168.1.16  
> Execute
> hangup(NORMAL_CLEARING)
> 2008-09-08 14:28:56 [NOTICE] mod_dptools.c:593 hangup_function()  
> Hangup
> sofia/auronix/116 at 192.168.1.16 [CS_EXECUTE] [NORMAL_CLEARING]
> 2008-09-08 14:28:56 [DEBUG] switch_channel.c:1368
> switch_channel_perform_hangup() Kill sofia/auronix/116 at 192.168.1.16  
> [KILL]
> 2008-09-08 14:28:56 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill
> sofia/auronix/116 at 192.168.1.16 [BREAK]
> 2008-09-08 14:28:56 [DEBUG] switch_core_state_machine.c:430
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> EXECUTE
> going to sleep
> 2008-09-08 14:28:56 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/auronix/116 at 192.168.1.16 Running State
> Change CS_HANGUP
> 2008-09-08 14:28:56 [DEBUG] switch_core_state_machine.c:393
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> HANGUP
> 2008-09-08 14:28:56 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel
> sofia/auronix/116 at 192.168.1.16 hanging up, cause: NORMAL_CLEARING
> 2008-09-08 14:28:56 [DEBUG] mod_sofia.c:321 sofia_on_hangup()  
> Sending BYE to
> sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:28:56 [DEBUG] switch_core_state_machine.c:46
> switch_core_standard_on_hangup() Standard HANGUP
> sofia/auronix/116 at 192.168.1.16, cause: NORMAL_CLEARING
> 2008-09-08 14:28:56 [DEBUG] switch_core_state_machine.c:393
> switch_core_session_run() (sofia/auronix/116 at 192.168.1.16) State  
> HANGUP
> going to sleep
> 2008-09-08 14:28:56 [DEBUG] switch_core_media_bug.c:284
> switch_core_media_bug_remove_all() Removing BUG from
> sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:28:56 [DEBUG] switch_core_media_bug.c:284
> switch_core_media_bug_remove_all() Removing BUG from
> sofia/auronix/116 at 192.168.1.16
> 2008-09-08 14:28:56 [DEBUG] switch_core_session.c:794
> switch_core_session_thread() Session 9 (sofia/auronix/ 
> 116 at 192.168.1.16)
> Locked, Waiting on external entities
> 2008-09-08 14:28:56 [NOTICE] switch_core_session.c:812
> switch_core_session_thread() Session 9 (sofia/auronix/ 
> 116 at 192.168.1.16)
> Ended
> 2008-09-08 14:28:56 [NOTICE] switch_core_session.c:814
> switch_core_session_thread() Close Channel sofia/auronix/116 at 192.168.1.16
> [CS_HANGUP]
>
>> Please do not hijack threads.  Click new message and input the list
>> address yourself or from your address book.
>
> Sorry about that, I thought that just by setting a new subject would  
> be OK.
> Won't happen again.
>
> Cesar Cepeda.
>
>
> _______________________________________________
> Freeswitch-dev mailing list
> Freeswitch-dev at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
> http://www.freeswitch.org

More information about the Freeswitch-dev mailing list