IP Office 6.1.22
1616 IP phones version 1.0.30b
Have an issue where a ocassionally a phone will reboot . Not always same phone and does not happen all the time.
However, When it does happen I get a Cause=38 Network out order error and I see the phone unregister and reregister.
I beleive it to be network related but, wanted to investigate further.. I have posted a trace below.
Any help is appreciated. extn 1508 192.168.14.52
Called[SMccann Msgs=0 Old=16 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [SMccann Msgs=0]
Timed: 01/12/11 12:22
699220407mS H323Evt: Recv: RegistrationRequest 192.168.11.60; Endpoints registered: 25; Endpoints in registration: 0
699220619mS SCN Ctrl Tx: PropagateBLF to 192.168.14.10 length=45
699220998mS H323Evt: Recv: RegistrationRequest 192.168.11.63; Endpoints registered: 25; Endpoints in registration: 0
699229283mS H323Evt: Recv: RegistrationRequest 192.168.11.54; Endpoints registered: 25; Endpoints in registration: 0
699229598mS H323Evt: Recv: RegistrationRequest 192.168.11.61; Endpoints registered: 25; Endpoints in registration: 0
699230648mS H323Evt: Recv: RegistrationRequest 192.168.11.69; Endpoints registered: 25; Endpoints in registration: 0
699231278mS H323Evt: Recv: RegistrationRequest 192.168.11.58; Endpoints registered: 25; Endpoints in registration: 0
699235001mS H323Evt: Recv: RegistrationRequest 192.168.11.66; Endpoints registered: 25; Endpoints in registration: 0
699236666mS H323Evt: Recv: RegistrationRequest 192.168.11.55; Endpoints registered: 25; Endpoints in registration: 0
699238490mS H323Evt: Recv: RegistrationRequest 192.168.11.68; Endpoints registered: 25; Endpoints in registration: 0
699241816mS H323Evt: Recv: RegistrationRequest 192.168.11.56; Endpoints registered: 25; Endpoints in registration: 0
699242457mS H323Evt: Recv: RegistrationRequest 192.168.11.52; Endpoints registered: 25; Endpoints in registration: 0
699243075mS H323Evt: Recv: RegistrationRequest 192.168.11.64; Endpoints registered: 25; Endpoints in registration: 0
699248108mS ISDNL2Tx: v=5 peb=5
0000 00 01 01 d9 ....
699248108mS ISDNL1Tx: v=5 peb=5
0000 00 01 01 d9 ....
699248118mS ISDNL1Rx: v=5 peb=5
0000 00 01 01 4b ...K
699248118mS ISDNL2Rx: v=5 peb=5
0000 00 01 01 4b ...K
699248752mS H323Evt: Recv: RegistrationRequest 192.168.11.53; Endpoints registered: 25; Endpoints in registration: 0
699252028mS H323Evt: Recv: RegistrationRequest 192.168.11.65; Endpoints registered: 25; Endpoints in registration: 0
699252568mS H323Evt: v=0 stacknum=250 State, new=NullState, old=Active id=86
699252568mS H323Evt: GK: Unregister endpoint AWESCO ALBANY_4ec49ae555920167 for extension 1508
699252568mS CMExtnEvt: SMccann: ExtnFault now 1
699252569mS CMExtnTx: v=1508, p1=8023
CMReleaseComp
Line: type=IPLine 250 Call: lid=274 id=86 in=1
Cause=16, Normal call clearing
699252570mS H323Evt: GK: Send URQ
699252571mS CMCallPkt: v=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=274 id=86 in=1
Cause=38, Network out of order
699252773mS RES: Thu 1/12/2011 12:23:02 FreeMem=68174824(1) CMMsg=8 (9) Buff=5200 968 999 7423 4 Links=24516
699252774mS RES2: IP 500 V2 6.1(22) Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=78 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
699253068mS SCN Ctrl Tx: PropagateBLF to 192.168.14.10 length=45
699253162mS H323Evt: Recv: RegistrationRequest 192.168.11.62; Endpoints registered: 24; Endpoints in registration: 0
699260325mS H323Evt: Recv: RegistrationRequest 192.168.11.73; Endpoints registered: 24; Endpoints in registration: 0
699260612mS H323Evt: Recv: RegistrationRequest 192.168.1.131; Endpoints registered: 24; Endpoints in registration: 0
699260818mS H323Evt: Recv: RegistrationRequest 192.168.14.51; Endpoints registered: 24; Endpoints in registration: 0
699261490mS H323Evt: Recv: RegistrationRequest 192.168.11.57; Endpoints registered: 24; Endpoints in registration: 0
699263687mS H323Evt: Recv: RegistrationRequest 192.168.11.71; Endpoints registered: 24; Endpoints in registration: 0
699264200mS H323Evt: Recv: RegistrationRequest 192.168.11.50; Endpoints registered: 24; Endpoints in registration: 0
699266912mS H323Evt: Recv GRQ from c0a80e34
699266912mS H323Evt: e_H225_AliasAddress_dialedDigits alias
699266913mS H323Evt: found number <1508>
699267237mS H323Evt: Recv: RegistrationRequest 192.168.14.52; Endpoints registered: 24; Endpoints in registration: 0
699267238mS H323Evt: e_H225_AliasAddress_dialedDigits alias
699267238mS H323Evt: found number <1508>
699267238mS H323Evt: RRQ --- CallSigProtocol is H323AnnexL_P. Go for Avaya 4600IP phone
699267239mS H323Evt: RRQ --- Register extn 1508 using product IP_Phone, version 1.300B
699267273mS RES: Thu 1/12/2011 12:23:17 FreeMem=68171204(1) CMMsg=8 (9) Buff=5200 968 999 7423 4 Links=24527
699267274mS RES2: IP 500 V2 6.1(22) Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=76 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
699267400mS H323Evt: Recv NSM from c0a80e34
699267401mS H323Evt: Recv SwitchInfoRequest from c0a80e34
699267570mS H323Rx: src=192.168.14.52:5602
H323 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
699267570mS H323Evt: v=0 stacknum=250 State, new=NullState, old=NullState id=-1
699267570mS H323Evt: v=0 stacknum=250 State, new=Present, old=NullState id=91
699267576mS CMCallPkt: v=0
CMSetup
Line: type=IPLine 250 Call: lid=274 id=91 in=1
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIEFastStartInfoData (6)
699267578mS CMExtnTx: v=1508, p1=8023
CMProceeding
Line: type=IPLine 250 Call: lid=274 id=91 in=1
699267578mS CMExtnTx: v=1508, p1=8023
CMConnect
Line: type=IPLine 250 Call: lid=274 id=91 in=1
IE CMIEFastStartInfoData (6)
699267579mS H323Evt: v=0 stacknum=250 State, new=ICProceeding, old=Present id=91
699267580mS H323Tx: dst=192.168.14.52:5602
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = CallProceeding
699267580mS H323Evt: v=0 stacknum=250 State, new=Active, old=ICProceeding id=91
699267581mS H323Tx: dst=192.168.14.52:5602
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Connect
699267581mS CMCallPkt: v=0
CMConnectAck
Line: type=IPLine 250 Call: lid=274 id=91 in=1
699268099mS H323Evt: Recv: RegistrationRequest 192.168.11.67; Endpoints registered: 24; Endpoints in registration: 1
699268578mS H323Tx: dst=192.168.14.52:5602
1616 IP phones version 1.0.30b
Have an issue where a ocassionally a phone will reboot . Not always same phone and does not happen all the time.
However, When it does happen I get a Cause=38 Network out order error and I see the phone unregister and reregister.
I beleive it to be network related but, wanted to investigate further.. I have posted a trace below.
Any help is appreciated. extn 1508 192.168.14.52
Called[SMccann Msgs=0 Old=16 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [SMccann Msgs=0]
Timed: 01/12/11 12:22
699220407mS H323Evt: Recv: RegistrationRequest 192.168.11.60; Endpoints registered: 25; Endpoints in registration: 0
699220619mS SCN Ctrl Tx: PropagateBLF to 192.168.14.10 length=45
699220998mS H323Evt: Recv: RegistrationRequest 192.168.11.63; Endpoints registered: 25; Endpoints in registration: 0
699229283mS H323Evt: Recv: RegistrationRequest 192.168.11.54; Endpoints registered: 25; Endpoints in registration: 0
699229598mS H323Evt: Recv: RegistrationRequest 192.168.11.61; Endpoints registered: 25; Endpoints in registration: 0
699230648mS H323Evt: Recv: RegistrationRequest 192.168.11.69; Endpoints registered: 25; Endpoints in registration: 0
699231278mS H323Evt: Recv: RegistrationRequest 192.168.11.58; Endpoints registered: 25; Endpoints in registration: 0
699235001mS H323Evt: Recv: RegistrationRequest 192.168.11.66; Endpoints registered: 25; Endpoints in registration: 0
699236666mS H323Evt: Recv: RegistrationRequest 192.168.11.55; Endpoints registered: 25; Endpoints in registration: 0
699238490mS H323Evt: Recv: RegistrationRequest 192.168.11.68; Endpoints registered: 25; Endpoints in registration: 0
699241816mS H323Evt: Recv: RegistrationRequest 192.168.11.56; Endpoints registered: 25; Endpoints in registration: 0
699242457mS H323Evt: Recv: RegistrationRequest 192.168.11.52; Endpoints registered: 25; Endpoints in registration: 0
699243075mS H323Evt: Recv: RegistrationRequest 192.168.11.64; Endpoints registered: 25; Endpoints in registration: 0
699248108mS ISDNL2Tx: v=5 peb=5
0000 00 01 01 d9 ....
699248108mS ISDNL1Tx: v=5 peb=5
0000 00 01 01 d9 ....
699248118mS ISDNL1Rx: v=5 peb=5
0000 00 01 01 4b ...K
699248118mS ISDNL2Rx: v=5 peb=5
0000 00 01 01 4b ...K
699248752mS H323Evt: Recv: RegistrationRequest 192.168.11.53; Endpoints registered: 25; Endpoints in registration: 0
699252028mS H323Evt: Recv: RegistrationRequest 192.168.11.65; Endpoints registered: 25; Endpoints in registration: 0
699252568mS H323Evt: v=0 stacknum=250 State, new=NullState, old=Active id=86
699252568mS H323Evt: GK: Unregister endpoint AWESCO ALBANY_4ec49ae555920167 for extension 1508
699252568mS CMExtnEvt: SMccann: ExtnFault now 1
699252569mS CMExtnTx: v=1508, p1=8023
CMReleaseComp
Line: type=IPLine 250 Call: lid=274 id=86 in=1
Cause=16, Normal call clearing
699252570mS H323Evt: GK: Send URQ
699252571mS CMCallPkt: v=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=274 id=86 in=1
Cause=38, Network out of order
699252773mS RES: Thu 1/12/2011 12:23:02 FreeMem=68174824(1) CMMsg=8 (9) Buff=5200 968 999 7423 4 Links=24516
699252774mS RES2: IP 500 V2 6.1(22) Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=78 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
699253068mS SCN Ctrl Tx: PropagateBLF to 192.168.14.10 length=45
699253162mS H323Evt: Recv: RegistrationRequest 192.168.11.62; Endpoints registered: 24; Endpoints in registration: 0
699260325mS H323Evt: Recv: RegistrationRequest 192.168.11.73; Endpoints registered: 24; Endpoints in registration: 0
699260612mS H323Evt: Recv: RegistrationRequest 192.168.1.131; Endpoints registered: 24; Endpoints in registration: 0
699260818mS H323Evt: Recv: RegistrationRequest 192.168.14.51; Endpoints registered: 24; Endpoints in registration: 0
699261490mS H323Evt: Recv: RegistrationRequest 192.168.11.57; Endpoints registered: 24; Endpoints in registration: 0
699263687mS H323Evt: Recv: RegistrationRequest 192.168.11.71; Endpoints registered: 24; Endpoints in registration: 0
699264200mS H323Evt: Recv: RegistrationRequest 192.168.11.50; Endpoints registered: 24; Endpoints in registration: 0
699266912mS H323Evt: Recv GRQ from c0a80e34
699266912mS H323Evt: e_H225_AliasAddress_dialedDigits alias
699266913mS H323Evt: found number <1508>
699267237mS H323Evt: Recv: RegistrationRequest 192.168.14.52; Endpoints registered: 24; Endpoints in registration: 0
699267238mS H323Evt: e_H225_AliasAddress_dialedDigits alias
699267238mS H323Evt: found number <1508>
699267238mS H323Evt: RRQ --- CallSigProtocol is H323AnnexL_P. Go for Avaya 4600IP phone
699267239mS H323Evt: RRQ --- Register extn 1508 using product IP_Phone, version 1.300B
699267273mS RES: Thu 1/12/2011 12:23:17 FreeMem=68171204(1) CMMsg=8 (9) Buff=5200 968 999 7423 4 Links=24527
699267274mS RES2: IP 500 V2 6.1(22) Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=76 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
699267400mS H323Evt: Recv NSM from c0a80e34
699267401mS H323Evt: Recv SwitchInfoRequest from c0a80e34
699267570mS H323Rx: src=192.168.14.52:5602
H323 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Setup
699267570mS H323Evt: v=0 stacknum=250 State, new=NullState, old=NullState id=-1
699267570mS H323Evt: v=0 stacknum=250 State, new=Present, old=NullState id=91
699267576mS CMCallPkt: v=0
CMSetup
Line: type=IPLine 250 Call: lid=274 id=91 in=1
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIEFastStartInfoData (6)
699267578mS CMExtnTx: v=1508, p1=8023
CMProceeding
Line: type=IPLine 250 Call: lid=274 id=91 in=1
699267578mS CMExtnTx: v=1508, p1=8023
CMConnect
Line: type=IPLine 250 Call: lid=274 id=91 in=1
IE CMIEFastStartInfoData (6)
699267579mS H323Evt: v=0 stacknum=250 State, new=ICProceeding, old=Present id=91
699267580mS H323Tx: dst=192.168.14.52:5602
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = CallProceeding
699267580mS H323Evt: v=0 stacknum=250 State, new=Active, old=ICProceeding id=91
699267581mS H323Tx: dst=192.168.14.52:5602
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Connect
699267581mS CMCallPkt: v=0
CMConnectAck
Line: type=IPLine 250 Call: lid=274 id=91 in=1
699268099mS H323Evt: Recv: RegistrationRequest 192.168.11.67; Endpoints registered: 24; Endpoints in registration: 1
699268578mS H323Tx: dst=192.168.14.52:5602