[asterisk-users] Asterisk Fails to convert INFO to Inband

Fri Jul 11 10:07:26 CDT 2008

Hi,

          We are using asterisk 1.4.20 load. We have seen that couple of
times Asterisk fails to convert SIP INFO packet in to Inband tone.

          Problem Description:

                        Asterisk behaves as a media proxy between proxy1 and
proxy2.

                   Proxy1 transmit DTMF using SIP INFO method while proxy2
works with Inaband Tone. I have provided complete setup descriptions below.

                   After analyzing the logs we found that when proxy1
transmit 1st DTMF Digit it successfully converts into inband but when we
send next digit chan->generatordata is set to NULL therefore no Inabdn tone
generate further. This remains set to NULL for a while and then it start
converting again into inband.

          Here is sip.conf

          [general]

          Dtmfmode=inband

          [peer-proxy1]

type=peer                     ; we only want to call out, not be called

fromdomain=proxy2.varaha.com ;

host= 71.153.215.73    ; 

context=from-proxy1

;dtmfmode=inband

dtmfmode=info

rtpkeepalive=1

port=5045

disallow=all

allow=ulaw

allow=alaw

[peer-proxy2]

type=peer                     ; we only want to call out, not be called

fromdomain= proxy1.varaha.com 

context=from-proxy2

;dtmfmode=auto

;dtmfmode=rfc2833

;rfc2833compensate=yes          ; Compensate for pre-1.4 DTMF transmission
from another Asterisk machine.

;dtmfmode=inband

;dtmfmode=info

;rtpkeepalive=1

port=5080

disallow=all

allow=ulaw

allow=alaw

We have seen following logs in message file

[Jul 11 03:58:22] DTMF[32393] chan_sip.c: -Got INFO with: [1] Duration:
[250]

[Jul 11 03:58:22] DTMF[32508] channel.c: DTMF end '1' received on
SIP/trunk.myvtel.com-090f4880, duration 250 ms

[Jul 11 03:58:22] DTMF[32508] channel.c: DTMF begin emulation of '1' with
duration 250 queued on SIP/trunk.myvtel.com-090f4880

[Jul 11 03:58:22] NOTICE[32508] channel.c: Send DTMF in Inband: [1]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Result of ast_playtones_start:
[0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: Generate DTMF for pointer:
[0x9103E20]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x9103E20] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] DTMF[32508] channel.c: DTMF end emulation of '1' queued on
SIP/trunk.myvtel.com-090f4880

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:22] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] DTMF[32393] chan_sip.c: -Got INFO with: [2] Duration:
[250]

[Jul 11 03:58:23] DTMF[32508] channel.c: DTMF end '2' received on
SIP/trunk.myvtel.com-090f4880, duration 250 ms

[Jul 11 03:58:23] DTMF[32508] channel.c: DTMF begin emulation of '2' with
duration 250 queued on SIP/trunk.myvtel.com-090f4880

[Jul 11 03:58:23] NOTICE[32508] channel.c: Send DTMF in Inband: [2]

[Jul 11 03:58:23] NOTICE[32508] channel.c: Result of ast_playtones_start:
[0]

[Jul 11 03:58:23] DTMF[32508] channel.c: DTMF end emulation of '2' queued on
SIP/trunk.myvtel.com-090f4880

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

[Jul 11 03:58:23] NOTICE[32508] channel.c: ast_read_generator_actions:
chan->generatordata[0x0] ast_internal_timing_enabled(chan): [0]

          I'll appreciate your help.

Thanks

Niraj Roy

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20080711/74ad1eba/attachment.htm 