[OpenSER-Users] [MediaProxy] Sessions ends ("did timeout") during the INVITE
Iñaki Baz Castillo
ibc at in.ilimit.es
Wed Jul 9 10:43:27 CEST 2008
Hi, I'm suffering an important issue with MediaProxy in calls from/to my carrier
softswitch. Sometimes it seems that MediaProxy assigns a media port during
the INVITE but it closes it inmediatelly, so the following 183 forces the creation
of a different media port and obviously the media dies.
It works perfectly for long days but some days it has those problems. Please,
could you people help me in investigating if a problem in my OpenSer/MediaProxy?
I have a detailed real example. This is what happens:
---------------------------------------------------------------------------
09:28:46 - INVITE Client -> OpenSer (audio: 192.168.1.203:10370)
09:28:46 - INVITE OpenSer -> gateway (audio: 88.99.3.10:60696) <-- MediaProxy set 60696 port but inmediatelly ends the session.
09:28:48 - 183 Gateway -> OpenSer (audio: 66.11.6.142:45734)
09:28:48 - 183 OpenSer -> Client (audio: 88.99.3.10:60698) <-- Wrong port, MediaProxy set 60698 port !!!
09:28:49 - 200 Gateway -> OpenSer (audio: 66.11.6.142:45734)
09:28:49 - 200 OpenSer -> Client (audio: 88.99.3.10:60704) <-- Wrong port, MediaProxy set 60704 port !!!
---------------------------------------------------------------------------
This is the mediaproxy/proxydispatcher log. As you can see media port open
during INVITE is inmediatelly closed WHY?):
---------------------------------------------------------------------------------------
### 09:28:46
- 9:28:46 openser-host proxydispatcher[31874]: request 5be83da413f9dbd468a6d83818fb356d at sip.domain.net 192.168.1.203:10370:audio 88.99.1.192 sip.domain.net
local 66.11.0.144 remote Domain=20-=20Asterisk=20PBX info=from:216 at sip.domain.net,to:996511959 at sip.domain.net,fromtag:as3c11216c,totag:
- 09:28:46 openser-host mediaproxy[31869]: request 5be83da413f9dbd468a6d83818fb356d at sip.domain.net 192.168.1.203:10370:audio 88.99.1.192 sip.domain.net local
66.11.0.144 remote Domain=20-=20Asterisk=20PBX info=totag:,to:996511959 at sip.domain.net,from:216 at sip.domain.net,fromtag:as3c11216c
- 09:28:46 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: started. listening on 88.99.3.10:60696
- 09:28:46 openser-host mediaproxy[31869]: execution time: 0.60 ms
- 09:28:46 openser-host proxydispatcher[31874]: forwarding to mediaproxy on /var/run/mediaproxy.sock: got: '88.99.3.10 60696'
- 09:28:46 openser-host proxydispatcher[31874]: execution time: 2.45 ms
**************** ERROR: Why does it occur ??? ***********************
- 09:28:46 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: 0/0/0 packets, 0/0/0 bytes (caller/called/relayed)
- 09:28:46 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: ended (did timeout).
*********************************************************************
### 09:28:48
- 09:28:48 openser-host proxydispatcher[31874]: lookup 5be83da413f9dbd468a6d83818fb356d at sip.domain.net 66.11.6.142:45734:audio 66.11.0.144 sip.domain.net local
unknown unknown CS2000_NGSS/9.0 info=from:216 at sip.domain.net,to:996511959 at sip.domain.net,fromtag:as3c11216c,totag:4d764120338220087992847
- 09:28:48 openser-host mediaproxy[31869]: lookup 5be83da413f9dbd468a6d83818fb356d at sip.domain.net 66.11.6.142:45734:audio 66.11.0.144 sip.domain.net local
unknown unknown CS2000_NGSS/9.0 info=totag:4d764120338220087992847,to:996511959 at sip.domain.net,from:216 at sip.domain.net,fromtag:as3c11216c
- 09:28:48 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: started. listening on 88.99.3.10:60698
- 09:28:48 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: ended (did timeout).
### 09:28:58
- 09:28:58 openser-host proxydispatcher[31874]: lookup 5be83da413f9dbd468a6d83818fb356d at sip.domain.net 66.11.6.142:45734:audio 66.11.0.144 sip.domain.net local
unknown unknown CS2000_NGSS/9.0 info=from:216 at sip.domain.net,to:996511959 at sip.domain.net,fromtag:as3c11216c,totag:4d764120338220087992847
- 09:28:58 openser-host mediaproxy[31869]: lookup 5be83da413f9dbd468a6d83818fb356d at sip.domain.net 66.11.6.142:45734:audio 66.11.0.144 sip.domain.net local
unknown unknown CS2000_NGSS/9.0 info=totag:4d764120338220087992847,to:996511959 at sip.domain.net,from:216 at sip.domain.net,fromtag:as3c11216c
- 09:28:58 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: started. listening on 88.99.3.10:60704
- 09:28:58 openser-host mediaproxy[31869]: execution time: 0.62 ms
- 09:28:58 openser-host proxydispatcher[31874]: forwarding to mediaproxy on /var/run/mediaproxy.sock: got: '88.99.3.10 60704'
- 09:28:58 openser-host proxydispatcher[31874]: execution time: 1.15 ms
**************** ERROR: Why does it occur ??? ***********************
- 09:28:59 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: 0/0/0 packets, 0/0/0 bytes (caller/called/relayed)
- 09:28:59 openser-host mediaproxy[31869]: session 5be83da413f9dbd468a6d83818fb356d at sip.domain.net: ended (did timeout).
*********************************************************************
### 09:29:05
- 09:29:05 openser-host proxydispatcher[31874]: delete 5be83da413f9dbd468a6d83818fb356d at sip.domain.net info=
- 09:29:05 openser-host mediaproxy[31869]: delete 5be83da413f9dbd468a6d83818fb356d at sip.domain.net info=
---------------------------------------------------------------------------------------
I also attach the complete SIP capture of this real example, but I see nothing
wrong in it. The question is:
Why MediaProxy ends the session during the INVITE? why does it close the port?
Thanks a lot for any help.
--
Iñaki Baz Castillo
ibc at in.ilimit.es
-------------- next part --------------
Resumen
---------------------------------------------------------------------------
09:28:46 - INVITE Client -> OpenSer (audio: 192.168.1.203:10370)
09:28:46 - INVITE OpenSer -> gateway (audio: 88.99.3.10:60696) <-- MediaProxy set 60696 port.
09:28:48 - 183 Gateway -> OpenSer (audio: 66.11.6.142:45734)
09:28:48 - 183 OpenSer -> Client (audio: 88.99.3.10:60698) <-- Wrong port, MediaProxy set 60698 port !!!
09:28:49 - 200 Gateway -> OpenSer (audio: 66.11.6.142:45734)
09:28:49 - 200 OpenSer -> Client (audio: 88.99.3.10:60704) <-- Wrong port, MediaProxy set 60704 port !!!
---------------------------------------------------------------------------
# 09:28:46 - Client -> OpenSer (audio: 192.168.1.203:10370)
# ------------------------------------------------------------------------------
INVITE sip:996511959 at sip.domain.net SIP/2.0
Via: SIP/2.0/UDP 192.168.1.203:5060;branch=z9hG4bK5d2718e5;rport
From: "Bob" <sip:216 at sip.domain.net>;tag=as3c11216c
To: <sip:996511959 at sip.domain.net>
Contact: <sip:216 at 192.168.1.203>
Call-ID: 5be83da413f9dbd468a6d83818fb356d at sip.domain.net
CSeq: 103 INVITE
User-Agent: Domain - Asterisk PBX
Max-Forwards: 69
Proxy-Authorization: Digest username="domain", realm="sip.domain.net", algorithm=MD5, uri="sip:996511959 at sip.domain.net", nonce="387469da663047d490a1e996457875e1928ac1b1", response="c991585f7778f1f31f738c850213292f", opaque="", qop=auth, cnonce="5d3ecf41", nc=00000001
Date: Wed, 09 Jul 2008 07:26:00 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 312
v=0
o=root 19072 19073 IN IP4 192.168.1.203
s=session
c=IN IP4 192.168.1.203
t=0 0
m=audio 10370 RTP/AVP 8 18 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
# 09:28:46 - OpenSer -> gateway (audio: 88.99.3.10:60696)
# ------------------------------------------------------------------------------
INVITE sip:0034996511959 at 66.11.0.144 SIP/2.0
Record-Route: <sip:88.99.3.10;lr=on;ftag=as3c11216c>
Via: SIP/2.0/UDP 88.99.3.10:5060;branch=z9hG4bK6d.0b4e08c2.0
Via: SIP/2.0/UDP 192.168.1.203:5060;received=88.99.1.192;branch=z9hG4bK5d2718e5;rport=10000
From: "Bob" <sip:216 at sip.domain.net>;tag=as3c11216c
To: <sip:996511959 at sip.domain.net>
Contact: <sip:216 at 88.99.1.192:10000>
Call-ID: 5be83da413f9dbd468a6d83818fb356d at sip.domain.net
CSeq: 103 INVITE
User-Agent: Domain - Asterisk PBX
Max-Forwards: 69
Date: Wed, 09 Jul 2008 07:26:00 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 309
X-Called-E164: +34996511959
P-Asserted-Identity: <sip:986333375 at 88.99.3.10>
v=0
o=root 19072 19073 IN IP4 192.168.1.203
s=session
c=IN IP4 88.99.3.10
t=0 0
m=audio 60696 RTP/AVP 8 18 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
# Gateway -> OpenSer
# ------------------------------------------------------------------------------
SIP/2.0 100 Trying
From: "Bob"<sip:216 at sip.domain.net>;tag=as3c11216c
To: <sip:996511959 at sip.domain.net>;tag=4d764120338220087992847
Call-ID: 5be83da413f9dbd468a6d83818fb356d at sip.domain.net
CSeq: 103 INVITE
Allow: ACK,BYE,CANCEL,INVITE,OPTIONS,INFO,SUBSCRIBE,REFER,NOTIFY,PRACK
Via: SIP/2.0/UDP 88.99.3.10:5060;branch=z9hG4bK6d.0b4e08c2.0
Via: SIP/2.0/UDP 192.168.1.203:5060;received=88.99.1.192;rport=10000;branch=z9hG4bK5d2718e5
Contact: <sip:66.11.0.144:5060;transport=UDP>
Content-Length: 0
# 09:28:48 - Gateway -> OpenSer (audio: 66.11.6.142:45734)
# ------------------------------------------------------------------------------
SIP/2.0 183 Session Progress
From: "Bob"<sip:216 at sip.domain.net>;tag=as3c11216c
To: <sip:996511959 at sip.domain.net>;tag=4d764120338220087992847
Call-ID: 5be83da413f9dbd468a6d83818fb356d at sip.domain.net
CSeq: 103 INVITE
Server: CS2000_NGSS/9.0
Allow: ACK,BYE,CANCEL,INVITE,OPTIONS,INFO,SUBSCRIBE,REFER,NOTIFY,PRACK,UPDATE
Via: SIP/2.0/UDP 88.99.3.10:5060;branch=z9hG4bK6d.0b4e08c2.0
Via: SIP/2.0/UDP 192.168.1.203:5060;received=88.99.1.192;rport=10000;branch=z9hG4bK5d2718e5
Contact: <sip:66.11.0.144:5060;transport=UDP>
Record-Route: <sip:88.99.3.10;lr;ftag=as3c11216c>
Content-Type: application/SDP
Content-Length: 222
v=0
o=IWSPM 341282043 341282043 IN IP4 66.11.6.142
s=-
c=IN IP4 66.11.6.142
t=0 0
a=sqn: 0
a=cdsc: 1 image udptl t38
m=audio 45734 RTP/AVP 8 18 0 101
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
# 09:28:48 - OpenSer -> Client (audio: 88.99.3.10:60698)
# ------------------------------------------------------------------------------
SIP/2.0 183 Session Progress
From: "Bob"<sip:216 at sip.domain.net>;tag=as3c11216c
To: <sip:996511959 at sip.domain.net>;tag=4d764120338220087992847
Call-ID: 5be83da413f9dbd468a6d83818fb356d at sip.domain.net
CSeq: 103 INVITE
Server: CS2000_NGSS/9.0
Allow: ACK,BYE,CANCEL,INVITE,OPTIONS,INFO,SUBSCRIBE,REFER,NOTIFY,PRACK,UPDATE
Via: SIP/2.0/UDP 192.168.1.203:5060;received=88.99.1.192;rport=10000;branch=z9hG4bK5d2718e5
Contact: <sip:66.11.0.144:5060;transport=UDP>
Record-Route: <sip:88.99.3.10;lr;ftag=as3c11216c>
Content-Type: application/SDP
Content-Length: 221
v=0
o=IWSPM 341282043 341282043 IN IP4 66.11.6.142
s=-
c=IN IP4 88.99.3.10
t=0 0
a=sqn: 0
a=cdsc: 1 image udptl t38
m=audio 60698 RTP/AVP 8 18 0 101
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
# 09:28:49 - Gateway -> OpenSer (audio: 66.11.6.142:45734)
# ------------------------------------------------------------------------------
SIP/2.0 200 OK
From: "Bob"<sip:216 at sip.domain.net>;tag=as3c11216c
To: <sip:996511959 at sip.domain.net>;tag=4d764120338220087992847
Call-ID: 5be83da413f9dbd468a6d83818fb356d at sip.domain.net
CSeq: 103 INVITE
Server: CS2000_NGSS/9.0
Allow: ACK,BYE,CANCEL,INVITE,OPTIONS,INFO,SUBSCRIBE,REFER,NOTIFY,PRACK,UPDATE
Via: SIP/2.0/UDP 88.99.3.10:5060;branch=z9hG4bK6d.0b4e08c2.0
Via: SIP/2.0/UDP 192.168.1.203:5060;received=88.99.1.192;rport=10000;branch=z9hG4bK5d2718e5
Contact: <sip:66.11.0.144:5060;transport=UDP>
Record-Route: <sip:88.99.3.10;lr;ftag=as3c11216c>
Content-Type: application/SDP
Content-Length: 222
v=0
o=IWSPM 341282043 341282043 IN IP4 66.11.6.142
s=-
c=IN IP4 66.11.6.142
t=0 0
a=sqn: 0
a=cdsc: 1 image udptl t38
m=audio 45734 RTP/AVP 8 18 0 101
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
# 09:28:49 - OpenSer -> Client (audio: 88.99.3.10:60704)
# ------------------------------------------------------------------------------
SIP/2.0 200 OK
From: "Bob"<sip:216 at sip.domain.net>;tag=as3c11216c
To: <sip:996511959 at sip.domain.net>;tag=4d764120338220087992847
Call-ID: 5be83da413f9dbd468a6d83818fb356d at sip.domain.net
CSeq: 103 INVITE
Server: CS2000_NGSS/9.0
Allow: ACK,BYE,CANCEL,INVITE,OPTIONS,INFO,SUBSCRIBE,REFER,NOTIFY,PRACK,UPDATE
Via: SIP/2.0/UDP 192.168.1.203:5060;received=88.99.1.192;rport=10000;branch=z9hG4bK5d2718e5
Contact: <sip:66.11.0.144:5060;transport=UDP>
Record-Route: <sip:88.99.3.10;lr;ftag=as3c11216c>
Content-Type: application/SDP
Content-Length: 221
v=0
o=IWSPM 341282043 341282043 IN IP4 66.11.6.142
s=-
c=IN IP4 88.99.3.10
t=0 0
a=sqn: 0
a=cdsc: 1 image udptl t38
m=audio 60704 RTP/AVP 8 18 0 101
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
More information about the sr-users
mailing list