Seems as though I had an extra space at the end of the PW field for my
subscriber. Now we are down to these logs on homestead. I get the same
digest flow OK, but now a 404 back from Homestead.
03-04-2016 17:00:00.631 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:00:00.631 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:00:00.631 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:00:00.631 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:00:00.631 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:00:00.631 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:00:00.631 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:00:00.632 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:00:00.634 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 8, failures 0
03-04-2016 17:00:00.634 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:00:00.634 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:00:00.634 UTC Info load_monitor.cpp:212: Accepted 100.000000%
of requests, latency error = -0.970250, overload responses = 0
03-04-2016 17:00:00.634 UTC Status load_monitor.cpp:260: Maximum incoming
request rate/second unchanged - only handled 631 requests in last
6136209ms, minimum threshold for a change is 306810.468750
03-04-2016 17:00:00.660 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:00:00.660 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:00:00.660 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:00:00.660 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:00:00.660 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:00:00.661 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:00:00.661 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702800661035
03-04-2016 17:00:00.661 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:00:00.661 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:00:00.663 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:00:00.663 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:00:00.663 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:00:00.663 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:00:02.754 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:00:03.278 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:00:03.278 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:00:13.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:00:13.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:00:13.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:00:13.783 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:00:13.783 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:00:17.767 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:00:23.349 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:00:23.349 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:00:32.764 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:00:32.764 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:00:32.764 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:00:32.764 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:00:32.765 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:00:32.765 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:00:32.765 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:00:32.766 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:00:32.768 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 2, failures 0
03-04-2016 17:00:32.768 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:00:32.768 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:00:32.782 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:00:32.787 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:00:32.787 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:00:32.787 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:00:32.787 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:00:32.787 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:00:32.787 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:00:32.787 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702832787383
03-04-2016 17:00:32.787 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:00:32.787 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:00:32.789 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:00:32.789 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:00:32.789 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:00:32.789 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:00:33.371 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:00:33.371 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:00:43.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:00:43.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:00:43.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:00:44.109 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:00:44.109 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:00:47.793 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:00:53.467 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:00:53.468 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:01:02.807 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:01:03.492 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:01:03.492 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:01:04.885 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:01:04.885 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:01:04.885 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:01:04.885 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:04.885 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:01:04.885 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:01:04.885 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:01:04.887 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:01:04.888 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 2, failures 0
03-04-2016 17:01:04.888 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:01:04.888 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:01:04.915 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:04.915 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:01:04.915 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:01:04.915 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:01:04.915 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:01:04.915 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:04.915 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702864915567
03-04-2016 17:01:04.915 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:01:04.916 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:01:04.924 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:01:04.924 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:01:04.924 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:01:04.924 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:12.048 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:01:12.048 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:01:12.048 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:01:12.048 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:12.048 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:01:12.048 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:01:12.048 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:01:12.049 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:01:12.051 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:01:12.051 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:01:12.067 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:12.067 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:01:12.067 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:01:12.067 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:01:12.067 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:01:12.068 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:12.068 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702872068023
03-04-2016 17:01:12.068 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:01:12.069 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:01:12.070 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:01:12.070 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:01:12.070 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:01:12.070 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:13.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:01:13.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:01:13.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:01:14.009 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:01:14.009 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:01:17.822 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:01:23.577 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:01:23.577 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:01:32.837 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:01:33.604 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:01:33.604 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:01:37.020 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:01:37.020 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:01:37.020 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:01:37.020 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:37.020 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:01:37.020 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:01:37.020 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:01:37.021 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:01:37.022 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 4, failures 0
03-04-2016 17:01:37.022 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:01:37.022 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:01:37.041 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:37.041 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:01:37.041 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:01:37.041 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:01:37.041 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:01:37.041 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:37.041 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702897041291
03-04-2016 17:01:37.041 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:01:37.041 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:01:37.042 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:01:37.042 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:01:37.042 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:01:37.042 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:43.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:01:43.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:01:43.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:01:44.169 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:01:44.170 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:01:44.170 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:01:44.170 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:44.170 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:01:44.170 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:01:44.170 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:01:44.171 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:01:44.172 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:01:44.172 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:01:44.220 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:01:44.220 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:01:44.250 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:44.250 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:01:44.250 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:01:44.250 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:01:44.250 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:01:44.250 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:01:44.250 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702904250150
03-04-2016 17:01:44.250 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:01:44.250 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:01:44.252 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:01:44.252 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:01:44.252 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:01:44.252 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:01:47.841 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:01:53.668 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:01:53.668 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:02:02.854 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:02:03.713 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:02:03.713 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:02:09.137 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:02:09.137 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:02:09.137 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:02:09.137 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:09.137 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:02:09.137 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:02:09.137 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:02:09.138 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:02:09.140 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 4, failures 0
03-04-2016 17:02:09.140 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:02:09.140 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:02:09.158 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:09.158 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:02:09.158 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:02:09.158 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:02:09.158 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:02:09.158 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:09.158 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702929158837
03-04-2016 17:02:09.158 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:02:09.159 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:02:09.160 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:02:09.160 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:02:09.160 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:02:09.160 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:13.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:02:13.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:02:13.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:02:14.332 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:02:14.332 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:02:16.339 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:02:16.339 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:02:16.339 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:02:16.339 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:16.339 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:02:16.339 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:02:16.339 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:02:16.340 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:02:16.342 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:02:16.342 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:02:16.360 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:16.360 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:02:16.360 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:02:16.360 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:02:16.360 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:02:16.361 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:16.361 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702936361053
03-04-2016 17:02:16.361 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:02:16.361 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:02:16.363 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:02:16.363 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:02:16.363 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:02:16.363 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:17.866 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:02:23.772 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:02:23.772 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:02:32.879 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:02:33.821 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:02:33.821 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:02:41.258 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:02:41.258 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:02:41.258 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:02:41.258 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:41.258 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:02:41.258 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:02:41.258 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:02:41.259 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:02:41.261 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 4, failures 0
03-04-2016 17:02:41.261 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:02:41.261 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:02:41.279 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:41.279 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:02:41.279 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:02:41.279 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:02:41.279 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:02:41.279 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:41.279 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702961279567
03-04-2016 17:02:41.279 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:02:41.280 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:02:41.281 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:02:41.281 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:02:41.281 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:02:41.281 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:43.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:02:43.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:02:43.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:02:44.414 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:02:44.414 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:02:47.893 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:02:48.461 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:02:48.461 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:02:48.461 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:02:48.461 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:48.461 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:02:48.461 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:02:48.462 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:02:48.462 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:02:48.464 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:02:48.464 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:02:48.482 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:48.482 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:02:48.482 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:02:48.482 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:02:48.482 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:02:48.482 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:02:48.482 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702968482152
03-04-2016 17:02:48.482 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:02:48.482 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:02:48.484 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:02:48.484 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:02:48.484 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:02:48.484 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:02:53.906 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:02:53.906 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:03:02.907 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:03:03.916 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:03:03.916 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:03:13.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:03:13.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:03:13.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:03:13.382 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:03:13.382 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:03:13.382 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:03:13.382 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:13.382 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:03:13.382 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:03:13.382 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:03:13.383 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:03:13.385 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 4, failures 0
03-04-2016 17:03:13.385 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:03:13.385 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:03:13.385 UTC Info load_monitor.cpp:212: Accepted 100.000000%
of requests, latency error = -0.973060, overload responses = 0
03-04-2016 17:03:13.385 UTC Status load_monitor.cpp:260: Maximum incoming
request rate/second unchanged - only handled 39 requests in last 192753ms,
minimum threshold for a change is 9637.650391
03-04-2016 17:03:13.403 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:13.403 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:03:13.403 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:03:13.403 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:03:13.403 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:03:13.404 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:13.404 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459702993404019
03-04-2016 17:03:13.404 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:03:13.404 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:03:13.405 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:03:13.405 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:03:13.406 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:03:13.406 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:14.532 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:03:14.532 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:03:17.922 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:03:20.585 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:03:20.585 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:03:20.585 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:03:20.585 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:20.585 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:03:20.585 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:03:20.585 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:03:20.586 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:03:20.587 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:03:20.587 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:03:20.605 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:20.605 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:03:20.605 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:03:20.605 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:03:20.605 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:03:20.605 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:20.605 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459703000605698
03-04-2016 17:03:20.605 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:03:20.606 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:03:20.607 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:03:20.607 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:03:20.607 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:03:20.607 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:24.016 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:03:24.016 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:03:32.933 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:03:34.018 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:03:34.018 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:03:43.261 UTC Debug alarm.cpp:254: Reraising alarms
03-04-2016 17:03:43.261 UTC Debug alarm.cpp:360: AlarmReqAgent: queue
overflowed
03-04-2016 17:03:43.261 UTC Status alarm.cpp:72: homestead issued 1501.1
alarm
03-04-2016 17:03:44.683 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:03:44.683 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
03-04-2016 17:03:45.502 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:03:45.502 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:03:45.502 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:03:45.502 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:45.502 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:03:45.502 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:03:45.502 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:03:45.503 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:03:45.505 UTC Debug communicationmonitor.cpp:82: Checking
communication changes - successful attempts 4, failures 0
03-04-2016 17:03:45.505 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:03:45.505 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:03:45.524 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:45.524 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:03:45.524 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:03:45.524 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:03:45.524 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:03:45.524 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:45.524 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459703025524217
03-04-2016 17:03:45.524 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:03:45.524 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:03:45.526 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:03:45.526 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:03:45.526 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:03:45.526 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:47.942 UTC Warning (Net-SNMP): Warning: Failed to connect
to the agentx master agent ([NIL]):
03-04-2016 17:03:52.705 UTC Verbose httpstack.cpp:293: Process request for
URL /impi/6505550435%40verified/av, args impu=sip%3A6505550435%40verified
03-04-2016 17:03:52.705 UTC Debug handlers.cpp:148: Parsed HTTP request:
private ID 6505550435@verified, public ID sip:6505550435@verified, scheme
Unknown, authorization
03-04-2016 17:03:52.705 UTC Debug handlers.cpp:174: Querying cache for
authentication vector for 6505550435@verified/sip:6505550435@verified
03-04-2016 17:03:52.705 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:52.705 UTC Debug cache.cpp:670: Looking for authentication
vector for 6505550435@verified
03-04-2016 17:03:52.705 UTC Debug cache.cpp:682: Checking public ID
sip:6505550435@verified
03-04-2016 17:03:52.705 UTC Debug cache.cpp:692: Issuing cache query
03-04-2016 17:03:52.706 UTC Debug cassandra_store.cpp:724: Failed TWO read
for get_columns. Try ONE
03-04-2016 17:03:52.707 UTC Debug handlers.cpp:194: Got authentication
vector with digest 420569c5d181d19ef3331576ccea8389 from cache
03-04-2016 17:03:52.708 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /impi/6505550435%40verified/av, args
impu=sip%3A6505550435%40verified
03-04-2016 17:03:52.726 UTC Verbose httpstack.cpp:293: Process request for
URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:52.726 UTC Debug handlers.cpp:1054: Parsed HTTP request:
private ID , public ID sip:verified
03-04-2016 17:03:52.726 UTC Debug handlers.cpp:998: Determining request
type from '{"reqtype": "call"}'
03-04-2016 17:03:52.726 UTC Debug handlers.cpp:1042: New value of _type is 2
03-04-2016 17:03:52.726 UTC Debug handlers.cpp:1092: Try to find IMS
Subscription information in the cache
03-04-2016 17:03:52.726 UTC Debug cassandra_store.cpp:284: Getting
thread-local Client
03-04-2016 17:03:52.726 UTC Debug cassandra_store.cpp:129: Generated
Cassandra timestamp 1459703032726691
03-04-2016 17:03:52.726 UTC Debug cache.cpp:347: Issuing get for key
sip:verified
03-04-2016 17:03:52.727 UTC Debug cassandra_store.cpp:755: Failed TWO read
for get_row. Try ONE
03-04-2016 17:03:52.728 UTC Debug handlers.cpp:1121: Got IMS subscription
from cache
03-04-2016 17:03:52.728 UTC Debug handlers.cpp:1137: TTL for this database
record is 0, IMS Subscription XML is empty, registration state is
NOT_REGISTERED, and the charging addresses are empty
03-04-2016 17:03:52.728 UTC Debug handlers.cpp:1352: Handling call
03-04-2016 17:03:52.728 UTC Verbose httpstack.cpp:69: Sending response 404
to request for URL /impu/sip%3Averified/reg-data, args (null)
03-04-2016 17:03:54.112 UTC Verbose httpstack.cpp:293: Process request for
URL /ping, args (null)
03-04-2016 17:03:54.112 UTC Verbose httpstack.cpp:69: Sending response 200
to request for URL /ping, args (null)
---------- Forwarded message ----------
From: Bud Asterisk <[email protected]>
Date: Sun, Apr 3, 2016 at 11:42 AM
Subject: 403 digest authentication error
To: [email protected]
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
