DX650 disconnects from 8510 MCU

John Garrison · ‎01-30-2014

I am having an issue with a DX650 disconnecting the from the MCU after 15 minutes. The DX is registered to CUCM 9.1 and the MCU is registered to VCS. I have a SIP trunk between the VCS and CUCM. The MCU is giving the following disconnect reason. What does faild to authenticate with VNC mean?

<event type="participant_left" time="16:49:10" date="29 January 2014" index="1033"><conference name="Albert Leung" unique_id="99023"> </conference><endpoint_details configured_name="<none>" h323_alias="Albert Leung" dn="5125@10.x.x.x" ip_address="10.x.x.x"> </endpoint_details><participants participant_id="64"> </participants><call direction="incoming" media_encryption_status="unencrypted" protocol="SIP" disconnect_reason="failed to authenticate with vnc" time_in_conference_in_minutes="15" time_in_conference="15 mins 0 sec"> </call><media_from_endpoint bandwidth="576000 bit/s" audio_codec="G.711mu" video_codec="H.264" resolution="1280 x 768"> </media_from_endpoint><media_to_endpoint bandwidth="384000 bit/s" audio_codec="G.711mu" video_codec="H.264" resolution="1280 x 768"> </media_to_endpoint></event>

Here are the CUCM logs from when the disconnection happens. One of the things I notice is the setPeriodicReInviteTimer is set to 900 seconds, which happens to be the amount of time that the connection is maintained.

INVITE sip:0560599f-0c92-195d-7553-f49ebe5146a6@10.20.44.135:43798;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 10.x.x.x:5060;branch=z9hG4bK912ad2b95

From: <sip:6125@10.x.x.x>;tag=4296~a965ef85-e94b-4ccd-b2b2-bd592a21ba03-27380814

To: "Joe Blow" <sip:5125@10.x.x.x>;tag=dca5f487247655b33879fb0d-5e904e52

Date: Wed, 29 Jan 2014 21:49:10 GMT

Call-ID: dca5f487-24760807-32a79f0e-73f55ce7@10.20.44.135

Supported: timer,resource-priority,replaces

Min-SE: 1800

User-Agent: Cisco-CUCM9.1

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

CSeq: 102 INVITE

Max-Forwards: 70

Expires: 180

Allow-Events: presence

Call-Info: <urn:x-cisco-remotecc:callinfo>; security= NotAuthenticated; orientation= to; gci= 2-110569; call-instance= 1

Remote-Party-ID: <sip:6125@10.x.x.x>;party=calling;screen=no;privacy=off

Contact: <sip:6125@10.x.x.x:5060;transport=tcp>;isfocus

Content-Type: application/sdp

Content-Length: 506

v=0

o=CiscoSystemsCCM-SIP 4296 1 IN IP4 10.x.x.x

s=SIP Call

c=IN IP4 10.20.60.25

b=TIAS:384000

b=AS:384

t=0 0

m=audio 53502 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=ptime:20

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

m=video 53504 RTP/AVP 126

b=TIAS:320000

a=label:11

a=rtpmap:126 H264/90000

a=fmtp:126 profile-level-id=42E00C;packetization-mode=1;max-mbps=108000;max-fs=3600;max-cpb=21;max-dpb=5400;max-br=512;max-fps=6000

a=content:main

a=rtcp-fb:* nack pli

a=rtcp-fb:* ccm fir

00900769.001 |15:49:10.789 |AppInfo |SIPTcp - SdlRead bufferLen=1448

00900769.002 |15:49:10.789 |AppInfo |SIPTcp - Saving 1448 bytes of incomplete message data.

00900770.001 |15:49:10.789 |AppInfo |SIPTcp - SdlRead bufferLen=1033

00900770.002 |15:49:10.789 |AppInfo |SIPTcp - wait_SdlReadRsp: Incoming SIP TCP message from 10.20.44.135 on port 43798 index 3 with 2481 bytes:

[11877,NET]

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.x.x.x:5060;branch=z9hG4bK912ad2b95

From: <sip:6125@10.x.x.x>;tag=4296~a965ef85-e94b-4ccd-b2b2-bd592a21ba03-27380814

To: "Joe Blow" <sip:5125@10.x.x.x>;tag=dca5f487247655b33879fb0d-5e904e52

Call-ID: dca5f487-24760807-32a79f0e-73f55ce7@10.20.44.135

Date: Wed, 29 Jan 2014 21:49:05 GMT

CSeq: 102 INVITE

Server: Cisco-CP-DX650/10.0.2

Contact: <sip:0560599f-0c92-195d-7553-f49ebe5146a6@10.20.44.135:43798;transport=tcp>;video

Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE,INFO

Remote-Party-ID: "Joe Blow" <sip:5125@10.x.x.x>;party=calling;id-type=subscriber;privacy=off;screen=yes

Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-6.0.2,X-cisco-xsi-8.5.1

Allow-Events: kpml,dialog

Recv-Info: conference

Recv-Info: x-cisco-conference

Content-Length: 1378

Content-Type: application/sdp

Content-Disposition: session;handling=optional

v=0

o=Cisco-SIPUA 9022 1 IN IP4 10.20.44.135

s=SIP Call

t=0 0

m=audio 21126 RTP/AVP 0 8 18 102 9 116 124 101

c=IN IP4 10.20.44.135

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:102 L16/16000

a=rtpmap:9 G722/8000

a=rtpmap:116 iLBC/8000

a=fmtp:116 mode=20

a=rtpmap:124 ISAC/16000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=sendrecv

m=video 29228 RTP/AVP 100 112 126 97

c=IN IP4 10.20.44.135

b=TIAS:4000000

a=rtpmap:100 H264/90000

a=fmtp:100 profile-level-id=640028;packetization-mode=1;level-asymmetry-allowed=1;max-fps=6000;max-rcmd-nalu-size=256000

a=imageattr:* recv [x=1024,y=600,q=0.60] [x=1280,y=720,q=0.50]

a=rtpmap:112 H264/90000

a=fmtp:112 profile-level-id=4D0028;packetization-mode=1;level-asymmetry-allowed=1;max-fps=6000;max-rcmd-nalu-size=256000

a=imageattr:* recv [x=1024,y=600,q=0.60] [x=1280,y=720,q=0.50]

a=rtpmap:126 H264/90000

a=fmtp:126 profile-level-id=428028;packetization-mode=1;level-asymmetry-allowed=1;max-fps=6000;max-rcmd-nalu-size=256000

a=imageattr:* recv [x=1024,y=600,q=0.60] [x=1280,y=720,q=0.50]

a=rtpmap:97 H264/90000

a=fmtp:97 profile-level-id=428028;packetization-mode=0;level-asymmetry-allowed=1;max-fps=6000;max-rcmd-nalu-size=256000

a=imageattr:* recv [x=1024,y=600,q=0.60] [x=1280,y=720,q=0.50]

a=rtcp-fb:* nack pli

a=rtcp-fb:* ccm fir

a=sendrecv

00900770.003 |15:49:10.789 |AppInfo |SIPTcp - SignalCounter = 5150

00900771.001 |15:49:10.789 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_TRYING value=500 retries=6

00900771.002 |15:49:10.789 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/extractAssertedInfo: parseResult[1]

00900771.003 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_fmtp_is_redundant_pic_cap returnVal=1

00900771.004 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_in_band_parameter_sets returnVal=1

00900771.005 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_use_level_src_parameter_sets returnVal=1

00900771.006 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: got max-fps=6000

00900771.007 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_fmtp_is_redundant_pic_cap returnVal=1

00900771.008 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_in_band_parameter_sets returnVal=1

00900771.009 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_use_level_src_parameter_sets returnVal=1

00900771.010 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: got max-fps=6000

00900771.011 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_fmtp_is_redundant_pic_cap returnVal=1

00900771.012 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_in_band_parameter_sets returnVal=1

00900771.013 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_use_level_src_parameter_sets returnVal=1

00900771.014 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: got max-fps=6000

00900771.015 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_fmtp_is_redundant_pic_cap returnVal=1

00900771.016 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_in_band_parameter_sets returnVal=1

00900771.017 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: sdp_attr_get_fmtp_use_level_src_parameter_sets returnVal=1

00900771.018 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getH264VideoCapability: got max-fps=6000

00900771.019 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/convertStackSDPtoCmSDP: ***SIPSdp=4406 bytes

00900771.020 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/copyConnectedInfoAdjustParseResult: identityParseResult[1] || rpidHdr

00900771.021 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/copyConnectedInfoAdjustParseResult: rpidHdr values taken

00900771.022 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=4296/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId

00900771.023 |15:49:10.790 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/parseContactHeader: Incoming Contact=[<sip:0560599f-0c92-195d-7553-f49ebe5146a6@10.20.44.135:43798;transport=tcp>;video]

00900773.001 |15:49:10.790 |AppInfo |SIPTcp - wait_SdlSPISignal: Outgoing SIP TCP message to 10.20.44.135 on port 43798 index 3

[11878,NET]

ACK sip:0560599f-0c92-195d-7553-f49ebe5146a6@10.20.44.135:43798;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 10.x.x.x:5060;branch=z9hG4bK91363d0f7b4

From: <sip:6125@10.x.x.x>;tag=4296~a965ef85-e94b-4ccd-b2b2-bd592a21ba03-27380814

To: "Joe Blow" <sip:5125@10.x.x.x>;tag=dca5f487247655b33879fb0d-5e904e52

Date: Wed, 29 Jan 2014 21:49:10 GMT

Call-ID: dca5f487-24760807-32a79f0e-73f55ce7@10.y.y.y

Max-Forwards: 70

CSeq: 102 ACK

Allow-Events: presence

Content-Length: 0

00900774.001 |15:49:10.790 |AppInfo |globalizeCgpn: StationSIPCdfc on device SEPDCA5F4872476 , CSS = ,useDevicePoolCgpnIngressDnCSS =1 AlternateCgpn(global)= cgpn=5125

00900775.001 |15:49:10.790 |AppInfo |SIPCdpc(38) - setPeriodicReInviteTimer: interval[900] secs

00900775.002 |15:49:10.790 |AppInfo |SIPCdpc(38) - getIdentityIncomingSIPMsg: fIdParseRes=[11], fRemoteNum[5125], fRemoteName[Joe Blow], fRemoteUser[], fRemoteHostname[], fHasBlended[false], fUserEqPhone[false], fRemoteNumPi[1], fRemoteNamPi[1], fRemoteSi[1]

00900775.003 |15:49:10.790 |AppInfo |SIPCdpc(38) - getIdentityIncomingSIPMsg: HCSCLLConfig = 0

00900775.004 |15:49:10.790 |AppInfo |SIPCdpc(38) - processRemoteIdentityInfo: Ignoring Remote-Party-ID/Asserted connected uri

00900775.005 |15:49:10.790 |AppInfo |SIPCdpc(38) - processRemoteIdentityInfo: identityFlag=[Unchanged]

00900775.006 |15:49:10.790 |AppInfo |SIPCdpc(38) - processTargetOrSessionRefresh: refresh[1], resulted sdp chng, sending updated answer to Media

00900775.007 |15:49:10.790 |AppInfo |SIPCdpc(38) - handleSDPUpdatedAnswerInd: SDP:

SDP:: isPresent=1, role=2

numAudiomLines=1 numVideomLines=1

numAppplicationmLines=0 numBFCPAppmLines=0 numIXAppmLines=0 numT38faxmLines=0

anatPresent=0

Bandwidth:: enabledMask=0x00000000 as=0 ct=0 tias=0 maxprate=0

SdpSetupType=0, DTLS Fingerprint=, ektInd=0

audiomLines[0] =

Media_mLine:: remoteIpAddress=0x872c140a remoteRtpPortNumber=21126 mSDPMode=0 mediaAttr=0x00000000 - idle=0

mLineStackIdx=1 telephonyEvent=101 silenceSuppressionFlag=0 midID=-1

SdpSetupType=0, DTLS Fingerprint=, ektInd=0

Bandwidth:: enabledMask=0x00000000 as=0 ct=0 tias=0 maxprate=0

rtp=1,srtp=0,rtcpmux=0

mLine:enabledMask=0x00000000

caps[0]:: payloadCapability=4 maxFramesPerPacket=20

caps[1]:: payloadCapability=2 maxFramesPerPacket=20

caps[2]:: payloadCapability=11 maxFramesPerPacket=20

caps[3]:: payloadCapability=12 maxFramesPerPacket=20

caps[4]:: payloadCapability=6 maxFramesPerPacket=20

caps[5]:: payloadCapability=86 maxFramesPerPacket=20

ILBC Params:: mode=1 framesPerPacket=20

caps[6]:: payloadCapability=89 maxFramesPerPacket=60

videomLines[0] =

Media_mLine:: remoteIpAddress=0x872c140a remoteRtpPortNumber=29228 mSDPMode=0 mediaAttr=0x00000000 - idle=0

mLineStackIdx=2 telephonyEvent=0 silenceSuppressionFlag=0 midID=-1

SdpSetupType=0, DTLS Fingerprint=, ektInd=0

Bandwidth:: enabledMask=0x00000004 as=0 ct=0 tias=4000000 maxprate=0

rtp=1,srtp=0,rtcpmux=0

mLine:enabledMask=0x00000002

Rtcp-Fb:payload=255, val=2

Rtcp-Fb:payload=255, val=6

imageattr=recv [x=1024,y=600,q=0.60] [x=1280,y=720,q=0.50]

caps[0]:: payloadTypeMapping=100 payloadCapability=103

H264:: maxBitRate=0 Profile:Profile_iop:Level=0x64:0:28 packetizationMode=1 maxRcmdNaluSize=256000 levelAsymmetryAllowed=1

imageattr=

caps[1]:: payloadTypeMapping=112 payloadCapability=103

H264:: maxBitRate=0 Profile:Profile_iop:Level=0x4d:0:28 packetizationMode=1 maxRcmdNaluSize=256000 levelAsymmetryAllowed=1

imageattr=

caps[2]:: payloadTypeMapping=126 payloadCapability=103

H264:: maxBitRate=0 Profile:Profile_iop:Level=0x42:80:28 packetizationMode=1 maxRcmdNaluSize=256000 levelAsymmetryAllowed=1

imageattr=

caps[3]:: payloadTypeMapping=97 payloadCapability=103

H264:: maxBitRate=0 Profile:Profile_iop:Level=0x42:80:28 packetizationMode=0 maxRcmdNaluSize=256000 levelAsymmetryAllowed=1

imageattr=