cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1407
Views
0
Helpful
8
Replies

inbound 8710 dialing issues

watkins.david
Level 1
Level 1

I have a 8710 that is registered to vcs (x7.1) with a sip trunk to CUCM(8.6) where my CTS rooms are registered.

Calling from the 8710 to the CTS rooms work fine, but dialing a conference room on the 8710 from the CTS endpoint does not work.

SIP packet sniff from TS endpoint shows a 'internal server error"

The 8710 reports the endpoint connecting, and then immediately disconnected.

I have the DN numbers used fro the call-in match paramenters'

I seem to get random errors from VCS; ie, call leg does not exist, request termination, etc.

ANyone have any idea what this might be?                  

1 Accepted Solution

Accepted Solutions

Hi David,

You're hitting the bug CSCty07061 which is resolved in CUCM 9.0 and some engineering specials on the 8.6.2 train.

As a workaround you can create a bogus A Record so the resolution will work.

-Paul

Sent from Cisco Technical Support iPad App

View solution in original post

8 Replies 8

Patrick Pettit
Cisco Employee
Cisco Employee

HI David.  Would be curious to know the versions on the CTS.  From 1.7.4 or higher, you don't need to add the CTS's as endpoints in the TS. 

Also, its important, if multiple screen CTS's are being used, its important to keep the call SIP to SIP without any interworking being involved here as some information is passed in the SIP headers to define the system as 3 screen system, single screen etc. 

If the call is SIP to SIP, and its still failing, would be interested in seeing network log debug from VCS, and SIP log from TS to see what may be going on here. 

If you can provide it, would be nice to see. 

VR

Patrick

                   1.8.2 is the code rev for th CTS endpoints

attached are the TS SIP logs

and here are the VCS network logs for anything coming from the cucm

Sep 5 09:49:13tvcs: UTCTime="2012-09-05 15:49:13,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=69576a185bb9b2c6@10.52.36.14"
Sep 5 09:49:13tvcs: UTCTime="2012-09-05 15:49:13,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=69576a185bb9b2c6@10.52.36.14"
Sep 5 09:48:42tvcs: UTCTime="2012-09-05 15:48:42,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=19810c4b32d41f39@10.52.36.14"
Sep 5 09:48:42tvcs: UTCTime="2012-09-05 15:48:42,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=19810c4b32d41f39@10.52.36.14"
Sep 5 09:48:12tvcs: UTCTime="2012-09-05 15:48:12,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=d726f682248dc0fe@10.52.36.14"
Sep 5 09:48:12tvcs: UTCTime="2012-09-05 15:48:12,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=d726f682248dc0fe@10.52.36.14"
Sep 5 09:47:42tvcs: UTCTime="2012-09-05 15:47:42,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=47c0916ae836f746@10.52.36.14"
Sep 5 09:47:42tvcs: UTCTime="2012-09-05 15:47:42,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=47c0916ae836f746@10.52.36.14"
Sep 5 09:47:11tvcs: UTCTime="2012-09-05 15:47:11,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=2309b4232bb47ac3@10.52.36.14"
Sep 5 09:47:11tvcs: UTCTime="2012-09-05 15:47:11,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=2309b4232bb47ac3@10.52.36.14"
Sep 5 09:46:41tvcs: UTCTime="2012-09-05 15:46:41,270" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=06f96859b7a02237@10.52.36.14"
Sep 5 09:46:41tvcs: UTCTime="2012-09-05 15:46:41,269" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=06f96859b7a02237@10.52.36.14"
Sep 5 09:46:09tvcs: UTCTime="2012-09-05 15:46:09,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=c4568d50b00b8791@10.52.36.14"
Sep 5 09:46:09tvcs: UTCTime="2012-09-05 15:46:09,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=c4568d50b00b8791@10.52.36.14"
Sep 5 09:45:38tvcs: UTCTime="2012-09-05 15:45:38,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=c322adffdcb056e9@10.52.36.14"
Sep 5 09:45:38tvcs: UTCTime="2012-09-05 15:45:38,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=c322adffdcb056e9@10.52.36.14"
Sep 5 09:45:07tvcs: UTCTime="2012-09-05 15:45:07,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=cb180fe914a02584@10.52.36.14"
Sep 5 09:45:07tvcs: UTCTime="2012-09-05 15:45:07,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=cb180fe914a02584@10.52.36.14"
Sep 5 09:44:37tvcs: UTCTime="2012-09-05 15:44:37,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=4ccb3ed42afa2b1e@10.52.36.14"
Sep 5 09:44:37tvcs: UTCTime="2012-09-05 15:44:37,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=4ccb3ed42afa2b1e@10.52.36.14"
Sep 5 09:44:17tvcs: UTCTime="2012-09-05 15:44:17,026" Module="network.sip" Level="INFO": Dst-ip="10.52.36.15" Dst-port="25011" Detail="Sending Response Code=481, Method=BYE, To=sip:3711@10.52.36.12, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:17tvcs: UTCTime="2012-09-05 15:44:17,018" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=481, Method=BYE, To=sip:3711@10.52.36.12, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:17tvcs: UTCTime="2012-09-05 15:44:17,017" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=BYE, Request-URI=sip:3711@10.52.36.12:5060;transport=tcp, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:17tvcs: UTCTime="2012-09-05 15:44:17,016" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="25011" Detail="Receive Request Method=BYE, Request-URI=sip:3711@10.52.36.12:5060;transport=tcp, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:16tvcs: UTCTime="2012-09-05 15:44:16,355" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:16tvcs: UTCTime="2012-09-05 15:44:16,354" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:12tvcs: UTCTime="2012-09-05 15:44:12,354" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:12tvcs: UTCTime="2012-09-05 15:44:12,353" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:08tvcs: UTCTime="2012-09-05 15:44:08,353" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:08tvcs: UTCTime="2012-09-05 15:44:08,352" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:07tvcs: UTCTime="2012-09-05 15:44:07,269" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="5060" Detail="Receive Response Code=200, Method=OPTIONS, To=sip:10.52.36.12:5060, Call-ID=ab14822edbe6470c@10.52.36.14"
Sep 5 09:44:07tvcs: UTCTime="2012-09-05 15:44:07,268" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Request Method=OPTIONS, Request-URI=sip:10.52.36.12:5060;transport=tcp, Call-ID=ab14822edbe6470c@10.52.36.14"
Sep 5 09:44:04tvcs: UTCTime="2012-09-05 15:44:04,352" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:04tvcs: UTCTime="2012-09-05 15:44:04,351" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:02tvcs: UTCTime="2012-09-05 15:44:02,351" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:02tvcs: UTCTime="2012-09-05 15:44:02,350" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:01tvcs: UTCTime="2012-09-05 15:44:01,350" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="5060" Detail="Sending Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:01tvcs: UTCTime="2012-09-05 15:44:01,349" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,859" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="38810" Detail="Sending Response Code=481, Method=CANCEL, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,859" Module="network.sip" Level="INFO": Src-ip="10.52.36.12" Src-port="38810" Detail="Receive Request Method=CANCEL, Request-URI=sip:511001@VCS-CC.uc.supervalu.com:5060, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,857" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="38810" Detail="Sending Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,850" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=200, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,768" Module="network.sip" Level="INFO": Dst-ip="10.52.36.12" Dst-port="38810" Detail="Sending Response Code=180, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,761" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=180, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,650" Module="network.sip" Level="INFO": Src-ip="10.52.36.15" Src-port="5061" Detail="Receive Response Code=100, Method=INVITE, To=sip:511001@VCS-CC.uc.supervalu.com, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,646" Module="network.sip" Level="INFO": Dst-ip="10.52.36.15" Dst-port="5061" Detail="Sending Request Method=INVITE, Request-URI=sip:511001@uc.supervalu.com:5060;registration-serial-number=c7cb3662-f6f9-11e1-a634-0010f3210632, Call-ID=818a6580-4717340-6f687-c24340a@10.52.36.12"
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,612" Module="network.h323" Level="INFO": Dst-ip="10.52.36.15" Dst-port="1719"
Detail="Sending RAS LRQ SeqNum=57306 DestAlias='511001' SrcAlias='3711@10.52.36.12' Hopcount=1 "
Sep 5 09:44:00tvcs: UTCTime="2012-09-05 15:44:00,611" Module="network.h323" Level="INFO": Dst-ip="10.52.36.15" Dst-port="1719"
Detail="Sending RAS LRQ SeqNum=57305 DestAlias='511001@uc.supervalu.com:5060' SrcAlias='3711@10.52.36.12' Hopcount=1 "

Hi David.

Seems the TS is constantly sending 200 Ok with SDP, but not getting an ACK back to start the call from the CUCM side perhaps.  If you can, would need to confirm that the VCS is sending the 200 OK with SDP to the CUCM side, and would be interested in seeing if the CUCM is receiving it, and sending the ACK back to finish the call. 

If you go to Maintanance>Diagnostics>Diagnostic Logging and set Network Log Level to Debug, and place the same call, this would show us the comms between the TS and the CUCM. 

I'm wondering also if the CCM can't process the large SDP from the TS at the beginning here. 

http://www.cisco.com/en/US/docs/telepresence/infrastructure/vcs/config_guide/Cisco_VCS_and_CUCM_Deployment_Guide_CUCM_6-1_7_8_and_X7-1.pdf

See Page 28 of 53.  Can you verify the SIP MAX Incoming Message Size is increased here?  I'd check here to see if this may be causing you your problem, and if this is set right, and it still fails, would need to see the network log level debug from the VCS. 

VR

Patrick

i did change the SIP max incoming from 5000 to 12000. Calls still dont complete however.

HI David.  Sounds good.  If you can, would you be able to post the log, network log level set to debug on the diagnostics page would be helpful.  Turn it on to debug, place the call, and lets take a look at the log so we can see whats happening. 

Thanks. 

VR

Patrick

            I also tried using the vcs-interop normalization script, to no avail

Looked at this with my call manager expert and it appears that its the CM that is hanging up the call. from the RTMT it looks like a dns query is failing, and then the SIP timer expires:

|2,100,63,1.3508793^10.52.36.15^*

11:20:09.377 |//SIP/SIPDns(2,72,1)/wait_SdlDnsSrvRecordRsp: Received SdlDnsSrvRecordRsp ReqCode is  -1|0,0,0,0.0^*^*

11:20:09.377 |//SIP/SIPDns(2,72,1)/copyRecordRspToRecordReq: Retry SRV query as an 1 query|0,0,0,0.0^*^*

11:20:09.377 |//SIP/SIPDns(2,72,1)/wait_SdlDnsSrvRecordRsp: (DNS A or AAAA query called as SRV query Fail):hostname=uc.supervalu.com

Contact: <sip:511001@uc.supervalu.com;gr=urn:uuid:b716ad28-2312-54db- 825f-4aca99cade58>;isfocus;x-cisco-tip

|2,100,63,1.3508793^10.52.36.15^*

11:20:09.377 |//SIP/SIPDns(2,72,1)/wait_SdlDnsSrvRecordRsp: Received SdlDnsSrvRecordRsp ReqCode is  -1|0,0,0,0.0^*^*

11:20:09.377 |//SIP/SIPDns(2,72,1)/copyRecordRspToRecordReq: Retry SRV query as an 1 query|0,0,0,0.0^*^*

11:20:09.377 |//SIP/SIPDns(2,72,1)/wait_SdlDnsSrvRecordRsp: (DNS A or AAAA query called as SRV query Fail):hostname=uc.supervalu.com

not sure where its trying to get uc.supervalu.com as that is a domain not a hostname.

After a couple more hours of troubleshooting he suggested that its from this:

Contact: <sip:511001@uc.supervalu.com;gr=urn:uuid:b716ad28-2312-54db- 825f-4aca99cade58>;isfocus;x-cisco-tip

in the header, which is not received when dialing a jabber client.

Hi David,

You're hitting the bug CSCty07061 which is resolved in CUCM 9.0 and some engineering specials on the 8.6.2 train.

As a workaround you can create a bogus A Record so the resolution will work.

-Paul

Sent from Cisco Technical Support iPad App

bogus A record pointing at my vcs seems to have done the trick, thanks!