1608 Handset over Site to Site VPN keeps re-registering every 1 minute and 42 seconds.

[JoeNewton]

Hi Guys,

I'm hoping someone will be able to help with an issue that we are having with a 1608 over a site to site IPSEC VPN.

It is an IP500v2, with all 16/96 series handsets. They have just opened a new site half a mile down the road, leased line in each, and an IPSEC VPN between the two. We unfortunately don't provide the connectivity or manage the VPN or any of the network kit.

There are no issues with the tunnel itself, it doesn't drop, it passes all of their data traffic fine, good bandwidth and latency etc. The phone registers fine, can make a call, and gets perfect audio quality. However after exactly 1 minute and 42 seconds, without fail, the handset re-registers itself. If no call is in progress it takes a few seconds, if a call is in progress then the call cuts off and handset reboots.

The IT company cannot find any issues, and is asking for logs from the phone to say what traffic it is receiving unexpectedly, or what traffic it is not receiving that it should be, etc. in order for this to happen. As far as I can see there is no way to export logs from the phone, so I have done a Monitor trace with default filters plus all H323 options. I will post the trace after this. I am no expert on Monitor and the various messages it spews out, I can usually work out what is going wrong one way or another, but with H323 signalling I am at a loss. I have looked through the knowledge base, but cannot find anything that gives me enough detail, either on Monitor, or H323, to decode what I am seeing. A few things that jump out at me are:

17:21:17 1700437mS H323Evt: RRQ --- CallSigProtocol is H323AnnexL_P. Go for Avaya 4600IP phone
17:21:17 1700437mS H323Evt: RRQ --- Treat this as a forced login, the phone was probably rebooted (same_cs_addr 1)
17:21:17 1700438mS H323Evt: GK: Unregister endpoint CustomerName_5df276a27b337860 for extension 612 reason 4
17:21:17 1700438mS CMExtnEvt: Test Phone:612 ExtnFault now 1
17:21:17 1700439mS CMExtnTx: v=612, p1=8071

And

17:21:17 1700365mS H323Evt: Recv: RegistrationRequest 192.168.52.80; Endpoints registered: 52; Endpoints in registration: 0
17:21:17 1700366mS H323Evt: RRQ --- keep-alive rejected (endpoint is currently not registered)

Does this mean anything to anyone? I'm thinking either some keepalive traffic is being blocked, or the extension is not fully registering and the IP is trying to register?

Full trace to follow; it was taken a few seconds before the phone started re-registering, and stopped a few seconds after it finished.

Any help on this would be very much appreciated.

Thanks in advance for your help.

Joe

Joe Newton

 

[JoeNewton]

Full Trace:

17:21:13 1696823mS H323Evt: Recv: RegistrationRequest 192.168.42.32; Endpoints registered: 52; Endpoints in registration: 0
17:21:13 1696824mS RasTx: v=Src=192.168.42.1:1719, Dst=192.168.42.32:49300 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 32
protocolIdentifier = 0.0.8.2250.0.2
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 01 ..*.
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0065 0031 0032 0031 0038 0030 0033 0e121803
0064 0034 0032 d42
timeToLive = 60
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
17:21:13 1696996mS RasRx: v=IFace=LAN1, Src=192.168.42.51:49300, Dst=192.168.42.1:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 33
protocolIdentifier = 0.0.8.2250.0.2
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 33 ..*3
port = 4207
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 33 ..*3
port = 49300
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
31 2e 33 34 30 44 1.340D
}
keepAlive = true
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0063 0036 0035 0063 0032 0034 0033 0c65c243
0036 0030 0061 60a
}
17:21:13 1696996mS H323Evt: Recv: RegistrationRequest 192.168.42.51; Endpoints registered: 52; Endpoints in registration: 0
17:21:13 1696997mS RasTx: v=Src=192.168.42.1:1719, Dst=192.168.42.51:49300 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 33
protocolIdentifier = 0.0.8.2250.0.2
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 01 ..*.
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0063 0036 0035 0063 0032 0034 0033 0c65c243
0036 0030 0061 60a
timeToLive = 60
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
17:21:14 1697243mS H323Rx: src=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Remote)
Message Type = Information
InformationElement = UU
0000 7e 00 1e 05 28 10 01 00 10 a0 01 00 13 01 00 0a ~...(...........
0010 60 86 48 01 86 f8 72 04 02 0a 05 05 38 00 80 01 `.H...r.....8...
0020 00 .
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
05 38 00 80 01 .8...
}
}
}
}
17:21:14 1697243mS CcmsRx: v=204
0000 05 38 00 80 01 .8...
17:21:14 1697244mS CcmsTx: v=204
0000 08 38 11 20 a3 80 1a 43 .8. ...C
17:21:14 1697244mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 20 05 28 10 01 00 10 a0 01 00 16 01 00 0a ~. .(...........
0010 60 86 48 01 86 f8 72 04 02 0a 08 08 38 11 20 a3 `.H...r.....8. .
0020 80 1a 43 ..C
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
08 38 11 20 a3 80 1a 43 .8. ...C
}
}
}
}
17:21:14 1697245mS CcmsTx: v=204
0000 08 38 11 20 a3 83 04 42 .8. ...B
17:21:14 1697245mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 20 05 28 10 01 00 10 a0 01 00 16 01 00 0a ~. .(...........
0010 60 86 48 01 86 f8 72 04 02 0a 08 08 38 11 20 a3 `.H...r.....8. .
0020 83 04 42 ..B
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
08 38 11 20 a3 83 04 42 .8. ...B
}
}
}
}
17:21:14 1697245mS CcmsTx: v=204
0000 32 38 11 20 bf 2d e3 1b 5b 3b 48 61 3d 30 37 34 28. .-..[;Ha=074
0010 36 39 37 30 39 30 32 38 3a 53 74 65 76 65 20 54 69709028:John D
0020 6f 70 6c 69 73 73 20 4d 6f 62 69 6c 65 20 20 20 oe Mobile
0030 20 20
17:21:14 1697246mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 4a 05 28 10 01 00 10 a0 01 00 40 01 00 0a ~.J.(.......@...
0010 60 86 48 01 86 f8 72 04 02 0a 32 32 38 11 20 bf `.H...r...228. .
0020 2d e3 1b 5b 3b 48 61 3d 30 37 34 36 39 37 30 39 -..[;Ha=07469709
0030 30 32 38 3a 53 74 65 76 65 20 54 6f 70 6c 69 73 028:John Doe
0040 73 20 4d 6f 62 69 6c 65 20 20 20 20 20 s Mobile
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
32 38 11 20 bf 2d e3 1b 5b 3b 48 61 3d 30 37 34 28. .-..[;Ha=074
36 39 37 30 39 30 32 38 3a 53 74 65 76 65 20 54 69709028:John D
6f 70 6c 69 73 73 20 4d 6f 62 69 6c 65 20 20 20 oe Mobile
20 20
}
}
}
}
17:21:14 1697246mS CcmsTx: v=204
0000 0d 38 11 20 bf 08 e3 1b 54 0e 14 02 0b .8. ....T....
17:21:14 1697246mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 25 05 28 10 01 00 10 a0 01 00 1b 01 00 0a ~.%.(...........
0010 60 86 48 01 86 f8 72 04 02 0a 0d 0d 38 11 20 bf `.H...r.....8. .
0020 08 e3 1b 54 0e 14 02 0b ...T....
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
0d 38 11 20 bf 08 e3 1b 54 0e 14 02 0b .8. ....T....
}
}
}
}
17:21:14 1697246mS CMExtnTx: v=204, p1=8020
CMFacility
Line: type=IPLine 250 Call: lid=271 id=34 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
17:21:14 1697247mS CMExtnRx: v=204, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=271 id=1032 in=0
17:21:14 1697247mS CMCallEvt: 271.1032.0 10 John Doe.0: StateChange: END=A CMCSConnected->CMCSCompleted
17:21:14 1697248mS CMExtnEvt: v=34 State, new=PortRecoverDelay old=Connected,0,0,John Doe
17:21:14 1697250mS CMExtnTx: v=204, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=271 id=1032 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 12/12/19 17:21
17:21:14 1697251mS CMLOGGING: CALL:2019/12/1217:21,00:00:02,000,204,O,07469709028,07469709028,JohnDoe,,,0,,""n/a,0
17:21:14 1697251mS CD: CALL: 271.1032.0 BState=Connected Cut=2 Music=0.0 Aend="John Doe(204)" (0.0) Bend="Line 3" [Line 3] (1.9) CalledNum=07469709028 () CallingNum=204 (John Doe) Internal=0 Time=3896 AState=Idle
17:21:14 1697251mS CD: CALL: 271.1032.0 Deleted
17:21:14 1697252mS CcmsTx: v=204
0000 08 38 11 20 a3 83 04 42 .8. ...B
17:21:14 1697252mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 20 05 28 10 01 00 10 a0 01 00 16 01 00 0a ~. .(...........
0010 60 86 48 01 86 f8 72 04 02 0a 08 08 38 11 20 a3 `.H...r.....8. .
0020 83 04 42 ..B
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
08 38 11 20 a3 83 04 42 .8. ...B
}
}
}
}
17:21:14 1697252mS CcmsTx: v=204
0000 32 38 11 20 bf 2d e3 1b 5b 3b 48 61 3d 30 37 34 28. .-..[;Ha=074
0010 36 39 37 30 39 30 32 38 3a 53 74 65 76 65 20 54 69709028:John D
0020 6f 70 6c 69 73 73 20 4d 6f 62 69 6c 65 20 20 20 oe Mobile
0030 20 20
17:21:14 1697252mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 4a 05 28 10 01 00 10 a0 01 00 40 01 00 0a ~.J.(.......@...
0010 60 86 48 01 86 f8 72 04 02 0a 32 32 38 11 20 bf `.H...r...228. .
0020 2d e3 1b 5b 3b 48 61 3d 30 37 34 36 39 37 30 39 -..[;Ha=07469709
0030 30 32 38 3a 53 74 65 76 65 20 54 6f 70 6c 69 73 028:John Doe
0040 73 20 4d 6f 62 69 6c 65 20 20 20 20 20 s Mobile
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
32 38 11 20 bf 2d e3 1b 5b 3b 48 61 3d 30 37 34 28. .-..[;Ha=074
36 39 37 30 39 30 32 38 3a 53 74 65 76 65 20 54 69709028:John D
6f 70 6c 69 73 73 20 4d 6f 62 69 6c 65 20 20 20 oe Mobile
20 20
}
}
}
}
17:21:14 1697253mS CcmsTx: v=204
0000 0d 38 11 20 bf 08 e3 1b 54 0e 14 02 0b .8. ....T....
17:21:14 1697253mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 25 05 28 10 01 00 10 a0 01 00 1b 01 00 0a ~.%.(...........
0010 60 86 48 01 86 f8 72 04 02 0a 0d 0d 38 11 20 bf `.H...r.....8. .
0020 08 e3 1b 54 0e 14 02 0b ...T....
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
0d 38 11 20 bf 08 e3 1b 54 0e 14 02 0b .8. ....T....
}
}
}
}
17:21:14 1697253mS CMExtnEvt: John Doe: CALL LOST (CMCauseNormal)
17:21:14 1697253mS CMExtnEvt: John Doe: Extn(204) Calling Party Number(204) Type(CMNTypeInternal)
17:21:14 1697254mS CMExtnEvt: John Doe: CMExtnHandler::SetCurrent( id: 1032->0 )
17:21:14 1697254mS CcmsTx: v=204
0000 09 38 11 20 a4 00 13 07 00 .8. .....
17:21:14 1697254mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 21 05 28 10 01 00 10 a0 01 00 17 01 00 0a ~.!.(...........
0010 60 86 48 01 86 f8 72 04 02 0a 09 09 38 11 20 a4 `.H...r.....8. .
0020 00 13 07 00 ....
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
09 38 11 20 a4 00 13 07 00 .8. .....
}
}
}
}
17:21:14 1697254mS CcmsTx: v=204
0000 0e 38 11 20 a9 80 20 0f 80 20 0e 80 2f 0e .8. .. .. ../.
17:21:14 1697255mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 26 05 28 10 01 00 10 a0 01 00 1c 01 00 0a ~.&.(...........
0010 60 86 48 01 86 f8 72 04 02 0a 0e 0e 38 11 20 a9 `.H...r.....8. .
0020 80 20 0f 80 20 0e 80 2f 0e . .. ../.
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
0e 38 11 20 a9 80 20 0f 80 20 0e 80 2f 0e .8. .. .. ../.
}
}
}
}
17:21:14 1697255mS CMCallEvt: 271.1032.0 -1 John Doe.-1: StateChange: END=X CMCSCompleted->CMCSDelete
17:21:14 1697257mS CMLineTx: v=3
CMDisconnect
Line: type=Q931Line 3 Call: lid=0 id=1035 in=0
Cause=16, Normal call clearing
17:21:14 1697257mS CMCallEvt: 0.1035.0 -1 Q931 Trunk:3 CHAN=1: StateChange: END=X CMCSConnected->CMCSCompleted
17:21:14 1697257mS CMCallEvt: END CALL:10 (f4990ae0)
17:21:14 1697258mS CMCallEvt: 271.1032.0 -1 BaseEP: DELETE CMEndpoint f4987110 TOTAL NOW=1 CALL_LIST=0
17:21:14 1697260mS CMMap: PCG::UnmapBChan pcp[93]b1r0 cp_b f54c4bd8 other_cp_b f5505358
17:21:14 1697260mS CMMap: a=0.1 b=1.9 M02
17:21:14 1697261mS CMMap: PlatformConnectionAudioSAP:isconnectVoice rtpRelay ID: 1 Close success
17:21:14 1697261mS H323Evt: SetOperational 9 192.168.42.1:46750 to 0
17:21:14 1697261mS CMMap: PCG::UnmapBChan pcp[112]b0r1 cp_b 0 other_cp_b 0
17:21:14 1697261mS H323Evt: RTP(END): 192.168.42.1/46750 192.168.42.67/49188 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=2288 SENT=106 RECV=113 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
17:21:14 1697263mS CMMap: a=0.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 32
17:21:14 1697264mS ISDNL3Evt: v=3 stacknum=3 State, new=DiscReq, old=Active id=1035
17:21:14 1697266mS H323Tx: 34 dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Facility
EMPTY Forward LCP
EMPTY Reverse LCP

17:21:14 1697266mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Facility
InformationElement = UU
0000 7e 00 3b 05 26 80 06 00 08 91 4a 00 05 63 e0 70 ~.;.&.....J..c.p
0010 00 11 00 00 00 00 00 00 00 10 00 00 00 00 00 c0 ................
0020 a8 2a 43 12 02 07 00 00 0d 06 04 01 00 08 40 00 .*C...........@.
0030 0c 06 04 01 00 04 01 00 01 00 10 80 01 00 ..............
UserInformation = {
h323-uu-pdu = {
h323-message-body = facility = {
protocolIdentifier = 0.0.8.2250.0.5
reason = undefinedReason
callIdentifier = {
guid =
00 00 00 00 00 00 10 00 00 00 00 00 c0 a8 2a 43 ..............*C
}
fastStart = { 2 item(s)
[0] =
00 00 0d 06 04 01 00 .......
[1] =
40 00 0c 06 04 01 00 04 @.......
}
multipleCalls = false
maintainConnection = false
}
h245Tunneling = false
}
}
fastStart[0] = {
forwardLogicalChannelNumber = 14
forwardLogicalChannelParameters = {
dataType = nullData
multiplexParameters = none
}
}
fastStart[1] = {
forwardLogicalChannelNumber = 13
forwardLogicalChannelParameters = {
dataType = nullData
multiplexParameters = none
}
reverseLogicalChannelParameters = {
dataType = nullData
}
}
17:21:14 1697361mS ISDNL3Evt: v=3 stacknum=3 State, new=NullState, old=DiscReq id=1035

********** SysMonitor v11.0.4.2.0 build 56 [connected to 192.168.16.100 (CustomerName)] **********
17:21:14 1697362mS CMLineRx: v=3
CMReleaseComp
Line: type=Q931Line 3 Call: lid=0 id=1035 in=0
BChan: slot=0 chan=1
17:21:14 1697362mS CMCallEvt: 0.1035.0 -1 Q931 Trunk:3 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
17:21:14 1697363mS CMCallEvt: 0.1035.0 -1 BaseEP: DELETE CMEndpoint f49910e8 TOTAL NOW=0 CALL_LIST=0
17:21:14 1697385mS PRN: Monitor Status IP 500 V2 9.0.0.0 build 829
17:21:14 1697385mS PRN: LAW=A PRI=0, BRI=4, ALOG=0, VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) 1-X=1 CALLS=0(TOT=10)
17:21:14 1697399mS CcmsTx: v=204
0000 13 38 11 20 ae e7 1b 46 21 2e 42 32 30 34 3a 50 .8. ...F!.B204
0010 65 74 65 ete
17:21:14 1697399mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 2b 05 28 10 01 00 10 a0 01 00 21 01 00 0a ~.+.(.......!...
0010 60 86 48 01 86 f8 72 04 02 0a 13 13 38 11 20 ae `.H...r.....8. .
0020 e7 1b 46 21 2e 42 32 30 34 3a 50 65 74 65 ..F!.B204ete
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
13 38 11 20 ae e7 1b 46 21 2e 42 32 30 34 3a 50 .8. ...F!.B204
65 74 65 ete
}
}
}
}
17:21:14 1697399mS CcmsTx: v=204
0000 10 38 11 20 ab e7 72 20 45 6c 6c 69 73 00 47 00 .8. ..r Ellis.G.
17:21:14 1697400mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 28 05 28 10 01 00 10 a0 01 00 1e 01 00 0a ~.(.(...........
0010 60 86 48 01 86 f8 72 04 02 0a 10 10 38 11 20 ab `.H...r.....8. .
0020 e7 72 20 45 6c 6c 69 73 00 47 00 .r Ellis.G.
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
10 38 11 20 ab e7 72 20 45 6c 6c 69 73 00 47 00 .8. ..r Ellis.G.
}
}
}
}
17:21:14 1697400mS CcmsTx: v=204
0000 0f 38 11 20 aa e7 1b 46 22 c2 40 00 61 3d 00 .8. ...F".@.a=.
17:21:14 1697400mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 27 05 28 10 01 00 10 a0 01 00 1d 01 00 0a ~.'.(...........
0010 60 86 48 01 86 f8 72 04 02 0a 0f 0f 38 11 20 aa `.H...r.....8. .
0020 e7 1b 46 22 c2 40 00 61 3d 00 ..F".@.a=.
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
0f 38 11 20 aa e7 1b 46 22 c2 40 00 61 3d 00 .8. ...F".@.a=.
}
}
}
}
17:21:14 1697400mS CcmsTx: v=204
0000 13 38 11 20 ae e7 1b 46 33 90 91 80 00 00 46 65 .8. ...F3.....Fe
0010 61 74 75 atu
17:21:14 1697401mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 2b 05 28 10 01 00 10 a0 01 00 21 01 00 0a ~.+.(.......!...
0010 60 86 48 01 86 f8 72 04 02 0a 13 13 38 11 20 ae `.H...r.....8. .
0020 e7 1b 46 33 90 91 80 00 00 46 65 61 74 75 ..F3.....Featu
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
13 38 11 20 ae e7 1b 46 33 90 91 80 00 00 46 65 .8. ...F3.....Fe
61 74 75 atu
}
}
}
}
17:21:14 1697401mS CcmsTx: v=204
0000 0a 38 11 20 a5 e7 72 65 73 00 .8. ..res.
17:21:14 1697401mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 22 05 28 10 01 00 10 a0 01 00 18 01 00 0a ~.".(...........
0010 60 86 48 01 86 f8 72 04 02 0a 0a 0a 38 11 20 a5 `.H...r.....8. .
0020 e7 72 65 73 00 .res.
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
0a 38 11 20 a5 e7 72 65 73 00 .8. ..res.
}
}
}
}
17:21:15 1698066mS RasRx: v=IFace=LAN1, Src=192.168.42.60:49300, Dst=192.168.42.1:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 32
protocolIdentifier = 0.0.8.2250.0.2
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 3c ..*<
port = 4915
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 3c ..*<
port = 49300
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
31 2e 33 34 30 44 1.340D
}
keepAlive = true
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0065 0031 0033 0035 0037 0030 0031 0e135701
0033 0039 0032 392
}
17:21:15 1698066mS H323Evt: Recv: RegistrationRequest 192.168.42.60; Endpoints registered: 52; Endpoints in registration: 0
17:21:15 1698067mS RasTx: v=Src=192.168.42.1:1719, Dst=192.168.42.60:49300 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 32
protocolIdentifier = 0.0.8.2250.0.2
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 01 ..*.
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0065 0031 0033 0035 0037 0030 0031 0e135701
0033 0039 0032 392
timeToLive = 60
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
17:21:15 1698949mS RasRx: v=IFace=LAN1, Src=192.168.42.58:49300, Dst=192.168.42.1:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 33
protocolIdentifier = 0.0.8.2250.0.2
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 3a ..*:
port = 3570
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 3a ..*:
port = 49300
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
31 2e 33 34 30 44 1.340D
}
keepAlive = true
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0063 0037 0030 0066 0036 0035 0032 0c70f652
0033 0061 0037 3a7
}
17:21:15 1698949mS H323Evt: Recv: RegistrationRequest 192.168.42.58; Endpoints registered: 52; Endpoints in registration: 0
17:21:15 1698950mS RasTx: v=Src=192.168.42.1:1719, Dst=192.168.42.58:49300 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 33
protocolIdentifier = 0.0.8.2250.0.2
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 01 ..*.
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0063 0037 0030 0066 0036 0035 0032 0c70f652
0033 0061 0037 3a7
timeToLive = 60
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
17:21:16 1699006mS RasRx: v=IFace=LAN1, Src=192.168.42.33:49300, Dst=192.168.42.1:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 32
protocolIdentifier = 0.0.8.2250.0.2
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 21 ..*!
port = 2484
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 21 ..*!
port = 49300
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
31 2e 33 34 30 44 1.340D
}
keepAlive = true
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0065 0031 0037 0065 0064 0063 0036 0e17edc6
0033 0064 0038 3d8
}
17:21:16 1699006mS H323Evt: Recv: RegistrationRequest 192.168.42.33; Endpoints registered: 52; Endpoints in registration: 0
17:21:16 1699007mS RasTx: v=Src=192.168.42.1:1719, Dst=192.168.42.33:49300 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 32
protocolIdentifier = 0.0.8.2250.0.2
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 01 ..*.
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0065 0031 0037 0065 0064 0063 0036 0e17edc6
0033 0064 0038 3d8
timeToLive = 60
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
17:21:16 1699255mS CMExtnEvt: John Doe: Recover Timer reason=CMTRWrapUp
17:21:16 1699255mS CMExtnEvt: v=34 State, new=Idle old=PortRecoverDelay,0,0,John Doe
17:21:16 1699256mS CMExtnTx: v=204, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[John Doe Msgs=0 Old=1 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [John Doe Msgs=0]
Timed: 12/12/19 17:21
17:21:16 1699326mS RasRx: v=IFace=LAN1, Src=192.168.42.64:49309, Dst=192.168.42.1:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 42
protocolIdentifier = 0.0.8.2250.0.5
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 40 ..*@
port = 4647
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 40 ..*@
port = 49309
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
36 2e 33 31 31 36 6.3116
}
timeToLive = 60
keepAlive = true
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0039 0038 0033 0038 0065 0031 0034 09838e14
0032 0036 0062 26b
}
17:21:16 1699326mS H323Evt: Recv: RegistrationRequest 192.168.42.64; Endpoints registered: 52; Endpoints in registration: 0
17:21:16 1699327mS RasTx: v=Src=192.168.42.1:1719, Dst=192.168.42.64:49309 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 42
protocolIdentifier = 0.0.8.2250.0.5
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 2a 01 ..*.
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0030 0039 0038 0033 0038 0065 0031 0034 09838e14
0032 0036 0062 26b
timeToLive = 60
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
17:21:16 1699398mS CcmsTx: v=204
0000 13 38 11 20 ae e7 1b 46 12 80 31 37 3a 32 31 20 .8. ...F..17:21
0010 31 32 2f 12/
17:21:16 1699398mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 2b 05 28 10 01 00 10 a0 01 00 21 01 00 0a ~.+.(.......!...
0010 60 86 48 01 86 f8 72 04 02 0a 13 13 38 11 20 ae `.H...r.....8. .
0020 e7 1b 46 12 80 31 37 3a 32 31 20 31 32 2f ..F..17:21 12/
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
13 38 11 20 ae e7 1b 46 12 80 31 37 3a 32 31 20 .8. ...F..17:21
31 32 2f 12/
}
}
}
}
17:21:16 1699398mS CcmsTx: v=204
0000 0c 38 11 20 a7 e7 31 32 2f 31 39 00 .8. ..12/19.
17:21:16 1699399mS H323Tx: dst=192.168.42.67:3057
H323 Pcol=08(Q931) Reflen=2 ref=0001(Local)
Message Type = Information
InformationElement = UU
0000 7e 00 24 05 28 10 01 00 10 a0 01 00 1a 01 00 0a ~.$.(...........
0010 60 86 48 01 86 f8 72 04 02 0a 0c 0c 38 11 20 a7 `.H...r.....8. .
0020 e7 31 32 2f 31 39 00 .12/19.
UserInformation = {
h323-uu-pdu = {
h323-message-body = empty
h245Tunneling = false
nonStandardControl = { 1 item(s)
[0] = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.10
data =
0c 38 11 20 a7 e7 31 32 2f 31 39 00 .8. ..12/19.
}
}
}
}
17:21:17 1700365mS RasRx: v=IFace=LAN2, Src=192.168.52.80:49308, Dst=192.168.16.100:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 40
protocolIdentifier = 0.0.8.2250.0.2
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 7855
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 49308
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
31 2e 33 34 30 44 1.340D
}
keepAlive = true
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0036 0061 0032 0033 0032 0039 0033 0036 6a232936
0033 0030 0038 308
}
17:21:17 1700365mS H323Evt: Recv: RegistrationRequest 192.168.52.80; Endpoints registered: 52; Endpoints in registration: 0
17:21:17 1700366mS H323Evt: RRQ --- keep-alive rejected (endpoint is currently not registered)
17:21:17 1700366mS RasTx: v=Src=192.168.16.100:1719, Dst=192.168.52.80:49308 peb=0
RasMessage = registrationReject = {
requestSeqNum = 40
protocolIdentifier = 0.0.8.2250.0.2
rejectReason = discoveryRequired
gatekeeperIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 st
}
17:21:17 1700366mS RasRx: v=IFace=LAN2, Src=192.168.52.80:49308, Dst=192.168.16.100:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 40
protocolIdentifier = 0.0.8.2250.0.2
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 7855
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 49308
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
31 2e 33 34 30 44 1.340D
}
keepAlive = true
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0036 0061 0032 0033 0032 0039 0033 0036 6a232936
0033 0030 0038 308
}
17:21:17 1700367mS H323Evt: Recv: RegistrationRequest 192.168.52.80; Endpoints registered: 52; Endpoints in registration: 0
17:21:17 1700367mS H323Evt: RRQ --- keep-alive rejected (endpoint is currently not registered)
17:21:17 1700367mS RasTx: v=Src=192.168.16.100:1719, Dst=192.168.52.80:49308 peb=0
RasMessage = registrationReject = {
requestSeqNum = 40
protocolIdentifier = 0.0.8.2250.0.2
rejectReason = discoveryRequired
gatekeeperIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 st
}
17:21:17 1700397mS RasRx: v=IFace=LAN2, Src=192.168.52.80:49308, Dst=192.168.16.100:1719 peb=0
RasMessage = gatekeeperRequest = {
requestSeqNum = 41
protocolIdentifier = 0.0.8.2250.0.2
nonStandardData = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.1
data =
85 01 40 ..@
}
rasAddress = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 49308
}
endpointType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointAlias = { 1 item(s)
[0] = dialedDigits =
36 31 32 612
}
tokens = { 1 item(s)
[0] = {
tokenOID = 2.16.840.1.114187.1.6.2
dhkey = {
halfkey =
18 11 9c 6d c9 23 57 ef ff c0 03 33 13 3c 67 13 ...m.#W....3.<g.
c7 c7 87 78 e5 81 b3 f7 9b 29 1a 98 ce 7b a9 1e ...x.....)...{..
5a fe 0b d0 9c 9b 68 97 98 d7 a1 9b 3f f7 a1 d2 Z.....h.....?...
a2 04 b6 2f 96 bf c2 be 3d d9 c4 42 7b a7 29 05 .../....=..B{.).
13 b7 cb 3e 64 2a 84 a8 ec 61 c6 78 8f 82 60 d1 ...>d*...a.x..`.
77 da b0 51 9d 72 49 e7 33 72 83 69 7e b1 c9 09 w..Q.rI.3r.i~...
a2 5f d3 f8 5e 3c ba 18 24 24 c3 5e 47 37 cc 6b ._..^<..$$.^G7.k
dd 54 95 de 0e 88 ef 2c 65 93 eb bf dc 1d 88 92 .T.....,e.......
modSize = empty
generator = empty
}
profileInfo = { 2 item(s)
[0] = {
elementID = 2
element = octets =
00 00 32 bc ..2.
}
[1] = {
elementID = 1
element = octets =
20 7b 00 00 3a 0a 00 00 6d 16 00 00 {..:...m...
}
}
}
}
authenticationCapability = { 2 item(s)
[0] = pwdSymEnc
[1] = keyExch = 2.16.840.1.114187.1.6.2
}
algorithmOIDs = { 2 item(s)
[0] = 1.3.14.3.2.6
[1] = 2.16.840.1.114187.1.3
}
featureSet = {
replacementFeatureSet = false
supportedFeatures = { 1 item(s)
[0] = {
id = oid = 2.16.840.1.114187.1.10
parameters = { 8 item(s)
[0] = {
id = standard = 5
}
[1] = {
id = standard = 9
}
[2] = {
id = standard = 10
}
[3] = {
id = standard = 11
}
[4] = {
id = standard = 13
}
[5] = {
id = standard = 14
}
[6] = {
id = standard = 15
}
[7] = {
id = standard = 16
}
}
}
}
}
}
NonStandardDataMessage = discoveryRequest = {
natTerminal
}
17:21:17 1700398mS H323Evt: Recv GRQ from c0a83450
17:21:17 1700398mS H323Evt: e_H225_AliasAddress_dialedDigits alias
17:21:17 1700398mS H323Evt: found number <612>
17:21:17 1700398mS RasTx: v=Src=192.168.16.100:1719, Dst=192.168.52.80:49308 peb=0
RasMessage = gatekeeperConfirm = {
requestSeqNum = 41
protocolIdentifier = 0.0.8.2250.0.5
gatekeeperIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 st
rasAddress = ipAddress = {
ip =
c0 a8 10 64 ...d
port = 1719
}
authenticationMode = pwdSymEnc
tokens = { 1 item(s)
[0] = {
tokenOID = 0.0
timeStamp = 1700398
random = 32096
generalID =
0044 0045 0046 0049 004e 0049 0054 0059 DEFINITY
}
}
algorithmOID = 1.3.14.3.2.6
featureSet = {
replacementFeatureSet = true
supportedFeatures = { 1 item(s)
[0] = {
id = oid = 2.16.840.1.114187.1.10
parameters = { 3 item(s)
[0] = {
id = standard = 13
}
[1] = {
id = standard = 14
}
[2] = {
id = standard = 15
}
}
}
}
}
}
17:21:17 1700399mS RasRx: v=IFace=LAN2, Src=192.168.52.80:49308, Dst=192.168.16.100:1719 peb=0
RasMessage = gatekeeperRequest = {
requestSeqNum = 41
protocolIdentifier = 0.0.8.2250.0.2
nonStandardData = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.1
data =
85 01 40 ..@
}
rasAddress = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 49308
}
endpointType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointAlias = { 1 item(s)
[0] = dialedDigits =
36 31 32 612
}
tokens = { 1 item(s)
[0] = {
tokenOID = 2.16.840.1.114187.1.6.2
dhkey = {
halfkey =
18 11 9c 6d c9 23 57 ef ff c0 03 33 13 3c 67 13 ...m.#W....3.<g.
c7 c7 87 78 e5 81 b3 f7 9b 29 1a 98 ce 7b a9 1e ...x.....)...{..
5a fe 0b d0 9c 9b 68 97 98 d7 a1 9b 3f f7 a1 d2 Z.....h.....?...
a2 04 b6 2f 96 bf c2 be 3d d9 c4 42 7b a7 29 05 .../....=..B{.).
13 b7 cb 3e 64 2a 84 a8 ec 61 c6 78 8f 82 60 d1 ...>d*...a.x..`.
77 da b0 51 9d 72 49 e7 33 72 83 69 7e b1 c9 09 w..Q.rI.3r.i~...
a2 5f d3 f8 5e 3c ba 18 24 24 c3 5e 47 37 cc 6b ._..^<..$$.^G7.k
dd 54 95 de 0e 88 ef 2c 65 93 eb bf dc 1d 88 92 .T.....,e.......
modSize = empty
generator = empty
}
profileInfo = { 2 item(s)
[0] = {
elementID = 2
element = octets =
00 00 32 bc ..2.
}
[1] = {
elementID = 1
element = octets =
20 7b 00 00 3a 0a 00 00 6d 16 00 00 {..:...m...
}
}
}
}
authenticationCapability = { 2 item(s)
[0] = pwdSymEnc
[1] = keyExch = 2.16.840.1.114187.1.6.2
}
algorithmOIDs = { 2 item(s)
[0] = 1.3.14.3.2.6
[1] = 2.16.840.1.114187.1.3
}
featureSet = {
replacementFeatureSet = false
supportedFeatures = { 1 item(s)
[0] = {
id = oid = 2.16.840.1.114187.1.10
parameters = { 8 item(s)
[0] = {
id = standard = 5
}
[1] = {
id = standard = 9
}
[2] = {
id = standard = 10
}
[3] = {
id = standard = 11
}
[4] = {
id = standard = 13
}
[5] = {
id = standard = 14
}
[6] = {
id = standard = 15
}
[7] = {
id = standard = 16
}
}
}
}
}
}
NonStandardDataMessage = discoveryRequest = {
natTerminal
}
17:21:17 1700400mS H323Evt: Recv GRQ from c0a83450
17:21:17 1700400mS H323Evt: e_H225_AliasAddress_dialedDigits alias
17:21:17 1700400mS H323Evt: found number <612>
17:21:17 1700401mS RasTx: v=Src=192.168.16.100:1719, Dst=192.168.52.80:49308 peb=0
RasMessage = gatekeeperConfirm = {
requestSeqNum = 41
protocolIdentifier = 0.0.8.2250.0.5
gatekeeperIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 st
rasAddress = ipAddress = {
ip =
c0 a8 10 64 ...d
port = 1719
}
authenticationMode = pwdSymEnc
tokens = { 1 item(s)
[0] = {
tokenOID = 0.0
timeStamp = 1700398
random = 32096
generalID =
0044 0045 0046 0049 004e 0049 0054 0059 DEFINITY
}
}
algorithmOID = 1.3.14.3.2.6
featureSet = {
replacementFeatureSet = true
supportedFeatures = { 1 item(s)
[0] = {
id = oid = 2.16.840.1.114187.1.10
parameters = { 3 item(s)
[0] = {
id = standard = 13
}
[1] = {
id = standard = 14
}
[2] = {
id = standard = 15
}
}
}
}
}
}
17:21:17 1700436mS RasRx: v=IFace=LAN2, Src=192.168.52.80:49308, Dst=192.168.16.100:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 42
protocolIdentifier = 0.0.8.2250.0.2
nonStandardData = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.1
data =
09 81 01 00 26 1e 9e 00 01 20 01 00 06 38 bb 3c ....&.... ...8.<
bf 77 73 01 00 05 c0 02 00 00 2f 01 00 01 80 01 .ws......./.....
00 01 00 ...
}
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 7855
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 34 50 ..4P
port = 49308
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
terminalAlias = { 1 item(s)
[0] = dialedDigits =
36 31 32 612
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
31 2e 33 34 30 44 1.340D
}
cryptoTokens = { 1 item(s)
[0] = cryptoEPPwdEncr = {
algorithmOID = 1.3.14.3.2.6
paramS = {
}
encryptedData =
1a 7b c2 6f c6 03 b7 d6 10 00 f7 d2 3b 10 cc e5 .{.o........;...
73 06 c9 86 37 58 b2 f9 c4 12 22 0c s...7X....".
}
}
keepAlive = false
willSupplyUUIEs = false
}
NonStandardDataMessage = loginRequest = {
forceLogin = false
audioCapable = true
callSigProtocol = h323AnnexL-P
connectionType = lan
emergencyCallHandling = emerExtension
macAddress =
38 bb 3c bf 77 73 8.<.ws
signalingChannelRecoveryAlgorithmVersion = 1
unicodeScript = 33554479
ttiCapable
sendButtonInfo = true
sendSwitchRelease
sendStationInfo
}
17:21:17 1700436mS H323Evt: Recv: RegistrationRequest 192.168.52.80; Endpoints registered: 52; Endpoints in registration: 0
17:21:17 1700437mS H323Evt: e_H225_AliasAddress_dialedDigits alias
17:21:17 1700437mS H323Evt: found number <612>
17:21:17 1700437mS H323Evt: RRQ --- CallSigProtocol is H323AnnexL_P. Go for Avaya 4600IP phone
17:21:17 1700437mS H323Evt: RRQ --- Treat this as a forced login, the phone was probably rebooted (same_cs_addr 1)
17:21:17 1700438mS H323Evt: GK: Unregister endpoint CustomerName_5df276a27b337860 for extension 612 reason 4
17:21:17 1700438mS CMExtnEvt: Test Phone:612 ExtnFault now 1
17:21:17 1700439mS CMExtnTx: v=612, p1=8071
CMReleaseComp
Line: type=IPLine 250 Call: lid=322 id=65 in=1
Cause=16, Normal call clearing
17:21:17 1700441mS H323Evt: RRQ --- Register extn 612 using product IP_Phone, version 1.340D
17:21:17 1700442mS H323Evt: v=(null) stacknum=0 State, new=NullState, old=NullState id=0
17:21:17 1700444mS RasTx: v=Src=192.168.16.100:1719, Dst=192.168.52.80:49308 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 42
protocolIdentifier = 0.0.8.2250.0.2
nonStandardData = {
nonStandardIdentifier = object = 2.16.840.1.113778.4.2.1
data =
2c a0 46 69 6c 74 65 72 6d 69 73 74 29 80 4c 30 ,.CustomerName).L0
01 40 16 02 b4 00 2e 00 b6 9e c6 3e 03 00 07 68 .@.........>...h
c0 a8 10 64 13 8d 20 00 22 1f 03 80 70 00 06 03 ...d.. ."...p...
80 01 00 01 ac 80 80 00 06 03 80 01 00 01 ac 80 ................
90 00 06 03 80 01 00 01 ac 0b 50 57 39 2e 30 2e ..........PW9.0.
30 2e 38 32 39 0b 80 db 60 23 80 01 80 01 80 01 0.829...`#......
80 03 80 65 6e 11 40 36 31 32 5f 31 36 78 78 64 ...en.@612_16xxd
61 74 61 2e 74 78 74 ata.txt
}
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 10 64 ...d
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 006c 0074 0065 0072 006d 0069 CustomerName
0073 0074 005f 0035 0064 0066 0032 0037 st_5df27
0037 0030 0064 0035 0064 0037 0036 0030 70d5d760
0061 0064 0062 adb
timeToLive = 112
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
NonStandardDataMessage = loginAccepted = {
locationName =
46 69 6c 74 65 72 6d 69 73 74 CustomerName
terminalService = fullService
qosParameters = { 2 item(s)
[0] = {
service = audio
layer3param = 46
layer4param = {
lowL4PortValue = 46750
highL4PortValue = 50750
}
rtcpParameters = {
rtcpControl = true

 

[ExpediaTelProg]

I am not expert on these traces either.

but first thing I would do is bring this phone to the site to eliminate the VPN from equation.

 

[JoeNewton]

Hi ExpediaTelProg,

Thank you for your reply. The phone was previously at the main site and worked fine, its only been playing up since it was taken to the remote site, so I think it must be a VPN related issue.

Thanks,

Joe Newton

 

[JoeNewton]

Hi Guys.

Any thoughts on this?

Thanks,

Joe Newton

 

[Telecomboy]

From a previous post:

The IPO Gatekeeper sends keep alive packets to the phones, the phones have to respond with a acknowledge. The Gatekeeper accepts a maximum of 9 missing acks in a row and then it unregister the phone. It works the other way around too, if the phone misses 9 acks in a row it will reboot itself trying to get problems solved.
In average every 10 seconds there are keep alive packets send, if you have packet loss longer then 90 seconds then it is very likely the phones will reboot

 

[kwing112000]

What devices are connecting the sites. this is probably a ALG/Inspect/Transformation issue in your VPN.

Kevin Wing
ACSS Small and Medium Enterprise (SME) Communications
ACS- Implement IP Office
ACA- Implement IP Office
Vive Communications

 

[JoeNewton]

Thanks both.

Telecomboy - I suspected keepalives, but any idea how I can prove this? Or what I can ask them to change on the VPN to rectify this? They say that there is no firewall between the two sites across the tunnel.

kwing112000 - It is a Draytek at the main site, where the IPO is, and a Cisco router at the remote site. I suggested this, but they said that because there is no NAT on the VPN there is no ALG/Transformation. Also, because it is a router rather than an ASA there is no packet inspection (or so they say). I looked into potential issues with Cisco devices and H323, and I've seen a lot of references to "nofixup", but again they say that that command doesn't exist on their router...

Its frustrating because we are relying on a 3rd party IT company. Ideally I need to be able to tell them exactly what is happening, and put the ball squarely back in their court.

Thanks for all your help.

Joe Newton

 

[Westi]

You will find that the VPN swallows the keep alive signalling and that is causing the issue.
Make sure that in your firewalls the h.323 and SIP "improvements" are turned off.

Cisco calls them h.323 fixups



Joe W.

FHandw, ACSS (SME)


"This is the end of the world, make sure to buy your T-shirt before it is too late"
Original expression of my daughter