[asterisk-dev] chan_sip.c - no reply to critical packet
Gregory Boehnlein
damin at nacs.net
Fri Jan 30 18:55:46 CST 2009
> > [Jan 30 10:49:49] Receiving INFO!
> > [Jan 30 10:49:49] Transmitting (no NAT) to 207.166.192.180:5060:
> > SIP/2.0 403 Unauthorized
> > Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
> > 17:DS0";branch=z9hG4bK9724104E;received=207.166.192.180
> > From: <sip:2164864965 at 207.166.192.180>;tag=5BD698E8-75
> > To: <sip:2169203080 at 207.166.192.174>;tag=as7f89f1a4
> > Call-ID: 7592F3FA-EE1C11DD-8E78B0F4-24748FB1 at 207.166.192.180
> > CSeq: 102 INFO
> > User-Agent: N2Net Univoice
> > Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
> > Content-Length: 0
>
> This INFO message, and the subsequent 403 response, did not show up in
> your call trace, but they are in the debug log. My suspicion is that
> Asterisk 1.2 is getting confused by the reception of the INFO message
> when the dialog establishment has not been completed yet.
Hmm.. I wonder why the Cisco is sending an Info request? The only thing that
I think that might be causing this is the setting "isdn supp-service name
calling" which is supposed to map Q.931 calling-name attributes into a SIP
message. Since the Q.931 message comes a split-second after the initial call
setup on the PRI, the Cisco probably sends a second packet w/ the Calling
Name. All the Cisco documentation says about the feature is:
"isdn supp-service name calling
To set the calling name display parameters sent out an ISDN serial
interface, use the isdn supp-service name calling command in interface
configuration mode. To disable calling name delivery, use the no form of
this command."
Here is another call trace. This call path is similar, and I see the INFO
and the 403 Unauthorized again.
[Jan 30 11:06:03] Sending to 207.166.192.180 : 5060 (non-NAT)
[Jan 30 11:06:03] Found RTP audio format 0
[Jan 30 11:06:03] Found RTP audio format 18
[Jan 30 11:06:03] Found RTP audio format 101
[Jan 30 11:06:03] Peer audio RTP is at port 207.166.192.180:17456
[Jan 30 11:06:03] Found description format PCMU
[Jan 30 11:06:03] Found description format G729
[Jan 30 11:06:03] Found description format telephone-event
[Jan 30 11:06:03] Capabilities: us - 0x104 (ulaw|g729), peer - audio=0x104
(ulaw|g729)/video=0x0 (nothing), combined - 0x104 (ulaw|g729)
[Jan 30 11:06:03] Non-codec capabilities: us - 0x1 (telephone-event), peer -
0x1 (telephone-event), combined - 0x1 (telephone-event)
[Jan 30 11:06:03] Looking for 4408243955 in inbound (domain 207.166.192.174)
[Jan 30 11:06:03] list_route: hop: <sip:8585361444 at 207.166.192.180:5060>
[Jan 30 11:06:04] Transmitting (no NAT) to 207.166.192.180:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Length: 0
---
[Jan 30 11:06:04] -- Executing Macro("SIP/207.166.192.180-b796a438",
"set-cdr-info-static|09056") in new stack
[Jan 30 11:06:04] -- Executing Set("SIP/207.166.192.180-b796a438",
"CDR(accountcode)=09056") in new stack
[Jan 30 11:06:04] -- Executing Set("SIP/207.166.192.180-b796a438",
"CDR(userfield)=inbound") in new stack
[Jan 30 11:06:04] -- Executing
SetAMAFlags("SIP/207.166.192.180-b796a438", "billing") in new stack
[Jan 30 11:06:04] -- Executing Dial("SIP/207.166.192.180-b796a438",
"IAX2/oswaldmh/4408243955") in new stack
[Jan 30 11:06:04] -- Called oswaldmh/4408243955
[Jan 30 11:06:04] -- Call accepted by 207.166.196.50 (format ulaw)
[Jan 30 11:06:04] -- Format for call is ulaw
[Jan 30 11:06:04] -- IAX2/oswaldmh-17 is ringing
[Jan 30 11:06:04] Transmitting (no NAT) to 207.166.192.180:5060:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Length: 0
---
[Jan 30 11:06:04] Receiving INFO!
[Jan 30 11:06:04] Transmitting (no NAT) to 207.166.192.180:5060:
SIP/2.0 403 Unauthorized
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9787836;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 102 INFO
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
---
[Jan 30 11:06:10] -- IAX2/oswaldmh-17 stopped sounds
[Jan 30 11:06:10] -- IAX2/oswaldmh-17 answered
SIP/207.166.192.180-b796a438
[Jan 30 11:06:10] We're at 207.166.192.174 port 11626
[Jan 30 11:06:10] Adding codec 0x4 (ulaw) to SDP
[Jan 30 11:06:10] Adding codec 0x100 (g729) to SDP
[Jan 30 11:06:10] Adding non-codec 0x1 (telephone-event) to SDP
[Jan 30 11:06:10] Reliably Transmitting (no NAT) to 207.166.192.180:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Type: application/sdp
Content-Length: 267
v=0
o=root 9987 9987 IN IP4 207.166.192.174
s=session
c=IN IP4 207.166.192.174
t=0 0
m=audio 11626 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 - - - -
---
[Jan 30 11:06:10] Retransmitting #1 (no NAT) to 207.166.192.180:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Type: application/sdp
Content-Length: 267
v=0
o=root 9987 9987 IN IP4 207.166.192.174
s=session
c=IN IP4 207.166.192.174
t=0 0
m=audio 11626 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 - - - -
---
[Jan 30 11:06:10] Retransmitting #2 (no NAT) to 207.166.192.180:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Type: application/sdp
Content-Length: 267
v=0
o=root 9987 9987 IN IP4 207.166.192.174
s=session
c=IN IP4 207.166.192.174
t=0 0
m=audio 11626 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 - - - -
---
[Jan 30 11:06:10] Retransmitting #3 (no NAT) to 207.166.192.180:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Type: application/sdp
Content-Length: 267
v=0
o=root 9987 9987 IN IP4 207.166.192.174
s=session
c=IN IP4 207.166.192.174
t=0 0
m=audio 11626 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 - - - -
---
[Jan 30 11:06:11] Retransmitting #4 (no NAT) to 207.166.192.180:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Type: application/sdp
Content-Length: 267
v=0
o=root 9987 9987 IN IP4 207.166.192.174
s=session
c=IN IP4 207.166.192.174
t=0 0
m=audio 11626 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 - - - -
---
[Jan 30 11:06:11] Retransmitting #5 (no NAT) to 207.166.192.180:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Type: application/sdp
Content-Length: 267
v=0
o=root 9987 9987 IN IP4 207.166.192.174
s=session
c=IN IP4 207.166.192.174
t=0 0
m=audio 11626 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 - - - -
---
[Jan 30 11:06:12] Retransmitting #6 (no NAT) to 207.166.192.180:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 207.166.192.180:5060;x-ds0num="ISDN 1/0:23 1/0:DS1
5:DS0";branch=z9hG4bK9786190E;received=207.166.192.180
From: <sip:8585361444 at 207.166.192.180>;tag=5BE5793C-1C2C
To: <sip:4408243955 at 207.166.192.174>;tag=as3292ad3f
Call-ID: BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180
CSeq: 101 INVITE
User-Agent: N2Net Univoice
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:4408243955 at 207.166.192.174>
Content-Type: application/sdp
Content-Length: 267
v=0
o=root 9987 9987 IN IP4 207.166.192.174
s=session
c=IN IP4 207.166.192.174
t=0 0
m=audio 11626 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 - - - -
---
[Jan 30 11:06:13] WARNING[11080]: chan_sip.c:1229 retrans_pkt: Maximum
retries exceeded on transmission
BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180 for seqno 101 (Critical
Response)
[Jan 30 11:06:13] WARNING[11080]: chan_sip.c:1246 retrans_pkt: Hanging up
call BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180 - no reply to our
critical packet.
[Jan 30 11:06:13] -- Hungup 'IAX2/oswaldmh-17'
[Jan 30 11:06:13] == Spawn extension (inbound, 4408243955, 2) exited
non-zero on 'SIP/207.166.192.180-b796a438'
[Jan 30 11:06:14] Destroying call
'BAB06EA6-EE1E11DD-8F18B0F4-24748FB1 at 207.166.192.180'
More information about the asterisk-dev
mailing list