[Freeswitch-users] mod_spandsp: Inband DTMF not processed

Mon Jun 23 17:58:19 MSD 2014

Hi,

I've listened to the DTMF events and I found the following behavior.

The DTMF events with 'DTMF-Conversion: native%3Ainband' header are
re-generated without the header, and only these re-generated DTMFs are
received in the application. My problem is that these events are not
re-generated consistently.

I've attached the DTMF events I've received. In this scenario, I pressed
392 and in the application only 2 is received.

On Fri, Jun 20, 2014 at 3:04 AM, Nagalenoj H. <nagalenoj at gmail.com> wrote:

> Hi Christopher,
>
> Thanks for your instant reply.
>
> I've tried without spandsp_start_dtmf now, and still the behavior is
> inconsistent. Here is a sample fs log.
>
> 1feeb0f2-f7f8-11e3-ad58-b36c1aaf53e8 2014-06-19 22:26:23.774526 [DEBUG]
> switch_rtp.c:3793 RTP RECV DTMF 3:1280
> 20d28520-f7f8-11e3-ad68-b36c1aaf53e8 2014-06-19 22:26:24.354513 [DEBUG]
> switch_rtp.c:3793 RTP RECV DTMF 3:400
> 2014-06-19 22:26:24.354513 [CRIT] sb_main.js:428 -----digit: 3
> 1feeb0f2-f7f8-11e3-ad58-b36c1aaf53e8 2014-06-19 22:26:25.394516 [DEBUG]
> switch_rtp.c:3793 RTP RECV DTMF 8:1280
> 1feeb0f2-f7f8-11e3-ad58-b36c1aaf53e8 2014-06-19 22:26:27.094516 [DEBUG]
> switch_rtp.c:3793 RTP RECV DTMF 0:1280
> 20d28520-f7f8-11e3-ad68-b36c1aaf53e8 2014-06-19 22:26:27.734531 [DEBUG]
> switch_rtp.c:3793 RTP RECV DTMF 0:400
> 2014-06-19 22:26:27.734531 [CRIT] sb_main.js:428 -----digit: 0
> 2014-06-19 22:26:32.754515 [CRIT] sb_main.js:390 digits pressed after
> collect input: 30
>
>
>
>
> On Fri, Jun 20, 2014 at 2:24 AM, Christopher Rienzo <cmrienzo at gmail.com>
> wrote:
>
>> RTP RECV DTMF means the DTMF was received as a telephone-event (not
>> inband audio).  You don't need to use the spandsp DTMF detector.
>>
>>
>>
>>  On Thu, Jun 19, 2014 at 4:44 PM, Nagalenoj H. <nagalenoj at gmail.com>
>> wrote:
>>
>>>  Hi,
>>>
>>> I am facing an issue with using Inband DTMFs.
>>>
>>> Inband DTMFs are getting detected in switch_rtp.c but then it is not
>>> processed further by mod_spandsp_dsp.c. This issue is inconsistent, for few
>>> calls it is working and not working for few other calls.
>>>
>>> For a working call, I'm getting the logs as below,
>>>
>>> 2014-06-19 21:24:40.334513 [DEBUG] switch_ivr_play_say.c:1682 done
>>> playing file
>>> file_string:///usr/local/freeswitch/sounds/music/switch/2539_SB_welcome_switch.wav!/usr/local/freeswitch/sounds/music/switch/1sec_silence.wav!/usr/local/freeswitch/sounds/music/switch/SB_landing_IVR.wav
>>> 2014-06-19 21:24:44.114522 [DEBUG] switch_rtp.c:3793 RTP RECV DTMF 3:1280
>>> 2014-06-19 21:24:44.694512 [DEBUG] mod_spandsp_dsp.c:372 DTMF BEGIN
>>> DETECTED: [3]
>>> 2014-06-19 21:24:44.854513 [DEBUG] mod_spandsp_dsp.c:384 DTMF END
>>> DETECTED: [3], duration = 165 ms
>>> 2014-06-19 21:24:45.434531 [DEBUG] switch_rtp.c:3793 RTP RECV DTMF 9:1280
>>> 2014-06-19 21:24:45.994513 [DEBUG] mod_spandsp_dsp.c:372 DTMF BEGIN
>>> DETECTED: [9]
>>> 2014-06-19 21:24:46.154514 [DEBUG] mod_spandsp_dsp.c:384 DTMF END
>>> DETECTED: [9], duration = 165 ms
>>> 2014-06-19 21:24:47.434530 [DEBUG] switch_rtp.c:3793 RTP RECV DTMF 2:1280
>>> 2014-06-19 21:24:48.034523 [DEBUG] mod_spandsp_dsp.c:372 DTMF BEGIN
>>> DETECTED: [2]
>>> 2014-06-19 21:24:48.034523 [CRIT] sb_main.js:390 digits pressed after
>>> collect input: 392
>>>
>>> On the failure calls, I'm getting only the RTP RECV DTMF log and not the
>>> DTMF BEGIN and DTMF END DETECTED log messages and because of this, dialplan
>>> application is not receiving any DTMFs.
>>>
>>> I've attached the pcap file and full fs log for failure call.
>>>
>>> What could be the cause of this inconsistent behavior? Kindly help me to
>>> rectify this.
>>>
>>> --
>>> Regards,
>>> Nagalenoj H.
>>>
>>> _________________________________________________________________________
>>> Professional FreeSWITCH Consulting Services:
>>> consulting at freeswitch.org
>>> http://www.freeswitchsolutions.com
>>>
>>> 
>>> 
>>>
>>> Official FreeSWITCH Sites
>>> http://www.freeswitch.org
>>> http://wiki.freeswitch.org
>>> http://www.cluecon.com
>>>
>>> FreeSWITCH-users mailing list
>>> FreeSWITCH-users at lists.freeswitch.org
>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>>> http://www.freeswitch.org
>>>
>>>
>>
>> _________________________________________________________________________
>> Professional FreeSWITCH Consulting Services:
>> consulting at freeswitch.org
>> http://www.freeswitchsolutions.com
>>
>> 
>> 
>>
>> Official FreeSWITCH Sites
>> http://www.freeswitch.org
>> http://wiki.freeswitch.org
>> http://www.cluecon.com
>>
>> FreeSWITCH-users mailing list
>> FreeSWITCH-users at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> http://www.freeswitch.org
>>
>>
>
>
> --
> Regards,
> Nagalenoj H.
>

-- 
Regards,
Nagalenoj H.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140623/fe86a517/attachment-0001.html 
-------------- next part --------------
Content-Length: 2681
Content-Type: text/event-plain

Event-Name: DTMF
Core-UUID: bd6a7efe-f7e6-11e3-8734-b36c1aaf53e8
FreeSWITCH-Hostname: freeswitch.vectone.com
FreeSWITCH-Switchname: freeswitch.vectone.com
FreeSWITCH-IPv4: 82.113.74.20
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2014-06-23%2014%3A41%3A41
Event-Date-GMT: Mon,%2023%20Jun%202014%2013%3A41%3A41%20GMT
Event-Date-Timestamp: 1403530901054514
Event-Calling-File: switch_ivr_async.c
Event-Calling-Function: generate_on_dtmf
Event-Calling-Line-Number: 2487
Event-Sequence: 4131183
Channel-State: CS_EXECUTE
Channel-Call-State: ACTIVE
Channel-State-Number: 4
Channel-Name: sofia/internal/409%403427.vbiz.mundio.com
Unique-ID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 409%403427.vbiz.mundio.com
Channel-Call-UUID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Answer-State: answered
Channel-Read-Codec-Name: PCMU
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMU
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Username: 409
Caller-Dialplan: XML
Caller-Caller-ID-Name: 409
Caller-Caller-ID-Number: 409
Caller-Callee-ID-Name: Outbound%20Call
Caller-Callee-ID-Number: 0342740902075364800
Caller-Network-Addr: 192.168.13.68
Caller-ANI: 409
Caller-Destination-Number: 02075364800
Caller-Unique-ID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/internal/409%403427.vbiz.mundio.com
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1403530865174516
Caller-Channel-Created-Time: 1403530865174516
Caller-Channel-Answered-Time: 1403530867334529
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 1403530867134528
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
Other-Type: originatee
Other-Leg-Direction: outbound
Other-Leg-Username: 409
Other-Leg-Dialplan: XML
Other-Leg-Caller-ID-Name: Nagalenoj%20Harinagasampath
Other-Leg-Caller-ID-Number: 442075364800
Other-Leg-Callee-ID-Name: Outbound%20Call
Other-Leg-Callee-ID-Number: 0342740902075364800
Other-Leg-Network-Addr: 10.30.3.27
Other-Leg-ANI: 409
Other-Leg-Destination-Number: 0342740902075364800
Other-Leg-Unique-ID: 06055d3a-fadc-11e3-b251-b36c1aaf53e8
Other-Leg-Source: mod_sofia
Other-Leg-Context: default
Other-Leg-Channel-Name: sofia/external/0342740902075364800%4010.30.3.27
Other-Leg-Screen-Bit: true
Other-Leg-Privacy-Hide-Name: false
Other-Leg-Privacy-Hide-Number: false
DTMF-Digit: 3
DTMF-Duration: 1280
DTMF-Conversion: native%3Ainband

Content-Length: 2681
Content-Type: text/event-plain

Event-Name: DTMF
Core-UUID: bd6a7efe-f7e6-11e3-8734-b36c1aaf53e8
FreeSWITCH-Hostname: freeswitch.vectone.com
FreeSWITCH-Switchname: freeswitch.vectone.com
FreeSWITCH-IPv4: 82.113.74.20
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2014-06-23%2014%3A41%3A41
Event-Date-GMT: Mon,%2023%20Jun%202014%2013%3A41%3A41%20GMT
Event-Date-Timestamp: 1403530901454533
Event-Calling-File: switch_ivr_async.c
Event-Calling-Function: generate_on_dtmf
Event-Calling-Line-Number: 2487
Event-Sequence: 4131222
Channel-State: CS_EXECUTE
Channel-Call-State: ACTIVE
Channel-State-Number: 4
Channel-Name: sofia/internal/409%403427.vbiz.mundio.com
Unique-ID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 409%403427.vbiz.mundio.com
Channel-Call-UUID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Answer-State: answered
Channel-Read-Codec-Name: PCMU
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMU
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Username: 409
Caller-Dialplan: XML
Caller-Caller-ID-Name: 409
Caller-Caller-ID-Number: 409
Caller-Callee-ID-Name: Outbound%20Call
Caller-Callee-ID-Number: 0342740902075364800
Caller-Network-Addr: 192.168.13.68
Caller-ANI: 409
Caller-Destination-Number: 02075364800
Caller-Unique-ID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/internal/409%403427.vbiz.mundio.com
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1403530865174516
Caller-Channel-Created-Time: 1403530865174516
Caller-Channel-Answered-Time: 1403530867334529
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 1403530867134528
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
Other-Type: originatee
Other-Leg-Direction: outbound
Other-Leg-Username: 409
Other-Leg-Dialplan: XML
Other-Leg-Caller-ID-Name: Nagalenoj%20Harinagasampath
Other-Leg-Caller-ID-Number: 442075364800
Other-Leg-Callee-ID-Name: Outbound%20Call
Other-Leg-Callee-ID-Number: 0342740902075364800
Other-Leg-Network-Addr: 10.30.3.27
Other-Leg-ANI: 409
Other-Leg-Destination-Number: 0342740902075364800
Other-Leg-Unique-ID: 06055d3a-fadc-11e3-b251-b36c1aaf53e8
Other-Leg-Source: mod_sofia
Other-Leg-Context: default
Other-Leg-Channel-Name: sofia/external/0342740902075364800%4010.30.3.27
Other-Leg-Screen-Bit: true
Other-Leg-Privacy-Hide-Name: false
Other-Leg-Privacy-Hide-Number: false
DTMF-Digit: 9
DTMF-Duration: 1280
DTMF-Conversion: native%3Ainband

Content-Length: 2681
Content-Type: text/event-plain

Event-Name: DTMF
Core-UUID: bd6a7efe-f7e6-11e3-8734-b36c1aaf53e8
FreeSWITCH-Hostname: freeswitch.vectone.com
FreeSWITCH-Switchname: freeswitch.vectone.com
FreeSWITCH-IPv4: 82.113.74.20
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2014-06-23%2014%3A41%3A41
Event-Date-GMT: Mon,%2023%20Jun%202014%2013%3A41%3A41%20GMT
Event-Date-Timestamp: 1403530901814515
Event-Calling-File: switch_ivr_async.c
Event-Calling-Function: generate_on_dtmf
Event-Calling-Line-Number: 2487
Event-Sequence: 4131229
Channel-State: CS_EXECUTE
Channel-Call-State: ACTIVE
Channel-State-Number: 4
Channel-Name: sofia/internal/409%403427.vbiz.mundio.com
Unique-ID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 409%403427.vbiz.mundio.com
Channel-Call-UUID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Answer-State: answered
Channel-Read-Codec-Name: PCMU
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMU
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Username: 409
Caller-Dialplan: XML
Caller-Caller-ID-Name: 409
Caller-Caller-ID-Number: 409
Caller-Callee-ID-Name: Outbound%20Call
Caller-Callee-ID-Number: 0342740902075364800
Caller-Network-Addr: 192.168.13.68
Caller-ANI: 409
Caller-Destination-Number: 02075364800
Caller-Unique-ID: 0601efc4-fadc-11e3-b246-b36c1aaf53e8
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/internal/409%403427.vbiz.mundio.com
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1403530865174516
Caller-Channel-Created-Time: 1403530865174516
Caller-Channel-Answered-Time: 1403530867334529
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 1403530867134528
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
Other-Type: originatee
Other-Leg-Direction: outbound
Other-Leg-Username: 409
Other-Leg-Dialplan: XML
Other-Leg-Caller-ID-Name: Nagalenoj%20Harinagasampath
Other-Leg-Caller-ID-Number: 442075364800
Other-Leg-Callee-ID-Name: Outbound%20Call
Other-Leg-Callee-ID-Number: 0342740902075364800
Other-Leg-Network-Addr: 10.30.3.27
Other-Leg-ANI: 409
Other-Leg-Destination-Number: 0342740902075364800
Other-Leg-Unique-ID: 06055d3a-fadc-11e3-b251-b36c1aaf53e8
Other-Leg-Source: mod_sofia
Other-Leg-Context: default
Other-Leg-Channel-Name: sofia/external/0342740902075364800%4010.30.3.27
Other-Leg-Screen-Bit: true
Other-Leg-Privacy-Hide-Name: false
Other-Leg-Privacy-Hide-Number: false
DTMF-Digit: 2
DTMF-Duration: 1280
DTMF-Conversion: native%3Ainband

Content-Length: 2096
Content-Type: text/event-plain

Event-Name: DTMF
Core-UUID: bd6a7efe-f7e6-11e3-8734-b36c1aaf53e8
FreeSWITCH-Hostname: freeswitch.vectone.com
FreeSWITCH-Switchname: freeswitch.vectone.com
FreeSWITCH-IPv4: 82.113.74.20
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2014-06-23%2014%3A41%3A42
Event-Date-GMT: Mon,%2023%20Jun%202014%2013%3A41%3A42%20GMT
Event-Date-Timestamp: 1403530902394530
Event-Calling-File: switch_channel.c
Event-Calling-Function: switch_channel_dequeue_dtmf
Event-Calling-Line-Number: 528
Event-Sequence: 4131263
Channel-State: CS_EXECUTE
Channel-Call-State: ACTIVE
Channel-State-Number: 4
Channel-Name: sofia/mo-internal/442075364800%4010.30.3.27
Unique-ID: 06f96e5c-fadc-11e3-b255-b36c1aaf53e8
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 442075364800%4010.30.3.27
Channel-Call-UUID: 06f96e5c-fadc-11e3-b255-b36c1aaf53e8
Answer-State: answered
Channel-Read-Codec-Name: PCMA
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMA
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Username: 442075364800
Caller-Dialplan: XML
Caller-Caller-ID-Name: 442075364800
Caller-Caller-ID-Number: 442075364800
Caller-Network-Addr: 10.30.3.27
Caller-ANI: 442075364800
Caller-Destination-Number: 442075364800
Caller-Unique-ID: 06f96e5c-fadc-11e3-b255-b36c1aaf53e8
Caller-Source: mod_sofia
Caller-Transfer-Source: 1403530866%3A06fb6842-fadc-11e3-b25b-b36c1aaf53e8%3Abl_xfer%3A442075364800/mo-internal-default/XML
Caller-Context: mo-internal-default
Caller-RDNIS: 442075364800
Caller-Channel-Name: sofia/mo-internal/442075364800%4010.30.3.27
Caller-Profile-Index: 2
Caller-Profile-Created-Time: 1403530866804528
Caller-Channel-Created-Time: 1403530866794530
Caller-Channel-Answered-Time: 1403530866874534
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 1403530866874534
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
DTMF-Digit: 2
DTMF-Duration: 400