12(15776) vm: calculated route:
12(15776) vm: next r-uri: sip:216105@xxx.xxx.xxx.18:5060
12(15776) parse_headers: flags=-1
12(15776) query="select email_address from subscriber where username='214103'"
12(15776) vm: write_to_vm_fifo: <0.2
sip_request.announcement
INVITE
214103
xxx.xxx.xxx.in
xxx.xxx.xxx.19
.
sip:214103@xxx.xxx.xxx.in
sip:216105@xxx.xxx.xxx.18:5060
hello <sip:216105@xxx.xxx.xxx.in>
<sip:214103@xxx.xxx.xxx.in>
D4DD8ACF-F8FE-11D8-8FE6-00E018FCDC33@xxx.xxx.xxx.18
3583624574
.
48216
14502:756140263
.
.
P-MsgFlags: 0
.
v=0
o=216105 15926530 15926613 IN IP4 xxx.xxx.xxx.18
s=X-Lite
c=IN IP4 xxx.xxx.xxx.18
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 97 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:97 speex/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
>
12(15776) DEBUG: write_to_vm_fifo: write completed
12(15776) DEBUG: add_to_tail_of_timer[0]: 0x422b9304
12(15776) receive_msg: cleaning up
14(15786) udp_rcv_loop: probing packet received from xxx.xxx.xxx.18 50195
15(15790) udp_rcv_loop: probing packet received from xxx.xxx.xxx.18 50195
18(15802) DEBUG: timer routine:0,tl=0x422b9304 next=(nil)
18(15802) DEBUG: FR_handler:stop retr. and send CANCEL (0x422b91c8)
18(15802) ->>>>>>>>> T_code=100, new_code=408
18(15802) DEBUG: relay_reply: branch=0, save=0, relay=0
18(15802) parse_headers: flags=-1
18(15802) check_via_address(xxx.xxx.xxx.18, xxx.xxx.xxx.18, 0)
18(15802) DEBUG: reply relayed. buf=0x80c3d38: SIP/2.0 4..., shmem=0x422ba890: SIP/2.0 4
18(15802) DEBUG: add_to_tail_of_timer[4]: 0x422b927c
18(15802) DEBUG: add_to_tail_of_timer[0]: 0x422b9290
18(15802) DEBUG: final_response_handler : done
9(15761) SIP Request:
9(15761) method: <ACK>
9(15761) uri: <sip:214103@xxx.xxx.xxx.in>
9(15761) version: <SIP/2.0>
9(15761) parse_headers: flags=1
9(15761) Found param type 235, <rport> = <n/a>; state=6
9(15761) Found param type 232, <branch> = <z9hG4bKD4DD8AD0F8FE11D88FE600E018FCDC33>; state=16
9(15761) end of header reached, state=5
9(15761) parse_headers: Via found, flags=1
9(15761) parse_headers: this is the first via
9(15761) After parse_msg...
9(15761) preparing to run routing scripts...
9(15761) DEBUG : sl_filter_ACK: to late to be a local ACK!
9(15761) DEBUG : is_maxfwd_present: searching for max_forwards header
9(15761) parse_headers: flags=128
9(15761) DEBUG: add_param: tag=a6a1c5f60faecf035a1ae5b6e96e979a-9573
9(15761) end of header reached, state=29
9(15761) DEBUG: get_hdr_field: <To> [74]; uri=[sip:214103@xxx.xxx.xxx.in]
9(15761) DEBUG: to body [<sip:214103@xxx.xxx.xxx.in>]
9(15761) get_hdr_field: cseq <CSeq>: <48216> <ACK>
9(15761) DEBUG: is_maxfwd_present: value = 70
9(15761) DEBUG: add_param: tag=3583624574
9(15761) end of header reached, state=29
9(15761) parse_headers: flags=256
9(15761) DEBUG: get_hdr_body : content_length=0
9(15761) found end of header
9(15761) find_first_route(): No Route headers found
9(15761) loose_route(): There is no Route HF
9(15761) check_self - checking if host==us: 17==9 && [xxx.xxx.xxx.in] == [127.0.0.1]
9(15761) check_self - checking if port 5060 matches port 5060
9(15761) check_self - checking if host==us: 17==13 && [xxx.xxx.xxx.in] == [xxx.xxx.xxx.19]
9(15761) check_self - checking if port 5060 matches port 5060
9(15761) DEBUG: t_addifnew: msg id=2 , global msg id=0 , T on entrance=0xffffffff
9(15761) parse_headers: flags=-1
9(15761) parse_headers: flags=60
9(15761) t_lookup_request: start searching: hash=14502, isACK=1
9(15761) DEBUG: RFC3261 transaction matched, tid=D4DD8AD0F8FE11D88FE600E018FCDC33
9(15761) DEBUG: t_lookup_request: transaction found (T=0x422b91c8)
9(15761) DEBUG: cleanup_uacs: RETR/FR timers reset
9(15761) DEBUG: add_to_tail_of_timer[2]: 0x422b9210
9(15761) receive_msg: cleaning up
18(15802) DEBUG: timer routine:0,tl=0x422b9290 next=(nil)
18(15802) DEBUG: timer routine:4,tl=0x422b927c next=(nil)
18(15802) DEBUG: timer routine:2,tl=0x422b9210 next=(nil)
18(15802) DEBUG: wait_handler : removing 0x422b91c8 from table
18(15802) DEBUG: delete transaction 0x422b91c8
18(15802) DEBUG: wait_handler : done