[Asterisk-Users] Zap DTMF problems.

[Matteo Brancaleoni]

Hi there.

Seems that there're some
problems with dtmf detection & chan_zap.
(today CVS)

I'm experiencing issues with transfer:
That's what's happens:
I receive a call
answer then want to transfer via flash-hook
I do flash, then dial the exten and get an
error. looking @ the logs, I see that
chan_zap detects multiple times my number
(along with m & u digits)
So I flash again to pickup the call on hold,
then flash again, dial the same extension
and that's ok.

So getting bad dmtf only on first transfer.
With any other app (dial, ivr & so on)
dmtf are ok.

My setup was ok sometime ago, seems
that the problem raised 1 week ago (more or less)
Any idea?

Here's is the log of the above action, when
I call exten 10 and want to transfer to exten 16.
Note that first time I get the '1' detected
multiple times, so it rings exten 11
Second time I flash & dial all is ok...
(channel 11 & 16 are not included here)


Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Ringer Off(11) on channel 10 (index 0)
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 2949 (zt_exception):
Exception on 29, channel 10
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Ring/Answered(2) on channel 10 (index 0)
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 919 (zt_enable_ec):
Enabled echo cancellation on channel 10
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 2581
(zt_handle_event): channel 10 answered
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 1780 (zt_setoption):
Set option TONE VERIFY, mode: MUTECONF/MAX(2) on Zap/10-1
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 2054 (zt_bridge):
master: 2, slave: 10, nothingok: 0
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 2069 (zt_bridge):
Stoping tones on 2/0 talking to 10/0
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 2081 (zt_bridge):
Stoping tones on 10/0 talking to 2/0
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 1953 (zt_link):
Making 10 slave to master 2 at 0
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 838 (conf_add):
Added 29 to conference 1021
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 838 (conf_add):
Added 25 to conference 1021
Mar 26 12:15:45 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 2949 (zt_exception):
Exception on 29, channel 10
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Wink/Flash(3) on channel 10 (index 0)
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 3424 (zt_new):
Already have a dsp on Zap/10-2?
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 935 (zt_disable_ec):
disabled echo cancellation on channel 10
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 1904 (zt_unlink):
Unlinking slave 10 from 2
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 862 (conf_del):
Removed 29 from conference 1021
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 862 (conf_del):
Removed 25 from conference 1021
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 2145 (zt_bridge):
Something changed out on 2/0 to 10/0, returning -3 to restart
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 919 (zt_enable_ec):
Enabled echo cancellation on channel 10
Mar 26 12:15:51 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: m on Zap/10-2
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: m on Zap/10-2
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: 1 on Zap/10-2
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: 1 on Zap/10-2
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: 1 on Zap/10-2
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: 1 on Zap/10-2
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: 1 on Zap/10-2
Mar 26 12:15:55 DEBUG[94232]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: u on Zap/10-2
Mar 26 12:16:11 DEBUG[94232]: File chan_zap.c, Line 2949 (zt_exception):
Exception on 29, channel 10
Mar 26 12:16:11 DEBUG[94232]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Wink/Flash(3) on channel 10 (index 0)
Mar 26 12:16:11 DEBUG[93207]: File chan_zap.c, Line 2145 (zt_bridge):
Something changed out on 2/0 to 10/2, returning -3 to restart
Mar 26 12:16:11 DEBUG[94232]: File chan_zap.c, Line 838 (conf_add):
Added 29 to conference 1021
Mar 26 12:16:11 DEBUG[94232]: File chan_zap.c, Line 838 (conf_add):
Added 46 to conference 1021
Mar 26 12:16:11 DEBUG[94232]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 2 conference users
Mar 26 12:16:11 DEBUG[93207]: File chan_zap.c, Line 2054 (zt_bridge):
master: 10, slave: 2, nothingok: 0
Mar 26 12:16:11 DEBUG[93207]: File chan_zap.c, Line 2065 (zt_bridge):
Playing ringback on Zap/2-1 since Zap/10-1 is in a ringing three-way
Mar 26 12:16:11 DEBUG[93207]: File chan_zap.c, Line 2081 (zt_bridge):
Stoping tones on 10/2 talking to 2/0
Mar 26 12:16:11 DEBUG[93207]: File chan_zap.c, Line 1953 (zt_link):
Making 2 slave to master 10 at 0
Mar 26 12:16:11 DEBUG[93207]: File chan_zap.c, Line 838 (conf_add):
Added 25 to conference 1021
Mar 26 12:16:11 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 3 conference users
Mar 26 12:16:14 DEBUG[94232]: File chan_zap.c, Line 2949 (zt_exception):
Exception on 29, channel 10
Mar 26 12:16:14 DEBUG[94232]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Wink/Flash(3) on channel 10 (index 0)
Mar 26 12:16:14 DEBUG[94232]: File chan_zap.c, Line 862 (conf_del):
Removed 29 from conference 1021
Mar 26 12:16:14 DEBUG[94232]: File chan_zap.c, Line 862 (conf_del):
Removed 46 from conference 1021
Mar 26 12:16:14 DEBUG[94232]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 1 conference users
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 1904 (zt_unlink):
Unlinking slave 2 from 10
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 862 (conf_del):
Removed 25 from conference 1021
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 2145 (zt_bridge):
Something changed out on 2/0 to 10/2, returning -3 to restart
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 2054 (zt_bridge):
master: 2, slave: 10, nothingok: 0
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 2069 (zt_bridge):
Stoping tones on 2/0 talking to 10/0
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 2081 (zt_bridge):
Stoping tones on 10/0 talking to 2/0
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 1953 (zt_link):
Making 10 slave to master 2 at 0
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 838 (conf_add):
Added 29 to conference 1021
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 838 (conf_add):
Added 25 to conference 1021
Mar 26 12:16:14 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:16:14 DEBUG[94232]: File chan_zap.c, Line 1456 (zt_hangup):
Hangup: channel: 10 index = 2, normal = 29, callwait = -1, thirdcall =
46
Mar 26 12:16:14 DEBUG[94232]: File chan_zap.c, Line 654 (unalloc_sub):
Released sub 2 of channel 10
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 2949 (zt_exception):
Exception on 29, channel 10
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Wink/Flash(3) on channel 10 (index 0)
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 3424 (zt_new):
Already have a dsp on Zap/10-2?
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 935 (zt_disable_ec):
disabled echo cancellation on channel 10
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 1904 (zt_unlink):
Unlinking slave 10 from 2
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 862 (conf_del):
Removed 29 from conference 1021
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 862 (conf_del):
Removed 25 from conference 1021
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 2145 (zt_bridge):
Something changed out on 2/0 to 10/0, returning -3 to restart
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 919 (zt_enable_ec):
Enabled echo cancellation on channel 10
Mar 26 12:16:18 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:16:20 DEBUG[95256]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: 1 on Zap/10-2
Mar 26 12:16:21 DEBUG[95256]: File chan_zap.c, Line 3168 (zt_read): DTMF
digit: 6 on Zap/10-2
Mar 26 12:16:27 DEBUG[95256]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Ringer On(10) on channel 16 (index 0)
Mar 26 12:16:27 DEBUG[95256]: File chan_zap.c, Line 2949 (zt_exception):
Exception on 29, channel 10
Mar 26 12:16:27 DEBUG[95256]: File chan_zap.c, Line 2431
(zt_handle_event): Got event On hook(1) on channel 10 (index 0)
Mar 26 12:16:27 DEBUG[95256]: File chan_zap.c, Line 654 (unalloc_sub):
Released sub 2 of channel 10
Mar 26 12:16:27 DEBUG[95256]: File chan_zap.c, Line 2208 (zt_fixup): New
owner for channel 10 is Zap/2-1<MASQ>
Mar 26 12:16:27 DEBUG[95256]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:16:27 DEBUG[95256]: File chan_zap.c, Line 1456 (zt_hangup):
Hangup: channel: 10 index = -1, normal = 29, callwait = -1, thirdcall =
-1
Mar 26 12:16:27 DEBUG[93207]: File chan_zap.c, Line 1456 (zt_hangup):
Hangup: channel: 10 index = 0, normal = 29, callwait = -1, thirdcall =
-1
Mar 26 12:16:27 DEBUG[93207]: File chan_zap.c, Line 935 (zt_disable_ec):
disabled echo cancellation on channel 10
Mar 26 12:16:27 DEBUG[93207]: File chan_zap.c, Line 1791 (zt_setoption):
Set option TDD MODE, value: OFF(0) on Zap/10-1
Mar 26 12:16:27 DEBUG[93207]: File chan_zap.c, Line 900 (update_conf):
Updated conferencing on 10, with 0 conference users
Mar 26 12:16:31 DEBUG[14351]: File chan_zap.c, Line 4391 (do_monitor):
Message status for 10 changed from -1 to 0 on 10
Mar 26 12:16:33 DEBUG[95256]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Ringer On(10) on channel 16 (index 0)
Mar 26 12:16:39 DEBUG[95256]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Ringer On(10) on channel 16 (index 0)
Mar 26 12:16:45 DEBUG[95256]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Ringer On(10) on channel 16 (index 0)
Mar 26 12:16:51 DEBUG[95256]: File chan_zap.c, Line 2431
(zt_handle_event): Got event Ringer On(10) on channel 16 (index 0)
[root at astro asterisk]#

Matteo Brancaleoni
mbrancaleoni at espia.it
Emmegi System Administrator
 
EspiA - EMMEGI Srl - e*solution provider
Uffici: Via Pascoli, 37
20129 Milano - Italy
Sede Legale: Corso Sempione 67
20149 Milano - Italy
Tel. +39 0270633354
Fax. +39 0245487890
http://www.espia.it