[asterisk-bugs] [Asterisk 0013053]: Called Party's inband DTMF removed almost entirely with overlapdial and non-native bridging

Asterisk Bug Tracker noreply at bugs.digium.com
Thu Dec 11 16:34:00 CST 2008


A NOTE has been added to this issue. 
====================================================================== 
http://bugs.digium.com/view.php?id=13053 
====================================================================== 
Reported By:                Seb7
Assigned To:                jpeeler
====================================================================== 
Project:                    Asterisk
Issue ID:                   13053
Category:                   Channels/chan_dahdi
Reproducibility:            always
Severity:                   minor
Priority:                   normal
Status:                     assigned
Asterisk Version:           1.4.19 
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Disclaimer on File?:        N/A 
Request Review:              
====================================================================== 
Date Submitted:             2008-07-10 09:50 CDT
Last Modified:              2008-12-11 16:33 CST
====================================================================== 
Summary:                    Called Party's inband DTMF removed almost entirely
with overlapdial and non-native bridging
Description: 
If you are using inband DTMF (tested on two Zap channels), and two bridged
channels are not bridged natively (e.g. because you are using MixMonitor),
and the incoming call used overlap dialing, and the Called Party tries
sending DTMF, you hear a blip and perhaps a few milliseconds of tone, but
not enough for the DTMF to be recognised by an application on the Calling
Party side. (Asterisk does not recognize the DTMF either). It is being
trimmed to within a millisecond of its life. Note, that the Calling Party's
DTMF is OK.
====================================================================== 

---------------------------------------------------------------------- 
 (0096288) jpeeler (administrator) - 2008-12-11 16:33
 http://bugs.digium.com/view.php?id=13053#c96288 
---------------------------------------------------------------------- 
My setup is a lot more complicated because I'm only using one server.

*CLI>     -- Starting simple switch on 'Zap/126-1'
[Dec 11 16:14:07] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '4'
received on Zap/126-1, duration 0 ms
[Dec 11 16:14:07] DTMF[17705]: channel.c:2206 __ast_read: DTMF end
accepted without begin '4' on Zap/126-1
[Dec 11 16:14:07] DTMF[17705]: channel.c:2217 __ast_read: DTMF end
passthrough '4' on Zap/126-1
[Dec 11 16:14:07] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '9'
received on Zap/126-1, duration 0 ms
[Dec 11 16:14:07] DTMF[17705]: channel.c:2206 __ast_read: DTMF end
accepted without begin '9' on Zap/126-1
[Dec 11 16:14:07] DTMF[17705]: channel.c:2217 __ast_read: DTMF end
passthrough '9' on Zap/126-1
[Dec 11 16:14:07] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '9'
received on Zap/126-1, duration 0 ms
[Dec 11 16:14:07] DTMF[17705]: channel.c:2206 __ast_read: DTMF end
accepted without begin '9' on Zap/126-1
[Dec 11 16:14:07] DTMF[17705]: channel.c:2217 __ast_read: DTMF end
passthrough '9' on Zap/126-1
[Dec 11 16:14:08] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '9'
received on Zap/126-1, duration 0 ms
[Dec 11 16:14:08] DTMF[17705]: channel.c:2206 __ast_read: DTMF end
accepted without begin '9' on Zap/126-1
[Dec 11 16:14:08] DTMF[17705]: channel.c:2217 __ast_read: DTMF end
passthrough '9' on Zap/126-1
    -- Executing [4999 at default:1] NoOp("Zap/126-1", "") in new stack
    -- Executing [4999 at default:2] MixMonitor("Zap/126-1", "jefftest.wav")
in new stack
  == Begin MixMonitor Recording Zap/126-1
    -- Executing [4999 at default:3] Dial("Zap/126-1", "Zap/32/11111||tT") in
new stack
    -- Requested transfer capability: 0x00 - SPEECH
    -- Called 32/11111
    -- Executing [11111 at default:1] NoOp("Zap/1-1", "") in new stack
    -- Executing [11111 at default:2] MixMonitor("Zap/1-1", "jefftest2.wav")
in new stack
  == Begin MixMonitor Recording Zap/1-1
    -- Accepting call from '' to '11111' on channel 0/1, span 1
    -- Executing [11111 at default:3] Dial("Zap/1-1", "Zap/63/11112") in new
stack
    -- Requested transfer capability: 0x00 - SPEECH
    -- Called 63/11112
    -- Executing [11112 at default:1] NoOp("Zap/94-1", "") in new stack
    -- Executing [11112 at default:2] MixMonitor("Zap/94-1", "jefftest3.wav")
in new stack
  == Begin MixMonitor Recording Zap/94-1
    -- Executing [11112 at default:3] Dial("Zap/94-1", "Zap/125") in new
stack
    -- Called 125
    -- Accepting call from '' to '11112' on channel 0/1, span 4
    -- Zap/125-1 is ringing
[Dec 11 16:14:08] DEBUG[17635]: chan_dahdi.c:9249 pri_dchannel: Queuing
frame from PRI_EVENT_PROCEEDING on channel 0/1 span 3
    -- Zap/63-1 is proceeding passing it to Zap/1-1
[Dec 11 16:14:08] DEBUG[17707]: chan_dahdi.c:5202 dahdi_indicate: Received
AST_CONTROL_PROCEEDING on Zap/1-1
    -- Zap/63-1 is ringing
[Dec 11 16:14:08] DEBUG[17634]: chan_dahdi.c:9249 pri_dchannel: Queuing
frame from PRI_EVENT_PROCEEDING on channel 0/1 span 2
    -- Zap/32-1 is proceeding passing it to Zap/126-1
[Dec 11 16:14:08] DEBUG[17705]: chan_dahdi.c:5202 dahdi_indicate: Received
AST_CONTROL_PROCEEDING on Zap/126-1
    -- Zap/32-1 is making progress passing it to Zap/126-1
[Dec 11 16:14:08] DEBUG[17705]: chan_dahdi.c:5220 dahdi_indicate: Received
AST_CONTROL_PROGRESS on Zap/126-1
    -- Zap/32-1 is ringing
    -- Zap/125-1 is ringing
[Dec 11 16:14:12] DEBUG[17709]: chan_dahdi.c:1504 dahdi_train_ec: No echo
training requested
[Dec 11 16:14:12] DEBUG[17709]: chan_dahdi.c:4135 dahdi_handle_event:
channel 125 answered
    -- Zap/125-1 answered Zap/94-1
[Dec 11 16:14:12] DEBUG[17635]: chan_dahdi.c:1464 dahdi_enable_ec: Echo
cancellation already on
    -- Zap/63-1 answered Zap/1-1
[Dec 11 16:14:12] DEBUG[17634]: chan_dahdi.c:1464 dahdi_enable_ec: Echo
cancellation already on
    -- Zap/32-1 answered Zap/126-1
[Dec 11 16:14:12] DEBUG[17705]: chan_dahdi.c:2880 dahdi_answer: Took
Zap/126-1 off hook
[Dec 11 16:14:20] DTMF[17709]: channel.c:2154 __ast_read: DTMF end '1'
received on Zap/125-1, duration 0 ms
[Dec 11 16:14:20] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '1' with duration 100 queued on Zap/125-1
[Dec 11 16:14:20] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '1'
[Dec 11 16:14:20] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '1' queued on Zap/125-1
[Dec 11 16:14:20] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '1'
[Dec 11 16:14:20] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '1'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:20] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '1' with duration 100 queued on Zap/63-1
[Dec 11 16:14:20] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '1'
[Dec 11 16:14:20] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '1' queued on Zap/63-1
[Dec 11 16:14:20] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '1'
[Dec 11 16:14:20] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '1'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:20] DTMF[17705]: channel.c:2190 __ast_read: DTMF begin
emulation of '1' with duration 100 queued on Zap/32-1
[Dec 11 16:14:20] DTMF[17705]: channel.c:2302 __ast_read: DTMF end
emulation of '1' queued on Zap/32-1
[Dec 11 16:14:20] DEBUG[17705]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '1'
[Dec 11 16:14:20] DEBUG[17705]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '1'
[Dec 11 16:14:22] DTMF[17709]: channel.c:2154 __ast_read: DTMF end '2'
received on Zap/125-1, duration 0 ms
[Dec 11 16:14:22] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '2' with duration 100 queued on Zap/125-1
[Dec 11 16:14:22] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '2'
[Dec 11 16:14:22] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '2' queued on Zap/125-1
[Dec 11 16:14:22] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '2'
[Dec 11 16:14:22] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '2'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:22] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '2' with duration 100 queued on Zap/63-1
[Dec 11 16:14:22] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '2'
[Dec 11 16:14:22] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '2' queued on Zap/63-1
[Dec 11 16:14:22] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '2'
[Dec 11 16:14:22] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '2'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:22] DTMF[17705]: channel.c:2190 __ast_read: DTMF begin
emulation of '2' with duration 100 queued on Zap/32-1
[Dec 11 16:14:22] DTMF[17705]: channel.c:2302 __ast_read: DTMF end
emulation of '2' queued on Zap/32-1
[Dec 11 16:14:22] DEBUG[17705]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '2'
[Dec 11 16:14:22] DEBUG[17705]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '2'
[Dec 11 16:14:32] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '#'
received on Zap/126-1, duration 0 ms
[Dec 11 16:14:32] DTMF[17705]: channel.c:2190 __ast_read: DTMF begin
emulation of '#' with duration 100 queued on Zap/126-1
[Dec 11 16:14:32] DTMF[17705]: channel.c:2302 __ast_read: DTMF end
emulation of '#' queued on Zap/126-1
    -- Started music on hold, class 'default', on Zap/32-1
    -- <Zap/126-1> Playing 'pbx-transfer' (language 'en')
[Dec 11 16:14:33] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '5'
received on Zap/126-1, duration 0 ms
[Dec 11 16:14:33] DTMF[17705]: channel.c:2206 __ast_read: DTMF end
accepted without begin '5' on Zap/126-1
[Dec 11 16:14:33] DTMF[17705]: channel.c:2217 __ast_read: DTMF end
passthrough '5' on Zap/126-1
[Dec 11 16:14:34] DTMF[17705]: channel.c:2154 __ast_read: DTMF end '1'
received on Zap/126-1, duration 0 ms
[Dec 11 16:14:34] DTMF[17705]: channel.c:2206 __ast_read: DTMF end
accepted without begin '1' on Zap/126-1
[Dec 11 16:14:34] DTMF[17705]: channel.c:2217 __ast_read: DTMF end
passthrough '1' on Zap/126-1
    -- Stopped music on hold on Zap/32-1
    -- Transferring Zap/32-1 to '51' (context default) priority 1
[Dec 11 16:14:34] DEBUG[17705]: chan_dahdi.c:3542 dahdi_fixup: New owner
for channel 32 is Zap/32-1
    -- Executing [51 at default:1] Answer("Zap/32-1", "") in new stack
    -- Executing [51 at default:2] Read("Zap/32-1", "digits|||||10") in new
stack
  == Spawn extension (default, 4999, 3) exited non-zero on 'Zap/126-1'
  == End MixMonitor Recording Zap/126-1
    -- Hungup 'Zap/126-1'

*CLI> [Dec 11 16:14:40] DTMF[17709]: channel.c:2154 __ast_read: DTMF end
'1' received on Zap/125-1, duration 0 ms
[Dec 11 16:14:40] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '1' with duration 100 queued on Zap/125-1
[Dec 11 16:14:40] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '1'
[Dec 11 16:14:40] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '1' queued on Zap/125-1
[Dec 11 16:14:40] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '1'
[Dec 11 16:14:40] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '1'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:40] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '1' with duration 100 queued on Zap/63-1
[Dec 11 16:14:40] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '1'
[Dec 11 16:14:40] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '1' queued on Zap/63-1
[Dec 11 16:14:40] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '1'
[Dec 11 16:14:40] DTMF[17711]: channel.c:2154 __ast_read: DTMF end '1'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:40] DTMF[17711]: channel.c:2206 __ast_read: DTMF end
accepted without begin '1' on Zap/32-1
[Dec 11 16:14:40] DTMF[17711]: channel.c:2217 __ast_read: DTMF end
passthrough '1' on Zap/32-1
[Dec 11 16:14:41] DTMF[17709]: channel.c:2154 __ast_read: DTMF end '2'
received on Zap/125-1, duration 0 ms
[Dec 11 16:14:41] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '2' with duration 100 queued on Zap/125-1
[Dec 11 16:14:41] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '2'
[Dec 11 16:14:41] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '2' queued on Zap/125-1
[Dec 11 16:14:41] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '2'
[Dec 11 16:14:41] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '2'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:41] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '2' with duration 100 queued on Zap/63-1
[Dec 11 16:14:41] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '2'
[Dec 11 16:14:41] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '2' queued on Zap/63-1
[Dec 11 16:14:41] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '2'
[Dec 11 16:14:41] DTMF[17711]: channel.c:2154 __ast_read: DTMF end '2'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:41] DTMF[17711]: channel.c:2206 __ast_read: DTMF end
accepted without begin '2' on Zap/32-1
[Dec 11 16:14:41] DTMF[17711]: channel.c:2217 __ast_read: DTMF end
passthrough '2' on Zap/32-1
[Dec 11 16:14:42] DTMF[17709]: channel.c:2154 __ast_read: DTMF end '3'
received on Zap/125-1, duration 0 ms
[Dec 11 16:14:42] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '3' with duration 100 queued on Zap/125-1
[Dec 11 16:14:42] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '3'
[Dec 11 16:14:42] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '3' queued on Zap/125-1
[Dec 11 16:14:42] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '3'
[Dec 11 16:14:42] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '3'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:42] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '3' with duration 100 queued on Zap/63-1
[Dec 11 16:14:42] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '3'
[Dec 11 16:14:42] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '3' queued on Zap/63-1
[Dec 11 16:14:42] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '3'
[Dec 11 16:14:42] DTMF[17711]: channel.c:2154 __ast_read: DTMF end '3'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:42] DTMF[17711]: channel.c:2206 __ast_read: DTMF end
accepted without begin '3' on Zap/32-1
[Dec 11 16:14:42] DTMF[17711]: channel.c:2217 __ast_read: DTMF end
passthrough '3' on Zap/32-1
[Dec 11 16:14:43] DTMF[17709]: channel.c:2154 __ast_read: DTMF end '4'
received on Zap/125-1, duration 0 ms
[Dec 11 16:14:43] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '4' with duration 100 queued on Zap/125-1
[Dec 11 16:14:43] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '4'
[Dec 11 16:14:43] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '4' queued on Zap/125-1
[Dec 11 16:14:43] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '4'
[Dec 11 16:14:43] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '4'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:43] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '4' with duration 100 queued on Zap/63-1
[Dec 11 16:14:43] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '4'
[Dec 11 16:14:43] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '4' queued on Zap/63-1
[Dec 11 16:14:43] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '4'
[Dec 11 16:14:43] DTMF[17711]: channel.c:2154 __ast_read: DTMF end '4'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:43] DTMF[17711]: channel.c:2206 __ast_read: DTMF end
accepted without begin '4' on Zap/32-1
[Dec 11 16:14:43] DTMF[17711]: channel.c:2217 __ast_read: DTMF end
passthrough '4' on Zap/32-1
[Dec 11 16:14:44] DTMF[17709]: channel.c:2154 __ast_read: DTMF end '5'
received on Zap/125-1, duration 0 ms
[Dec 11 16:14:44] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '5' with duration 100 queued on Zap/125-1
[Dec 11 16:14:44] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '5'
[Dec 11 16:14:44] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '5' queued on Zap/125-1
[Dec 11 16:14:44] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '5'
[Dec 11 16:14:44] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '5'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:44] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '5' with duration 100 queued on Zap/63-1
[Dec 11 16:14:44] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '5'
[Dec 11 16:14:44] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '5' queued on Zap/63-1
[Dec 11 16:14:44] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '5'
[Dec 11 16:14:44] DTMF[17711]: channel.c:2154 __ast_read: DTMF end '5'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:44] DTMF[17711]: channel.c:2206 __ast_read: DTMF end
accepted without begin '5' on Zap/32-1
[Dec 11 16:14:44] DTMF[17711]: channel.c:2217 __ast_read: DTMF end
passthrough '5' on Zap/32-1
[Dec 11 16:14:44] DTMF[17709]: channel.c:2154 __ast_read: DTMF end '6'
received on Zap/125-1, duration 0 ms
[Dec 11 16:14:44] DTMF[17709]: channel.c:2190 __ast_read: DTMF begin
emulation of '6' with duration 100 queued on Zap/125-1
[Dec 11 16:14:44] DEBUG[17709]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '6'
[Dec 11 16:14:44] DTMF[17709]: channel.c:2302 __ast_read: DTMF end
emulation of '6' queued on Zap/125-1
[Dec 11 16:14:44] DEBUG[17709]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '6'
[Dec 11 16:14:44] DTMF[17707]: channel.c:2154 __ast_read: DTMF end '6'
received on Zap/63-1, duration 0 ms
[Dec 11 16:14:44] DTMF[17707]: channel.c:2190 __ast_read: DTMF begin
emulation of '6' with duration 100 queued on Zap/63-1
[Dec 11 16:14:44] DEBUG[17707]: chan_dahdi.c:1099 dahdi_digit_begin:
Started VLDTMF digit '6'
[Dec 11 16:14:44] DTMF[17707]: channel.c:2302 __ast_read: DTMF end
emulation of '6' queued on Zap/63-1
[Dec 11 16:14:44] DEBUG[17707]: chan_dahdi.c:1134 dahdi_digit_end: Ending
VLDTMF digit '6'
[Dec 11 16:14:45] DTMF[17711]: channel.c:2154 __ast_read: DTMF end '6'
received on Zap/32-1, duration 0 ms
[Dec 11 16:14:45] DTMF[17711]: channel.c:2206 __ast_read: DTMF end
accepted without begin '6' on Zap/32-1
[Dec 11 16:14:45] DTMF[17711]: channel.c:2217 __ast_read: DTMF end
passthrough '6' on Zap/32-1
    -- User entered '123456'
    -- Executing [51 at default:3] NoOp("Zap/32-1", ""Read finished"") in new
stack
    -- Executing [51 at default:4] NoOp("Zap/32-1", "123456") in new stack
  == Auto fallthrough, channel 'Zap/32-1' status is 'UNKNOWN'
[Dec 11 16:14:55] DEBUG[17711]: chan_dahdi.c:3071 dahdi_setoption: Set
option AUDIO MODE, value: ON(1) on Zap/32-1
[Dec 11 16:14:55] DEBUG[17711]: chan_dahdi.c:2704 dahdi_hangup: Not yet
hungup...  Calling hangup once with icause, and clearing call
[Dec 11 16:14:55] DEBUG[17711]: chan_dahdi.c:3067 dahdi_setoption: Set
option AUDIO MODE, value: OFF(0) on Zap/32-1
    -- Hungup 'Zap/32-1'
    -- Channel 0/1, span 1 got hangup request, cause 16
[Dec 11 16:14:55] DEBUG[17707]: chan_dahdi.c:3071 dahdi_setoption: Set
option AUDIO MODE, value: ON(1) on Zap/63-1
[Dec 11 16:14:55] DEBUG[17707]: chan_dahdi.c:2704 dahdi_hangup: Not yet
hungup...  Calling hangup once with icause, and clearing call
[Dec 11 16:14:55] DEBUG[17707]: chan_dahdi.c:3067 dahdi_setoption: Set
option AUDIO MODE, value: OFF(0) on Zap/63-1
    -- Hungup 'Zap/63-1'
  == Spawn extension (default, 11111, 3) exited non-zero on 'Zap/1-1'
[Dec 11 16:14:55] DEBUG[17707]: chan_dahdi.c:3071 dahdi_setoption: Set
option AUDIO MODE, value: ON(1) on Zap/1-1
  == End MixMonitor Recording Zap/1-1
[Dec 11 16:14:55] DEBUG[17707]: chan_dahdi.c:2704 dahdi_hangup: Not yet
hungup...  Calling hangup once with icause, and clearing call
[Dec 11 16:14:55] DEBUG[17707]: chan_dahdi.c:3067 dahdi_setoption: Set
option AUDIO MODE, value: OFF(0) on Zap/1-1
    -- Hungup 'Zap/1-1'
    -- Channel 0/1, span 4 got hangup request, cause 16
    -- Hungup 'Zap/125-1'
  == Spawn extension (default, 11112, 3) exited non-zero on 'Zap/94-1'
  == End MixMonitor Recording Zap/94-1
[Dec 11 16:14:55] DEBUG[17709]: chan_dahdi.c:3071 dahdi_setoption: Set
option AUDIO MODE, value: ON(1) on Zap/94-1
[Dec 11 16:14:55] DEBUG[17709]: chan_dahdi.c:2704 dahdi_hangup: Not yet
hungup...  Calling hangup once with icause, and clearing call
[Dec 11 16:14:55] DEBUG[17709]: chan_dahdi.c:3067 dahdi_setoption: Set
option AUDIO MODE, value: OFF(0) on Zap/94-1
    -- Hungup 'Zap/94-1'


I have two analog phones and 4 pri spans. I tried to duplicate your setup
as closely as possible with both CPE endpoints terminating at Asterisk. So
the call flow is as follows: the first analog phone calls the NET side
(span 2) which is looped back to the CPE (span 1), which dials the CPE side
(span 3) which is looped back to the net side (span 4), which dials the
second analog phone.

You'll see I dialed a few digits, just to see how that went. Then I
transfered the first analog phone to an extension which reads digits. All
the digits from the called/second phone made it to the other side. I'm
using MixMonitor between all the Zap channels to prevent native bridging
and do have overlapdial turned on.

Thoughts? I tested with both 1.4.19 and current 1.4 with no difference in
behavior. 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2008-12-11 16:33 jpeeler        Note Added: 0096288                          
======================================================================




More information about the asterisk-bugs mailing list