Re: [OpenSER-Users] problem in audio when using media proxy - sr-users

15 May 2008


      Hi,
Actually I am new to openser.
Here i am sending trace from openser.
So please check out SDP.
***************************************************************************************************************************************
Message Body: INVITE
sip:999999999@voip.domain.comsip%3A999999999@voip.domain.comSIP/2.0
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;rport;branch=z9hG4bK7F77064E8D8FD3FD41779B0F67EF5C05
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com>
Contact: sip:5555555555@122.xxx.xxx.35:5061
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28079 INVITE
Max-Forwards: 70
Content-Type: application/sdp
User-Agent: X-Lite release 1105d
Content-Length: 291
v=0
o=5555555555 3961416264 3961416447 IN IP4 122.xxx.xxx.35
s=X-Lite
c=IN IP4 122.xxx.xxx.35
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
0(14396) parse_headers: flags=100
 0(14396) DEBUG:maxfwd:is_maxfwd_present: value = 70
 0(14396) parse_headers: flags=80
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) parse_headers: flags=8000000
 0(14396)
#############################record_route###############################
 0(14396) #######################After record_route########################3
 0(14396) ##################################Before
loose_route##########################
 0(14396) parse_headers: flags=200
 0(14396) DEBUG: get_hdr_body : content_length=291
 0(14396) found end of header
 0(14396) find_first_route: No Route headers found
 0(14396) loose_route: There is no Route HF
 0(14396) DEBUG: t_newtran:  T on entrance=0xffffffff
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) parse_headers: flags=78
 0(14396) t_lookup_request: start searching: hash=48297, isACK=0
 0(14396) DEBUG: RFC3261 transaction matching failed
 0(14396) DEBUG: t_lookup_request: no transaction found
 0(14396) DBG: trans=0xb60e0960, callback type 1, id 0 entered
 0(14396)
##########################method=INVITE##################################
 0(14396)
##############################client_nat_test(7)##############################
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) DEBUG:check_content_type: type <application/sdp> found valid
 0(14396) parse_headers: flags=80
 0(14396) parse_headers: flags=10000
 0(14396) pre_auth(): Credentials with given realm not found
 0(14396) build_auth_hf(): 'Proxy-Authenticate: Digest realm="
voip.domain.com", nonce="482c8213b23f69ac2b268e593620cb2367dc643b"
'
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) WARNING: script writer didn't release transaction
 0(14396) DEBUG: cleanup_uac_timers: RETR/FR timers reset
 0(14396) DEBUG: add_to_tail_of_timer[2]: 0xb60e09a8 (10704)
 0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list (nil)
 0(14396) receive_msg: cleaning up
 0(14396) SIP Request:
 0(14396)  method:  <ACK>
 0(14396)  uri:
<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
0(14396)  version: <SIP/2.0>
 0(14396) parse_headers: flags=2
 0(14396) Found param type 235, <rport> = <n/a>; state=6
 0(14396) Found param type 232, <branch> =
<z9hG4bK7F77064E8D8FD3FD41779B0F67EF5C05>; state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) preparing to run routing scripts...
 0(14396) parse_headers: flags=8
 0(14396) DEBUG: add_param: tag=b802e73ab92fec121a4f880263d541a3.3a34
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [83]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
0(14396) DEBUG: sl_filter_ACK : local ACK found -> dropping it!
 0(14396) DEBUG:destroy_avp_list: destroying list (nil)
 0(14396) receive_msg: cleaning up
 0(14396) SIP Request:
 0(14396)  method:  <INVITE>
 0(14396)  uri:
<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
0(14396)  version: <SIP/2.0>
 0(14396) parse_headers: flags=2
 0(14396) Found param type 235, <rport> = <n/a>; state=6
 0(14396) Found param type 232, <branch> =
<z9hG4bK200ED8B65B4AFC558C060A0A8B5FE1ED>; state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) preparing to run routing scripts...
 0(14396) In request log route
 0(14396)
 0(14396) ############################## NEW REQUEST RECEIVED
##############################
 0(14396) parse_headers: flags=10
 0(14396) DEBUG: add_param: tag=2027736222
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={5555555555}, ruri={
sip:5555555555@voip.domain.com:5061}
 0(14396) request from 122.xxx.xxx.35 [Thu May 15 20:28:55 2008]: INVITE
sip:5555555555@voip.domain.com:5061 ->
sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
:
 0(14396) request from 122.xxx.xxx.35 [Thu May 15 20:28:55 2008]:
  Request: INVITE sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
 0(14396)     From: sip:5555555555@voip.domain.com:5061;tag=2027736222
 0(14396) parse_headers: flags=8
 0(14396) DEBUG:parse_to:end of header reached, state=10
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [41]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
 0(14396)     To: sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
;tag=<null>
 0(14396)     SIP message-id: 201
 0(14396) parse_headers: flags=40
 0(14396)     Call-id: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
 0(14396) parse_headers: flags=20
 0(14396) get_hdr_field: cseq <CSeq>: <28080> <INVITE>
 0(14396)     CSeq: 28080
 0(14396)     Contact: sip:5555555555@122.xxx.xxx.35:5061
 0(14396)
Message Body: INVITE
sip:999999999@voip.domain.comsip%3A999999999@voip.domain.comSIP/2.0
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;rport;branch=z9hG4bK200ED8B65B4AFC558C060A0A8B5FE1ED
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com>
Contact: sip:5555555555@122.xxx.xxx.35:5061
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 INVITE
Proxy-Authorization: Digest username="5555555555",realm="voip.domain.com
",nonce="482c8213b23f69ac2b268e593620cb2367dc643b",response="7a7d3cf46a5b7cf90cc623664ca52e4d",uri="
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com"
Max-Forwards: 70
Content-Type: application/sdp
User-Agent: X-Lite release 1105d
Content-Length: 291
v=0
o=5555555555 3961416264 3961416447 IN IP4 122.xxx.xxx.35
s=X-Lite
c=IN IP4 122.xxx.xxx.35
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
0(14396) parse_headers: flags=100
 0(14396) DEBUG:maxfwd:is_maxfwd_present: value = 70
 0(14396) parse_headers: flags=80
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) parse_headers: flags=8000000
 0(14396)
#############################record_route###############################
 0(14396) #######################After record_route########################3
 0(14396) ##################################Before
loose_route##########################
 0(14396) parse_headers: flags=200
 0(14396) DEBUG: get_hdr_body : content_length=291
 0(14396) found end of header
 0(14396) find_first_route: No Route headers found
 0(14396) loose_route: There is no Route HF
 0(14396) DEBUG: t_newtran:  T on entrance=0xffffffff
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) parse_headers: flags=78
 0(14396) t_lookup_request: start searching: hash=48303, isACK=0
 0(14396) DEBUG: RFC3261 transaction matching failed
 0(14396) DEBUG: t_lookup_request: no transaction found
 0(14396) DBG: trans=0xb60df1c8, callback type 1, id 0 entered
 0(14396)
##########################method=INVITE##################################
 0(14396)
##############################client_nat_test(7)##############################
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) DEBUG:check_content_type: type <application/sdp> found valid
 0(14396) parse_headers: flags=80
 0(14396) check_nonce(): comparing
[482c8213b23f69ac2b268e593620cb2367dc643b] and
[482c8213b23f69ac2b268e593620cb2367dc643b]
 0(14396) DEBUG:auth_radius:radius_authorize_sterman: Success
 0(14396) DEBUG:auth_radius:generate_avps: getting SIP AVPs from avpair 225
 0(14396) ERROR:avp_radius:avp_load_user: rc_auth failed
 0(14396) DEBUG:usrloc:db_load_urecord: aor not found in DB
 0(14396) lookup(): '999999999@voip.domain.com' Not found in usrloc
 0(14396) grep_sock_info - checking if host==us: 23==23 &&  [voip.domain.com]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) alias_db_lookup: no alias found for R-URI
 0(14396) does_uri_exit(): User in request uri does not exist
 0(14396) parse_headers: flags=78
 0(14396) ------[sip:5555555555@voip.domain.com:5061] ---checkeding----
 0(14396) radius_is_user_in(): Success
 0(14396)  -------- media route 5 ----
  0(14396) ########################## isbflagset(6)
isbflagset(7)####################################
  0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) parse_headers: flags=8000000
 0(14396) DEBUG:lcr:load_gws: add matched_gws[0]=[0,0]
 0(14396) load_gws(): DEBUG: Added gw_uri_avp sip:|0@213.XXX.XXX.28
:5060;transport=udp
 0(14396) -----------------lcr is loaded into the                ------
  0(14396) load_gws(): DEBUG: Added ruri_user_avp <999999999>
 0(14396) ------------- Gateway is loaded     ---------
  0(14396) DEBUG: mk_proxy: doing DNS lookup...
 0(14396) parse_headers: flags=2000
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) clen_builder: content-length: 313 (313)
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) DBG:check_against_rule_list: using list dns
 0(14396) DEBUG:tm:set_timer: relative timeout is 500000
 0(14396) DEBUG: add_to_tail_of_timer[4]: 0xb60df314 (10701100000)
 0(14396) DEBUG:tm:set_timer: relative timeout is 20
 0(14396) DEBUG: add_to_tail_of_timer[0]: 0xb60df330 (10720)
 0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list (nil)
 0(14396) receive_msg: cleaning up
 0(14396) SIP Reply  (status):
 0(14396)  version: <SIP/2.0>
 0(14396)  status:  <100>
 0(14396)  reason:  <Trying>
 0(14396) parse_headers: flags=2
 0(14396) get_hdr_field: cseq <CSeq>: <28080> <INVITE>
 0(14396) DEBUG: add_param: tag=5430ebc2c5d6575
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
0(14396) Found param type 232, <branch> = <z9hG4bKfacb.bd19b862.0>;
state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) grep_sock_info - checking if host==us: 13==23 &&  [195.XXX.XXX.51]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) DEBUG:forward_reply: found module nathelper, passing reply to it
 0(14396) DEBUG:forward_reply: found module tm, passing reply to it
 0(14396) DEBUG: t_check: start=0xffffffff
 0(14396) parse_headers: flags=22
 0(14396) parse_headers: flags=8
 0(14396) DEBUG: t_reply_matching: hash 48303 label 646681051 branch 0
 0(14396) DEBUG:tm:REF_UNSAFE: after is 1
 0(14396) DEBUG: t_reply_matching: reply matched (T=0xb60df1c8)!
 0(14396) DEBUG: t_check: end=0xb60df1c8
 0(14396) DEBUG:tm:reply_received: org. status uas=0, uac[0]=0 local=0
is_invite=1)
 0(14396) DEBUG:tm:t_should_relay_response: T_code=0, new_code=100
 0(14396) DEBUG:tm:relay_reply: branch=0, save=0, relay=-1
 0(14396) DEBUG:tm:set_timer: relative timeout is 50
 0(14396) DEBUG: add_to_tail_of_timer[1]: 0xb60df330 (10750)
 0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list (nil)
 0(14396) receive_msg: cleaning up
 0(14396) SIP Reply  (status):
 0(14396)  version: <SIP/2.0>
 0(14396)  status:  <183>
 0(14396)  reason:  <Session Progress>
 0(14396) parse_headers: flags=2
 0(14396) get_hdr_field: cseq <CSeq>: <28080> <INVITE>
 0(14396) DEBUG: add_param: tag=5430ebc2c5d6575
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
0(14396) Found param type 232, <branch> = <z9hG4bKfacb.bd19b862.0>;
state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) grep_sock_info - checking if host==us: 13==23 &&  [195.XXX.XXX.51]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) DEBUG:forward_reply: found module nathelper, passing reply to it
 0(14396) DEBUG:forward_reply: found module tm, passing reply to it
 0(14396) DEBUG: t_check: start=0xffffffff
 0(14396) parse_headers: flags=22
 0(14396) parse_headers: flags=8
 0(14396) DEBUG: t_reply_matching: hash 48303 label 646681051 branch 0
 0(14396) DEBUG:tm:REF_UNSAFE: after is 1
 0(14396) DEBUG: t_reply_matching: reply matched (T=0xb60df1c8)!
 0(14396) DEBUG: t_check: end=0xb60df1c8
 0(14396) DEBUG:tm:reply_received: org. status uas=0, uac[0]=100 local=0
is_invite=1)
 0(14396) DEBUG:tm:t_should_relay_response: T_code=0, new_code=183
 0(14396) DEBUG:tm:relay_reply: branch=0, save=0, relay=0
 0(14396)  old size: 884, new size: 822
 0(14396) build_res_from_sip_res: copied size: orig:318, new: 256, rest: 566
msg=
SIP/2.0 183 Session Progress
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 INVITE
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com
...
;tag=5430ebc2c5d6575
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;branch=z9hG4bK200ED8B65B4AFC558C060A0A8B5FE1ED;rport=5061
Record-Route: sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Content-Length: 209
Content-Type: application/sdp
Contact: sip:999999999@213.XXX.XXX.28:5060
User-Agent: Mediatrix MDD1404 2404 1500 1600 2500 2600 MxSF v3.2.8.45
00a0ba009b05
v=0
o=MxSIP 0 7983 IN IP4 213.XXX.XXX.28
s=SIP Call
c=IN IP4 213.XXX.XXX.28
t=0 0
m=audio 19462 RTP/AVP 0 8 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
0(14396) WARNING:vqm_resize: resize(0) called
 0(14396) DEBUG:tm:relay_reply: sent buf=0x819a510: SIP/2.0 1...,
shmem=0xb60e8218: SIP/2.0 1
 0(14396) DEBUG:tm:set_timer: relative timeout is 50
 0(14396) DEBUG: add_to_tail_of_timer[1]: 0xb60df330 (10750)
 0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list (nil)
 0(14396) receive_msg: cleaning up
 1(14397) DEBUG: timer routine:4,tl=0xb60df314 next=(nil),
timeout=10701100000
 1(14397) DEBUG: timer routine:2,tl=0xb60e09a8 next=(nil), timeout=10704
 1(14397) DEBUG: wait_handler : removing 0xb60e0960 from table
 1(14397) DEBUG: delete transaction 0xb60e0960
 1(14397) DEBUG: wait_handler : done
 0(14396) SIP Reply  (status):
 0(14396)  version: <SIP/2.0>
 0(14396)  status:  <200>
 0(14396)  reason:  <OK>
 0(14396) parse_headers: flags=2
 0(14396) get_hdr_field: cseq <CSeq>: <28080> <INVITE>
 0(14396) DEBUG: add_param: tag=5430ebc2c5d6575
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
0(14396) Found param type 232, <branch> = <z9hG4bKfacb.bd19b862.0>;
state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) grep_sock_info - checking if host==us: 13==23 &&  [195.XXX.XXX.51]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) DEBUG:forward_reply: found module nathelper, passing reply to it
 0(14396) DEBUG:forward_reply: found module tm, passing reply to it
 0(14396) DEBUG: t_check: start=0xffffffff
 0(14396) parse_headers: flags=22
 0(14396) parse_headers: flags=8
 0(14396) DEBUG: t_reply_matching: hash 48303 label 646681051 branch 0
 0(14396) DEBUG:tm:REF_UNSAFE: after is 1
 0(14396) DEBUG: t_reply_matching: reply matched (T=0xb60df1c8)!
 0(14396) DEBUG: t_check: end=0xb60df1c8
 0(14396) DEBUG:tm:reply_received: org. status uas=183, uac[0]=183 local=0
is_invite=1)
 0(14396) DEBUG:tm:t_should_relay_response: T_code=183, new_code=200
 0(14396) DEBUG:tm:relay_reply: branch=0, save=0, relay=0
 0(14396)  old size: 891, new size: 829
 0(14396) build_res_from_sip_res: copied size: orig:304, new: 242, rest: 587
msg=
SIP/2.0 200 OK
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 INVITE
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com
...
;tag=5430ebc2c5d6575
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;branch=z9hG4bK200ED8B65B4AFC558C060A0A8B5FE1ED;rport=5061
Record-Route: sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Content-Length: 209
Content-Type: application/sdp
Supported: replaces
Contact: sip:999999999@213.XXX.XXX.28:5060
User-Agent: Mediatrix MDD1404 2404 1500 1600 2500 2600 MxSF v3.2.8.45
00a0ba009b05
v=0
o=MxSIP 0 7983 IN IP4 213.XXX.XXX.28
s=SIP Call
c=IN IP4 213.XXX.XXX.28
t=0 0
m=audio 19462 RTP/AVP 0 8 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
0(14396) DEBUG: add_to_tail_of_timer[2]: 0xb60df210 (10710)
 0(14396) DEBUG:tm:relay_reply: sent buf=0x819a510: SIP/2.0 2...,
shmem=0xb60e05e0: SIP/2.0 2
 0(14396) DEBUG: cleanup_uac_timers: RETR/FR timers reset
 0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list (nil)
 0(14396) receive_msg: cleaning up
 0(14396) SIP Reply  (status):
 0(14396)  version: <SIP/2.0>
 0(14396)  status:  <200>
 0(14396)  reason:  <OK>
 0(14396) parse_headers: flags=2
 0(14396) get_hdr_field: cseq <CSeq>: <28080> <INVITE>
 0(14396) DEBUG: add_param: tag=5430ebc2c5d6575
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
0(14396) Found param type 232, <branch> = <z9hG4bKfacb.bd19b862.0>;
state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) grep_sock_info - checking if host==us: 13==23 &&  [195.XXX.XXX.51]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) DEBUG:forward_reply: found module nathelper, passing reply to it
 0(14396) DEBUG:forward_reply: found module tm, passing reply to it
 0(14396) DEBUG: t_check: start=0xffffffff
 0(14396) parse_headers: flags=22
 0(14396) parse_headers: flags=8
 0(14396) DEBUG: t_reply_matching: hash 48303 label 646681051 branch 0
 0(14396) DEBUG:tm:REF_UNSAFE: after is 1
 0(14396) DEBUG: t_reply_matching: reply matched (T=0xb60df1c8)!
 0(14396) DEBUG: t_check: end=0xb60df1c8
 0(14396) DEBUG:tm:reply_received: org. status uas=200, uac[0]=200 local=0
is_invite=1)
 0(14396) DEBUG:tm:t_should_relay_response: T_code=200, new_code=200
 0(14396) DEBUG:tm:t_should_relay_response: 200 OK for INVITE after final
sent
 0(14396) DEBUG:tm:relay_reply: branch=0, save=0, relay=0
 0(14396)  old size: 891, new size: 829
 0(14396) build_res_from_sip_res: copied size: orig:304, new: 242, rest: 587
msg=
SIP/2.0 200 OK
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 INVITE
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com
...
;tag=5430ebc2c5d6575
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;branch=z9hG4bK200ED8B65B4AFC558C060A0A8B5FE1ED;rport=5061
Record-Route: sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Content-Length: 209
Content-Type: application/sdp
Supported: replaces
Contact: sip:999999999@213.XXX.XXX.28:5060
User-Agent: Mediatrix MDD1404 2404 1500 1600 2500 2600 MxSF v3.2.8.45
00a0ba009b05
v=0
o=MxSIP 0 7983 IN IP4 213.XXX.XXX.28
s=SIP Call
c=IN IP4 213.XXX.XXX.28
t=0 0
m=audio 19462 RTP/AVP 0 8 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
0(14396) DEBUG:tm:relay_reply: sent buf=0x819a510: SIP/2.0 2...,
shmem=0xb60e05e0: SIP/2.0 2
 0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list (nil)
 0(14396) receive_msg: cleaning up
 0(14396) SIP Request:
 0(14396)  method:  <ACK>
 0(14396)  uri:     sip:999999999@213.XXX.XXX.28:5060
 0(14396)  version: <SIP/2.0>
 0(14396) parse_headers: flags=2
 0(14396) Found param type 235, <rport> = <n/a>; state=6
 0(14396) Found param type 232, <branch> =
<z9hG4bK384293560A7C9508E28212EC0D72CDFA>; state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) preparing to run routing scripts...
 0(14396) DEBUG : sl_filter_ACK: to late to be a local ACK!
 0(14396) In request log route
 0(14396)
 0(14396) ############################## NEW REQUEST RECEIVED
##############################
 0(14396) parse_headers: flags=10
 0(14396) DEBUG: add_param: tag=2027736222
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={5555555555}, ruri={
sip:5555555555@voip.domain.com:5061}
 0(14396) request from 122.xxx.xxx.35 [Thu May 15 20:29:02 2008]: ACK
sip:5555555555@voip.domain.com:5061 -> sip:999999999@213.XXX.XXX.28:5060:
 0(14396) request from 122.xxx.xxx.35 [Thu May 15 20:29:02 2008]:
  Request: ACK sip:999999999@213.XXX.XXX.28:5060
 0(14396)     From: sip:5555555555@voip.domain.com:5061;tag=2027736222
 0(14396) parse_headers: flags=8
 0(14396) DEBUG: add_param: tag=5430ebc2c5d6575
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
0(14396)     To: sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
;tag=5430ebc2c5d6575
 0(14396)     SIP message-id: 206
 0(14396) parse_headers: flags=40
 0(14396)     Call-id: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
 0(14396) parse_headers: flags=20
 0(14396) get_hdr_field: cseq <CSeq>: <28080> <ACK>
 0(14396)     CSeq: 28080
 0(14396)     Contact: sip:5555555555@122.xxx.xxx.35:5061
 0(14396)
Message Body: ACK sip:999999999@213.XXX.XXX.28:5060 SIP/2.0
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;rport;branch=z9hG4bK384293560A7C9508E28212EC0D72CDFA
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com
...
;tag=5430ebc2c5d6575
Contact: sip:5555555555@122.xxx.xxx.35:5061
Route: sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 ACK
Max-Forwards: 70
Content-Length: 0
0(14396) parse_headers: flags=100
 0(14396) DEBUG:maxfwd:is_maxfwd_present: value = 70
 0(14396)
#############################record_route###############################
 0(14396) #######################After record_route########################3
 0(14396) ##################################Before
loose_route##########################
 0(14396) parse_headers: flags=200
 0(14396) is_preloaded: No
 0(14396) grep_sock_info - checking if host==us: 14==23 &&  [213.XXX.XXX.28]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) grep_sock_info - checking if host==us: 14==23 &&  [213.XXX.XXX.28]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) DEBUG:check_self: host != me
 0(14396) grep_sock_info - checking if host==us: 23==23 &&  [voip.domain.com]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) after_loose: Topmost route URI:
'sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on'
is me
 0(14396) parse_headers: flags=200
 0(14396) DEBUG: get_hdr_body : content_length=0
 0(14396) found end of header
 0(14396) find_next_route: No next Route HF found
 0(14396) after_loose: No next URI found
 0(14396) #################IN Loose_route############################
 0(14396) DEBUG: has_totag: totag found
 0(14396) ############################has_totag() method==INVITE
method==ACK################################
 0(14396) parse_headers: flags=80
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) parse_headers: flags=8000000
 0(14396)
###########################setbflag6#################################
 0(14396)
###############################force_rport#############################
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396)
#############################use_media_proxy###############################
 0(14396) parse_headers: flags=78
 0(14396) DEBUG: t_newtran:  T on entrance=0xffffffff
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) parse_headers: flags=78
 0(14396) t_lookup_request: start searching: hash=48303, isACK=1
 0(14396) parse_headers: flags=38
 0(14396) DEBUG:tm:REF_UNSAFE: after is 1
 0(14396) DEBUG: t_lookup_request: e2e proxy ACK found
 0(14396) DEBUG:tm:t_newtran: building branch for end2end ACK
 0(14396) DEBUG:tm:t_relay: forwarding ACK
 0(14396) DEBUG: mk_proxy: doing DNS lookup...
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) DBG:check_against_rule_list: using list dns
 0(14396) DEBUG:forward_request: sending:
ACK sip:999999999@213.XXX.XXX.28:5060 SIP/2.0
Record-Route: sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Via: SIP/2.0/UDP 195.XXX.XXX.51;branch=z9hG4bKfacb.bd19b862.2
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;rport=5061;branch=z9hG4bK384293560A7C9508E28212EC0D72CDFA
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com
...
;tag=5430ebc2c5d6575
Contact: sip:5555555555@122.xxx.xxx.35:5061
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 ACK
Max-Forwards: 69
Content-Length: 0
.
 0(14396) DEBUG:forward_request: orig. len=519, new_len=593, proto=1
 0(14396)  -------------- cheked t reply ---
  0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list 0xb60e04c8
 0(14396) receive_msg: cleaning up
 0(14396) SIP Request:
 0(14396)  method:  <ACK>
 0(14396)  uri:     sip:999999999@213.XXX.XXX.28:5060
 0(14396)  version: <SIP/2.0>
 0(14396) parse_headers: flags=2
 0(14396) Found param type 235, <rport> = <n/a>; state=6
 0(14396) Found param type 232, <branch> =
<z9hG4bK384293560A7C9508E28212EC0D72CDFA>; state=16
 0(14396) end of header reached, state=5
 0(14396) parse_headers: Via found, flags=2
 0(14396) parse_headers: this is the first via
 0(14396) After parse_msg...
 0(14396) preparing to run routing scripts...
 0(14396) DEBUG : sl_filter_ACK: to late to be a local ACK!
 0(14396) In request log route
 0(14396)
 0(14396) ############################## NEW REQUEST RECEIVED
##############################
 0(14396) parse_headers: flags=10
 0(14396) DEBUG: add_param: tag=2027736222
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={5555555555}, ruri={
sip:5555555555@voip.domain.com:5061}
 0(14396) request from 122.xxx.xxx.35 [Thu May 15 20:29:02 2008]: ACK
sip:5555555555@voip.domain.com:5061 -> sip:999999999@213.XXX.XXX.28:5060:
 0(14396) request from 122.xxx.xxx.35 [Thu May 15 20:29:02 2008]:
  Request: ACK sip:999999999@213.XXX.XXX.28:5060
 0(14396)     From: sip:5555555555@voip.domain.com:5061;tag=2027736222
 0(14396) parse_headers: flags=8
 0(14396) DEBUG: add_param: tag=5430ebc2c5d6575
 0(14396) DEBUG:parse_to:end of header reached, state=29
 0(14396) DBUG:parse_to: display={},
ruri={sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
}
 0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com]
 0(14396) DEBUG: to body
[<sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
...
]
0(14396)     To: sip:999999999@voip.domain.comsip%3A999999999@voip.domain.com
;tag=5430ebc2c5d6575
 0(14396)     SIP message-id: 207
 0(14396) parse_headers: flags=40
 0(14396)     Call-id: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
 0(14396) parse_headers: flags=20
 0(14396) get_hdr_field: cseq <CSeq>: <28080> <ACK>
 0(14396)     CSeq: 28080
 0(14396)     Contact: sip:5555555555@122.xxx.xxx.35:5061
 0(14396)
Message Body: ACK sip:999999999@213.XXX.XXX.28:5060 SIP/2.0
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;rport;branch=z9hG4bK384293560A7C9508E28212EC0D72CDFA
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com
...
;tag=5430ebc2c5d6575
Contact: sip:5555555555@122.xxx.xxx.35:5061
Route: sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 ACK
Max-Forwards: 70
Content-Length: 0
0(14396) parse_headers: flags=100
 0(14396) DEBUG:maxfwd:is_maxfwd_present: value = 70
 0(14396)
#############################record_route###############################
 0(14396) #######################After record_route########################3
 0(14396) ##################################Before
loose_route##########################
 0(14396) parse_headers: flags=200
 0(14396) is_preloaded: No
 0(14396) grep_sock_info - checking if host==us: 14==23 &&  [213.XXX.XXX.28]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) grep_sock_info - checking if host==us: 14==23 &&  [213.XXX.XXX.28]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) DEBUG:check_self: host != me
 0(14396) grep_sock_info - checking if host==us: 23==23 &&  [voip.domain.com]
== [voip.domain.com]
 0(14396) grep_sock_info - checking if port 5060 matches port 5060
 0(14396) after_loose: Topmost route URI:
'sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on'
is me
 0(14396) parse_headers: flags=200
 0(14396) DEBUG: get_hdr_body : content_length=0
 0(14396) found end of header
 0(14396) find_next_route: No next Route HF found
 0(14396) after_loose: No next URI found
 0(14396) #################IN Loose_route############################
 0(14396) DEBUG: has_totag: totag found
 0(14396) ############################has_totag() method==INVITE
method==ACK################################
 0(14396) parse_headers: flags=80
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) parse_headers: flags=8000000
 0(14396)
###########################setbflag6#################################
 0(14396)
###############################force_rport#############################
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396)
#############################use_media_proxy###############################
 0(14396) parse_headers: flags=78
 0(14396) DEBUG: t_newtran:  T on entrance=0xffffffff
 0(14396) parse_headers: flags=ffffffffffffffff
 0(14396) parse_headers: flags=78
 0(14396) t_lookup_request: start searching: hash=48303, isACK=1
 0(14396) parse_headers: flags=38
 0(14396) DEBUG:tm:REF_UNSAFE: after is 1
 0(14396) DEBUG: t_lookup_request: e2e proxy ACK found
 0(14396) DEBUG:tm:t_newtran: building branch for end2end ACK
 0(14396) DEBUG:tm:t_relay: forwarding ACK
 0(14396) DEBUG: mk_proxy: doing DNS lookup...
 0(14396) check_via_address(122.xxx.xxx.35, 122.xxx.xxx.35, 0)
 0(14396) DBG:check_against_rule_list: using list dns
 0(14396) DEBUG:forward_request: sending:
ACK sip:999999999@213.XXX.XXX.28:5060 SIP/2.0
Record-Route: sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Via: SIP/2.0/UDP 195.XXX.XXX.51;branch=z9hG4bKfacb.bd19b862.2
Via: SIP/2.0/UDP
122.xxx.xxx.35:5061;rport=5061;branch=z9hG4bK384293560A7C9508E28212EC0D72CDFA
From: 5555555555 sip:5555555555@voip.domain.com:5061;tag=2027736222
To: <sip:999999999@voip.domain.com sip%3A999999999@voip.domain.com
...
;tag=5430ebc2c5d6575
Contact: sip:5555555555@122.xxx.xxx.35:5061
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B@192.168.1.10
CSeq: 28080 ACK
Max-Forwards: 69
Content-Length: 0
.
 0(14396) DEBUG:forward_request: orig. len=519, new_len=593, proto=1
 0(14396)  -------------- cheked t reply ---
  0(14396) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(14396) DEBUG:destroy_avp_list: destroying list 0xb60e04c8
 0(14396) receive_msg: cleaning up
 1(14397) DEBUG: timer routine:2,tl=0xb60df210 next=(nil), timeout=10710
 1(14397) DEBUG: wait_handler : removing 0xb60df1c8 from table
 1(14397) DEBUG: delete transaction 0xb60df1c8
 1(14397) DEBUG: wait_handler : done
 ***************************************************************************************************************************************
-- 
Thanks & regards,
Krunal Patel


On Thu, May 15, 2008 at 12:03 AM, LetMeKnow <
sunkara.raviprakash.feb14@gmail.com> wrote:

> hey krunal,
> i hopes ur fine and also all members of Ecosmob are fine,
>
> i have read ur mail,
> yours problem is mention properly  in mailing list.
>
> In ur openser script file some problem in there and u not handling the nat
> in ur script.
> make sure u have use both modules nathelper and mediaproxy,
>
> Before doing on this checkout is openser system is located inside the nat
> or not.
>
> while test use ngrep tool on openser system, see whether u have got the
> properly signaling happening not.
>
> check out after 200 ok of invite msg
>
>
> On Wed, May 14, 2008 at 5:40 PM, Krunal Patel krunal.patel@ecosmob.com
> wrote:
>
>> Hi,
>>
>> We are using openser with media proxy and radiator. We're having audio
>> problems when we send calls to mediatrix gateway for termination.
>> Internal calls work fine. We hear audio both side and we can see the
>> session and bytes transfer properly in media proxy monitoring webpage.
>> When we send calls to mediatrix gateway for termination, calls connect
>> properly but we don't hear audio. We see inactive session in mediaproxy
>> status page with no bytes transferring.
>> One doubt I have is it is not entering reply route when it receives
>> replies like 183, 180, etc.
>> Has anyone encountered similar issue? How can we fix it?
>> Thanks in advance!
>>
>> --
>> regards,
>> Krunal Patel
>>
>>
>> _______________________________________________
>> Users mailing list
>> Users@lists.openser.org
>> http://lists.openser.org/cgi-bin/mailman/listinfo/users
>>
>>
>
>
> --
> Thanks &Regards
> Ravi Prakash Sunkara
> VoIP Development Tech Lead
> 91-9999882776
>