[Partially SOLVED]Random DTMF during calls

Hi all,

I’ve been running asterks 1.4.x for the past year or so at home, and have been tolerating the random DTMF tones up until now. The Boss at home finally called me on it. I’ve search/tried everything I could find about the talk-off issue, but nothing helps. If anybody has any idea on what else I can try, I would very much appreciate it.

I have a TDM (400P I think) card w/ 1 FXO & 1 FXS
2 SIP providers (callcentric & voicepulse)
2 analog phones - 1 connected to TDM card (dahdi) and 1 connected to an ATCOMM 188 ATA (have also tried SPA3102 in place of this ATCOMM).
1 cisco IP phone
The PSTN line on the TDM card is not used.

This happens are ALL SIP lines. dahdi line to SIP, SIP to SIP, SIP to dahdi, etc.

All SIP connections are using RFC2833. Relaxdtmf=no. rfc2833compensate - tried yes & no. Only using ulaw/alaw codecs. I’ve also tried multiple computer hardware.

Here are sample logs when the events occur. For these logs, SIP/1777-XXXX is callcentric, SIP/10 is the ATCOMM ata. I don’t understand really what is happening. I read this as asterisk is detecting DTMF on SIP/1777X and SIP/10. Are these DTMF events actually being sent via SIP (RFC2833) or are they being detected in the RTP stream? I’m only using rfc2833 for all SIP channels.

[Jan 5 18:57:44] DTMF[28614] channel.c: DTMF end ‘8’ received on SIP/1777XXXX-02927c30, duration 0 ms
[Jan 5 18:57:44] DTMF[28614] channel.c: DTMF begin emulation of ‘8’ with duration 100 queued on SIP/1777XXXXX-02927c30
[Jan 5 18:57:44] DTMF[28614] channel.c: DTMF end emulation of ‘8’ queued on SIP/1777XXXXX-02927c30
[Jan 5 18:58:42] DTMF[28614] channel.c: DTMF end ‘D’ received on SIP/10-02991cc0, duration 0 ms
[Jan 5 18:58:42] DTMF[28614] channel.c: DTMF begin emulation of ‘D’ with duration 100 queued on SIP/10-02991cc0
[Jan 5 18:58:42] DTMF[28614] channel.c: DTMF end emulation of ‘D’ queued on SIP/10-02991cc0
[Jan 5 18:58:54] DTMF[28614] channel.c: DTMF end ‘#’ received on SIP/10-02991cc0, duration 0 ms
[Jan 5 18:58:54] DTMF[28614] channel.c: DTMF begin emulation of ‘#’ with duration 100 queued on SIP/10-02991cc0
[Jan 5 18:58:54] DTMF[28614] channel.c: DTMF end emulation of ‘#’ queued on SIP/10-02991cc0
[Jan 5 18:58:58] DTMF[28614] channel.c: DTMF end ‘D’ received on SIP/1777XXXXX-02927c30, duration 0 ms
[Jan 5 18:58:58] DTMF[28614] channel.c: DTMF begin emulation of ‘D’ with duration 100 queued on SIP/1777XXXX-02927c30
[Jan 5 18:58:58] DTMF[28614] channel.c: DTMF end emulation of ‘D’ queued on SIP/1777XXXXX-02927c30

If this helps, here is the output of “sip show settings”:

Global Settings:

SIP Port: 5060
Bindaddress: 0.0.0.0
Videosupport: No
AutoCreatePeer: No
Allow unknown access: Yes
Allow subscriptions: Yes
Allow overlap dialing: Yes
Promsic. redir: No
SIP domain support: No
Call to non-local dom.: Yes
URI user is phone no: No
Our auth realm asterisk
Realm. auth: No
Always auth rejects: No
Call limit peers only: Yes
Direct RTP setup: No
User Agent: Asterisk PBX
MWI checking interval: 10 secs
Reg. context: (not set)
Caller ID: Unknown
From: Domain:
Record SIP history: Off
Call Events: Off
IP ToS SIP: CS3
IP ToS RTP audio: EF
IP ToS RTP video: AF41
T38 fax pt UDPTL: No
RFC2833 Compensation: Yes
SIP realtime: Disabled

Global Signalling Settings:

Codecs: 0xc (ulaw|alaw)
Codec Order: ulaw:20,alaw:20
T1 minimum: 100
Relax DTMF: No
Compact SIP headers: No
RTP Keepalive: 0 (Disabled)
RTP Timeout: 0 (Disabled)
RTP Hold Timeout: 0 (Disabled)
MWI NOTIFY mime type: application/simple-message-summary
DNS SRV lookup: Yes
Pedantic SIP support: No
Reg. min duration 60 secs
Reg. max duration: 3600 secs
Reg. default duration: 240 secs
Outbound reg. timeout: 20 secs
Outbound reg. attempts: 0
Notify ringing state: Yes
Notify hold state: Yes
SIP Transfer mode: open
Max Call Bitrate: 384 kbps
Auto-Framing: No

Default Settings:

Context: from-sip-external-custom
Nat: RFC3581
DTMF: rfc2833
Qualify: 0
Use ClientCode: No
Progress inband: Never
Language: (Defaults to English)
MOH Interpret: default
MOH Suggest:
Voice Mail Extension: *97

Thanks for any ideas,

Matt

check if you have AMI script running and playing DTMF on active channels.
voip-info.org/wiki/view/Aste … n+PlayDTMF
you can do AMI settings in manager.conf


Godson Gera
Asterisk India Hyderabad

It looks to me as though the problem is in the phones, rather than in Asterisk. As you are using RFC 2833, Asterisk will only detect an inbound digit if the phone explicitly indicates that there is an inbound digit.

If you used inband DTMF, background noise could be misinterpreted as digits.

That’s for the suggestions. But, there are no AMI scripts running which execute playDTMF.

This is something that I can reproduce always, which may help.

A Dahdi channel calls a SIP provider (callcentric in this case). On the Dahdi device, I press the #1 key. On the other end, they hear a constant tone for about 30 seconds. This is what the logs shows:

[Jan 13 14:57:42] DTMF[23851] channel.c: DTMF end ‘1’ received on DAHDI/1-1, duration 0 ms
[Jan 13 14:57:42] DTMF[23851] channel.c: DTMF begin emulation of ‘1’ with duration 100 queued on DAHDI/1-1
[Jan 13 14:57:42] DTMF[23851] channel.c: DTMF end emulation of ‘1’ queued on DAHDI/1-

which seems right… This is what rtp debug shows. Can anyone explain why the RTP DTMF packets gets sent 11 times? I think this would be OK if all the TS’s were the same, but they are not.


[Jan 13 14:59:22] DTMF[23851] channel.c: DTMF end ‘1’ received on DAHDI/1-1, duration 0 ms
[Jan 13 14:59:22] DTMF[23851] channel.c: DTMF begin emulation of ‘1’ with duration 100 queued on DAHDI/1-1
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048376, ts 890733, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048377, ts 890893, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048378, ts 891053, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048379, ts 891213, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048380, ts 891373, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048381, ts 891533, len 000020)
[Jan 13 14:59:22] DTMF[23851] channel.c: DTMF end emulation of ‘1’ queued on DAHDI/1-1
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050670, ts 826992, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050671, ts 826992, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050672, ts 826992, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048382, ts 891693, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP packet to 204.11.192.37:57908 (type 18, seq 050673, ts 828200, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050674, ts 828200, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048383, ts 891853, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP packet to 204.11.192.37:57908 (type 18, seq 050675, ts 828360, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050676, ts 828360, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048384, ts 892013, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP packet to 204.11.192.37:57908 (type 18, seq 050677, ts 828520, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050678, ts 828520, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048385, ts 892173, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP packet to 204.11.192.37:57908 (type 18, seq 050679, ts 828680, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050680, ts 828680, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048386, ts 892333, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP packet to 204.11.192.37:57908 (type 18, seq 050681, ts 828840, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050682, ts 828840, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Got RTP packet from 204.11.192.37:57908 (type 18, seq 048387, ts 892493, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP packet to 204.11.192.37:57908 (type 18, seq 050683, ts 829000, len 000020)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050684, ts 829000, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050684, ts 829000, len 000004)
[Jan 13 14:59:22] VERBOSE[23851] logger.c: Sent RTP DTMF packet to 204.11.192.37:57908 (type 101, seq 050684, ts 829000, len 000004)

Here is an example when the using the local SIP device (192.168.0.61): Notice the local sip device sends the RTP DTMF multiple times, but all with the same TS. Asterisk replays it on the other channels, but w/multiple TS’s.

[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008374, ts 111806120, len 000004, mark 1, event 00000001, end 0, duration 00000)
[Jan 13 15:10:07] DEBUG[23893] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] DTMF[23893] channel.c: DTMF end ‘1’ received on SIP/15-00b33e50, duration 0 ms
[Jan 13 15:10:07] DTMF[23893] channel.c: DTMF begin emulation of ‘1’ with duration 100 queued on SIP/15-00b33e50
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015938, ts 084415, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 192.168.0.61:31328 (type 00, seq 017767, ts 084408, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008375, ts 111806160, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008376, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008376, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 00160)
[Jan 13 15:10:07] DEBUG[23893] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015939, ts 084575, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 192.168.0.61:31328 (type 00, seq 017768, ts 084568, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008377, ts 111806320, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008378, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008378, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 00320)
[Jan 13 15:10:07] DEBUG[23893] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015940, ts 084735, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 192.168.0.61:31328 (type 00, seq 017769, ts 084728, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008379, ts 111806480, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008380, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008380, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 00480)
[Jan 13 15:10:07] DEBUG[23893] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015941, ts 084895, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 192.168.0.61:31328 (type 00, seq 017770, ts 084888, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008381, ts 111806640, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008382, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008382, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 00640)
[Jan 13 15:10:07] DEBUG[23893] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015942, ts 085055, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 192.168.0.61:31328 (type 00, seq 017771, ts 085048, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008383, ts 111806800, len 000160)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008384, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008384, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 00800)
[Jan 13 15:10:07] DEBUG[23893] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] DTMF[23893] channel.c: DTMF end emulation of ‘1’ queued on SIP/15-00b33e50
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007632, ts 111805920, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007633, ts 111805920, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007634, ts 111805920, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015943, ts 085215, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 204.11.192.37:59410 (type 18, seq 007635, ts 111806928, len 000020)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008385, ts 111806960, len 000160)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008386, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008386, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 00960)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007636, ts 111806928, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015944, ts 085375, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 204.11.192.37:59410 (type 18, seq 007637, ts 111807088, len 000020)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008387, ts 111807120, len 000160)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008388, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008388, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 01120)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007638, ts 111807088, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015945, ts 085535, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 204.11.192.37:59410 (type 18, seq 007639, ts 111807248, len 000020)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008389, ts 111807280, len 000160)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008390, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008390, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 01280)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007640, ts 111807248, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015946, ts 085695, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 204.11.192.37:59410 (type 18, seq 007641, ts 111807408, len 000020)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008391, ts 111807440, len 000160)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008392, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008392, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 01440)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007642, ts 111807408, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015947, ts 085855, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 204.11.192.37:59410 (type 18, seq 007643, ts 111807568, len 000020)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008393, ts 111807600, len 000160)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008394, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008394, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 01600)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007644, ts 111807568, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015948, ts 086015, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 204.11.192.37:59410 (type 18, seq 007645, ts 111807728, len 000020)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 00, seq 008395, ts 111807760, len 000160)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008396, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008396, ts 111806120, len 000004, mark 0, event 00000001, end 0, duration 01760)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008397, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008397, ts 111806120, len 000004, mark 0, event 00000001, end 1, duration 01800)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008398, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008398, ts 111806120, len 000004, mark 0, event 00000001, end 1, duration 01800)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP packet from 192.168.0.61:31328 (type 101, seq 008399, ts 111806120, len 000004)
[Jan 13 15:10:07] VERBOSE[23729] logger.c: Got RTP RFC2833 from 192.168.0.61:31328 (type 101, seq 008399, ts 111806120, len 000004, mark 0, event 00000001, end 1, duration 01800)
[Jan 13 15:10:07] DEBUG[23729] rtp.c: - RTP 2833 Event: 00000001 (len = 4)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007646, ts 111807728, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007646, ts 111807728, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP DTMF packet to 204.11.192.37:59410 (type 101, seq 007646, ts 111807728, len 000004)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Got RTP packet from 204.11.192.37:59410 (type 18, seq 015949, ts 086175, len 000020)
[Jan 13 15:10:07] VERBOSE[23893] logger.c: Sent RTP packet to 204.11.192.37:59410 (type 18, seq 007647, ts 111807888, len 000020)

If anybody is googling/having this issue, you should really check out bug 0013209. It seems like DTMF via RFC2833 in SIP has been broken since asterisk 1.4.18.1. There is a patch in that bug report. I’m not sure if this actually fixes the randomness of the DTMF tones, but at least they don’t flip out my SIP provider anymore.

Based on that bugtrack, it appears that particular RFC2833 issue was fixed and committed on 12/09/08.

I just upgraded to 1.4.22.1 and the change is NOT in the rtp.c file… what gives.

Am I missing something:

bugs.digium.com/view.php?id=13209

EDIT - Looks like the first version this shows up in is 1.4.23rc1… probably didn’t make it into 1.4.22.1 because that was only a security release fix for the IAX channel.

Anyway, I am running the 1.4.23rc4 version of rtp.c in my 1.4.22.1 base install.