| Summary: | ASTERISK-20721: fake DTMF | ||||
| Reporter: | Marlon Costa (detentosdoeb) | Labels: | |||
| Date Opened: | 2012-11-23 07:19:13.000-0600 | Date Closed: | 2012-11-26 08:29:55.000-0600 | ||
| Priority: | Major | Regression? | |||
| Status: | Closed/Complete | Components: | |||
| Versions: | 1.8.13.1 | Frequency of Occurrence | Frequent | ||
| Related Issues: |
| ||||
| Environment: | CentOS release 4.9 (Final) | Attachments: | ( 0) DTMF_beep.cap ( 1) DTMF_complete_log.log ( 2) DTMF_filtered.log | ||
| Description: | Hi,
Many of our customers claims a beep durin making or received calls. Our tech team enabled high debug in our Asterisk and we can found that this beeps are an DTMF tone. In asterisk logs it's possible see this things many times: {noformat} [2012-11-23 10:46:09] DTMF[7843] channel.c: DTMF begin 'A' received on SIP/ENTRADA-GATEWAY-2-SS3-0000153d [2012-11-23 10:46:09] DTMF[7843] channel.c: DTMF begin passthrough 'A' on SIP/ENTRADA-GATEWAY-2-SS3-0000153d [2012-11-23 10:46:09] DTMF[7843] channel.c: DTMF end 'A' received on SIP/ENTRADA-GATEWAY-2-SS3-0000153d, duration 100 ms [2012-11-23 10:46:09] DTMF[7843] channel.c: DTMF end accepted with begin 'A' on SIP/ENTRADA-GATEWAY-2-SS3-0000153d [2012-11-23 10:46:09] DTMF[7843] channel.c: DTMF end 'A' detected to have actual duration 39 on the wire, emulation will be triggered on SIP/ENTRADA-GATEWAY-2-SS3-0000153d [2012-11-23 10:46:09] DTMF[7843] channel.c: DTMF end 'A' has duration 39 but want minimum 80, emulating on SIP/ENTRADA-GATEWAY-2-SS3-0000153d [2012-11-23 10:46:09] DTMF[7843] channel.c: DTMF end emulation of 'A' queued on SIP/ENTRADA-GATEWAY-2-SS3-0000153d [2012-11-23 10:14:55] DTMF[7733] channel.c: DTMF begin 'A' received on SIP/ibrtelecom-2021-000014fe [2012-11-23 10:14:55] DTMF[7733] channel.c: DTMF begin passthrough 'A' on SIP/ibrtelecom-2021-000014fe [2012-11-23 10:14:56] DTMF[7733] channel.c: DTMF end 'A' received on SIP/ibrtelecom-2021-000014fe, duration 55 ms [2012-11-23 10:14:56] DTMF[7733] channel.c: DTMF end accepted with begin 'A' on SIP/ibrtelecom-2021-000014fe [2012-11-23 10:14:56] DTMF[7733] channel.c: DTMF end 'A' has duration 55 but want minimum 80, emulating on SIP/ibrtelecom-2021-000014fe [2012-11-23 10:14:56] DTMF[7733] channel.c: DTMF end emulation of 'A' queued on SIP/ibrtelecom-2021-000014fe [2012-10-08 11:34:49] DTMF[1660] channel.c: DTMF begin 'B' received on SIP/ENTRADA-SS1-SS3-00000024 [2012-10-08 11:34:49] DTMF[1660] channel.c: DTMF begin passthrough 'B' on SIP/ENTRADA-SS1-SS3-00000024 [2012-10-08 11:34:49] DTMF[1660] channel.c: DTMF end 'B' received on SIP/ENTRADA-SS1-SS3-00000024, duration 160 ms [2012-10-08 11:34:49] DTMF[1660] channel.c: DTMF end accepted with begin 'B' on SIP/ENTRADA-SS1-SS3-00000024 [2012-10-08 11:34:49] DTMF[1660] channel.c: DTMF end passthrough 'B' on SIP/ENTRADA-SS1-SS3-00000024 [2012-09-04 12:19:51] DTMF[13604] channel.c: DTMF begin 'B' received on SIP/ibrtelecom-2027-00000f84 [2012-09-04 12:19:51] DTMF[13604] channel.c: DTMF begin passthrough 'B' on SIP/ibrtelecom-2027-00000f84 [2012-09-04 12:19:51] DTMF[13604] channel.c: DTMF end 'B' received on SIP/ibrtelecom-2027-00000f84, duration 158 ms [2012-09-04 12:19:51] DTMF[13604] channel.c: DTMF end accepted with begin 'B' on SIP/ibrtelecom-2027-00000f84 [2012-09-04 12:19:51] DTMF[13604] channel.c: DTMF end passthrough 'B' on SIP/ibrtelecom-2027-00000f84 [2012-08-30 14:50:55] DTMF[23252] channel.c: DTMF begin 'C' received on SIP/ENTRADA-GATEWAY-2-SS3-000002c0 [2012-08-30 14:50:55] DTMF[23252] channel.c: DTMF begin passthrough 'C' on SIP/ENTRADA-GATEWAY-2-SS3-000002c0 [2012-08-30 14:50:55] DTMF[23250] channel.c: DTMF begin 'C' received on Local/9187@tronco-comunica-f491;2 [2012-08-30 14:50:55] DTMF[23250] channel.c: DTMF begin passthrough 'C' on Local/9187@tronco-comunica-f491;2 [2012-08-30 14:50:55] DTMF[23252] channel.c: DTMF end 'C' received on SIP/ENTRADA-GATEWAY-2-SS3-000002c0, duration 100 ms [2012-08-30 14:50:55] DTMF[23252] channel.c: DTMF end accepted with begin 'C' on SIP/ENTRADA-GATEWAY-2-SS3-000002c0 [2012-08-30 14:50:55] DTMF[23252] channel.c: DTMF end 'C' detected to have actual duration 0 on the wire, emulation will be triggered on SIP/ENTRADA-GATEWAY-2-SS3-000002c0 [2012-08-30 14:50:55] DTMF[23252] channel.c: DTMF end 'C' has duration 0 but want minimum 80, emulating on SIP/ENTRADA-GATEWAY-2-SS3-000002c0 [2012-08-30 14:50:55] DTMF[23252] channel.c: DTMF end emulation of 'C' queued on SIP/ENTRADA-GATEWAY-2-SS3-000002c0 [2012-08-30 14:50:55] DTMF[23250] channel.c: DTMF end 'C' received on Local/9187@tronco-comunica-f491;2, duration 99 ms [2012-08-30 14:50:55] DTMF[23250] channel.c: DTMF end accepted with begin 'C' on Local/9187@tronco-comunica-f491;2 [2012-08-30 14:50:55] DTMF[23250] channel.c: DTMF end passthrough 'C' on Local/9187@tronco-comunica-f491;2 [2012-08-30 12:54:24] DTMF[22835] channel.c: DTMF begin 'D' received on SIP/ibrtelecom-2001-0000020b [2012-08-30 12:54:24] DTMF[22835] channel.c: DTMF begin passthrough 'D' on SIP/ibrtelecom-2001-0000020b [2012-08-30 12:54:24] DTMF[22835] channel.c: DTMF end 'D' received on SIP/ibrtelecom-2001-0000020b, duration 100 ms [2012-08-30 12:54:24] DTMF[22835] channel.c: DTMF end accepted with begin 'D' on SIP/ibrtelecom-2001-0000020b [2012-08-30 12:54:24] DTMF[22835] channel.c: DTMF end 'D' detected to have actual duration 0 on the wire, emulation will be triggered on SIP/ibrtelecom-2001-0000020b [2012-08-30 12:54:24] DTMF[22835] channel.c: DTMF end 'D' has duration 0 but want minimum 80, emulating on SIP/ibrtelecom-2001-0000020b [2012-08-30 12:54:24] DTMF[22835] channel.c: DTMF end emulation of 'D' queued on SIP/ibrtelecom-2001-0000020b {noformat} We done a wireshark capture to find out what is happening and it's possible see an RTP EVENT see file attached. Could you please, help us to solve this issue? Thanks, Marlon | ||||
| Comments: | By: Michael L. Young (elguero) 2012-11-24 16:15:41.054-0600 We require a complete debug log to help triage the issue. This document will provide instructions on how to collect debugging logs from an Asterisk machine for the purpose of helping bug marshals troubleshoot an issue: https://wiki.asterisk.org/wiki/display/AST/Collecting+Debug+Information By: Marlon Costa (detentosdoeb) 2012-11-26 07:52:56.529-0600 Dear, Look the files attached. This is a live traffic server and I done my best to clean up only call with "DTMF begin 'A'" Thanks, Marlon By: Matt Jordan (mjordan) 2012-11-26 08:29:46.255-0600 Asterisk isn't generating the DTMF 'A'. Your log file indicates that the endpoint associated with SIP/ibrtelecom-2408-000018c2 sent Asterisk the DTMF 'A'. The DTMF was passed over the bridge to the SIP channel that SIP/ibrtelecom-2408 was bridged with; apparently, that endpoint interpreted the DTMF 'A' as a beep. {noformat} [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: - RTP 2833 Event: 0000000c (len = 4) [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: Sending dtmf: 65 (A), at 177.75.96.135:12214 [2012-11-26 08:23:43] DTMF[23040] channel.c: DTMF begin 'A' received on SIP/ibrtelecom-2408-000018c2 [2012-11-26 08:23:43] DTMF[23040] channel.c: DTMF begin passthrough 'A' on SIP/ibrtelecom-2408-000018c2 [2012-11-26 08:23:43] DEBUG[23040] channel.c: Got DTMF begin on channel (SIP/ibrtelecom-2408-000018c2) [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: Setting the marker bit due to a source update [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: Setting the marker bit due to a source update [2012-11-26 08:23:43] DEBUG[23040] channel.c: Bridge stops bridging channels SIP/2829-000018c1 and SIP/ibrtelecom-2408-000018c2 [2012-11-26 08:23:43] DEBUG[23040] features.c: Passing DTMF through, since it is not a feature code [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: Setting the marker bit due to a source update [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: Setting the marker bit due to a source update [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: - RTP 2833 Event: 0000000c (len = 4) [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: - RTP 2833 Event: 0000000c (len = 4) [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: - RTP 2833 Event: 0000000c (len = 4) [2012-11-26 08:23:43] DEBUG[23040] res_rtp_asterisk.c: Sending dtmf: 65 (A), at 177.75.96.135:12214 [2012-11-26 08:23:43] DTMF[23040] channel.c: DTMF end 'A' received on SIP/ibrtelecom-2408-000018c2, duration 100 ms [2012-11-26 08:23:43] DTMF[23040] channel.c: DTMF end accepted with begin 'A' on SIP/ibrtelecom-2408-000018c2 [2012-11-26 08:23:43] DTMF[23040] channel.c: DTMF end 'A' detected to have actual duration 0 on the wire, emulation will be triggered on SIP/ibrtelecom-2408-000018c2 [2012-11-26 08:23:43] DTMF[23040] channel.c: DTMF end 'A' has duration 0 but want minimum 80, emulating on SIP/ibrtelecom-2408-000018c2 {noformat} You need to find out why the endpoint associated with that channel is sending the DTMF that you're seeing in the pcap. As this is not a bug with Asterisk, I'm closing out this issue. | ||||