07-01-2013 04:02 PM - edited 03-18-2019 01:23 AM
I've installed VCS using the 'Single VCS Control Deployment Guide' for 7.2.2. After following the directions, I'm unable to register an E20 to VCS. Below are the network logs from VCS.
2013-07-01T18:39:23-04:00 tvcs: UTCTime="2013-07-01 22:39:23,056" Module="network.sip" Level="INFO": Dst-ip="10.10.1.56" Dst-port="57531" Detail="Sending Response Code=404, Method=SUBSCRIBE, To=sip:provisioning@mydomain.com, Call-ID=0f11da9ae013fe17@10.10.1.56"I haven't been able to determine the meaning of response code 404.
Thanks,
Robb R.
07-01-2013 06:06 PM
Hi Robb,
the initial subscribe message sent by E-20 is not matching to any of the search rule on the VCS and hence it send 404 not found which means server can't find the requested destination.
check the search rules, transforms you created.
Rgds
Alok
07-01-2013 07:28 PM
Alok,
Any suggestions as to what search rules I may need to configure? I've configured the ones beginning on page 12 of the deployment guide below.
Thanks!
Robb R.
07-01-2013 08:20 PM
Hi Robb,
can you paste the whole log, i mean to say the snippet you posted above starts with VCS sending 404 message.
i want to see what is happening when the VCS is receives the first subscribe message. does it goes in some search then it sends 404 message.
Please post the whole set of logs and let me know what search rules you have configured on the VCS?
Rgds
Alok
07-02-2013 06:39 AM
Alok, below are the only logs recorded by VCS when I attempt to connect. The top entry is the most recent. Below are screenshots of my Transform and Search rules.
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,889" Module="network.sip" Level="INFO": Dst-ip="10.254.0.5" Dst-port="59373" Detail="Sending Response Code=404, Method=SUBSCRIBE, To=sip:provisioning@mydomain.com, Call-ID=a0df36ba0e5130cc@127.0.0.1"
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,887" Module="network.search" Level="INFO": Detail="Considering search rule 'Local zone – full URI' towards target 'LocalZone' at priority '49' with alias 'cisco@mydomain.com'"
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,886" Module="network.search" Level="INFO": Detail="Considering search rule 'Local zone – no domain' towards target 'LocalZone' at priority '48' with alias 'cisco'"
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,883" Module="network.ldap" Level="INFO": Detail="Authentication credential found in directory for identity: cisco"
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,879" Module="network.sip" Level="INFO": Src-ip="10.254.0.5" Src-port="59373" Detail="Receive Request Method=SUBSCRIBE, Request-URI=sip:cisco@mydomain.com, Call-ID=a0df36ba0e5130cc@127.0.0.1"
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,827" Module="network.sip" Level="INFO": Dst-ip="10.254.0.5" Dst-port="59373" Detail="Sending Response Code=407, Method=SUBSCRIBE, To=sip:provisioning@mydomain.com, Call-ID=a0df36ba0e5130cc@127.0.0.1"
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,827" Module="network.sip" Level="INFO": Src-ip="10.254.0.5" Src-port="59373" Detail="Receive Request Method=SUBSCRIBE, Request-URI=sip:cisco@mydomain.com, Call-ID=a0df36ba0e5130cc@127.0.0.1"
Transform Rule:
Search Rule 1:
Search Rule 2:
Search Rule 3:
07-02-2013 07:52 AM
Hi Robb,
i am getting confused with the below two lines
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,889" Module="network.sip" Level="INFO": Dst-ip="10.254.0.5" Dst-port="59373" Detail="Sending Response Code=404, Method=SUBSCRIBE, To=sip:
,
Call-ID=a0df36ba0e5130cc@127.0.0.1
"
2013-07-02T09:21:50-04:00 tvcs: UTCTime="2013-07-02 13:21:50,827" Module="network.sip" Level="INFO": Dst-ip="10.254.0.5" Dst-port="59373" Detail="Sending Response Code=407, Method=SUBSCRIBE, To=sip:
,
Call-ID=a0df36ba0e5130cc@127.0.0.1
"
one time the reply is 404 i.e. not found and one time it is 407 which basically is asking for authentication.
anyways one more thing why you have search rules 49 & 48 with ".+" and why not ".*". can you change it to ".*@mydomain.com.*" and check again.
Also can you pull the diagnostic logs from the VCS in debug mode and see what happens after the first subscribe message.
Rgds
Alok
07-02-2013 11:17 AM
Thanks Alok. The authentication (407) request is generated before the 404. Here's the debug output. I had to run this test with the Jabber Video (Movi) client as I don't currently have access to the E20. I also changed the .+ to .* with the same result.
2013-07-02T14:08:52-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:52,355" Module="network.tcp" Level="DEBUG": Src-ip="10.254.0.7" Src-port="62338" Dst-ip="10.10.2.100" Dst-port="5061" Detail="TCP Connecting"
2013-07-02T14:08:52-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:52,356" Module="network.tcp" Level="DEBUG": Src-ip="10.254.0.7" Src-port="62338" Dst-ip="10.10.2.100" Dst-port="5061" Detail="TCP Connection Established"
2013-07-02T14:08:52-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:52,784" Module="network.sip" Level="INFO": Src-ip="10.254.0.7" Src-port="62338" Detail="Receive Request Method=SUBSCRIBE, Request-URI=sip:cisco@mydomain.com, Call-ID=6af0c6f351e86ac8@127.0.0.1"
2013-07-02T14:08:52-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:52,784" Module="network.sip" Level="DEBUG": Src-ip="10.254.0.7" Src-port="62338"
SIPMSG:
|SUBSCRIBE sip:cisco@mydomain.com SIP/2.0
Via: SIP/2.0/TLS 10.254.0.7:62338;branch=z9hG4bK41804a1eee2152f9366168960169b367.1;received=10.254.0.7;rport=62338
Call-ID: 6af0c6f351e86ac8@127.0.0.1
CSeq: 201 SUBSCRIBE
Contact:
From: <>>cisco@mydomain.com>;tag=fa9d28431f16d75c
To: <>>provisioning@mydomain.com>
Max-Forwards: 70
Route: <10.10.2.100:5061>10.10.2.100:5061>
User-Agent: TANDBERG/774 (MCX 4.6.3.17194) - Windows
Expires: 300
Event: ua-profile;model=movi;vendor=tandberg.com;profile-type=user;version=4.6.3.17194;clientid="S-1-5-21-3537420569-697092696-2587007096";connectivity=0
Accept: application/pidf+xml
Content-Length: 0
|
2013-07-02T14:08:52-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:52,785" Module="network.sip" Level="INFO": Dst-ip="10.254.0.7" Dst-port="62338" Detail="Sending Response Code=407, Method=SUBSCRIBE, To=sip:provisioning@mydomain.com, Call-ID=6af0c6f351e86ac8@127.0.0.1"
2013-07-02T14:08:52-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:52,785" Module="network.sip" Level="DEBUG": Dst-ip="10.254.0.7" Dst-port="62338"
SIPMSG:
|SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/TLS 10.254.0.7:62338;branch=z9hG4bK41804a1eee2152f9366168960169b367.1;received=10.254.0.7;rport=62338
Call-ID: 6af0c6f351e86ac8@127.0.0.1
CSeq: 201 SUBSCRIBE
From: <>>cisco@mydomain.com>;tag=fa9d28431f16d75c
To: <>>provisioning@mydomain.com>;tag=97504018f194617b
Server: TANDBERG/4120 (X7.2.2)
Proxy-Authenticate: Digest realm="vcs.mydomain.com", nonce="734622e4a24a70910f7e67b2080ef8bdb56021e396546aa2be572fd76084", opaque="AQAAAHKD1uSTaXVf0u3a4/LkCmjR1phx", stale=FALSE, algorithm=MD5, qop="auth"
Content-Length: 0
|
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,029" Module="network.sip" Level="INFO": Src-ip="10.254.0.7" Src-port="62338" Detail="Receive Request Method=SUBSCRIBE, Request-URI=sip:cisco@mydomain.com, Call-ID=6af0c6f351e86ac8@127.0.0.1"
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,029" Module="network.sip" Level="DEBUG": Src-ip="10.254.0.7" Src-port="62338"
SIPMSG:
|SUBSCRIBE sip:cisco@mydomain.com SIP/2.0
Via: SIP/2.0/TLS 10.254.0.7:62338;branch=z9hG4bK76c5899d1676671b960ae6cd5e81b29b.1;received=10.254.0.7;rport=62338
Call-ID: 6af0c6f351e86ac8@127.0.0.1
CSeq: 202 SUBSCRIBE
Contact:
From: <>>cisco@mydomain.com>;tag=fa9d28431f16d75c
To: <>>provisioning@mydomain.com>
Max-Forwards: 70
Route: <10.10.2.100:5061>10.10.2.100:5061>
User-Agent: TANDBERG/774 (MCX 4.6.3.17194) - Windows
Expires: 300
Proxy-Authorization: Digest nonce="734622e4a24a70910f7e67b2080ef8bdb56021e396546aa2be572fd76084", realm="vcs.mydomain.com", qop=auth, opaque="AQAAAHKD1uSTaXVf0u3a4/LkCmjR1phx", username="cisco", uri="sip:mydomain.com", response="0df0b98314e40203a888b87692999a5c", algorithm=MD5, nc=00000001, cnonce="c724d64a764db4d30ebfa518f9ab5af3"
Event: ua-profile;model=movi;vendor=tandberg.com;profile-type=user;version=4.6.3.17194;clientid="S-1-5-21-3537420569-697092696-2587007096";connectivity=0
Accept: application/pidf+xml
Content-Length: 0
|
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,030" Module="network.http" Level="DEBUG": Message="Request" Method="POST" URL="http://127.0.0.1:9998/credential/name/cisco" Ref="0x7fd67829a0a0"
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,033" Module="network.http" Level="DEBUG": Message="Response" Src-ip="127.0.0.1" Src-port="9998" Dst-ip="127.0.0.1" Dst-port="43954" Response="200 OK" ResponseTime="0.00339" Ref="0x7fd67829a0a0"
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,033" Module="network.ldap" Level="INFO": Detail="Authentication credential found in directory for identity: cisco"
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,036" Module="network.cpl" Level="DEBUG": Remote-ip="10.254.0.7" Remote-port="62338" Detail="CPL:
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,036" Module="network.cpl" Level="DEBUG": Remote-ip="10.254.0.7" Remote-port="62338" Detail="CPL:
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,037" Module="network.cpl" Level="DEBUG": Remote-ip="10.254.0.7" Remote-port="62338" Detail="CPL:
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,037" Module="network.cpl" Level="DEBUG": Remote-ip="10.254.0.7" Remote-port="62338" Detail="CPL:
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,038" Module="network.search" Level="INFO": Detail="Search rule 'Local zone – no domain' did not match destination alias 'cisco@mydomain.com'"
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,038" Module="network.search" Level="INFO": Detail="Considering search rule 'Local zone – full URI' towards target 'LocalZone' at priority '49' with alias 'cisco@mydomain.com'"
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,041" Module="network.sip" Level="INFO": Dst-ip="10.254.0.7" Dst-port="62338" Detail="Sending Response Code=404, Method=SUBSCRIBE, To=sip:provisioning@mydomain.com, Call-ID=6af0c6f351e86ac8@127.0.0.1"
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,041" Module="network.sip" Level="DEBUG": Dst-ip="10.254.0.7" Dst-port="62338"
SIPMSG:
|SIP/2.0 404 Not Found
Via: SIP/2.0/TLS 10.254.0.7:62338;branch=z9hG4bK76c5899d1676671b960ae6cd5e81b29b.1;received=10.254.0.7;rport=62338;ingress-zone=DefaultZone
Call-ID: 6af0c6f351e86ac8@127.0.0.1
CSeq: 202 SUBSCRIBE
From: <>>cisco@mydomain.com>;tag=fa9d28431f16d75c
To: <>>provisioning@mydomain.com>;tag=f9cb192329b65fef
Server: TANDBERG/4120 (X7.2.2)
Warning: 399 10.10.2.100:5061 "Policy Response"
Content-Length: 0
|
2013-07-02T14:08:53-04:00 vcs tvcs: UTCTime="2013-07-02 18:08:53,251" Module="network.tcp" Level="DEBUG": Src-ip="10.254.0.7" Src-port="62338" Dst-ip="10.10.2.100" Dst-port="5061" Detail="TCP Connection Closed"
Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: