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

Chris Stone axisml at gmail.com
Wed Feb 9 22:02:47 CET 2011


Henk,

On Tue, Feb 8, 2011 at 6:23 PM, Henk Hesselink <opensips-users at 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 at 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 at 67.212.153.178:5060;transport=udp
SIP/2.0..From: "STONE C AND C"
<sip:+13038382386 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f1ba-24e62632-44ba7768..To:
<sip:17204497101 at 67.21
  2.153.178:5060>..Call-ID:
CXC-173-6b187330-a9d5ed0-13c4-4d52f1ba-24e62632-15a0585a at 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:+13038382386 at cxc.dashcs.com:5060>..Supported:
timer,100rel..Content-Disposition: session;handling=required..Contact:
<sip:+1303838238
  6 at 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 at 67.212.153.178:5060;transport=udp
SIP/2.0..From: "STONE C AND C"
<sip:+13038382386 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f1ba-24e62632-44ba7768..To:
<sip:17204497101 at 67.21
  2.153.178:5060>..Call-ID:
CXC-173-6b187330-a9d5ed0-13c4-4d52f1ba-24e62632-15a0585a at 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:+13038382386 at cxc.dashcs.com:5060>..Supported: t
  imer,100rel..Content-Disposition:
session;handling=required..Contact:
<sip:+13038382386 at 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 at 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 at 67.212.153.178:5060}
Feb  9 13:27:38 [12757] DBG:core:get_hdr_field: <To> [39];
uri=[sip:17204497101 at 67.212.153.178:5060]
Feb  9 13:27:38 [12757] DBG:core:get_hdr_field: to body
[<sip:17204497101 at 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 at 67.212.153.178:5060;transport=udp SIP/2.0
From: "STONE C AND C"
<sip:+13038382386 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To: <sip:17204497101 at 67.212.153.178:5060>
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd at 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:+13038382386 at cxc.dashcs.com:5060>
Supported: timer,100rel
Content-Disposition: session;handling=required
Contact: <sip:+13038382386 at 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 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To: <sip:17204497101 at 67.212.153.178:5060>
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd at 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 at 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 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To: <sip:17204497101 at 67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd at 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 at 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 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To: <sip:17204497101 at 67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd at 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 at 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 at 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 at 67.212.153.178:5060}
Feb  9 13:27:41 [12757] DBG:core:get_hdr_field: <To> [54];
uri=[sip:17204497101 at 67.212.153.178:5060]
Feb  9 13:27:41 [12757] DBG:core:get_hdr_field: to body
[<sip:17204497101 at 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 at 67.212.153.179:5060 SIP/2.0
From: "STONE C AND C"
<sip:+13038382386 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To: <sip:17204497101 at 67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd at 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 at 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 at 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 at 67.212.153.178:5060}
Feb  9 13:27:52 [12757] DBG:core:get_hdr_field: <To> [54];
uri=[sip:17204497101 at 67.212.153.178:5060]
Feb  9 13:27:52 [12757] DBG:core:get_hdr_field: to body
[<sip:17204497101 at 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 at 67.212.153.179:5060 SIP/2.0
From: "STONE C AND C"
<sip:+13038382386 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To: <sip:17204497101 at 67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd at 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 at 208.94.157.10:5060>;tag=a9d5ed0-13c4-4d52f8b9-25017a0d-7828b493
To: <sip:17204497101 at 67.212.153.178:5060>;tag=as4903531b
Call-ID: CXC-439-6b15b570-a9d5ed0-13c4-4d52f8b9-25017a0d-19d038cd at 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



More information about the Users mailing list