<!DOCTYPE html PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN">
<html>
<head>
<meta content="text/html;charset=ISO-8859-7" http-equiv="Content-Type">
</head>
<body bgcolor="#ffffff" text="#000066">
<font size="-1"><font face="Trebuchet MS">Hi All,<br>
i am getting back on this because i think i 'll go crazy!<br>
I have this case running over a TAC and they can't find anything!.<br>
I have upgraded these two phones 3 times; last time was to the latest
firmware.<br>
1. with the first firmware they were resetting every 46 minutes<br>
</font></font><font size="-1"><font face="Trebuchet MS">2. with the
second firmware they were resetting every 42 minutes<br>
</font></font><font size="-1"><font face="Trebuchet MS">3. with the
latest firmware they were resetting every 54 minutes!<br>
<br>
<br>
And it is so precise!!<br>
In the same LAN, 7940 do not get resetted (i can see them registered
for a day or more!)<br>
The 2 7911, tested on any other site, DO NOT get unregistered!<br>
TAC has asked for captures from the switches but there seem to be no
problem (captured traffic from the CUCME interface and the ipphone
interface).<br>
<br>
Anyone has faced something like this before?<br>
What device logs would be good to gather over the phone<br>
<br>
Thanks<br>
Anthony<br>
</font></font><br>
Ryan Ratliff wrote:
<blockquote cite="mid:F30DD08D-9D3F-4CA4-A001-20AF1FCDA98B@cisco.com"
type="cite">I don't know what that error means I'm afraid.
<br>
<br>
-Ryan
<br>
<br>
On May 18, 2009, at 11:24 AM, Anthony Kouloglou wrote:
<br>
<br>
Hi Ryan,
<br>
what is the possibility that both phones have the same problem?
<br>
I just bought only 2 of these for the remote site so i can't really
tell if another one has the same problems?
<br>
What is this error that i have enboldened and mark with red?
<br>
<br>
Thanks
<br>
Akoul
<br>
<br>
Ryan Ratliff wrote:
<br>
<blockquote type="cite"><br>
May 18 17:17:41: %IPPHONE-6-REG_ALARM: 25: Name=SEP001FCAE9332E Load=
SCCP11.8-3-3S Last=Initialized
<br>
<br>
This means the phone either crashed or lost power. You may need to
get some eyes on these phones or try and get logs from the phone itself
to get more details.
<br>
<br>
-Ryan
<br>
<br>
On May 18, 2009, at 11:00 AM, Anthony Kouloglou wrote:
<br>
<br>
Hi Ryan,
<br>
i have also opened a TAC for this but from my experience, cisco-voip
list is much more quicker!!
<br>
Here is a debugging that they have told me to do.
<br>
<br>
May 18 17:17:38: socket 10 fatal error 254! can't read msg header with
size -1, fd 10 phone tag 20 device name SEP001FCAE9332E state 1
keepalive 74
<br>
May 18 17:17:41: New Skinny socket accepted [1] from 0, sub 1 (23
active)
<br>
May 18 17:17:41: sin_family 2, sin_port 50219, in_addr 10.43.3.17
<br>
May 18 17:17:41: skinny_add_socket 1 10.43.3.17 50219
<br>
May 18 17:17:41: %IPPHONE-6-REG_ALARM: 25: Name=SEP001FCAE9332E Load=
SCCP11.8-3-3S Last=Initialized
<br>
May 18 17:17:41:
<br>
Skinny StationAlarmMessage on socket [20] 10.43.3.17 SEP001FCAE9332E
<br>
May 18 17:17:41: severityInformational p1=0 [0x0] p2=0 [0x0]
<br>
May 18 17:17:41: 25: Name=SEP001FCAE9332E Load= SCCP11.8-3-3S
Last=Initialized
<br>
May 18 17:17:41: ephone-(20)[20] StationRegisterMessage (19/21/37) from
10.43.3.17
<br>
May 18 17:17:41: ephone-(20)[20] Register StationIdentifier DeviceName
SEP001FCAE9332E
<br>
May 18 17:17:41: ephone-(20)[20] StationIdentifier Instance 0
deviceType 307
<br>
May 18 17:17:41: ephone-20[19/10]:stationIpAddr 10.43.3.17
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]:maxStreams 5
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]:From Phone raw
protocol Ver 0x8570000C
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]:protocol Ver
0x8570000C
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]:phone-size 13216
dn-size 784
<br>
May 18 17:17:41: ephone-(20) Allow any Skinny Server IP address
192.168.101.1
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]:Found entry 19 for
001FCAE9332E
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]:socket change 10 to
20
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]:DisAssociate: Closed
socket 10 while REGISTERED
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]: DN out-of-service
for DN 20 chan 1
<br>
May 18 17:17:41: ephone-20[19/10][SEP001FCAE9332E]: DN out-of-service
for DN 20 chan 2
<br>
May 18 17:17:41: Bring down DN 20 chan 1 (14332) with the following
traceback -Traceback= 0x40CB2104 0x40CB26FC 0x40CB51CC 0x40CE63A0
0x40CE6DA0 0x40CE9074 0x40CA0FCC
<br>
May 18 17:17:41: Skinny DN 20 chan 1 state change to DOWN
<br>
May 18 17:17:41: Bring down DN 20 chan 2 (14332) with the following
traceback -Traceback= 0x40CB2104 0x40CB26FC 0x40CB51CC 0x40CE63A0
0x40CE6DA0 0x40CE9074 0x40CA0FCC
<br>
May 18 17:17:41: Skinny DN 20 chan 2 state change to DOWN
<br>
May 18 17:17:41: %IPPHONE-6-UNREGISTER_ABNORMAL:
ephone-20:SEP001FCAE9332E IP:10.43.3.17 Socket:10 DeviceType:Phone has
unregistered abnormally.
<br>
May 18 17:17:41: ephone-20[19/-1][SEP001FCAE9332E]:FAILED: CLOSED old
socket -1
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:phone
SEP001FCAE9332E re-associate OK on socket [20]
<br>
May 18 17:17:41: %IPPHONE-6-REGISTER: ephone-20:SEP001FCAE9332E
IP:10.43.3.17 Socket:20 DeviceType:Phone has registered.
<br>
May 18 17:17:41: Phone 19 socket 20
<br>
May 18 17:17:41: Skinny Local IP address = 192.168.101.1 on port 2000
<br>
<br>
May 18 17:17:41: Skinny Phone IP address = 10.43.3.17 50219
<br>
May 18 17:17:41: ephone-20 If FastEthernet0/0 ETHERNET 10.43.3.17 via
192.168.101.254
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:Signal protocol ver
9 to phone with ver 12
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:Date Format D/M/Y
<br>
May 18 17:17:41: ephone-20[19/20]:RegisterAck sent to sockettype ephone
socket 20: keepalive period 60 use sccp-version 9
<br>
May 18 17:17:41: ephone-20[19/20]:CapabilitiesReq sent
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:Skinny IP port 3500
set for socket [20]
<br>
May 18 17:17:41: ephone-20[19/20]:ButtonTemplateReqMessage
<br>
May 18 17:17:41: ephone-20[19/20]:ButtonTemplateReqMessage waiting for
Caps
<br>
May 18 17:17:41: ephone-20[19/20]:StationSoftKeyTemplateReqMessage
<br>
May 18 17:17:41: ephone-20[19/20]:StationSoftKeyTemplateResMessage
<br>
May 18 17:17:41: ephone-20[19/20]:StationSoftKeySetReqMessage
<br>
May 18 17:17:41: ephone-20[19/20]:StationSoftKeySetResMessage
<br>
May 18 17:17:41: ephone-20[19/20]:StationConfigStatReqMessage
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationConfigStatMessage sent for
device SEP001FCAE9332E (40/280)
<br>
May 18 17:17:41: ephone-20[19/20]:CapabilitiesRes received
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:Caps list 9
<br>
WideBand_256K 40 ms, is_mtp 0
<br>
G711Ulaw64k 40 ms, is_mtp 0
<br>
G711Alaw64k 40 ms, is_mtp 0
<br>
ILBC 60 ms, is_mtp 0
<br>
G729AnnexB 60 ms, is_mtp 0
<br>
G729AnnexAwAnnexB 60 ms, is_mtp 0
<br>
G729 60 ms, is_mtp 0
<br>
G729AnnexA 60 ms, is_mtp 0
<br>
Unrecognized Media Type 257 1 ms, is_mtp 0
<br>
<br>
May 18 17:17:41: ephone-20[19/20]:Process pending button template
<br>
May 18 17:17:41: ephone-20[19/20]:ButtonTemplateReqMessage
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationButtonTemplateReqMessage set
max presentation to 2
<br>
May 18 17:17:41: ephone-20[19/20]:CheckAutoReg
<br>
May 18 17:17:41: ephone-20[19/20]:AutoReg is disabled
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:Setting 1 lines 4
speed-dials on phone (max_line 1)
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:First Speed Dial
Button location is 3 (0)
<br>
May 18 17:17:41: ephone-20[19/20]:ButtonTemplate lines=1 speed=4
buttons=6 offset=0
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:ButtonTemplate
buttonCount=6 totalButtonCount=6 buttonOffset=0
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:Configured 4 speed
dial buttons
<br>
May 18 17:17:41: ephone-20[19/20]:MediaPathEventMessage
<br>
May 18 17:17:41: ephone-20[19/20]:MediaPathEventMessage
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationLineStatReqMessage from ephone
line 1
<br>
May 18 17:17:41: ephone-20[19/20]:StationLineStatReqMessage ephone line
1 DN 20 = 14332 desc = 14332 label =
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationLineStatResMessage sent to
ephone (1 of 1)
<br>
May 18 17:17:41: ephone-20[19/20]:SkinnyCompleteRegistration
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]: DN in-service for
DN 20 chan 1
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]: DN in-service for
DN 20 chan 2
<br>
May 18 17:17:41: Skinny DN 20 chan 1 state change to UP
<br>
May 18 17:17:41: Skinny DN 20 chan 2 state change to UP
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationForwardStatReqMessage line 1
from ephone
<br>
May 18 17:17:41: Skinny StationForwardStatMessage line 1 sent on ephone
socket [20] for ephone-20
<br>
May 18 17:17:41: activeForward 0 AllActive 0 BusyActive 0
NoAnswerActive 0
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationSpeedDialStatReqMessage speed
1
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:No speed-dial set 1
<br>
May 18 17:17:41: ephone-20[19/20]:StationSpeedDialStatMessage sent
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationSpeedDialStatReqMessage speed
2
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:No speed-dial set 2
<br>
May 18 17:17:41: ephone-20[19/20]:StationSpeedDialStatMessage sent
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationSpeedDialStatReqMessage speed
3
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:No speed-dial set 3
<br>
May 18 17:17:41: ephone-20[19/20]:StationSpeedDialStatMessage sent
<br>
May 18 17:17:41:
ephone-20[19/20][SEP001FCAE9332E]:StationSpeedDialStatReqMessage speed
4
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:No speed-dial set 4
<br>
May 18 17:17:41: ephone-20[19/20]:StationSpeedDialStatMessage sent
<br>
May 18 17:17:41: ephone-20[19/20][SEP001FCAE9332E]:Skinny Available
Lines 1 set for socket [20]
<br>
May 18 17:17:41: ephone-20[19/20]:Already done
SkinnyCompleteRegistration
<br>
May 18 17:17:41: STANDBY Keepalive socket 26 nonregistered ephone {3}
192.168.101.28 1026
<br>
<br>
Ryan Ratliff wrote:
<br>
<blockquote type="cite"><br>
When the phone re-registers to CCME I believe the alarm messages gets
printed to the log/console. It should have a string like "last=" in
it. This is the reason the phone last unregistered and will be very
useful.
<br>
<br>
-Ryan
<br>
<br>
On May 18, 2009, at 7:01 AM, Anthony Kouloglou wrote:
<br>
<br>
Hi, in a CUCME 7.0(1) running on 2811 with 12.4.22.T1, in the local
site all phones have no problem.
<br>
But, in a remote site, i registered 2 7911 and i noticed that they
unregister and reregister every 46 minutes!!
<br>
Not 45 not 47!
<br>
I upgraded them to SCCP11.8-3-3S and they are doing the same thing.
<br>
There is no connectivity problem with the remote site. I registered a
7940 at the remote site and it never reseted!!.
<br>
Also, the phone 7911 reseted even in an active call (it was at th 46th
minute)
<br>
<br>
Has anyone faced that before?
<br>
<br>
Thanks
<br>
Anthony
<br>
_______________________________________________
<br>
cisco-voip mailing list
<br>
<a class="moz-txt-link-abbreviated" href="mailto:cisco-voip@puck.nether.net">cisco-voip@puck.nether.net</a>
<br>
<a class="moz-txt-link-freetext" href="https://puck.nether.net/mailman/listinfo/cisco-voip">https://puck.nether.net/mailman/listinfo/cisco-voip</a>
<br>
<br>
</blockquote>
<br>
</blockquote>
<br>
</blockquote>
</body>
</html>