Problems with far end 183 w/ SDP

I have a asterisk box with a PRI connected that I am using as a media gateway for testing of another soft switch. I am trying to replicate a problem I have seen with that other soft switch in which when calling a number that utilizes eraly media for a complex IVR the call times out and is dropped by the soft switch before the far end sends the 200 message to place the call into a completed state.

In my attempts to use asterisk as a gateway I seem to see that asterisk is sending a 200 to the soft switch without being triggered to do so by any event on the PRI. Below is my configuration for the sip peer in sip.conf and also a log showing the output of the asterisk console with intense debuggin on on the PRI and SIP debugging on while the call was made. Note that prior to the 200 there has been a substantial period with no actrivity other than RR frames. If possible I would like to be able to prevet this behavior or raise the time for this to a user specified value in order to further my tests. Any assistance would be greatly appericiated. Thanks.

—SIP peer definition from sip.conf—

[10.100.249.6]
type=friend
host=10.100.249.6
context=outbound
insecure=very
canreinvite=no
dtmfmode=rfc2833

—END SIP peer—

—LOG FROM ASTERISK CONSOLE—

[ 00 01 01 03 ]
Supervisory frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 001 P/F: 1
0 bytes of data
– Restarting T203 counter

< [ 00 01 01 eb ]
< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 117 P/F: 1
< 0 bytes of data

– ACKing all packets from 116 to (but not including) 117
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
– Got RR response to our frame
– Restarting T203 counter

← SIP read from 10.100.249.6:5060:
INVITE sip:8004267378@10.100.0.30:5060 SIP/2.0
Via: SIP/2.0/UDP 10.100.249.6:5060;branch=z9hG4bK009770b901be004b
From: sip:2059495351@10.100.249.6:5060;hop-count=1;call-type=0;bgid=16778400;bgt=public;tag=gK0100f298
To: sip:8004267378@10.100.0.30:5060;ucid-node=1234;ucid-boot=101;ucid-call=1258291232
Call-ID: 1234101125829123200_0_17795@10.100.249.6
CSeq: 21325 INVITE
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,BYE,REGISTER,REFER,INFO,SUBSCRIBE,NOTIFY,PRACK,UPDATE,OPTIONS
Accept: application/sdp, application/isup, application/dtmf, application/dtmf-relay
Contact: sip:2059495351@10.100.249.6:5060;bgid=16778400;bgt=public
Remote-Party-ID: “2059495345” sip:2059495345@10.100.249.6:5060;id-context=plain;privacy=off
Supported: 100rel
Supported: timer
Session-Expires: 120
Min-SE: 120
Content-Length: 337
Content-Disposition: session; handling=optional
Content-Type: application/sdp

v=0
o=Sonus_Asx_UAC 11211 11717 IN IP4 10.100.249.6
s=SIP Media Capabilities
c=IN IP4 10.100.0.18
t=0 0
m=audio 16454 RTP/AVP 0 2 4 8 18 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 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=ptime:30

— (18 headers 15 lines)—
Using INVITE request as basis request - 1234101125829123200_0_17795@10.100.249.6
Sending to 10.100.249.6 : 5060 (non-NAT)
Found peer ‘10.100.249.6’
Found RTP audio format 0
Found RTP audio format 2
Found RTP audio format 4
Found RTP audio format 8
Found RTP audio format 18
Found RTP audio format 101
Peer audio RTP is at port 10.100.0.18:16454
Found description format PCMU
Found description format G726-32
Found description format G723
Found description format PCMA
Found description format G729
Found description format telephone-event
Capabilities: us - 0x8000e (gsm|ulaw|alaw|h263), peer - audio=0x11d (g723|ulaw|alaw|g726|g729)/video=0x0 (nothing), combined - 0xc (ulaw|alaw)
Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Looking for 8004267378 in outbound (domain 10.100.0.30)
list_route: hop: sip:2059495351@10.100.249.6:5060;bgid=16778400;bgt=public
Transmitting (no NAT) to 10.100.249.6:5060:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.100.249.6:5060;branch=z9hG4bK009770b901be004b;received=10.100.249.6
From: sip:2059495351@10.100.249.6:5060;hop-count=1;call-type=0;bgid=16778400;bgt=public;tag=gK0100f298
To: sip:8004267378@10.100.0.30:5060;ucid-node=1234;ucid-boot=101;ucid-call=1258291232
Call-ID: 1234101125829123200_0_17795@10.100.249.6
CSeq: 21325 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: sip:8004267378@10.100.0.30
Content-Length: 0

-- Executing Dial("SIP/5060-087d41e0", "Zap/g1/8004267378") in new stack

-- Requested transfer capability: 0x00 - SPEECH

[ 00 01 ea 02 08 02 00 33 05 04 03 80 90 a2 18 03 a9 83 81 1e 02 80 83 6c 0c 21 80 32 30 35 39 34 39 35 33 35 31 70 0b a1 38 30 30 34 32 36 37 33 37 38 ]
Informational frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
N(S): 117 0: 0
N(R): 001 P: 0
46 bytes of data

– Restarting T203 counter
Stopping T_203 timer
Starting T_200 timer

Protocol Discriminator: Q.931 (8) len=46
Call Ref: len= 2 (reference 51/0x33) (Originator)
Message type: SETUP (5)
[04 03 80 90 a2]

Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0)
Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
Ext: 1 User information layer 1: u-Law (34)

[18 03 a9 83 81]
Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
ChanSel: Reserved
Ext: 1 Coding: 0 Number Specified Channel Type: 3
Ext: 1 Channel: 1 ]

[1e 02 80 83]
Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0)
Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ]

[6c 0c 21 80 32 30 35 39 34 39 35 33 35 31]
Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
Presentation: Presentation permitted, user number not screened (0) ‘2059495351’ ]

[70 0b a1 38 30 30 34 32 36 37 33 37 38]
Called Number (len=13) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) ‘8004267378’ ]

– Called g1/8004267378

< [ 00 01 01 ec ]
< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 118 P/F: 0
< 0 bytes of data
– ACKing all packets from 116 to (but not including) 118
– ACKing packet 117, new txqueue is -1 (-1 means empty)
– Since there was nothing left, stopping T200 counter
– Nothing left, starting T203 counter
– Restarting T203 counter

We’re at 10.100.0.30 port 15074
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x8 (alaw) to SDP
Adding non-codec 0x1 (telephone-event) to SDP

Transmitting (no NAT) to 10.100.249.6:5060:
SIP/2.0 183 Session Progress

Via: SIP/2.0/UDP 10.100.249.6:5060;branch=z9hG4bK009770b901be004b;received=10.100.249.6
From: sip:2059495351@10.100.249.6:5060;hop-count=1;call-type=0;bgid=16778400;bgt=public;tag=gK0100f298
To: sip:8004267378@10.100.0.30:5060;ucid-node=1234;ucid-boot=101;ucid-call=1258291232;tag=as74d14234
Call-ID: 1234101125829123200_0_17795@10.100.249.6
CSeq: 21325 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: sip:8004267378@10.100.0.30
Content-Type: application/sdp
Content-Length: 236

v=0
o=root 2803 2803 IN IP4 10.100.0.30
s=session
c=IN IP4 10.100.0.30
t=0 0
m=audio 15074 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

< [ 02 01 02 ec 08 02 80 33 02 18 03 a9 83 81 ]
< Informational frame:
< SAPI: 00 C/R: 1 EA: 0
< TEI: 000 EA: 1
< N(S): 001 0: 0
< N(R): 118 P: 0
< 10 bytes of data
– ACKing all packets from 117 to (but not including) 118
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8) len=10
< Call Ref: len= 2 (reference 51/0x33) (Terminator)
< Message type: CALL PROCEEDING (2)

< [18 03 a9 83 81]
< Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
< ChanSel: Reserved
< Ext: 1 Coding: 0 Number Specified Channel Type: 3
< Ext: 1 Channel: 1 ]
Sending Receiver Ready (2)

[ 02 01 01 04 ]

Supervisory frame:
SAPI: 00 C/R: 1 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 002 P/F: 0
0 bytes of data
– Restarting T203 counter
– Restarting T203 counter

-- Zap/1-1 is proceeding passing it to SIP/5060-087d41e0

< [ 02 01 04 ec 08 02 80 33 03 08 02 82 ff 1e 02 82 81 ]

< Informational frame:
< SAPI: 00 C/R: 1 EA: 0
< TEI: 000 EA: 1
< N(S): 002 0: 0
< N(R): 118 P: 0
< 13 bytes of data
– ACKing all packets from 117 to (but not including) 118
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8) len=13
< Call Ref: len= 2 (reference 51/0x33) (Terminator)
< Message type: PROGRESS (3)

< [08 02 82 ff]
< Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
< Ext: 1 Cause: Unknown (127), class = Interworking (7) ]

< [1e 02 82 81]
< Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
< Ext: 1 Progress Description: Call is not end-to-end ISDN; further call progress information may be available inband. (1) ]
Sending Receiver Ready (3)

[ 02 01 01 06 ]

Supervisory frame:
SAPI: 00 C/R: 1 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 003 P/F: 0
0 bytes of data
– Restarting T203 counter
– Restarting T203 counter
– PROGRESS with cause code 127 received

-- Zap/1-1 is making progress passing it to SIP/5060-087d41e0

T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (3)

[ 00 01 01 07 ]

Supervisory frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 003 P/F: 1
0 bytes of data
– Restarting T203 counter

< [ 00 01 01 ed ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 118 P/F: 1
< 0 bytes of data
– ACKing all packets from 117 to (but not including) 118
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
– Got RR response to our frame
– Restarting T203 counter

T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (3)

[ 00 01 01 07 ]

Supervisory frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 003 P/F: 1
0 bytes of data
– Restarting T203 counter

< [ 00 01 01 ed ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 118 P/F: 1
< 0 bytes of data
– ACKing all packets from 117 to (but not including) 118
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
– Got RR response to our frame
– Restarting T203 counter

T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (3)

[ 00 01 01 07 ]

Supervisory frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 003 P/F: 1
0 bytes of data
– Restarting T203 counter

< [ 00 01 01 ed ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 118 P/F: 1
< 0 bytes of data
– ACKing all packets from 117 to (but not including) 118
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
– Got RR response to our frame
– Restarting T203 counter

-- Zap/1-1 answered SIP/5060-087d41e0

We’re at 10.100.0.30 port 15074
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x8 (alaw) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (no NAT) to 10.100.249.6:5060:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.100.249.6:5060;branch=z9hG4bK009770b901be004b;received=10.100.249.6

From: sip:2059495351@10.100.249.6:5060;hop-count=1;call-type=0;bgid=16778400;bgt=public;tag=gK0100f298

To: sip:8004267378@10.100.0.30:5060;ucid-node=1234;ucid-boot=101;ucid-call=1258291232;tag=as74d14234

Call-ID: 1234101125829123200_0_17795@10.100.249.6

CSeq: 21325 INVITE

User-Agent: Asterisk PBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Contact: sip:8004267378@10.100.0.30

Content-Type: application/sdp

Content-Length: 236

v=0

o=root 2803 2804 IN IP4 10.100.0.30

s=session

c=IN IP4 10.100.0.30

t=0 0

m=audio 15074 RTP/AVP 0 8 101

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -


← SIP read from 10.100.249.6:5060:
ACK sip:8004267378@10.100.0.30:5060 SIP/2.0

Via: SIP/2.0/UDP 10.100.249.6:5060;branch=z9hG4bK009879b701be004b

From: sip:2059495351@10.100.249.6:5060;hop-count=1;call-type=0;bgid=16778400;bgt=public;tag=gK0100f298

To: sip:8004267378@10.100.0.30:5060;ucid-node=1234;ucid-boot=101;ucid-call=1258291232;tag=as74d14234
Call-ID: 1234101125829123200_0_17795@10.100.249.6
CSeq: 21325 ACK
Max-Forwards: 70
Content-Length: 0

— (8 headers 0 lines)—

T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (3)

[ 00 01 01 07 ]

Supervisory frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 003 P/F: 1
0 bytes of data
– Restarting T203 counter

< [ 00 01 01 ed ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 118 P/F: 1
< 0 bytes of data
– ACKing all packets from 117 to (but not including) 118
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
– Got RR response to our frame
– Restarting T203 counter

T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (3)

[ 00 01 01 07 ]

Supervisory frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 003 P/F: 1
0 bytes of data
– Restarting T203 counter

< [ 00 01 01 ed ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 118 P/F: 1
< 0 bytes of data
– ACKing all packets from 117 to (but not including) 118
– Since there was nothing left, stopping T200 counter
– Stopping T203 counter since we got an ACK
– Nothing left, starting T203 counter
– Got RR response to our frame
– Restarting T203 counter

T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (3)

[ 00 01 01 07 ]

Supervisory frame:
SAPI: 00 C/R: 0 EA: 0
TEI: 000 EA: 1
Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
N(R): 003 P/F: 1
0 bytes of data
– Restarting T203 counter

< [ 00 01 01 ed ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 118 P/F: 1
< 0 bytes of data

—END LOG—