[asterisk-bugs] [Asterisk 0018667]: Chan_dahdi hangs up after first ring when doing dtmf cid detection without polarity reversal

[Asterisk Bug Tracker]

A NOTE has been added to this issue. 
====================================================================== 
https://issues.asterisk.org/view.php?id=18667 
====================================================================== 
Reported By:                enegaard
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   18667
Category:                   Channels/chan_dahdi
Reproducibility:            always
Severity:                   major
Priority:                   normal
Status:                     new
Asterisk Version:           1.8.2.2 
JIRA:                        
Regression:                 No 
Reviewboard Link:            
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Request Review:              
====================================================================== 
Date Submitted:             2011-01-24 08:29 CST
Last Modified:              2011-01-24 08:34 CST
====================================================================== 
Summary:                    Chan_dahdi hangs up after first ring when doing dtmf
cid detection without polarity reversal
Description: 
I believe this issue is related to closed bugs 16460 and 9096.
Seen when doing dtmf callerid detection without polarity reversal:
cidsignalling=dtmf
cidstart=dtmf
CID is properly detected, and the first ring is detected.
The dahdi channel is then hungup, and a second ring is detected (without
CID).
The (external) caller is not disconnected.
====================================================================== 

---------------------------------------------------------------------- 
 (0130926) enegaard (reporter) - 2011-01-24 08:34
 https://issues.asterisk.org/view.php?id=18667#c130926 
---------------------------------------------------------------------- 
[Jan 24 10:09:07] DEBUG[10691] sig_analog.c: channel (1) - signaling (5) -
event (UNKNOWN/OTHER)
[Jan 24 10:09:07] VERBOSE[10691] sig_analog.c:   == Starting DTMF CID
detection on channel 1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: __analog_ss_thread 1
[Jan 24 10:09:07] VERBOSE[12467] sig_analog.c:     -- Starting simple
switch on 'DAHDI/1-1'
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: Receiving DTMF cid on channel
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x41 'A' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x41 'A' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: End DTMF digit: 0x41 'A' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x41 'A' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: CID got digit 'A'
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x32 '2' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x32 '2' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: End DTMF digit: 0x32 '2' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x32 '2' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: CID got digit '2'
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: End DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: CID got digit '0'
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: End DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: CID got digit '1'
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x34 '4' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x34 '4' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: End DTMF digit: 0x34 '4' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x34 '4' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: CID got digit '4'
[Jan 24 10:09:07] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x37 '7' on
DAHDI/1-1
[Jan 24 10:09:07] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x37 '7' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: End DTMF digit: 0x37 '7' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x37 '7' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: CID got digit '7'
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x35 '5' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x35 '5' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: End DTMF digit: 0x35 '5' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x35 '5' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: CID got digit '5'
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: End DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x30 '0' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: CID got digit '0'
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: End DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x31 '1' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: CID got digit '1'
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: Begin DTMF digit: 0x23 '#' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: Begin DTMF digit: 0x23 '#' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: End DTMF digit: 0x23 '#' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] chan_dahdi.c: End DTMF digit: 0x23 '#' on
DAHDI/1-1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: CID got digit '#'
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: analog_exception 1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: Exception on 10, channel 1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: __analog_handle_event 1
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: Got event
ANALOG_EVENT_RINGBEGIN(12) on channel 1 (index 0)
[Jan 24 10:09:08] DEBUG[12467] sig_analog.c: Dunno what to do with event
12 on channel 1
[Jan 24 10:09:09] DEBUG[12467] sig_analog.c: analog_exception 1
[Jan 24 10:09:09] DEBUG[12467] sig_analog.c: Exception on 10, channel 1
[Jan 24 10:09:09] DEBUG[12467] sig_analog.c: __analog_handle_event 1
[Jan 24 10:09:09] DEBUG[12467] sig_analog.c: Got event
ANALOG_EVENT_RINGOFFHOOK(2) on channel 1 (index 0)
[Jan 24 10:09:09] DEBUG[12467] sig_analog.c: Ring detected
[Jan 24 10:09:09] DEBUG[12467] sig_analog.c: CID got string 'A20147501#'
[Jan 24 10:09:09] DEBUG[12467] sig_analog.c: CID is '20147501', flags 0
[Jan 24 10:09:09] DEBUG[12467] chan_dahdi.c: Requested indication 3 on
channel DAHDI/1-1
[Jan 24 10:09:09] DEBUG[12467] chan_dahdi.c: Requested indication 3 on
channel DAHDI/1-1
[Jan 24 10:09:09] DEBUG[12467] chan_dahdi.c: Requested indication 3 on
channel DAHDI/1-1
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: analog_exception 1
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: Exception on 10, channel 1
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: __analog_handle_event 1
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: Got event
ANALOG_EVENT_RINGBEGIN(12) on channel 1 (index 0)
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: Dunno what to do with event
12 on channel 1
[Jan 24 10:09:17] DEBUG[12467] chan_dahdi.c: dahdi_hangup(DAHDI/1-1)
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: analog_hangup 1
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: Hangup: channel: 1 index = 0,
normal = 1, callwait = 0, thirdcall = 0
[Jan 24 10:09:17] DEBUG[12467] chan_dahdi.c: Set option TONE VERIFY, mode:
OFF(0) on DAHDI/1-1
[Jan 24 10:09:17] DEBUG[12467] chan_dahdi.c: Set option TDD MODE, value:
OFF(0) on DAHDI/1-1
[Jan 24 10:09:17] DEBUG[12467] sig_analog.c: Updated conferencing on 1,
with 0 conference users
[Jan 24 10:09:17] VERBOSE[12467] sig_analog.c:     -- Hanging up on
'DAHDI/1-1'
[Jan 24 10:09:17] VERBOSE[12467] chan_dahdi.c:     -- Hungup 'DAHDI/1-1'
[Jan 24 10:09:25] DEBUG[10691] sig_analog.c: channel (1) - signaling (5) -
event (UNKNOWN/OTHER)
[Jan 24 10:09:25] VERBOSE[10691] sig_analog.c:   == Starting DTMF CID
detection on channel 1
[Jan 24 10:09:25] DEBUG[12470] sig_analog.c: __analog_ss_thread 1
[Jan 24 10:09:25] VERBOSE[12470] sig_analog.c:     -- Starting simple
switch on 'DAHDI/1-1'
[Jan 24 10:09:25] DEBUG[12470] sig_analog.c: Receiving DTMF cid on channel
DAHDI/1-1
[Jan 24 10:09:25] DEBUG[12470] sig_analog.c: analog_exception 1
[Jan 24 10:09:25] DEBUG[12470] sig_analog.c: Exception on 10, channel 1
[Jan 24 10:09:25] DEBUG[12470] sig_analog.c: __analog_handle_event 1
[Jan 24 10:09:25] DEBUG[12470] sig_analog.c: Got event
ANALOG_EVENT_RINGBEGIN(12) on channel 1 (index 0)
[Jan 24 10:09:25] DEBUG[12470] sig_analog.c: Dunno what to do with event
12 on channel 1
[Jan 24 10:09:26] DEBUG[12470] sig_analog.c: analog_exception 1
[Jan 24 10:09:26] DEBUG[12470] sig_analog.c: Exception on 10, channel 1
[Jan 24 10:09:26] DEBUG[12470] sig_analog.c: __analog_handle_event 1
[Jan 24 10:09:26] DEBUG[12470] sig_analog.c: Got event
ANALOG_EVENT_RINGOFFHOOK(2) on channel 1 (index 0)
[Jan 24 10:09:26] DEBUG[12470] sig_analog.c: Ring detected
[Jan 24 10:09:26] DEBUG[12470] sig_analog.c: CID got string ''
[Jan 24 10:09:26] DEBUG[12470] sig_analog.c: CID is '', flags 8
[Jan 24 10:09:26] DEBUG[12470] chan_dahdi.c: Requested indication 3 on
channel DAHDI/1-1
[Jan 24 10:09:26] DEBUG[12470] chan_dahdi.c: Requested indication 3 on
channel DAHDI/1-1
[Jan 24 10:09:26] DEBUG[12470] chan_dahdi.c: Requested indication 3 on
channel DAHDI/1-1
[Jan 24 10:09:34] DEBUG[12470] chan_dahdi.c: dahdi_hangup(DAHDI/1-1)
[Jan 24 10:09:34] DEBUG[12470] sig_analog.c: analog_hangup 1
[Jan 24 10:09:34] DEBUG[12470] sig_analog.c: Hangup: channel: 1 index = 0,
normal = 1, callwait = 0, thirdcall = 0
[Jan 24 10:09:34] DEBUG[12470] chan_dahdi.c: Set option TONE VERIFY, mode:
OFF(0) on DAHDI/1-1
[Jan 24 10:09:34] DEBUG[12470] chan_dahdi.c: Set option TDD MODE, value:
OFF(0) on DAHDI/1-1
[Jan 24 10:09:34] DEBUG[12470] sig_analog.c: Updated conferencing on 1,
with 0 conference users
[Jan 24 10:09:34] VERBOSE[12470] sig_analog.c:     -- Hanging up on
'DAHDI/1-1'
[Jan 24 10:09:34] VERBOSE[12470] chan_dahdi.c:     -- Hungup 'DAHDI/1-1'
[Jan 24 10:09:35] DEBUG[10691] sig_analog.c: channel (1) - signaling (5) -
event (UNKNOWN/OTHER)
[Jan 24 10:09:35] VERBOSE[10691] sig_analog.c:   == Starting DTMF CID
detection on channel 1
[Jan 24 10:09:35] DEBUG[12511] sig_analog.c: __analog_ss_thread 1
[Jan 24 10:09:35] VERBOSE[12511] sig_analog.c:     -- Starting simple
switch on 'DAHDI/1-1'
[Jan 24 10:09:35] DEBUG[12511] sig_analog.c: Receiving DTMF cid on channel
DAHDI/1-1
[Jan 24 10:09:39] WARNING[12511] sig_analog.c: DTMFCID timed out waiting
for ring. Exiting simple switch
[Jan 24 10:09:39] DEBUG[12511] chan_dahdi.c: dahdi_hangup(DAHDI/1-1)
[Jan 24 10:09:39] DEBUG[12511] sig_analog.c: analog_hangup 1
[Jan 24 10:09:39] DEBUG[12511] sig_analog.c: Hangup: channel: 1 index = 0,
normal = 1, callwait = 0, thirdcall = 0
[Jan 24 10:09:39] DEBUG[12511] chan_dahdi.c: Set option TONE VERIFY, mode:
OFF(0) on DAHDI/1-1
[Jan 24 10:09:39] DEBUG[12511] chan_dahdi.c: Set option TDD MODE, value:
OFF(0) on DAHDI/1-1
[Jan 24 10:09:39] DEBUG[12511] sig_analog.c: Updated conferencing on 1,
with 0 conference users
[Jan 24 10:09:39] VERBOSE[12511] sig_analog.c:     -- Hanging up on
'DAHDI/1-1'
[Jan 24 10:09:39] VERBOSE[12511] chan_dahdi.c:     -- Hungup 'DAHDI/1-1' 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2011-01-24 08:34 enegaard       Note Added: 0130926                          
======================================================================