Re: [OpenSIPS-Users] Change in SDP/RTP routing with Opensips 1.4 and 1.6

Wed, 09 Feb 2011 14:48:48 -0800 

Chris,

First off, the ngrep trace doesn't seem to match the debug log, f.i.
the From: tag is different.  You might also want to check out -Wbyline
for ngrep, makes reading the output a lot easier.

But ignoring the tags, the debug log shows "DBG:core:forward_request:
sending:" and then the exact original message (with an extra Via: of
course).  So it seems to me OpenSIPS is sending out what you'd expect.

So I believe the munging is happening after the packet leaves OpenSIPS
and before it is sent out on the wire.  Run "lsmod | grep nat" and see
if you have "nf_nat_sip" (I'm assuming it's a Linux box), or maybe some
other module that might be doing this.

Cheers,

Henk


On 09-02-11 22:02, Chris Stone wrote:

Henk,

On Tue, Feb 8, 2011 at 6:23 PM, Henk Hesselink<opensips-us...@voipro.nl>  wrote:

What does an ngrep trace on the interface (interfaces?) of the machine
show?  Are the packets changed when they leave the machine?  In that
case it must be happening on that machine, i.e. no external firewall


It shows that the packets are changed on the machine - no external
firewall involvement:

[root@mars ~]# ngrep -pt -d eth0 port 5060 and not host 38.116.136.247
interface: eth0 (67.212.153.176/255.255.255.240)
filter: (ip) and ( port 5060 and not host 38.116.136.247 )
#
U 2011/02/09 12:57:47.457399 208.94.157.10:5060 ->  67.212.153.178:5060
   INVITE sip:17204497101@67.212.153.178:5060;transport=udp
SIP/2.0..From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f1ba-24e62632-44ba7768..To:
<sip:17204497101@67.21
   2.153.178:5060>..Call-ID:
cxc-173-6b187330-a9d5ed0-13c4-4d52f1ba-24e62632-15a05...@208.94.157.10..cseq:
1 INVITE..Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-9be4f-4d52f1ba-24e62632-5636b781
   ..Max-Forwards: 69..P-Asserted-Identity: "STONE C AND C  "
<sip:+13038382...@cxc.dashcs.com:5060>..Supported:
timer,100rel..Content-Disposition: session;handling=required..Contact:
<sip:+1303838238
   6@208.94.157.10:5060;maddr=208.94.157.10;transport=udp>..Session-Expires:
1800..Content-Type: application/sdp..Content-Length:
238....v=0..o=Acme_UAS 0 1 IN IP4 208.94.157.10..s=SIP Media Capabilit
   ies..c=IN IP4 208.94.157.10..t=0 0..m=audio 21428 RTP/AVP 0 18
101..a=rtpmap:0 PCMU/8000..a=rtpmap:18 G729/8000..a=rtpmap:101
telephone-event/8000..a=maxptime:20..a=sendrecv..
#
U 2011/02/09 12:57:47.459479 67.212.153.178:5060 ->  67.212.153.179:5060
   INVITE sip:17204497101@67.212.153.178:5060;transport=udp
SIP/2.0..From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f1ba-24e62632-44ba7768..To:
<sip:17204497101@67.21
   2.153.178:5060>..Call-ID:
cxc-173-6b187330-a9d5ed0-13c4-4d52f1ba-24e62632-15a05...@208.94.157.10..cseq:
1 INVITE..Via: SIP/2.0/UDP
67.212.153.178:5060;branch=z9hG4bK-9be4f-4d52f1ba-24e62632-5636b78
   1..Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-9be4f-4d52f1ba-24e62632-5636b781..Max-Forwards:
69..P-Asserted-Identity: "STONE C AND C  "
<sip:+13038382...@cxc.dashcs.com:5060>..Supported: t
   imer,100rel..Content-Disposition:
session;handling=required..Contact:
<sip:+13038382386@67.212.153.178:5060;maddr=208.94.157.10;transport=udp>..Session-Expires:
1800..Content-Type: application/sdp.
   .Content-Length: 240....v=0..o=Acme_UAS 0 1 IN IP4
67.212.153.178..s=SIP Media Capabilities..c=IN IP4 67.212.153.178..t=0
0..m=audio 21428 RTP/AVP 0 18 101..a=rtpmap:0 PCMU/8000..a=rtpmap:18
G729/8
   000..a=rtpmap:101 telephone-event/8000..a=maxptime:20..a=sendrecv..


munging.  If that is the case then run OpenSIPS with debug level 9 or
so, save the output and then grep on the 2 different IP adresses that
are in the incoming/outgoing contact header to see why that header is
being changed.  That might give a clue as to the SDP routing.


I can see that the addresses are being changed for what's send v.s.
what's received from the upstream. Can't say that I can tell the why
of it though. Here's the output of the call test. Do you maybe see why
Opensips is making such a change and hence, causing the SDP to be
routed back to it?

========================================================
Feb  9 13:27:28 [12757] DBG:core:socket2str:<udp:67.212.153.178:5060>
Feb  9 13:27:28 [12757] WARNING:core:main: no fork mode
Feb  9 13:27:28 [12757] DBG:core:fm_malloc_init: F_OPTIMIZE=16384, /ROUNDTO=2048
Feb  9 13:27:28 [12757] DBG:core:fm_malloc_init: F_HASH_SIZE=2099,
fm_block size=33632
Feb  9 13:27:28 [12757] DBG:core:fm_malloc_init: params
(0x2b1aeab1d000, 33554432), start=0x2b1aeab1d000
Feb  9 13:27:28 [12757] DBG:core:shm_mem_init_mallocs: success
Feb  9 13:27:28 [12757] INFO:core:init_tcp: using epoll_lt as the TCP
io watch method (auto detected)
Feb  9 13:27:28 [12757] DBG:core:set_core_dump: core dump limits set
to 18446744073709551615
Feb  9 13:27:28 [12757] NOTICE:core:main: version: opensips
1.4.0-notls (x86_64/linux)
Feb  9 13:27:28 [12757] INFO:core:main: using 32 Mb shared memory
Feb  9 13:27:28 [12757] INFO:core:main: using 1 Mb private memory per process
Feb  9 13:27:28 [12757] DBG:core:add_avp_galias: registering
<serial_branch>  for avp id<16725044>
Feb  9 13:27:28 [12757] DBG:core:init_stats_collector: statistics
manager successfully initialized
Feb  9 13:27:28 [12757] DBG:core:count_module_procs: modules require 0
extra processes
Feb  9 13:27:28 [12757] DBG:core:mk_proxy: doing DNS lookup...
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: getsockopt
SO_RCVBUF is initially 129024
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: trying
SO_RCVBUF: 258048
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: setting
SO_RCVBUF; set=258048,verify=262142
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: trying
SO_RCVBUF: 260096
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: setting
SO_RCVBUF; set=260096,verify=262142
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: trying
SO_RCVBUF: 262144
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: setting
SO_RCVBUF; set=262144,verify=262142
Feb  9 13:27:28 [12757] DBG:core:probe_max_receive_buffer: setting
SO_RCVBUF has no effect
Feb  9 13:27:28 [12757] INFO:core:probe_max_receive_buffer: using a
UDP receive buffer of 255 kb
Feb  9 13:27:28 [12757] DBG:core:internal_fork: forking new process "timer"
Feb  9 13:27:28 [12759] DBG:core:run_timer_process: tv = 1, 0 , m=1
Feb  9 13:27:38 [12757] DBG:core:parse_msg: SIP Request:
Feb  9 13:27:38 [12757] DBG:core:parse_msg:  method:<INVITE>
Feb  9 13:27:38 [12757] DBG:core:parse_msg:  uri:
<sip:17204497101@67.212.153.178:5060;transport=udp>
Feb  9 13:27:38 [12757] DBG:core:parse_msg:  version:<SIP/2.0>
Feb  9 13:27:38 [12757] DBG:core:parse_headers: flags=2
Feb  9 13:27:38 [12757] DBG:core:parse_to: end of header reached, state=10
Feb  9 13:27:38 [12757] DBG:core:parse_to: display={},
ruri={sip:17204497101@67.212.153.178:5060}
Feb  9 13:27:38 [12757] DBG:core:get_hdr_field:<To>  [39];
uri=[sip:17204497101@67.212.153.178:5060]
Feb  9 13:27:38 [12757] DBG:core:get_hdr_field: to body
[<sip:17204497101@67.212.153.178:5060>
]
Feb  9 13:27:38 [12757] DBG:core:get_hdr_field: cseq<CSeq>:<1>  <INVITE>
Feb  9 13:27:38 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0>; state=16
Feb  9 13:27:38 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:38 [12757] DBG:core:parse_headers: via found, flags=2
Feb  9 13:27:38 [12757] DBG:core:parse_headers: this is the first via
Feb  9 13:27:38 [12757] DBG:core:receive_msg: After parse_msg...
Feb  9 13:27:38 [12757] DBG:core:receive_msg: preparing to run routing
scripts...
Feb  9 13:27:38 [12757] DBG:core:check_via_address: params
208.94.157.10, 208.94.157.10, 0
Feb  9 13:27:38 [12757] DBG:core:forward_request: sending:
INVITE sip:17204497101@67.212.153.178:5060;transport=udp SIP/2.0
From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To:<sip:17204497101@67.212.153.178:5060>
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd@208.94.157.10
CSeq: 1 INVITE
Via: SIP/2.0/UDP 67.212.153.178;branch=z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0
Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0
Max-Forwards: 69
P-Asserted-Identity: "STONE C AND C  "<sip:+13038382...@cxc.dashcs.com:5060>
Supported: timer,100rel
Content-Disposition: session;handling=required
Contact:<sip:+13038382386@208.94.157.10:5060;maddr=208.94.157.10;transport=udp>
Session-Expires: 1800
Content-Type: application/sdp
Content-Length: 238

v=0
o=Acme_UAS 0 1 IN IP4 208.94.157.10
s=SIP Media Capabilities
c=IN IP4 208.94.157.10
t=0 0
m=audio 23366 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=maxptime:20
a=sendrecv
.
Feb  9 13:27:38 [12757] DBG:core:forward_request: orig. len=962,
new_len=1042, proto=1
Feb  9 13:27:38 [12757] DBG:core:destroy_avp_list: destroying list (nil)
Feb  9 13:27:38 [12757] DBG:core:receive_msg: cleaning up
Feb  9 13:27:38 [12757] DBG:core:parse_msg: SIP Reply  (status):
Feb  9 13:27:38 [12757] DBG:core:parse_msg:  version:<SIP/2.0>
Feb  9 13:27:38 [12757] DBG:core:parse_msg:  status:<100>
Feb  9 13:27:38 [12757] DBG:core:parse_msg:  reason:<Trying>
Feb  9 13:27:38 [12757] DBG:core:parse_headers: flags=2
Feb  9 13:27:38 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0>; state=6
Feb  9 13:27:38 [12757] DBG:core:parse_via_param: found param type
234,<received>  =<67.212.153.178>; state=16
Feb  9 13:27:38 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:38 [12757] DBG:core:parse_headers: via found, flags=2
Feb  9 13:27:38 [12757] DBG:core:parse_headers: this is the first via
Feb  9 13:27:38 [12757] DBG:core:receive_msg: After parse_msg...
Feb  9 13:27:38 [12757] DBG:core:parse_headers: flags=4
Feb  9 13:27:38 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0>; state=16
Feb  9 13:27:38 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:38 [12757] DBG:core:parse_headers: via found, flags=4
Feb  9 13:27:38 [12757] DBG:core:parse_headers: parse_headers: this is
the second via
Feb  9 13:27:38 [12757] DBG:core:build_res_buf_from_sip_res:  old
size: 661, new size: 552
Feb  9 13:27:38 [12757] DBG:core:build_res_buf_from_sip_res: copied
size: orig:129, new: 20, rest: 532 msg=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0
From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To:<sip:17204497101@67.212.153.178:5060>
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd@208.94.157.10
CSeq: 1 INVITE
Server: Asterisk PBX 1.8.2.3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY,
INFO, PUBLISH
Supported: replaces
Contact:<sip:17204497101@67.212.153.179:5060>
Content-Length: 0


Feb  9 13:27:38 [12757] DBG:core:update_sock_struct_from_via: using via host
Feb  9 13:27:38 [12757] DBG:core:update_sock_struct_from_via: trying SRV lookup
Feb  9 13:27:38 [12757] DBG:core:forward_reply: reply forwarded to
208.94.157.10:5060
Feb  9 13:27:38 [12757] DBG:core:destroy_avp_list: destroying list (nil)
Feb  9 13:27:38 [12757] DBG:core:receive_msg: cleaning up
Feb  9 13:27:39 [12757] DBG:core:parse_msg: SIP Reply  (status):
Feb  9 13:27:39 [12757] DBG:core:parse_msg:  version:<SIP/2.0>
Feb  9 13:27:39 [12757] DBG:core:parse_msg:  status:<180>
Feb  9 13:27:39 [12757] DBG:core:parse_msg:  reason:<Ringing>
Feb  9 13:27:39 [12757] DBG:core:parse_headers: flags=2
Feb  9 13:27:39 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0>; state=6
Feb  9 13:27:39 [12757] DBG:core:parse_via_param: found param type
234,<received>  =<67.212.153.178>; state=16
Feb  9 13:27:39 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:39 [12757] DBG:core:parse_headers: via found, flags=2
Feb  9 13:27:39 [12757] DBG:core:parse_headers: this is the first via
Feb  9 13:27:39 [12757] DBG:core:receive_msg: After parse_msg...
Feb  9 13:27:39 [12757] DBG:core:parse_headers: flags=4
Feb  9 13:27:39 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0>; state=16
Feb  9 13:27:39 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:39 [12757] DBG:core:parse_headers: via found, flags=4
Feb  9 13:27:39 [12757] DBG:core:parse_headers: parse_headers: this is
the second via
Feb  9 13:27:39 [12757] DBG:core:build_res_buf_from_sip_res:  old
size: 677, new size: 568
Feb  9 13:27:39 [12757] DBG:core:build_res_buf_from_sip_res: copied
size: orig:130, new: 21, rest: 547 msg=
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0
From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To:<sip:17204497101@67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd@208.94.157.10
CSeq: 1 INVITE
Server: Asterisk PBX 1.8.2.3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY,
INFO, PUBLISH
Supported: replaces
Contact:<sip:17204497101@67.212.153.179:5060>
Content-Length: 0


Feb  9 13:27:39 [12757] DBG:core:update_sock_struct_from_via: using via host
Feb  9 13:27:39 [12757] DBG:core:update_sock_struct_from_via: trying SRV lookup
Feb  9 13:27:39 [12757] DBG:core:forward_reply: reply forwarded to
208.94.157.10:5060
Feb  9 13:27:39 [12757] DBG:core:destroy_avp_list: destroying list (nil)
Feb  9 13:27:39 [12757] DBG:core:receive_msg: cleaning up
Feb  9 13:27:41 [12757] DBG:core:parse_msg: SIP Reply  (status):
Feb  9 13:27:41 [12757] DBG:core:parse_msg:  version:<SIP/2.0>
Feb  9 13:27:41 [12757] DBG:core:parse_msg:  status:<200>
Feb  9 13:27:41 [12757] DBG:core:parse_msg:  reason:<OK>
Feb  9 13:27:41 [12757] DBG:core:parse_headers: flags=2
Feb  9 13:27:41 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0>; state=6
Feb  9 13:27:41 [12757] DBG:core:parse_via_param: found param type
234,<received>  =<67.212.153.178>; state=16
Feb  9 13:27:41 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:41 [12757] DBG:core:parse_headers: via found, flags=2
Feb  9 13:27:41 [12757] DBG:core:parse_headers: this is the first via
Feb  9 13:27:41 [12757] DBG:core:receive_msg: After parse_msg...
Feb  9 13:27:41 [12757] DBG:core:parse_headers: flags=4
Feb  9 13:27:41 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0>; state=16
Feb  9 13:27:41 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:41 [12757] DBG:core:parse_headers: via found, flags=4
Feb  9 13:27:41 [12757] DBG:core:parse_headers: parse_headers: this is
the second via
Feb  9 13:27:41 [12757] DBG:core:build_res_buf_from_sip_res:  old
size: 990, new size: 881
Feb  9 13:27:41 [12757] DBG:core:build_res_buf_from_sip_res: copied
size: orig:125, new: 16, rest: 865 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-a89b6-4d52f8b9-25017a0d-d0bdcd0
From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To:<sip:17204497101@67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd@208.94.157.10
CSeq: 1 INVITE
Server: Asterisk PBX 1.8.2.3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY,
INFO, PUBLISH
Supported: replaces
Contact:<sip:17204497101@67.212.153.179:5060>
Content-Type: application/sdp
Content-Length: 285

v=0
o=root 330278739 330278739 IN IP4 67.212.153.179
s=Asterisk PBX 1.8.2.3
c=IN IP4 67.212.153.179
t=0 0
m=audio 13376 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

Feb  9 13:27:41 [12757] DBG:core:update_sock_struct_from_via: using via host
Feb  9 13:27:41 [12757] DBG:core:update_sock_struct_from_via: trying SRV lookup
Feb  9 13:27:41 [12757] DBG:core:forward_reply: reply forwarded to
208.94.157.10:5060
Feb  9 13:27:41 [12757] DBG:core:destroy_avp_list: destroying list (nil)
Feb  9 13:27:41 [12757] DBG:core:receive_msg: cleaning up
Feb  9 13:27:41 [12757] DBG:core:parse_msg: SIP Request:
Feb  9 13:27:41 [12757] DBG:core:parse_msg:  method:<ACK>
Feb  9 13:27:41 [12757] DBG:core:parse_msg:  uri:
<sip:17204497101@67.212.153.179:5060>
Feb  9 13:27:41 [12757] DBG:core:parse_msg:  version:<SIP/2.0>
Feb  9 13:27:41 [12757] DBG:core:parse_headers: flags=2
Feb  9 13:27:41 [12757] DBG:core:parse_to_param: tag=as4903531b
Feb  9 13:27:41 [12757] DBG:core:parse_to: end of header reached, state=29
Feb  9 13:27:41 [12757] DBG:core:parse_to: display={},
ruri={sip:17204497101@67.212.153.178:5060}
Feb  9 13:27:41 [12757] DBG:core:get_hdr_field:<To>  [54];
uri=[sip:17204497101@67.212.153.178:5060]
Feb  9 13:27:41 [12757] DBG:core:get_hdr_field: to body
[<sip:17204497101@67.212.153.178:5060>]
Feb  9 13:27:41 [12757] DBG:core:get_hdr_field: cseq<CSeq>:<1>  <ACK>
Feb  9 13:27:41 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a89fb-4d52f8bc-250185e3-53cb0b94>; state=16
Feb  9 13:27:41 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:41 [12757] DBG:core:parse_headers: via found, flags=2
Feb  9 13:27:41 [12757] DBG:core:parse_headers: this is the first via
Feb  9 13:27:41 [12757] DBG:core:receive_msg: After parse_msg...
Feb  9 13:27:41 [12757] DBG:core:receive_msg: preparing to run routing
scripts...
Feb  9 13:27:41 [12757] DBG:core:check_via_address: params
208.94.157.10, 208.94.157.10, 0
Feb  9 13:27:41 [12757] DBG:core:forward_request: sending:
ACK sip:17204497101@67.212.153.179:5060 SIP/2.0
From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To:<sip:17204497101@67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd@208.94.157.10
CSeq: 1 ACK
Via: SIP/2.0/UDP 67.212.153.178;branch=z9hG4bK-a89fb-4d52f8bc-250185e3-53cb0b94
Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-a89fb-4d52f8bc-250185e3-53cb0b94
Max-Forwards: 69
Contact:<sip:+13038382386@208.94.157.10:5060;maddr=208.94.157.10;transport=udp>
Content-Length: 0

.
Feb  9 13:27:41 [12757] DBG:core:forward_request: orig. len=512,
new_len=593, proto=1
Feb  9 13:27:41 [12757] DBG:core:destroy_avp_list: destroying list (nil)
Feb  9 13:27:41 [12757] DBG:core:receive_msg: cleaning up
Feb  9 13:27:52 [12757] DBG:core:parse_msg: SIP Request:
Feb  9 13:27:52 [12757] DBG:core:parse_msg:  method:<BYE>
Feb  9 13:27:52 [12757] DBG:core:parse_msg:  uri:
<sip:17204497101@67.212.153.179:5060>
Feb  9 13:27:52 [12757] DBG:core:parse_msg:  version:<SIP/2.0>
Feb  9 13:27:52 [12757] DBG:core:parse_headers: flags=2
Feb  9 13:27:52 [12757] DBG:core:parse_to_param: tag=as4903531b
Feb  9 13:27:52 [12757] DBG:core:parse_to: end of header reached, state=29
Feb  9 13:27:52 [12757] DBG:core:parse_to: display={},
ruri={sip:17204497101@67.212.153.178:5060}
Feb  9 13:27:52 [12757] DBG:core:get_hdr_field:<To>  [54];
uri=[sip:17204497101@67.212.153.178:5060]
Feb  9 13:27:52 [12757] DBG:core:get_hdr_field: to body
[<sip:17204497101@67.212.153.178:5060>]
Feb  9 13:27:52 [12757] DBG:core:get_hdr_field: cseq<CSeq>:<2>  <BYE>
Feb  9 13:27:52 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a8b34-4d52f8c7-2501b153-4f303295>; state=16
Feb  9 13:27:52 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:52 [12757] DBG:core:parse_headers: via found, flags=2
Feb  9 13:27:52 [12757] DBG:core:parse_headers: this is the first via
Feb  9 13:27:52 [12757] DBG:core:receive_msg: After parse_msg...
Feb  9 13:27:52 [12757] DBG:core:receive_msg: preparing to run routing
scripts...
Feb  9 13:27:52 [12757] DBG:core:check_via_address: params
208.94.157.10, 208.94.157.10, 0
Feb  9 13:27:52 [12757] DBG:core:forward_request: sending:
BYE sip:17204497101@67.212.153.179:5060 SIP/2.0
From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To:<sip:17204497101@67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd@208.94.157.10
CSeq: 2 BYE
Via: SIP/2.0/UDP 67.212.153.178;branch=z9hG4bK-a8b34-4d52f8c7-2501b153-4f303295
Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-a8b34-4d52f8c7-2501b153-4f303295
Max-Forwards: 69
Content-Length: 0

.
Feb  9 13:27:52 [12757] DBG:core:forward_request: orig. len=430,
new_len=511, proto=1
Feb  9 13:27:52 [12757] DBG:core:destroy_avp_list: destroying list (nil)
Feb  9 13:27:52 [12757] DBG:core:receive_msg: cleaning up
Feb  9 13:27:52 [12757] DBG:core:parse_msg: SIP Reply  (status):
Feb  9 13:27:52 [12757] DBG:core:parse_msg:  version:<SIP/2.0>
Feb  9 13:27:52 [12757] DBG:core:parse_msg:  status:<200>
Feb  9 13:27:52 [12757] DBG:core:parse_msg:  reason:<OK>
Feb  9 13:27:52 [12757] DBG:core:parse_headers: flags=2
Feb  9 13:27:52 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a8b34-4d52f8c7-2501b153-4f303295>; state=6
Feb  9 13:27:52 [12757] DBG:core:parse_via_param: found param type
234,<received>  =<67.212.153.178>; state=16
Feb  9 13:27:52 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:52 [12757] DBG:core:parse_headers: via found, flags=2
Feb  9 13:27:52 [12757] DBG:core:parse_headers: this is the first via
Feb  9 13:27:52 [12757] DBG:core:receive_msg: After parse_msg...
Feb  9 13:27:52 [12757] DBG:core:parse_headers: flags=4
Feb  9 13:27:52 [12757] DBG:core:parse_via_param: found param type
232,<branch>  =<z9hG4bK-a8b34-4d52f8c7-2501b153-4f303295>; state=16
Feb  9 13:27:52 [12757] DBG:core:parse_via: end of header reached, state=5
Feb  9 13:27:52 [12757] DBG:core:parse_headers: via found, flags=4
Feb  9 13:27:52 [12757] DBG:core:parse_headers: parse_headers: this is
the second via
Feb  9 13:27:52 [12757] DBG:core:build_res_buf_from_sip_res:  old
size: 623, new size: 513
Feb  9 13:27:52 [12757] DBG:core:build_res_buf_from_sip_res: copied
size: orig:126, new: 16, rest: 497 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
208.94.157.10:5060;branch=z9hG4bK-a8b34-4d52f8c7-2501b153-4f303295
From: "STONE C AND C"
<sip:+13038382386@208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To:<sip:17204497101@67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd@208.94.157.10
CSeq: 2 BYE
Server: Asterisk PBX 1.8.2.3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY,
INFO, PUBLISH
Supported: replaces
Content-Length: 0


Feb  9 13:27:52 [12757] DBG:core:update_sock_struct_from_via: using via host
Feb  9 13:27:52 [12757] DBG:core:update_sock_struct_from_via: trying SRV lookup
Feb  9 13:27:52 [12757] DBG:core:forward_reply: reply forwarded to
208.94.157.10:5060
Feb  9 13:27:52 [12757] DBG:core:destroy_avp_list: destroying list (nil)
Feb  9 13:27:52 [12757] DBG:core:receive_msg: cleaning up
========================================================



Thanks


Chris



_______________________________________________
Users mailing list
Users@lists.opensips.org
http://lists.opensips.org/cgi-bin/mailman/listinfo/users

Reply via email to