SPA525G SPCP Token Issue

[apisano]

Having an issue with a SPA525G registering on a UC540. SPCP Token Issue.  All other phones work with no issue and 540 box working properly.  Multiple factory resets with same result.  Any ideas?

[Steven DiStefano]

Are you sure you are not trying to register more phones than licenses?

Are you certain auto register is still enabled under Telephony-Service?

[apisano]

I will check on those items again.

Thanks.  I will let you know.

[apisano]

32 Licenses.  Other Phones autoregistering.

License issue is gone but phone will not register.

5 other phones of all different models work no issue.

Just this one SPA525G.  Any ideas?

Would not autodiscover as well.  Had to maually enter in the MAC.

[Steven DiStefano]

open a telnet window, type 'term mon'

Unplug and plug in that little rascal...

That alone should reveal the ugly beast further.

Add 'debug tftp events', to see if its getting denied when it looks for its CNF file.

past in the result here.

Then 'term no mon' and 'un all' of course.

perhaps factory reset the phone (from the phone menu) as well to clar out any prior badness.

[apisano]

As requested

banner login ^Cisco Configuration Assistant. Version: 2.2 (5). Wed Dec 01 17:42:
12 EST 2010^

User Access Verification

Username: admin
Password:
UC_540#term mon
UC_540#"term mon"
Translating "term"...domain server (167.206.254.2)

% Bad IP address or host name
% Unknown command or computer name, or unable to find computer address
UC_540#term mon
UC_540#debug tftp events
TFTP Event debugging is on
UC_540#
000730: Dec  2 22:40:14.546: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to down
000731: Dec  2 22:40:15.550: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to down
000732: Dec  2 22:40:24.786: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to down
000733: Dec  2 22:40:27.482: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to up
000734: Dec  2 22:40:28.482: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to up
000735: Dec  2 22:40:38.186: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to down
000736: Dec  2 22:40:39.190: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to up
000737: Dec  2 22:40:40.190: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to up
000738: Dec  2 22:41:09.482: TFTP: Looking for SEP68EFBDCCA2E2.cnf.xml
000739: Dec  2 22:41:09.482: TFTP: Opened system:/its/XMLDefault525G.cnf.xml, fd
14, size 1191 for process 222
000740: Dec  2 22:41:09.486: TFTP: Finished system:/its/XMLDefault525G.cnf.xml,
time 00:00:00 for process 222
000741: Dec  2 22:41:44.970: %SEC-6-IPACCESSLOGP: list 104 denied udp 0.0.0.0(68
) -> 255.255.255.255(67), 1 packet

[Steven DiStefano]

Need to debug ephone registration

I think

Deb phone erg

[apisano]

UC_540#term mon
UC_540#debug ephone er
EPHONE error debugging is enabled
UC_540#
000783: Dec  3 02:41:47.505: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to down
000784: Dec  3 02:41:48.505: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to down
000785: Dec  3 02:41:58.209: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to down
000786: Dec  3 02:42:00.773: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to up
000787: Dec  3 02:42:01.773: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to up
000788: Dec  3 02:42:11.561: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to down
000789: Dec  3 02:42:12.561: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to up
000790: Dec  3 02:42:13.561: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to up
000791: Dec  3 02:42:42.981: TFTP: Looking for SEP68EFBDCCA2E2.cnf.xml
000792: Dec  3 02:42:42.985: TFTP: Opened system:/its/XMLDefault525G.cnf.xml, fd
14, size 1191 for process 222
000793: Dec  3 02:42:42.989: TFTP: Finished system:/its/XMLDefault525G.cnf.xml,
time 00:00:00 for process 222
000794: Dec  3 02:47:15.821: socket 4 fatal error 254! can't read msg header wit
h size -1, fd 4
000795: Dec  3 02:47:15.821:  it's a stale socket! delete it!!

[Steven DiStefano]

Thanks.   We are close.   I typed from my iphone last time.

I meant to say 'debug ephone register'

But I have an idea anyway to try.

Download the latest SPA525G phone load from CCO.  Extract the .bin from it and put it on your desk top.

You will notice its later than whats in the SWP.  Thats good.  7-4-6 is what you want.

So then drag and drop it onto the UC500 icon in your topology view.

This will do 4 things:

TFTP the file to flash

Create the load statements in telephony-service

Add tftp-server statements in the IOS

Create new CNF files (the system/its *****.xml gets updates

I think this may help.

[apisano]

No Luck.  New firmware confirmed in phone status menu as 7.4.6 but still no connectivity after reboot of entire system.

[Steven DiStefano]

debug ephone reg?

[apisano]

UC_540#debug ephone reg
EPHONE registration debugging is enabled
UC_540#
000188: Dec  3 16:10:17.855: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to down
000189: Dec  3 16:10:18.859: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to down
000190: Dec  3 16:10:28.407: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to down
000191: Dec  3 16:10:31.135: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to up
000192: Dec  3 16:10:32.135: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to up
000193: Dec  3 16:10:41.779: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to down
000194: Dec  3 16:10:42.779: %LINK-3-UPDOWN: Interface FastEthernet0/1/3, change
d state to up
000195: Dec  3 16:10:43.779: %LINEPROTO-5-UPDOWN: Line protocol on Interface Fas
tEthernet0/1/3, changed state to up
000196: Dec  3 16:11:12.831: New Skinny socket accepted [2] from 0, sub 1 (5 act
ive)
000197: Dec  3 16:11:12.831: sin_family 2, sin_port 2613, in_addr 10.1.1.11
000198: Dec  3 16:11:12.831: skinny_add_socket 2 10.1.1.11 2613
000199: Dec  3 16:11:13.779: ephone-1[0/6]:SPCPRegisterTokenReq
000200: Dec  3 16:11:13.779: fStationSPCPRegisterTokenReq received on socket 5
000201: Dec  3 16:11:13.779: Invalid Device Id 80009
000202: Dec  3 16:11:13.779: StationSPCPRegisterTokenReject sent on socket 5

[Steven DiStefano]

Is this a SPA525G or a SPA525G2 (physically turn the phone over to read the SKU on the back side)?

What TYPE do you have it defined with in CCA against the ephone?

[apisano]

SPA525G2

[Steven DiStefano]

I feel so silly for not asking this first ;-)

What SW version are you running on UC500 and CCA?

You do know support for that phone came only recently (8.0.2 SWP with CCA 2.2.5).

Show me ephone display too please.