UC560 & ATA186

[sistemas.sede]

Hi,

I am having problems with an ATA186. I have the ATA correctly registered with my UC560 and I can make calls from ip phones to analog phones connected to the ATA, but I can not make calls from analog phone to outside or even to another ip phones, it returns a busy tone. The most strange is I only can make calls to ip phones that previously has called the analog phone, and only once, I mean: ip phone A makes a call to analog phone B correctly, then I can make a call from analog B to ip phone A, but only one, if I try to make the call again, it gives me a busy tone.

Any suggestions?.

Thanks.

[antyeung]

Very strange...

To verify, is the ATA registered using SCCP?

If you enter the digit one-by-one on the analog phone after what digit do you hear the busy tone?

Can you enable the following debugs on the UC500 and place a call to the ATA and then attempt to place a call from the ATA?

debug voip ccapi inout

Attach the 'show run' as well.

--
Anthony Yeung
Technical Marketing Engineer
Small Business Technolog Group

[sistemas.sede]

Very strange...

To verify, is the ATA registered using  SCCP? Yes, it is:

ephone-1[0]  Mac:A8B1.D4FB.0253 TCP socket:[14] activeLine:0 whisperLine:0 REGISTERED in SCCP  ver 1/1 max_streams=0

mediaActive:0  whisper_mediaActive:0 startMedia:0 offhook:0 ringing:0 reset:0 reset_sent:0  paging 0 debug:0 caps:6

IP:192.168.61.12 *  14906 IPv6:241A:1700::F8:1600:0:0 ATA Phone  keepalive 245 max_line 1  available_line 0

button 1: cw:1 ccw:(0  0)

  dn 14 number 505  CH1   IDLE         CH2   IDLE

Preferred Codec:  g711ulaw

Lpcor Type: none  Username: fax965438786 Password: fax1fax1

ephone-2[1]  Mac:B1D4.FB02.5301 TCP socket:[4] activeLine:0 whisperLine:0 REGISTERED in SCCP  ver 1/1 max_streams=0

mediaActive:0  whisper_mediaActive:0 startMedia:0 offhook:0 ringing:0 reset:0 reset_sent:0  paging 0 debug:0 caps:2

IP:192.168.61.12 *  15007 IPv6:349F:1700::54:1700:0:0 ATA Phone  keepalive 245 max_line 1  available_line 0

button 1: cw:1 ccw:(0  0)

  dn 11 number 318  CH1   IDLE         CH2   IDLE

Preferred Codec:  g711ulaw

Lpcor Type: none  Username: fax965449514 Password: fax2fax2

If you enter the digit one-by-one on the  analog phone after what digit do you hear the busy tone? The phone waits until the digit timeout, whatever the length  of the number is.

Can you enable the following debugs on the  UC500 and place a call to the ATA and then attempt to place a call from the  ATA?

debug voip ccapi inout

Debug messages are attached in txt.

Attach the 'show run' as  well.

Thanks.

[antyeung]

This is strange. I see that the phone dials A, B, and the C. Also when the phone goes offhook the ANI is BCD (cisco-ani=BCD). Can you also attach the 'show run'?

Thanks,

Anthony

[sistemas.sede]

Sorry I forgot to upload in the las post. Now it is.

Regards.

[antyeung]

Slowly making more sense. The debugs you collected only show the MWI outcall and didn't capture the logs of the call to/from the ATA. Can you increase the buffer size of the logging? You may need to increase the scroll back buffer on your telnet app as well. If you are using putty go to settings > Window > Lines of scrollback. Set it to 2000. If you want to directly capture the output to a file in putty go to Settings > Session > Logging and select "All Session Output" and specify the file. Enable the following debugs

debug ephone detail mac-address A8B1.D4FB.0253

debug ephone detail mac-addres B1D4.FB02.5301

debug voip ccapi inout

Thanks,

Anthony

[sistemas.sede]

Hi Anthony,

     Attached is the debug's results.

     I have placed three calls, the first one from ip phone to ata, after from ata to ip (ip phone rings) and the last one from ata to ip phone again, in this case the ata returns the busy tone after a few seconds.

Thanks.

[sistemas.sede]

Hi Anthony,

Did you find out something about this??. I have reset the ata but still the same.

Regards,

Antonio Maciá.

[antyeung]

Hi Antonio,

Thanks for the nudge ;-)

This is the first I've seen this behavior.

In the working case I see that CME receives the keypress DTMF tones and passes it up to the CCAPI layer-

# Digit 5 received from the phone

002985: Aug 12 16:00:48.129: ephone-2[1/9][ATAB1D4FB025301]:KeypadButtonMessage 5

003010: Aug 12 16:00:48.133: //83/9AC94E228115/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=83, Digit=5, DigitBeginFlags=0x0,
   Rtp Timestamp=0xBA860, Rtp Expiration=0x0

003018: Aug 12 16:00:48.133: //83/9AC94E228115/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=83, Digit=5, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
003019: Aug 12 16:00:48.133: //83/9AC94E228115/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)

# Digit 0 received from the phone
  
003020: Aug 12 16:00:48.221: ephone-2[1/9][ATAB1D4FB025301]:KeypadButtonMessage 0
...
003033: Aug 12 16:00:48.221: //83/9AC94E228115/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=83, Digit=0, DigitBeginFlags=0x0,
   Rtp Timestamp=0xC2560, Rtp Expiration=0x0
003034: Aug 12 16:00:48.221: //83/9AC94E228115/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=83, Digit=0, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
003035: Aug 12 16:00:48.221: //83/9AC94E228115/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
  
# Digit 1 received from the phone
 
003036: Aug 12 16:00:49.065: ephone-2[1/9][ATAB1D4FB025301]:KeypadButtonMessage 1
...
003089: Aug 12 16:00:49.069: //83/9AC94E228115/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=83, Digit=1, DigitBeginFlags=0x0,
   Rtp Timestamp=0xCA260, Rtp Expiration=0x0
003090: Aug 12 16:00:49.069: //83/9AC94E228115/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=83, Digit=1, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
003091: Aug 12 16:00:49.069: //83/9AC94E228115/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)

In the failed call scenario we see CME receive the DTMF tones however it never passes it to the CCAPI layer to process-

003392: Aug 12 16:00:58.246: ephone-2[1/9][ATAB1D4FB025301]:KeypadButtonMessage 5
003393: Aug 12 16:00:58.246: ephone-2[1/9]:Entering FAC code
003394: Aug 12 16:00:58.246: ephone-2[1/9][ATAB1D4FB025301]:First fac digit received 5
003395: Aug 12 16:00:58.246: SkinnyGetCallState for DN 11 chan 1 SIEZE
003396: Aug 12 16:00:58.246: called DN -1 chan 1, calling DN -1 chan 1 phone 2 s2s:0
003397: Aug 12 16:00:58.246: ephone-2[1/9][ATAB1D4FB025301]:is_auto_local 0 for DN 11
003398: Aug 12 16:00:58.246: ephone-2[1/9][ATAB1D4FB025301]:SkinnyGetToneRef toneRef 0x2B callRef 0x2B
003399: Aug 12 16:00:58.246: ephone-2[1/9]:SkinnyPhoneTone: StopTone sent: normal line=1 ref=43 tone=0x0
003400: Aug 12 16:00:58.246: ephone-2[1/9][ATAB1D4FB025301]:Not matched at first digit 5
003401: Aug 12 16:00:58.246: ephone-2[1/9]:
003402: Aug 12 16:00:58.246: bulk_sd_search_lists:Entry: digit:5, prefix= # state=1, index= 0
003403: Aug 12 16:00:58.246: ephone-2[1/9]:
003404: Aug 12 16:00:58.246: bulk_sd_search_lists:Exit : digit:5, prefix= # state=7, index= 1, retval = 1
003405: Aug 12 16:00:58.246: ephone-2[1/9][ATAB1D4FB025301]:
003406: Aug 12 16:00:58.246: bulk_speeddial_check_new_digit: button= 5 state FAIL_MATCH
003407: Aug 12 16:00:58.246: ephone-2[1/9][ATAB1D4FB025301]:invalid DN SignalKeyPad button 5
003408: Aug 12 16:00:58.486: ephone-2[1/9][ATAB1D4FB025301]:KeypadButtonMessage 0
003409: Aug 12 16:00:58.486: ephone-2[1/9]:Entering FAC code
003410: Aug 12 16:00:58.486: ephone-2[1/9]:At fac_dial NOMATCHED/NORMAL state
003411: Aug 12 16:00:58.486: ephone-2[1/9]:
003412: Aug 12 16:00:58.486: bulk_sd_search_lists:Entry: digit:0, prefix= # state=7, index= 1
003413: Aug 12 16:00:58.486: ephone-2[1/9][ATAB1D4FB025301]:
003414: Aug 12 16:00:58.486: bulk_sd_search_lists: button= 0 state NOT_IN_SD
003415: Aug 12 16:00:58.486: ephone-2[1/9][ATAB1D4FB025301]:invalid DN SignalKeyPad button 0
003416: Aug 12 16:00:58.726: ephone-2[1/9][ATAB1D4FB025301]:KeypadButtonMessage 1

Despite CME receiving the DTMF digits the voice component of the router is not able to register that it received any DTMF digits since it was not passed to CCAPI. What was even more suspucious was the following after each keypress- "invalid DN  SignalKeyPad button".

Upon further investigation you are hitting CSCtd07228. If you upgrade your IOS to 15.0(1)XA02 (via SWP 8.0.2) or 15.0(1)XA3a (via SWP 8.0.4) it should fix the problem.

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

Thanks,

Anthony

[sistemas.sede]

Hi Anthony,

Thanks for the answer. The SWPack 8.0.4 is not available to download yet. I have followed the link you gave me and although the release date is August 2010, it is not available either at the website https://upload.cisco.com/cgi-bin/swc/fileexg/main.cgi?CONTYPES=UC500 or in the Cisco's support center. Do you know when will be published?

Regards.

[antyeung · Accepted solution]

Hi Antonio,

SWP 8.0.4 should be available by the end of this week. I'll also post an update when it has been officially posted.

Thanks,

Anthony

[sistemas.sede]

Solved!.

As you said, updating to the last IOS fix the problem.

Thanks!.

[antyeung]

Good to hear! Thank you for your patience.

-Anthony

[csco10074200]

Hi, sorry to trouble you but i am having the exact same issue, can you please

confirm the IOS image that resolved this as already running 8.0.2

[Antonio Macia]

Hi, download the swpack 8.0.4 or later if available. I'm running the IOS 15.0(1)XA3a, included in the 8.0.4 and as you have read, it solved the issue.

Regards.