[OpenSIPS-Users] Change in SDP/RTP routing with Opensips 1.4 and 1.6
Ovidiu Sas
osas at voipembedded.com
Wed Feb 9 22:12:29 CET 2011
Add the following xlog at the beginning of the script:
xlog("L_INFO", "$mb\n");
Then and add a local_route:
local_route {
xlog("L_INFO", "$mb\n");
}
Then check the logs. There you should have the received and sent message.
Regards,
Ovidiu Sas
On Wed, Feb 9, 2011 at 4:02 PM, Chris Stone <axisml at gmail.com> wrote:
> 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
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>
More information about the Users
mailing list