[asterisk-dev] SIP register expiry timer

Tue May 4 10:47:08 CDT 2010

Ok I have changed the default and I am sending 1800 but the UAS is still
sending 30 sec. So I will have to reregister at 30 sec correct? Also I
have included more debug output as hopfully someone can tell me why I am
retransmiting after a 200 ok?

Thanks Again,
Josh

[May  4 10:40:49] NOTICE[4045]: chan_sip.c:11463 sip_reregister:    --
Re-registration for  405233xxxx at sip.broadvoice.com@sip.broadvoice.com
       > doing dnsmgr_lookup for 'sip.broadvoice.com'
REGISTER 11 headers, 0 lines
Reliably Transmitting (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---

<--- SIP read from UDP:208.122.63.94:52846 --->

<------------->
Retransmitting #1 (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---
Retransmitting #2 (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---
Retransmitting #3 (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---

<--- SIP read from UDP:192.168.0.61:5060 --->

<------------->
Really destroying SIP dialog
'54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com' Method: REGISTER
Retransmitting #4 (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 401 Unauthorized
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 103 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as1f259f12
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK3a29d0c2
WWW-Authenticate: DIGEST
realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXg8svzetfTx
fnkywBW"
Content-Length:    0

<------------->
--- (8 headers 0 lines) ---
Responding to challenge, registration to domain/host name
sip.broadvoice.com
       > doing dnsmgr_lookup for 'sip.broadvoice.com'
REGISTER 12 headers, 0 lines
Reliably Transmitting (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK60d84ba9;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as4dca7e46
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 104 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Authorization: Digest username="405233xxxx", realm="BroadWorks",
algorithm=MD5, uri="sip:sip.broadvoice.com",
nonce="BroadWorksXg8svzetfTxfnkywBW",
response="da9898b04632ac73bbb60b4354f98449", qop=auth,
cnonce="37dd8178", nc=00000001
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 104 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as4dca7e46
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK60d84ba9
Contact: <sip:s at 98.174.176.x>;expires=30
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---
Scheduling destruction of SIP dialog
'54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com' in 32000 ms
(Method: REGISTER)
[May  4 10:40:57] NOTICE[4045]: chan_sip.c:18184
handle_response_register: Outbound Registration: Expiry for
sip.broadvoice.com is 30 sec (Scheduling reregistration in 23 s)
Really destroying SIP dialog '3e0737fe26842412 at 192.168.0.61' Method:
REGISTER

<--- SIP read from UDP:208.122.63.94:52846 --->

<------------->
Really destroying SIP dialog '2a9b84d26c326 at 192.168.0.40' Method:
REGISTER

<--- SIP read from UDP:192.168.0.40:5060 --->

<------------->

<--- SIP read from UDP:208.122.63.94:52846 --->

<------------->

<--- SIP read from UDP:208.122.63.94:52846 --->

<------------->
[May  4 10:41:21] NOTICE[4045]: chan_sip.c:11463 sip_reregister:    --
Re-registration for  405233xxxx at sip.broadvoice.com@sip.broadvoice.com
       > doing dnsmgr_lookup for 'sip.broadvoice.com'
REGISTER 12 headers, 0 lines
Reliably Transmitting (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Authorization: Digest username="405233xxxx", realm="BroadWorks",
algorithm=MD5, uri="sip:sip.broadvoice.com",
nonce="BroadWorksXg8svzetfTxfnkywBW",
response="df7b68414109ce8da24acfdfb8b20fe7", qop=auth,
cnonce="28da1b1a", nc=00000002
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---
Retransmitting #1 (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Authorization: Digest username="405233xxxx", realm="BroadWorks",
algorithm=MD5, uri="sip:sip.broadvoice.com",
nonce="BroadWorksXg8svzetfTxfnkywBW",
response="df7b68414109ce8da24acfdfb8b20fe7", qop=auth,
cnonce="28da1b1a", nc=00000002
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---
Retransmitting #2 (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Authorization: Digest username="405233xxxx", realm="BroadWorks",
algorithm=MD5, uri="sip:sip.broadvoice.com",
nonce="BroadWorksXg8svzetfTxfnkywBW",
response="df7b68414109ce8da24acfdfb8b20fe7", qop=auth,
cnonce="28da1b1a", nc=00000002
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---

<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---

<--- SIP read from UDP:192.168.0.61:5060 --->
REGISTER sip:192.168.0.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.61:5060;branch=z9hG4bK6d133a6b1ac0612d;rport
From: 206 <sip:206 at 192.168.0.1>;tag=3d2ba41ce778d
To: 206 <sip:206 at 192.168.0.1>
Contact: 206 <sip:206 at 192.168.0.61>;expires=30
Authorization: Digest username="206", realm="asterisk",
nonce="0bb3f63c", uri="sip:192.168.0.1", algorithm=MD5,
response="7d74b28955901110cdaa5cf66eaed127"
Call-ID: 3e0737fe26842412 at 192.168.0.61
CSeq: 318629 REGISTER
Expires: 30
Max-Forwards: 70
Allow: INVITE, ACK, BYE, CANCEL, PRACK
Content-Length: 0

<------------->
--- (12 headers 0 lines) ---
Sending to 192.168.0.61 : 5060 (no NAT)

<--- Transmitting (no NAT) to 192.168.0.61:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.0.61:5060;branch=z9hG4bK6d133a6b1ac0612d;received=192.168.0.61;r
port=5060
From: 206 <sip:206 at 192.168.0.1>;tag=3d2ba41ce778d
To: 206 <sip:206 at 192.168.0.1>;tag=as0f54a504
Call-ID: 3e0737fe26842412 at 192.168.0.61
CSeq: 318629 REGISTER
Server: Asterisk PBX 1.6.2.6
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk",
nonce="7783dbb7"
Content-Length: 0

<------------>
Scheduling destruction of SIP dialog '3e0737fe26842412 at 192.168.0.61' in
32000 ms (Method: REGISTER)

<--- SIP read from UDP:192.168.0.61:5060 --->
REGISTER sip:192.168.0.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.61:5060;branch=z9hG4bK6d1b8225c7266cf;rport
From: 206 <sip:206 at 192.168.0.1>;tag=3d2ba41ce778d
To: 206 <sip:206 at 192.168.0.1>
Contact: 206 <sip:206 at 192.168.0.61>;expires=30
Authorization: Digest username="206", realm="asterisk",
nonce="7783dbb7", uri="sip:192.168.0.1", algorithm=MD5,
response="f8f20337a877785b9a8a75cab42f77e0"
Call-ID: 3e0737fe26842412 at 192.168.0.61
CSeq: 318630 REGISTER
Expires: 30
Max-Forwards: 70
Allow: INVITE, ACK, BYE, CANCEL, PRACK
Content-Length: 0

<------------->
--- (12 headers 0 lines) ---
Sending to 192.168.0.61 : 5060 (no NAT)
sip set debug 
<--- Transmitting (no NAT) to 192.168.0.61:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.0.61:5060;branch=z9hG4bK6d1b8225c7266cf;received=192.168.0.61;rp
ort=5060
From: 206 <sip:206 at 192.168.0.1>;tag=3d2ba41ce778d
To: 206 <sip:206 at 192.168.0.1>;tag=as0f54a504
Call-ID: 3e0737fe26842412 at 192.168.0.61
CSeq: 318630 REGISTER
Server: Asterisk PBX 1.6.2.6
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Expires: 60
Contact: <sip:206 at 192.168.0.61>;expires=60
Date: Tue, 04 May 2010 15:41:25 GMT
Content-Length: 0

<------------>
Scheduling destruction of SIP dialog '3e0737fe26842412 at 192.168.0.61' in
32000 ms (Method: REGISTER)
Retransmitting #3 (no NAT) to 206.15.140.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f;rport
Max-Forwards: 70
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
User-Agent: Asterisk PBX 1.6.2.6
Authorization: Digest username="405233xxxx", realm="BroadWorks",
algorithm=MD5, uri="sip:sip.broadvoice.com",
nonce="BroadWorksXg8svzetfTxfnkywBW",
response="df7b68414109ce8da24acfdfb8b20fe7", qop=auth,
cnonce="28da1b1a", nc=00000002
Expires: 1800
Contact: <sip:s at 98.174.176.x>
Content-Length: 0

---
sip set debug 
<--- SIP read from UDP:192.168.0.61:5060 --->
REGISTER sip:192.168.0.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.61:5060;branch=z9hG4bK6d1b8225c7266cf;rport
From: 206 <sip:206 at 192.168.0.1>;tag=3d2ba41ce778d
To: 206 <sip:206 at 192.168.0.1>
Contact: 206 <sip:206 at 192.168.0.61>;expires=30
Authorization: Digest username="206", realm="asterisk",
nonce="7783dbb7", uri="sip:192.168.0.1", algorithm=MD5,
response="f8f20337a877785b9a8a75cab42f77e0"
Call-ID: 3e0737fe26842412 at 192.168.0.61
CSeq: 318630 REGISTER
Expires: 30
Max-Forwards: 70
Allow: INVITE, ACK, BYE, CANCEL, PRACK
Content-Length: 0

<------------->
--- (12 headers 0 lines) ---
Sending to 192.168.0.61 : 5060 (no NAT)
[May  4 10:41:25] NOTICE[4045]: chan_sip.c:12716 check_auth: Correct
auth, but based on stale nonce received from '206 <sip:206 at 192.168.0.1>'
sip set debug 
<--- Transmitting (no NAT) to 192.168.0.61:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.0.61:5060;branch=z9hG4bK6d1b8225c7266cf;received=192.168.0.61;rp
ort=5060
From: 206 <sip:206 at 192.168.0.1>;tag=3d2ba41ce778d
To: 206 <sip:206 at 192.168.0.1>;tag=as0f54a504
Call-ID: 3e0737fe26842412 at 192.168.0.61
CSeq: 318630 REGISTER
Server: Asterisk PBX 1.6.2.6
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk",
nonce="7452cf80", stale=true
Content-Length: 0

<------------>
Scheduling destruction of SIP dialog '3e0737fe26842412 at 192.168.0.61' in
32000 ms (Method: REGISTER)
sip set debug 
<--- SIP read from UDP:206.15.140.221:5060 --->
SIP/2.0 200 OK
Call-ID: 54d2c0f828b6eb944ba5a75662e2e280 at sip.broadvoice.com
CSeq: 105 REGISTER
From: <sip:405233xxxx at sip.broadvoice.com>;tag=as5092402d
To: <sip:405233xxxx at sip.broadvoice.com>
Via: SIP/2.0/UDP 98.174.176.x:5060;branch=z9hG4bK5787b90f
Contact: <sip:s at 98.174.176.x>
Expires: 30
Content-Length:    0

<------------->
--- (9 headers 0 lines) ---

-----Original Message-----

It looks as if there are a number of problems.

The parameter for setting the default expiry has an error in your
sip.conf. It should be defaultexpiry=1800 (defaultexpirey=1800 would
also be acceptable). I think this will change the requested expires
header from 120 to 1800.

However as you point out broadvoice are using 30 seconds for the expires
header. Unfortunately the correct expiry negotiation consists of the UAC
requesting a value and the UAS deciding the value so I do not think
there is a way to change this.

One thing that confuses me is that Asterisk seems to be retransmitting
even when it is receiving a 200 OK response. I would expect it to send
an acknowledge instead. Perhaps Asterisk is not able to match the 200 OK
response to the REGISTER request for some reason so is sending again and
again.

-- N_L