Processing DTMF

Asterisk Asterisk SIP
#1

Hi here!
I need help with proccesing signal DTMF by asterisk.
I do not understand why the asterisk does not correctly process DTMF signals. Signals arrive at asterisk correctly.
Example:
10.0.0.10 - asterisk
10.0.0.75 - ip-phone Grandstream
Grandstream press buttons 8733, asterisk processed only ‘83’
<

побережье
побережье1463×1154 224 KB
>

-- Executing [188@from-internal-xfer:10] Read("Local/188@from-internal-xfer-000031ba;2", "PIN,enter-conf-pin-number,,,,") in new stack
    -- <Local/188@from-internal-xfer-000031ba;2> Playing 'enter-conf-pin-number.slin' (language 'ru')
[2020-10-08 16:05:23] DTMF[20016][C-00004d17]: channel.c:4084 __ast_read: DTMF end '8' received on SIP/111-0000b7e6, duration 240 ms
[2020-10-08 16:05:23] DTMF[20016][C-00004d17]: channel.c:4111 __ast_read: DTMF begin emulation of '8' with duration 240 queued on SIP/111-0000b7e6
    --  Feature Found: ATXFi-8 exten: ATXFi-8
[2020-10-08 16:05:24] DTMF[20016][C-00004d17]: channel.c:4248 __ast_read: DTMF end emulation of '8' queued on SIP/111-0000b7e6
    --  Feature Found: ATXFi-8 exten: ATXFi-8
[2020-10-08 16:05:24] DTMF[7461][C-00004d17]: channel.c:4084 __ast_read: DTMF end '7' received on SIP/111-0000b7e6, duration 240 ms
[2020-10-08 16:05:24] DTMF[7461][C-00004d17]: channel.c:4154 __ast_read: DTMF end passthrough '7' on SIP/111-0000b7e6
[2020-10-08 16:05:24] DTMF[20028][C-00004d17]: channel.c:4170 __ast_read: DTMF begin '8' received on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:24] DTMF[20028][C-00004d17]: channel.c:4174 __ast_read: DTMF begin ignored '8' on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:24] DTMF[20028][C-00004d17]: channel.c:4084 __ast_read: DTMF end '8' received on Local/188@from-internal-xfer-000031ba;2, duration 251 ms
[2020-10-08 16:05:24] DTMF[20028][C-00004d17]: channel.c:4154 __ast_read: DTMF end passthrough '8' on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:24] DTMF[20016][C-00004d17]: channel.c:4084 __ast_read: DTMF end '3' received on SIP/111-0000b7e6, duration 240 ms
[2020-10-08 16:05:24] DTMF[20016][C-00004d17]: channel.c:4111 __ast_read: DTMF begin emulation of '3' with duration 240 queued on SIP/111-0000b7e6
    --  Feature Found: ATXFi-3 exten: ATXFi-3
[2020-10-08 16:05:24] DTMF[20016][C-00004d17]: channel.c:4248 __ast_read: DTMF end emulation of '3' queued on SIP/111-0000b7e6
    --  Feature Found: ATXFi-3 exten: ATXFi-3
[2020-10-08 16:05:24] DTMF[7461][C-00004d17]: channel.c:4084 __ast_read: DTMF end '3' received on SIP/111-0000b7e6, duration 240 ms
[2020-10-08 16:05:24] DTMF[7461][C-00004d17]: channel.c:4154 __ast_read: DTMF end passthrough '3' on SIP/111-0000b7e6
[2020-10-08 16:05:24] DTMF[20028][C-00004d17]: channel.c:4170 __ast_read: DTMF begin '3' received on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:24] DTMF[20028][C-00004d17]: channel.c:4174 __ast_read: DTMF begin ignored '3' on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:25] DTMF[20028][C-00004d17]: channel.c:4084 __ast_read: DTMF end '3' received on Local/188@from-internal-xfer-000031ba;2, duration 261 ms
[2020-10-08 16:05:25] DTMF[20028][C-00004d17]: channel.c:4154 __ast_read: DTMF end passthrough '3' on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:25] DTMF[20016][C-00004d17]: channel.c:4084 __ast_read: DTMF end '#' received on SIP/111-0000b7e6, duration 240 ms
[2020-10-08 16:05:25] DTMF[20016][C-00004d17]: channel.c:4111 __ast_read: DTMF begin emulation of '#' with duration 240 queued on SIP/111-0000b7e6
[2020-10-08 16:05:25] DTMF[20028][C-00004d17]: channel.c:4170 __ast_read: DTMF begin '#' received on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:25] DTMF[20028][C-00004d17]: channel.c:4174 __ast_read: DTMF begin ignored '#' on Local/188@from-internal-xfer-000031ba;2
[2020-10-08 16:05:25] DTMF[20016][C-00004d17]: channel.c:4248 __ast_read: DTMF end emulation of '#' queued on SIP/111-0000b7e6
[2020-10-08 16:05:25] DTMF[20028][C-00004d17]: channel.c:4084 __ast_read: DTMF end '#' received on Local/188@from-internal-xfer-000031ba;2, duration 250 ms
[2020-10-08 16:05:25] DTMF[20028][C-00004d17]: channel.c:4154 __ast_read: DTMF end passthrough '#' on Local/188@from-internal-xfer-000031ba;2
    -- User entered '83'

If press the buttons slowly - its working!
Why it happens?

#2

Is there a reason you’re using INFO DTMF? It’s highly recommended to use RFC2833/RFC4733, as INFO DTMF is actually received after DTMF completes.

#3

DTMF INFO i using for illustrative purposes. With RFC2833 same
User press 8733, processing 873

Снимок экрана от 2020-10-13 11-47-44

-- <Local/188@from-internal-xfer-00003291;2> Playing 'enter-conf-pin-number.slin' (language 'ru')
[2020-10-13 11:41:19] DTMF[25970][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '8' received on SIP/111-0000bc0f
[2020-10-13 11:41:19] DTMF[25970][C-00004efe]: channel.c:4181 __ast_read: DTMF begin passthrough '8' on SIP/111-0000bc0f
    --  Feature Found: ATXFi-8 exten: ATXFi-8
[2020-10-13 11:41:19] DTMF[25970][C-00004efe]: channel.c:4084 __ast_read: DTMF end '8' received on SIP/111-0000bc0f, duration 160 ms
[2020-10-13 11:41:19] DTMF[25970][C-00004efe]: channel.c:4125 __ast_read: DTMF end accepted with begin '8' on SIP/111-0000bc0f
[2020-10-13 11:41:19] DTMF[25970][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '8' on SIP/111-0000bc0f
    --  Feature Found: ATXFi-8 exten: ATXFi-8
[2020-10-13 11:41:19] DTMF[26077][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '8' received on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:19] DTMF[26077][C-00004efe]: channel.c:4174 __ast_read: DTMF begin ignored '8' on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:19] DTMF[7461][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '7' received on SIP/111-0000bc0f
[2020-10-13 11:41:19] DTMF[7461][C-00004efe]: channel.c:4174 __ast_read: DTMF begin ignored '7' on SIP/111-0000bc0f
[2020-10-13 11:41:19] DTMF[26077][C-00004efe]: channel.c:4084 __ast_read: DTMF end '8' received on Local/188@from-internal-xfer-00003291;2, duration 160 ms
[2020-10-13 11:41:19] DTMF[26077][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '8' on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4084 __ast_read: DTMF end '7' received on SIP/111-0000bc0f, duration 160 ms
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4111 __ast_read: DTMF begin emulation of '7' with duration 160 queued on SIP/111-0000bc0f
    --  Feature Found: ATXFi-7 exten: ATXFi-7
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4248 __ast_read: DTMF end emulation of '7' queued on SIP/111-0000bc0f
    --  Feature Found: ATXFi-7 exten: ATXFi-7
[2020-10-13 11:41:20] DTMF[26077][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '7' received on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:20] DTMF[26077][C-00004efe]: channel.c:4174 __ast_read: DTMF begin ignored '7' on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:20] DTMF[7461][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '3' received on SIP/111-0000bc0f
[2020-10-13 11:41:20] DTMF[7461][C-00004efe]: channel.c:4174 __ast_read: DTMF begin ignored '3' on SIP/111-0000bc0f
[2020-10-13 11:41:20] DTMF[7461][C-00004efe]: channel.c:4084 __ast_read: DTMF end '3' received on SIP/111-0000bc0f, duration 160 ms
[2020-10-13 11:41:20] DTMF[7461][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '3' on SIP/111-0000bc0f
[2020-10-13 11:41:20] DTMF[26077][C-00004efe]: channel.c:4084 __ast_read: DTMF end '7' received on Local/188@from-internal-xfer-00003291;2, duration 178 ms
[2020-10-13 11:41:20] DTMF[26077][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '7' on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '3' received on SIP/111-0000bc0f
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4181 __ast_read: DTMF begin passthrough '3' on SIP/111-0000bc0f
    --  Feature Found: ATXFi-3 exten: ATXFi-3
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4084 __ast_read: DTMF end '3' received on SIP/111-0000bc0f, duration 160 ms
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4125 __ast_read: DTMF end accepted with begin '3' on SIP/111-0000bc0f
[2020-10-13 11:41:20] DTMF[25970][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '3' on SIP/111-0000bc0f
    --  Feature Found: ATXFi-3 exten: ATXFi-3
[2020-10-13 11:41:20] DTMF[26077][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '3' received on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:20] DTMF[26077][C-00004efe]: channel.c:4174 __ast_read: DTMF begin ignored '3' on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:21] DTMF[26077][C-00004efe]: channel.c:4084 __ast_read: DTMF end '3' received on Local/188@from-internal-xfer-00003291;2, duration 160 ms
[2020-10-13 11:41:21] DTMF[26077][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '3' on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:21] DTMF[25970][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '#' received on SIP/111-0000bc0f
[2020-10-13 11:41:21] DTMF[25970][C-00004efe]: channel.c:4181 __ast_read: DTMF begin passthrough '#' on SIP/111-0000bc0f
[2020-10-13 11:41:21] DTMF[26077][C-00004efe]: channel.c:4170 __ast_read: DTMF begin '#' received on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:21] DTMF[26077][C-00004efe]: channel.c:4174 __ast_read: DTMF begin ignored '#' on Local/188@from-internal-xfer-00003291;2
[2020-10-13 11:41:21] DTMF[25970][C-00004efe]: channel.c:4084 __ast_read: DTMF end '#' received on SIP/111-0000bc0f, duration 160 ms
[2020-10-13 11:41:21] DTMF[25970][C-00004efe]: channel.c:4125 __ast_read: DTMF end accepted with begin '#' on SIP/111-0000bc0f
[2020-10-13 11:41:21] DTMF[25970][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '#' on SIP/111-0000bc0f
[2020-10-13 11:41:21] DTMF[26077][C-00004efe]: channel.c:4084 __ast_read: DTMF end '#' received on Local/188@from-internal-xfer-00003291;2, duration 160 ms
[2020-10-13 11:41:21] DTMF[26077][C-00004efe]: channel.c:4154 __ast_read: DTMF end passthrough '#' on Local/188@from-internal-xfer-00003291;2
    -- User entered '873'
#4

What version of Asterisk are you using?

#5

Asterisk 11.6-cert4
relaxdtmf=yes/no - It does not help

#6

Personally I don’t support old versions of Asterisk, as many times issues in them have been resolved in later versions.

#7

Also, this is an old version of Asterisk 11. The final version was 11.25.3. Also, unless you have paid support from Sangoma, you should not be using cert versions, if a more recent version exists.