Hi Chris,
Thanks for your response.I have added the scscf.sprout.xflow-research.com to
the DNS record but it didn’t solved the problem I am still stuck in the same
error.
The bono forwards the request to sprout and sprout is also getting the
authentication vector but it doesn’t find the sproutlet to forward the
request/response.
I’m not upgrading from existing vIMS setup. I’m performing fresh manual
installation according to instructions given on:
http://clearwater.readthedocs.io/en/stable/Manual_Install.html
sprout logs shared_ file and DNS file is attached below.
kindly help me out to solve this issue so that I am able to register the client
any help in this regard will be highly appreciated.
Regards,
Tahir
Shared_Config
# Deployment definitions
home_domain=xflow-research.com
sprout_hostname=sprout.xflow-research.com
hs_hostname=hs.xflow-research.com:8888
hs_provisioning_hostname=hs.xflow-research.com:8889
ralf_hostname=ralf.xflow-research.com:10888
xdms_hostname=homer.xflow-research.com:7888
# Email server configuration
smtp_smarthost=localhost
smtp_username=username
smtp_password=password
[email protected]
# Keys
signup_key=secret
turn_workaround=secret
ellis_api_key=secret
ellis_cookie_key=secret
DNS DB file :
vi /var/lib/bind/db.xflow-research.com
$ORIGIN .
$TTL 3600 ; 1 hour
xflow-research.com IN SOA ns.xflow-research.com.
admin\@xflow-research.com. (
2016050217 ; serial
86400 ; refresh (1 day)
7200 ; retry (2 hours)
604800 ; expire (1 week)
30 ; minimum (30 seconds)
)
NS ns.xflow-research.com.
$TTL 30 ; 30 seconds
A 10.204.110.76
NAPTR 0 0 "s" "SIP+D2T" ""
_sip._tcp.xflow-research.com.
NAPTR 0 0 "s" "SIP+D2U" ""
_sip._udp.xflow-research.com.
$ORIGIN xflow-research.com.
_sip._tcp SRV 0 0 5060 bono-0
_sip._udp SRV 0 0 5060 bono-0
bono-0 A 10.204.110.76
ellis A 192.168.0.3
ellis-0 A 192.168.0.3
homer A 192.168.0.4
homer-0 A 192.168.0.4
homestead-0 A 192.168.0.7
hs A 192.168.0.7
$TTL 3600 ; 1 hour
ns A 192.168.0.1
$TTL 30 ; 30 seconds
ralf A 192.168.0.6
ralf-0 A 192.168.0.6
sprout A 192.168.0.5
NAPTR 0 0 "s" "SIP+D2T" "" _sip._tcp.sprout
$ORIGIN sprout.xflow-research.com.
_sip._tcp SRV 0 0 5054 sprout-0.xflow-research.com.
icscf NAPTR 0 0 "s" "SIP+D2T" "" _sip._tcp.icscf
$ORIGIN icscf.sprout.xflow-research.com.
_sip._tcp SRV 0 0 5052 sprout-0.xflow-research.com.
$ORIGIN xflow-research.com.
sprout-0 A 192.168.0.5
Sprout Logs
--start msg--
REGISTER sip:xflow-research.com;transport=UDP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.8:36706;rport;branch=z9hG4bKPjE.GXBI48JVyprPuiYVUePgd6g3GiKnFN
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
10.204.67.8:53148;received=10.204.67.8;branch=z9hG4bK-524287-1---df924eafbeb94504
Max-Forwards: 70
Contact:
<sip:[email protected]:53148;transport=UDP;rinstance=61fb2a27c508dde4>
To: "2010000007" <sip:[email protected]>
From: "2010000007" <sip:[email protected]>;tag=6a655b11
Call-ID: ovYu94WjaeuHDdSqtFlENw..
CSeq: 1 REGISTER
Expires: 3600
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO,
SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, outbound, path,
X-cisco-serviceuri
User-Agent: Z 3.9.32144 r32121
Allow-Events: presence, kpml
P-Visited-Network-ID: xflow-research.com
Route: <sip:sprout.xflow-research.com:5054;transport=TCP;lr;orig>
Content-Length: 0
--end msg--
02-05-2016 11:15:14.261 UTC Debug pjutils.cpp:1662: Logging SAS Call-ID marker,
Call-ID ovYu94WjaeuHDdSqtFlENw..
02-05-2016 11:15:14.261 UTC Debug thread_dispatcher.cpp:253: Queuing cloned
received message 0x7fa42803ae08 for worker threads
02-05-2016 11:15:14.261 UTC Debug thread_dispatcher.cpp:149: Worker thread
dequeue message 0x7fa42803ae08
02-05-2016 11:15:14.261 UTC Debug pjsip: sip_endpoint.c Distributing rdata to
modules: Request msg REGISTER/cseq=1 (rdata0x7fa42803ae08)
02-05-2016 11:15:14.261 UTC Debug uri_classifier.cpp:167: home domain: true,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
02-05-2016 11:15:14.261 UTC Debug uri_classifier.cpp:197: Classified URI as 4
02-05-2016 11:15:14.261 UTC Debug authentication.cpp:775: Authentication module
invoked
02-05-2016 11:15:14.261 UTC Debug authentication.cpp:789: Request needs
authentication
02-05-2016 11:15:14.261 UTC Debug acr.cpp:1765: Create RalfACR for node type
S-CSCF with role Originating
02-05-2016 11:15:14.261 UTC Debug acr.cpp:49: Created ACR (0x7fa4402e8510)
02-05-2016 11:15:14.261 UTC Debug acr.cpp:177: Created S-CSCF Ralf ACR
02-05-2016 11:15:14.261 UTC Debug acr.cpp:216: Set record type for P/S-CSCF
02-05-2016 11:15:14.261 UTC Debug acr.cpp:224: Non-dialog message =>
EVENT_RECORD
02-05-2016 11:15:14.261 UTC Debug acr.cpp:1493: Stored 0 subscription
identifiers
02-05-2016 11:15:14.261 UTC Debug authentication.cpp:1091: No authentication
information in request or stale nonce, so reject with challenge
02-05-2016 11:15:14.261 UTC Debug pjsip: endpoint Response msg
401/REGISTER/cseq=1 (tdta0x7fa4402e6af0) created
02-05-2016 11:15:14.261 UTC Debug pjutils.cpp:423: Private identity defaulted
from public identity = [email protected]
02-05-2016 11:15:14.261 UTC Debug httpresolver.cpp:71: HttpResolver::resolve
for host hs.xflow-research.com, port 8888, family 2
02-05-2016 11:15:14.261 UTC Debug baseresolver.cpp:513: Attempt to parse
hs.xflow-research.com as IP address
02-05-2016 11:15:14.261 UTC Verbose dnscachedresolver.cpp:240: Check cache for
hs.xflow-research.com type 1
02-05-2016 11:15:14.261 UTC Debug dnscachedresolver.cpp:326: Pulling 1 records
from cache for hs.xflow-research.com A
02-05-2016 11:15:14.261 UTC Debug baseresolver.cpp:361: Found 1 A/AAAA records,
randomizing
02-05-2016 11:15:14.261 UTC Debug baseresolver.cpp:503: 192.168.0.7:8888
transport 6 is not blacklisted
02-05-2016 11:15:14.261 UTC Debug baseresolver.cpp:382: Added a server, now
have 1 of 5
02-05-2016 11:15:14.261 UTC Debug baseresolver.cpp:420: Adding 0 servers from
blacklist
02-05-2016 11:15:14.261 UTC Debug httpconnection.cpp:623: Sending HTTP request
:
http://hs.xflow-research.com:8888/impi/2010000007%40xflow-research.com/av?impu=sip%3A2010000007%40xflow-research.com
(trying 192.168.0.7) on new connection
02-05-2016 11:15:14.266 UTC Debug httpconnection.cpp:638: Received HTTP
response: status=200,
doc={"digest":{"ha1":"82df27334d7d024d25f6bc073bcb437a","realm":"xflow-research.com","qop":"auth"}}
02-05-2016 11:15:14.266 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 1, failures 0
02-05-2016 11:15:14.266 UTC Debug authentication.cpp:197: Verifying AV:
{"digest":{"ha1":"82df27334d7d024d25f6bc073bcb437a","realm":"xflow-research.com","qop":"auth"}}
02-05-2016 11:15:14.266 UTC Debug authentication.cpp:224: Digest specified
02-05-2016 11:15:14.266 UTC Debug authentication.cpp:386: Valid AV - generate
challenge
02-05-2016 11:15:14.266 UTC Debug authentication.cpp:395: Create
WWW-Authenticate header
02-05-2016 11:15:14.266 UTC Debug authentication.cpp:478: Add Digest information
02-05-2016 11:15:14.266 UTC Debug authentication.cpp:533: Write authentication
challenge to IMPI store
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:195: Key
impi\\[email protected] hashes to vbucket 12 via hash 0xce2abf8c
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:367: 1 read replicas for
key impi\\[email protected]
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:402: Attempt to read from
replica 0 (connection 0x7fa440075310)
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:780: Fetch result
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:788: Found record on
replica
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:410: Read for
impi\\[email protected] on replica 0 returned SUCCESS
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:453: Read 446 bytes from
table impi key [email protected], CAS = 665
02-05-2016 11:15:14.266 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 5, failures 0
02-05-2016 11:15:14.266 UTC Debug impistore.cpp:786: Retrieved IMPI for
[email protected]
{"authChallenges":[{"type":"digest","nonce":"79e9a39005e8762f","nc":2,"expires":1462187705,"correlator":"z9hG4bKPjMJ6bEUCQu8ixjjCL8QLGbuAvr2UxNRtE","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"},{"type":"digest","nonce":"399d9cdb2c311db7","nc":2,"expires":1462187738,"correlator":"z9hG4bKPjo1ugXlE1.107loQhfcEzl9koHUiv1DLe","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"}]}
02-05-2016 11:15:14.266 UTC Debug impistore.cpp:194: Expires in past - dropping
02-05-2016 11:15:14.266 UTC Debug impistore.cpp:648: Storing IMPI for
[email protected]
{"authChallenges":[{"type":"digest","nonce":"399d9cdb2c311db7","nc":2,"expires":1462187738,"correlator":"z9hG4bKPjo1ugXlE1.107loQhfcEzl9koHUiv1DLe","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"},{"type":"digest","nonce":"6cf2f9ca20c85c06","nc":1,"expires":1462187754,"correlator":"z9hG4bKPjE.GXBI48JVyprPuiYVUePgd6g3GiKnFN","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"}]}
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:542: Writing 446 bytes to
table impi key [email protected], CAS = 665, expiry = 40
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:195: Key
impi\\[email protected] hashes to vbucket 12 via hash 0xce2abf8c
02-05-2016 11:15:14.266 UTC Debug memcachedstore.cpp:562: 1 write replicas for
key impi\\[email protected]
02-05-2016 11:15:14.267 UTC Debug memcachedstore.cpp:616: Attempt conditional
write to vbucket 12 on replica 0 (connection 0x7fa440075310), CAS = 665, expiry
= 40
02-05-2016 11:15:14.267 UTC Debug memcachedstore.cpp:657: Conditional write
succeeded to replica 0
02-05-2016 11:15:14.267 UTC Debug authentication.cpp:605: Sending {"impi":
"[email protected]", "impu": "sip:[email protected]",
"nonce": "6cf2f9ca20c85c06"} to Chronos to set AV timer
02-05-2016 11:15:14.267 UTC Debug httpresolver.cpp:71: HttpResolver::resolve
for host 127.0.0.1, port 7253, family 2
02-05-2016 11:15:14.267 UTC Debug baseresolver.cpp:513: Attempt to parse
127.0.0.1 as IP address
02-05-2016 11:15:14.267 UTC Debug httpresolver.cpp:79: Target is an IP address
02-05-2016 11:15:14.267 UTC Debug httpconnection.cpp:623: Sending HTTP request
: http://127.0.0.1:7253/timers (trying 127.0.0.1) on new connection
02-05-2016 11:15:14.268 UTC Debug httpconnection.cpp:915: Received header
http/1.1200ok with value
02-05-2016 11:15:14.268 UTC Debug httpconnection.cpp:915: Received header
location with value /timers/004911b0c000014f0040001000104104
02-05-2016 11:15:14.268 UTC Debug httpconnection.cpp:915: Received header
content-length with value 0
02-05-2016 11:15:14.268 UTC Debug httpconnection.cpp:915: Received header with
value
02-05-2016 11:15:14.268 UTC Debug httpconnection.cpp:638: Received HTTP
response: status=200, doc=
02-05-2016 11:15:14.268 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 1, failures 0
02-05-2016 11:15:14.268 UTC Debug pjsip: tsx0x7fa4403fb Transaction created for
Request msg REGISTER/cseq=1 (rdata0x7fa42803ae08)
02-05-2016 11:15:14.268 UTC Debug pjsip: tsx0x7fa4403fb Incoming Request msg
REGISTER/cseq=1 (rdata0x7fa42803ae08) in state Null
02-05-2016 11:15:14.268 UTC Debug pjsip: tsx0x7fa4403fb State changed from Null
to Trying, event=RX_MSG
02-05-2016 11:15:14.268 UTC Debug pjsip: tsx0x7fa4403fb Sending Response msg
401/REGISTER/cseq=1 (tdta0x7fa4402e6af0) in state Trying
02-05-2016 11:15:14.268 UTC Verbose common_sip_processing.cpp:136: TX 618 bytes
Response msg 401/REGISTER/cseq=1 (tdta0x7fa4402e6af0) to TCP 192.168.0.8:36706:
--start msg--
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TCP
192.168.0.8:36706;rport=36706;received=192.168.0.8;branch=z9hG4bKPjE.GXBI48JVyprPuiYVUePgd6g3GiKnFN
Via: SIP/2.0/UDP
10.204.67.8:53148;received=10.204.67.8;branch=z9hG4bK-524287-1---df924eafbeb94504
Call-ID: ovYu94WjaeuHDdSqtFlENw..
From: "2010000007" <sip:[email protected]>;tag=6a655b11
To: "2010000007"
<sip:[email protected]>;tag=z9hG4bKPjE.GXBI48JVyprPuiYVUePgd6g3GiKnFN
CSeq: 1 REGISTER
WWW-Authenticate: Digest
realm="xflow-research.com",nonce="6cf2f9ca20c85c06",opaque="7b4deaef1d617429",algorithm=MD5,qop="auth"
Content-Length: 0
--end msg--
02-05-2016 11:15:14.268 UTC Debug pjsip: tsx0x7fa4403fb State changed from
Trying to Completed, event=TX_MSG
02-05-2016 11:15:14.268 UTC Info acr.cpp:660: No CCF or ECF to send ACR for
session ovYu94WjaeuHDdSqtFlENw.. to - dropping!
02-05-2016 11:15:14.268 UTC Debug acr.cpp:54: Destroyed ACR (0x7fa4402e8510)
02-05-2016 11:15:14.268 UTC Debug thread_dispatcher.cpp:193: Worker thread
completed processing message 0x7fa42803ae08
02-05-2016 11:15:14.268 UTC Debug thread_dispatcher.cpp:199: Request latency =
7584us
02-05-2016 11:15:14.271 UTC Debug pjsip: tsx0x7fa4403fb Timeout timer event
02-05-2016 11:15:14.271 UTC Debug pjsip: tsx0x7fa4403fb State changed from
Completed to Terminated, event=TIMER
02-05-2016 11:15:14.272 UTC Debug pjsip: tsx0x7fa4403fb Timeout timer event
02-05-2016 11:15:14.272 UTC Debug pjsip: tsx0x7fa4403fb State changed from
Terminated to Destroyed, event=TIMER
02-05-2016 11:15:14.272 UTC Debug pjsip: tdta0x7fa4402e Destroying txdata
Response msg 401/REGISTER/cseq=1 (tdta0x7fa4402e6af0)
02-05-2016 11:15:14.272 UTC Debug pjsip: tsx0x7fa4403fb Transaction destroyed!
02-05-2016 11:15:14.612 UTC Debug pjsip: sip_endpoint.c Processing incoming
message: Request msg REGISTER/cseq=2 (rdata0x7fa4281d14d0)
02-05-2016 11:15:14.612 UTC Verbose common_sip_processing.cpp:120: RX 1322
bytes Request msg REGISTER/cseq=2 (rdata0x7fa4281d14d0) from TCP
192.168.0.8:48775:
--start msg--
REGISTER sip:xflow-research.com;transport=UDP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.8:48775;rport;branch=z9hG4bKPjKgCiRtQe4QsjmjUGEuJPZA1JLkUKUh92
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
10.204.67.8:53148;received=10.204.67.8;branch=z9hG4bK-524287-1---cf627f16cbecf23b
Max-Forwards: 70
Contact:
<sip:[email protected]:53148;transport=UDP;rinstance=61fb2a27c508dde4>
To: "2010000007" <sip:[email protected]>
From: "2010000007" <sip:[email protected]>;tag=6a655b11
Call-ID: ovYu94WjaeuHDdSqtFlENw..
CSeq: 2 REGISTER
Expires: 3600
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO,
SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, outbound, path,
X-cisco-serviceuri
User-Agent: Z 3.9.32144 r32121
Authorization: Digest response="67c42a8ee455c3a04a3b0b025ba7a728",
username="[email protected]", realm="xflow-research.com",
nonce="6cf2f9ca20c85c06", uri="sip:xflow-research.com;transport=UDP",
algorithm=MD5, cnonce="fa518045bea1d4900d67ea5f9cf83b23",
opaque="7b4deaef1d617429", qop=auth,
nc=00000001,integrity-protected=ip-assoc-pending
Allow-Events: presence, kpml
P-Visited-Network-ID: xflow-research.com
Route: <sip:sprout.xflow-research.com:5054;transport=TCP;lr;orig>
Content-Length: 0
--end msg--
02-05-2016 11:15:14.612 UTC Debug pjutils.cpp:1662: Logging SAS Call-ID marker,
Call-ID ovYu94WjaeuHDdSqtFlENw..
02-05-2016 11:15:14.612 UTC Debug thread_dispatcher.cpp:253: Queuing cloned
received message 0x7fa42803ae08 for worker threads
02-05-2016 11:15:14.612 UTC Debug thread_dispatcher.cpp:149: Worker thread
dequeue message 0x7fa42803ae08
02-05-2016 11:15:14.612 UTC Debug pjsip: sip_endpoint.c Distributing rdata to
modules: Request msg REGISTER/cseq=2 (rdata0x7fa42803ae08)
02-05-2016 11:15:14.612 UTC Debug uri_classifier.cpp:167: home domain: true,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
02-05-2016 11:15:14.612 UTC Debug uri_classifier.cpp:197: Classified URI as 4
02-05-2016 11:15:14.612 UTC Debug authentication.cpp:775: Authentication module
invoked
02-05-2016 11:15:14.612 UTC Debug authentication.cpp:683: Authorization header
in request
02-05-2016 11:15:14.612 UTC Debug authentication.cpp:789: Request needs
authentication
02-05-2016 11:15:14.612 UTC Debug memcachedstore.cpp:195: Key
impi\\[email protected] hashes to vbucket 12 via hash 0xce2abf8c
02-05-2016 11:15:14.612 UTC Debug memcachedstore.cpp:367: 1 read replicas for
key impi\\[email protected]
02-05-2016 11:15:14.612 UTC Debug memcachedstore.cpp:402: Attempt to read from
replica 0 (connection 0x7fa44c00e0d0)
02-05-2016 11:15:14.612 UTC Debug memcachedstore.cpp:780: Fetch result
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:788: Found record on
replica
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:410: Read for
impi\\[email protected] on replica 0 returned SUCCESS
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:453: Read 446 bytes from
table impi key [email protected], CAS = 666
02-05-2016 11:15:14.613 UTC Debug impistore.cpp:786: Retrieved IMPI for
[email protected]
{"authChallenges":[{"type":"digest","nonce":"399d9cdb2c311db7","nc":2,"expires":1462187738,"correlator":"z9hG4bKPjo1ugXlE1.107loQhfcEzl9koHUiv1DLe","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"},{"type":"digest","nonce":"6cf2f9ca20c85c06","nc":1,"expires":1462187754,"correlator":"z9hG4bKPjE.GXBI48JVyprPuiYVUePgd6g3GiKnFN","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"}]}
02-05-2016 11:15:14.613 UTC Debug authentication.cpp:908: Verify authentication
information in request
02-05-2016 11:15:14.613 UTC Debug authentication.cpp:325: Found Digest HA1 =
82df27334d7d024d25f6bc073bcb437a
02-05-2016 11:15:14.613 UTC Debug authentication.cpp:917: Request authenticated
successfully
02-05-2016 11:15:14.613 UTC Debug impistore.cpp:648: Storing IMPI for
[email protected]
{"authChallenges":[{"type":"digest","nonce":"399d9cdb2c311db7","nc":2,"expires":1462187738,"correlator":"z9hG4bKPjo1ugXlE1.107loQhfcEzl9koHUiv1DLe","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"},{"type":"digest","nonce":"6cf2f9ca20c85c06","nc":2,"expires":1462187754,"correlator":"z9hG4bKPjE.GXBI48JVyprPuiYVUePgd6g3GiKnFN","realm":"xflow-research.com","qop":"auth","ha1":"82df27334d7d024d25f6bc073bcb437a"}]}
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:542: Writing 446 bytes to
table impi key [email protected], CAS = 666, expiry = 40
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:195: Key
impi\\[email protected] hashes to vbucket 12 via hash 0xce2abf8c
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:562: 1 write replicas for
key impi\\[email protected]
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:616: Attempt conditional
write to vbucket 12 on replica 0 (connection 0x7fa44c00e0d0), CAS = 666, expiry
= 40
02-05-2016 11:15:14.613 UTC Debug memcachedstore.cpp:657: Conditional write
succeeded to replica 0
02-05-2016 11:15:14.613 UTC Debug uri_classifier.cpp:167: home domain: true,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
02-05-2016 11:15:14.613 UTC Debug uri_classifier.cpp:197: Classified URI as 4
02-05-2016 11:15:14.613 UTC Debug uri_classifier.cpp:167: home domain: false,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
02-05-2016 11:15:14.613 UTC Debug uri_classifier.cpp:197: Classified URI as 5
02-05-2016 11:15:14.613 UTC Debug basicproxy.cpp:92: Process REGISTER request
02-05-2016 11:15:14.613 UTC Verbose sproutletproxy.cpp:503: Sproutlet Proxy
transaction (0x7fa44c322030) created
02-05-2016 11:15:14.613 UTC Debug basicproxy.cpp:1271: Report SAS start marker
- trail (99f)
02-05-2016 11:15:14.613 UTC Debug pjutils.cpp:674: Cloned Request msg
REGISTER/cseq=2 (rdata0x7fa42803ae08) to tdta0x7fa44c2694c0
02-05-2016 11:15:14.613 UTC Debug pjsip: tsx0x7fa44c3e9 Transaction created for
Request msg REGISTER/cseq=2 (rdata0x7fa42803ae08)
02-05-2016 11:15:14.613 UTC Debug pjsip: tsx0x7fa44c3e9 Incoming Request msg
REGISTER/cseq=2 (rdata0x7fa42803ae08) in state Null
02-05-2016 11:15:14.613 UTC Debug pjsip: tsx0x7fa44c3e9 State changed from Null
to Trying, event=RX_MSG
02-05-2016 11:15:14.613 UTC Debug basicproxy.cpp:213: tsx0x7fa44c3e9cd8 -
tu_on_tsx_state UAS, TSX_STATE RX_MSG state=Trying
02-05-2016 11:15:14.613 UTC Debug pjsip: endpoint Response msg
408/REGISTER/cseq=2 (tdta0x7fa44c3ea440) created
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:124: Find target Sproutlet
for request
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:163: Found next routable
URI: sip:sprout.xflow-research.com:5054;transport=TCP;lr;orig
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:334: Possible service name
- sprout
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:340: Hostname -
xflow-research.com
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:334: Possible service name
- sprout
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:340: Hostname -
xflow-research.com
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:334: Possible service name
- sprout
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:340: Hostname -
xflow-research.com
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:195: No Sproutlet found
using service name or host
02-05-2016 11:15:14.613 UTC Debug sproutletproxy.cpp:201: Find default service
for port 5054
02-05-2016 11:15:14.613 UTC Verbose sproutletproxy.cpp:1159: Created Sproutlet
bgcf-0x7fa44c079920 for Request msg REGISTER/cseq=2 (tdta0x7fa44c2694c0)
02-05-2016 11:15:14.613 UTC Verbose sproutletproxy.cpp:2067: Routing Request
msg REGISTER/cseq=2 (tdta0x7fa44c2694c0) (1349 bytes) to downstream sproutlet
bgcf:
--start msg--
REGISTER sip:xflow-research.com;transport=UDP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.8:48775;rport=48775;received=192.168.0.8;branch=z9hG4bKPjKgCiRtQe4QsjmjUGEuJPZA1JLkUKUh92
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
10.204.67.8:53148;received=10.204.67.8;branch=z9hG4bK-524287-1---cf627f16cbecf23b
Max-Forwards: 70
Contact:
<sip:[email protected]:53148;transport=UDP;rinstance=61fb2a27c508dde4>
To: "2010000007" <sip:[email protected]>
From: "2010000007" <sip:[email protected]>;tag=6a655b11
Call-ID: ovYu94WjaeuHDdSqtFlENw..
CSeq: 2 REGISTER
Expires: 3600
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO,
SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, outbound, path,
X-cisco-serviceuri
User-Agent: Z 3.9.32144 r32121
Authorization: Digest response="67c42a8ee455c3a04a3b0b025ba7a728",
username="[email protected]", realm="xflow-research.com",
nonce="6cf2f9ca20c85c06", uri="sip:xflow-research.com;transport=UDP",
algorithm=MD5, cnonce="fa518045bea1d4900d67ea5f9cf83b23",
opaque="7b4deaef1d617429", qop=auth,
nc=00000001,integrity-protected=ip-assoc-pending
Allow-Events: presence, kpml
P-Visited-Network-ID: xflow-research.com
Route: <sip:sprout.xflow-research.com:5054;transport=TCP;lr;orig>
Content-Length: 0
--end msg--
02-05-2016 11:15:14.614 UTC Debug pjutils.cpp:691: Cloned tdta0x7fa44c2694c0 to
tdta0x7fa44c3ec400
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1220: Remove top Route
header Route: <sip:sprout.xflow-research.com:5054;transport=TCP;lr;orig>
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1740: Adding message
0x7fa44c3eca10 => txdata 0x7fa44c3ec4a8 mapping
02-05-2016 11:15:14.614 UTC Verbose sproutletproxy.cpp:1592:
bgcf-0x7fa44c079920 pass initial request Request msg REGISTER/cseq=2
(tdta0x7fa44c3ec400) to Sproutlet
02-05-2016 11:15:14.614 UTC Debug acr.cpp:1765: Create RalfACR for node type
BGCF with role Terminating
02-05-2016 11:15:14.614 UTC Debug acr.cpp:49: Created ACR (0x7fa44c2d76c0)
02-05-2016 11:15:14.614 UTC Debug acr.cpp:177: Created BGCF Ralf ACR
02-05-2016 11:15:14.614 UTC Debug acr.cpp:256: Set record type for I-CSCF,
BGCF, IBCF, AS to EVENT_RECORD
02-05-2016 11:15:14.614 UTC Debug uri_classifier.cpp:167: home domain: true,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
02-05-2016 11:15:14.614 UTC Debug uri_classifier.cpp:197: Classified URI as 4
02-05-2016 11:15:14.614 UTC Debug pjutils.cpp:2360: Not translating URI
02-05-2016 11:15:14.614 UTC Debug uri_classifier.cpp:167: home domain: true,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
02-05-2016 11:15:14.614 UTC Debug uri_classifier.cpp:197: Classified URI as 4
02-05-2016 11:15:14.614 UTC Debug bgcfsproutlet.cpp:208: No route configured for
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1740: Adding message
0x7fa44c3ef980 => txdata 0x7fa44c3ef418 mapping
02-05-2016 11:15:14.614 UTC Verbose sproutletproxy.cpp:1418:
bgcf-0x7fa44c079920 sending Response msg 404/REGISTER/cseq=2
(tdta0x7fa44c3ef370)
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1747: Removing message
0x7fa44c3eca10 => txdata 0x7fa44c3ec4a8 mapping
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1495: Free message
tdta0x7fa44c3ec400
02-05-2016 11:15:14.614 UTC Debug pjsip: tdta0x7fa44c3e Destroying txdata
Request msg REGISTER/cseq=2 (tdta0x7fa44c3ec400)
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1755: Processing actions
from sproutlet - 1 responses, 0 requests, 0 timers
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1841: Aggregating response
with status code 404
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1891: 3xx/4xx/5xx/6xx
response
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1895: Best 3xx/4xx/5xx/6xx
response so far
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1782: All UAC responded
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1747: Removing message
0x7fa44c3ef980 => txdata 0x7fa44c3ef418 mapping
02-05-2016 11:15:14.614 UTC Debug pjsip: tsx0x7fa44c3e9 Sending Response msg
404/REGISTER/cseq=2 (tdta0x7fa44c3ef370) in state Trying
02-05-2016 11:15:14.614 UTC Verbose common_sip_processing.cpp:136: TX 494 bytes
Response msg 404/REGISTER/cseq=2 (tdta0x7fa44c3ef370) to TCP 192.168.0.8:48775:
--start msg--
SIP/2.0 404 No route to target
Via: SIP/2.0/TCP
192.168.0.8:48775;rport=48775;received=192.168.0.8;branch=z9hG4bKPjKgCiRtQe4QsjmjUGEuJPZA1JLkUKUh92
Via: SIP/2.0/UDP
10.204.67.8:53148;received=10.204.67.8;branch=z9hG4bK-524287-1---cf627f16cbecf23b
Call-ID: ovYu94WjaeuHDdSqtFlENw..
From: "2010000007" <sip:[email protected]>;tag=6a655b11
To: "2010000007"
<sip:[email protected]>;tag=z9hG4bKPjKgCiRtQe4QsjmjUGEuJPZA1JLkUKUh92
CSeq: 2 REGISTER
Content-Length: 0
--end msg--
02-05-2016 11:15:14.614 UTC Debug pjsip: tsx0x7fa44c3e9 State changed from
Trying to Completed, event=TX_MSG
02-05-2016 11:15:14.614 UTC Debug basicproxy.cpp:213: tsx0x7fa44c3e9cd8 -
tu_on_tsx_state UAS, TSX_STATE TX_MSG state=Completed
02-05-2016 11:15:14.614 UTC Verbose sproutletproxy.cpp:1833:
bgcf-0x7fa44c079920 suiciding
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1165: Destroying
SproutletWrapper 0x7fa44c312090
02-05-2016 11:15:14.614 UTC Info acr.cpp:660: No CCF or ECF to send ACR for
session ovYu94WjaeuHDdSqtFlENw.. to - dropping!
02-05-2016 11:15:14.614 UTC Debug acr.cpp:54: Destroyed ACR (0x7fa44c2d76c0)
02-05-2016 11:15:14.614 UTC Debug sproutletproxy.cpp:1174: Free original
request Request msg REGISTER/cseq=2 (tdta0x7fa44c2694c0) (tdta0x7fa44c2694c0)
02-05-2016 11:15:14.614 UTC Debug thread_dispatcher.cpp:193: Worker thread
completed processing message 0x7fa42803ae08
02-05-2016 11:15:14.614 UTC Debug thread_dispatcher.cpp:199: Request latency =
1958us
02-05-2016 11:15:14.622 UTC Debug pjsip: tsx0x7fa44c3e9 Timeout timer event
02-05-2016 11:15:14.622 UTC Debug pjsip: tsx0x7fa44c3e9 State changed from
Completed to Terminated, event=TIMER
02-05-2016 11:15:14.622 UTC Debug basicproxy.cpp:213: tsx0x7fa44c3e9cd8 -
tu_on_tsx_state UAS, TSX_STATE TIMER state=Terminated
02-05-2016 11:15:14.622 UTC Debug basicproxy.cpp:1281: Report SAS end marker -
trail (99f)
02-05-2016 11:15:14.622 UTC Debug pjsip: tsx0x7fa44c3e9 Timeout timer event
02-05-2016 11:15:14.623 UTC Debug pjsip: tsx0x7fa44c3e9 State changed from
Terminated to Destroyed, event=TIMER
02-05-2016 11:15:14.623 UTC Debug basicproxy.cpp:213: tsx0x7fa44c3e9cd8 -
tu_on_tsx_state UAS, TSX_STATE TIMER state=Destroyed
02-05-2016 11:15:14.623 UTC Debug sproutletproxy.cpp:746: tsx0x7fa44c3e9cd8 -
UAS tsx destroyed
02-05-2016 11:15:14.623 UTC Debug sproutletproxy.cpp:1086: Safe for UASTsx to
suicide
02-05-2016 11:15:14.623 UTC Debug basicproxy.cpp:1456: Transaction ((nil))
suiciding
02-05-2016 11:15:14.623 UTC Verbose sproutletproxy.cpp:534: Sproutlet Proxy
transaction (0x7fa44c322030) destroyed
02-05-2016 11:15:14.623 UTC Debug basicproxy.cpp:467: BasicProxy::UASTsx
destructor (0x7fa44c322030)
02-05-2016 11:15:14.623 UTC Debug basicproxy.cpp:484: Disconnect UAC
transactions from UAS transaction
02-05-2016 11:15:14.623 UTC Debug basicproxy.cpp:498: Free original request
02-05-2016 11:15:14.623 UTC Debug pjsip: tdta0x7fa44c26 Destroying txdata
Request msg REGISTER/cseq=2 (tdta0x7fa44c2694c0)
02-05-2016 11:15:14.623 UTC Debug basicproxy.cpp:507: Free un-used best response
02-05-2016 11:15:14.623 UTC Debug pjsip: tdta0x7fa44c3e Destroying txdata
Response msg 408/REGISTER/cseq=2 (tdta0x7fa44c3ea440)
02-05-2016 11:15:14.623 UTC Debug basicproxy.cpp:528: BasicProxy::UASTsx
destructor completed
02-05-2016 11:15:14.623 UTC Debug pjsip: tdta0x7fa44c3e Destroying txdata
Response msg 404/REGISTER/cseq=2 (tdta0x7fa44c3ef370)
02-05-2016 11:15:14.623 UTC Debug pjsip: tsx0x7fa44c3e9 Transaction destroyed!
02-05-2016 11:15:15.038 UTC Verbose pjsip: tcplis:5054 TCP listener
192.168.0.5:5054: got incoming TCP connection from 192.168.0.5:39683, sock=936
02-05-2016 11:15:15.038 UTC Verbose pjsip: tcps0x7fa42808 TCP server transport
created
02-05-2016 11:15:15.038 UTC Debug pjsip: sip_endpoint.c Processing incoming
message: Request msg OPTIONS/cseq=19155 (rdata0x7fa42808d1c0)
02-05-2016 11:15:15.038 UTC Verbose common_sip_processing.cpp:120: RX 342 bytes
Request msg OPTIONS/cseq=19155 (rdata0x7fa42808d1c0) from TCP 192.168.0.5:39683:
Sent from Windows Mail
From: Chris Elford (projectclearwater.org)
Sent: Thursday, April 28, 2016 7:53 PM
To: [email protected]
Cc: [email protected]
Hi Masood,
It looks like Sprout is sending x-lite a ‘404 – No route to target’ response:
--start msg--
SIP/2.0 404 No route to target
Via: SIP/2.0/TCP
192.168.211.12:52287;rport=52287;received=192.168.211.12;branch=z9hG4bKPjO61SVsPgGy3JNZ5GjcfAhUGhue.Rt-lt
Via: SIP/2.0/UDP
192.168.2.18:59190;rport=59190;received=192.168.2.18;branch=z9hG4bK-524287-1---1a816c6de2183a5e
Call-ID: 79961MzZiMGM4MWUwMmZlOTljMTljN2FhNDk5OWI2NjBmNzM
From: "2010000006" <sip:[email protected]>;tag=961aa033
To: "2010000006"
<sip:[email protected]>;tag=z9hG4bKPjO61SVsPgGy3JNZ5GjcfAhUGhue.Rt-lt
CSeq: 2 REGISTER
Content-Length: 0
--end msg--
I think the tell-tale logs are the following:
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:124: Find target Sproutlet
for request
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:163: Found next routable
URI: sip:sprout.xflowims.com:5054;transport=TCP;lr;orig
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:334: Possible service name
- sprout
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:340: Hostname -
xflowims.com
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:334: Possible service name
- sprout
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:340: Hostname -
xflowims.com
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:334: Possible service name
- sprout
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:340: Hostname -
xflowims.com
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:195: No Sproutlet found
using service name or host
28-04-2016 11:46:06.672 UTC Debug sproutletproxy.cpp:201: Find default service
for port 5054
28-04-2016 11:46:06.672 UTC Verbose sproutletproxy.cpp:1159: Created Sproutlet
bgcf-0x7efd9c066a40 for Request msg REGISTER/cseq=2 (tdta0x7efd9c06fe00)
Sprout is attempting to figure out which service it needs to apply to your
register messge: I-CSCF, S-CSCF, or BGCF. It does that by looking at the URI
sip:sprout.xflowims.com:5054;transport=TCP;lr;orig, and trying to find a
sproutlet which is configured to handle requests to that URI. Your URI doesn’t
match one of the configured sproutlet URIs, so it tries to match on the port.
The first sproutlet that it finds running on port 5054 is the BGCF sproutlet,
so it sends the request to the BGCF. The BGCF can’t route this request, so it
sends a 404 response.
This behaviour has changed recently, and there are some changes that you will
need to make to your shared configuration to allow your subscribers to register.
Remove the scscf_uri setting from /etc/clearwater/shared_config, and run
/usr/share/clearwater/clearwater-config-manager/upload_shared_config to
propagate this change across your deployment
Set up a DNS record for your S-CSCF URI – this should have the form scscf.<your
sprout hostname>, and reference all your Sprout nodes that provide S-CSCF
function
If you have I-CSCF function enabled in your deployment as well, then you’ll
also need to change the default S-CSCF for the I-CSCF. Edit the
/etc/clearwater/s-cscf.json file to reference scscf.<your sprout hostname>, and
run /usr/share/clearwater/clearwater-config-manager/upload_scscf_json to
propagate this change across your deployment.
For the full sproutlet options reference, see
http://clearwater.readthedocs.io/en/stable/Clearwater_Configuration_Options_Reference.html#sproutlet-options.
We’ve had a few people hit this issue now. Out of interest, are you upgrading
from an old deployment? If not, how are you building your shared configuration?
Is there something wrong in our documentation that we can fix up so that other
people don’t hit this issue?
Yours,
Chris
From: [email protected] [mailto:[email protected]]
Sent: 28 April 2016 13:22
To: [email protected]; Eleanor Merry
<[email protected]>; Chris Elford (projectclearwater.org)
<[email protected]>; Robert Day (projectclearwater.org)
<[email protected]>
Cc: 'Muhammad Ali' <[email protected]>
Subject: sip client authentication issue error 408
Hi all,
I have manually deployed the Clearwater IMS with 6 nodes and dns. I have
provisioned the users in homestead and homer using the bulk create script.
I am unable to register the client, where bono forwards the request to the
sprout and sprout asks the homestead for authentication vector it can be seen
from the logs
I have attached the debug logs of homestead and sprout also I have attached the
homestead cache from impi and impu where we can see the xml is present and
users are registered with correct relam
I have also attached the monit logs form homestead
Any guidance would be most appreciated. waiting for your response
regards,
Tahir
_______________________________________________
Clearwater mailing list
[email protected]
http://lists.projectclearwater.org/mailman/listinfo/clearwater_lists.projectclearwater.org
