[Kamailio-Users] protocol/port mismatch on BYE from Calle to Caller

Arafath-uz-zaman khan shaon.khan at gmail.com
Wed Feb 3 22:33:25 CET 2010


Hello Daniel

Thanx for your reply. Here is my ngrep result with following command

# ngrep -qt -W byline port 5060 >> cap

I got folling output

U 2010/02/03 17:39:08.679498 192.168.10.1:5060 -> 192.168.10.2:5060
ACK sip:192.168.10.2:5060;transport=udp SIP/2.0.
Via: SIP/2.0/UDP 192.168.10.1;branch=z9hG4bK51b7.97a27486.2;i=1.
Via: SIP/2.0/TLS 192.168.101.3:31483
;received=120.50.182.149;branch=z9hG4bK-d8754z-95673c1cb2589e19-1---d8754z-;rport=3095.
Max-Forwards: 69.
Contact: <sip:91131 at 120.50.182.149:3095;transport=TLS>.
To: "8801911317434"<sip:8801911317434 at 192.168.10.2:5060
>;tag=04022410040217016214856405.
From: "91131"<sip:91131 at 192.168.10.2:5060>;tag=34455268.
Call-ID: MDczMTBiYmI2NjEyNjAxZmUxN2E4MDE2NWRiNWJkM2I..
CSeq: 2 ACK.
Proxy-Authorization: Digest
username="91131",realm="TestSwitch",nonce="126523584204040403502240205405",uri="
sip:8801911317434 at 192.168.10.2:5060
",response="a248a23f5fc5fab350d65d4b2a2980b8",algorithm=MD5.
User-Agent: eyeBeam release 1100z stamp 47739.
Content-Length: 0.
.


U 2010/02/03 17:39:13.260282 192.168.10.2:5060 -> 192.168.10.1:5060
BYE sip:91131 at 192.168.10.2:5060 SIP/2.0.
Route: <sip:192.168.10.1;r2=on;lr=on;nat=yes>,<sip:192.168.10.1:7161
;transport=tls;r2=on;lr=on;nat=yes>.
CSeq: 1 BYE.
Via: SIP/2.0/UDP 192.168.10.2:5060;branch=z9hG4bk040224100419041701638860.
From: "8801911317434"<sip:8801911317434 at 192.168.10.2:5060
>;tag=04022410040217016214856405.
Call-ID: MDczMTBiYmI2NjEyNjAxZmUxN2E4MDE2NWRiNWJkM2I..
To: "91131"<sip:91131 at 192.168.10.2:5060>;tag=34455268.
Content-Length: 0.
.


U 2010/02/03 17:39:13.386050 192.168.10.1:5060 -> 192.168.10.2:5060
BYE sip:91131 at 192.168.10.2:5060 SIP/2.0.
Max-Forwards: 10.
CSeq: 1 BYE.
Via: SIP/2.0/UDP 192.168.10.1;branch=z9hG4bK81b7.e2177ca1.0.
Via: SIP/2.0/UDP 192.168.10.2:5060
;rport=5060;received=192.168.10.2;branch=z9hG4bk040224100419041701638860.
From: "8801911317434"<sip:8801911317434 at 192.168.10.2:5060
>;tag=04022410040217016214856405.
Call-ID: MDczMTBiYmI2NjEyNjAxZmUxN2E4MDE2NWRiNWJkM2I..
To: "91131"<sip:91131 at 192.168.10.2:5060>;tag=34455268.
Content-Length: 0.
.

U 2010/02/03 17:39:13.866269 192.168.10.1:5060 -> 192.168.10.2:5060
BYE sip:91131 at 192.168.10.2:5060 SIP/2.0.
Max-Forwards: 10.
CSeq: 1 BYE.
Via: SIP/2.0/UDP 192.168.10.1;branch=z9hG4bK81b7.e2177ca1.0.
Via: SIP/2.0/UDP 192.168.10.2:5060
;rport=5060;received=192.168.10.2;branch=z9hG4bk040224100419041701638860.
From: "8801911317434"<sip:8801911317434 at 192.168.10.2:5060
>;tag=04022410040217016214856405.
Call-ID: MDczMTBiYmI2NjEyNjAxZmUxN2E4MDE2NWRiNWJkM2I..
To: "91131"<sip:91131 at 192.168.10.2:5060>;tag=34455268.
Content-Length: 0.
.

U 2010/02/03 17:39:15.858733 192.168.10.2:5060 -> 192.168.10.1:5060
SIP/2.0 200 OK.
CSeq: 3 REGISTER.
Via: SIP/2.0/UDP 192.168.10.1;branch=z9hG4bKfb81.fa39fe67.0.
Via: SIP/2.0/TLS 192.168.101.3:31483
;branch=z9hG4bK-d8754z-69339a27c300930f-1---d8754z-.
From: "91131"<sip:91131 at 192.168.10.2:5060>;tag=e721b374.
Call-ID: MWEyYjJhMTVmZmFmMDNmMjI4YzhiZmZiOWRjNTZkYTg..
To: "91131"<sip:91131 at 192.168.10.2:5060>;tag=040224100422.
Contact: <sip:91131 at 120.50.182.149:3095
;rinstance=72706949e91b40b1;transport=TLS>;expires=60.
Expires: 60.
Content-Length: 0.
.

U 2010/02/03 17:39:16.866411 192.168.10.1:5060 -> 192.168.10.2:5060
BYE sip:91131 at 192.168.10.2:5060 SIP/2.0.
Max-Forwards: 10.
CSeq: 1 BYE.
Via: SIP/2.0/UDP 192.168.10.1;branch=z9hG4bK81b7.e2177ca1.0.
Via: SIP/2.0/UDP 192.168.10.2:5060
;rport=5060;received=192.168.10.2;branch=z9hG4bk040224100419041701638860.
From: "8801911317434"<sip:8801911317434 at 192.168.10.2:5060
>;tag=04022410040217016214856405.
Call-ID: MDczMTBiYmI2NjEyNjAxZmUxN2E4MDE2NWRiNWJkM2I..
To: "91131"<sip:91131 at 192.168.10.2:5060>;tag=34455268.
Content-Length: 0.
.

U 2010/02/03 17:39:14.866271 192.168.10.1:5060 -> 192.168.10.2:5060
BYE sip:91131 at 192.168.10.2:5060 SIP/2.0.
Max-Forwards: 10.
CSeq: 1 BYE.
Via: SIP/2.0/UDP 192.168.10.1;branch=z9hG4bK81b7.e2177ca1.0.
Via: SIP/2.0/UDP 192.168.10.2:5060
;rport=5060;received=192.168.10.2;branch=z9hG4bk040224100419041701638860.
From: "8801911317434"<sip:8801911317434 at 192.168.10.2:5060
>;tag=04022410040217016214856405.
Call-ID: MDczMTBiYmI2NjEyNjAxZmUxN2E4MDE2NWRiNWJkM2I..
To: "91131"<sip:91131 at 192.168.10.2:5060>;tag=34455268.
Content-Length: 0.
.


U 2010/02/03 17:39:15.857072 192.168.10.1:5060 -> 192.168.10.2:5060
REGISTER sip:192.168.10.2:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.10.1;branch=z9hG4bKfb81.fa39fe67.0;i=1.
Via: SIP/2.0/TLS 192.168.101.3:31483
;received=120.50.182.149;branch=z9hG4bK-d8754z-69339a27c300930f-1---d8754z-;rport=3095.
Max-Forwards: 69.
Contact: <sip:91131 at 120.50.182.149:3095
;rinstance=72706949e91b40b1;transport=TLS>.
To: "91131"<sip:91131 at 192.168.10.2:5060>.
From: "91131"<sip:91131 at 192.168.10.2:5060>;tag=e721b374.
Call-ID: MWEyYjJhMTVmZmFmMDNmMjI4YzhiZmZiOWRjNTZkYTg..
CSeq: 3 REGISTER.
Expires: 3600.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE,
INFO.
Proxy-Authorization: Digest
username="91131",realm="TestSwitch",nonce="126523580604040403502232605405",uri="sip:
192.168.10.2:5060
",response="8a6afaccacc510ea2521326a95e89724",algorithm=MD5.
User-Agent: eyeBeam release 1100z stamp 47739.
Content-Length: 0.
P-hint: outbound.



Thanx Again

On Wed, Feb 3, 2010 at 3:11 AM, Daniel-Constantin Mierla
<miconda at gmail.com>wrote:

>  Hello,
>
>
> On 2/2/10 3:00 PM, Arafath-uz-zaman khan wrote:
>
> is no one available to help me on that issue?
>
>
> can you take the SIP trace (ngrep) of such call and send it here? There
> should be no dns query for in-dialog messages, they should be routed via
> Route/R-URI IP addresses.
>
> Cheers,
> Daniel
>
>
> On Mon, Feb 1, 2010 at 2:28 PM, Arafath-uz-zaman khan <
> shaon.khan at gmail.com> wrote:
>
>> Hello All,
>>
>> I need a working example for sending all request to another sip server
>> using kamailio.
>>
>> X-lite-->TLS(7161)-->Kamailio-->UDP(5060)-->SIP Server-->PSTN Number. i
>> have attached my cfg file. The problem is when i dialed any pstn number
>> everything working fine except the by msg. If the caller send BYE callee get
>> hangup but if Calle send BYE caller never hangup the call. i got the
>> following msg on my log file
>>
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_msg:
>> method:  <BYE>
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_msg:
>> uri:     <sip:91131 at 192.168.10.2:5060>
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_msg:
>> version: <SIP/2.0>
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=2
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:get_hdr_field: cseq <CSeq>: <1> <BYE>
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_via_param: found param type 232, <branch> =
>> <z9hG4bk010213101521011481381407>; state=16
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_via:
>> end of header reached, state=5
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: via found, flags=2
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: this is the first via
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:receive_msg:
>> After parse_msg...
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:receive_msg:
>> preparing to run routing scripts...
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=100
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_to_param: tag=b23c1531
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_to: end
>> of header reached, state=29
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_to:
>> display={"91131"}, ruri={sip:91131 at 192.168.10.2:5060}
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:get_hdr_field: <To> [51]; uri=[sip:91131 at 192.168.10.2:5060]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:get_hdr_field: to body ["91131"<sip:91131 at 192.168.10.2:5060>]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:get_hdr_field: content_length=0
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:get_hdr_field: found end of header
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:maxfwd:is_maxfwd_present: max_forwards header not found!
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:check_via_address: params 192.168.10.2, 192.168.10.2, 0
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=80
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:siputils:has_totag: totag found
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=200
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:rr:is_preloaded:
>> is_preloaded: No
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if host==us: 12==13 &&  [192.168.10.2] ==
>> [192.168.10.1]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if port 5060 matches port 5060
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if host==us: 12==13 &&  [192.168.10.2] ==
>> [192.168.10.1]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if port 7161 matches port 5060
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: no match for: [192.168.10.2:5060]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:grep_aliases:
>> no match for: [0:192.168.10.2:5060]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:check_self:
>> host != me
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [192.168.10.1] ==
>> [192.168.10.1]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if port 5060 matches port 5060
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: match found for: [192.168.10.1:5060]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:check_self:
>> host == me
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:rr:after_loose:
>> Topmost route URI: 'sip:192.168.10.1;r2=on;lr=on;nat=yes' is me
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [192.168.10.1] ==
>> [192.168.10.1]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: checking if port 7161 matches port 7161
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:grep_sock_info: match found for: [192.168.10.1:7161]
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=200
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:get_hdr_field: found end of header
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:rr:find_next_route: No next Route HF found
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:rr:after_loose: no
>> next URI found
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:rr:check_route_param: params are <;r2=on;lr=on;nat=yes>
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=40
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_to_param: tag=01021310150514813644546133
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_to: end
>> of header reached, state=29
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:parse_to:
>> display={"911317434"}, ruri={sip:911317434 at 192.168.10.1:5060}
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:siputils:has_totag: totag found
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:tm:t_newtran:
>> transaction on entrance=0xffffffff
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=ffffffffffffffff
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=78
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:tm:t_lookup_request: start searching: hash=850, isACK=0
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:tm:t_lookup_request: proceeding to pre-RFC3261 transaction matching
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:tm:t_lookup_request: no transaction found
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:tm:run_reqin_callbacks: trans=0xb615b5f8, callback type 1, id 0 entered
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:parse_headers: flags=78
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:mk_proxy:
>> doing DNS lookup...
>> *Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:get_send_socket: force_send_socket of different proto (1)!
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> WARNING:core:get_send_socket: protocol/port mismatch*
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:tm:set_timer:
>> relative timeout is 500000
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:tm:insert_timer_unsafe: [4]: 0xb615b744 (46600000)
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:tm:set_timer:
>> relative timeout is 30
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:tm:insert_timer_unsafe: [0]: 0xb615b760 (76)
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:tm:t_relay_to: new
>> transaction fwd'ed
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:tm:t_unref:
>> UNREF_UNSAFE: after is 0
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]:
>> DBG:core:destroy_avp_list: destroying list (nil)
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8808]: DBG:core:receive_msg:
>> cleaning up
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8809]: DBG:tm:utimer_routine:
>> timer routine:4,tl=0xb615b744 next=(nil), timeout=46600000
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8809]:
>> DBG:tm:retransmission_handler: retransmission_handler : request resending
>> (t=0xb615b5f8, BYE sip:9 ... )
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8809]: DBG:tm:set_timer:
>> relative timeout is 1000000
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8809]:
>> DBG:tm:insert_timer_unsafe: [5]: 0xb615b744 (47600000)
>> Feb  1 04:27:29 ser /usr/local/sbin/kamailio[8809]:
>> DBG:tm:retransmission_handler: retransmission_handler : done
>> Feb  1 04:27:30 ser /usr/local/sbin/kamailio[8809]: DBG:tm:utimer_routine:
>> timer routine:5,tl=0xb615b744 next=(nil), timeout=47600000
>> Feb  1 04:27:30 ser /usr/local/sbin/kamailio[8809]:
>> DBG:tm:retransmission_handler: retransmission_handler : request resending
>> (t=0xb615b5f8, BYE sip:9 ... )
>> Feb  1 04:27:30 ser /usr/local/sbin/kamailio[8809]: DBG:tm:set_timer:
>> relative timeout is 2000000
>> Feb  1 04:27:30 ser /usr/local/sbin/kamailio[8809]:
>> DBG:tm:insert_timer_unsafe: [6]: 0xb615b744 (49600000)
>> Feb  1 04:27:30 ser /usr/local/sbin/kamailio[8809]:
>> DBG:tm:retransmission_handler: retransmission_handler : done
>>
>>
>>
>>
>
>
> --
> Arafath-uz-zaman khan
> Cell Phone: 880-1911-317434
> opennets.net
> Bangladesh
>
>
> _______________________________________________
> Kamailio (OpenSER) - Users mailing listUsers at lists.kamailio.orghttp://lists.kamailio.org/cgi-bin/mailman/listinfo/usershttp://lists.openser-project.org/cgi-bin/mailman/listinfo/users
>
>
> --
> Daniel-Constantin Mierla
> eLearning class for Kamailio 3.0.0
> Starting Feb 8, 2010
> * http://www.asipto.com/
>
>


-- 
Arafath-uz-zaman khan
Cell Phone: 880-1911-317434
opennets.net
Bangladesh
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20100204/aa663aec/attachment.htm>


More information about the sr-users mailing list