[Home]

Summary:ASTERISK-13405: [patch] Asterisk retransmits the 401 response of failed REGISTER
Reporter:klaus3000 (klaus3000)Labels:
Date Opened:2009-01-20 04:19:10.000-0600Date Closed:2009-01-26 08:57:52.000-0600
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Channels/chan_sip/Registration
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:( 0) patch_chan_sip_unreliable_1.4.23_14284.txt
( 1) sip.conf
Description:Hi! Asterisk retransmits the 401 of a failed REGISTER. This is buggy as there will not be an ACK for REGISTER, does Asterisk must not retransmit any response in a REGISTER transaction - except the incoming REGISTER itself is a retransmission!

client       Asterisk
-----REGISTER---->
<-----401---------
-----REGISTER---->
<-----401---------
-----REGISTER---->
<-----401---------
<-----401---------
<-----401---------
<-----401---------
<-----401---------
<-----401---------
<-----401---------
<-----401---------
<-----401---------
<-----401---------


****** ADDITIONAL INFORMATION ******

Here is the trace from the logfile:


[Jan 20 11:03:26] VERBOSE[28643] logger.c:
<--- SIP read from 83.136.33.3:46716 --->
REGISTER sip:app.nxdomain.at:5160 SIP/2.0
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:+43720123456789@10.10.0.51:46716;rinstance=786cace76a58af35;transport=udp>
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 1 REGISTER
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: eyeBeam release 1101l stamp 49847
Content-Length: 0

<------------->
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 0: REGISTER sip:app.nxdomain.at:5160 SIP/2.0 (41)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 1: Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;rport (90)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 2: Max-Forwards: 70 (16)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 3: Contact: <sip:+43720123456789@10.10.0.51:46716;rinstance=786cace76a58af35;transport=udp> (88)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 4: To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160> (57)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 5: From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48 (72)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 6: Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. (53)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 7: CSeq: 1 REGISTER (16)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 8: Expires: 300 (12)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 10: User-Agent: eyeBeam release 1101l stamp 49847 (45)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 11: Content-Length: 0 (17)
[Jan 20 11:03:26] VERBOSE[28643] logger.c: --- (12 headers 0 lines) ---
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: = Looking for  Call ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. (Checking From) --From tag b82d9e48 --To-tag  
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: = No match Their Call ID: 3c2e232eefea-ebz9owxh3d3h Their Tag wkczc3ww7f Our tag: as74132e07
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Allocating new SIP dialog for MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. - REGISTER (No RTP)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Using latest REGISTER request as basis request
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Sending to 83.136.33.3 : 46716 (NAT)
[Jan 20 11:03:26] VERBOSE[28643] logger.c:
<--- Reliably Transmitting (NAT) to 83.136.33.3:46716 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 1 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="67405067"
Content-Length: 0


<------------>
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id  #-1
[Jan 20 11:03:26] NOTICE[28643] chan_sip.c: Registration from '"app aster"<sip:+43720123456789@app.nxdomain.at:5160>' failed for '83.136.33.3' - No matching peer found
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Scheduling destruction of SIP dialog 'MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.' in 32000 ms (Method: REGISTER)
[Jan 20 11:03:26] VERBOSE[28643] logger.c:
<--- SIP read from 83.136.33.3:46716 --->
REGISTER sip:app.nxdomain.at:5160 SIP/2.0
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:+43720123456789@10.10.0.51:46716;rinstance=786cace76a58af35;transport=udp>
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 2 REGISTER
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: eyeBeam release 1101l stamp 49847
Authorization: Digest username="+43720123456789",realm="asterisk",nonce="67405067",uri="sip:app.nxdomain.at:5160",response="76a28ab94dfbbf51745a0daeae19971e",algorithm=MD5
Content-Length: 0

<------------->
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 0: REGISTER sip:app.nxdomain.at:5160 SIP/2.0 (41)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 1: Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;rport (90)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 2: Max-Forwards: 70 (16)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 3: Contact: <sip:+43720123456789@10.10.0.51:46716;rinstance=786cace76a58af35;transport=udp> (88)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 4: To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160> (57)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 5: From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48 (72)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 6: Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. (53)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 7: CSeq: 2 REGISTER (16)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 8: Expires: 300 (12)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 10: User-Agent: eyeBeam release 1101l stamp 49847 (45)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 11: Authorization: Digest username="+43720123456789",realm="asterisk",nonce="67405067",uri="sip:app.nxdomain.at:5160",response="76a28ab94dfbbf51745a0daeae19971e",algorithm=MD5 (171)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 12: Content-Length: 0 (17)
[Jan 20 11:03:26] VERBOSE[28643] logger.c: --- (13 headers 0 lines) ---
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: = Looking for  Call ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. (Checking From) --From tag b82d9e48 --To-tag  
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: = Found Their Call ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. Their Tag b82d9e48 Our tag: as387d3d28
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Using latest REGISTER request as basis request
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Sending to 83.136.33.3 : 46716 (NAT)
[Jan 20 11:03:26] VERBOSE[28643] logger.c:
<--- Reliably Transmitting (NAT) to 83.136.33.3:46716 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 2 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="563256a4"
Content-Length: 0


<------------>
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id  #-1
[Jan 20 11:03:26] NOTICE[28643] chan_sip.c: Registration from '"app aster"<sip:+43720123456789@app.nxdomain.at:5160>' failed for '83.136.33.3' - No matching peer found
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Scheduling destruction of SIP dialog 'MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.' in 32000 ms (Method: REGISTER)
[Jan 20 11:03:26] VERBOSE[28643] logger.c:
<--- SIP read from 83.136.33.3:46716 --->
REGISTER sip:app.nxdomain.at:5160 SIP/2.0
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:+43720123456789@10.10.0.51:46716;rinstance=786cace76a58af35;transport=udp>
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 3 REGISTER
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: eyeBeam release 1101l stamp 49847
Authorization: Digest username="+43720123456789",realm="asterisk",nonce="563256a4",uri="sip:app.nxdomain.at:5160",response="f3b2c76ecc55ab6af0efefea9df749a4",algorithm=MD5
Content-Length: 0

<------------->
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 0: REGISTER sip:app.nxdomain.at:5160 SIP/2.0 (41)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 1: Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;rport (90)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 2: Max-Forwards: 70 (16)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 3: Contact: <sip:+43720123456789@10.10.0.51:46716;rinstance=786cace76a58af35;transport=udp> (88)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 4: To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160> (57)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 5: From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48 (72)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 6: Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. (53)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 7: CSeq: 3 REGISTER (16)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 8: Expires: 300 (12)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 10: User-Agent: eyeBeam release 1101l stamp 49847 (45)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 11: Authorization: Digest username="+43720123456789",realm="asterisk",nonce="563256a4",uri="sip:app.nxdomain.at:5160",response="f3b2c76ecc55ab6af0efefea9df749a4",algorithm=MD5 (171)
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: Header 12: Content-Length: 0 (17)
[Jan 20 11:03:26] VERBOSE[28643] logger.c: --- (13 headers 0 lines) ---
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: = Looking for  Call ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. (Checking From) --From tag b82d9e48 --To-tag  
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: = Found Their Call ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI. Their Tag b82d9e48 Our tag: as387d3d28
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Using latest REGISTER request as basis request
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Sending to 83.136.33.3 : 46716 (NAT)
[Jan 20 11:03:26] VERBOSE[28643] logger.c:
<--- Reliably Transmitting (NAT) to 83.136.33.3:46716 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 3 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7ba272cd"
Content-Length: 0


<------------>
[Jan 20 11:03:26] DEBUG[28643] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id  #-1
[Jan 20 11:03:26] NOTICE[28643] chan_sip.c: Registration from '"app aster"<sip:+43720123456789@app.nxdomain.at:5160>' failed for '83.136.33.3' - No matching peer found
[Jan 20 11:03:26] VERBOSE[28643] logger.c: Scheduling destruction of SIP dialog 'MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.' in 32000 ms (Method: REGISTER)
[Jan 20 11:03:27] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ASTERISK-11338 (1) SIP/2.0 - 1
[Jan 20 11:03:27] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id ASTERISK-11338))
[Jan 20 11:03:27] VERBOSE[28643] logger.c: Retransmitting #1 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 1 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="67405067"
Content-Length: 0


---
[Jan 20 11:03:27] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission AGUI-67 (1) SIP/2.0 - 1
[Jan 20 11:03:27] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id AGUI-67))
[Jan 20 11:03:27] VERBOSE[28643] logger.c: Retransmitting #1 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 2 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="563256a4"
Content-Length: 0


---
[Jan 20 11:03:27] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ZAP-284 (1) SIP/2.0 - 1
[Jan 20 11:03:27] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id ZAP-284))
[Jan 20 11:03:27] VERBOSE[28643] logger.c: Retransmitting #1 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 3 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7ba272cd"
Content-Length: 0


---
[Jan 20 11:03:28] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ASTERISK-11338 (2) SIP/2.0 - 1
[Jan 20 11:03:28] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id ASTERISK-11338))
[Jan 20 11:03:28] VERBOSE[28643] logger.c: Retransmitting #2 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 1 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="67405067"
Content-Length: 0


---
[Jan 20 11:03:28] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission AGUI-67 (2) SIP/2.0 - 1
[Jan 20 11:03:28] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id AGUI-67))
[Jan 20 11:03:28] VERBOSE[28643] logger.c: Retransmitting #2 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 2 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="563256a4"
Content-Length: 0


---
[Jan 20 11:03:28] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ZAP-284 (2) SIP/2.0 - 1
[Jan 20 11:03:28] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id ZAP-284))
[Jan 20 11:03:28] VERBOSE[28643] logger.c: Retransmitting #2 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 3 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7ba272cd"
Content-Length: 0


---
[Jan 20 11:03:30] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ASTERISK-11338 (3) SIP/2.0 - 1
[Jan 20 11:03:30] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id ASTERISK-11338))
[Jan 20 11:03:30] VERBOSE[28643] logger.c: Retransmitting #3 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 1 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="67405067"
Content-Length: 0


---
[Jan 20 11:03:30] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission AGUI-67 (3) SIP/2.0 - 1
[Jan 20 11:03:30] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id AGUI-67))
[Jan 20 11:03:30] VERBOSE[28643] logger.c: Retransmitting #3 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 2 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="563256a4"
Content-Length: 0


---
[Jan 20 11:03:30] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ZAP-284 (3) SIP/2.0 - 1
[Jan 20 11:03:30] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id ZAP-284))
[Jan 20 11:03:30] VERBOSE[28643] logger.c: Retransmitting #3 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 3 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7ba272cd"
Content-Length: 0


---
[Jan 20 11:03:34] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ASTERISK-11338 (4) SIP/2.0 - 1
[Jan 20 11:03:34] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id ASTERISK-11338))
[Jan 20 11:03:34] VERBOSE[28643] logger.c: Retransmitting #4 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 1 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="67405067"
Content-Length: 0


---
[Jan 20 11:03:34] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission AGUI-67 (4) SIP/2.0 - 1
[Jan 20 11:03:34] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id AGUI-67))
[Jan 20 11:03:34] VERBOSE[28643] logger.c: Retransmitting #4 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 2 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="563256a4"
Content-Length: 0


---
[Jan 20 11:03:34] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ZAP-284 (4) SIP/2.0 - 1
[Jan 20 11:03:34] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id ZAP-284))
[Jan 20 11:03:34] VERBOSE[28643] logger.c: Retransmitting #4 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 3 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7ba272cd"
Content-Length: 0


---
[Jan 20 11:03:38] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ASTERISK-11338 (5) SIP/2.0 - 1
[Jan 20 11:03:38] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id ASTERISK-11338))
[Jan 20 11:03:38] VERBOSE[28643] logger.c: Retransmitting ASTERISK-1 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-2448634cc070273a-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 1 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="67405067"
Content-Length: 0


---
[Jan 20 11:03:38] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission AGUI-67 (5) SIP/2.0 - 1
[Jan 20 11:03:38] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id AGUI-67))
[Jan 20 11:03:38] VERBOSE[28643] logger.c: Retransmitting ASTERISK-1 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-0e461c2d315af76e-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 2 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="563256a4"
Content-Length: 0


---
[Jan 20 11:03:38] DEBUG[28643] chan_sip.c: SIP TIMER: Rescheduling retransmission ZAP-284 (5) SIP/2.0 - 1
[Jan 20 11:03:38] DEBUG[28643] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id ZAP-284))
[Jan 20 11:03:38] VERBOSE[28643] logger.c: Retransmitting ASTERISK-1 (NAT) to 83.136.33.3:46716:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.0.51:46716;branch=z9hG4bK-d8754z-1e1ff1064f299a14-1---d8754z-;received=83.136.33.3;rport=46716
From: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=b82d9e48
To: "app aster"<sip:+43720123456789@app.nxdomain.at:5160>;tag=as387d3d28
Call-ID: MWI2NjUzNmRiMTRiZmE0MWYyZDYxZDg4MjY1NGFjZDI.
CSeq: 3 REGISTER
User-Agent: InnoSIP-app
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7ba272cd"
Content-Length: 0


---



Comments:By: klaus3000 (klaus3000) 2009-01-26 04:55:11.000-0600

I found out that the problem exists only when alwaysauthreject = yes.

Thus I reviewd the code and found that the transmit_fake_auth_response function was called with "reliable=1" which is false.

Attached is a patch.
By: Olle Johansson (oej) 2009-01-26 06:49:45.000-0600

Thanks for finding this and providing a patch. Will commit a.s.a.p.
By: Digium Subversion (svnbot) 2009-01-26 06:51:17.000-0600

Repository: asterisk
Revision: 171264

U   branches/1.4/channels/chan_sip.c

------------------------------------------------------------------------
r171264 | oej | 2009-01-26 06:51:16 -0600 (Mon, 26 Jan 2009) | 9 lines

Don't retransmit 401 on REGISTER requests when alwaysauthreject=yes

(closes issue ASTERISK-13405)
Reported by: klaus3000
Patches:
     patch_chan_sip_unreliable_1.4.23_14284.txt uploaded by klaus3000 (license 65)
Tested by: klaus3000


------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=171264
By: Digium Subversion (svnbot) 2009-01-26 07:44:37.000-0600

Repository: asterisk
Revision: 171326

_U  trunk/
U   trunk/channels/chan_sip.c

------------------------------------------------------------------------
r171326 | oej | 2009-01-26 07:44:37 -0600 (Mon, 26 Jan 2009) | 17 lines

Merged revisions 171264 via svnmerge from
https://origsvn.digium.com/svn/asterisk/branches/1.4

........
r171264 | oej | 2009-01-26 13:51:53 +0100 (M?\195?\131?\194?\165n, 26 Jan 2009) | 9 lines

Don't retransmit 401 on REGISTER requests when alwaysauthreject=yes

(closes issue ASTERISK-13405)
Reported by: klaus3000
Patches:
     patch_chan_sip_unreliable_1.4.23_14284.txt uploaded by klaus3000 (license 65)
Tested by: klaus3000


........

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=171326
By: Digium Subversion (svnbot) 2009-01-26 08:02:16.000-0600

Repository: asterisk
Revision: 171327

_U  branches/1.6.0/
U   branches/1.6.0/channels/chan_sip.c

------------------------------------------------------------------------
r171327 | oej | 2009-01-26 08:02:15 -0600 (Mon, 26 Jan 2009) | 25 lines

Merged revisions 171326 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

................
r171326 | oej | 2009-01-26 14:44:40 +0100 (M?\195?\131?\194?\165n, 26 Jan 2009) | 17 lines

Merged revisions 171264 via svnmerge from
https://origsvn.digium.com/svn/asterisk/branches/1.4

........
r171264 | oej | 2009-01-26 13:51:53 +0100 (M?\195?\131?\194?\165n, 26 Jan 2009) | 9 lines

Don't retransmit 401 on REGISTER requests when alwaysauthreject=yes

(closes issue ASTERISK-13405)
Reported by: klaus3000
Patches:
     patch_chan_sip_unreliable_1.4.23_14284.txt uploaded by klaus3000 (license 65)
Tested by: klaus3000


........

................

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=171327
By: Digium Subversion (svnbot) 2009-01-26 08:57:52.000-0600

Repository: asterisk
Revision: 171361

_U  branches/1.6.1/
U   branches/1.6.1/channels/chan_sip.c

------------------------------------------------------------------------
r171361 | oej | 2009-01-26 08:57:51 -0600 (Mon, 26 Jan 2009) | 25 lines

Merged revisions 171326 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

................
r171326 | oej | 2009-01-26 14:44:40 +0100 (M?\195?\131?\194?\165n, 26 Jan 2009) | 17 lines

Merged revisions 171264 via svnmerge from
https://origsvn.digium.com/svn/asterisk/branches/1.4

........
r171264 | oej | 2009-01-26 13:51:53 +0100 (M?\195?\131?\194?\165n, 26 Jan 2009) | 9 lines

Don't retransmit 401 on REGISTER requests when alwaysauthreject=yes

(closes issue ASTERISK-13405)
Reported by: klaus3000
Patches:
     patch_chan_sip_unreliable_1.4.23_14284.txt uploaded by klaus3000 (license 65)
Tested by: klaus3000


........

................

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=171361