[asterisk-users] Unusual DTMF behavior

[Jason Landrey]

We are having an issue where DTMF is not being sent out right away and the
tone duration is inconsistent.  For a test we send a '5', then a second
later we send a '9', and then five seconds later we send a '5'.  If you look
at the logs below you can see the first '5' is played right away, then the
'9' comes in and gets queued, but it doesn't start playing until five
seconds later and it plays for six seconds.  Then the last '5' is played.

The DTMF is coming in as only 'end' packets and we can't change that.  For
this reason we have turned on rfc2833compensate.  Using Asterisk 1.4.11.

Any ideas?


asteriskpri04*CLI>
< Protocol Discriminator: Q.931 (8)  len=5
< Call Ref: len= 2 (reference 499/0x1F3) (Terminator)
< Message type: CONNECT (7)
q931.c:3371 q931_receive: call 33267 on channel 3 enters state 10 (Active)
> Protocol Discriminator: Q.931 (8)  len=5
> Call Ref: len= 2 (reference 499/0x1F3) (Originator)
> Message type: CONNECT ACKNOWLEDGE (15)
[Oct 23 10:39:56] DEBUG[6136]: chan_zap.c:1413 zt_enable_ec: Echo
cancellation already on
    -- Zap/3-1 answered SIP/test.com-08dc1ef8

[Oct 23 10:39:58] DTMF[13914]: channel.c:2346 __ast_read: DTMF end '5'
received on SIP/test.com-08dc1ef8, duration 0 ms
[Oct 23 10:39:58] DTMF[13914]: channel.c :2382 __ast_read: DTMF begin
emulation of '5' with duration 100 queued on SIP/test.com-08dc1ef8
[Oct 23 10:39:58] DEBUG[13914]: chan_zap.c:1048 zt_digit_begin: Started
VLDTMF digit '5'

[Oct 23 10:39:59] DTMF[13914]: channel.c:2346 __ast_read: DTMF end '9'
received on SIP/test.com-08dc1ef8, duration 0 ms
[Oct 23 10:39:59] DTMF[13914]: channel.c:2352 __ast_read: DTMF end '9' put
into dtmf queue on SIP/test.com-08dc1ef8

[Oct 23 10:39:59] DTMF[13914]: channel.c:2434 __ast_read: DTMF end emulation
of '5' queued on SIP/test.com-08dc1ef8
[Oct 23 10:39:59] DEBUG[13914]: chan_zap.c:1083 zt_digit_end: Ending VLDTMF
digit '5'

[Oct 23 10:40:04] DTMF[13914]: channel.c:2215 __ast_read: DTMF begin
emulation of '9' with duration 100 queued on SIP/test.com-08dc1ef8
[Oct 23 10:40:04] DEBUG[13914]: chan_zap.c:1048 zt_digit_begin: Started
VLDTMF digit '9'
[Oct 23 10:40:04] DTMF[13914]: channel.c:2346 __ast_read: DTMF end '5'
received on SIP/test.com-08dc1ef8, duration 0 ms
[Oct 23 10:40:04] DTMF[13914]: channel.c:2352 __ast_read: DTMF end '5' put
into dtmf queue on SIP/test.com-08dc1ef8

[Oct 23 10:40:10] DTMF[13914]: channel.c:2465 __ast_read: DTMF end emulation
of '9' queued on SIP/test.com-08dc1ef8
[Oct 23 10:40:10] DEBUG[13914]: chan_zap.c:1083 zt_digit_end: Ending VLDTMF
digit '9'
[Oct 23 10:40:10] DTMF[13914]: channel.c:2215 __ast_read: DTMF begin
emulation of '5' with duration 100 queued on SIP/test.com-08dc1ef8
[Oct 23 10:40:10] DEBUG[13914]: chan_zap.c:1048 zt_digit_begin: Started
VLDTMF digit '5'

[Oct 23 10:40:10] DTMF[13914]: channel.c:2465 __ast_read: DTMF end emulation
of '5' queued on SIP/test.com-08dc1ef8
[Oct 23 10:40:10] DEBUG[13914]: chan_zap.c:1083 zt_digit_end: Ending VLDTMF
digit '5'


Thanks,
Jason
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20071024/b6cd941b/attachment.htm