Sip registration

Hi folks,

I've got a plain Asterisk installation on a production box to which a variety of SIP 
clients can connect (swissvoice, siptone, xlite, etc). and is working okay in general.

I'm trying to get AMP working on a test machine using Asterisk CVS version HEAD (as of 
8/24).  It installed and runs, but no matter what I do, I can't get a SIP client to 
register.  Clients always get a 403 Forbidden from register_verify() in chan_sip.c. I'm 
trying to step through in the debugger but it's not easy going.

There's a full log attached if someone would like to have a look?

Thanks much,
--
Mitchell Perilstein
Partner
ACE Technology Group, LLC
http://www.acetechgroup.com
(866) 229-1543 x11


Aug 26 10:51:28 DEBUG[12876] manager.c: Manager received command 'Command'
Aug 26 10:51:28 DEBUG[12876] manager.c: Manager received command 'Command'
Aug 26 10:51:33 DEBUG[12876] chan_iax2.c: Sending registration request for 
'mitch2'
Aug 26 10:51:33 DEBUG[12876] chan_iax2.c: Allocate call number
Aug 26 10:51:33 DEBUG[12876] chan_iax2.c: New max nontrunk callno is 3
Aug 26 10:51:33 DEBUG[12876] chan_iax2.c: Creating new call structure 2
Aug 26 10:51:33 DEBUG[12876] chan_iax2.c: Registration created on call 2
Aug 26 10:51:36 VERBOSE[12876] logger.c: 
<-- SIP read from 10.2.5.99:5060: 
REGISTER sip:10.2.5.2 SIP/2.0
Via: SIP/2.0/UDP 10.2.5.99:5060
Call-ID: 00001557-f0f0e5a7-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx
Contact: "mitch" <sip:mitch-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx>
CSeq: 26111 REGISTER
From: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=00000918-f0f0f9e8
Supported: timer
To: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>
Max-Forwards: 70
User-Agent: ipDialog SipTone 1.2.0 rc Z_21 UA
Expires: 3600
Content-Length: 0


Aug 26 10:51:36 VERBOSE[12876] logger.c: --- (12 headers 0 lines)Aug 26 
10:51:36 VERBOSE[12876] logger.c: --- (12 headers 0 lines)---
Aug 26 10:51:36 DEBUG[12876] chan_sip.c: Allocating new SIP dialog for 
00001557-f0f0e5a7-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx - REGISTER (No RTP)
Aug 26 10:51:36 VERBOSE[12876] logger.c: Using latest request as basis request
Aug 26 10:51:36 VERBOSE[12876] logger.c: Sending to 10.2.5.99 : 5060 (non-NAT)
Aug 26 10:51:36 DEBUG[12876] chan_sip.c: chan_sip.c:register_verify: 
uri="sip:10.2.5.2"
Aug 26 10:51:36 VERBOSE[12876] logger.c: Transmitting (no NAT) to 
10.2.5.99:5060:
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.2.5.99:5060
From: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=00000918-f0f0f9e8
To: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=as60ebb13a
Call-ID: 00001557-f0f0e5a7-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx
CSeq: 26111 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY
Contact: <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>
Content-Length: 0


---
Aug 26 10:51:36 NOTICE[12876] chan_sip.c: Registration from '"mitch" 
<sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>' failed for '10.2.5.99'
Aug 26 10:51:36 VERBOSE[12876] logger.c: Scheduling destruction of call 
'00001557-f0f0e5a7-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx' in 15000 ms
Aug 26 10:51:36 VERBOSE[12876] logger.c: 
<-- SIP read from 10.2.5.99:5060: 
SUBSCRIBE sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx SIP/2.0
Via: SIP/2.0/UDP 10.2.5.99:5060
Call-ID: 00004ee4-f0f0be14-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx
Contact: "mitch" <sip:mitch-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx>
CSeq: 26111 SUBSCRIBE
From: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=000022a0-f0f0d250
Supported: timer
To: "999" <sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx>
Max-Forwards: 70
Event: message-summary
User-Agent: ipDialog SipTone 1.2.0 rc Z_21 UA
Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,SUBSCRIBE,INFO,NOTIFY,REFER,MESSAGE
Expires: 86400
Content-Length: 0


Aug 26 10:51:36 VERBOSE[12876] logger.c: --- (14 headers 0 lines)Aug 26 
10:51:36 VERBOSE[12876] logger.c: --- (14 headers 0 lines)---
Aug 26 10:51:36 DEBUG[12876] chan_sip.c: Allocating new SIP dialog for 
00004ee4-f0f0be14-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx - SUBSCRIBE (No RTP)
Aug 26 10:51:36 VERBOSE[12876] logger.c: Using latest SUBSCRIBE request as 
basis request
Aug 26 10:51:36 VERBOSE[12876] logger.c: Sending to 10.2.5.99 : 5060 (non-NAT)
Aug 26 10:51:36 VERBOSE[12876] logger.c: Found no matching peer or user for 
'10.2.5.99:5060'
Aug 26 10:51:36 VERBOSE[12876] logger.c: Looking for 999 in from-sip-external
Aug 26 10:51:36 VERBOSE[12876] logger.c: Transmitting (no NAT) to 
10.2.5.99:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.2.5.99:5060
From: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=000022a0-f0f0d250
To: "999" <sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=as5bb5229d
Call-ID: 00004ee4-f0f0be14-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx
CSeq: 26111 SUBSCRIBE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY
Expires: 3600
Contact: <sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;expires=3600
Content-Length: 0


---
Aug 26 10:51:36 VERBOSE[12876] logger.c: Scheduling destruction of call 
'00004ee4-f0f0be14-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx' in 3610000 ms
Aug 26 10:51:36 VERBOSE[12876] logger.c: Reliably Transmitting (no NAT) to 
10.2.5.99:5060:
NOTIFY sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx SIP/2.0
Via: SIP/2.0/UDP 10.2.5.2:5060;branch=z9hG4bK470a7ffa;rport
From: "999" <sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=as5bb5229d
To: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=000022a0-f0f0d250
Contact: <sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx>
Call-ID: 00004ee4-f0f0be14-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Event: presence
Subscription-State: active
Content-Type: application/xpidf+xml
Content-Length: 335

<?xml version="1.0"?>
<!DOCTYPE presence PUBLIC "-//IETF//DTD RFCxxxx XPIDF 1.0//EN" "xpidf.dtd">
<presence>
<presentity uri="sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx;method=SUBSCRIBE" />
<atom id="999">
<address uri="sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx;user=ip" priority="0.800000">
<status status="open" />
<msnsubstatus substatus="online" />
</address>
</atom>
</presence>

---
Aug 26 10:51:37 VERBOSE[12876] logger.c: 
<-- SIP read from 10.2.5.99:5060: 
SIP/2.0 415 Unsupported Media Type
Via: SIP/2.0/UDP 10.2.5.2:5060;branch=z9hG4bK470a7ffa;rport
Call-ID: 00004ee4-f0f0be14-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx
Contact: "mitch" <sip:mitch-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx>
CSeq: 102 NOTIFY
From: "999" <sip:999-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=as5bb5229d
Supported: timer
To: "mitch" <sip:mitch-CGvKMmOvYys@xxxxxxxxxxxxxxxx>;tag=000022a0-f0f0d250
Server: ipDialog SipTone 1.2.0 rc Z_21 UAS
Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,SUBSCRIBE,INFO,NOTIFY,REFER,MESSAGE
Content-Length: 0


Aug 26 10:51:37 VERBOSE[12876] logger.c: --- (11 headers 0 lines)Aug 26 
10:51:37 VERBOSE[12876] logger.c: --- (11 headers 0 lines)---
Aug 26 10:51:37 DEBUG[12876] chan_sip.c: Stopping retransmission on 
'00004ee4-f0f0be14-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx' of Request 102: 
Match Found
Aug 26 10:51:37 VERBOSE[12876] logger.c: Response message NOTIFY arrived
Aug 26 10:51:51 DEBUG[12876] chan_sip.c: Auto destroying call 
'00001557-f0f0e5a7-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx'
Aug 26 10:51:51 VERBOSE[12876] logger.c: Destroying call 
'00001557-f0f0e5a7-2+t+ymLpmFJuxbqukCiDuA@xxxxxxxxxxxxxxxx'

mitch.vcf
Description: Vcard