[Home]

Summary:ASTERISK-14601: Snom phones occasionally fail to register in 1.6.1.3rc1
Reporter:Benny Amorsen (amorsen)Labels:
Date Opened:2009-08-05 07:17:47Date Closed:2011-06-07 14:08:24
Priority:MinorRegression?Yes
Status:Closed/CompleteComponents:Channels/chan_sip/Registration
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:Sometimes Snom phones fail to register and just display "NR":

5]5/8/2009 14:00:04: sip::process_auth:Match challenge for user=776-000413245bda-1, realm=asterisk
[2]5/8/2009 14:00:05: Registered at registrar as 776-000413123456-1@10.0.1.5 (Expires: 60 secs)
[5]5/8/2009 14:00:05: sip::process_auth:Match challenge for user=776-000413123456-1, realm=asterisk
[2]5/8/2009 14:00:05: Registrar 776-000413123456-1@10.0.1.5 refused with code 401
[5]5/8/2009 14:00:05: Will try to reregister in 20 seconds

I have put a packet dump in "additional information". I have changed the name to Phoneuser and the MAC address to 000413123456 for privacy reasons, but everything else (including IP's) are unchanged.

Notice how Asterisk first responds "OK" and then "Unauthorized" in the final two packets.

The problem does not occur with 1.6.1.1. I have not tried 1.6.1.2 yet.

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

14:00:04.953233 IP (tos 0xb8, ttl 62, id 0, offset 0, flags [DF], proto UDP (17), length 789) 10.1.27.164.dls-monitor > 10.0.1.5.sip: SIP, length: 761
       REGISTER sip:10.0.1.5 SIP/2.0
       Via: SIP/2.0/UDP 10.1.27.164:2048;branch=z9hG4bK-4vm7cr1zrjs3;rport
       From: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=18bed0nasf
       To: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>
       Call-ID: 3c2670165539-cxa15tyugbx9
       CSeq: 115963 REGISTER
       Max-Forwards: 70
       Contact: <sip:776-000413123456-1@10.1.27.164:2048;line=lk52tap3>;reg-id=1;q=1.0;+sip.instance="<urn:uuid:536cd57a-1a95-49a6-b41b-470a6b0ffb62>";audio;mobility="fixed";duplex="full";description="snom320";actor="principal";events="dialog";methods="INVITE,ACK,CANCEL,BYE,REFER,OPTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO"
       User-Agent: snom320/7.3.14
       Supported: gruu
       Allow-Events: dialog
       X-Real-IP: 10.1.27.164
       Expires: 60
       Content-Length: 0
       
       
14:00:04.953361 IP (tos 0x60, ttl 64, id 60399, offset 0, flags [none], proto UDP (17), length 595) 10.0.1.5.sip > 10.1.27.164.dls-monitor: SIP, length: 567
       SIP/2.0 401 Unauthorized
       Via: SIP/2.0/UDP 10.1.27.164:2048;branch=z9hG4bK-4vm7cr1zrjs3;received=10.1.27.164;rport=2048
       From: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=18bed0nasf
       To: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=as1fd48033
       Call-ID: 3c2670165539-cxa15tyugbx9
       CSeq: 115963 REGISTER
       Server: Asterisk PBX 1.6.1.3-rc1
       Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
       Supported: replaces, timer
       WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7cc63491"
       Content-Length: 0
       

14:00:05.003063 IP (tos 0xb8, ttl 62, id 0, offset 0, flags [DF], proto UDP (17), length 953) 10.1.27.164.dls-monitor > 10.0.1.5.sip: SIP, length: 925
       REGISTER sip:10.0.1.5 SIP/2.0
       Via: SIP/2.0/UDP 10.1.27.164:2048;branch=z9hG4bK-oe3qzeuuk2qc;rport
       From: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=18bed0nasf
       To: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>
       Call-ID: 3c2670165539-cxa15tyugbx9
       CSeq: 115964 REGISTER
       Max-Forwards: 70
       Contact: <sip:776-000413123456-1@10.1.27.164:2048;line=lk52tap3>;reg-id=1;q=1.0;+sip.instance="<urn:uuid:536cd57a-1a95-49a6-b41b-470a6b0ffb62>";audio;mobility="fixed";duplex="full";description="snom320";actor="principal";events="dialog";methods="INVITE,ACK,CANCEL,BYE,REFER,OPTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO"
       User-Agent: snom320/7.3.14
       Supported: gruu
       Allow-Events: dialog
       X-Real-IP: 10.1.27.164
       Authorization: Digest username="776-000413123456-1",realm="asterisk",nonce="7cc63491",uri="sip:10.0.1.5",response="1f0e70c1c3d738904e015036e3c561b5",algorithm=MD5
       Expires: 60
       Content-Length: 0
       
       
14:00:05.488589 IP (tos 0xb8, ttl 62, id 0, offset 0, flags [DF], proto UDP (17), length 953) 10.1.27.164.dls-monitor > 10.0.1.5.sip: SIP, length: 925
       REGISTER sip:10.0.1.5 SIP/2.0
       Via: SIP/2.0/UDP 10.1.27.164:2048;branch=z9hG4bK-oe3qzeuuk2qc;rport
       From: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=18bed0nasf
       To: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>
       Call-ID: 3c2670165539-cxa15tyugbx9
       CSeq: 115964 REGISTER
       Max-Forwards: 70
       Contact: <sip:776-000413123456-1@10.1.27.164:2048;line=lk52tap3>;reg-id=1;q=1.0;+sip.instance="<urn:uuid:536cd57a-1a95-49a6-b41b-470a6b0ffb62>";audio;mobility="fixed";duplex="full";description="snom320";actor="principal";events="dialog";methods="INVITE,ACK,CANCEL,BYE,REFER,OPTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO"
       User-Agent: snom320/7.3.14
       Supported: gruu
       Allow-Events: dialog
       X-Real-IP: 10.1.27.164
       Authorization: Digest username="776-000413123456-1",realm="asterisk",nonce="7cc63491",uri="sip:10.0.1.5",response="1f0e70c1c3d738904e015036e3c561b5",algorithm=MD5
       Expires: 60
       Content-Length: 0
       
       
14:00:06.294280 IP (tos 0x60, ttl 64, id 60400, offset 0, flags [none], proto UDP (17), length 599) 10.0.1.5.sip > 10.1.27.164.dls-monitor: SIP, length: 571
       OPTIONS sip:776-000413123456-1@10.1.27.164:2048;line=lk52tap3 SIP/2.0
       Via: SIP/2.0/UDP 10.0.1.5:5060;branch=z9hG4bK3b5609f3;rport
       Max-Forwards: 70
       From: "asterisk" <sip:asterisk@10.0.1.5>;tag=as753af3ea
       To: <sip:776-000413123456-1@10.1.27.164:2048;line=lk52tap3>
       Contact: <sip:asterisk@10.0.1.5>
       Call-ID: 1c0d0ac250769091469d7fe72e780fea@10.0.1.5
       CSeq: 102 OPTIONS
       User-Agent: Asterisk PBX 1.6.1.3-rc1
       Date: Wed, 05 Aug 2009 12:00:06 GMT
       Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
       Supported: replaces, timer
       Content-Length: 0
       
       
14:00:06.294505 IP (tos 0x60, ttl 64, id 60401, offset 0, flags [none], proto UDP (17), length 636) 10.0.1.5.sip > 10.1.27.164.dls-monitor: SIP, length: 608
       SIP/2.0 200 OK
       Via: SIP/2.0/UDP 10.1.27.164:2048;branch=z9hG4bK-oe3qzeuuk2qc;received=10.1.27.164;rport=2048
       From: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=18bed0nasf
       To: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=as1fd48033
       Call-ID: 3c2670165539-cxa15tyugbx9
       CSeq: 115964 REGISTER
       Server: Asterisk PBX 1.6.1.3-rc1
       Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
       Supported: replaces, timer
       Expires: 60
       Contact: <sip:776-000413123456-1@10.1.27.164:2048;line=lk52tap3>;expires=60
       Date: Wed, 05 Aug 2009 12:00:06 GMT
       Content-Length: 0
       
       
14:00:06.295271 IP (tos 0x60, ttl 64, id 60402, offset 0, flags [none], proto UDP (17), length 607) 10.0.1.5.sip > 10.1.27.164.dls-monitor: SIP, length: 579
       SIP/2.0 401 Unauthorized
       Via: SIP/2.0/UDP 10.1.27.164:2048;branch=z9hG4bK-oe3qzeuuk2qc;received=10.1.27.164;rport=2048
       From: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=18bed0nasf
       To: "776 Phoneuser" <sip:776-000413123456-1@10.0.1.5>;tag=as1fd48033
       Call-ID: 3c2670165539-cxa15tyugbx9
       CSeq: 115964 REGISTER
       Server: Asterisk PBX 1.6.1.3-rc1
       Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
       Supported: replaces, timer
       WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="62a3c547", stale=true
       Content-Length: 0

Comments:By: Benny Amorsen (amorsen) 2009-08-07 07:31:16

I have now had 1.6.1.2 running for a day, and the issue has not recurred. It must be specific to 1.6.1.3-rc1.
By: Benny Amorsen (amorsen) 2009-08-14 03:10:54

Is there anything I can do to help get this issue fixed? It has acquired higher priority for me now, because we're hitting issue 15290, and so we would like to upgrade.
By: Benny Amorsen (amorsen) 2009-09-08 02:16:10

Problem persists in 1.6.1.6
By: Benny Amorsen (amorsen) 2009-09-08 10:32:54

Problem is in 1.6.0.15 as well! Notice it does NOT exist in 1.6.0.13.
By: Leif Madsen (lmadsen) 2009-09-08 11:08:20

Assigned to Tilghman for review. Please set back to 'New' if you are not able to move this issue forward. Thanks!
By: Leif Madsen (lmadsen) 2009-09-16 10:24:29

Could you also provide console debugging information and sip history when this is occurring? Please provide these in a file attached to the issue in order to make it easier to work with.

Thanks!
By: Leif Madsen (lmadsen) 2009-10-26 10:04:08

Pinging reporter; this probably is an issue, so I don't want to close it, but additional information is required at this point to verify what is happening.
By: Leif Madsen (lmadsen) 2009-11-10 09:03:45.000-0600

If the reporter is able to provide the necessary information to move this issue forward, they are welcome to reopen the issue. Thanks!