SPA122 with extra digit in DTMF buffer

[braymond1963]

Hi all,

I ran into a very weird issue where the ATA sent 9 digits instead of the usual 10 and the next set of calls had the missing 10th digit of the previous call prepended.

My dialplan is as follow (for both FXS lines):

(*xx|[34689]11S0|[2-9]xxxxxxxxxS0|1xxx[2-9]xxxxxxS0|310xxxxS0|xxxxxxxxxxxx.)

The customer dialed a 10-digit number but for some obscure reason, only 9 digits got sent. For example:

First call: 8005551212 but ATA dialed 800555121

Next call: 8005551212 but ATA dialed 2800555121

Next call: 6 and ATA dials 2

A restart cleared the condition.

Anyone ran into this before?

The config is mostly defaults so did not play with the interdigit timeouts or DTMF related parameters. I am running 1.3.1 firmware

Thanks,

Benoit

[nseto]

We haven't seen this, but if you see this again, we'd like to know about it. 

See if there's a way this can be reproduced if possible.  Thanks.

[braymond1963]

Hi,

As a Sr. Support Architect with 25 years in software support, I can tell you that if it happened, it means a bug in the firmware when a (rare) condition is met.

It happened to us but could have happened to others who just decided to just reboot the device. Waiting on us to report the issue again is not really productive if you want the product to meet high quality standards as I expect from Cisco.

Is there anything I should enabled or watch for to help diagnose in the event it happens again?

You guys have the source code so should be in a much better position to analyze the problem. I think I have framed it well enough to give some insights to the Engineering team so they can troubleshoot it.

Thanks for your cooperation.

Benoit

[Leif Mathis Gaup]

I noticed the same behavior this on my test-unit. On my unit I now run 1.3.2-XU (014), I did not see this repalcement of first digit with previous firmware, as I skipped the first 1.3.2-upgrade due to vacation.

My customers using the SPA122 are still fw SPA122-1.3.1(003) and must remain there until my test-unit starts behaving.

I just rebooted the device and the first 4 calls was correct, and the 5th had inserted the first digit in previous number as first digit. Any hints as to where to start debug? I get nothing useful in the debugging on my syslog-server, set up with Priority level 7.

[nseto]

See if you can capture more with syslog and debug turned on.

https://supportforums.cisco.com/docs/DOC-9862

I will ask the developer if there's something more that can be turned on to see why this is happening.  Thanks.

[Leif Mathis Gaup]

Had to make 8 calls to get the faulty behavior today.

I have syslog-server on my pc, as I might need to debug issues. I did set logging for debug, both kernel and system is set with priority debug. Debug for last two few minutes and a few calls below, the final call is the one that ended up with extra digit in front.

2013-08-20T09:42:49+02:00 SPA122 [17209635.168000] voice_release, chan: 0
2013-08-20T09:42:49+02:00 SPA122 [17209635.212000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:42:49+02:00 SPA122 [17209635.216000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:42:49+02:00 SPA122 [17209635.220000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:42:49+02:00 SPA122 [17209635.224000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:42:49+02:00 SPA122 [17209635.228000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:42:49+02:00 SPA122 [17209635.232000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:42:51+02:00 SPA122 [17209637.744000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:52+02:00 SPA122 [17209637.936000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:42:52+02:00 SPA122 [17209638.048000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:52+02:00 SPA122 [17209638.192000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:42:52+02:00 SPA122 [17209638.288000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:52+02:00 SPA122 [17209638.444000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:42:52+02:00 SPA122 [17209638.540000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:52+02:00 SPA122 [17209638.748000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:42:53+02:00 SPA122 [17209638.840000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:53+02:00 SPA122 [17209639.044000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:42:53+02:00 SPA122 [17209639.096000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:53+02:00 SPA122 [17209639.192000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:42:53+02:00 SPA122 [17209639.288000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:53+02:00 SPA122 [17209639.488000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:42:54+02:00 SPA122 [17209640.688000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:42:55+02:00 SPA122 [17209640.844000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:04+02:00 SPA122 msgswitchd[298]:   MSGSWD RTCP Reqt len 12 Data 2,1093392736,382,0
2013-08-20T09:43:04+02:00 SPA122 msgswitchd[298]:   MSGSWD RTCP Reqt len 12 Data 2,0,3778184,0
2013-08-20T09:43:04+02:00 SPA122 [17209650.348000] voice_release, chan: 0
2013-08-20T09:43:09+02:00 SPA122 [17209655.512000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:43:09+02:00 SPA122 [17209655.516000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:43:09+02:00 SPA122 [17209655.520000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:43:09+02:00 SPA122 [17209655.524000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:43:09+02:00 SPA122 [17209655.528000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:43:09+02:00 SPA122 [17209655.532000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:43:11+02:00 SPA122 [17209657.112000] voice_release, chan: 0
2013-08-20T09:43:11+02:00 SPA122 [17209657.156000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:43:11+02:00 SPA122 [17209657.160000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:43:11+02:00 SPA122 [17209657.164000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:43:11+02:00 SPA122 [17209657.168000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:43:11+02:00 SPA122 [17209657.172000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:43:11+02:00 SPA122 [17209657.176000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:43:13+02:00 SPA122 [17209659.688000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:14+02:00 SPA122 [17209659.892000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:14+02:00 SPA122 [17209660.084000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:14+02:00 SPA122 [17209660.240000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:15+02:00 SPA122 [17209661.440000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:15+02:00 SPA122 [17209661.648000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:15+02:00 SPA122 [17209661.744000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:16+02:00 SPA122 [17209661.884000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:16+02:00 SPA122 [17209661.992000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:16+02:00 SPA122 [17209662.136000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:16+02:00 SPA122 [17209662.244000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:16+02:00 SPA122 [17209662.340000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:16+02:00 SPA122 [17209662.484000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:16+02:00 SPA122 [17209662.640000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:16+02:00 SPA122 [17209662.736000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:17+02:00 SPA122 [17209662.844000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:24+02:00 SPA122 [17209669.840000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:24+02:00 SPA122 [17209670.044000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:24+02:00 SPA122 [17209670.536000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:24+02:00 SPA122 [17209670.740000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:25+02:00 SPA122 [17209670.836000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:25+02:00 SPA122 [17209670.992000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:25+02:00 SPA122 [17209671.088000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:25+02:00 SPA122 [17209671.248000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:25+02:00 SPA122 [17209671.344000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:25+02:00 SPA122 [17209671.484000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:25+02:00 SPA122 [17209671.592000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:25+02:00 SPA122 [17209671.736000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:26+02:00 SPA122 [17209671.844000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:26+02:00 SPA122 [17209671.944000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:26+02:00 SPA122 [17209672.084000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:26+02:00 SPA122 [17209672.148000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:34+02:00 SPA122 [17209680.472000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:43:34+02:00 SPA122 [17209680.476000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:43:34+02:00 SPA122 [17209680.480000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:43:34+02:00 SPA122 [17209680.484000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:43:34+02:00 SPA122 [17209680.488000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:43:34+02:00 SPA122 [17209680.492000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:43:36+02:00 SPA122 [17209682.368000] voice_release, chan: 0
2013-08-20T09:43:36+02:00 SPA122 [17209682.416000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:43:36+02:00 SPA122 [17209682.420000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:43:36+02:00 SPA122 [17209682.424000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:43:36+02:00 SPA122 [17209682.428000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:43:36+02:00 SPA122 [17209682.432000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:43:36+02:00 SPA122 [17209682.436000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:43:39+02:00 SPA122 [17209684.988000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:39+02:00 SPA122 [17209685.144000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:39+02:00 SPA122 [17209685.240000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:39+02:00 SPA122 [17209685.388000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:39+02:00 SPA122 [17209685.492000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:39+02:00 SPA122 [17209685.636000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:40+02:00 SPA122 [17209685.844000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:40+02:00 SPA122 [17209686.036000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:40+02:00 SPA122 [17209686.144000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:40+02:00 SPA122 [17209686.288000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:40+02:00 SPA122 [17209686.384000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:40+02:00 SPA122 [17209686.492000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:40+02:00 SPA122 [17209686.592000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:41+02:00 SPA122 [17209686.840000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:41+02:00 SPA122 [17209686.936000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:43:41+02:00 SPA122 [17209687.044000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:43:46+02:00 SPA122 msgswitchd[298]:   MSGSWD RTCP Reqt len 12 Data 2,0,5,0
2013-08-20T09:43:48+02:00 SPA122 [17209694.764000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:43:48+02:00 SPA122 [17209694.768000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:43:48+02:00 SPA122 [17209694.772000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:43:48+02:00 SPA122 [17209694.776000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:43:48+02:00 SPA122 [17209694.780000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:43:48+02:00 SPA122 [17209694.784000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:43:50+02:00 SPA122 [17209696.068000] voice_release, chan: 0
2013-08-20T09:43:50+02:00 SPA122 [17209696.108000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:43:50+02:00 SPA122 [17209696.112000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:43:50+02:00 SPA122 [17209696.116000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:43:50+02:00 SPA122 [17209696.120000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:43:50+02:00 SPA122 [17209696.124000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:43:50+02:00 SPA122 [17209696.128000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:43:55+02:00 SPA122 [17209701.064000]  In cordless Driver Codec 8 and str PCMA/8000  chan 1
2013-08-20T09:43:55+02:00 SPA122 [17209701.068000]  In cordless Driver Codec 0 and str PCMU/8000  chan 1
2013-08-20T09:43:55+02:00 SPA122 [17209701.072000]  In cordless Driver Codec 18 and str G.729/8000  chan 1
2013-08-20T09:43:55+02:00 SPA122 [17209701.076000]  In cordless Driver Codec 100 and str NSE/8000  chan 1
2013-08-20T09:43:55+02:00 SPA122 [17209701.080000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 1
2013-08-20T09:43:55+02:00 SPA122 [17209701.084000]  In cordless Driver Codec 255 and str   chan 1
2013-08-20T09:43:56+02:00 SPA122 msgswitchd[298]:   MSGSWD RTCP Reqt len 12 Data 2,0,0,0
2013-08-20T09:43:56+02:00 SPA122 [17209702.044000] voice_release, chan: 0
2013-08-20T09:43:56+02:00 SPA122 msgswitchd[298]:   MSGSWD RTCP Reqt len 12 Data 2,0,0,0
2013-08-20T09:43:56+02:00 SPA122 [17209702.104000] voice_release, chan: 1
2013-08-20T09:44:07+02:00 SPA122 [17209713.616000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:44:07+02:00 SPA122 [17209713.620000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:44:07+02:00 SPA122 [17209713.624000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:44:07+02:00 SPA122 [17209713.628000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:44:07+02:00 SPA122 [17209713.632000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:44:07+02:00 SPA122 [17209713.636000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:44:09+02:00 SPA122 [17209715.264000] voice_release, chan: 0
2013-08-20T09:44:09+02:00 SPA122 [17209715.308000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:44:09+02:00 SPA122 [17209715.312000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:44:09+02:00 SPA122 [17209715.316000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:44:09+02:00 SPA122 [17209715.320000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:44:09+02:00 SPA122 [17209715.324000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:44:09+02:00 SPA122 [17209715.328000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:44:12+02:00 SPA122 [17209718.440000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:12+02:00 SPA122 [17209718.648000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:12+02:00 SPA122 [17209718.696000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:13+02:00 SPA122 [17209718.936000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:13+02:00 SPA122 [17209719.044000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:13+02:00 SPA122 [17209719.188000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:13+02:00 SPA122 [17209719.344000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:13+02:00 SPA122 [17209719.488000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:13+02:00 SPA122 [17209719.792000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:14+02:00 SPA122 [17209719.992000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:14+02:00 SPA122 [17209720.088000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:14+02:00 SPA122 [17209720.240000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:14+02:00 SPA122 [17209720.336000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:14+02:00 SPA122 [17209720.540000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:14+02:00 SPA122 [17209720.636000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0xff
2013-08-20T09:44:14+02:00 SPA122 [17209720.744000] voice_send_dtmf, chan: 0, MaxEvtDuration: 0x0
2013-08-20T09:44:22+02:00 SPA122 msgswitchd[298]:   MSGSWD RTCP Reqt len 12 Data 2,0,5,0
2013-08-20T09:44:26+02:00 SPA122 [17209732.616000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:44:26+02:00 SPA122 [17209732.620000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:44:26+02:00 SPA122 [17209732.624000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:44:26+02:00 SPA122 [17209732.628000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:44:26+02:00 SPA122 [17209732.632000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:44:26+02:00 SPA122 [17209732.636000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:44:27+02:00 SPA122 [17209733.008000] voice_release, chan: 0
2013-08-20T09:44:27+02:00 SPA122 [17209733.048000]  In cordless Driver Codec 8 and str PCMA/8000  chan 0
2013-08-20T09:44:27+02:00 SPA122 [17209733.052000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0
2013-08-20T09:44:27+02:00 SPA122 [17209733.056000]  In cordless Driver Codec 18 and str G.729/8000  chan 0
2013-08-20T09:44:27+02:00 SPA122 [17209733.060000]  In cordless Driver Codec 100 and str NSE/8000  chan 0
2013-08-20T09:44:27+02:00 SPA122 [17209733.064000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0
2013-08-20T09:44:27+02:00 SPA122 [17209733.068000]  In cordless Driver Codec 255 and str   chan 0
2013-08-20T09:44:32+02:00 SPA122 msgswitchd[298]:   MSGSWD RTCP Reqt len 12 Data 2,0,5,0
2013-08-20T09:45:55+02:00 SPA122 syslog-ng[132]: STATS: dropped 0

[Leif Mathis Gaup]

I have narrowed this issue a bit down. A few handsets are behaving correctly, but with ie  Zodiac Elegance 20 its mostly a matter of hitting the numbers fairly fast. DTMF-mode strict or normal makes no difference

Still waiting on another ATA to get 1.3.1 firmware to verify with my Zodiac.

Hope this helps out a bit, as it seems to be not a big an issue as I thought is was, but then again I have no control ove rmy customers handsets.

edit: minor update, did not manage to reproduce with fw1.3.1 using the Zodiac, only with fw1.3.2.

[nseto]

I've forwarded the info to the developer.  Let me know if entering the numbers a bit more slowly resolves the issue.  I can pass that info to the developer.  Thanks.

[daleedillinger]

probably hitting the same issue:

https://supportforums.cisco.com/discussion/12748761/uchconnecteptonode281-error-connecting-ep-voip-0-node-0-93

same isp btw (videotron) but don't think it's relevant.

[nseto]

Dev is asking for the voice syslog.

https://supportforums.cisco.com/docs/DOC-26697

Thanks.

[braymond1963]

I have just got another instance where I have an extra digit. It just happened like that ... I even checked the phone since it is my unit at home and the digits were all there in the phone's memory.

I had syslog with debug turned on. What I noticed are these lines:

Dec  4 09:26:45 modemcable026.13-83-70.mc.videotron.ca kernel: [ 1036.624210] ath_bstuck_tasklet: stuck beacon; resetting (bmiss count 36)

Dec  4 09:26:45 modemcable026.13-83-70.mc.videotron.ca kernel: [ 1037.272997] ath_bstuck_tasklet: stuck beacon; resetting (bmiss count 36)

Dec  4 09:26:49 modemcable026.13-83-70.mc.videotron.ca kernel: [ 1041.301060] ath_bstuck_tasklet: stuck beacon; resetting (bmiss count 36)

Note the time ... This was written just after I performed a test call with SIP debug turned ON and notice the "real" time of the unit (We are on Dec 8 but the kernel reports Dec 4th):

Dec  8 17:37:12 INVITE sip: 6450602843@bitell.alarmedesjardins.ca SIP/2.0#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-28e37eed#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>#015#012Remote-Party-ID: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;screen=yes;party=calling#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 101 INVITE#015#012Max-Forwards: 70#015#012Contact: "Benoit Raymond" <4504324671>#015#012Expires: 240#015#012User-Agent: Cisco/SPA122-1.3.2(014)#015#012Content-Length: 332#015#012Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER#015#012Supported: replaces#015#012Content-Type: application/sdp#015#012#015#012v=0#015#012o=- 11940391 11940391 IN IP4 70.83.13.26#015#012s=-#015#012c=IN IP4 70.83.13.26#015#012t=0 0#015#012m=audio 32794 RTP/AVP 0 2 8 18 100 101#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:2 G726-32/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:18 G729/8000#015#012a=rtpmap:100 NSE/8000#015#012a=fmtp:100 192-193#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=ptime:20#015#012a=sendrecv#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(370)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(370)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca SIP/2.0 100 Trying#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-28e37eed#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 101 INVITE#015#012User-Agent: FreeSWITCH-mod_sofia/1.2.3+git~20120920T220849Z~f718a5e8e6#015#012Content-Length: 0#015#012#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(883)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(883)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca SIP/2.0 407 Proxy Authentication Required#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-28e37eed#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>;tag=UpXSN9gBH5vZN#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 101 INVITE#015#012User-Agent: FreeSWITCH-mod_sofia/1.2.3+git~20120920T220849Z~f718a5e8e6#015#012Accept: application/sdp#015#012Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE#015#012Supported: timer, precondition, path, replaces#015#012Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer#015#012Proxy-Authenticate: Digest realm="bitell.alarmedesjardins.ca", nonce="47b7fed6-6059-11e3-8ac5-ef68b1f82d4e", algorithm=MD5, qop="auth"#015#012Content-Length: 0#015#012#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(479)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(479)

Dec  8 17:37:12 ACK sip: 6450602843@bitell.alarmedesjardins.ca SIP/2.0#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-28e37eed#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>;tag=UpXSN9gBH5vZN#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 101 ACK#015#012Max-Forwards: 70#015#012Contact: "Benoit Raymond" <4504324671>#015#012User-Agent: Cisco/SPA122-1.3.2(014)#015#012Content-Length: 0#015#012#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(1310)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(1310)

Dec  8 17:37:12 INVITE sip: 6450602843@bitell.alarmedesjardins.ca SIP/2.0#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-8ae7d9f9#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>#015#012Remote-Party-ID: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;screen=yes;party=calling#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 102 INVITE#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest username="4504324671",realm="bitell.alarmedesjardins.ca",nonce="47b7fed6-6059-11e3-8ac5-ef68b1f82d4e",uri="sip:6450602843@bitell.alarmedesjardins.ca",algorithm=MD5,response="02d853ab242b4a7040fb28918d0e9028",qop=auth,nc=00000001,cnonce="2359aaac"#015#012Contact: "Benoit Raymond" <4504324671>#015#012Expires: 240#015#012User-Agent: Cisco/SPA122-1.3.2(014)#015#012Content-Length: 332#015#012Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER#015#012Supported: replaces#015#012Content-Type: application/sdp#015#012#015#012v=0#015#012o=- 11940391 11940391 IN IP4 70.83.13.26#015#012s=-#015#012c=IN IP4 70.83.13.26#015#012t=0 0#015#012m=audio 32794 RTP/AVP 0 2 8 18 100 101#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:2 G726-32/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:18 G729/8000#015#012a=rtpmap:100 NSE/8000#015#012a=fmtp:100 192-193#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=ptime:20#015#012a=sendrecv#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(370)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(370)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca SIP/2.0 100 Trying#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-8ae7d9f9#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 102 INVITE#015#012User-Agent: FreeSWITCH-mod_sofia/1.2.3+git~20120920T220849Z~f718a5e8e6#015#012Content-Length: 0#015#012#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(1203)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(1203)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca SIP/2.0 200 OK#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-8ae7d9f9#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>;tag=vZpjQ41eeeKjH#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 102 INVITE#015#012Contact: <6450602843>#015#012User-Agent: FreeSWITCH-mod_sofia/1.2.3+git~20120920T220849Z~f718a5e8e6#015#012Accept: application/sdp#015#012Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE#015#012Supported: timer, precondition, path, replaces#015#012Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer#015#012Content-Type: application/sdp#015#012Content-Disposition: session#015#012Content-Length: 249#015#012Remote-Party-ID: "6450602843" <>6450602843@bitell.alarmedesjardins.ca>;party=calling;privacy=off;screen=no#015#012#015#012v=0#015#012o=FreeSWITCH 1386511326 1386511327 IN IP4 72.55.176.171#015#012s=FreeSWITCH#015#012c=IN IP4 72.55.176.171#015#012t=0 0#015#012m=audio 30906 RTP/AVP 0 101#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-16#015#012a=silenceSupp:off - - - -#015#012a=ptime:20#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 Already has a RTP channel.

Dec  8 17:37:12 Already has a RTP channel.

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca *** RTCP already running on channel 0.

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca *** RTCP already running on channel 0.

Dec  8 17:37:12 Already has a RTP channel.

Dec  8 17:37:12 Already has a RTP channel.

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(761)

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(761)

Dec  8 17:37:12 ACK sip: 6450602843@72.55.176.171:5060;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-b4342b1a#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>;tag=vZpjQ41eeeKjH#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 102 ACK#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest username="4504324671",realm="bitell.alarmedesjardins.ca",nonce="47b7fed6-6059-11e3-8ac5-ef68b1f82d4e",uri="sip:6450602843@bitell.alarmedesjardins.ca",algorithm=MD5,response="02d853ab242b4a7040fb28918d0e9028",qop=auth,nc=00000001,cnonce="2359aaac"#015#012Contact: "Benoit Raymond" <4504324671>#015#012User-Agent: Cisco/SPA122-1.3.2(014)#015#012Content-Length: 0#015#012#015

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:12 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:15 modemcable117.223-82-70.mc.videotron.ca [0]Reg Addr Change(0) 0:5060->abb03748:5060

Dec  8 17:37:15 modemcable117.223-82-70.mc.videotron.ca [0]Reg Addr Change(0) 0:5060->abb03748:5060

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca uchDisconnectEpFromNode(), invalid node ID: -1

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca uchDisconnectEpFromNode(), invalid node ID: -1

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(760)

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca [1]->72.55.176.171: 5060(760)

Dec  8 17:37:16 BYE sip: 6450602843@72.55.176.171:5060;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-ff8aa60e#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>;tag=vZpjQ41eeeKjH#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 103 BYE#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest username="4504324671",realm="bitell.alarmedesjardins.ca",nonce="47b7fed6-6059-11e3-8ac5-ef68b1f82d4e",uri="sip:6450602843@72.55.176.171:5060",algorithm=MD5,response="5382e04b670647dd35157e0187b61ead",qop=auth,nc=00000002,cnonce="2359aaac"#015#012User-Agent: Cisco/SPA122-1.3.2(014)#015#012P-RTP-Stat: PS=196,OS=31360,PR=157,OR=25120,PL=0,JI=0,LA=0,DU=4,EN=G711u,DE=G711u#015#012Content-Length: 0#015#012#015

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(539)

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca [1]<<72.55.176.171: 5060(539)

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca SIP/2.0 200 OK#015#012Via: SIP/2.0/UDP 70.83.13.26:5062;branch=z9hG4bK-ff8aa60e#015#012From: "Benoit Raymond" <>4504324671@bitell.alarmedesjardins.ca>;tag=1b2b8b03f1901d7fo1#015#012To: <>6450602843@bitell.alarmedesjardins.ca>;tag=vZpjQ41eeeKjH#015#012Call-ID: 9a97ec53-c346ac6f@70.83.13.26#015#012CSeq: 103 BYE#015#012User-Agent: FreeSWITCH-mod_sofia/1.2.3+git~20120920T220849Z~f718a5e8e6#015#012Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE#015#012Supported: timer, precondition, path, replaces#015#012Content-Length: 0#015#012#015

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca 

Dec  8 17:37:16 modemcable026.13-83-70.mc.videotron.ca 

I also tried dialing slowly and I have a dialplan that triggers the call after the 10th digit and it did it after my 10th digit so I know the ATA recognizes how many digits I entered.

Hope this helps