<div dir="ltr"><div><div><div><div><div><div>Hi All,<br><br></div>As said i tested with debug=3 and got two set of logs..<br><br></div>1 - User A succesfully send IM to User B ( with tcp)<br></div>2 - User B try to send IM to User B ( with tcp ) = FAIL<br>
<br></div>Kindly help me understand what is happening ..<br><br></div>1. I could see that there i 2 via headers added by Kamailio server.<br></div>2. I tested with <br><br>
<div class="gmail_extra">
sudo kamcmd core.tcp_list <br>
<br>
</div>
<div class="gmail_extra">I get 2 connection ( with 2 users
registred with TCP)<br>
<br>
</div>
<div class="gmail_extra">But when i try to send message from
User 2 to User 1, i see that 1 more connection is added.<br><br>
</div>
But here the port is confusing. becuase
the other 2 tcp connection shows the rport of the users. But
with the 3rd connection the port is shown as 5060..<br><div><div><div><div><div><div><div><br></div><div>I am attaching the log below. Also i am attaching the log file your reference ,<br><br><br>ubuntu@ubuntu-desktop:/var/log$ tail -f kamailio.log <br>
<br>Jun 21 11:51:44 ubuntu-desktop /usr/local/sbin/kamailio[11056]: last message repeated 5 times<br>Jun 21 11:51:44 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)<br>
Jun 21 11:51:44 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up<br>Jun 21 11:51:44 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x827f2a0, 6, 2, 0xb34f0b90), fd_no=1<br>
Jun 21 11:51:50 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x827f2a0, 6, -1, 0x10) fd_no=2 called<br>Jun 21 11:51:50 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_read.c:1285]: releasing con 0xb34f0b90, state 1, fd=6, id=3<br>
Jun 21 11:51:50 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_read.c:1286]: extra_data (nil)<br>Jun 21 11:51:50 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3381]: handle_tcp_child: reader response= b34f0b90, 1 from 2 <br>
Jun 21 11:51:50 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x825d060, 33, 2, 0xb34f0b90), fd_no=23<br>Jun 21 11:51:50 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3511]: handle_tcp_child: CONN_RELEASE 0xb34f0b90 refcnt= 1<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x825d060, 33, -1, 0x0) fd_no=24 called<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:4298]: tcp: DBG: sending to child, events 1<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3969]: selected tcp worker 3 16(11057) for activity on [tcp:<a href="http://43.88.79.137:5060">43.88.79.137:5060</a>], 0xb34f0b90<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_read.c:1358]: received n=4 con=0xb34f0b90, fd=6<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_read.c:1168]: tcp_read_req: content-length= 5<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:626]: method: <MESSAGE><br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:628]: uri: <<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>><br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:630]: version: <SIP/2.0><br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK11639>; state=16<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [receive.c:149]: After parse_msg...<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [34]; uri=[<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>] <br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["sard7" <<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>>#015#012]<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <20> <MESSAGE><br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 <br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=5<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=29636<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: siputils [checks.c:103]: no totag<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffff<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=38277, isACK=0<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=1 global id=1 T end=(nil)<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==9 && [43.88.79.137] == [127.0.0.1]<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==12 && [43.88.79.137] == [43.88.79.137]<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: registrar [lookup.c:181]: contact for [sard7] found by address<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=1 , global msg id=1 , T on entrance=(nil)<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=38277, isACK=0<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xb34f1de8, callback type 1, id 0 entered<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [msg_translator.c:2496]: create_via_hf: id added: <;i=3>, rcv proto=2<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [msg_translator.c:206]: check_via_address(43.88.108.53, 43.88.108.53, 0)<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_main.c:1843]: tcp_send: no open tcp connection found, opening new one<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [ip_addr.c:247]: tcpconn_new: new tcp connection: 43.88.109.14<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_main.c:1089]: tcpconn_new: on port 5060, type 2<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_main.c:1403]: tcpconn_add: hashes: 1600:1269:0, 4<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_main.c:2733]: pending write on new connection 0xb34f3538 (-1/439 bytes written)<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: last message repeated 5 times<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x827f2a0, 6, 2, 0xb34f0b90), fd_no=1<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3620]: handle_ser_child: read response= b34f3538, 5, fd 34 from 16 (11057)<br>Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x825d060, 34, 2, 0xb34f3538), fd_no=23<br>
Jun 21 11:53:29 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:796]: DBG: io_watch_chg (0x825d060, 34, 0x1, 0xffffffff) fd_no=24 called<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x825d060, 34, -1, 0x0) fd_no=24 called<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:4298]: tcp: DBG: sending to child, events 1<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3969]: selected tcp worker 0 13(11054) for activity on [tcp:<a href="http://43.88.79.137:5060">43.88.79.137:5060</a>], 0xb34f3538<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_read.c:1358]: received n=4 con=0xb34f3538, fd=6<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_read.c:1168]: tcp_read_req: content-length= 0<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:634]: SIP Reply (status):<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:636]: version: <SIP/2.0><br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:638]: status: <200><br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:640]: reason: <OK><br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK5859.a75f15f4.0>; state=6<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 236, <i> = <3>; state=16<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [receive.c:149]: After parse_msg...<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1 T start=(nil)<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <64935>; state=6<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK11639>; state=16<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=22<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=12730<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [44]; uri=[<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>] <br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["sard7" <<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>>]<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <20> <MESSAGE><br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 38277 label 1330771322 branch 0<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xb34f1de8)!<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2 T end=0xb34f1de8<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=0, new_code=200<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=0<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [msg_translator.c:1933]: old size: 337, new size: 272<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [msg_translator.c:1951]: build_res_from_sip_res: copied size: orig:81, new: 16, rest: 256 msg=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/TCP 43.88.108.53:5060;rport=64935;branch=z9hG4bK11639#015#012From: <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>>;tag=29636#015#012To: "sard7" <<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>>;tag=12730#015#012Call-ID: 4226#015#012CSeq: 20 MESSAGE#015#012User-Agent: Linphone/3.5.2 (eXosip2/3.6.0)#015#012Content-Length: 0#015#012#015#012<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_main.c:2332]: tcp_send: tcp connection found (0xb34f0b90), acquiring fd<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_main.c:2343]: tcp_send, c= 0xb34f0b90, n=8<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3620]: handle_ser_child: read response= b34f0b90, 2, fd -1 from 13 (11054)<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_main.c:2373]: tcp_send: after receive_fd: c= 0xb34f0b90 n=4 fd=10<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_main.c:2552]: tcp_send: sending...<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_main.c:2586]: tcp_send: after real write: c= 0xb34f0b90 n=272 fd=10<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_main.c:2587]: tcp_send: buf=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/TCP 43.88.108.53:5060;rport=64935;branch=z9hG4bK11639#015#012From: <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>>;tag=29636#015#012To: "sard7" <<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>>;tag=12730#015#012Call-ID: 4226#015#012CSeq: 20 MESSAGE#015#012User-Agent: Linphone/3.5.2 (eXosip2/3.6.0)#015#012Content-Length: 0#015#012#015#012<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: last message repeated 5 times<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)<br>
Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up<br>Jun 21 11:53:30 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x827f2a0, 6, 2, 0xb34f3538), fd_no=1<br>
Jun 21 11:53:35 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x827f2a0, 6, -1, 0x10) fd_no=2 called<br>Jun 21 11:53:35 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_read.c:1285]: releasing con 0xb34f0b90, state 1, fd=6, id=3<br>
Jun 21 11:53:35 ubuntu-desktop /usr/local/sbin/kamailio[11057]: DEBUG: <core> [tcp_read.c:1286]: extra_data (nil)<br>Jun 21 11:53:35 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3381]: handle_tcp_child: reader response= b34f0b90, 1 from 3 <br>
Jun 21 11:53:35 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x825d060, 33, 2, 0xb34f0b90), fd_no=23<br>Jun 21 11:53:35 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3511]: handle_tcp_child: CONN_RELEASE 0xb34f0b90 refcnt= 1<br>
Jun 21 11:53:36 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x827f2a0, 6, -1, 0x10) fd_no=2 called<br>Jun 21 11:53:36 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_read.c:1285]: releasing con 0xb34f3538, state 1, fd=6, id=4<br>
Jun 21 11:53:36 ubuntu-desktop /usr/local/sbin/kamailio[11054]: DEBUG: <core> [tcp_read.c:1286]: extra_data (nil)<br>Jun 21 11:53:36 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3381]: handle_tcp_child: reader response= b34f3538, 1 from 0 <br>
Jun 21 11:53:36 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x825d060, 34, 2, 0xb34f3538), fd_no=24<br>Jun 21 11:53:36 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3511]: handle_tcp_child: CONN_RELEASE 0xb34f3538 refcnt= 1<br>
<br>================================================== Secnario when IM- Fail=======================<br><br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x825d060, 31, -1, 0x0) fd_no=25 called<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:4298]: tcp: DBG: sending to child, events 1<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3969]: selected tcp worker 1 14(11055) for activity on [tcp:<a href="http://43.88.79.137:5060">43.88.79.137:5060</a>], 0xb34ef8a0<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_read.c:1358]: received n=4 con=0xb34ef8a0, fd=6<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_read.c:1168]: tcp_read_req: content-length= 5<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:626]: method: <MESSAGE><br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:628]: uri: <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>><br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:630]: version: <SIP/2.0><br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK29470>; state=16<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [receive.c:149]: After parse_msg...<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [34]; uri=[<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>] <br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["sard4" <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>>#015#012]<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <20> <MESSAGE><br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 <br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=5<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=17990<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: siputils [checks.c:103]: no totag<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1 T start=(nil)<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=1628, isACK=0<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2 T end=(nil)<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==9 && [43.88.79.137] == [127.0.0.1]<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==12 && [43.88.79.137] == [43.88.79.137]<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: registrar [lookup.c:181]: contact for [sard4] found by address<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=2 , global msg id=2 , T on entrance=(nil)<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=1628, isACK=0<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xb34f1de8, callback type 1, id 0 entered<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [msg_translator.c:2496]: create_via_hf: id added: <;i=2>, rcv proto=2<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [msg_translator.c:206]: check_via_address(43.88.109.14, 43.88.109.14, 0)<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_main.c:1843]: tcp_send: no open tcp connection found, opening new one<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [ip_addr.c:247]: tcpconn_new: new tcp connection: 43.88.108.53<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_main.c:1089]: tcpconn_new: on port 5060, type 2<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_main.c:1403]: tcpconn_add: hashes: 2555:2894:0, 5<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_main.c:2733]: pending write on new connection 0xb34f4f58 (-1/439 bytes written)<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: last message repeated 5 times<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x827f2a0, 6, 2, 0xb34ef8a0), fd_no=1<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3620]: handle_ser_child: read response= b34f4f58, 5, fd 35 from 14 (11055)<br>Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x825d060, 35, 2, 0xb34f4f58), fd_no=24<br>
Jun 21 11:54:03 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:796]: DBG: io_watch_chg (0x825d060, 35, 0x1, 0xffffffff) fd_no=25 called<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_read.c:1168]: tcp_read_req: content-length= 5<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:626]: method: <MESSAGE><br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:628]: uri: <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>><br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:630]: version: <SIP/2.0><br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK29470>; state=16<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [receive.c:149]: After parse_msg...<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [34]; uri=[<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>] <br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["sard4" <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>>#015#012]<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <20> <MESSAGE><br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 <br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=5<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=17990<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: siputils [checks.c:103]: no totag<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=3 global id=2 T start=0xffffffff<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=1628, isACK=0<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:470]: DEBUG: RFC3261 transaction matched, tid=29470<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:726]: DEBUG: t_lookup_request: transaction found (T=0xb34f1de8)<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=3 global id=3 T end=0xb34f1de8<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: tm [t_reply.c:1483]: DBG: t_retransmit_reply: nothing to retransmit<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: last message repeated 5 times<br>
Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)<br>Jun 21 11:54:04 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x825d060, 35, -1, 0x0) fd_no=25 called<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:4298]: tcp: DBG: sending to child, events 1<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3969]: selected tcp worker 2 15(11056) for activity on [tcp:<a href="http://43.88.79.137:5060">43.88.79.137:5060</a>], 0xb34f4f58<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_read.c:1358]: received n=4 con=0xb34f4f58, fd=6<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_read.c:1168]: tcp_read_req: content-length= 0<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:634]: SIP Reply (status):<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:636]: version: <SIP/2.0><br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:638]: status: <480><br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:640]: reason: <Temporarily Unavailable><br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKc56.5c477096.0>; state=6<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 236, <i> = <2>; state=16<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [receive.c:149]: After parse_msg...<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1 T start=(nil)<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <60840>; state=6<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK29470>; state=16<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=22<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=278a8a835a2e4f92aca1f8bc3899ef93<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [71]; uri=[<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>] <br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["sard4" <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>>]<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <20> <MESSAGE><br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 1628 label 1762096325 branch 0<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xb34f1de8)!<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2 T end=0xb34f1de8<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=0, new_code=480<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=0<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [msg_translator.c:1933]: old size: 343, new size: 279<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [msg_translator.c:1951]: build_res_from_sip_res: copied size: orig:101, new: 37, rest: 242 msg=#012SIP/2.0 480 Temporarily Unavailable#015#012Via: SIP/2.0/TCP 43.88.109.14:5060;rport=60840;branch=z9hG4bK29470#015#012From: <<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>>;tag=17990#015#012To: "sard4" <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>>;tag=278a8a835a2e4f92aca1f8bc3899ef93#015#012Call-ID: 25806#015#012CSeq: 20 MESSAGE#015#012Content-Length: 0#015#012#015#012<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_main.c:2332]: tcp_send: tcp connection found (0xb34ef8a0), acquiring fd<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_main.c:2343]: tcp_send, c= 0xb34ef8a0, n=8<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3620]: handle_ser_child: read response= b34ef8a0, 2, fd -1 from 15 (11056)<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_main.c:2373]: tcp_send: after receive_fd: c= 0xb34ef8a0 n=4 fd=10<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_main.c:2552]: tcp_send: sending...<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_main.c:2586]: tcp_send: after real write: c= 0xb34ef8a0 n=279 fd=10<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [tcp_main.c:2587]: tcp_send: buf=#012SIP/2.0 480 Temporarily Unavailable#015#012Via: SIP/2.0/TCP 43.88.109.14:5060;rport=60840;branch=z9hG4bK29470#015#012From: <<a href="mailto:sip%3Asard7@43.88.79.137">sip:sard7@43.88.79.137</a>>;tag=17990#015#012To: "sard4" <<a href="mailto:sip%3Asard4@43.88.79.137">sip:sard4@43.88.79.137</a>>;tag=278a8a835a2e4f92aca1f8bc3899ef93#015#012Call-ID: 25806#015#012CSeq: 20 MESSAGE#015#012Content-Length: 0#015#012#015#012<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: last message repeated 5 times<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)<br>
Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up<br>Jun 21 11:54:07 ubuntu-desktop /usr/local/sbin/kamailio[11056]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x827f2a0, 6, 2, 0xb34f4f58), fd_no=1<br>
Jun 21 11:54:10 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x827f2a0, 6, -1, 0x10) fd_no=2 called<br>Jun 21 11:54:10 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_read.c:1285]: releasing con 0xb34ef8a0, state 1, fd=6, id=2<br>
Jun 21 11:54:10 ubuntu-desktop /usr/local/sbin/kamailio[11055]: DEBUG: <core> [tcp_read.c:1286]: extra_data (nil)<br>Jun 21 11:54:10 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3381]: handle_tcp_child: reader response= b34ef8a0, 1 from 1 <br>
Jun 21 11:54:10 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x825d060, 31, 2, 0xb34ef8a0), fd_no=24<br>Jun 21 11:54:10 ubuntu-desktop /usr/local/sbin/kamailio[11058]: DEBUG: <core> [tcp_main.c:3511]: handle_tcp_child: CONN_RELEASE 0xb34ef8a0 refcnt= 1<br>
<br><br></div><div>Regards<br>Sunil C<br></div><div><div class="gmail_extra"><br><br><div class="gmail_quote">On Sun, Jun 23, 2013 at 8:55 PM, Daniel-Constantin Mierla <span dir="ltr"><<a href="mailto:miconda@gmail.com" target="_blank">miconda@gmail.com</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
<div bgcolor="#FFFFFF" text="#000000">
Hello,<br>
<br>
run with debug=3 and see the log messages. I guess there is a
mismatch on local socket and remote socket conditions that result in
creating a new connection.<br>
<br>
Cheers,<br>
Daniel<div><div class="h5"><br>
<br>
<div>On 6/21/13 1:47 PM, Sunil
Chandrasekharan wrote:<br>
</div>
<blockquote type="cite">
<div dir="ltr">Hi All<br>
<div class="gmail_extra"><br>
</div>
<div class="gmail_extra">Today i could find some information
from the logs.<br>
</div>
<div class="gmail_extra">When i tested the command :<br>
<br>
</div>
<div class="gmail_extra">
sudo kamcmd core.tcp_list <br>
<br>
</div>
<div class="gmail_extra">I get 2 connection ( with 2 users
regitred with TCP)<br>
<br>
</div>
<div class="gmail_extra">But when i try to send message from
User 2 to User 1, i see that 1 more connection is added.<br>
</div>
<div class="gmail_extra">But here the port is confusing. becuase
the other 2 tcp connection shows the rport of the users. But
with the 3rd connection the port is shown as 5060..<br>
</div>
<div class="gmail_extra"><br>
</div>
<div class="gmail_extra">I feel Kamailio is adding a new TCP
connection instead of using the existing connection between
the client and Kamailio with the rport number.<br>
<br>
</div>
<div class="gmail_extra">I read that we can use (force_rport())
, but i could not understand how to use it . Alo when i gave <br>
<br>
</div>
<div class="gmail_extra">force_rport=yes inside the global
parameter of Kamaili.cfg file, i still face the same issue. <br>
<br>
This issue exists even when User 2 try to call/IM to User 1.<br>
<br>
</div>
<div class="gmail_extra">
my laptop is User 1. But i can send IM/Call User 2
succeessfully. I dont understand how my machine can send
IM/call successfully, and why no other user can do the same?<br>
<br>
</div>
<div class="gmail_extra">Kindly support me <br>
</div>
<div class="gmail_extra"><br>
<div class="gmail_quote">On Thu, Jun 20, 2013 at 5:27 PM,
Sunil Chandrasekharan <span dir="ltr"><<a href="mailto:sunil.kainat@gmail.com" target="_blank">sunil.kainat@gmail.com</a>></span>
wrote:<br>
<blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
<div dir="ltr">
<div>
<div>
<div>Hi All,<br>
<br>
</div>
I tested again today by disabling the presence
module.<br>
<br>
</div>
Still i could not make TCP based IM working with
Kamailio.<br>
<br>
</div>
<div>I checked the tcp-connection-lifetime =3605.<br>
</div>
<div>Still i get 480 Temperory un available.<br>
<br>
</div>
<div>Step 1 : login/register on two lin phones ( on
differnt PC)<br>
</div>
<div>Step 2: from PC 1 (user A) send message to user B <br>
</div>
<div>Step 3: user B receive the mesage.<br>
</div>
<div>Step 4. Send message from User B to User A<br>
<br>
</div>
<div>Result : 480 Temperory unavailable.<br>
<br>
</div>
<div>1. I feel user A connection is getting closed .
Hence not able to reach user A.<br>
<br>
</div>
<div>
2. I also see User A and User B message contruct has 2
Via headers.<br>
<br>
</div>
<div>There is no change or anything abnormal happening.<br>
<br>
</div>
<div>Kindly help me forward to get tcp based IM working
between two clients.<br>
<br>
</div>
</div>
<div>
<div>
<div class="gmail_extra"><br>
<br>
<div class="gmail_quote">On Tue, Jun 18, 2013 at
2:27 PM, Sunil Chandrasekharan <span dir="ltr"><<a href="mailto:sunil.kainat@gmail.com" target="_blank">sunil.kainat@gmail.com</a>></span>
wrote:<br>
<blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
<div dir="ltr">
<div>
<div>
<div>
<div>
<div>
<div>Hi ,<br>
<br>
</div>
I used open internet, i really
doubt if there is any NAT issue
here.<br>
<br>
</div>
But my config file(kamailio.cfg) shows
: tcp-connection-lifetime=3605<br>
</div>
But i dont know what is the registration
expire time . How can i see the
registration expire time?<br>
</div>
also my lin phone sends keep alive right?<br>
<br>
</div>
can you please help me how can i see
connection close parameter during
forward/reply? i dont know where to set
them?<br>
<br>
</div>
Please suggest me the correct link to find tcp
paremeter and cook book.<br>
</div>
<div>
<div>
<div class="gmail_extra"><br>
<br>
<div class="gmail_quote">On Tue, Jun 18,
2013 at 2:06 PM, Daniel-Constantin
Mierla <span dir="ltr"><<a href="mailto:miconda@gmail.com" target="_blank">miconda@gmail.com</a>></span>
wrote:<br>
<blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">Hello,
<div>
<div><br>
<br>
On 6/17/13 7:09 AM, Sunil
Chandrasekharan wrote:<br>
<blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
<br>
Hi All,<br>
<br>
I have set up Kamailio 3.3 on
Ubuntu machine.I created two
user test 1 and test 2.<br>
I could use Presence and also i
was able to register to Kamailio
server and exchange presence
status with each other.<br>
<br>
Details- I used TCP protocol .<br>
<br>
testing methods - Linphone, a
sample application.<br>
<br>
I am able to register
succesfully with (TCP) on to
kamailio server using Lin phone
and sample application.<br>
I am able to update presence
status of the users.<br>
<br>
Issue :<br>
<br>
When i try to send messages
between two users ( using TCP )<br>
<br>
1. from Sample application, when
i try to send message, i get 420
temporarily Unavailable error
from server.<br>
2. from Lin phone 3.5.2, i was
able to send messages ( sue TCP)
but when the other user try to
reply back , i get same error<br>
<br>
480 Temporarily Unavailable.
error from server.<br>
<br>
I want a support to understand
why i get error while sending
messages with TCP via Kamailio
server.<br>
<br>
I am able to succeesfully send
and receive messages when i use
UDP from my sample application
and Lin phone.<br>
</blockquote>
</div>
</div>
if the phones are behind nat, be sure
the tcp connection lifetime is higher
than the registration expire and that
you don't set connection close after
forward/reply. Look at core cookbook
for the appropriate tcp parameter and
config functions.<br>
<br>
Cheers,<br>
Daniel<span><font color="#888888"><br>
<br>
-- <br>
Daniel-Constantin Mierla - <a href="http://www.asipto.com" target="_blank">http://www.asipto.com</a><br>
<a href="http://twitter.com/#%21/miconda" target="_blank">http://twitter.com/#!/miconda</a>
- <a href="http://www.linkedin.com/in/miconda" target="_blank">http://www.linkedin.com/in/miconda</a><br>
Kamailio Advanced Training, San
Francisco, USA - June 24-27, 2013<br>
* <a href="http://asipto.com/u/katu" target="_blank">http://asipto.com/u/katu</a>
*<br>
<br>
<br>
_______________________________________________<br>
SIP Express Router (SER) and
Kamailio (OpenSER) - sr-users
mailing list<br>
<a href="mailto:sr-users@lists.sip-router.org" target="_blank">sr-users@lists.sip-router.org</a><br>
<a href="http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users" target="_blank">http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users</a><br>
</font></span></blockquote>
</div>
<br>
</div>
</div>
</div>
</blockquote>
</div>
<br>
</div>
</div>
</div>
</blockquote>
</div>
<br>
</div>
</div>
<br>
<fieldset></fieldset>
<br>
<pre>_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
<a href="mailto:sr-users@lists.sip-router.org" target="_blank">sr-users@lists.sip-router.org</a>
<a href="http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users" target="_blank">http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users</a>
</pre>
</blockquote>
<br>
<pre cols="72">--
Daniel-Constantin Mierla - <a href="http://www.asipto.com" target="_blank">http://www.asipto.com</a>
<a href="http://twitter.com/#!/miconda" target="_blank">http://twitter.com/#!/miconda</a> - <a href="http://www.linkedin.com/in/miconda" target="_blank">http://www.linkedin.com/in/miconda</a>
</pre>
</div></div></div>
<br>_______________________________________________<br>
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list<br>
<a href="mailto:sr-users@lists.sip-router.org">sr-users@lists.sip-router.org</a><br>
<a href="http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users" target="_blank">http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users</a><br>
<br></blockquote></div><br></div></div></div></div></div></div></div></div></div>