[asterisk-users] Re: DUNDi with SIP

Douglas Garstang dgarstang at oneeighty.com
Thu Aug 3 08:43:22 MST 2006


Thanks Bradley.

Here's a full sip console trace on the first pbx box.

xxx.yyy.128.18(phone 3254101): Originating phone, registered on pbx1.
xxx.yyy.142.162: pbx1
xxx.yyy.142.163: pbx2

Do you know what you are looking for?

Doug.

[Aug  3 09:40:28] 
<-- SIP read from xxx.yyy.128.18:5060: 
INVITE sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bKeac2a6619BA84D58
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>
CSeq: 1 INVITE
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
Contact: <sip:3254101 at xxx.yyy.128.18>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER
User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.6.3.0067
Supported: 100rel,replace
Allow-Events: talk,hold,conference
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 229

v=0
o=- 1154619309 1154619309 IN IP4 xxx.yyy.128.18
s=Polycom IP Phone
c=IN IP4 xxx.yyy.128.18
t=0 0
a=sendrecv
m=audio 2260 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000

[Aug  3 09:40:28] --- (14 headers 10 lines)[Aug  3 09:40:28] ---
[Aug  3 09:40:28] Using INVITE request as basis request - 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
[Aug  3 09:40:28] Sending to xxx.yyy.128.18 : 5060 (non-NAT)
[Aug  3 09:40:28] Reliably Transmitting (no NAT) to xxx.yyy.128.18:5060:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bKeac2a6619BA84D58;received=xxx.yyy.128.18
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>;tag=as7757655f
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
CSeq: 1 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.162>
Proxy-Authenticate: Digest realm="ipt.twoeighty.com", nonce="673c57e6"
Content-Length: 0


---
[Aug  3 09:40:28] Scheduling destruction of call '86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18' in 15000 ms
[Aug  3 09:40:28] Found user '3254101'
[Aug  3 09:40:28] 
<-- SIP read from xxx.yyy.128.18:5060: 
ACK sip:9220370 at labpbx1.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bKeac2a6619BA84D58
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>;tag=as7757655f
CSeq: 1 ACK
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
Contact: <sip:3254101 at xxx.yyy.128.18>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER
User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.6.3.0067
Max-Forwards: 70
Content-Length: 0


[Aug  3 09:40:28] --- (11 headers 0 lines)[Aug  3 09:40:28] ---
[Aug  3 09:40:28] 
<-- SIP read from xxx.yyy.128.18:5060: 
INVITE sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bK23dd36ae56C5E669
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>
CSeq: 2 INVITE
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
Contact: <sip:3254101 at xxx.yyy.128.18>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER
User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.6.3.0067
Supported: 100rel,replace
Allow-Events: talk,hold,conference
Proxy-Authorization: Digest username="3254101", realm="ipt.twoeighty.com", nonce="673c57e6", uri="sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone", response="9d5875c8a16dfdf29ac5a43a02e44eec", algorithm=MD5
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 229

v=0
o=- 1154619309 1154619309 IN IP4 xxx.yyy.128.18
s=Polycom IP Phone
c=IN IP4 xxx.yyy.128.18
t=0 0
a=sendrecv
m=audio 2260 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000

[Aug  3 09:40:28] --- (15 headers 10 lines)[Aug  3 09:40:28] ---
[Aug  3 09:40:28] Using INVITE request as basis request - 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
[Aug  3 09:40:28] Sending to xxx.yyy.128.18 : 5060 (non-NAT)
[Aug  3 09:40:28] Found user '3254101'
[Aug  3 09:40:28] Found RTP audio format 0
[Aug  3 09:40:28] Found RTP audio format 18
[Aug  3 09:40:28] Found RTP audio format 101
[Aug  3 09:40:28] Peer audio RTP is at port xxx.yyy.128.18:2260
[Aug  3 09:40:28] Found description format PCMU
[Aug  3 09:40:28] Found description format G729
[Aug  3 09:40:28] Found description format telephone-event
[Aug  3 09:40:28] Capabilities: us - 0x104 (ulaw|g729), peer - audio=0x104 (ulaw|g729)/video=0x0 (nothing), combined - 0x104 (ulaw|g729)
[Aug  3 09:40:28] Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
[Aug  3 09:40:28] Looking for 9220370 in pbx_betty_start (domain labpbx1.ipt.twoeighty.com)
[Aug  3 09:40:28] list_route: hop: <sip:3254101 at xxx.yyy.128.18>
[Aug  3 09:40:28] Transmitting (no NAT) to xxx.yyy.128.18:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bK23dd36ae56C5E669;received=xxx.yyy.128.18
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.162>
Content-Length: 0


---
[Aug  3 09:40:28]     -- Executing NoOp("SIP/3254101-3ebc", "*** OnNet originated call "Chocolate Chip" <3254101> -> 9220370") in new stack
[Aug  3 09:40:28]     -- Executing AGI("SIP/3254101-3ebc", "ipt/originator.py") in new stack
[Aug  3 09:40:28]     -- Launched AGI Script /var/lib/asterisk/agi-bin/ipt/originator.py
[Aug  3 09:40:28]     -- AGI Script Executing Application: (SetAccount) Options: (9220370)
[Aug  3 09:40:28]     -- AGI Script Executing Application: (ChanIsAvail) Options: (SIP/9220370)
[Aug  3 09:40:28] Destroying call '46b578613a629ebb69171e8b7f9b8458 at xxx.yyy.142.162'
[Aug  3 09:40:28]     -- AGI Script Executing Application: (Dial) Options: (SIP/dundisip2/9220370)
[Aug  3 09:40:28] We're at xxx.yyy.142.162 port 27164
[Aug  3 09:40:28] Adding codec 0x4 (ulaw) to SDP
[Aug  3 09:40:28] Adding codec 0x100 (g729) to SDP
[Aug  3 09:40:28] Adding non-codec 0x1 (telephone-event) to SDP
[Aug  3 09:40:28] 14 headers, 12 lines
[Aug  3 09:40:28] Reliably Transmitting (no NAT) to xxx.yyy.142.163:5060:
INVITE sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK23000840;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Date: Thu, 03 Aug 2006 15:40:28 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 269

v=0
o=root 13082 13082 IN IP4 xxx.yyy.142.162
s=session
c=IN IP4 xxx.yyy.142.162
t=0 0
m=audio 27164 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

---
[Aug  3 09:40:28]     -- Called dundisip2/9220370
[Aug  3 09:40:28] 
<-- SIP read from xxx.yyy.142.163:5060: 
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK23000840;rport;received=xxx.yyy.142.162
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.163>
Proxy-Authenticate: Digest realm="ipt.twoeighty.com", nonce="122095ae"
Content-Length: 0


[Aug  3 09:40:28] --- (11 headers 0 lines)[Aug  3 09:40:28] ---
[Aug  3 09:40:28] Transmitting (no NAT) to xxx.yyy.142.163:5060:
ACK sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK23000840;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Content-Length: 0


---
[Aug  3 09:40:28] We're at xxx.yyy.142.162 port 27164
[Aug  3 09:40:28] Adding codec 0x4 (ulaw) to SDP
[Aug  3 09:40:28] Adding codec 0x100 (g729) to SDP
[Aug  3 09:40:28] Adding non-codec 0x1 (telephone-event) to SDP
[Aug  3 09:40:28] Reliably Transmitting (no NAT) to xxx.yyy.142.163:5060:
INVITE sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK188cc77a;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Proxy-Authorization: Digest username="dundisip2", realm="ipt.twoeighty.com", algorithm=MD5, uri="sip:9220370 at labpbx2.ipt.twoeighty.com", nonce="122095ae", response="c3812ae6a639df3827b26ff969acad23", opaque=""
Date: Thu, 03 Aug 2006 15:40:28 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 269

v=0
o=root 13082 13083 IN IP4 xxx.yyy.142.162
s=session
c=IN IP4 xxx.yyy.142.162
t=0 0
m=audio 27164 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

---
[Aug  3 09:40:28] 
<-- SIP read from xxx.yyy.142.163:5060: 
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK188cc77a;rport;received=xxx.yyy.142.162
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.163>
Content-Length: 0


[Aug  3 09:40:28] --- (10 headers 0 lines)[Aug  3 09:40:28] ---
[Aug  3 09:40:28] Transmitting (no NAT) to xxx.yyy.142.163:5060:
ACK sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK188cc77a;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 103 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Content-Length: 0


---
[Aug  3 09:40:28] WARNING[13063]: chan_sip.c:9696 handle_response_invite: Forbidden - wrong password on authentication for INVITE to '"Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86'
[Aug  3 09:40:28]     -- SIP/dundisip2-936f is circuit-busy
[Aug  3 09:40:28]   == Everyone is busy/congested at this time (1:0/1/0)
[Aug  3 09:40:28]     -- AGI Script Executing Application: (Dial) Options: (Local/u9220370 at global_vmdeposit)
[Aug  3 09:40:28]     -- Called u9220370 at global_vmdeposit
[Aug  3 09:40:28]     -- Executing Answer("Local/u9220370 at global_vmdeposit-3666,2", "") in new stack
[Aug  3 09:40:28]     -- Local/u9220370 at global_vmdeposit-3666,1 answered SIP/3254101-3ebc
[Aug  3 09:40:28]     -- Executing Wait("Local/u9220370 at global_vmdeposit-3666,2", "1") in new stack
[Aug  3 09:40:28] We're at xxx.yyy.142.162 port 28718
[Aug  3 09:40:28] Adding codec 0x4 (ulaw) to SDP
[Aug  3 09:40:28] Adding codec 0x100 (g729) to SDP
[Aug  3 09:40:28] Adding non-codec 0x1 (telephone-event) to SDP
[Aug  3 09:40:28] Reliably Transmitting (no NAT) to xxx.yyy.128.18:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bK23dd36ae56C5E669;received=xxx.yyy.128.18
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>;tag=as4889fec6
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.162>
Content-Type: application/sdp
Content-Length: 269

v=0
o=root 13082 13082 IN IP4 xxx.yyy.142.162
s=session
c=IN IP4 xxx.yyy.142.162
t=0 0
m=audio 28718 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

---
[Aug  3 09:40:28] 
<-- SIP read from xxx.yyy.128.18:5060: 
ACK sip:9220370 at xxx.yyy.142.162 SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bKfc84778d745812F4
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>;tag=as4889fec6
CSeq: 2 ACK
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
Contact: <sip:3254101 at xxx.yyy.128.18>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER
User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.6.3.0067
Max-Forwards: 70
Content-Length: 0


[Aug  3 09:40:28] --- (11 headers 0 lines)[Aug  3 09:40:28] ---
[Aug  3 09:40:28] Destroying call '7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162'
[Aug  3 09:40:29]   == Spawn extension (pbx_betty_start, 9220370, 2) exited non-zero on 'Local/u9220370 at global_vmdeposit-3666,2<ZOMBIE>'
[Aug  3 09:40:29]     -- Executing VoiceMail("SIP/3254101-3ebc", "u9220370 at voicemail") in new stack
uniqueid => 58
customer_id => 0
context => voicemail
mailbox => 9220370
password => 1234
operator => No
attach => Yes
delete => No
stamp => 0000-00-00 00:00:00
envelope => Yes
saycid => Yes
[Aug  3 09:40:29]     -- Playing 'vm-theperson' (language 'en')
[Aug  3 09:40:30] 
<-- SIP read from xxx.yyy.128.18:5060: 
BYE sip:9220370 at xxx.yyy.142.162 SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bKcaab7aa7CAA53996
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>;tag=as4889fec6
CSeq: 3 BYE
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
Contact: <sip:3254101 at xxx.yyy.128.18>
User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.6.3.0067
Proxy-Authorization: Digest username="3254101", realm="ipt.twoeighty.com", nonce="673c57e6", uri="sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone", response="9d5875c8a16dfdf29ac5a43a02e44eec", algorithm=MD5
Max-Forwards: 70
Content-Length: 0


[Aug  3 09:40:30] --- (11 headers 0 lines)[Aug  3 09:40:30] ---
[Aug  3 09:40:30] Sending to xxx.yyy.128.18 : 5060 (non-NAT)
[Aug  3 09:40:30] Transmitting (no NAT) to xxx.yyy.128.18:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xxx.yyy.128.18;branch=z9hG4bKcaab7aa7CAA53996;received=xxx.yyy.128.18
From: "Chocolate Chip" <sip:3254101 at labpbx1.ipt.twoeighty.com>;tag=6140575F-F74900CC
To: <sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone>;tag=as4889fec6
Call-ID: 86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18
CSeq: 3 BYE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.162>
Content-Length: 0


---
[Aug  3 09:40:30]   == Spawn extension (global_vmdeposit, u9220370, 3) exited non-zero on 'SIP/3254101-3ebc'
[Aug  3 09:40:31] Destroying call '86e7a3db-bc712205-5e89a5da at xxx.yyy.128.18'


and here's a full sip console trace on the second pbx box.

<-- SIP read from xxx.yyy.142.162:5060: 
INVITE sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK23000840;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Date: Thu, 03 Aug 2006 15:40:28 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 269

v=0
o=root 13082 13082 IN IP4 xxx.yyy.142.162
s=session
c=IN IP4 xxx.yyy.142.162
t=0 0
m=audio 27164 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

--- (14 headers 12 lines)---
Using INVITE request as basis request - 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
Sending to xxx.yyy.142.162 : 5060 (NAT)
Reliably Transmitting (no NAT) to xxx.yyy.142.162:5060:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK23000840;rport;received=xxx.yyy.142.162
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.163>
Proxy-Authenticate: Digest realm="ipt.twoeighty.com", nonce="122095ae"
Content-Length: 0


---
Scheduling destruction of call '7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162' in 15000 ms
Found user '3254101'

<-- SIP read from xxx.yyy.142.162:5060: 
ACK sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK23000840;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Content-Length: 0


--- (11 headers 0 lines)---

<-- SIP read from xxx.yyy.142.162:5060: 
INVITE sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK188cc77a;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Proxy-Authorization: Digest username="dundisip2", realm="ipt.twoeighty.com", algorithm=MD5, uri="sip:9220370 at labpbx2.ipt.twoeighty.com", nonce="122095ae", response="c3812ae6a639df3827b26ff969acad23", opaque=""
Date: Thu, 03 Aug 2006 15:40:28 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 269

v=0
o=root 13082 13083 IN IP4 xxx.yyy.142.162
s=session
c=IN IP4 xxx.yyy.142.162
t=0 0
m=audio 27164 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

--- (15 headers 12 lines)---
Using INVITE request as basis request - 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
Sending to xxx.yyy.142.162 : 5060 (NAT)
Found user '3254101'
Aug  3 09:40:25 NOTICE[10884]: chan_sip.c:10469 handle_request_invite: Failed to authenticate user "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
Reliably Transmitting (no NAT) to xxx.yyy.142.162:5060:
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK188cc77a;rport;received=xxx.yyy.142.162
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:9220370 at xxx.yyy.142.163>
Content-Length: 0


---

<-- SIP read from xxx.yyy.142.162:5060: 
ACK sip:9220370 at labpbx2.ipt.twoeighty.com SIP/2.0
Via: SIP/2.0/UDP xxx.yyy.142.162:5060;branch=z9hG4bK188cc77a;rport
From: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as097cbc86
To: <sip:9220370 at labpbx2.ipt.twoeighty.com>;tag=as004358bd
Contact: <sip:3254101 at xxx.yyy.142.162>
Call-ID: 7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162
CSeq: 103 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;privacy=off;screen=no
Content-Length: 0


--- (11 headers 0 lines)---
Destroying call '7cd3cdfc64631a6f243aed9968503ae2 at xxx.yyy.142.162'



> -----Original Message-----
> From: Watkins, Bradley
> [mailto:asterisk-users-bounces at lists.digium.com]On Behalf Of Watkins,
> Bradley
> Sent: Wednesday, August 02, 2006 6:31 PM
> To: Asterisk Users Mailing List - Non-Commercial Discussion
> Subject: RE: [asterisk-users] Re: DUNDi with SIP
> 
> 
> Could you perhaps post a sip debug from both of the Asterisk 
> consoles for the respective peers?
>  
> I think we're very close, and I definitely want to get this 
> working for you.
>  
> Regards,
> - Brad
> 
>   _____  
> 
> From: asterisk-users-bounces at lists.digium.com on behalf of 
> Douglas Garstang
> Sent: Wed 8/2/2006 7:16 PM
> To: Asterisk Users Mailing List - Non-Commercial Discussion
> Subject: RE: [asterisk-users] Re: DUNDi with SIP
> 
> 
> 
> Bradley,
> 
> I changed the type from friend to peer in sip.conf...
> 
> [dundisip1]
> type=peer
> secret=password
> insecure=very
> context=global_dundi_local
> host=labpbx1.ipt.twoeighty.com
> qualify=yes
> 
> [dundisip2]
> type=peer
> secret=password
> insecure=very
> context=global_dundi_local
> host=labpbx2.ipt.twoeighty.com
> qualify=yes
> 
> but that just yielded the same error...
> 
> [Aug  2 17:07:51] NOTICE[10971]: chan_sip.c:9685 
> handle_response_invite: Failed to authenticate on INVITE to 
> '"Chocolate Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as5e6e3efe'
> 
> Btw, here's a sip trace between the asterisk boxes...
> 
> Capturing on eth0
> 1   0.000000 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP/SDP 
> Request: INVITE 
> sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone, with 
> session description
> 2   0.000311 xxx.yyy.142.162 -> xxx.yyy.128.18 SIP Status: 
> 407 Proxy Authentication Required
> 3   0.177497 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP Request: 
> ACK sip:9220370 at labpbx1.ipt.oneeighty.com
> 4   0.329036 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP/SDP 
> Request: INVITE 
> sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone, with 
> session description
> 5   0.329492 xxx.yyy.142.162 -> xxx.yyy.128.18 SIP Status: 100 Trying
> 6   0.643513 xxx.yyy.142.162 -> xxx.yyy.142.163 SIP/SDP 
> Request: INVITE sip:9220370 at labpbx2.ipt.twoeighty.com, with 
> session description
> 7   0.644104 xxx.yyy.142.163 -> xxx.yyy.142.162 SIP Status: 
> 407 Proxy Authentication Required
> 8   0.644356 xxx.yyy.142.162 -> xxx.yyy.142.163 SIP Request: 
> ACK sip:9220370 at labpbx2.ipt.oneeighty.com
> 9   0.647990 xxx.yyy.142.162 -> xxx.yyy.128.18 SIP/SDP 
> Status: 200 OK, with session description
> 10   0.806136 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP Request: 
> ACK sip:9220370 at xxx.yyy.142.162
> 11   2.280664 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP Request: 
> BYE sip:9220370 at xxx.yyy.142.162
> 12   2.280780 xxx.yyy.142.162 -> xxx.yyy.128.18 SIP Status: 200 OK
> 
> You can see that the first system doesn't resend the INVITE 
> with the auth credentials as requested.
> 
> So, then I also put the username field, and sip.conf now 
> looks like this:
> 
> [dundisip1]
> type=peer
> username=dundisip1
> secret=password
> insecure=very
> context=global_dundi_local
> host=labpbx1.ipt.twoeighty.com
> qualify=yes
> 
> [dundisip2]
> type=peer
> username=dundisip2
> secret=password
> insecure=very
> context=global_dundi_local
> host=labpbx2.ipt.twoeeighty.com
> qualify=yes
> 
> and with dundi.conf as:
> 
> 180netsip => global_dundi_local,0,SIP,dundisip1/${NUMBER}
> 180netsip => global_dundi_local,0,SIP,dundisip2/${NUMBER}
> 
> A CLI lookup still yields:
> 
> *CLI> dundi lookup 9220370 at 180netsip
>   1.     0 SIP/dundisip2/9220370 (EXISTS|CANMATCH)
>      from 00:14:22:1e:2a:d0, expires in 0 s
> DUNDi lookup completed in 130 ms
> 
> HOWEVER, at attempt to dial results in this now:
> 
> [Aug  2 17:14:00] WARNING[11178]: chan_sip.c:9696 
> handle_response_invite: Forbidden - wrong password on 
> authentication for INVITE to '"Chocolate Chip" 
> <sip:3254101 at xxx.yyy.142.162>;tag=as6090e60f'
> 
> And here's the SIP trace for THAT!
> 
> 1   0.000000 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP/SDP 
> Request: INVITE 
> sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone, with 
> session description
> 2   0.000311 xxx.yyy.142.162 -> xxx.yyy.128.18 SIP Status: 
> 407 Proxy Authentication Required
> 3   0.158008 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP Request: 
> ACK sip:9220370 at labpbx1.ipt.twoeighty.com
> 4   0.295054 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP/SDP 
> Request: INVITE 
> sip:9220370 at labpbx1.ipt.twoeighty.com;user=phone, with 
> session description
> 5   0.300557 xxx.yyy.142.162 -> xxx.yyy.128.18 SIP Status: 100 Trying
> 6   0.602324 xxx.yyy.142.162 -> xxx.yyy.142.163 SIP/SDP 
> Request: INVITE sip:9220370 at labpbx2.ipt.twoeighty.com, with 
> session description
> 7   0.603002 xxx.yyy.142.163 -> xxx.yyy.142.162 SIP Status: 
> 407 Proxy Authentication Required
> 8   0.603303 xxx.yyy.142.162 -> xxx.yyy.142.163 SIP Request: 
> ACK sip:9220370 at labpbx2.ipt.twoeighty.com
> 9   0.603485 xxx.yyy.142.162 -> xxx.yyy.142.163 SIP/SDP 
> Request: INVITE sip:9220370 at labpbx2.ipt.twoeighty.com, with 
> session description
> 10   0.604251 xxx.yyy.142.163 -> xxx.yyy.142.162 SIP Status: 
> 403 Forbidden
> 11   0.604553 xxx.yyy.142.162 -> xxx.yyy.142.163 SIP Request: 
> ACK sip:9220370 at labpbx2.ipt.twoeighty.com
> 12   0.608324 xxx.yyy.142.162 -> xxx.yyy.128.18 SIP/SDP 
> Status: 200 OK, with session description
> 13   0.749668 xxx.yyy.128.18 -> xxx.yyy.142.162 SIP Request: 
> ACK sip:9220370 at xxx.yyy.142.162
> 
> And on the second Asterisk console we have logged:
> 
> Aug  2 17:13:57 NOTICE[29764]: chan_sip.c:10469 
> handle_request_invite: Failed to authenticate user "Chocolate 
> Chip" <sip:3254101 at xxx.yyy.142.162>;tag=as6090e60f
> 
> Ugh... not having much luck with this...
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> > -----Original Message-----
> > From: Watkins, Bradley [mailto:Bradley.Watkins at compuware.com]
> > Sent: Wednesday, August 02, 2006 5:01 PM
> > To: Asterisk Users Mailing List - Non-Commercial Discussion
> > Subject: RE: [asterisk-users] Re: DUNDi with SIP
> >
> >
> > Try putting a username= in the peer (BTW, use peer not friend)
> > definitions.  You appear to be attempting to authenticate as the
> > originating callerid (3254101).
> >
> > - Brad
> >
> > -----Original Message-----
> > From: asterisk-users-bounces at lists.digium.com
> > [mailto:asterisk-users-bounces at lists.digium.com] On Behalf 
> Of Douglas
> > Garstang
> > Sent: Wednesday, August 02, 2006 6:45 PM
> > To: Asterisk Users Mailing List - Non-Commercial Discussion
> > Subject: RE: [asterisk-users] Re: DUNDi with SIP
> >
> > > -----Original Message-----
> > > From: Aaron Daniel [mailto:amdtech at shsu.edu]
> > > Sent: Wednesday, August 02, 2006 4:02 PM
> > > To: Asterisk Users Mailing List - Non-Commercial Discussion
> > > Subject: RE: [asterisk-users] Re: DUNDi with SIP
> > >
> > >
> > > On Wed, 2006-08-02 at 15:52 -0600, Douglas Garstang wrote:
> > > > Well yes, it looked dubious to me too, although I can't
> > > find the syntaxt documented anywhere.
> > > > However, that's what DUNDis giving me as a path to the phone!
> > > >
> > > > Something is screwed with DUNDi and SIP. Has ANYONE
> > > actually implemnted it?
> > > > I can't find it documented anywhere
> > > >
> > > > Doug.
> > >
> > > DUNDi gives you only what you give it to give you.  You're the one
> > > that needs to set the dial string correctly in DUNDi to get
> > one back
> > > that works.  DUNDi is only as automatic as you let it be.
> > >
> > > This is what ours looks like.  We don't use the iax
> > versions (mainly
> > > cause I want a homogenous SIP system), but we have entries
> > in sip.conf
> >
> > > include files for each of the servers so we just dial
> > > ${server}/${number}.  This has been working for us for
> > about 2 months
> > > now, pretty much flawlessly as long as the phone's registered.
> > >
> > > e164 => dundi-extens,0,SIP,scm1/${NUMBER} e164-iax =>
> > > dundi-extens,0,IAX2,dundi:${SECRET}@scm1/${NUMBER}
> > > internal => dundi-extens,0,SIP,scm1/${NUMBER} internal-iax =>
> > > dundi-extens,0,IAX2,dundi:${SECRET}@scm1/${NUMBER}
> > >
> > > [scm1]
> > > type=friend
> > > secret=p4ssw0rd
> > > insecure=very
> > > context=incoming
> > > host=scm1.shsu.edu
> > > qualify=yes
> > > nat=no
> >
> > Aaron, while not really sure what I was doing, but extending
> > beyond your
> > example, I gave this a shot:
> >
> > dundi.conf:
> > 180netsip => global_dundi_local,0,SIP,dundisip1/${NUMBER}
> > 180netsip => global_dundi_local,0,SIP,dundisip2/${NUMBER}
> >
> > sip.conf:
> > [dundisip1]
> > type=friend
> > secret=password
> > insecure=very
> > context=global_dundi_local
> > host=labpbx1.ipt.twoeighty.com
> > qualify=yes
> >
> > [dundisip2]
> > type=friend
> > secret=password
> > insecure=very
> > context=global_dundi_local
> > host=labpbx2.ipt.twoeighty.com
> > qualify=yes
> >
> > A CLI lookup looks better...
> >
> > *CLI> dundi lookup 9220370 at 180netsip
> >   1.     0 SIP/dundisip2/9220370 (EXISTS|CANMATCH)
> >      from 00:14:22:1e:2a:d0, expires in 0 s DUNDi lookup 
> completed in
> > 107 ms
> >
> > However, my dial still fails...
> >
> > [Aug  2 16:42:44]     -- AGI Script Executing Application: (Dial)
> > Options: (SIP/dundisip2/9220370)
> > [Aug  2 16:42:44]     -- Called dundisip2/9220370
> > [Aug  2 16:42:44] NOTICE[10474]: chan_sip.c:9685
> > handle_response_invite:
> > Failed to authenticate on INVITE to '"Chocolate Chip"
> > <sip:3254101 at xxx.yyy.142.162>;tag=as4f13a2f1'
> > [Aug  2 16:42:44]     -- SIP/dundisip2-7b30 is circuit-busy
> >
> >
> > _______________________________________________
> > --Bandwidth and Colocation provided by Easynews.com --
> >
> > asterisk-users mailing list
> > To UNSUBSCRIBE or update options visit:
> >    http://lists.digium.com/mailman/listinfo/asterisk-users
> >
> > The contents of this e-mail are intended for the named
> > addressee only. It contains information that may be
> > confidential. Unless you are the named addressee or an
> > authorized designee, you may not copy or use it, or disclose
> > it to anyone else. If you received it in error please notify
> > us immediately and then destroy it.
> > _______________________________________________
> > --Bandwidth and Colocation provided by Easynews.com --
> >
> > asterisk-users mailing list
> > To UNSUBSCRIBE or update options visit:
> >    http://lists.digium.com/mailman/listinfo/asterisk-users
> >
> _______________________________________________
> --Bandwidth and Colocation provided by Easynews.com --
> 
> asterisk-users mailing list
> To UNSUBSCRIBE or update options visit:
>    http://lists.digium.com/mailman/listinfo/asterisk-users
> 
> 
> 
> The contents of this e-mail are intended for the named 
> addressee only. It contains information that may be 
> confidential. Unless you are the named addressee or an 
> authorized designee, you may not copy or use it, or disclose 
> it to anyone else. If you received it in error please notify 
> us immediately and then destroy it. 
> 



More information about the asterisk-users mailing list