Seems like I am getting slightly smarter! Wireshark is showing me that
digest authentication is flowing between nodes and my client correctly. On
the second put from sprout to homestead, homestead is responding 200OK but
with:
registrationsState as unregistered as seen via wireshark.
What do I need to look for as to pinpoint this issue? I have checked my impi
table and the user exists. Here are my homestead logs for the registration.
There is nothing in here that is obvious as to where I should be looking.
03-04-2016 15:33:24.833 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 15:33:24.833 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 15:33:28.635 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 15:33:28.635 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 15:33:28.635 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 15:33:28.635 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 15:33:28.635 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 15:33:28.635 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 15:33:28.635 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 15:33:28.636 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 15:33:28.638 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 1, failures 0
03-04-2016 15:33:28.638 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 15:33:28.638 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 15:33:28.674 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3A6505550435%40verified/reg-data, args
private_id=6505550435%40verified
03-04-2016 15:33:28.674 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified
03-04-2016 15:33:28.674 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "dereg-auth-failed"}'
03-04-2016 15:33:28.674 UTC Debug handlers.cpp:1042: New value of _type is 6
03-04-2016 15:33:28.674 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 15:33:28.674 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 15:33:28.674 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459697608674749
03-04-2016 15:33:28.674 UTC Debug cache.cpp:347: Issuing get for key
sip:6505550435@verified
03-04-2016 15:33:28.675 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 15:33:28.677 UTC Debug cache.cpp:367: 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.verified</ServerName><DefaultHandling>0</DefaultHandling></ApplicationServer></InitialFilterCriteria><PublicIdentity><Identity>sip:6505550435@verified
</Identity></PublicIdentity></ServiceProfile></IMSSubscription>
03-04-2016 15:33:28.677 UTC Debug cache.cpp:435: Found stored XML for
subscriber, treating as UNREGISTERED state
03-04-2016 15:33:28.677 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 15:33:28.677 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is not empty, registration state is
UNREGISTERED, and the charging addresses are empty
03-04-2016 15:33:28.677 UTC Debug handlers.cpp:1163: Subscriber registering
with new binding
03-04-2016 15:33:28.677 UTC Debug handlers.cpp:1396: Handling
authentication failure/timeout
03-04-2016 15:33:28.677 UTC Debug handlers.cpp:1443: Sending 200 response
(body was {"reqtype": "dereg-auth-failed"})
03-04-2016 15:33:28.677 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impu/sip%3A6505550435%40verified/reg-data, args
private_id=6505550435%40verified
03-04-2016 15:33:28.695 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 15:33:34.278 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 15:33:34.278 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 15:33:43.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 15:33:43.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 15:33:43.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 15:33:43.707 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 15:33:44.291 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 15:33:44.291 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 15:33:54.816 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 15:33:54.816 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
_______________________________________________
Clearwater mailing list
[email protected]
http://lists.projectclearwater.org/mailman/listinfo/clearwater_lists.projectclearwater.org
