[asterisk-bugs] [Asterisk 0008078]: T38 relay doesn't work between Audiocodes Tulip AC494 ATAs

noreply at bugs.digium.com noreply at bugs.digium.com
Mon Oct 1 10:27:24 CDT 2007


A NOTE has been added to this issue. 
====================================================================== 
http://bugs.digium.com/view.php?id=8078 
====================================================================== 
Reported By:                candlerb
Assigned To:                oej
====================================================================== 
Project:                    Asterisk
Issue ID:                   8078
Category:                   Channels/chan_sip/T.38
Reproducibility:            always
Severity:                   major
Priority:                   normal
Status:                     feedback
Asterisk Version:            SVN 
SVN Branch (only for SVN checkouts, not tarball releases):  trunk 
SVN Revision (number only!): 44249 
Disclaimer on File?:        No 
Request Review:              
====================================================================== 
Date Submitted:             10-03-2006 07:32 CDT
Last Modified:              10-01-2007 10:27 CDT
====================================================================== 
Summary:                    T38 relay doesn't work between Audiocodes Tulip
AC494 ATAs
Description: 
When trying to use T38 fax relay between a pair of Audiocodes AC494's, when
fax is detected and one ATA sends a reinvite with

   m=image 5004 UDPTL t38
   a=T38FaxVersion:0
   ...

Asterisk rejects it with "488 Not acceptable here" and logs "Unsupported
SDP media type in offer: image 5004 UDPTL t38". As a result, fax relay
fails completely.

======================================================================
Relationships       ID      Summary
----------------------------------------------------------------------
related to          0007679 T.38 passthrough is not working between...
====================================================================== 

---------------------------------------------------------------------- 
 jon - 10-01-07 10:27  
---------------------------------------------------------------------- 
Here's some more info...

I'm trying to place a SIP call and expect the other end to answer with an
audio stream (not fax).

sip.conf entries

[jon]
regexten=3149
callerid="Jon Webster" <3149>
secret=********
type=friend
host=dynamic
nat=yes
canreinvite=no
context=local
qualify=yes
call-limit=100
busy-limit=99

[bandwidth]
type=friend
host=216.82.224.202
fromdomain=4.68.250.148
insecure=port,invite
videosupport=yes
canreinvite=yes
qualify=no

call with core debug on
pbx*CLI>
<--- SIP read from 71.52.101.157:5060 --->
INVITE sip:15559942999 at pbx.elephantoutlook.com SIP/2.0
Via: SIP/2.0/UDP 192.168.22.22:5060;branch=z9hG4bK-e9b67a65
From: "Jon"
<sip:jon at pbx.elephantoutlook.com>;tag=dcfcb54af05ff81do0
To: <sip:15559942999 at pbx.elephantoutlook.com>
Call-ID: ededa8e-5e7073a9 at 192.168.22.22
CSeq: 101 INVITE
Max-Forwards: 70
Contact: "Jon" <sip:jon at 192.168.22.22:5060>
Expires: 240
User-Agent: Sipura/SPA941-4.1.8
Content-Length: 403
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
Content-Type: application/sdp

v=0
o=- 329124448 329124448 IN IP4 192.168.22.22
s=-
c=IN IP4 192.168.22.22
t=0 0
m=audio 16458 RTP/AVP 0 2 4 8 18 96 97 98 101
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:4 G723/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729a/8000
a=rtpmap:96 G726-40/8000
a=rtpmap:97 G726-24/8000
a=rtpmap:98 G726-16/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv

<------------->
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 0:
INVITE sip:15559942999 at pbx.elephantoutlook.com SIP/2.0 (54)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 1:
Via: SIP/2.0/UDP 192.168.22.22:5060;branch=z9hG4bK-e9b67a65 (59)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 2:
From: "Jon"
<sip:jon at pbx.elephantoutlook.com>;tag=dcfcb54af05ff81do0 (76)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 3:
To: <sip:15559942999 at pbx.elephantoutlook.com> (45)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 4:
Call-ID: ededa8e-5e7073a9 at 192.168.22.22 (39)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 5:
CSeq: 101 INVITE (16)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 6:
Max-Forwards: 70 (16)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 7:
Contact: "Jon" <sip:jon at 192.168.22.22:5060> (51)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 8:
Expires: 240 (12)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 9:
User-Agent: Sipura/SPA941-4.1.8 (31)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header
10: Content-Length: 403 (19)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header
11: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header
12: Content-Type: application/sdp (29)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header
13:  (0)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line: v=0
(3)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line: o=-
329124448 329124448 IN IP4 192.168.22.22 (44)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line: s=-
(3)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
c=IN IP4 192.168.22.22 (22)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line: t=0
0 (5)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
m=audio 16458 RTP/AVP 0 2 4 8 18 96 97 98 101 (45)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:0 PCMU/8000 (20)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:2 G726-32/8000 (23)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:4 G723/8000 (20)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:8 PCMA/8000 (20)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:18 G729a/8000 (22)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:96 G726-40/8000 (24)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:97 G726-24/8000 (24)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:98 G726-16/8000 (24)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=rtpmap:101 telephone-event/8000 (33)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=fmtp:101 0-15 (15)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=ptime:30 (10)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4747 parse_request: Line:
a=sendrecv (10)
--- (13 headers 18 lines) ---
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:2695 do_setnat: Setting NAT
on RTP to Off
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:2700 do_setnat: Setting NAT
on VRTP to Off
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4450 sip_alloc: Allocating
new SIP dialog for ededa8e-5e7073a9 at 192.168.22.22 - INVITE (With RTP)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:14944 handle_request: ****
Received INVITE (5) - Command in SIP INVITE
Sending to 192.168.22.22 : 5060 (no NAT)
Using INVITE request as basis request - ededa8e-5e7073a9 at 192.168.22.22
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:2695 do_setnat: Setting NAT
on RTP to On
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:2700 do_setnat: Setting NAT
on VRTP to On

<--- Reliably Transmitting (NAT) to 71.52.101.157:5060 --->
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP
192.168.22.22:5060;branch=z9hG4bK-e9b67a65;received=71.52.101.157
From: "Jon"
<sip:jon at pbx.elephantoutlook.com>;tag=dcfcb54af05ff81do0
To: <sip:15559942999 at pbx.elephantoutlook.com>;tag=as65a84993
Call-ID: ededa8e-5e7073a9 at 192.168.22.22
CSeq: 101 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk",
nonce="39375bd1"
Content-Length: 0


<------------>
[Oct  1 10:47:48] DEBUG[26204]: sched.c:284 ast_sched_del: Attempted to
delete nonexistent schedule entry 0!
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:2037 __sip_reliable_xmit: ***
SIP TIMER: Initalizing retransmit timer on packet: Id 
http://bugs.digium.com/view.php?id=88922
Scheduling destruction of SIP dialog 'ededa8e-5e7073a9 at 192.168.22.22' in
32000 ms (Method: INVITE)
Found user 'jon'
pbx*CLI>
<--- SIP read from 71.52.101.157:5060 --->
ACK sip:15559942999 at pbx.elephantoutlook.com SIP/2.0
Via: SIP/2.0/UDP 192.168.22.22:5060;branch=z9hG4bK-e9b67a65
From: "Jon"
<sip:jon at pbx.elephantoutlook.com>;tag=dcfcb54af05ff81do0
To: <sip:15559942999 at pbx.elephantoutlook.com>;tag=as65a84993
Call-ID: ededa8e-5e7073a9 at 192.168.22.22
CSeq: 101 ACK
Max-Forwards: 70
Contact: "Jon" <sip:jon at 192.168.22.22:5060>
User-Agent: Sipura/SPA941-4.1.8
Content-Length: 0


<------------->
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 0:
ACK sip:15559942999 at pbx.elephantoutlook.com SIP/2.0 (51)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 1:
Via: SIP/2.0/UDP 192.168.22.22:5060;branch=z9hG4bK-e9b67a65 (59)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 2:
From: "Jon"
<sip:jon at pbx.elephantoutlook.com>;tag=dcfcb54af05ff81do0 (76)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 3:
To: <sip:15559942999 at pbx.elephantoutlook.com>;tag=as65a84993 (60)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 4:
Call-ID: ededa8e-5e7073a9 at 192.168.22.22 (39)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 5:
CSeq: 101 ACK (13)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 6:
Max-Forwards: 70 (16)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 7:
Contact: "Jon" <sip:jon at 192.168.22.22:5060> (51)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 8:
User-Agent: Sipura/SPA941-4.1.8 (31)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header 9:
Content-Length: 0 (17)
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4715 parse_request: Header
10:  (0)
--- (10 headers 0 lines) ---
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:4503 find_call: = Found Their
Call ID: ededa8e-5e7073a9 at 192.168.22.22 Their Tag dcfcb54af05ff81do0 Our
tag: as65a84993
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:14944 handle_request: ****
Received ACK (6) - Command in SIP ACK
[Oct  1 10:47:48] DEBUG[26204]: chan_sip.c:2148 __sip_ack: ** SIP TIMER:
Cancelling retransmit of packet (reply received) Retransid
http://bugs.digium.com/view.php?id=88922




More information about the asterisk-bugs mailing list