Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

  • Congratulations Mike Lewis on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

IP phones 16xx resetting 1

Status
Not open for further replies.

VanZenden

MIS
Sep 1, 2003
345
SI
Hi all,

In the last two days IP phones (1608 and 1616) are going into reset, for no apparent reason. for last two months they were working fine.
IP phones are using DHCP/HTTP from IPO.

Partial trace (down below is a complete trace):

GK: Unregister endpoint Maketa_R42_11_493543682b366a07 for extension 77
6691138mS CMExtnEvt: UserXY: ExtnFault now 1


Also, there is alarm in SSA which shows from time to time, that there's no licence for IP Endpoint !?!?!.

Service Alarms
--------------

Last Date Of Error Occurrences Error Description
-----------------------------------------------------------------------------------------------
2.12.2008 14:23:10 2 Attempt to use a feature for which no license is installed.
License Type: IP End-points


IPO 500 R 4.2 (11) - Embedded VM
Manager 6.2 (114901)
IP phones FW:
- h1608b10526vs.bin
- h1616b10526vs.bin
- hb1608ua1_020.bin
- hb1616ua1_020.bin

On a network side no modifications were done.

Did anyone have any similar issues?


Regards,





Monitor trace:


6689801mS H323Evt: Recv: RegistrationRequest c0a804e4; Endpoints registered: 6; Endpoints in registration: 0
6690133mS H323Evt: Recv: RegistrationRequest c0a804e3; Endpoints registered: 6; Endpoints in registration: 0
6691138mS H323Evt: v=0 stacknum=250 State, new=NullState, old=Active id=7
6691138mS H323Evt: GK: Unregister endpoint Maketa_R42_11_493543682b366a07 for extension 77
6691138mS CMExtnEvt: UserXY: ExtnFault now 1
6691139mS CMExtnTx: v=77, p1=8000
CMReleaseComp
Line: type=IPLine 250 Call: lid=251 id=7 in=1
Cause=16, Normal call clearing
6691350mS RES: Tue 2/12/2008 14:23:03 FreeMem=74676008(1) CMMsg=5 (5) Buff=200 957 998 7414 3 Links=2393
6691350mS RES2: RTEngine=0, CMRTEngine=0, Timer=62, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
6699850mS RES: Tue 2/12/2008 14:23:11 FreeMem=74675060(1) CMMsg=5 (5) Buff=200 957 999 7412 3 Links=2393
6699851mS RES2: RTEngine=0, CMRTEngine=0, Timer=64, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
6714272mS H323Evt: Recv GRQ from c0a804e6
6714272mS H323Evt: e_H225_AliasAddress_dialedDigits alias
6714273mS H323Evt: found number <77>
6714350mS RES: Tue 2/12/2008 14:23:26 FreeMem=74674176(1) CMMsg=5 (5) Buff=200 957 998 7338 3 Links=2392
6714350mS RES2: RTEngine=0, CMRTEngine=0, Timer=66, Poll=0, Ready=0, CMReady=0, CMQueue=0, VPNNQueue=0
6714538mS H323Evt: Recv: RegistrationRequest c0a804e6; Endpoints registered: 5; Endpoints in registration: 0
6714538mS H323Evt: e_H225_AliasAddress_dialedDigits alias
6714538mS H323Evt: found number <77>
6714538mS H323Evt: RRQ --- CallSigProtocol is H323AnnexL_P. Go for Avaya 4600IP phone
6714539mS H323Evt: RRQ --- Register extn 77 using product IP_Phone, version 1.045
6714580mS H323Evt: Recv NSM from c0a804e6
6714580mS H323Evt: Recv SwitchInfoRequest from c0a804e6
6714581mS PRN: received SwitchInfoRequest_requestType_buttonInfo:
6714624mS H323Evt: v=0 stacknum=250 State, new=NullState, old=NullState id=-1
6714624mS H323Evt: v=0 stacknum=250 State, new=Present, old=NullState id=8
6714631mS CMExtnTx: v=77, p1=8000
CMProceeding
Line: type=IPLine 250 Call: lid=251 id=8 in=1
6714631mS CMExtnTx: v=77, p1=8000
CMConnect
Line: type=IPLine 250 Call: lid=251 id=8 in=1
IE CMIEFastStartInfoData (6)
6714632mS H323Evt: v=0 stacknum=250 State, new=ICProceeding, old=Present id=8
6714633mS H323Evt: v=0 stacknum=250 State, new=Active, old=ICProceeding id=8
6717642mS CMExtnEvt: UserXY: ExtnFault now 0
6717649mS CMExtnTx: v=77, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[UserXY Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [UserXY Msgs=0]
Timed: 02/12/08 14:23
6717649mS CMExtnTx: v=77, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[DayHG Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [DayHG Msgs=0]
Timed: 02/12/08 14:23
6722887mS R2DSP: PRIU DSP 3: is alive


 
That false licence warning was a fault in 4.2.4 and the beta for it are you on 4.2.4?

ACS - IP Office Implement
 
What version are your phones running?

Check if they run 1.045 and check if the user has CA keys programmed.

y1pzZTEUdok1vrI5cLb3FdPX4PgTPlSONkb5WPjz0x50etSujaMSmhdRCbOx9vASnrRNzzXv0IxNQA

___________________________________________
It works! Now if only I could remember what I did...
___________________________________________
 
Sorry, skipped bits. And don't asssign the CA keys through user rights groups as that can cause issues, you can copy the values from user rights though.

ACS - IP Office Implement
 
This are the filenames for 16xx phones. can't tell you what version, coz I'm in company right now...

It's a version that came with 4.2.11

- h1608b10526vs.bin
- h1616b10526vs.bin
- hb1608ua1_020.bin
- hb1616ua1_020.bin


I have assigned CA keys through User Rights Groups, so i'll have to remove them

Is that one of undetected bugs in 4.2.11??

Thanks
 
It has caused issues on many releases, it does not always cause an issue and it may not be the problem here but it can and has caused similiar problems for me a couple of times in the last 6 months, setting DND buttons through user rights groups for example just did not work my first 4.2.4 install so I have not done it since, as I say you can copy the values from user rights which is just as easy but does not lock the changes out in the user.

ACS - IP Office Implement
 
phones with restting issue had a programmed CA in User Rights, so I removed it.

Before I done this, I rolled back configuration from week ago, and it was ok then.

I assume beacuse collegue adminstered IPO (4.2.11) with Manager 6.2.4 could be a reason for a corrupt configuration.
Strange thing was I when installed patch 6.2(1148011) for Manager this resetting isssue was starting.

Thanks again,
 
Are you guys saying that not using user rights to assign CA's will solve the issue of IP Phones randomly re-registered for no apperent reason?

Is that it?

We have a system that is plagued with IP Sets (5621's) constantly re-registering for no reason. Happends to Regular IP Phoens and VPN Phoens on the Same PBX. Never seen anythign like this. We even upgraed our VPN's to Rock Solid ASA's form crappy Netgears, but the issues remain.

Any help would be appreciated. Avaya T3 has no answers so far.

Some excerpts form Monitor Trace:

420298583mS H323Evt: Keep-alive time-out on 1210 (keep_alive_count 0)
420298583mS H323Evt: GK: Unregister endpoint TEL IP500 RNJ_497ee9c53a3d686f for extension 1210
420298583mS CMExtnEvt: IP Test 1: ExtnFault now 1
420298584mS CMExtnTx: v=1210, p1=8010
CMReleaseComp
Line: type=IPLine 250 Call: lid=261 id=298 in=1
Cause=16, Normal call clearing
420298585mS H323Evt: GK: Send URQ
420298585mS RasTx: v=Src=192.168.0.20:1719, Dst=172.16.23.53:49300 peb=0
RasMessage = unregistrationRequest

420298586mS H323Evt: v=0 stacknum=250 State, new=ReleaseReq, old=Present id=298
420298587mS H323Tx: 1289 dst=172.16.23.53:5584
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = ReleaseComplete


Below is the re-registering


420331666mS H323Evt: Recv GRQ from ac101735
420331666mS H323Evt: e_H225_AliasAddress_dialedDigits alias
420331667mS H323Evt: found number <1210>
420331667mS RasTx: v=Src=192.168.0.20:1719, Dst=172.16.23.53:49300 peb=0
RasMessage = gatekeeperConfirm

420331718mS RasRx: v=IFace=LAN1, Src=172.16.23.53:49300, Dst=192.168.0.20:1719 peb=0
RasMessage = registrationRequest

420331719mS H323Evt: Recv: RegistrationRequest ac101735; Endpoints registered: 9; Endpoints in registration: 1
420331719mS H323Evt: e_H225_AliasAddress_dialedDigits alias
420331719mS H323Evt: found number <1210>
420331719mS H323Evt: RRQ --- CallSigProtocol is H323AnnexL_P. Go for Avaya VPN phone
420331720mS H323Evt: RRQ --- Register extn 1210 using product IP_Phone_VPN, version 2.300
420331721mS RasTx: v=Src=192.168.0.20:1719, Dst=172.16.23.53:49300 peb=0
RasMessage = registrationConfirm

420331975mS H323Rx: 1291 src=172.16.23.53:3356
H323 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
ForwardLCParameters EAAI:[G711_ULAW64K (frames=10)] H2250 SID=01:
ReverseLCParameters EAAI:[G711_ULAW64K (frames=60)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G711_ALAW64K (frames=10)] H2250 SID=01:
ReverseLCParameters EAAI:[G711_ALAW64K (frames=60)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G729_AWB (frames=1)] H2250 SID=01:
ReverseLCParameters EAAI:[G729_AWB (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G729_A (frames=1)] H2250 SID=01:
ReverseLCParameters EAAI:[G729_A (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G729_WB (frames=1)] H2250 SID=01:
ReverseLCParameters EAAI:[G729_WB (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters H2250 SID=01:
ReverseLCParameters H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G711_ULAW64K (frames=10)] H2250 SID=01:
ReverseLCParameters EAAI:[G711_ULAW64K (frames=60)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G711_ALAW64K (frames=10)] H2250 SID=01:
ReverseLCParameters EAAI:[G711_ALAW64K (frames=60)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G729_AWB (frames=1)] H2250 SID=01:
ReverseLCParameters EAAI:[G729_AWB (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G729_A (frames=1)] H2250 SID=01:
ReverseLCParameters EAAI:[G729_A (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters EAAI:[G729_WB (frames=1)] H2250 SID=01:
ReverseLCParameters EAAI:[G729_WB (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters H2250 SID=01:
ReverseLCParameters H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters [G711_ULAW64K (frames=10)] H2250 SID=01:
ReverseLCParameters [G711_ULAW64K (frames=60)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters [G711_ALAW64K (frames=10)] H2250 SID=01:
ReverseLCParameters [G711_ALAW64K (frames=60)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters [G729_AWB (frames=1)] H2250 SID=01:
ReverseLCParameters [G729_AWB (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters [G729_A (frames=1)] H2250 SID=01:
ReverseLCParameters [G729_A (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters [G729_WB (frames=1)] H2250 SID=01:
ReverseLCParameters [G729_WB (frames=6)] H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,
ForwardLCParameters H2250 SID=01:
ReverseLCParameters H2250 SID=01: RTP=172.16.23.53(52292) , RTCP=172.16.23.53(52293) ,

420331976mS H323Evt: v=0 stacknum=250 State, new=NullState, old=NullState id=-1
420331976mS H323Evt: v=0 stacknum=250 State, new=Present, old=NullState id=300
420331986mS CMExtnTx: v=1210, p1=8010
CMProceeding
Line: type=IPLine 250 Call: lid=261 id=300 in=1
420331987mS CMExtnTx: v=1210, p1=8010
CMConnect
Line: type=IPLine 250 Call: lid=261 id=300 in=1
IE CMIEFastStartInfoData (6)
420331987mS CcmsTx: v=6099
0000 07 38 11 20 a2 a0 0b .8. ...
420331988mS H323Evt: v=0 stacknum=250 State, new=ICProceeding, old=Present id=300
420331988mS H323Tx: 1291 dst=172.16.23.53:3356
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = CallProceeding


Isaac Braca
Avaya ACA - IP Office
CTO / ICCS & Co., LLC.


ICCS, Your Premier IT & Telecom Partner, is a New York City Based Avaya SMB Expert Business Partner and IT Consulting Firm.

Visit and Post on my Blog:
 
Strange our 16xx are running 1.045 on 4.2.11?!

y1pzZTEUdok1vrI5cLb3FdPX4PgTPlSONkb5WPjz0x50etSujaMSmhdRCbOx9vASnrRNzzXv0IxNQA

___________________________________________
It works! Now if only I could remember what I did...
___________________________________________
 
Status
Not open for further replies.

Part and Inventory Search

Sponsor

Back
Top