15 May
2008
15 May
'08
11:12 a.m.
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.com<sip%3A999999999@voip.domain.com>SIP/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(a)voip.domain.com> Contact:
<sip:5555555555@122.xxx.xxx.35:5061
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B(a)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.com<sip%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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [83]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)voip.domain.com 0(14396) uri:
<sip:999999999@voip.domain.com<sip%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.com<sip%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.com<sip%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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [41]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)voip.domain.com
]
0(14396) To: sip:999999999@voip.domain.com<sip%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(a)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.com<sip%3A999999999@voip.domain.com>SIP/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(a)voip.domain.com> Contact:
<sip:5555555555@122.xxx.xxx.35:5061
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B(a)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(a)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(a)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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)voip.domain.com 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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)voip.domain.com 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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)voip.domain.com 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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)voip.domain.com 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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)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(a)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(a)voip.domain.com Route:
<sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B(a)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(a)voip.domain.com Call-ID:
64E8EEAF-6014-1CCB-98A5-1185295C388B(a)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.com<sip%3A999999999@voip.domain.com }
0(14396) DEBUG: get_hdr_field: <To> [61]; uri=[
sip:999999999@voip.domain.com <sip%3A999999999(a)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(a)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(a)voip.domain.com Route:
<sip:voip.domain.com:5060;nat=yes;ftag=2027736222;lr=on
Call-ID: 64E8EEAF-6014-1CCB-98A5-1185295C388B(a)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(a)voip.domain.com Call-ID:
64E8EEAF-6014-1CCB-98A5-1185295C388B(a)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(a)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(a)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(a)lists.openser.org
>> http://lists.openser.org/cgi-bin/mailman/listinfo/users
> >
> --
> Thanks &Regards
> Ravi Prakash Sunkara
> VoIP Development Tech Lead
> 91-9999882776
]
0(14396) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%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) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%3A999999999@voip.domain.com ]
0(14396) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%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) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%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(a)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(a)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 ]
0(14396) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%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(a)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(a)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 ]
0(14396) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%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(a)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(a)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 ]
0(14396) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%3A999999999@voip.domain.com ]
0(14396) To:
sip:999999999@voip.domain.com<sip%3A999999999@voip.domain.com
;tag=5430ebc2c5d6575
Contact: <sip:5555555555@122.xxx.xxx.35:5061
;tag=5430ebc2c5d6575
Contact: <sip:5555555555@122.xxx.xxx.35:5061 ]
0(14396) DEBUG: to body
[<sip:999999999@voip.domain.com<sip%3A999999999@voip.domain.com ]
0(14396) To:
sip:999999999@voip.domain.com<sip%3A999999999@voip.domain.com
;tag=5430ebc2c5d6575
Contact: <sip:5555555555@122.xxx.xxx.35:5061
;tag=5430ebc2c5d6575
Contact: <sip:5555555555@122.xxx.xxx.35:5061