I have a problem while registering using zoiper on my phone i received
403 forbidden from homestead node whenever i try to register however
All nodes are running normally
The user name is exist at ellis node database and in the homestead_cache
tables beside the logs of homestead which shows no errors in the logs, i
have attached the logs of homestead and sprout and hope you find a solution.
Thanks in advance.
IMSSubscription xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";
xsi:noNamespaceSchemaLocation="CxDataType.xsd"><PrivateID>Unspecified</PrivateID><ServiceProfile><InitialFilterCriteria><TriggerPoint><ConditionTypeCNF>0</ConditionTypeCNF><SPT><ConditionNegated>0</ConditionNegated><Group>0</Group><Method>INVITE</Method><Extension
/></SPT></TriggerPoint><ApplicationServer><ServerName>sip:mmtel.ims.cw.4gtss.com</ServerName><DefaultHandling>0</DefaultHandling></ApplicationServer></InitialFilterCriteria><PublicIdentity><Identity>sip:[email protected]</Identity></PublicIdentity></ServiceProfile></IMSSubscription>
21-03-2017 09:13:19.217 UTC Debug cache.cpp:438: Found stored XML for
subscriber, treating as UNREGISTERED state
21-03-2017 09:13:19.217 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:9160 transport 6
21-03-2017 09:13:19.217 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 9160 to pool
21-03-2017 09:13:19.217 UTC Debug handlers.cpp:1200: Got IMS subscription from
cache
21-03-2017 09:13:19.217 UTC Debug handlers.cpp:1215: TTL for this database
record is 0, IMS Subscription XML is not empty, registration state is
UNREGISTERED, and the charging addresses are empty
21-03-2017 09:13:19.217 UTC Debug handlers.cpp:1241: Subscriber registering
with new binding
21-03-2017 09:13:19.217 UTC Debug handlers.cpp:1475: Handling authentication
failure/timeout
21-03-2017 09:13:19.217 UTC Debug handlers.cpp:1522: Sending 200 response (body
was {"reqtype": "dereg-auth-timeout", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"})
21-03-2017 09:13:19.217 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
-----------------------------------------------------------------------------------------------------------------
21-03-2017 09:13:23.449 UTC Verbose httpstack.cpp:345: Process request for URL
/impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:23.449 UTC Debug handlers.cpp:1133: Parsed HTTP request:
private ID [email protected], public ID
sip:[email protected], server name
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 09:13:23.449 UTC Debug handlers.cpp:1057: Determining request type
from '{"reqtype": "dereg-auth-timeout", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"}'
21-03-2017 09:13:23.449 UTC Debug handlers.cpp:1101: New value of _type is 7
21-03-2017 09:13:23.449 UTC Debug handlers.cpp:1171: Try to find IMS
Subscription information in the cache
21-03-2017 09:13:23.449 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host 127.0.0.1, port 9160, family 2
21-03-2017 09:13:23.449 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 09:13:23.449 UTC Debug a_record_resolver.cpp:88: Target is an IP
address
21-03-2017 09:13:23.449 UTC Debug connection_pool.h:231: Request for connection
to IP: 127.0.0.1, port: 9160
21-03-2017 09:13:23.449 UTC Debug connection_pool.h:244: Found existing
connection 0x25ef330 in pool
21-03-2017 09:13:23.449 UTC Debug cassandra_store.cpp:159: Generated Cassandra
timestamp 1490087603449392
21-03-2017 09:13:23.449 UTC Debug cache.cpp:350: Issuing get for key
sip:[email protected]
21-03-2017 09:13:23.450 UTC Debug cassandra_store.cpp:731: Failed TWO read for
get_row. Try ONE
21-03-2017 09:13:23.452 UTC Debug cache.cpp:370: Retrieved XML column with TTL
0 and value <?xml version='1.0' encoding='UTF-8'?>
<IMSSubscription xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";
xsi:noNamespaceSchemaLocation="CxDataType.xsd"><PrivateID>Unspecified</PrivateID><ServiceProfile><InitialFilterCriteria><TriggerPoint><ConditionTypeCNF>0</ConditionTypeCNF><SPT><ConditionNegated>0</ConditionNegated><Group>0</Group><Method>INVITE</Method><Extension
/></SPT></TriggerPoint><ApplicationServer><ServerName>sip:mmtel.ims.cw.4gtss.com</ServerName><DefaultHandling>0</DefaultHandling></ApplicationServer></InitialFilterCriteria><PublicIdentity><Identity>sip:[email protected]</Identity></PublicIdentity></ServiceProfile></IMSSubscription>
21-03-2017 09:13:23.452 UTC Debug cache.cpp:438: Found stored XML for
subscriber, treating as UNREGISTERED state
21-03-2017 09:13:23.452 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:9160 transport 6
21-03-2017 09:13:23.453 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 9160 to pool
21-03-2017 09:13:23.453 UTC Debug handlers.cpp:1200: Got IMS subscription from
cache
21-03-2017 09:13:23.453 UTC Debug handlers.cpp:1215: TTL for this database
record is 0, IMS Subscription XML is not empty, registration state is
UNREGISTERED, and the charging addresses are empty
21-03-2017 09:13:23.453 UTC Debug handlers.cpp:1241: Subscriber registering
with new binding
21-03-2017 09:13:23.453 UTC Debug handlers.cpp:1475: Handling authentication
failure/timeout
21-03-2017 09:13:23.453 UTC Debug handlers.cpp:1522: Sending 200 response (body
was {"reqtype": "dereg-auth-timeout", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"})
21-03-2017 09:13:23.453 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
--------------------------------------------------------------------------------------------------
21-03-2017 09:13:30.852 UTC Verbose httpstack.cpp:345: Process request for URL
/ping, args (null)
21-03-2017 09:13:30.852 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /ping, args (null)
21-03-2017 09:13:31.569 UTC Verbose httpstack.cpp:345: Process request for URL
/impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:31.569 UTC Debug handlers.cpp:1133: Parsed HTTP request:
private ID [email protected], public ID
sip:[email protected], server name
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 09:13:31.569 UTC Debug handlers.cpp:1057: Determining request type
from '{"reqtype": "dereg-auth-timeout", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"}'
21-03-2017 09:13:31.569 UTC Debug handlers.cpp:1101: New value of _type is 7
21-03-2017 09:13:31.569 UTC Debug handlers.cpp:1171: Try to find IMS
Subscription information in the cache
21-03-2017 09:13:31.569 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host 127.0.0.1, port 9160, family 2
21-03-2017 09:13:31.569 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 09:13:31.569 UTC Debug a_record_resolver.cpp:88: Target is an IP
address
21-03-2017 09:13:31.569 UTC Debug connection_pool.h:231: Request for connection
to IP: 127.0.0.1, port: 9160
21-03-2017 09:13:31.569 UTC Debug connection_pool.h:244: Found existing
connection 0x25ef330 in pool
21-03-2017 09:13:31.570 UTC Debug cassandra_store.cpp:159: Generated Cassandra
timestamp 1490087611570002
21-03-2017 09:13:31.570 UTC Debug cache.cpp:350: Issuing get for key
sip:[email protected]
21-03-2017 09:13:31.570 UTC Debug cassandra_store.cpp:731: Failed TWO read for
get_row. Try ONE
21-03-2017 09:13:31.573 UTC Debug cache.cpp:370: Retrieved XML column with TTL
0 and value <?xml version='1.0' encoding='UTF-8'?>
<IMSSubscription xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";
xsi:noNamespaceSchemaLocation="CxDataType.xsd"><PrivateID>Unspecified</PrivateID><ServiceProfile><InitialFilterCriteria><TriggerPoint><ConditionTypeCNF>0</ConditionTypeCNF><SPT><ConditionNegated>0</ConditionNegated><Group>0</Group><Method>INVITE</Method><Extension
/></SPT></TriggerPoint><ApplicationServer><ServerName>sip:mmtel.ims.cw.4gtss.com</ServerName><DefaultHandling>0</DefaultHandling></ApplicationServer></InitialFilterCriteria><PublicIdentity><Identity>sip:[email protected]</Identity></PublicIdentity></ServiceProfile></IMSSubscription>
21-03-2017 09:13:31.573 UTC Debug cache.cpp:438: Found stored XML for
subscriber, treating as UNREGISTERED state
21-03-2017 09:13:31.573 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:9160 transport 6
21-03-2017 09:13:31.573 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 9160 to pool
21-03-2017 09:13:31.573 UTC Debug handlers.cpp:1200: Got IMS subscription from
cache
21-03-2017 09:13:31.573 UTC Debug handlers.cpp:1215: TTL for this database
record is 0, IMS Subscription XML is not empty, registration state is
UNREGISTERED, and the charging addresses are empty
21-03-2017 09:13:31.573 UTC Debug handlers.cpp:1241: Subscriber registering
with new binding
21-03-2017 09:13:31.573 UTC Debug handlers.cpp:1475: Handling authentication
failure/timeout
21-03-2017 09:13:31.573 UTC Debug handlers.cpp:1522: Sending 200 response (body
was {"reqtype": "dereg-auth-timeout", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"})
21-03-2017 09:13:31.573 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
----------------------------------------------------------------------------------------------------------
21-03-2017 09:13:36.303 UTC Verbose httpstack.cpp:345: Process request for URL
/impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:36.303 UTC Debug handlers.cpp:647: No HSS configured - fake
response if subscriber exists
21-03-2017 09:13:36.303 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:36.303 UTC Verbose httpstack.cpp:345: Process request for URL
/impi/6505550579%40ims.cw.4gtss.com/av, args
impu=sip%3A6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:36.303 UTC Debug handlers.cpp:155: Parsed HTTP request:
private ID [email protected], public ID
sip:[email protected], scheme Unknown, authorization
21-03-2017 09:13:36.303 UTC Debug handlers.cpp:181: Querying cache for
authentication vector for
[email protected]/sip:[email protected]
21-03-2017 09:13:36.303 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host 127.0.0.1, port 9160, family 2
21-03-2017 09:13:36.303 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 09:13:36.303 UTC Debug a_record_resolver.cpp:88: Target is an IP
address
21-03-2017 09:13:36.303 UTC Debug connection_pool.h:231: Request for connection
to IP: 127.0.0.1, port: 9160
21-03-2017 09:13:36.303 UTC Debug connection_pool.h:244: Found existing
connection 0x25ef330 in pool
21-03-2017 09:13:36.303 UTC Debug cache.cpp:673: Looking for authentication
vector for [email protected]
21-03-2017 09:13:36.303 UTC Debug cache.cpp:685: Checking public ID
sip:[email protected]
21-03-2017 09:13:36.303 UTC Debug cache.cpp:695: Issuing cache query
21-03-2017 09:13:36.304 UTC Debug cassandra_store.cpp:700: Failed TWO read for
get_columns. Try ONE
21-03-2017 09:13:36.305 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:9160 transport 6
21-03-2017 09:13:36.305 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 9160 to pool
21-03-2017 09:13:36.305 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 6, failures 0
21-03-2017 09:13:36.305 UTC Debug handlers.cpp:201: Got authentication vector
with digest f9490a60de145aed870afb6facbc1894 from cache
21-03-2017 09:13:36.305 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impi/6505550579%40ims.cw.4gtss.com/av, args
impu=sip%3A6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:36.325 UTC Verbose httpstack.cpp:345: Process request for URL
/impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:36.325 UTC Debug handlers.cpp:647: No HSS configured - fake
response if subscriber exists
21-03-2017 09:13:36.325 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:36.326 UTC Verbose httpstack.cpp:345: Process request for URL
/impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:36.326 UTC Debug handlers.cpp:1133: Parsed HTTP request:
private ID [email protected], public ID
sip:[email protected], server name
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 09:13:36.326 UTC Debug handlers.cpp:1057: Determining request type
from '{"reqtype": "dereg-auth-failed", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"}'
21-03-2017 09:13:36.326 UTC Debug handlers.cpp:1101: New value of _type is 6
21-03-2017 09:13:36.326 UTC Debug handlers.cpp:1171: Try to find IMS
Subscription information in the cache
21-03-2017 09:13:36.326 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host 127.0.0.1, port 9160, family 2
21-03-2017 09:13:36.326 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 09:13:36.326 UTC Debug a_record_resolver.cpp:88: Target is an IP
address
21-03-2017 09:13:36.326 UTC Debug connection_pool.h:231: Request for connection
to IP: 127.0.0.1, port: 9160
21-03-2017 09:13:36.326 UTC Debug connection_pool.h:244: Found existing
connection 0x25ef330 in pool
21-03-2017 09:13:36.326 UTC Debug cassandra_store.cpp:159: Generated Cassandra
timestamp 1490087616326224
21-03-2017 09:13:36.326 UTC Debug cache.cpp:350: Issuing get for key
sip:[email protected]
21-03-2017 09:13:36.326 UTC Debug cassandra_store.cpp:731: Failed TWO read for
get_row. Try ONE
21-03-2017 09:13:36.327 UTC Debug cache.cpp:370: Retrieved XML column with TTL
0 and value <?xml version='1.0' encoding='UTF-8'?>
<IMSSubscription xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";
xsi:noNamespaceSchemaLocation="CxDataType.xsd"><PrivateID>Unspecified</PrivateID><ServiceProfile><InitialFilterCriteria><TriggerPoint><ConditionTypeCNF>0</ConditionTypeCNF><SPT><ConditionNegated>0</ConditionNegated><Group>0</Group><Method>INVITE</Method><Extension
/></SPT></TriggerPoint><ApplicationServer><ServerName>sip:mmtel.ims.cw.4gtss.com</ServerName><DefaultHandling>0</DefaultHandling></ApplicationServer></InitialFilterCriteria><PublicIdentity><Identity>sip:[email protected]</Identity></PublicIdentity></ServiceProfile></IMSSubscription>
21-03-2017 09:13:36.327 UTC Debug cache.cpp:438: Found stored XML for
subscriber, treating as UNREGISTERED state
21-03-2017 09:13:36.327 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:9160 transport 6
21-03-2017 09:13:36.327 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 9160 to pool
21-03-2017 09:13:36.327 UTC Debug handlers.cpp:1200: Got IMS subscription from
cache
21-03-2017 09:13:36.327 UTC Debug handlers.cpp:1215: TTL for this database
record is 0, IMS Subscription XML is not empty, registration state is
UNREGISTERED, and the charging addresses are empty
21-03-2017 09:13:36.327 UTC Debug handlers.cpp:1241: Subscriber registering
with new binding
21-03-2017 09:13:36.327 UTC Debug handlers.cpp:1475: Handling authentication
failure/timeout
21-03-2017 09:13:36.327 UTC Debug handlers.cpp:1522: Sending 200 response (body
was {"reqtype": "dereg-auth-failed", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"})
21-03-2017 09:13:36.327 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
-------------------------------------------------------------------------------------------------
21-03-2017 09:13:40.259 UTC Verbose httpstack.cpp:345: Process request for URL
/impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:40.259 UTC Debug handlers.cpp:647: No HSS configured - fake
response if subscriber exists
21-03-2017 09:13:40.259 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:40.261 UTC Verbose httpstack.cpp:345: Process request for URL
/impi/6505550579%40ims.cw.4gtss.com/av, args
impu=sip%3A6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:40.261 UTC Debug handlers.cpp:155: Parsed HTTP request:
private ID [email protected], public ID
sip:[email protected], scheme Unknown, authorization
21-03-2017 09:13:40.261 UTC Debug handlers.cpp:181: Querying cache for
authentication vector for
[email protected]/sip:[email protected]
21-03-2017 09:13:40.261 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host 127.0.0.1, port 9160, family 2
21-03-2017 09:13:40.261 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 09:13:40.261 UTC Debug a_record_resolver.cpp:88: Target is an IP
address
21-03-2017 09:13:40.261 UTC Debug connection_pool.h:231: Request for connection
to IP: 127.0.0.1, port: 9160
21-03-2017 09:13:40.261 UTC Debug connection_pool.h:244: Found existing
connection 0x25ef330 in pool
21-03-2017 09:13:40.261 UTC Debug cache.cpp:673: Looking for authentication
vector for [email protected]
21-03-2017 09:13:40.261 UTC Debug cache.cpp:685: Checking public ID
sip:[email protected]
21-03-2017 09:13:40.261 UTC Debug cache.cpp:695: Issuing cache query
21-03-2017 09:13:40.262 UTC Debug cassandra_store.cpp:700: Failed TWO read for
get_columns. Try ONE
21-03-2017 09:13:40.264 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:9160 transport 6
21-03-2017 09:13:40.264 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 9160 to pool
21-03-2017 09:13:40.264 UTC Debug handlers.cpp:201: Got authentication vector
with digest f9490a60de145aed870afb6facbc1894 from cache
21-03-2017 09:13:40.264 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impi/6505550579%40ims.cw.4gtss.com/av, args
impu=sip%3A6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:40.291 UTC Verbose httpstack.cpp:345: Process request for URL
/impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:40.291 UTC Debug handlers.cpp:647: No HSS configured - fake
response if subscriber exists
21-03-2017 09:13:40.291 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impi/6505550579%40ims.cw.4gtss.com/registration-status, args
impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
21-03-2017 09:13:40.292 UTC Verbose httpstack.cpp:345: Process request for URL
/impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
21-03-2017 09:13:40.292 UTC Debug handlers.cpp:1133: Parsed HTTP request:
private ID [email protected], public ID
sip:[email protected], server name
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 09:13:40.292 UTC Debug handlers.cpp:1057: Determining request type
from '{"reqtype": "dereg-auth-failed", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"}'
21-03-2017 09:13:40.292 UTC Debug handlers.cpp:1101: New value of _type is 6
21-03-2017 09:13:40.292 UTC Debug handlers.cpp:1171: Try to find IMS
Subscription information in the cache
21-03-2017 09:13:40.292 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host 127.0.0.1, port 9160, family 2
21-03-2017 09:13:40.292 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 09:13:40.292 UTC Debug a_record_resolver.cpp:88: Target is an IP
address
21-03-2017 09:13:40.292 UTC Debug connection_pool.h:231: Request for connection
to IP: 127.0.0.1, port: 9160
21-03-2017 09:13:40.292 UTC Debug connection_pool.h:244: Found existing
connection 0x25ef330 in pool
21-03-2017 09:13:40.292 UTC Debug cassandra_store.cpp:159: Generated Cassandra
timestamp 1490087620292910
21-03-2017 09:13:40.292 UTC Debug cache.cpp:350: Issuing get for key
sip:[email protected]
21-03-2017 09:13:40.293 UTC Debug cassandra_store.cpp:731: Failed TWO read for
get_row. Try ONE
21-03-2017 09:13:40.294 UTC Debug cache.cpp:370: Retrieved XML column with TTL
0 and value <?xml version='1.0' encoding='UTF-8'?>
<IMSSubscription xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";
xsi:noNamespaceSchemaLocation="CxDataType.xsd"><PrivateID>Unspecified</PrivateID><ServiceProfile><InitialFilterCriteria><TriggerPoint><ConditionTypeCNF>0</ConditionTypeCNF><SPT><ConditionNegated>0</ConditionNegated><Group>0</Group><Method>INVITE</Method><Extension
/></SPT></TriggerPoint><ApplicationServer><ServerName>sip:mmtel.ims.cw.4gtss.com</ServerName><DefaultHandling>0</DefaultHandling></ApplicationServer></InitialFilterCriteria><PublicIdentity><Identity>sip:[email protected]</Identity></PublicIdentity></ServiceProfile></IMSSubscription>
21-03-2017 09:13:40.294 UTC Debug cache.cpp:438: Found stored XML for
subscriber, treating as UNREGISTERED state
21-03-2017 09:13:40.294 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:9160 transport 6
21-03-2017 09:13:40.294 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 9160 to pool
21-03-2017 09:13:40.294 UTC Debug handlers.cpp:1200: Got IMS subscription from
cache
21-03-2017 09:13:40.294 UTC Debug handlers.cpp:1215: TTL for this database
record is 0, IMS Subscription XML is not empty, registration state is
UNREGISTERED, and the charging addresses are empty
21-03-2017 09:13:40.294 UTC Debug handlers.cpp:1241: Subscriber registering
with new binding
21-03-2017 09:13:40.294 UTC Debug handlers.cpp:1475: Handling authentication
failure/timeout
21-03-2017 09:13:40.294 UTC Debug handlers.cpp:1522: Sending 200 response (body
was {"reqtype": "dereg-auth-failed", "server_name":
"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"})
21-03-2017 09:13:40.294 UTC Verbose httpstack.cpp:93: Sending response 200 to
request for URL /impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data, args
private_id=6505550579%40ims.cw.4gtss.com
Monit 5.18.1 uptime: 49m
Process 'sprout_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 1392
parent pid 1
uid 997
effective uid 997
gid 997
uptime 48m
threads 148
children 0
cpu 0.6%
cpu total 0.6%
memory 0.6% [12.0 MB]
memory total 0.6% [12.0 MB]
data collected Tue, 21 Mar 2017 10:16:14
Program 'sprout_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:14
Program 'poll_sprout_sip'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:14
Program 'poll_sprout_http'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:14
Process 'snmpd_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 1685
parent pid 1
uid 119
effective uid 119
gid 129
uptime 48m
threads 1
children 0
cpu 0.0%
cpu total 0.0%
memory 0.1% [3.0 MB]
memory total 0.1% [3.0 MB]
data collected Tue, 21 Mar 2017 10:16:14
Process 'ntp_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 2297
parent pid 1
uid 118
effective uid 118
gid 128
uptime 48m
threads 1
children 0
cpu 0.0%
cpu total 0.0%
memory 0.1% [2.0 MB]
memory total 0.1% [2.0 MB]
data collected Tue, 21 Mar 2017 10:16:14
System 'node-machine'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
load average [0.38] [0.14] [0.18]
cpu 2.7%us 2.3%sy 1.8%wa
memory usage 1.7 GB [85.7%]
swap usage 282.5 MB [27.6%]
uptime 50m
boot time Tue, 21 Mar 2017 09:26:43
data collected Tue, 21 Mar 2017 10:16:14
Process 'nginx_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 1708
parent pid 1
uid 0
effective uid 0
gid 0
uptime 48m
threads 1
children 4
cpu 0.0%
cpu total 0.0%
memory 0.0% [624 kB]
memory total 0.2% [4.0 MB]
data collected Tue, 21 Mar 2017 10:16:14
Program 'nginx_ping'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output % Total % Received % Xferd Average Speed
Time Time Time Current
Dload Upload
Total Spent Left Speed
0 0 0 0 0 0 0 0
--:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0
--:--:-- --:--:-- --:--:-- 0
data collected Tue, 21 Mar 2017 10:16:14
Program 'nginx_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:14
Program 'monit_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:14
Process 'memcached_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3304
parent pid 1
uid 120
effective uid 120
gid 130
uptime 48m
threads 6
children 0
cpu 0.0%
cpu total 0.0%
memory 0.4% [7.9 MB]
memory total 0.4% [7.9 MB]
data collected Tue, 21 Mar 2017 10:16:14
Program 'memcached_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:14
Program 'poll_memcached'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:14
Process 'homestead_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 4143
parent pid 1
uid 991
effective uid 991
gid 991
uptime 47m
threads 121
children 0
cpu 0.0%
cpu total 0.0%
memory 1.2% [24.6 MB]
memory total 1.2% [24.6 MB]
data collected Tue, 21 Mar 2017 10:16:14
Program 'homestead_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:14
Program 'poll_homestead'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:14
Program 'check_cx_health'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 1
last output Traceback (most recent call last):
File
"/usr/share/clearwater/bin/check_cx_health.py", line 64, in <module>
import netsnmp
ImportError: No module named netsnmp
data collected Tue, 21 Mar 2017 10:16:14
Process 'homestead-prov_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3622
parent pid 1
uid 0
effective uid 0
gid 0
uptime 47m
threads 6
children 0
cpu 0.0%
cpu total 0.0%
memory 1.9% [37.7 MB]
memory total 1.9% [37.7 MB]
data collected Tue, 21 Mar 2017 10:16:14
Program 'poll_homestead-prov'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:14
Process 'clearwater_queue_manager_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3490
parent pid 1
uid 0
effective uid 0
gid 0
uptime 48m
threads 10
children 0
cpu 0.0%
cpu total 0.0%
memory 0.6% [11.2 MB]
memory total 0.6% [11.2 MB]
data collected Tue, 21 Mar 2017 10:16:14
Program 'clearwater_queue_manager_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:14
Process 'etcd_process'
status Execution failed | Does not exist
monitoring status Monitored
monitoring mode active
on reboot start
data collected Tue, 21 Mar 2017 10:16:45
Program 'etcd_uptime'
status Wait parent
monitoring status Wait parent
monitoring mode active
on reboot start
data collected Tue, 21 Mar 2017 09:29:11
Program 'poll_etcd_cluster'
status Wait parent
monitoring status Wait parent
monitoring mode active
on reboot start
data collected Tue, 21 Mar 2017 09:29:11
Program 'poll_etcd'
status Wait parent
monitoring status Wait parent
monitoring mode active
on reboot start
data collected Tue, 21 Mar 2017 09:29:11
Process 'clearwater_diags_monitor_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 1630
parent pid 1
uid 0
effective uid 0
gid 0
uptime 48m
threads 1
children 1
cpu 0.0%
cpu total 0.0%
memory 0.0% [516 kB]
memory total 0.0% [516 kB]
data collected Tue, 21 Mar 2017 10:16:45
Process 'clearwater_config_manager_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3186
parent pid 1
uid 0
effective uid 0
gid 0
uptime 48m
threads 8
children 0
cpu 0.0%
cpu total 0.0%
memory 0.5% [9.4 MB]
memory total 0.5% [9.4 MB]
data collected Tue, 21 Mar 2017 10:16:45
Process 'clearwater_cluster_manager_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3120
parent pid 1
uid 0
effective uid 0
gid 0
uptime 48m
threads 5
children 0
cpu 0.0%
cpu total 0.0%
memory 0.2% [4.6 MB]
memory total 0.2% [4.6 MB]
data collected Tue, 21 Mar 2017 10:16:45
Process 'cassandra_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3177
parent pid 1
uid 121
effective uid 121
gid 131
uptime 48m
threads 86
children 0
cpu 0.0%
cpu total 0.0%
memory 68.8% [1.3 GB]
memory total 68.8% [1.3 GB]
data collected Tue, 21 Mar 2017 10:16:45
Program 'cassandra_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:45
Program 'poll_cassandra'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:45
Program 'poll_cqlsh'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:45
Process 'chronos_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3672
parent pid 1
uid 993
effective uid 993
gid 993
uptime 47m
threads 235
children 0
cpu 0.0%
cpu total 0.0%
memory 1.0% [20.2 MB]
memory total 1.0% [20.2 MB]
data collected Tue, 21 Mar 2017 10:16:45
Program 'chronos_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:45
Program 'poll_chronos'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output -
data collected Tue, 21 Mar 2017 10:16:45
Process 'astaire_process'
status Running
monitoring status Monitored
monitoring mode active
on reboot start
pid 3089
parent pid 1
uid 996
effective uid 996
gid 996
uptime 48m
threads 20
children 0
cpu 0.0%
cpu total 0.0%
memory 0.2% [4.2 MB]
memory total 0.2% [4.2 MB]
data collected Tue, 21 Mar 2017 10:16:45
Program 'astaire_uptime'
status Status ok
monitoring status Monitored
monitoring mode active
on reboot start
last exit value 0
last output zmq_msg_recv: Resource temporarily unavailable
data collected Tue, 21 Mar 2017 10:16:45
21-03-2017 08:22:33.431 UTC Verbose sproutletproxy.cpp:538: Sproutlet Proxy
transaction (0x7fee48010aa0) destroyed
21-03-2017 08:22:33.431 UTC Debug basicproxy.cpp:494: BasicProxy::UASTsx
destructor (0x7fee48010aa0)
21-03-2017 08:22:33.431 UTC Debug basicproxy.cpp:511: Disconnect UAC
transactions from UAS transaction
21-03-2017 08:22:33.431 UTC Debug basicproxy.cpp:525: Free original request
21-03-2017 08:22:33.431 UTC Debug pjsip: tdta0x7fee4803 Destroying txdata
Request msg REGISTER/cseq=2 (tdta0x7fee4803f080)
21-03-2017 08:22:33.431 UTC Debug basicproxy.cpp:534: Free un-used best response
21-03-2017 08:22:33.431 UTC Debug pjsip: tdta0x7fee4800 Destroying txdata
Response msg 408/REGISTER/cseq=2 (tdta0x7fee4800b4d0)
21-03-2017 08:22:33.431 UTC Debug basicproxy.cpp:555: BasicProxy::UASTsx
destructor completed
21-03-2017 08:22:33.431 UTC Debug pjsip: tdta0x7fee4806 Destroying txdata
Response msg 403/REGISTER/cseq=2 (tdta0x7fee48062180)
21-03-2017 08:22:33.431 UTC Debug pjsip: tsx0x7fee4802f Transaction destroyed!
21-03-2017 08:22:36.387 UTC Debug pjsip: sip_endpoint.c Processing incoming
message: Request msg REGISTER/cseq=1 (rdata0x7fee1408b790)
21-03-2017 08:22:36.387 UTC Verbose common_sip_processing.cpp:120: RX 792 bytes
Request msg REGISTER/cseq=1 (rdata0x7fee1408b790) from TCP 192.168.0.210:60414:
--start msg--
REGISTER sip:ims.cw.4gtss.com:5060;transport=UDP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.210:5058;rport;branch=z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---e90943a4cef07621
Max-Forwards: 70
Contact:
<sip:[email protected]:48520;transport=UDP;rinstance=a86ac72e2fe2b65c>
To: <sip:[email protected]>
From: <sip:[email protected]>;tag=c84a675a
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
CSeq: 1 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.30
Allow-Events: presence, kpml, talk
P-Visited-Network-ID: ims.cw.4gtss.com
Route: <sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig>
Content-Length: 0
--end msg--
21-03-2017 08:22:36.388 UTC Debug pjutils.cpp:1689: Logging SAS Call-ID marker,
Call-ID HnR-9wnGh4KD9zJ059lKvw..
21-03-2017 08:22:36.388 UTC Debug thread_dispatcher.cpp:264: Queuing cloned
received message 0x7fee14078ee8 for worker threads
21-03-2017 08:22:36.388 UTC Debug thread_dispatcher.cpp:150: Worker thread
dequeue message 0x7fee14078ee8
21-03-2017 08:22:36.388 UTC Debug pjsip: sip_endpoint.c Distributing rdata to
modules: Request msg REGISTER/cseq=1 (rdata0x7fee14078ee8)
21-03-2017 08:22:36.388 UTC Debug uri_classifier.cpp:174: home domain: true,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
21-03-2017 08:22:36.388 UTC Debug uri_classifier.cpp:204: Classified URI as 4
21-03-2017 08:22:36.388 UTC Debug basicproxy.cpp:92: Process REGISTER request
21-03-2017 08:22:36.388 UTC Verbose sproutletproxy.cpp:507: Sproutlet Proxy
transaction (0x7fee5005a0c0) created
21-03-2017 08:22:36.388 UTC Debug basicproxy.cpp:1298: Report SAS start marker
- trail (183)
21-03-2017 08:22:36.389 UTC Debug pjutils.cpp:699: Cloned Request msg
REGISTER/cseq=1 (rdata0x7fee14078ee8) to tdta0x7fee50026b90
21-03-2017 08:22:36.389 UTC Debug pjsip: tsx0x7fee5002a Transaction created for
Request msg REGISTER/cseq=1 (rdata0x7fee14078ee8)
21-03-2017 08:22:36.389 UTC Debug pjsip: tsx0x7fee5002a Incoming Request msg
REGISTER/cseq=1 (rdata0x7fee14078ee8) in state Null
21-03-2017 08:22:36.389 UTC Debug pjsip: tsx0x7fee5002a State changed from Null
to Trying, event=RX_MSG
21-03-2017 08:22:36.389 UTC Debug basicproxy.cpp:213: tsx0x7fee5002a948 -
tu_on_tsx_state UAS, TSX_STATE RX_MSG state=Trying
21-03-2017 08:22:36.389 UTC Debug pjsip: endpoint Response msg
408/REGISTER/cseq=1 (tdta0x7fee50029260) created
21-03-2017 08:22:36.389 UTC Debug sproutletproxy.cpp:119: Find target Sproutlet
for request
21-03-2017 08:22:36.389 UTC Debug sproutletproxy.cpp:154: Found next routable
URI: sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig
21-03-2017 08:22:36.389 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.389 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.389 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.390 UTC Verbose sproutletproxy.cpp:1163: Created Sproutlet
icscf-0x7fee50059fb0 for Request msg REGISTER/cseq=1 (tdta0x7fee50026b90)
21-03-2017 08:22:36.390 UTC Verbose sproutletproxy.cpp:2095: Routing Request
msg REGISTER/cseq=1 (tdta0x7fee50026b90) (821 bytes) to downstream sproutlet
icscf:
--start msg--
REGISTER sip:ims.cw.4gtss.com:5060;transport=UDP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60414;received=192.168.0.210;branch=z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---e90943a4cef07621
Max-Forwards: 70
Contact:
<sip:[email protected]:48520;transport=UDP;rinstance=a86ac72e2fe2b65c>
To: <sip:[email protected]>
From: <sip:[email protected]>;tag=c84a675a
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
CSeq: 1 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.30
Allow-Events: presence, kpml, talk
P-Visited-Network-ID: ims.cw.4gtss.com
Route: <sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig>
Content-Length: 0
--end msg--
21-03-2017 08:22:36.390 UTC Debug pjutils.cpp:716: Cloned tdta0x7fee50026b90 to
tdta0x7fee50018000
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:1224: Remove top Route
header Route: <sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig>
21-03-2017 08:22:36.390 UTC Debug sproutletproxy.cpp:1768: Adding message
0x7fee50018610 => txdata 0x7fee500180a8 mapping
21-03-2017 08:22:36.390 UTC Verbose sproutletproxy.cpp:1613:
icscf-0x7fee50059fb0 pass initial request Request msg REGISTER/cseq=1
(tdta0x7fee50018000) to Sproutlet
21-03-2017 08:22:36.390 UTC Debug acr.cpp:1812: Create RalfACR for node type
I-CSCF with role Terminating
21-03-2017 08:22:36.390 UTC Debug acr.cpp:49: Created ACR (0x7fee5002a270)
21-03-2017 08:22:36.390 UTC Debug acr.cpp:189: Created I-CSCF Ralf ACR
21-03-2017 08:22:36.390 UTC Debug acr.cpp:269: Set record type for I-CSCF,
BGCF, IBCF, AS to EVENT_RECORD
21-03-2017 08:22:36.390 UTC Debug icscfsproutlet.cpp:193: I-CSCF initialize
transaction for REGISTER request
21-03-2017 08:22:36.390 UTC Debug icscfrouter.cpp:345: Perform UAR - impi
[email protected], impu sip:[email protected], vn
ims.cw.4gtss.com, auth_type REG
21-03-2017 08:22:36.391 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host hs.ims.cw.4gtss.com, port 8888, family 2
21-03-2017 08:22:36.391 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.391 UTC Verbose dnscachedresolver.cpp:486: Check cache for
hs.ims.cw.4gtss.com type 1
21-03-2017 08:22:36.391 UTC Debug dnscachedresolver.cpp:588: Pulling 1 records
from cache for hs.ims.cw.4gtss.com A
21-03-2017 08:22:36.391 UTC Debug baseresolver.cpp:366: Found 1 A/AAAA records,
creating iterator
21-03-2017 08:22:36.391 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.391 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.391 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.391 UTC Debug baseresolver.cpp:1004: Added a whitelisted
server, now have 1 of 1
21-03-2017 08:22:36.391 UTC Debug connection_pool.h:231: Request for connection
to IP: 192.168.0.212, port: 8888
21-03-2017 08:22:36.391 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee04023ac0 in pool
21-03-2017 08:22:36.391 UTC Debug httpclient.cpp:478: Set CURLOPT_RESOLVE:
hs.ims.cw.4gtss.com:8888:192.168.0.212
21-03-2017 08:22:36.391 UTC Debug httpclient.cpp:505: Sending HTTP request :
http://hs.ims.cw.4gtss.com:8888/impi/6505550579%40ims.cw.4gtss.com/registration-status?impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
(trying 192.168.0.212)
21-03-2017 08:22:36.391 UTC Debug httpclient.cpp:832: Received header
http/1.1200ok with value
21-03-2017 08:22:36.391 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1350
21-03-2017 08:22:36.392 UTC Debug httpclient.cpp:832: Received header
content-length with value 83
21-03-2017 08:22:36.392 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1350
21-03-2017 08:22:36.392 UTC Debug httpclient.cpp:832: Received header
content-type with value text/plain
21-03-2017 08:22:36.392 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1350
21-03-2017 08:22:36.392 UTC Debug httpclient.cpp:832: Received header with
value
21-03-2017 08:22:36.392 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1350
21-03-2017 08:22:36.392 UTC Debug httpclient.cpp:538: Received HTTP response:
status=200,
doc={"result-code":2001,"scscf":"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"}
21-03-2017 08:22:36.392 UTC Debug baseresolver.cpp:830: Successful response
from 192.168.0.212:8888 transport 6
21-03-2017 08:22:36.392 UTC Debug connection_pool.h:267: Release connection to
IP: 192.168.0.212, port: 8888 to pool
21-03-2017 08:22:36.392 UTC Debug icscfrouter.cpp:237: HSS returned S-CSCF
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP as target
21-03-2017 08:22:36.392 UTC Debug acr.cpp:653: Storing Server-Capabilities
21-03-2017 08:22:36.392 UTC Debug icscfrouter.cpp:113: SCSCF specified by HSS:
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 08:22:36.392 UTC Debug uri_classifier.cpp:174: home domain: false,
local_to_node: true, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
21-03-2017 08:22:36.392 UTC Debug uri_classifier.cpp:204: Classified URI as 3
21-03-2017 08:22:36.392 UTC Debug icscfsproutlet.cpp:279: Found SCSCF for
REGISTER
21-03-2017 08:22:36.392 UTC Debug sproutletproxy.cpp:1364: Sproutlet
send_request 0x7fee50018610
21-03-2017 08:22:36.392 UTC Verbose sproutletproxy.cpp:1400:
icscf-0x7fee50059fb0 sending Request msg REGISTER/cseq=1 (tdta0x7fee50018000)
on fork 0
21-03-2017 08:22:36.392 UTC Debug sproutletproxy.cpp:1783: Processing actions
from sproutlet - 0 responses, 1 requests, 0 timers
21-03-2017 08:22:36.392 UTC Debug sproutletproxy.cpp:1823: Processing request
0x7fee500180a8, fork = 0
21-03-2017 08:22:36.392 UTC Debug sproutletproxy.cpp:1947: icscf-0x7fee50059fb0
transmitting request on fork 0
21-03-2017 08:22:36.392 UTC Debug sproutletproxy.cpp:1961: icscf-0x7fee50059fb0
store reference to non-ACK request Request msg REGISTER/cseq=1
(tdta0x7fee50018000) on fork 0
21-03-2017 08:22:36.392 UTC Debug sproutletproxy.cpp:1775: Removing message
0x7fee50018610 => txdata 0x7fee500180a8 mapping
21-03-2017 08:22:36.393 UTC Debug sproutletproxy.cpp:119: Find target Sproutlet
for request
21-03-2017 08:22:36.393 UTC Debug sproutletproxy.cpp:154: Found next routable
URI: sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 08:22:36.393 UTC Debug sproutletproxy.cpp:289: Possible service name
scscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.393 UTC Debug sproutletproxy.cpp:293: Adding possible
service name scscf based on domain
21-03-2017 08:22:36.393 UTC Verbose sproutletproxy.cpp:1163: Created Sproutlet
authentication-0x7fee5005a990 for Request msg REGISTER/cseq=1
(tdta0x7fee50018000)
21-03-2017 08:22:36.393 UTC Verbose sproutletproxy.cpp:2095: Routing Request
msg REGISTER/cseq=1 (tdta0x7fee50018000) (763 bytes) to downstream sproutlet
authentication:
--start msg--
REGISTER sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60414;received=192.168.0.210;branch=z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---e90943a4cef07621
Max-Forwards: 69
Contact:
<sip:[email protected]:48520;transport=UDP;rinstance=a86ac72e2fe2b65c>
To: <sip:[email protected]>
From: <sip:[email protected]>;tag=c84a675a
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
CSeq: 1 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.30
Allow-Events: presence, kpml, talk
P-Visited-Network-ID: ims.cw.4gtss.com
Content-Length: 0
--end msg--
21-03-2017 08:22:36.393 UTC Debug pjutils.cpp:716: Cloned tdta0x7fee50018000 to
tdta0x7fee500616a0
21-03-2017 08:22:36.393 UTC Debug sproutletproxy.cpp:1768: Adding message
0x7fee50061cb0 => txdata 0x7fee50061748 mapping
21-03-2017 08:22:36.393 UTC Verbose sproutletproxy.cpp:1613:
authentication-0x7fee5005a990 pass initial request Request msg REGISTER/cseq=1
(tdta0x7fee500616a0) to Sproutlet
21-03-2017 08:22:36.393 UTC Debug authenticationsproutlet.cpp:829:
Authentication module invoked
21-03-2017 08:22:36.393 UTC Debug authenticationsproutlet.cpp:841: Request
needs authentication
21-03-2017 08:22:36.393 UTC Debug acr.cpp:1812: Create RalfACR for node type
S-CSCF with role Originating
21-03-2017 08:22:36.393 UTC Debug acr.cpp:49: Created ACR (0x7fee50019c20)
21-03-2017 08:22:36.393 UTC Debug acr.cpp:189: Created S-CSCF Ralf ACR
21-03-2017 08:22:36.393 UTC Debug acr.cpp:229: Set record type for P/S-CSCF
21-03-2017 08:22:36.393 UTC Debug acr.cpp:237: Non-dialog message =>
EVENT_RECORD
21-03-2017 08:22:36.393 UTC Debug acr.cpp:1540: Stored 0 subscription
identifiers
21-03-2017 08:22:36.393 UTC Debug authenticationsproutlet.cpp:1150: No
authentication information in request or stale nonce, so reject with challenge
21-03-2017 08:22:36.393 UTC Debug sproutletproxy.cpp:1768: Adding message
0x7fee50063c70 => txdata 0x7fee50063708 mapping
21-03-2017 08:22:36.393 UTC Debug pjutils.cpp:423: Private identity defaulted
from public identity = [email protected]
21-03-2017 08:22:36.393 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host hs.ims.cw.4gtss.com, port 8888, family 2
21-03-2017 08:22:36.393 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.393 UTC Verbose dnscachedresolver.cpp:486: Check cache for
hs.ims.cw.4gtss.com type 1
21-03-2017 08:22:36.393 UTC Debug dnscachedresolver.cpp:588: Pulling 1 records
from cache for hs.ims.cw.4gtss.com A
21-03-2017 08:22:36.393 UTC Debug baseresolver.cpp:366: Found 1 A/AAAA records,
creating iterator
21-03-2017 08:22:36.394 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.394 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.394 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.394 UTC Debug baseresolver.cpp:1004: Added a whitelisted
server, now have 1 of 1
21-03-2017 08:22:36.394 UTC Debug connection_pool.h:231: Request for connection
to IP: 192.168.0.212, port: 8888
21-03-2017 08:22:36.394 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee04023ac0 in pool
21-03-2017 08:22:36.394 UTC Debug httpclient.cpp:478: Set CURLOPT_RESOLVE:
hs.ims.cw.4gtss.com:8888:192.168.0.212
21-03-2017 08:22:36.394 UTC Debug httpclient.cpp:505: Sending HTTP request :
http://hs.ims.cw.4gtss.com:8888/impi/6505550579%40ims.cw.4gtss.com/av?impu=sip%3A6505550579%40ims.cw.4gtss.com
(trying 192.168.0.212)
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:832: Received header
http/1.1200ok with value
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1060
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:832: Received header
content-length with value 93
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1060
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:832: Received header
content-type with value text/plain
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1060
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:832: Received header with
value
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1060
21-03-2017 08:22:36.397 UTC Debug httpclient.cpp:538: Received HTTP response:
status=200,
doc={"digest":{"ha1":"f9490a60de145aed870afb6facbc1894","realm":"ims.cw.4gtss.com","qop":"auth"}}
21-03-2017 08:22:36.398 UTC Debug baseresolver.cpp:830: Successful response
from 192.168.0.212:8888 transport 6
21-03-2017 08:22:36.398 UTC Debug connection_pool.h:267: Release connection to
IP: 192.168.0.212, port: 8888 to pool
21-03-2017 08:22:36.398 UTC Debug authenticationsproutlet.cpp:219: Verifying
AV:
{"digest":{"ha1":"f9490a60de145aed870afb6facbc1894","realm":"ims.cw.4gtss.com","qop":"auth"}}
21-03-2017 08:22:36.398 UTC Debug authenticationsproutlet.cpp:246: Digest
specified
21-03-2017 08:22:36.398 UTC Debug authenticationsproutlet.cpp:411: Valid AV -
generate challenge
21-03-2017 08:22:36.398 UTC Debug authenticationsproutlet.cpp:420: Create
WWW-Authenticate header
21-03-2017 08:22:36.398 UTC Debug authenticationsproutlet.cpp:536: Add Digest
information
21-03-2017 08:22:36.398 UTC Debug authenticationsproutlet.cpp:591: Write
authentication challenge to IMPI store
21-03-2017 08:22:36.398 UTC Debug memcachedstore.cpp:1128: Start GET from table
impi for key [email protected]
21-03-2017 08:22:36.398 UTC Debug astaire_resolver.cpp:72:
AstaireResolver::resolve for host sprout.ims.cw.4gtss.com, family 2
21-03-2017 08:22:36.398 UTC Debug utils.cpp:352: Malformed host/port
sprout.ims.cw.4gtss.com
21-03-2017 08:22:36.398 UTC Debug baseresolver.cpp:425: Attempt to parse
sprout.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.398 UTC Verbose dnscachedresolver.cpp:486: Check cache for
sprout.ims.cw.4gtss.com type 1
21-03-2017 08:22:36.398 UTC Debug dnscachedresolver.cpp:588: Pulling 1 records
from cache for sprout.ims.cw.4gtss.com A
21-03-2017 08:22:36.398 UTC Debug baseresolver.cpp:366: Found 1 A/AAAA records,
creating iterator
21-03-2017 08:22:36.398 UTC Debug baseresolver.cpp:819: 192.168.0.212:11311
transport 6 has state: WHITE
21-03-2017 08:22:36.398 UTC Debug baseresolver.cpp:819: 192.168.0.212:11311
transport 6 has state: WHITE
21-03-2017 08:22:36.398 UTC Debug baseresolver.cpp:1004: Added a whitelisted
server, now have 1 of 2
21-03-2017 08:22:36.398 UTC Debug memcachedstore.cpp:1469: Found 1 targets for
sprout.ims.cw.4gtss.com
21-03-2017 08:22:36.398 UTC Debug memcachedstore.cpp:1494: Duplicate target
IP=192.168.0.212, port= 11311 as it is the only target
21-03-2017 08:22:36.398 UTC Debug memcachedstore.cpp:1082: Try server IP
192.168.0.212, port 11311
21-03-2017 08:22:36.398 UTC Debug connection_pool.h:231: Request for connection
to IP: 192.168.0.212, port: 11311
21-03-2017 08:22:36.398 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee0403fb90 in pool
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:107: Fetch result
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:115: Found record on
replica
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:1093: libmemcached
returned 0
21-03-2017 08:22:36.399 UTC Debug connection_pool.h:267: Release connection to
IP: 192.168.0.212, port: 11311 to pool
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:1172: Read 442 bytes from
table impi key [email protected], CAS = 104
21-03-2017 08:22:36.399 UTC Debug impistore.cpp:783: Retrieved IMPI for
[email protected]
{"authChallenges":[{"type":"digest","nonce":"7651f6915ec1a1a3","nc":1,"expires":1490084591,"correlator":"z9hG4bKPjt.RUpwKN0ZmRpvwMT1uo0uP7E2.k3mmT","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"},{"type":"digest","nonce":"3f0f42671ad0e902","nc":1,"expires":1490084593,"correlator":"z9hG4bKPj2AIRW02l3PcY2jz.sbXZd5wO-0TIRhDK","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"}]}
21-03-2017 08:22:36.399 UTC Debug impistore.cpp:648: Storing IMPI for
[email protected]
{"authChallenges":[{"type":"digest","nonce":"7651f6915ec1a1a3","nc":1,"expires":1490084591,"correlator":"z9hG4bKPjt.RUpwKN0ZmRpvwMT1uo0uP7E2.k3mmT","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"},{"type":"digest","nonce":"3f0f42671ad0e902","nc":1,"expires":1490084593,"correlator":"z9hG4bKPj2AIRW02l3PcY2jz.sbXZd5wO-0TIRhDK","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"},{"type":"digest","nonce":"74aa750f213c9ee2","nc":1,"expires":1490084596,"correlator":"z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"}]}
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:1251: Writing 653 bytes to
table impi key [email protected], CAS = 104, expiry = 40
21-03-2017 08:22:36.399 UTC Debug astaire_resolver.cpp:72:
AstaireResolver::resolve for host sprout.ims.cw.4gtss.com, family 2
21-03-2017 08:22:36.399 UTC Debug utils.cpp:352: Malformed host/port
sprout.ims.cw.4gtss.com
21-03-2017 08:22:36.399 UTC Debug baseresolver.cpp:425: Attempt to parse
sprout.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.399 UTC Verbose dnscachedresolver.cpp:486: Check cache for
sprout.ims.cw.4gtss.com type 1
21-03-2017 08:22:36.399 UTC Debug dnscachedresolver.cpp:588: Pulling 1 records
from cache for sprout.ims.cw.4gtss.com A
21-03-2017 08:22:36.399 UTC Debug baseresolver.cpp:366: Found 1 A/AAAA records,
creating iterator
21-03-2017 08:22:36.399 UTC Debug baseresolver.cpp:819: 192.168.0.212:11311
transport 6 has state: WHITE
21-03-2017 08:22:36.399 UTC Debug baseresolver.cpp:819: 192.168.0.212:11311
transport 6 has state: WHITE
21-03-2017 08:22:36.399 UTC Debug baseresolver.cpp:1004: Added a whitelisted
server, now have 1 of 2
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:1469: Found 1 targets for
sprout.ims.cw.4gtss.com
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:1494: Duplicate target
IP=192.168.0.212, port= 11311 as it is the only target
21-03-2017 08:22:36.399 UTC Debug memcachedstore.cpp:1082: Try server IP
192.168.0.212, port 11311
21-03-2017 08:22:36.399 UTC Debug connection_pool.h:231: Request for connection
to IP: 192.168.0.212, port: 11311
21-03-2017 08:22:36.399 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee0403fb90 in pool
21-03-2017 08:22:36.403 UTC Debug memcachedstore.cpp:1093: libmemcached
returned 0
21-03-2017 08:22:36.403 UTC Debug connection_pool.h:267: Release connection to
IP: 192.168.0.212, port: 11311 to pool
21-03-2017 08:22:36.403 UTC Debug memcachedstore.cpp:1328: Write successful
21-03-2017 08:22:36.403 UTC Debug authenticationsproutlet.cpp:663: Sending
{"impi": "[email protected]", "impu":
"sip:[email protected]", "nonce": "74aa750f213c9ee2"} to Chronos to
set AV timer
21-03-2017 08:22:36.403 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host 127.0.0.1, port 7253, family 2
21-03-2017 08:22:36.403 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 08:22:36.403 UTC Debug a_record_resolver.cpp:88: Target is an IP
address
21-03-2017 08:22:36.403 UTC Debug baseresolver.cpp:425: Attempt to parse
127.0.0.1 as IP address
21-03-2017 08:22:36.403 UTC Debug connection_pool.h:231: Request for connection
to IP: 127.0.0.1, port: 7253
21-03-2017 08:22:36.403 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee0406a370 in pool
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:505: Sending HTTP request :
http://127.0.0.1:7253/timers (trying 127.0.0.1)
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:832: Received header
http/1.1200ok with value
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1540
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:832: Received header location
with value /timers/000cc83863f00067-2
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1540
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:832: Received header
content-length with value 0
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1540
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:832: Received header with
value
21-03-2017 08:22:36.403 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee217c1540
21-03-2017 08:22:36.404 UTC Debug httpclient.cpp:538: Received HTTP response:
status=200, doc=
21-03-2017 08:22:36.404 UTC Debug baseresolver.cpp:830: Successful response
from 127.0.0.1:7253 transport 6
21-03-2017 08:22:36.404 UTC Debug connection_pool.h:267: Release connection to
IP: 127.0.0.1, port: 7253 to pool
21-03-2017 08:22:36.404 UTC Info acr.cpp:690: No CCF or ECF to send ACR for
session HnR-9wnGh4KD9zJ059lKvw.. to - dropping!
21-03-2017 08:22:36.404 UTC Verbose sproutletproxy.cpp:1427:
authentication-0x7fee5005a990 sending Response msg 401/REGISTER/cseq=1
(tdta0x7fee50063660)
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1775: Removing message
0x7fee50061cb0 => txdata 0x7fee50061748 mapping
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1504: Free message
tdta0x7fee500616a0
21-03-2017 08:22:36.404 UTC Debug pjsip: tdta0x7fee5006 Destroying txdata
Request msg REGISTER/cseq=1 (tdta0x7fee500616a0)
21-03-2017 08:22:36.404 UTC Debug acr.cpp:54: Destroyed ACR (0x7fee50019c20)
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1783: Processing actions
from sproutlet - 1 responses, 0 requests, 0 timers
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1869: Aggregating response
with status code 401
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1919: 3xx/4xx/5xx/6xx
response
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1923: Best 3xx/4xx/5xx/6xx
response so far
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1810: All UAC responded
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1775: Removing message
0x7fee50063c70 => txdata 0x7fee50063708 mapping
21-03-2017 08:22:36.404 UTC Verbose sproutletproxy.cpp:2095: Routing Response
msg 401/REGISTER/cseq=1 (tdta0x7fee50063660) (605 bytes) to upstream sproutlet
icscf:
--start msg--
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60414;received=192.168.0.210;branch=z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---e90943a4cef07621
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
From: <sip:[email protected]>;tag=c84a675a
To:
<sip:[email protected]>;tag=z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv
CSeq: 1 REGISTER
WWW-Authenticate: Digest
realm="ims.cw.4gtss.com",nonce="74aa750f213c9ee2",opaque="6672d0ba0d7b3b36",algorithm=MD5,qop="auth"
Content-Length: 0
--end msg--
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1768: Adding message
0x7fee50063c70 => txdata 0x7fee50063708 mapping
21-03-2017 08:22:36.404 UTC Verbose sproutletproxy.cpp:1666:
icscf-0x7fee50059fb0 received final response Response msg 401/REGISTER/cseq=1
(tdta0x7fee50063660) on fork 0, state = Terminated
21-03-2017 08:22:36.404 UTC Debug acr.cpp:1540: Stored 1 subscription
identifiers
21-03-2017 08:22:36.404 UTC Debug icscfsproutlet.cpp:329: Check retry
conditions for REGISTER, status = 401, S-CSCF responsive
21-03-2017 08:22:36.404 UTC Verbose sproutletproxy.cpp:1427:
icscf-0x7fee50059fb0 sending Response msg 401/REGISTER/cseq=1
(tdta0x7fee50063660)
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1783: Processing actions
from sproutlet - 1 responses, 0 requests, 0 timers
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1869: Aggregating response
with status code 401
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1919: 3xx/4xx/5xx/6xx
response
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1923: Best 3xx/4xx/5xx/6xx
response so far
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1810: All UAC responded
21-03-2017 08:22:36.404 UTC Debug sproutletproxy.cpp:1775: Removing message
0x7fee50063c70 => txdata 0x7fee50063708 mapping
21-03-2017 08:22:36.404 UTC Debug pjsip: tsx0x7fee5002a Sending Response msg
401/REGISTER/cseq=1 (tdta0x7fee50063660) in state Trying
21-03-2017 08:22:36.404 UTC Verbose common_sip_processing.cpp:136: TX 605 bytes
Response msg 401/REGISTER/cseq=1 (tdta0x7fee50063660) to TCP
192.168.0.210:60414:
--start msg--
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60414;received=192.168.0.210;branch=z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---e90943a4cef07621
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
From: <sip:[email protected]>;tag=c84a675a
To:
<sip:[email protected]>;tag=z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv
CSeq: 1 REGISTER
WWW-Authenticate: Digest
realm="ims.cw.4gtss.com",nonce="74aa750f213c9ee2",opaque="6672d0ba0d7b3b36",algorithm=MD5,qop="auth"
Content-Length: 0
--end msg--
21-03-2017 08:22:36.409 UTC Debug pjsip: tsx0x7fee5002a State changed from
Trying to Completed, event=TX_MSG
21-03-2017 08:22:36.409 UTC Debug basicproxy.cpp:213: tsx0x7fee5002a948 -
tu_on_tsx_state UAS, TSX_STATE TX_MSG state=Completed
21-03-2017 08:22:36.409 UTC Verbose sproutletproxy.cpp:1861:
icscf-0x7fee50059fb0 suiciding
21-03-2017 08:22:36.409 UTC Debug sproutletproxy.cpp:1169: Destroying
SproutletWrapper 0x7fee50059e80
21-03-2017 08:22:36.409 UTC Info acr.cpp:690: No CCF or ECF to send ACR for
session HnR-9wnGh4KD9zJ059lKvw.. to - dropping!
21-03-2017 08:22:36.409 UTC Debug acr.cpp:54: Destroyed ACR (0x7fee5002a270)
21-03-2017 08:22:36.409 UTC Debug sproutletproxy.cpp:1178: Free original
request Request msg REGISTER/cseq=1 (tdta0x7fee50026b90) (tdta0x7fee50026b90)
21-03-2017 08:22:36.409 UTC Verbose sproutletproxy.cpp:1861:
authentication-0x7fee5005a990 suiciding
21-03-2017 08:22:36.409 UTC Debug sproutletproxy.cpp:1169: Destroying
SproutletWrapper 0x7fee50019440
21-03-2017 08:22:36.409 UTC Debug sproutletproxy.cpp:1178: Free original
request Request msg REGISTER/cseq=1 (tdta0x7fee50018000) (tdta0x7fee50018000)
21-03-2017 08:22:36.409 UTC Debug pjsip: tdta0x7fee5001 Destroying txdata
Request msg REGISTER/cseq=1 (tdta0x7fee50018000)
21-03-2017 08:22:36.409 UTC Debug thread_dispatcher.cpp:200: Worker thread
completed processing message 0x7fee14078ee8
21-03-2017 08:22:36.409 UTC Debug thread_dispatcher.cpp:206: Request latency =
21665us
21-03-2017 08:22:36.419 UTC Debug pjsip: sip_endpoint.c Processing incoming
message: Request msg REGISTER/cseq=2 (rdata0x7fee1403bad0)
21-03-2017 08:22:36.420 UTC Verbose common_sip_processing.cpp:120: RX 1143
bytes Request msg REGISTER/cseq=2 (rdata0x7fee1403bad0) from TCP
192.168.0.210:60955:
--start msg--
REGISTER sip:ims.cw.4gtss.com:5060;transport=UDP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.210:5058;rport;branch=z9hG4bKPjS7rvJDhkmLD17J-SlQqWj9JY7WZyM0xm
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---a84d41eebb19eac6
Max-Forwards: 70
Contact:
<sip:[email protected]:48520;transport=UDP;rinstance=a86ac72e2fe2b65c>
To: <sip:[email protected]>
From: <sip:[email protected]>;tag=c84a675a
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
CSeq: 2 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.30
Authorization: Digest response="1af58947279ff5b6675f966e26754b8e",
username="[email protected]", realm="ims.cw.4gtss.com",
nonce="74aa750f213c9ee2", uri="sip:ims.cw.4gtss.com:5060;transport=UDP",
algorithm=MD5, cnonce="ab4a76c4cdfc6a814c214b2a6c26bb13",
opaque="6672d0ba0d7b3b36", qop=auth,
nc=00000001,integrity-protected=ip-assoc-pending
Allow-Events: presence, kpml, talk
P-Visited-Network-ID: ims.cw.4gtss.com
Route: <sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig>
Content-Length: 0
--end msg--
21-03-2017 08:22:36.420 UTC Debug pjutils.cpp:1689: Logging SAS Call-ID marker,
Call-ID HnR-9wnGh4KD9zJ059lKvw..
21-03-2017 08:22:36.420 UTC Debug thread_dispatcher.cpp:264: Queuing cloned
received message 0x7fee14078ee8 for worker threads
21-03-2017 08:22:36.420 UTC Debug thread_dispatcher.cpp:150: Worker thread
dequeue message 0x7fee14078ee8
21-03-2017 08:22:36.420 UTC Debug pjsip: sip_endpoint.c Distributing rdata to
modules: Request msg REGISTER/cseq=2 (rdata0x7fee14078ee8)
21-03-2017 08:22:36.420 UTC Debug uri_classifier.cpp:174: home domain: true,
local_to_node: false, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
21-03-2017 08:22:36.420 UTC Debug uri_classifier.cpp:204: Classified URI as 4
21-03-2017 08:22:36.420 UTC Debug basicproxy.cpp:92: Process REGISTER request
21-03-2017 08:22:36.420 UTC Verbose sproutletproxy.cpp:507: Sproutlet Proxy
transaction (0x36e3b40) created
21-03-2017 08:22:36.420 UTC Debug basicproxy.cpp:1298: Report SAS start marker
- trail (184)
21-03-2017 08:22:36.420 UTC Debug pjutils.cpp:699: Cloned Request msg
REGISTER/cseq=2 (rdata0x7fee14078ee8) to tdta0x3724020
21-03-2017 08:22:36.420 UTC Debug pjsip: tsx0x36e48f8 Transaction created for
Request msg REGISTER/cseq=2 (rdata0x7fee14078ee8)
21-03-2017 08:22:36.420 UTC Debug pjsip: tsx0x36e48f8 Incoming Request msg
REGISTER/cseq=2 (rdata0x7fee14078ee8) in state Null
21-03-2017 08:22:36.420 UTC Debug pjsip: tsx0x36e48f8 State changed from Null
to Trying, event=RX_MSG
21-03-2017 08:22:36.420 UTC Debug basicproxy.cpp:213: tsx0x36e48f8 -
tu_on_tsx_state UAS, TSX_STATE RX_MSG state=Trying
21-03-2017 08:22:36.420 UTC Debug pjsip: endpoint Response msg
408/REGISTER/cseq=2 (tdta0x36dd4f0) created
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:119: Find target Sproutlet
for request
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:154: Found next routable
URI: sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:289: Possible service name
icscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:293: Adding possible
service name icscf based on domain
21-03-2017 08:22:36.420 UTC Verbose sproutletproxy.cpp:1163: Created Sproutlet
icscf-0x2726120 for Request msg REGISTER/cseq=2 (tdta0x3724020)
21-03-2017 08:22:36.420 UTC Verbose sproutletproxy.cpp:2095: Routing Request
msg REGISTER/cseq=2 (tdta0x3724020) (1172 bytes) to downstream sproutlet icscf:
--start msg--
REGISTER sip:ims.cw.4gtss.com:5060;transport=UDP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60955;received=192.168.0.210;branch=z9hG4bKPjS7rvJDhkmLD17J-SlQqWj9JY7WZyM0xm
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---a84d41eebb19eac6
Max-Forwards: 70
Contact:
<sip:[email protected]:48520;transport=UDP;rinstance=a86ac72e2fe2b65c>
To: <sip:[email protected]>
From: <sip:[email protected]>;tag=c84a675a
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
CSeq: 2 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.30
Authorization: Digest response="1af58947279ff5b6675f966e26754b8e",
username="[email protected]", realm="ims.cw.4gtss.com",
nonce="74aa750f213c9ee2", uri="sip:ims.cw.4gtss.com:5060;transport=UDP",
algorithm=MD5, cnonce="ab4a76c4cdfc6a814c214b2a6c26bb13",
opaque="6672d0ba0d7b3b36", qop=auth,
nc=00000001,integrity-protected=ip-assoc-pending
Allow-Events: presence, kpml, talk
P-Visited-Network-ID: ims.cw.4gtss.com
Route: <sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig>
Content-Length: 0
--end msg--
21-03-2017 08:22:36.420 UTC Debug pjutils.cpp:716: Cloned tdta0x3724020 to
tdta0x3741fc0
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:1224: Remove top Route
header Route: <sip:icscf.sprout.ims.cw.4gtss.com:5052;transport=TCP;lr;orig>
21-03-2017 08:22:36.420 UTC Debug sproutletproxy.cpp:1768: Adding message
0x37425d0 => txdata 0x3742068 mapping
21-03-2017 08:22:36.420 UTC Verbose sproutletproxy.cpp:1613: icscf-0x2726120
pass initial request Request msg REGISTER/cseq=2 (tdta0x3741fc0) to Sproutlet
21-03-2017 08:22:36.420 UTC Debug acr.cpp:1812: Create RalfACR for node type
I-CSCF with role Terminating
21-03-2017 08:22:36.420 UTC Debug acr.cpp:49: Created ACR (0x36e0ac0)
21-03-2017 08:22:36.420 UTC Debug acr.cpp:189: Created I-CSCF Ralf ACR
21-03-2017 08:22:36.420 UTC Debug acr.cpp:269: Set record type for I-CSCF,
BGCF, IBCF, AS to EVENT_RECORD
21-03-2017 08:22:36.420 UTC Debug icscfsproutlet.cpp:193: I-CSCF initialize
transaction for REGISTER request
21-03-2017 08:22:36.421 UTC Debug icscfrouter.cpp:345: Perform UAR - impi
[email protected], impu sip:[email protected], vn
ims.cw.4gtss.com, auth_type REG
21-03-2017 08:22:36.421 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host hs.ims.cw.4gtss.com, port 8888, family 2
21-03-2017 08:22:36.421 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.421 UTC Verbose dnscachedresolver.cpp:486: Check cache for
hs.ims.cw.4gtss.com type 1
21-03-2017 08:22:36.421 UTC Debug dnscachedresolver.cpp:588: Pulling 1 records
from cache for hs.ims.cw.4gtss.com A
21-03-2017 08:22:36.421 UTC Debug baseresolver.cpp:366: Found 1 A/AAAA records,
creating iterator
21-03-2017 08:22:36.421 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.421 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.421 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.421 UTC Debug baseresolver.cpp:1004: Added a whitelisted
server, now have 1 of 1
21-03-2017 08:22:36.421 UTC Debug connection_pool.h:231: Request for connection
to IP: 192.168.0.212, port: 8888
21-03-2017 08:22:36.421 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee04023ac0 in pool
21-03-2017 08:22:36.425 UTC Debug httpclient.cpp:478: Set CURLOPT_RESOLVE:
hs.ims.cw.4gtss.com:8888:192.168.0.212
21-03-2017 08:22:36.425 UTC Debug httpclient.cpp:505: Sending HTTP request :
http://hs.ims.cw.4gtss.com:8888/impi/6505550579%40ims.cw.4gtss.com/registration-status?impu=sip%3A6505550579%40ims.cw.4gtss.com&visited-network=ims.cw.4gtss.com&auth-type=REG
(trying 192.168.0.212)
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:832: Received header
http/1.1200ok with value
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0350
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:832: Received header
content-length with value 83
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0350
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:832: Received header
content-type with value text/plain
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0350
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:832: Received header with
value
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0350
21-03-2017 08:22:36.426 UTC Debug httpclient.cpp:538: Received HTTP response:
status=200,
doc={"result-code":2001,"scscf":"sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP"}
21-03-2017 08:22:36.426 UTC Debug baseresolver.cpp:830: Successful response
from 192.168.0.212:8888 transport 6
21-03-2017 08:22:36.426 UTC Debug connection_pool.h:267: Release connection to
IP: 192.168.0.212, port: 8888 to pool
21-03-2017 08:22:36.426 UTC Debug icscfrouter.cpp:237: HSS returned S-CSCF
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP as target
21-03-2017 08:22:36.426 UTC Debug acr.cpp:653: Storing Server-Capabilities
21-03-2017 08:22:36.426 UTC Debug icscfrouter.cpp:113: SCSCF specified by HSS:
sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 08:22:36.426 UTC Debug uri_classifier.cpp:174: home domain: false,
local_to_node: true, is_gruu: false, enforce_user_phone: false, prefer_sip:
true, treat_number_as_phone: false
21-03-2017 08:22:36.426 UTC Debug uri_classifier.cpp:204: Classified URI as 3
21-03-2017 08:22:36.426 UTC Debug icscfsproutlet.cpp:279: Found SCSCF for
REGISTER
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:1364: Sproutlet
send_request 0x37425d0
21-03-2017 08:22:36.426 UTC Verbose sproutletproxy.cpp:1400: icscf-0x2726120
sending Request msg REGISTER/cseq=2 (tdta0x3741fc0) on fork 0
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:1783: Processing actions
from sproutlet - 0 responses, 1 requests, 0 timers
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:1823: Processing request
0x3742068, fork = 0
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:1947: icscf-0x2726120
transmitting request on fork 0
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:1961: icscf-0x2726120
store reference to non-ACK request Request msg REGISTER/cseq=2 (tdta0x3741fc0)
on fork 0
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:1775: Removing message
0x37425d0 => txdata 0x3742068 mapping
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:119: Find target Sproutlet
for request
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:154: Found next routable
URI: sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:289: Possible service name
scscf will be used if sprout.ims.cw.4gtss.com is a local hostname
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:293: Adding possible
service name scscf based on domain
21-03-2017 08:22:36.426 UTC Verbose sproutletproxy.cpp:1163: Created Sproutlet
authentication-0x36e5590 for Request msg REGISTER/cseq=2 (tdta0x3741fc0)
21-03-2017 08:22:36.426 UTC Verbose sproutletproxy.cpp:2095: Routing Request
msg REGISTER/cseq=2 (tdta0x3741fc0) (1114 bytes) to downstream sproutlet
authentication:
--start msg--
REGISTER sip:scscf.sprout.ims.cw.4gtss.com:5054;transport=TCP SIP/2.0
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60955;received=192.168.0.210;branch=z9hG4bKPjS7rvJDhkmLD17J-SlQqWj9JY7WZyM0xm
Path: <sip:[email protected]:5058;transport=TCP;lr;ob>
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---a84d41eebb19eac6
Max-Forwards: 69
Contact:
<sip:[email protected]:48520;transport=UDP;rinstance=a86ac72e2fe2b65c>
To: <sip:[email protected]>
From: <sip:[email protected]>;tag=c84a675a
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
CSeq: 2 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.30
Authorization: Digest response="1af58947279ff5b6675f966e26754b8e",
username="[email protected]", realm="ims.cw.4gtss.com",
nonce="74aa750f213c9ee2", uri="sip:ims.cw.4gtss.com:5060;transport=UDP",
algorithm=MD5, cnonce="ab4a76c4cdfc6a814c214b2a6c26bb13",
opaque="6672d0ba0d7b3b36", qop=auth,
nc=00000001,integrity-protected=ip-assoc-pending
Allow-Events: presence, kpml, talk
P-Visited-Network-ID: ims.cw.4gtss.com
Content-Length: 0
--end msg--
21-03-2017 08:22:36.426 UTC Debug pjutils.cpp:716: Cloned tdta0x3741fc0 to
tdta0x36efd70
21-03-2017 08:22:36.426 UTC Debug sproutletproxy.cpp:1768: Adding message
0x36f0380 => txdata 0x36efe18 mapping
21-03-2017 08:22:36.426 UTC Verbose sproutletproxy.cpp:1613:
authentication-0x36e5590 pass initial request Request msg REGISTER/cseq=2
(tdta0x36efd70) to Sproutlet
21-03-2017 08:22:36.427 UTC Debug authenticationsproutlet.cpp:829:
Authentication module invoked
21-03-2017 08:22:36.427 UTC Debug authenticationsproutlet.cpp:748:
Authorization header in request
21-03-2017 08:22:36.427 UTC Debug authenticationsproutlet.cpp:756: Integrity
protected with ip-assoc-pending
21-03-2017 08:22:36.427 UTC Debug authenticationsproutlet.cpp:841: Request
needs authentication
21-03-2017 08:22:36.427 UTC Debug memcachedstore.cpp:1128: Start GET from table
impi for key [email protected]
21-03-2017 08:22:36.427 UTC Debug astaire_resolver.cpp:72:
AstaireResolver::resolve for host sprout.ims.cw.4gtss.com, family 2
21-03-2017 08:22:36.427 UTC Debug utils.cpp:352: Malformed host/port
sprout.ims.cw.4gtss.com
21-03-2017 08:22:36.427 UTC Debug baseresolver.cpp:425: Attempt to parse
sprout.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.427 UTC Verbose dnscachedresolver.cpp:486: Check cache for
sprout.ims.cw.4gtss.com type 1
21-03-2017 08:22:36.427 UTC Debug dnscachedresolver.cpp:588: Pulling 1 records
from cache for sprout.ims.cw.4gtss.com A
21-03-2017 08:22:36.427 UTC Debug baseresolver.cpp:366: Found 1 A/AAAA records,
creating iterator
21-03-2017 08:22:36.427 UTC Debug baseresolver.cpp:819: 192.168.0.212:11311
transport 6 has state: WHITE
21-03-2017 08:22:36.427 UTC Debug baseresolver.cpp:819: 192.168.0.212:11311
transport 6 has state: WHITE
21-03-2017 08:22:36.427 UTC Debug baseresolver.cpp:1004: Added a whitelisted
server, now have 1 of 2
21-03-2017 08:22:36.427 UTC Debug memcachedstore.cpp:1469: Found 1 targets for
sprout.ims.cw.4gtss.com
21-03-2017 08:22:36.427 UTC Debug memcachedstore.cpp:1494: Duplicate target
IP=192.168.0.212, port= 11311 as it is the only target
21-03-2017 08:22:36.427 UTC Debug memcachedstore.cpp:1082: Try server IP
192.168.0.212, port 11311
21-03-2017 08:22:36.427 UTC Debug connection_pool.h:231: Request for connection
to IP: 192.168.0.212, port: 11311
21-03-2017 08:22:36.427 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee0403fb90 in pool
21-03-2017 08:22:36.428 UTC Debug memcachedstore.cpp:107: Fetch result
21-03-2017 08:22:36.428 UTC Debug memcachedstore.cpp:115: Found record on
replica
21-03-2017 08:22:36.428 UTC Debug memcachedstore.cpp:1093: libmemcached
returned 0
21-03-2017 08:22:36.428 UTC Debug connection_pool.h:267: Release connection to
IP: 192.168.0.212, port: 11311 to pool
21-03-2017 08:22:36.428 UTC Debug memcachedstore.cpp:1172: Read 653 bytes from
table impi key [email protected], CAS = 105
21-03-2017 08:22:36.428 UTC Debug impistore.cpp:783: Retrieved IMPI for
[email protected]
{"authChallenges":[{"type":"digest","nonce":"7651f6915ec1a1a3","nc":1,"expires":1490084591,"correlator":"z9hG4bKPjt.RUpwKN0ZmRpvwMT1uo0uP7E2.k3mmT","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"},{"type":"digest","nonce":"3f0f42671ad0e902","nc":1,"expires":1490084593,"correlator":"z9hG4bKPj2AIRW02l3PcY2jz.sbXZd5wO-0TIRhDK","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"},{"type":"digest","nonce":"74aa750f213c9ee2","nc":1,"expires":1490084596,"correlator":"z9hG4bKPjIDVhvlY1flniuKKiX46I4TY4BGhG04bv","realm":"ims.cw.4gtss.com","qop":"auth","ha1":"f9490a60de145aed870afb6facbc1894"}]}
21-03-2017 08:22:36.428 UTC Debug authenticationsproutlet.cpp:961: Verify
authentication information in request
21-03-2017 08:22:36.428 UTC Debug authenticationsproutlet.cpp:343: Found Digest
HA1 = f9490a60de145aed870afb6facbc1894
21-03-2017 08:22:36.428 UTC Debug acr.cpp:1812: Create RalfACR for node type
S-CSCF with role Originating
21-03-2017 08:22:36.428 UTC Debug acr.cpp:49: Created ACR (0x3702a70)
21-03-2017 08:22:36.428 UTC Debug acr.cpp:189: Created S-CSCF Ralf ACR
21-03-2017 08:22:36.428 UTC Debug acr.cpp:229: Set record type for P/S-CSCF
21-03-2017 08:22:36.428 UTC Debug acr.cpp:237: Non-dialog message =>
EVENT_RECORD
21-03-2017 08:22:36.428 UTC Debug acr.cpp:1540: Stored 0 subscription
identifiers
21-03-2017 08:22:36.428 UTC Error authenticationsproutlet.cpp:1168:
Authentication failed, Invalid authorization digest (PJSIP_EAUTHINVALIDDIGEST)
21-03-2017 08:22:36.428 UTC Debug pjutils.cpp:417: Private identity from
authorization header = [email protected]
21-03-2017 08:22:36.428 UTC Debug hssconnection.cpp:614: Making Homestead
request for
/impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data?private_id=6505550579%40ims.cw.4gtss.com
21-03-2017 08:22:36.428 UTC Debug a_record_resolver.cpp:80:
ARecordResolver::resolve_iter for host hs.ims.cw.4gtss.com, port 8888, family 2
21-03-2017 08:22:36.428 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.428 UTC Verbose dnscachedresolver.cpp:486: Check cache for
hs.ims.cw.4gtss.com type 1
21-03-2017 08:22:36.428 UTC Debug dnscachedresolver.cpp:588: Pulling 1 records
from cache for hs.ims.cw.4gtss.com A
21-03-2017 08:22:36.428 UTC Debug baseresolver.cpp:366: Found 1 A/AAAA records,
creating iterator
21-03-2017 08:22:36.428 UTC Debug baseresolver.cpp:425: Attempt to parse
hs.ims.cw.4gtss.com as IP address
21-03-2017 08:22:36.428 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.428 UTC Debug baseresolver.cpp:819: 192.168.0.212:8888
transport 6 has state: WHITE
21-03-2017 08:22:36.428 UTC Debug baseresolver.cpp:1004: Added a whitelisted
server, now have 1 of 1
21-03-2017 08:22:36.428 UTC Debug connection_pool.h:231: Request for connection
to IP: 192.168.0.212, port: 8888
21-03-2017 08:22:36.428 UTC Debug connection_pool.h:244: Found existing
connection 0x7fee04023ac0 in pool
21-03-2017 08:22:36.428 UTC Debug httpclient.cpp:478: Set CURLOPT_RESOLVE:
hs.ims.cw.4gtss.com:8888:192.168.0.212
21-03-2017 08:22:36.428 UTC Debug httpclient.cpp:505: Sending HTTP request :
http://hs.ims.cw.4gtss.com:8888/impu/sip%3A6505550579%40ims.cw.4gtss.com/reg-data?private_id=6505550579%40ims.cw.4gtss.com
(trying 192.168.0.212)
21-03-2017 08:22:36.430 UTC Debug pjsip: tsx0x7fee5002a Timeout timer event
21-03-2017 08:22:36.430 UTC Debug pjsip: tsx0x7fee5002a State changed from
Completed to Terminated, event=TIMER
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:213: tsx0x7fee5002a948 -
tu_on_tsx_state UAS, TSX_STATE TIMER state=Terminated
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:1308: Report SAS end marker -
trail (183)
21-03-2017 08:22:36.430 UTC Debug pjsip: tsx0x7fee5002a Timeout timer event
21-03-2017 08:22:36.430 UTC Debug pjsip: tsx0x7fee5002a State changed from
Terminated to Destroyed, event=TIMER
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:213: tsx0x7fee5002a948 -
tu_on_tsx_state UAS, TSX_STATE TIMER state=Destroyed
21-03-2017 08:22:36.430 UTC Debug sproutletproxy.cpp:750: tsx0x7fee5002a948 -
UAS tsx destroyed
21-03-2017 08:22:36.430 UTC Debug sproutletproxy.cpp:1090: Safe for UASTsx to
suicide
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:1483: Transaction ((nil))
suiciding
21-03-2017 08:22:36.430 UTC Verbose sproutletproxy.cpp:538: Sproutlet Proxy
transaction (0x7fee5005a0c0) destroyed
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:494: BasicProxy::UASTsx
destructor (0x7fee5005a0c0)
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:511: Disconnect UAC
transactions from UAS transaction
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:525: Free original request
21-03-2017 08:22:36.430 UTC Debug pjsip: tdta0x7fee5002 Destroying txdata
Request msg REGISTER/cseq=1 (tdta0x7fee50026b90)
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:534: Free un-used best response
21-03-2017 08:22:36.430 UTC Debug pjsip: tdta0x7fee5002 Destroying txdata
Response msg 408/REGISTER/cseq=1 (tdta0x7fee50029260)
21-03-2017 08:22:36.430 UTC Debug basicproxy.cpp:555: BasicProxy::UASTsx
destructor completed
21-03-2017 08:22:36.430 UTC Debug pjsip: tdta0x7fee5006 Destroying txdata
Response msg 401/REGISTER/cseq=1 (tdta0x7fee50063660)
21-03-2017 08:22:36.430 UTC Debug pjsip: tsx0x7fee5002a Transaction destroyed!
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:832: Received header
http/1.1200ok with value
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0540
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:832: Received header
content-length with value 825
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0540
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:832: Received header
content-type with value text/plain
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0540
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:832: Received header with
value
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:833: Header pointer:
0x7fee20fc0540
21-03-2017 08:22:36.430 UTC Debug httpclient.cpp:538: Received HTTP response:
status=200, doc=<ClearwaterRegData>
<RegistrationState>UNREGISTERED</RegistrationState>
<IMSSubscription xsi="http://www.w3.org/2001/XMLSchema-instance";
noNamespaceSchemaLocation="CxDataType.xsd">
<PrivateID>Unspecified</PrivateID>
<ServiceProfile>
<InitialFilterCriteria>
<TriggerPoint>
<ConditionTypeCNF>0</ConditionTypeCNF>
<SPT>
<ConditionNegated>0</ConditionNegated>
<Group>0</Group>
<Method>INVITE</Method>
<Extension/>
</SPT>
</TriggerPoint>
<ApplicationServer>
<ServerName>sip:mmtel.ims.cw.4gtss.com</ServerName>
<DefaultHandling>0</DefaultHandling>
</ApplicationServer>
</InitialFilterCriteria>
<PublicIdentity>
<Identity>sip:[email protected]</Identity>
</PublicIdentity>
</ServiceProfile>
</IMSSubscription>
</ClearwaterRegData>
21-03-2017 08:22:36.430 UTC Debug baseresolver.cpp:830: Successful response
from 192.168.0.212:8888 transport 6
21-03-2017 08:22:36.430 UTC Debug connection_pool.h:267: Release connection to
IP: 192.168.0.212, port: 8888 to pool
21-03-2017 08:22:36.430 UTC Debug hssconnection.cpp:372: Processing Identity
node from HSS XML - sip:[email protected]
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1768: Adding message
0x3747820 => txdata 0x37472b8 mapping
21-03-2017 08:22:36.431 UTC Info acr.cpp:690: No CCF or ECF to send ACR for
session HnR-9wnGh4KD9zJ059lKvw.. to - dropping!
21-03-2017 08:22:36.431 UTC Verbose sproutletproxy.cpp:1427:
authentication-0x36e5590 sending Response msg 403/REGISTER/cseq=2
(tdta0x3747210)
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1775: Removing message
0x36f0380 => txdata 0x36efe18 mapping
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1504: Free message
tdta0x36efd70
21-03-2017 08:22:36.431 UTC Debug pjsip: tdta0x36efd70 Destroying txdata
Request msg REGISTER/cseq=2 (tdta0x36efd70)
21-03-2017 08:22:36.431 UTC Debug acr.cpp:54: Destroyed ACR (0x3702a70)
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1783: Processing actions
from sproutlet - 1 responses, 0 requests, 0 timers
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1869: Aggregating response
with status code 403
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1919: 3xx/4xx/5xx/6xx
response
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1923: Best 3xx/4xx/5xx/6xx
response so far
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1810: All UAC responded
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1775: Removing message
0x3747820 => txdata 0x37472b8 mapping
21-03-2017 08:22:36.431 UTC Verbose sproutletproxy.cpp:2095: Routing Response
msg 403/REGISTER/cseq=2 (tdta0x3747210) (474 bytes) to upstream sproutlet icscf:
--start msg--
SIP/2.0 403 Forbidden
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60955;received=192.168.0.210;branch=z9hG4bKPjS7rvJDhkmLD17J-SlQqWj9JY7WZyM0xm
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---a84d41eebb19eac6
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
From: <sip:[email protected]>;tag=c84a675a
To:
<sip:[email protected]>;tag=z9hG4bKPjS7rvJDhkmLD17J-SlQqWj9JY7WZyM0xm
CSeq: 2 REGISTER
Content-Length: 0
--end msg--
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1768: Adding message
0x3747820 => txdata 0x37472b8 mapping
21-03-2017 08:22:36.431 UTC Verbose sproutletproxy.cpp:1666: icscf-0x2726120
received final response Response msg 403/REGISTER/cseq=2 (tdta0x3747210) on
fork 0, state = Terminated
21-03-2017 08:22:36.431 UTC Debug acr.cpp:1540: Stored 1 subscription
identifiers
21-03-2017 08:22:36.431 UTC Debug icscfsproutlet.cpp:329: Check retry
conditions for REGISTER, status = 403, S-CSCF responsive
21-03-2017 08:22:36.431 UTC Verbose sproutletproxy.cpp:1427: icscf-0x2726120
sending Response msg 403/REGISTER/cseq=2 (tdta0x3747210)
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1783: Processing actions
from sproutlet - 1 responses, 0 requests, 0 timers
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1869: Aggregating response
with status code 403
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1919: 3xx/4xx/5xx/6xx
response
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1923: Best 3xx/4xx/5xx/6xx
response so far
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1810: All UAC responded
21-03-2017 08:22:36.431 UTC Debug sproutletproxy.cpp:1775: Removing message
0x3747820 => txdata 0x37472b8 mapping
21-03-2017 08:22:36.431 UTC Debug pjsip: tsx0x36e48f8 Sending Response msg
403/REGISTER/cseq=2 (tdta0x3747210) in state Trying
21-03-2017 08:22:36.431 UTC Verbose common_sip_processing.cpp:136: TX 474 bytes
Response msg 403/REGISTER/cseq=2 (tdta0x3747210) to TCP 192.168.0.210:60955:
--start msg--
SIP/2.0 403 Forbidden
Via: SIP/2.0/TCP
192.168.0.210:5058;rport=60955;received=192.168.0.210;branch=z9hG4bKPjS7rvJDhkmLD17J-SlQqWj9JY7WZyM0xm
Via: SIP/2.0/UDP
192.168.0.240:48520;rport=48520;received=192.168.0.240;branch=z9hG4bK-524287-1---a84d41eebb19eac6
Call-ID: HnR-9wnGh4KD9zJ059lKvw..
From: <sip:[email protected]>;tag=c84a675a
To:
<sip:[email protected]>;tag=z9hG4bKPjS7rvJDhkmLD17J-SlQqWj9JY7WZyM0xm
CSeq: 2 REGISTER
Content-Length: 0
--end msg--
21-03-2017 08:22:36.436 UTC Debug pjsip: tsx0x36e48f8 State changed from
Trying to Completed, event=TX_MSG
21-03-2017 08:22:36.436 UTC Debug basicproxy.cpp:213: tsx0x36e48f8 -
tu_on_tsx_state UAS, TSX_STATE TX_MSG state=Completed
21-03-2017 08:22:36.436 UTC Verbose sproutletproxy.cpp:1861: icscf-0x2726120
suiciding
21-03-2017 08:22:36.436 UTC Debug sproutletproxy.cpp:1169: Destroying
SproutletWrapper 0x2725fc0
21-03-2017 08:22:36.436 UTC Info acr.cpp:690: No CCF or ECF to send ACR for
session HnR-9wnGh4KD9zJ059lKvw.. to - dropping!
21-03-2017 08:22:36.436 UTC Debug acr.cpp:54: Destroyed ACR (0x36e0ac0)
21-03-2017 08:22:36.436 UTC Debug sproutletproxy.cpp:1178: Free original
request Request msg REGISTER/cseq=2 (tdta0x3724020) (tdta0x3724020)
21-03-2017 08:22:36.436 UTC Verbose sproutletproxy.cpp:1861:
authentication-0x36e5590 suiciding
21-03-2017 08:22:36.436 UTC Debug sproutletproxy.cpp:1169: Destroying
SproutletWrapper 0x36e5230
21-03-2017 08:22:36.436 UTC Debug sproutletproxy.cpp:1178: Free original
request Request msg REGISTER/cseq=2 (tdta0x3741fc0) (tdta0x3741fc0)
21-03-2017 08:22:36.436 UTC Debug pjsip: tdta0x3741fc0 Destroying txdata
Request msg REGISTER/cseq=2 (tdta0x3741fc0)
21-03-2017 08:22:36.436 UTC Debug thread_dispatcher.cpp:200: Worker thread
completed processing message 0x7fee14078ee8
21-03-2017 08:22:36.436 UTC Debug thread_dispatcher.cpp:206: Request latency =
16533us
21-03-2017 08:22:36.440 UTC Debug pjsip: tsx0x36e48f8 Timeout timer event
21-03-2017 08:22:36.440 UTC Debug pjsip: tsx0x36e48f8 State changed from
Completed to Terminated, event=TIMER
21-03-2017 08:22:36.440 UTC Debug basicproxy.cpp:213: tsx0x36e48f8 -
tu_on_tsx_state UAS, TSX_STATE TIMER state=Terminated
21-03-2017 08:22:36.440 UTC Debug basicproxy.cpp:1308: Report SAS end marker -
trail (184)
21-03-2017 08:22:36.440 UTC Debug pjsip: tsx0x36e48f8 Timeout timer event
21-03-2017 08:22:36.441 UTC Debug pjsip: tsx0x36e48f8 State changed from
Terminated to Destroyed, event=TIMER
21-03-2017 08:22:36.441 UTC Debug basicproxy.cpp:213: tsx0x36e48f8 -
tu_on_tsx_state UAS, TSX_STATE TIMER state=Destroyed
21-03-2017 08:22:36.441 UTC Debug sproutletproxy.cpp:750: tsx0x36e48f8 - UAS
tsx destroyed
21-03-2017 08:22:36.441 UTC Debug sproutletproxy.cpp:1090: Safe for UASTsx to
suicide
21-03-2017 08:22:36.441 UTC Debug basicproxy.cpp:1483: Transaction ((nil))
suiciding
21-03-2017 08:22:36.441 UTC Verbose sproutletproxy.cpp:538: Sproutlet Proxy
transaction (0x36e3b40) destroyed
21-03-2017 08:22:36.441 UTC Debug basicproxy.cpp:494: BasicProxy::UASTsx
destructor (0x36e3b40)
21-03-2017 08:22:36.441 UTC Debug basicproxy.cpp:511: Disconnect UAC
transactions from UAS transaction
21-03-2017 08:22:36.441 UTC Debug basicproxy.cpp:525: Free original request
21-03-2017 08:22:36.441 UTC Debug pjsip: tdta0x3724020 Destroying txdata
Request msg REGISTER/cseq=2 (tdta0x3724020)
21-03-2017 08:22:36.441 UTC Debug basicproxy.cpp:534: Free un-used best response
21-03-2017 08:22:36.441 UTC Debug pjsip: tdta0x36dd4f0 Destroying txdata
Response msg 408/REGISTER/cseq=2 (tdta0x36dd4f0)
21-03-2017 08:22:36.441 UTC Debug basicproxy.cpp:555: BasicProxy::UASTsx
destructor completed
21-03-2017 08:22:36.441 UTC Debug pjsip: tdta0x3747210 Destroying txdata
Response msg 403/REGISTER/cseq=2 (tdta0x3747210)
21-03-2017 08:22:36.441 UTC Debug pjsip: tsx0x36e48f8 Transaction destroyed!
_______________________________________________
Clearwater mailing list
[email protected]
http://lists.projectclearwater.org/mailman/listinfo/clearwater_lists.projectclearwater.org
