DTMF issues 5% of the time calling from Cisco SPA to 3rd party conferencing providers on SIP trunk

Trying to troubleshoot a DTMF issues where a small percentage of the time when people are calling to conferencing services like GoToMeeting (but not just GoToMeeting) it doesn’t properly recognize the digits they type in.

FBPX 2.11.0.43
Phones: Cisco SPA504G running firmware 7.5.6a
Extension: DTMF on the extension is set to RFC 2833
SIP trunk: set to dtmf=auto on the PEER side

It happens infrequently so unfortunately I don’t have logged examples of when the issue occurs. To test I called a conference # 50 times but couldn’t recreate the issue

Here is what is in the logs:
[2015-10-09 15:25:57] DTMF[25340][C-0001480a]: channel.c:4214 __ast_read: DTMF begin ‘8’ received on SIP/2281-00016531
[2015-10-09 15:25:57] DTMF[25340][C-0001480a]: channel.c:4225 __ast_read: DTMF begin passthrough ‘8’ on SIP/2281-00016531
[2015-10-09 15:25:57] DTMF[25340][C-0001480a]: channel.c:4128 __ast_read: DTMF end ‘8’ received on SIP/2281-00016531, duration 80 ms
[2015-10-09 15:25:57] DTMF[25340][C-0001480a]: channel.c:4169 __ast_read: DTMF end accepted with begin ‘8’ on SIP/2281-00016531
[2015-10-09 15:25:57] DTMF[25340][C-0001480a]: channel.c:4184 __ast_read: DTMF end ‘8’ detected to have actual duration 68 on the wire, emulation will be triggered on SIP/2281-00016531
[2015-10-09 15:25:57] DTMF[25340][C-0001480a]: channel.c:4191 __ast_read: DTMF end ‘8’ has duration 68 but want minimum 80, emulating on SIP/2281-00016531
[2015-10-09 15:25:57] DTMF[25340][C-0001480a]: channel.c:4292 __ast_read: DTMF end emulation of ‘8’ queued on SIP/2281-00016531

If I switch the extension DTMF setting to auto I see the same thing. Not sure if I need to restart the phone, rebuilt template or anything else of the change to happen?

The Cisco 504G is setup with the following settings:
G711u
DTMF Process AVT: Yes
DTMF Tx Method: Auto
DTMF Tx Volume for AVT Packet: 0
DTMF AVT Packet Interval: 0
<DTMF_Relay_MIME_Type group=“SIP/SIP_Parameters”>application/dtmf-relay</DTMF_Relay_MIME_Type>

Any suggestions?

Yes! I also had intermittent problem with this and Cisco SPA514G

Is it possible that 5 percent of the time you are using speaker phone?

Maybe this is superstition but try hitting the mute button before entering dtmf digits. I think its being reflected back from the speaker into the mic and then the system on th other end thinks a key has been entered again

Just a theory, has seemed to work for me

Interesting, not something I had even considered - but that certainly would be a logical explanation. I believe there are settings on the phone side to change whether it plays the DTMF audibly so I may dive into that.

-Adam

Some user feedback suggests speakerphone echo perhaps is causing this issue. Anyone know what DTMF signaling mode works best for the Cisco SPA504G ?

Some Google research suggests: RFC2833 is recommended, this is what is set on the extension level.
Perhaps on the PEER side I should also set RFC2833 ?

Debug logs:

On Speaker (with logs showing what looks like an attempt to correct problems) ? :
4925998#
9299825#
4259982#

On Handset (clean transmission):
492599825#

Debug:
4
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF begin ‘4’ received on SIP/204-000186a8
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘4’ on SIP/204-000186a8
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF end ‘4’ received on SIP/204-000186a8, duration 80 ms
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘4’ on SIP/204-000186a8
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF end ‘4’ detected to have actual duration 68 on the wire, emulation will be triggered on SIP/204-000186a8
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF end ‘4’ has duration 68 but want minimum 80, emulating on SIP/204-000186a8
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF end emulation of ‘4’ queued on SIP/204-000186a8
9
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:31:41] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 118 ms
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186a8
2
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF begin ‘2’ received on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘2’ on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end ‘2’ received on SIP/204-000186a8, duration 110 ms
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘2’ on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘2’ on SIP/204-000186a8
5
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF begin ‘5’ received on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘5’ on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ received on SIP/204-000186a8, duration 30 ms
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘5’ on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ detected to have actual duration 18 on the wire, emulation will be triggered on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ has duration 18 but want minimum 80, emulating on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF end emulation of ‘5’ queued on SIP/204-000186a8
9
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:31:42] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 140 ms
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186a8
9
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 90 ms
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186a8
8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF begin ‘8’ received on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘8’ on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end ‘8’ received on SIP/204-000186a8, duration 30 ms
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘8’ on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end ‘8’ detected to have actual duration 19 on the wire, emulation will be triggered on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end ‘8’ has duration 19 but want minimum 80, emulating on SIP/204-000186a8
[2015-10-28 11:31:43] DTMF[27533][C-00016776] channel.c: DTMF end emulation of ‘8’ queued on SIP/204-000186a8

[2015-10-28 11:31:44] DTMF[27533][C-00016776] channel.c: DTMF begin ‘#’ received on SIP/204-000186a8
[2015-10-28 11:31:44] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘#’ on SIP/204-000186a8
[2015-10-28 11:31:44] DTMF[27533][C-00016776] channel.c: DTMF end ‘#’ received on SIP/204-000186a8, duration 90 ms
[2015-10-28 11:31:44] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘#’ on SIP/204-000186a8
[2015-10-28 11:31:44] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘#’ on SIP/204-000186a8
9
[2015-10-28 11:31:51] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:31:51] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:51] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 90 ms
[2015-10-28 11:31:51] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:51] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186a8
2
[2015-10-28 11:31:52] DTMF[27533][C-00016776] channel.c: DTMF begin ‘2’ received on SIP/204-000186a8
[2015-10-28 11:31:52] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘2’ on SIP/204-000186a8
[2015-10-28 11:31:52] DTMF[27533][C-00016776] channel.c: DTMF end ‘2’ received on SIP/204-000186a8, duration 140 ms
[2015-10-28 11:31:52] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘2’ on SIP/204-000186a8
[2015-10-28 11:31:52] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘2’ on SIP/204-000186a8
9
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 120 ms
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186a8
9
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 140 ms
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:31:53] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186a8
8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF begin ‘8’ received on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘8’ on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end ‘8’ received on SIP/204-000186a8, duration 140 ms
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘8’ on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘8’ on SIP/204-000186a8
2
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF begin ‘2’ received on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘2’ on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end ‘2’ received on SIP/204-000186a8, duration 120 ms
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘2’ on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘2’ on SIP/204-000186a8
5
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF begin ‘5’ received on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘5’ on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ received on SIP/204-000186a8, duration 30 ms
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘5’ on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ detected to have actual duration 18 on the wire, emulation will be triggered on SIP/204-000186a8
[2015-10-28 11:31:54] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ has duration 18 but want minimum 80, emulating on SIP/204-000186a8
[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF end emulation of ‘5’ queued on SIP/204-000186a8

[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF begin ‘#’ received on SIP/204-000186a8
[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘#’ on SIP/204-000186a8
[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF end ‘#’ received on SIP/204-000186a8, duration 90 ms
[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘#’ on SIP/204-000186a8
[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF end ‘#’ detected to have actual duration 77 on the wire, emulation will be triggered on SIP/204-000186a8
[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF end ‘#’ has duration 77 but want minimum 80, emulating on SIP/204-000186a8
[2015-10-28 11:31:55] DTMF[27533][C-00016776] channel.c: DTMF end emulation of ‘#’ queued on SIP/204-000186a8
4
[2015-10-28 11:32:06] DTMF[27533][C-00016776] channel.c: DTMF begin ‘4’ received on SIP/204-000186a8
[2015-10-28 11:32:06] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘4’ on SIP/204-000186a8
[2015-10-28 11:32:06] DTMF[27533][C-00016776] channel.c: DTMF end ‘4’ received on SIP/204-000186a8, duration 110 ms
[2015-10-28 11:32:06] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘4’ on SIP/204-000186a8
[2015-10-28 11:32:06] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘4’ on SIP/204-000186a8
2
[2015-10-28 11:32:07] DTMF[27533][C-00016776] channel.c: DTMF begin ‘2’ received on SIP/204-000186a8
[2015-10-28 11:32:07] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘2’ on SIP/204-000186a8
[2015-10-28 11:32:07] DTMF[27533][C-00016776] channel.c: DTMF end ‘2’ received on SIP/204-000186a8, duration 120 ms
[2015-10-28 11:32:07] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘2’ on SIP/204-000186a8
[2015-10-28 11:32:07] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘2’ on SIP/204-000186a8
5
[2015-10-28 11:32:08] DTMF[27533][C-00016776] channel.c: DTMF begin ‘5’ received on SIP/204-000186a8
[2015-10-28 11:32:08] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘5’ on SIP/204-000186a8
[2015-10-28 11:32:08] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ received on SIP/204-000186a8, duration 90 ms
[2015-10-28 11:32:08] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘5’ on SIP/204-000186a8
[2015-10-28 11:32:08] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ detected to have actual duration 79 on the wire, emulation will be triggered on SIP/204-000186a8
[2015-10-28 11:32:08] DTMF[27533][C-00016776] channel.c: DTMF end ‘5’ has duration 79 but want minimum 80, emulating on SIP/204-000186a8
[2015-10-28 11:32:08] DTMF[27533][C-00016776] channel.c: DTMF end emulation of ‘5’ queued on SIP/204-000186a8
9
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 30 ms
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ detected to have actual duration 19 on the wire, emulation will be triggered on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ has duration 19 but want minimum 80, emulating on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end emulation of ‘9’ queued on SIP/204-000186a8
9
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF begin ‘9’ received on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end ‘9’ received on SIP/204-000186a8, duration 90 ms
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186a8
[2015-10-28 11:32:09] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186a8
8
[2015-10-28 11:32:10] DTMF[27533][C-00016776] channel.c: DTMF begin ‘8’ received on SIP/204-000186a8
[2015-10-28 11:32:10] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘8’ on SIP/204-000186a8
[2015-10-28 11:32:10] DTMF[27533][C-00016776] channel.c: DTMF end ‘8’ received on SIP/204-000186a8, duration 100 ms
[2015-10-28 11:32:10] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘8’ on SIP/204-000186a8
[2015-10-28 11:32:10] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘8’ on SIP/204-000186a8
2
[2015-10-28 11:32:11] DTMF[27533][C-00016776] channel.c: DTMF begin ‘2’ received on SIP/204-000186a8
[2015-10-28 11:32:11] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘2’ on SIP/204-000186a8
[2015-10-28 11:32:11] DTMF[27533][C-00016776] channel.c: DTMF end ‘2’ received on SIP/204-000186a8, duration 140 ms
[2015-10-28 11:32:11] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘2’ on SIP/204-000186a8
[2015-10-28 11:32:11] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘2’ on SIP/204-000186a8

[2015-10-28 11:32:12] DTMF[27533][C-00016776] channel.c: DTMF begin ‘#’ received on SIP/204-000186a8
[2015-10-28 11:32:12] DTMF[27533][C-00016776] channel.c: DTMF begin passthrough ‘#’ on SIP/204-000186a8
[2015-10-28 11:32:12] DTMF[27533][C-00016776] channel.c: DTMF end ‘#’ received on SIP/204-000186a8, duration 180 ms
[2015-10-28 11:32:12] DTMF[27533][C-00016776] channel.c: DTMF end accepted with begin ‘#’ on SIP/204-000186a8
[2015-10-28 11:32:12] DTMF[27533][C-00016776] channel.c: DTMF end passthrough ‘#’ on SIP/204-000186a8
[2015-10-28 11:32:16] VERBOSE[27533][C-00016776] pbx.c: – Executing [h@macro-dialout-trunk:1] Macro(“SIP/204-000186a8”, “hangupcall,”) in new stack

Handset

4
[2015-10-28 11:32:30] DTMF[27534][C-00016777] channel.c: DTMF begin ‘4’ received on SIP/204-000186aa
[2015-10-28 11:32:30] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘4’ on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end ‘4’ received on SIP/204-000186aa, duration 98 ms
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘4’ on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘4’ on SIP/204-000186aa
9
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF begin ‘9’ received on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end ‘9’ received on SIP/204-000186aa, duration 98 ms
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186aa
2
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF begin ‘2’ received on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘2’ on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end ‘2’ received on SIP/204-000186aa, duration 108 ms
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘2’ on SIP/204-000186aa
[2015-10-28 11:32:31] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘2’ on SIP/204-000186aa
5
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF begin ‘5’ received on SIP/204-000186aa
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘5’ on SIP/204-000186aa
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF end ‘5’ received on SIP/204-000186aa, duration 108 ms
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘5’ on SIP/204-000186aa
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘5’ on SIP/204-000186aa
9
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF begin ‘9’ received on SIP/204-000186aa
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186aa
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF end ‘9’ received on SIP/204-000186aa, duration 118 ms
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186aa
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186aa
9
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF begin ‘9’ received on SIP/204-000186aa
[2015-10-28 11:32:32] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘9’ on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end ‘9’ received on SIP/204-000186aa, duration 108 ms
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘9’ on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘9’ on SIP/204-000186aa
8
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF begin ‘8’ received on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘8’ on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end ‘8’ received on SIP/204-000186aa, duration 98 ms
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘8’ on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘8’ on SIP/204-000186aa
2
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF begin ‘2’ received on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘2’ on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end ‘2’ received on SIP/204-000186aa, duration 98 ms
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘2’ on SIP/204-000186aa
[2015-10-28 11:32:33] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘2’ on SIP/204-000186aa
5
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF begin ‘5’ received on SIP/204-000186aa
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘5’ on SIP/204-000186aa
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF end ‘5’ received on SIP/204-000186aa, duration 108 ms
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘5’ on SIP/204-000186aa
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘5’ on SIP/204-000186aa

[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF begin ‘#’ received on SIP/204-000186aa
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF begin passthrough ‘#’ on SIP/204-000186aa
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF end ‘#’ received on SIP/204-000186aa, duration 118 ms
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF end accepted with begin ‘#’ on SIP/204-000186aa
[2015-10-28 11:32:34] DTMF[27534][C-00016777] channel.c: DTMF end passthrough ‘#’ on SIP/204-000186aa